2026-09-12 03:53:33.460326 | Job console starting 2026-09-12 03:53:33.478471 | Updating git repos 2026-09-12 03:53:42.869959 | Cloning repos into workspace 2026-09-12 03:53:59.034464 | Restoring repo states 2026-09-12 03:54:04.996204 | Merging changes 2026-09-12 03:54:06.555381 | Checking out repos 2026-09-12 03:54:08.346362 | Preparing playbooks 2026-09-12 03:54:11.497850 | Running Ansible setup 2026-09-12 03:54:15.403081 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:54:15.957823 | 2026-09-12 03:54:15.957962 | PLAY [localhost] 2026-09-12 03:54:15.964967 | 2026-09-12 03:54:15.965032 | TASK [Gathering Facts] 2026-09-12 03:54:16.862018 | localhost | ok 2026-09-12 03:54:16.875756 | 2026-09-12 03:54:16.875910 | TASK [Setup log path fact] 2026-09-12 03:54:16.898273 | localhost | ok 2026-09-12 03:54:16.914115 | 2026-09-12 03:54:16.914204 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 03:54:16.944085 | localhost | ok 2026-09-12 03:54:16.954860 | 2026-09-12 03:54:16.954953 | TASK [emit-job-header : Print job information] 2026-09-12 03:54:17.015000 | # Job Information 2026-09-12 03:54:17.015213 | Ansible Version: 2.16.18 2026-09-12 03:54:17.015280 | Job: atmosphere-molecule-csi-rbd 2026-09-12 03:54:17.015324 | Pipeline: check 2026-09-12 03:54:17.015363 | Executor: 2d72f0692154 2026-09-12 03:54:17.015402 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4363 2026-09-12 03:54:17.015448 | Event ID: dbc7e190-ae5c-11f1-9d63-8d7cb98da735 2026-09-12 03:54:17.020840 | 2026-09-12 03:54:17.020924 | LOOP [emit-job-header : Print node information] 2026-09-12 03:54:17.104959 | localhost | ok: 2026-09-12 03:54:17.105203 | localhost | # Node Information 2026-09-12 03:54:17.105246 | localhost | Inventory Hostname: instance 2026-09-12 03:54:17.105275 | localhost | Hostname: np0000192680 2026-09-12 03:54:17.105302 | localhost | Username: zuul 2026-09-12 03:54:17.105334 | localhost | Distro: Ubuntu 22.04 2026-09-12 03:54:17.105361 | localhost | Provider: yul1 2026-09-12 03:54:17.105386 | localhost | Region: ca-ymq-1 2026-09-12 03:54:17.105411 | localhost | Label: ubuntu-jammy 2026-09-12 03:54:17.105435 | localhost | Product Name: OpenStack Nova 2026-09-12 03:54:17.105459 | localhost | Interface IP: 162.253.55.210 2026-09-12 03:54:17.135706 | 2026-09-12 03:54:17.135959 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-12 03:54:17.565545 | localhost -> localhost | changed 2026-09-12 03:54:17.574209 | 2026-09-12 03:54:17.574342 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-12 03:54:18.519949 | localhost -> localhost | changed 2026-09-12 03:54:18.526465 | 2026-09-12 03:54:18.526546 | PLAY [all] 2026-09-12 03:54:18.534667 | 2026-09-12 03:54:18.534751 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-12 03:54:18.828241 | instance -> localhost | ok 2026-09-12 03:54:18.835249 | 2026-09-12 03:54:18.835332 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-12 03:54:18.866005 | instance | ok 2026-09-12 03:54:18.883034 | instance | included: /var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-12 03:54:18.948953 | 2026-09-12 03:54:18.949085 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-12 03:54:20.756385 | instance -> localhost | Generating public/private rsa key pair. 2026-09-12 03:54:20.756673 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/67a1e08ede024b418efeae0f57cd70cc_id_rsa 2026-09-12 03:54:20.756726 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/67a1e08ede024b418efeae0f57cd70cc_id_rsa.pub 2026-09-12 03:54:20.757346 | instance -> localhost | The key fingerprint is: 2026-09-12 03:54:20.757406 | instance -> localhost | SHA256:f72qfX4X3Ce5oPX47NtoxawIz1uYNX0iH+A9vHMdJQM zuul-build-sshkey 2026-09-12 03:54:20.757603 | instance -> localhost | The key's randomart image is: 2026-09-12 03:54:20.757650 | instance -> localhost | +---[RSA 3072]----+ 2026-09-12 03:54:20.757780 | instance -> localhost | | E | 2026-09-12 03:54:20.757882 | instance -> localhost | | . | 2026-09-12 03:54:20.758106 | instance -> localhost | | . o .| 2026-09-12 03:54:20.758304 | instance -> localhost | | . + +.| 2026-09-12 03:54:20.758380 | instance -> localhost | | S o X=+| 2026-09-12 03:54:20.758638 | instance -> localhost | | .. oBoOX| 2026-09-12 03:54:20.758690 | instance -> localhost | | .*+=*==| 2026-09-12 03:54:20.758722 | instance -> localhost | | .o=o**o| 2026-09-12 03:54:20.758775 | instance -> localhost | | ..+OOoo| 2026-09-12 03:54:20.758804 | instance -> localhost | +----[SHA256]-----+ 2026-09-12 03:54:20.759347 | instance -> localhost | ok: Runtime: 0:00:01.346560 2026-09-12 03:54:20.770953 | 2026-09-12 03:54:20.771025 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-12 03:54:20.814545 | instance | ok 2026-09-12 03:54:20.827179 | instance | included: /var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-12 03:54:20.837574 | 2026-09-12 03:54:20.837654 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-12 03:54:20.862861 | instance | skipping: Conditional result was False 2026-09-12 03:54:20.877816 | 2026-09-12 03:54:20.877903 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-12 03:54:21.365799 | instance | changed 2026-09-12 03:54:21.381208 | 2026-09-12 03:54:21.381421 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-12 03:54:21.600020 | instance | ok 2026-09-12 03:54:21.608278 | 2026-09-12 03:54:21.608360 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-12 03:54:22.163162 | instance | changed 2026-09-12 03:54:22.169320 | 2026-09-12 03:54:22.169404 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-12 03:54:22.682997 | instance | changed 2026-09-12 03:54:22.688887 | 2026-09-12 03:54:22.688953 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-12 03:54:22.702446 | instance | skipping: Conditional result was False 2026-09-12 03:54:22.707726 | 2026-09-12 03:54:22.707797 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-12 03:54:23.116827 | instance -> localhost | changed 2026-09-12 03:54:23.138916 | 2026-09-12 03:54:23.139076 | TASK [add-build-sshkey : Add back temp key] 2026-09-12 03:54:23.456557 | instance -> localhost | Identity added: /var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/67a1e08ede024b418efeae0f57cd70cc_id_rsa (zuul-build-sshkey) 2026-09-12 03:54:23.456842 | instance -> localhost | ok: Runtime: 0:00:00.011688 2026-09-12 03:54:23.463510 | 2026-09-12 03:54:23.463591 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-12 03:54:23.797691 | instance | ok 2026-09-12 03:54:23.802187 | 2026-09-12 03:54:23.802249 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-12 03:54:23.835819 | instance | skipping: Conditional result was False 2026-09-12 03:54:23.848038 | 2026-09-12 03:54:23.848135 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-12 03:54:24.211540 | instance | ok 2026-09-12 03:54:24.219229 | 2026-09-12 03:54:24.219419 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-12 03:54:27.829912 | instance | Output suppressed because no_log was given 2026-09-12 03:54:27.848395 | 2026-09-12 03:54:27.848550 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-12 03:54:28.099436 | instance | ok: "logs" 2026-09-12 03:54:28.099720 | instance | ok: All items complete 2026-09-12 03:54:28.099773 | 2026-09-12 03:54:28.275621 | instance | ok: "artifacts" 2026-09-12 03:54:28.415079 | instance | ok: "docs" 2026-09-12 03:54:28.430643 | 2026-09-12 03:54:28.430892 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-12 03:54:28.620580 | instance | changed: "logs" 2026-09-12 03:54:28.777584 | instance | changed: "artifacts" 2026-09-12 03:54:28.924809 | instance | changed: "docs" 2026-09-12 03:54:28.938389 | 2026-09-12 03:54:28.938509 | PLAY RECAP 2026-09-12 03:54:28.938553 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-12 03:54:28.938578 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:54:28.938599 | 2026-09-12 03:54:29.082342 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 03:54:29.093473 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:54:29.748548 | 2026-09-12 03:54:29.748681 | PLAY [all] 2026-09-12 03:54:29.758684 | 2026-09-12 03:54:29.758767 | TASK [setup-uv : Extract archive] 2026-09-12 03:54:32.111863 | instance | changed 2026-09-12 03:54:32.125087 | 2026-09-12 03:54:32.125197 | TASK [setup-uv : Print version] 2026-09-12 03:54:32.512288 | instance | uv 0.8.13 2026-09-12 03:54:32.670508 | instance | ok: Runtime: 0:00:00.010467 2026-09-12 03:54:32.689701 | 2026-09-12 03:54:32.689920 | TASK [Install Ansible collection siblings] 2026-09-12 03:54:48.869892 | instance | changed: 2026-09-12 03:54:48.870078 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 03:54:48.870106 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 03:54:48.870126 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 03:54:48.870145 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 03:54:48.870162 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 03:54:48.870183 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 03:54:48.870203 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 03:54:48.870228 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 03:54:48.870247 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 03:54:48.870265 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 03:54:48.870283 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 03:54:48.870300 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 03:54:48.870318 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-12 03:54:48.870335 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-12 03:54:48.870352 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-12 03:54:48.870369 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-12 03:54:48.870385 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-12 03:54:48.870402 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-12 03:54:48.870419 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-12 03:54:48.870437 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-12 03:54:48.870454 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-12 03:54:48.870470 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-12 03:54:48.870488 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:54:48.870522 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-12 03:54:48.870540 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-12 03:54:48.870557 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:54:48.870574 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:54:48.870591 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 03:54:48.870607 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-12 03:54:48.870624 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-12 03:54:48.870641 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 03:54:48.870658 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 03:54:48.870675 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-12 03:54:48.870692 | 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-12 03:54:48.870719 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.870737 | instance | Process install dependency map 2026-09-12 03:54:48.870766 | instance | Starting collection install process 2026-09-12 03:54:48.870782 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-12 03:54:48.870799 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-12 03:54:48.870814 | instance | ansible.netcommon:* was installed successfully 2026-09-12 03:54:48.870830 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-12 03:54:48.870846 | instance | Creating virtual environment at: .venv 2026-09-12 03:54:48.870862 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:54:48.870878 | instance | Downloading setuptools (1.1MiB) 2026-09-12 03:54:48.870894 | instance | Downloading pydantic-core (2.0MiB) 2026-09-12 03:54:48.870914 | instance | Downloading openstacksdk (1.7MiB) 2026-09-12 03:54:48.870931 | instance | Downloading rjsonnet (1.2MiB) 2026-09-12 03:54:48.870947 | instance | Downloading kubernetes (1.9MiB) 2026-09-12 03:54:48.870963 | instance | Downloading cryptography (4.2MiB) 2026-09-12 03:54:48.870980 | instance | Downloading ansible-core (2.1MiB) 2026-09-12 03:54:48.871000 | instance | Downloading pygments (1.2MiB) 2026-09-12 03:54:48.871017 | instance | Downloading netaddr (2.2MiB) 2026-09-12 03:54:48.871034 | instance | Downloading openstacksdk (1.7MiB) 2026-09-12 03:54:48.871051 | instance | Downloading pydantic-core (2.0MiB) 2026-09-12 03:54:48.871068 | instance | Downloading cryptography (4.2MiB) 2026-09-12 03:54:48.871084 | instance | Downloading rjsonnet (1.2MiB) 2026-09-12 03:54:48.871100 | instance | Downloading kubernetes (1.9MiB) 2026-09-12 03:54:48.871117 | instance | Downloading ansible-core (2.1MiB) 2026-09-12 03:54:48.871133 | instance | Downloading setuptools (1.1MiB) 2026-09-12 03:54:48.871148 | instance | Downloading netaddr (2.2MiB) 2026-09-12 03:54:48.871164 | instance | Downloading pygments (1.2MiB) 2026-09-12 03:54:48.871181 | instance | Downloading rjsonnet 2026-09-12 03:54:48.871197 | instance | Downloading pydantic-core 2026-09-12 03:54:48.871215 | instance | Downloading netaddr 2026-09-12 03:54:48.871231 | instance | Downloading cryptography 2026-09-12 03:54:48.871248 | instance | Downloading pygments 2026-09-12 03:54:48.871265 | instance | Downloading setuptools 2026-09-12 03:54:48.871281 | instance | Downloading kubernetes 2026-09-12 03:54:48.871297 | instance | Downloading ansible-core 2026-09-12 03:54:48.871313 | instance | Downloading openstacksdk 2026-09-12 03:54:48.871329 | instance | Building pyperclip==1.9.0 2026-09-12 03:54:48.871345 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 03:54:48.871368 | instance | Built pyperclip==1.9.0 2026-09-12 03:54:48.871385 | instance | Installed 83 packages in 46ms 2026-09-12 03:54:48.871401 | instance | Checked out ansible.posix to 1.6.0 2026-09-12 03:54:48.871417 | 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-12 03:54:48.871434 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.871450 | instance | Process install dependency map 2026-09-12 03:54:48.871467 | instance | Starting collection install process 2026-09-12 03:54:48.871484 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-12 03:54:48.871501 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-12 03:54:48.871519 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-12 03:54:48.871536 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.871553 | instance | 2.17.12 2026-09-12 03:54:48.871569 | instance | Checked out ansible.utils to v6.1.0 2026-09-12 03:54:48.871585 | 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-12 03:54:48.871602 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.871619 | instance | Process install dependency map 2026-09-12 03:54:48.871635 | instance | Starting collection install process 2026-09-12 03:54:48.871651 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-12 03:54:48.871667 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-12 03:54:48.871686 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-12 03:54:48.871704 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.871721 | instance | 2.17.12 2026-09-12 03:54:48.871737 | instance | Checked out atmosphere.common to v0.7.0 2026-09-12 03:54:48.871767 | 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-12 03:54:48.871834 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.871861 | instance | Process install dependency map 2026-09-12 03:54:48.871879 | instance | Starting collection install process 2026-09-12 03:54:48.871896 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-12 03:54:48.871912 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-12 03:54:48.871929 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-12 03:54:48.871946 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.871961 | instance | 2.17.12 2026-09-12 03:54:48.871977 | instance | Checked out community.crypto to 2.2.3 2026-09-12 03:54:48.871993 | 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-12 03:54:48.872010 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.872025 | instance | Process install dependency map 2026-09-12 03:54:48.872041 | instance | Starting collection install process 2026-09-12 03:54:48.872056 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-12 03:54:48.872078 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-12 03:54:48.872095 | instance | community.crypto:2.2.3 was installed successfully 2026-09-12 03:54:48.872111 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.872127 | instance | 2.17.12 2026-09-12 03:54:48.872142 | instance | Checked out community.general to 7.3.0 2026-09-12 03:54:48.872158 | 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-12 03:54:48.872177 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.872194 | instance | Process install dependency map 2026-09-12 03:54:48.872211 | instance | Starting collection install process 2026-09-12 03:54:48.872227 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-12 03:54:48.872243 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-12 03:54:48.872260 | instance | community.general:7.3.0 was installed successfully 2026-09-12 03:54:48.872276 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.872291 | instance | 2.17.12 2026-09-12 03:54:48.872308 | instance | Checked out community.mysql to 3.6.0 2026-09-12 03:54:48.872324 | 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-12 03:54:48.872341 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.872358 | instance | Process install dependency map 2026-09-12 03:54:48.872374 | instance | Starting collection install process 2026-09-12 03:54:48.872391 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-12 03:54:48.872407 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-12 03:54:48.872424 | instance | community.mysql:3.6.0 was installed successfully 2026-09-12 03:54:48.872440 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.872456 | instance | 2.17.12 2026-09-12 03:54:48.872471 | instance | Checked out kubernetes.core to 2.4.0 2026-09-12 03:54:48.872487 | 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-12 03:54:48.872533 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.872558 | instance | Process install dependency map 2026-09-12 03:54:48.872576 | instance | Starting collection install process 2026-09-12 03:54:48.872593 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-12 03:54:48.872656 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-12 03:54:48.872685 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-12 03:54:48.872704 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.872721 | instance | 2.17.12 2026-09-12 03:54:48.872738 | instance | Checked out openstack.cloud to 2.6.0 2026-09-12 03:54:48.872772 | 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-12 03:54:48.872795 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.872818 | instance | Process install dependency map 2026-09-12 03:54:48.872853 | instance | Starting collection install process 2026-09-12 03:54:48.872873 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-12 03:54:48.872889 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-12 03:54:48.872908 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-12 03:54:48.872926 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.872942 | instance | 2.17.12 2026-09-12 03:54:48.872957 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-12 03:54:48.872975 | 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-12 03:54:48.872991 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.873006 | instance | Process install dependency map 2026-09-12 03:54:48.873021 | instance | Starting collection install process 2026-09-12 03:54:48.873037 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-12 03:54:48.873052 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-12 03:54:48.873068 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-12 03:54:48.873084 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.873100 | instance | 2.17.12 2026-09-12 03:54:48.873117 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-12 03:54:48.873134 | 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-12 03:54:48.873150 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.873167 | instance | Process install dependency map 2026-09-12 03:54:48.873183 | instance | Starting collection install process 2026-09-12 03:54:48.873202 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-12 03:54:48.873219 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-12 03:54:48.873235 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-12 03:54:48.873252 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.873269 | instance | 2.17.12 2026-09-12 03:54:48.873285 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-12 03:54:48.873301 | 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-12 03:54:48.873317 | instance | Starting galaxy collection install process 2026-09-12 03:54:48.873333 | instance | Process install dependency map 2026-09-12 03:54:48.873349 | instance | Starting collection install process 2026-09-12 03:54:48.873365 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-12 03:54:48.873381 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-12 03:54:48.873397 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-12 03:54:48.873413 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 03:54:48.873429 | instance | 2.17.12 2026-09-12 03:54:48.878819 | 2026-09-12 03:54:48.878869 | PLAY RECAP 2026-09-12 03:54:48.878921 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:54:48.878949 | 2026-09-12 03:54:49.019339 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 03:54:49.022997 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:54:49.680863 | 2026-09-12 03:54:49.680990 | PLAY [all] 2026-09-12 03:54:49.691074 | 2026-09-12 03:54:49.691148 | TASK [Install "jq" for log collection] 2026-09-12 03:54:59.354671 | instance | changed 2026-09-12 03:54:59.431130 | 2026-09-12 03:54:59.431354 | PLAY RECAP 2026-09-12 03:54:59.431457 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:54:59.431575 | 2026-09-12 03:54:59.588285 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 03:54:59.593295 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 03:55:00.176266 | 2026-09-12 03:55:00.176544 | PLAY [all] 2026-09-12 03:55:00.186669 | 2026-09-12 03:55:00.186739 | LOOP [Check inventory directories] 2026-09-12 03:55:00.567427 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-12 03:55:00.736354 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-12 03:55:00.884710 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-12 03:55:00.899097 | 2026-09-12 03:55:00.899286 | LOOP [Copy inventory file for Zuul] 2026-09-12 03:55:01.790203 | instance | changed: 2026-09-12 03:55:01.790735 | instance | { 2026-09-12 03:55:01.790789 | instance | "ansible_loop_var": "item", 2026-09-12 03:55:01.790813 | instance | "changed": false, 2026-09-12 03:55:01.790831 | instance | "failed": false, 2026-09-12 03:55:01.790851 | instance | "invocation": { 2026-09-12 03:55:01.790871 | instance | "module_args": { 2026-09-12 03:55:01.790914 | instance | "checksum_algorithm": "sha1", 2026-09-12 03:55:01.790946 | instance | "follow": true, 2026-09-12 03:55:01.790970 | instance | "get_attributes": true, 2026-09-12 03:55:01.791089 | instance | "get_checksum": true, 2026-09-12 03:55:01.791110 | instance | "get_mime": true, 2026-09-12 03:55:01.791136 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-12 03:55:01.791152 | instance | } 2026-09-12 03:55:01.791167 | instance | }, 2026-09-12 03:55:01.791182 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:55:01.791197 | instance | "stat": { 2026-09-12 03:55:01.791213 | instance | "atime": 1789185281.720705, 2026-09-12 03:55:01.791229 | instance | "attr_flags": "e", 2026-09-12 03:55:01.791244 | instance | "attributes": [ 2026-09-12 03:55:01.791258 | instance | "extents" 2026-09-12 03:55:01.791272 | instance | ], 2026-09-12 03:55:01.791287 | instance | "block_size": 4096, 2026-09-12 03:55:01.791302 | instance | "blocks": 8, 2026-09-12 03:55:01.791317 | instance | "charset": "unknown", 2026-09-12 03:55:01.791334 | instance | "ctime": 1789185273.5685537, 2026-09-12 03:55:01.791349 | instance | "dev": 64513, 2026-09-12 03:55:01.791364 | instance | "device_type": 0, 2026-09-12 03:55:01.791379 | instance | "executable": true, 2026-09-12 03:55:01.791393 | instance | "exists": true, 2026-09-12 03:55:01.791408 | instance | "gid": 1000, 2026-09-12 03:55:01.791422 | instance | "gr_name": "zuul", 2026-09-12 03:55:01.791438 | instance | "inode": 1049261, 2026-09-12 03:55:01.791452 | instance | "isblk": false, 2026-09-12 03:55:01.791468 | instance | "ischr": false, 2026-09-12 03:55:01.791483 | instance | "isdir": true, 2026-09-12 03:55:01.791497 | instance | "isfifo": false, 2026-09-12 03:55:01.791512 | instance | "isgid": false, 2026-09-12 03:55:01.791528 | instance | "islnk": false, 2026-09-12 03:55:01.791542 | instance | "isreg": false, 2026-09-12 03:55:01.791556 | instance | "issock": false, 2026-09-12 03:55:01.791579 | instance | "isuid": false, 2026-09-12 03:55:01.791594 | instance | "mimetype": "unknown", 2026-09-12 03:55:01.791608 | instance | "mode": "0755", 2026-09-12 03:55:01.791622 | instance | "mtime": 1789185273.5685537, 2026-09-12 03:55:01.791636 | instance | "nlink": 23, 2026-09-12 03:55:01.791650 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-12 03:55:01.791664 | instance | "pw_name": "zuul", 2026-09-12 03:55:01.791679 | instance | "readable": true, 2026-09-12 03:55:01.791694 | instance | "rgrp": true, 2026-09-12 03:55:01.791709 | instance | "roth": true, 2026-09-12 03:55:01.791723 | instance | "rusr": true, 2026-09-12 03:55:01.791738 | instance | "size": 4096, 2026-09-12 03:55:01.791767 | instance | "uid": 1000, 2026-09-12 03:55:01.791786 | instance | "version": "3857738468", 2026-09-12 03:55:01.791810 | instance | "wgrp": false, 2026-09-12 03:55:01.791835 | instance | "woth": false, 2026-09-12 03:55:01.791851 | instance | "writeable": true, 2026-09-12 03:55:01.791867 | instance | "wusr": true, 2026-09-12 03:55:01.791881 | instance | "xgrp": true, 2026-09-12 03:55:01.791895 | instance | "xoth": true, 2026-09-12 03:55:01.791908 | instance | "xusr": true 2026-09-12 03:55:01.791923 | instance | } 2026-09-12 03:55:01.791942 | instance | } 2026-09-12 03:55:01.791976 | instance | ok: All items complete 2026-09-12 03:55:01.791995 | 2026-09-12 03:55:01.793253 | instance | skipping: Conditional result was False 2026-09-12 03:55:01.794788 | instance | skipping: Conditional result was False 2026-09-12 03:55:01.806707 | 2026-09-12 03:55:01.806805 | LOOP [Switch "ansible_host" to private IP] 2026-09-12 03:55:02.164056 | instance | changed: 1 replacements made 2026-09-12 03:55:02.166038 | instance | skipping: Conditional result was False 2026-09-12 03:55:02.166829 | instance | skipping: Conditional result was False 2026-09-12 03:55:02.178292 | 2026-09-12 03:55:02.178418 | TASK [Run Molecule scenario] 2026-09-12 03:55:03.174667 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-12 03:55:03.570578 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-12 03:55:03.570694 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-12 03:55:14.357544 | instance | INFO [csi > dependency] Executing 2026-09-12 03:55:14.358174 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-12 03:55:14.358561 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-12 03:55:14.358849 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-12 03:55:14.374464 | instance | INFO [csi > cleanup] Executing 2026-09-12 03:55:14.375108 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:55:14.387947 | instance | INFO [csi > destroy] Executing 2026-09-12 03:55:14.388004 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-12 03:55:14.388187 | instance | INFO [csi > destroy] Executed: Successful 2026-09-12 03:55:14.398854 | instance | INFO [csi > syntax] Executing 2026-09-12 03:55:15.936193 | instance | 2026-09-12 03:55:15.936515 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 03:55:15.995208 | instance | INFO [csi > syntax] Executed: Successful 2026-09-12 03:55:16.011533 | instance | INFO [csi > create] Executing 2026-09-12 03:55:16.015419 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 03:55:16.031643 | instance | INFO [csi > prepare] Executing 2026-09-12 03:55:16.715890 | instance | 2026-09-12 03:55:16.716088 | instance | PLAY [Prepare] ***************************************************************** 2026-09-12 03:55:16.716318 | instance | 2026-09-12 03:55:16.716585 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:16.716891 | instance | Saturday 12 September 2026 03:55:16 +0000 (0:00:00.022) 0:00:00.022 **** 2026-09-12 03:55:17.974602 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:55:17.974741 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:55:17.975047 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:55:17.975320 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:55:17.975634 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:55:17.984425 | instance | ok: [instance] 2026-09-12 03:55:17.984651 | instance | 2026-09-12 03:55:17.984900 | instance | TASK [Configure short hostname] ************************************************ 2026-09-12 03:55:17.985219 | instance | Saturday 12 September 2026 03:55:17 +0000 (0:00:01.269) 0:00:01.291 **** 2026-09-12 03:55:18.713574 | instance | changed: [instance] 2026-09-12 03:55:18.713852 | instance | 2026-09-12 03:55:18.714167 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-12 03:55:18.714434 | instance | Saturday 12 September 2026 03:55:18 +0000 (0:00:00.728) 0:00:02.020 **** 2026-09-12 03:55:18.972990 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-12 03:55:18.973045 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-12 03:55:18.973056 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-12 03:55:18.982917 | instance | changed: [instance] 2026-09-12 03:55:18.982949 | instance | 2026-09-12 03:55:18.982960 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-12 03:55:18.982970 | instance | Saturday 12 September 2026 03:55:18 +0000 (0:00:00.268) 0:00:02.289 **** 2026-09-12 03:55:19.763888 | instance | ok: [instance] 2026-09-12 03:55:19.764266 | instance | 2026-09-12 03:55:19.764620 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-12 03:55:19.764962 | instance | 2026-09-12 03:55:19.765312 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:19.765686 | instance | Saturday 12 September 2026 03:55:19 +0000 (0:00:00.782) 0:00:03.071 **** 2026-09-12 03:55:20.534608 | instance | ok: [instance] 2026-09-12 03:55:20.535147 | instance | 2026-09-12 03:55:20.535539 | instance | TASK [Install depedencies] ***************************************************** 2026-09-12 03:55:20.535894 | instance | Saturday 12 September 2026 03:55:20 +0000 (0:00:00.770) 0:00:03.841 **** 2026-09-12 03:55:43.939123 | instance | changed: [instance] 2026-09-12 03:55:43.939193 | instance | 2026-09-12 03:55:43.939205 | instance | TASK [Start up service] ******************************************************** 2026-09-12 03:55:43.939215 | instance | Saturday 12 September 2026 03:55:43 +0000 (0:00:23.403) 0:00:27.245 **** 2026-09-12 03:55:44.503715 | instance | ok: [instance] 2026-09-12 03:55:44.503947 | instance | 2026-09-12 03:55:44.504275 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-12 03:55:44.504550 | instance | Saturday 12 September 2026 03:55:44 +0000 (0:00:00.565) 0:00:27.810 **** 2026-09-12 03:55:44.834708 | instance | ok: [instance] 2026-09-12 03:55:44.834826 | instance | 2026-09-12 03:55:44.835004 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-12 03:55:44.835204 | instance | Saturday 12 September 2026 03:55:44 +0000 (0:00:00.331) 0:00:28.141 **** 2026-09-12 03:55:45.513938 | instance | changed: [instance] 2026-09-12 03:55:45.514000 | instance | 2026-09-12 03:55:45.514032 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-12 03:55:45.514049 | instance | Saturday 12 September 2026 03:55:45 +0000 (0:00:00.678) 0:00:28.820 **** 2026-09-12 03:55:45.711306 | instance | ok: [instance] 2026-09-12 03:55:45.711332 | instance | 2026-09-12 03:55:45.711338 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-12 03:55:45.711342 | instance | Saturday 12 September 2026 03:55:45 +0000 (0:00:00.198) 0:00:29.018 **** 2026-09-12 03:55:45.731986 | instance | skipping: [instance] 2026-09-12 03:55:45.732024 | instance | 2026-09-12 03:55:45.732031 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-12 03:55:45.732037 | instance | Saturday 12 September 2026 03:55:45 +0000 (0:00:00.020) 0:00:29.039 **** 2026-09-12 03:55:46.271834 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:46.272776 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:46.272789 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:46.272794 | instance | 2026-09-12 03:55:46.272799 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-12 03:55:46.273011 | instance | Saturday 12 September 2026 03:55:46 +0000 (0:00:00.539) 0:00:29.579 **** 2026-09-12 03:55:46.917093 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:46.917949 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:46.917966 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:46.917971 | instance | 2026-09-12 03:55:46.918452 | instance | TASK [Start loop devices] ****************************************************** 2026-09-12 03:55:46.918507 | instance | Saturday 12 September 2026 03:55:46 +0000 (0:00:00.644) 0:00:30.223 **** 2026-09-12 03:55:47.665320 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:47.666089 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:47.666464 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:47.666487 | instance | 2026-09-12 03:55:47.666531 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-12 03:55:47.666546 | instance | Saturday 12 September 2026 03:55:47 +0000 (0:00:00.748) 0:00:30.972 **** 2026-09-12 03:55:50.958472 | instance | changed: [instance] => (item=osd0) 2026-09-12 03:55:50.959650 | instance | changed: [instance] => (item=osd1) 2026-09-12 03:55:50.959713 | instance | changed: [instance] => (item=osd2) 2026-09-12 03:55:50.959725 | instance | 2026-09-12 03:55:50.959736 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-12 03:55:50.959746 | instance | Saturday 12 September 2026 03:55:50 +0000 (0:00:03.292) 0:00:34.265 **** 2026-09-12 03:55:52.923450 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-12 03:55:52.923744 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-12 03:55:52.924169 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-12 03:55:52.924477 | instance | 2026-09-12 03:55:52.924842 | instance | PLAY RECAP ********************************************************************* 2026-09-12 03:55:52.925220 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-12 03:55:52.925522 | instance | 2026-09-12 03:55:52.925906 | instance | Saturday 12 September 2026 03:55:52 +0000 (0:00:01.965) 0:00:36.231 **** 2026-09-12 03:55:52.926264 | instance | =============================================================================== 2026-09-12 03:55:52.926592 | instance | Install depedencies ---------------------------------------------------- 23.40s 2026-09-12 03:55:52.926976 | instance | Create a volume group for each loop device ------------------------------ 3.29s 2026-09-12 03:55:52.927303 | instance | Create a logical volume for each loop device ---------------------------- 1.97s 2026-09-12 03:55:52.927607 | instance | Gathering Facts --------------------------------------------------------- 1.27s 2026-09-12 03:55:52.927943 | instance | Purge "snapd" package --------------------------------------------------- 0.78s 2026-09-12 03:55:52.928297 | instance | Gathering Facts --------------------------------------------------------- 0.77s 2026-09-12 03:55:52.928605 | instance | Start loop devices ------------------------------------------------------ 0.75s 2026-09-12 03:55:52.928934 | instance | Configure short hostname ------------------------------------------------ 0.73s 2026-09-12 03:55:52.929284 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.68s 2026-09-12 03:55:52.929602 | instance | Set permissions on loopback devices ------------------------------------- 0.64s 2026-09-12 03:55:52.930004 | instance | Start up service -------------------------------------------------------- 0.57s 2026-09-12 03:55:52.930398 | instance | Create devices for Ceph ------------------------------------------------- 0.54s 2026-09-12 03:55:52.930754 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s 2026-09-12 03:55:52.931100 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s 2026-09-12 03:55:52.931418 | instance | Get list of all loopback devices ---------------------------------------- 0.20s 2026-09-12 03:55:52.931765 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-12 03:55:53.003685 | instance | INFO [csi > prepare] Executed: Successful 2026-09-12 03:55:53.018496 | instance | INFO [csi > converge] Executing 2026-09-12 03:55:54.394110 | instance | 2026-09-12 03:55:54.394480 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-12 03:55:54.394772 | instance | 2026-09-12 03:55:54.395093 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:54.395502 | instance | Saturday 12 September 2026 03:55:54 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 03:55:55.418849 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 03:55:55.419233 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 03:55:55.419548 | instance | interpreter could change the meaning of that path. See 2026-09-12 03:55:55.419881 | instance | https://docs.ansible.com/ansible- 2026-09-12 03:55:55.420189 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 03:55:55.434675 | instance | ok: [instance] 2026-09-12 03:55:55.435013 | instance | 2026-09-12 03:55:55.435318 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-12 03:55:55.435615 | instance | Saturday 12 September 2026 03:55:55 +0000 (0:00:01.040) 0:00:01.047 **** 2026-09-12 03:55:55.498275 | instance | ok: [instance] => { 2026-09-12 03:55:55.498621 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-12 03:55:55.498938 | instance | } 2026-09-12 03:55:55.499221 | instance | 2026-09-12 03:55:55.499513 | instance | PLAY [all] ********************************************************************* 2026-09-12 03:55:55.499793 | instance | 2026-09-12 03:55:55.500113 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:55.500434 | instance | Saturday 12 September 2026 03:55:55 +0000 (0:00:00.063) 0:00:01.111 **** 2026-09-12 03:55:56.399934 | instance | ok: [instance] 2026-09-12 03:55:56.400340 | instance | 2026-09-12 03:55:56.400682 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-12 03:55:56.401048 | instance | Saturday 12 September 2026 03:55:56 +0000 (0:00:00.901) 0:00:02.012 **** 2026-09-12 03:55:56.448218 | instance | skipping: [instance] 2026-09-12 03:55:56.448577 | instance | 2026-09-12 03:55:56.448931 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-12 03:55:56.449239 | instance | Saturday 12 September 2026 03:55:56 +0000 (0:00:00.048) 0:00:02.061 **** 2026-09-12 03:55:56.666730 | instance | ok: [instance] 2026-09-12 03:55:56.667013 | instance | 2026-09-12 03:55:56.667224 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-12 03:55:56.667408 | instance | 2026-09-12 03:55:56.667600 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 03:55:56.667830 | instance | Saturday 12 September 2026 03:55:56 +0000 (0:00:00.217) 0:00:02.278 **** 2026-09-12 03:55:57.652287 | instance | ok: [instance] 2026-09-12 03:55:57.652505 | instance | 2026-09-12 03:55:57.652878 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:55:57.653190 | instance | Saturday 12 September 2026 03:55:57 +0000 (0:00:00.986) 0:00:03.264 **** 2026-09-12 03:55:57.964058 | instance | ok: [instance] 2026-09-12 03:55:57.964259 | instance | 2026-09-12 03:55:57.964783 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:55:57.965129 | instance | Saturday 12 September 2026 03:55:57 +0000 (0:00:00.311) 0:00:03.576 **** 2026-09-12 03:55:58.026604 | instance | skipping: [instance] 2026-09-12 03:55:58.026834 | instance | 2026-09-12 03:55:58.027104 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-12 03:55:58.027334 | instance | Saturday 12 September 2026 03:55:58 +0000 (0:00:00.060) 0:00:03.636 **** 2026-09-12 03:55:58.426217 | instance | changed: [instance] 2026-09-12 03:55:58.426428 | instance | 2026-09-12 03:55:58.426780 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:55:58.427150 | instance | Saturday 12 September 2026 03:55:58 +0000 (0:00:00.401) 0:00:04.038 **** 2026-09-12 03:55:58.499577 | instance | ok: [instance] => { 2026-09-12 03:55:58.499866 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-12 03:55:58.500113 | instance | } 2026-09-12 03:55:58.500438 | instance | 2026-09-12 03:55:58.500808 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:55:58.501082 | instance | Saturday 12 September 2026 03:55:58 +0000 (0:00:00.073) 0:00:04.112 **** 2026-09-12 03:55:59.168255 | instance | changed: [instance] 2026-09-12 03:55:59.168386 | instance | 2026-09-12 03:55:59.168552 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:55:59.168713 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:00.668) 0:00:04.781 **** 2026-09-12 03:55:59.226341 | instance | skipping: [instance] 2026-09-12 03:55:59.226562 | instance | 2026-09-12 03:55:59.226892 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:55:59.227139 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:00.058) 0:00:04.839 **** 2026-09-12 03:55:59.280357 | instance | skipping: [instance] 2026-09-12 03:55:59.280573 | instance | 2026-09-12 03:55:59.280844 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:55:59.281086 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:00.053) 0:00:04.893 **** 2026-09-12 03:55:59.515038 | instance | ok: [instance] 2026-09-12 03:55:59.515274 | instance | 2026-09-12 03:55:59.515519 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 03:55:59.515775 | instance | Saturday 12 September 2026 03:55:59 +0000 (0:00:00.234) 0:00:05.128 **** 2026-09-12 03:56:00.788972 | instance | ok: [instance] 2026-09-12 03:56:00.789247 | instance | 2026-09-12 03:56:00.789575 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:56:00.789848 | instance | Saturday 12 September 2026 03:56:00 +0000 (0:00:01.273) 0:00:06.401 **** 2026-09-12 03:56:00.869828 | instance | ok: [instance] => { 2026-09-12 03:56:00.869959 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-12 03:56:00.870089 | instance | } 2026-09-12 03:56:00.870228 | instance | 2026-09-12 03:56:00.870384 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:56:00.870538 | instance | Saturday 12 September 2026 03:56:00 +0000 (0:00:00.081) 0:00:06.483 **** 2026-09-12 03:56:01.626963 | instance | changed: [instance] 2026-09-12 03:56:01.627181 | instance | 2026-09-12 03:56:01.627441 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:56:01.627693 | instance | Saturday 12 September 2026 03:56:01 +0000 (0:00:00.756) 0:00:07.239 **** 2026-09-12 03:56:04.476969 | instance | changed: [instance] 2026-09-12 03:56:04.477772 | instance | 2026-09-12 03:56:04.477828 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-12 03:56:04.477842 | instance | Saturday 12 September 2026 03:56:04 +0000 (0:00:02.850) 0:00:10.090 **** 2026-09-12 03:56:04.518722 | instance | skipping: [instance] 2026-09-12 03:56:04.518830 | instance | 2026-09-12 03:56:04.519445 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-12 03:56:04.519487 | instance | Saturday 12 September 2026 03:56:04 +0000 (0:00:00.041) 0:00:10.131 **** 2026-09-12 03:56:04.562693 | instance | skipping: [instance] 2026-09-12 03:56:04.563466 | instance | 2026-09-12 03:56:04.563522 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-12 03:56:04.563534 | instance | Saturday 12 September 2026 03:56:04 +0000 (0:00:00.043) 0:00:10.175 **** 2026-09-12 03:56:04.602597 | instance | skipping: [instance] 2026-09-12 03:56:04.603400 | instance | 2026-09-12 03:56:04.603447 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-12 03:56:04.603459 | instance | Saturday 12 September 2026 03:56:04 +0000 (0:00:00.039) 0:00:10.215 **** 2026-09-12 03:56:10.387349 | instance | changed: [instance] 2026-09-12 03:56:10.387626 | instance | 2026-09-12 03:56:10.388227 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-12 03:56:10.388273 | instance | Saturday 12 September 2026 03:56:10 +0000 (0:00:05.784) 0:00:16.000 **** 2026-09-12 03:56:11.025118 | instance | changed: [instance] 2026-09-12 03:56:11.025167 | instance | 2026-09-12 03:56:11.025178 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-12 03:56:11.025188 | instance | Saturday 12 September 2026 03:56:11 +0000 (0:00:00.637) 0:00:16.637 **** 2026-09-12 03:56:12.066719 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-12 03:56:12.068557 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-12 03:56:12.068644 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-12 03:56:12.068667 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-12 03:56:12.068680 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-12 03:56:12.068691 | instance | 2026-09-12 03:56:12.068701 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-12 03:56:12.068710 | instance | Saturday 12 September 2026 03:56:12 +0000 (0:00:01.041) 0:00:17.679 **** 2026-09-12 03:56:12.725636 | instance | changed: [instance] 2026-09-12 03:56:12.725727 | instance | 2026-09-12 03:56:12.726798 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-12 03:56:12.726826 | instance | Saturday 12 September 2026 03:56:12 +0000 (0:00:00.634) 0:00:18.314 **** 2026-09-12 03:56:12.726835 | instance | 2026-09-12 03:56:12.726842 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 03:56:12.726850 | instance | Saturday 12 September 2026 03:56:12 +0000 (0:00:00.024) 0:00:18.338 **** 2026-09-12 03:56:13.627248 | instance | ok: [instance] 2026-09-12 03:56:13.627396 | instance | 2026-09-12 03:56:13.627971 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-12 03:56:13.628038 | instance | Saturday 12 September 2026 03:56:13 +0000 (0:00:00.901) 0:00:19.239 **** 2026-09-12 03:56:14.147894 | instance | changed: [instance] 2026-09-12 03:56:14.148084 | instance | 2026-09-12 03:56:14.148675 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-12 03:56:14.148759 | instance | Saturday 12 September 2026 03:56:14 +0000 (0:00:00.521) 0:00:19.760 **** 2026-09-12 03:56:14.729964 | instance | changed: [instance] 2026-09-12 03:56:14.730034 | instance | 2026-09-12 03:56:14.730342 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 03:56:14.730427 | instance | Saturday 12 September 2026 03:56:14 +0000 (0:00:00.582) 0:00:20.342 **** 2026-09-12 03:56:14.985731 | instance | ok: [instance] 2026-09-12 03:56:14.985844 | instance | 2026-09-12 03:56:14.986230 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 03:56:14.986308 | instance | Saturday 12 September 2026 03:56:14 +0000 (0:00:00.255) 0:00:20.598 **** 2026-09-12 03:56:15.051280 | instance | ok: [instance] => { 2026-09-12 03:56:15.052489 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-12 03:56:15.052565 | instance | } 2026-09-12 03:56:15.052577 | instance | 2026-09-12 03:56:15.052586 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 03:56:15.052598 | instance | Saturday 12 September 2026 03:56:15 +0000 (0:00:00.065) 0:00:20.663 **** 2026-09-12 03:56:15.796945 | instance | changed: [instance] 2026-09-12 03:56:15.797997 | instance | 2026-09-12 03:56:15.798094 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 03:56:15.798110 | instance | Saturday 12 September 2026 03:56:15 +0000 (0:00:00.744) 0:00:21.408 **** 2026-09-12 03:56:20.201336 | instance | changed: [instance] 2026-09-12 03:56:20.202017 | instance | 2026-09-12 03:56:20.202072 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-12 03:56:20.202090 | instance | Saturday 12 September 2026 03:56:20 +0000 (0:00:04.405) 0:00:25.814 **** 2026-09-12 03:56:21.242435 | instance | ok: [instance] 2026-09-12 03:56:21.243450 | instance | 2026-09-12 03:56:21.243487 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-12 03:56:21.243501 | instance | Saturday 12 September 2026 03:56:21 +0000 (0:00:01.040) 0:00:26.855 **** 2026-09-12 03:56:21.648129 | instance | changed: [instance] 2026-09-12 03:56:21.648179 | instance | 2026-09-12 03:56:21.648469 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-12 03:56:21.648486 | instance | Saturday 12 September 2026 03:56:21 +0000 (0:00:00.406) 0:00:27.261 **** 2026-09-12 03:56:22.153877 | instance | changed: [instance] 2026-09-12 03:56:22.154990 | instance | 2026-09-12 03:56:22.155029 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-12 03:56:22.155045 | instance | Saturday 12 September 2026 03:56:22 +0000 (0:00:00.505) 0:00:27.766 **** 2026-09-12 03:56:22.771669 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-12 03:56:22.771767 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-12 03:56:22.772234 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-12 03:56:22.772272 | instance | 2026-09-12 03:56:22.772278 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-12 03:56:22.772282 | instance | Saturday 12 September 2026 03:56:22 +0000 (0:00:00.618) 0:00:28.385 **** 2026-09-12 03:56:23.265403 | instance | changed: [instance] 2026-09-12 03:56:23.265975 | instance | 2026-09-12 03:56:23.266228 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-12 03:56:23.266606 | instance | Saturday 12 September 2026 03:56:23 +0000 (0:00:00.492) 0:00:28.877 **** 2026-09-12 03:56:23.822676 | instance | changed: [instance] 2026-09-12 03:56:23.823634 | instance | 2026-09-12 03:56:23.823680 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-12 03:56:23.823701 | instance | Saturday 12 September 2026 03:56:23 +0000 (0:00:00.530) 0:00:29.408 **** 2026-09-12 03:56:23.823707 | instance | 2026-09-12 03:56:23.823713 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 03:56:23.823725 | instance | Saturday 12 September 2026 03:56:23 +0000 (0:00:00.027) 0:00:29.436 **** 2026-09-12 03:56:24.584786 | instance | ok: [instance] 2026-09-12 03:56:24.585762 | instance | 2026-09-12 03:56:24.585801 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-12 03:56:24.585816 | instance | Saturday 12 September 2026 03:56:24 +0000 (0:00:00.761) 0:00:30.197 **** 2026-09-12 03:56:25.716465 | instance | changed: [instance] 2026-09-12 03:56:25.716585 | instance | 2026-09-12 03:56:25.716988 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-12 03:56:25.717058 | instance | Saturday 12 September 2026 03:56:25 +0000 (0:00:01.132) 0:00:31.329 **** 2026-09-12 03:56:26.394792 | instance | changed: [instance] 2026-09-12 03:56:26.394913 | instance | 2026-09-12 03:56:26.395338 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-12 03:56:26.395382 | instance | Saturday 12 September 2026 03:56:26 +0000 (0:00:00.678) 0:00:32.008 **** 2026-09-12 03:56:26.464921 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-12 03:56:26.465031 | instance | 2026-09-12 03:56:26.465359 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-12 03:56:26.465403 | instance | Saturday 12 September 2026 03:56:26 +0000 (0:00:00.070) 0:00:32.078 **** 2026-09-12 03:56:32.086883 | instance | changed: [instance] 2026-09-12 03:56:32.087709 | instance | 2026-09-12 03:56:32.087946 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-12 03:56:32.087965 | instance | Saturday 12 September 2026 03:56:32 +0000 (0:00:05.621) 0:00:37.699 **** 2026-09-12 03:56:32.772424 | instance | ok: [instance] => (item=chronyd) 2026-09-12 03:56:32.772551 | instance | ok: [instance] => (item=sshd) 2026-09-12 03:56:32.773078 | instance | 2026-09-12 03:56:32.773133 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-12 03:56:32.773141 | instance | Saturday 12 September 2026 03:56:32 +0000 (0:00:00.686) 0:00:38.385 **** 2026-09-12 03:56:45.894121 | instance | changed: [instance] 2026-09-12 03:56:45.894229 | instance | 2026-09-12 03:56:45.894351 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-12 03:56:45.894607 | instance | Saturday 12 September 2026 03:56:45 +0000 (0:00:13.120) 0:00:51.506 **** 2026-09-12 03:56:46.131523 | instance | ok: [instance] 2026-09-12 03:56:46.131644 | instance | 2026-09-12 03:56:46.131916 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-12 03:56:46.132205 | instance | Saturday 12 September 2026 03:56:46 +0000 (0:00:00.238) 0:00:51.744 **** 2026-09-12 03:56:46.632400 | instance | changed: [instance] 2026-09-12 03:56:46.632646 | instance | 2026-09-12 03:56:46.632940 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-12 03:56:46.633228 | instance | Saturday 12 September 2026 03:56:46 +0000 (0:00:00.501) 0:00:52.245 **** 2026-09-12 03:56:47.034883 | instance | changed: [instance] 2026-09-12 03:56:47.034971 | instance | 2026-09-12 03:56:47.035023 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-12 03:56:47.035177 | instance | Saturday 12 September 2026 03:56:47 +0000 (0:00:00.402) 0:00:52.648 **** 2026-09-12 03:56:47.142096 | instance | ok: [instance] 2026-09-12 03:56:47.142375 | instance | 2026-09-12 03:56:47.142730 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-12 03:56:47.143101 | instance | Saturday 12 September 2026 03:56:47 +0000 (0:00:00.107) 0:00:52.755 **** 2026-09-12 03:56:47.382859 | instance | ok: [instance] => (item=instance) 2026-09-12 03:56:47.383125 | instance | 2026-09-12 03:56:47.383330 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-12 03:56:47.383555 | instance | Saturday 12 September 2026 03:56:47 +0000 (0:00:00.240) 0:00:52.996 **** 2026-09-12 03:56:47.433964 | instance | ok: [instance] 2026-09-12 03:56:47.434314 | instance | 2026-09-12 03:56:47.434579 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-12 03:56:47.434860 | instance | Saturday 12 September 2026 03:56:47 +0000 (0:00:00.051) 0:00:53.047 **** 2026-09-12 03:56:47.517489 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-12 03:56:47.517661 | instance | 2026-09-12 03:56:47.517961 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-12 03:56:47.518336 | instance | Saturday 12 September 2026 03:56:47 +0000 (0:00:00.083) 0:00:53.130 **** 2026-09-12 03:56:47.873198 | instance | changed: [instance] 2026-09-12 03:56:47.873405 | instance | 2026-09-12 03:56:47.873647 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-12 03:56:47.873879 | instance | Saturday 12 September 2026 03:56:47 +0000 (0:00:00.355) 0:00:53.486 **** 2026-09-12 03:56:48.636134 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-12 03:56:48.636190 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-12 03:56:48.636201 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-12 03:56:48.636210 | instance | 2026-09-12 03:56:48.636220 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-12 03:56:48.636229 | instance | Saturday 12 September 2026 03:56:48 +0000 (0:00:00.761) 0:00:54.247 **** 2026-09-12 03:58:24.826288 | instance | ok: [instance] 2026-09-12 03:58:24.827051 | instance | 2026-09-12 03:58:24.827065 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-12 03:58:24.827244 | instance | Saturday 12 September 2026 03:58:24 +0000 (0:01:36.190) 0:02:30.438 **** 2026-09-12 03:58:25.091207 | instance | changed: [instance] 2026-09-12 03:58:25.091322 | instance | 2026-09-12 03:58:25.091460 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-12 03:58:25.091608 | instance | Saturday 12 September 2026 03:58:25 +0000 (0:00:00.265) 0:02:30.704 **** 2026-09-12 03:58:25.149346 | instance | ok: [instance] 2026-09-12 03:58:25.149409 | instance | 2026-09-12 03:58:25.149528 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-12 03:58:25.149650 | instance | Saturday 12 September 2026 03:58:25 +0000 (0:00:00.058) 0:02:30.762 **** 2026-09-12 03:58:25.273021 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-12 03:58:25.273136 | instance | 2026-09-12 03:58:25.273290 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-12 03:58:25.273440 | instance | Saturday 12 September 2026 03:58:25 +0000 (0:00:00.123) 0:02:30.886 **** 2026-09-12 03:58:26.796431 | instance | ok: [instance] 2026-09-12 03:58:26.796512 | instance | 2026-09-12 03:58:26.796648 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-12 03:58:26.796738 | instance | Saturday 12 September 2026 03:58:26 +0000 (0:00:01.522) 0:02:32.409 **** 2026-09-12 03:58:26.864558 | instance | ok: [instance] => (item=instance) 2026-09-12 03:58:26.864663 | instance | 2026-09-12 03:58:26.864727 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-12 03:58:26.864813 | instance | Saturday 12 September 2026 03:58:26 +0000 (0:00:00.068) 0:02:32.477 **** 2026-09-12 03:58:27.195582 | instance | ok: [instance] 2026-09-12 03:58:27.195820 | instance | 2026-09-12 03:58:27.196077 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-12 03:58:27.196310 | instance | Saturday 12 September 2026 03:58:27 +0000 (0:00:00.331) 0:02:32.809 **** 2026-09-12 03:58:29.825994 | instance | ok: [instance] 2026-09-12 03:58:29.826056 | instance | 2026-09-12 03:58:29.826068 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-12 03:58:29.826077 | instance | Saturday 12 September 2026 03:58:29 +0000 (0:00:02.628) 0:02:35.438 **** 2026-09-12 03:59:22.535150 | 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-12 03:59:22.535939 | instance | fatal: [instance]: FAILED! => {"changed": false, "module_stderr": "Traceback (most recent call last):\n File \"\", line 107, in \n File \"\", line 99, in _ansiballz_main\n File \"\", 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_yerqq4_x/ansible_vexxhost.ceph.orch_apply_payload.zip/ansible_collections/vexxhost/ceph/plugins/modules/orch_apply.py\", line 202, in \n File \"/tmp/ansible_vexxhost.ceph.orch_apply_payload_yerqq4_x/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_yerqq4_x/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_yerqq4_x/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-12T03:59:22.375+0000 7efc4f16e640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCzzaRqr+ihBiAArqdcPrI+uF+jLmRCn1/RLYQm5rAZg5Ytvs/YFjFJquI=: Malformed input [buffer:3]\n2026-09-12T03:59:22.375+0000 7efc4f16e640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T03:59:22.379+0000 7efc4f16e640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCzzaRqr+ihBiAArqdcPrI+uF+jLmRCn1/RLYQm5rAZg5Ytvs/YFjFJquI=: Malformed input [buffer:3]\n2026-09-12T03:59:22.379+0000 7efc4f16e640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T03:59:22.379+0000 7efc4f16e640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgCzzaRqr+ihBiAArqdcPrI+uF+jLmRCn1/RLYQm5rAZg5Ytvs/YFjFJquI=: Malformed input [buffer:3]\n2026-09-12T03:59:22.379+0000 7efc4f16e640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T03:59:22.379+0000 7efc4f16e640 -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-12 03:59:22.539111 | instance | 2026-09-12 03:59:22.539361 | instance | PLAY RECAP ********************************************************************* 2026-09-12 03:59:22.540654 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-12 03:59:22.540688 | instance | 2026-09-12 03:59:22.540699 | instance | Saturday 12 September 2026 03:59:22 +0000 (0:00:52.714) 0:03:28.152 **** 2026-09-12 03:59:22.540709 | instance | =============================================================================== 2026-09-12 03:59:22.540718 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 96.19s 2026-09-12 03:59:22.540731 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 52.71s 2026-09-12 03:59:22.542027 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 13.12s 2026-09-12 03:59:22.542110 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.78s 2026-09-12 03:59:22.542122 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.62s 2026-09-12 03:59:22.542132 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.41s 2026-09-12 03:59:22.542160 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.85s 2026-09-12 03:59:22.542266 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.63s 2026-09-12 03:59:22.545312 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.66s 2026-09-12 03:59:22.545348 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.52s 2026-09-12 03:59:22.545359 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.27s 2026-09-12 03:59:22.545368 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.13s 2026-09-12 03:59:22.545377 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.04s 2026-09-12 03:59:22.545386 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.04s 2026-09-12 03:59:22.545394 | instance | Gathering Facts --------------------------------------------------------- 1.04s 2026-09-12 03:59:22.545403 | instance | Gathering Facts --------------------------------------------------------- 0.99s 2026-09-12 03:59:22.545412 | instance | Gathering Facts --------------------------------------------------------- 0.90s 2026-09-12 03:59:22.545420 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.76s 2026-09-12 03:59:22.545429 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.76s 2026-09-12 03:59:22.545437 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.74s 2026-09-12 03:59:22.638852 | 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-12 03:59:22.639148 | instance | ERROR [csi > converge] Executed: Failed 2026-09-12 03:59:22.639334 | 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-12 03:59:23.051990 | instance | ERROR 2026-09-12 03:59:23.052290 | instance | { 2026-09-12 03:59:23.052346 | instance | "delta": "0:04:20.098116", 2026-09-12 03:59:23.052385 | instance | "end": "2026-09-12 03:59:22.709636", 2026-09-12 03:59:23.052420 | instance | "msg": "non-zero return code", 2026-09-12 03:59:23.052453 | instance | "rc": 2, 2026-09-12 03:59:23.052486 | instance | "start": "2026-09-12 03:55:02.611520" 2026-09-12 03:59:23.052553 | instance | } failure 2026-09-12 03:59:23.061584 | 2026-09-12 03:59:23.061677 | PLAY RECAP 2026-09-12 03:59:23.061752 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-12 03:59:23.061793 | 2026-09-12 03:59:23.200277 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 03:59:23.224026 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 03:59:23.828209 | 2026-09-12 03:59:23.828750 | PLAY [all] 2026-09-12 03:59:23.843749 | 2026-09-12 03:59:23.843854 | TASK [gather-host-logs : creating directory for system status] 2026-09-12 03:59:24.213606 | instance | changed 2026-09-12 03:59:24.224419 | 2026-09-12 03:59:24.224660 | TASK [gather-host-logs : Get logs for each host] 2026-09-12 03:59:24.612931 | instance | + systemd-cgls --full --all --no-pager 2026-09-12 03:59:24.628255 | instance | + ip addr 2026-09-12 03:59:24.630655 | instance | + ip route 2026-09-12 03:59:24.632950 | instance | + lsblk 2026-09-12 03:59:24.637702 | instance | + mount 2026-09-12 03:59:24.640236 | instance | + docker images 2026-09-12 03:59:24.664522 | instance | + brctl show 2026-09-12 03:59:24.665135 | instance | /bin/bash: line 8: brctl: command not found 2026-09-12 03:59:24.665569 | instance | + ip neigh 2026-09-12 03:59:24.668676 | instance | + bridge fdb show 2026-09-12 03:59:24.673018 | instance | + ps aux --sort=-%mem 2026-09-12 03:59:24.692712 | instance | + dpkg -l 2026-09-12 03:59:24.703719 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-12 03:59:24.704204 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-12 03:59:24.724458 | instance | + '[' '!' -z '' ']' 2026-09-12 03:59:24.777008 | instance | ok: Runtime: 0:00:00.115517 2026-09-12 03:59:24.787534 | 2026-09-12 03:59:24.787690 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-12 03:59:25.490729 | instance | changed: 2026-09-12 03:59:25.490995 | instance | created directory /var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/logs/instance 2026-09-12 03:59:25.491053 | instance | cd+++++++++ system/ 2026-09-12 03:59:25.491097 | instance | >f+++++++++ system/brctl-show.txt 2026-09-12 03:59:25.491137 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-12 03:59:25.491175 | instance | >f+++++++++ system/docker-images.txt 2026-09-12 03:59:25.491220 | instance | >f+++++++++ system/ip-addr.txt 2026-09-12 03:59:25.491260 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-12 03:59:25.491312 | instance | >f+++++++++ system/ip-route.txt 2026-09-12 03:59:25.491355 | instance | >f+++++++++ system/lsblk.txt 2026-09-12 03:59:25.491393 | instance | >f+++++++++ system/mount.txt 2026-09-12 03:59:25.491429 | instance | >f+++++++++ system/packages.txt 2026-09-12 03:59:25.491464 | instance | >f+++++++++ system/ps.txt 2026-09-12 03:59:25.491499 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-12 03:59:25.508913 | 2026-09-12 03:59:25.509048 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-12 03:59:25.730802 | instance | changed: "values" 2026-09-12 03:59:25.915542 | instance | changed: "releases" 2026-09-12 03:59:25.932941 | 2026-09-12 03:59:25.933100 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-12 03:59:26.204694 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-12 03:59:26.483705 | instance | ok: Runtime: 0:00:00.005999 2026-09-12 03:59:26.493294 | 2026-09-12 03:59:26.493425 | TASK [helm-release-status : Downloads logs to executor] 2026-09-12 03:59:27.044119 | instance | changed: 2026-09-12 03:59:27.044369 | instance | cd+++++++++ helm/ 2026-09-12 03:59:27.044428 | instance | cd+++++++++ helm/releases/ 2026-09-12 03:59:27.044473 | instance | cd+++++++++ helm/values/ 2026-09-12 03:59:27.063018 | 2026-09-12 03:59:27.063156 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-12 03:59:27.289601 | instance | changed 2026-09-12 03:59:27.300667 | 2026-09-12 03:59:27.300811 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-12 03:59:27.608382 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:27.609174 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:27.615245 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:27.617430 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:27.617539 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:27.619315 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:27.621512 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:27.622299 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:27.624276 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:27.625705 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:27.626957 | instance | environment: line 1: kubectl: command not found 2026-09-12 03:59:27.627875 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 03:59:27.850573 | instance | ok: Runtime: 0:00:00.029892 2026-09-12 03:59:27.859862 | 2026-09-12 03:59:27.859989 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-12 03:59:28.095741 | instance | changed 2026-09-12 03:59:28.105001 | 2026-09-12 03:59:28.105136 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-12 03:59:28.363771 | instance | environment: line 5: kubectl: command not found 2026-09-12 03:59:28.364435 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:28.365371 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:28.365810 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 03:59:28.654394 | instance | ok: Runtime: 0:00:00.011288 2026-09-12 03:59:28.662129 | 2026-09-12 03:59:28.662219 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-12 03:59:29.202412 | instance | changed: 2026-09-12 03:59:29.202687 | instance | cd+++++++++ objects/ 2026-09-12 03:59:29.202741 | instance | cd+++++++++ objects/cluster/ 2026-09-12 03:59:29.202782 | instance | cd+++++++++ objects/namespaced/ 2026-09-12 03:59:29.220087 | 2026-09-12 03:59:29.220210 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-12 03:59:29.443809 | instance | changed 2026-09-12 03:59:29.450426 | 2026-09-12 03:59:29.450513 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-12 03:59:29.675171 | instance | changed 2026-09-12 03:59:29.681396 | 2026-09-12 03:59:29.681486 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-12 03:59:29.937854 | instance | environment: line 3: kubectl: command not found 2026-09-12 03:59:30.214083 | instance | ok: Runtime: 0:00:00.009294 2026-09-12 03:59:30.224029 | 2026-09-12 03:59:30.224150 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-12 03:59:30.762747 | instance | changed: 2026-09-12 03:59:30.762939 | instance | cd+++++++++ pod-logs/ 2026-09-12 03:59:30.762975 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-12 03:59:30.775513 | 2026-09-12 03:59:30.775613 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-12 03:59:31.002396 | instance | changed 2026-09-12 03:59:31.013206 | 2026-09-12 03:59:31.013343 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-12 03:59:31.285019 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 03:59:31.563048 | instance | ok: Runtime: 0:00:00.053129 2026-09-12 03:59:31.574489 | 2026-09-12 03:59:31.574770 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-12 03:59:31.849977 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 03:59:31.873583 | instance | ceph-mgr endpoints: 2026-09-12 03:59:32.133212 | instance | ok: Runtime: 0:00:00.029262 2026-09-12 03:59:32.142787 | 2026-09-12 03:59:32.142955 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-12 03:59:32.408407 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-12 03:59:32.691714 | instance | ok: Runtime: 0:00:00.055192 2026-09-12 03:59:32.702502 | 2026-09-12 03:59:32.702662 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-12 03:59:33.230806 | instance | changed: cd+++++++++ prometheus/ 2026-09-12 03:59:33.242152 | 2026-09-12 03:59:33.242230 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-12 03:59:33.497386 | instance | changed 2026-09-12 03:59:33.503457 | 2026-09-12 03:59:33.503537 | TASK [gather-selenium-data : Get selenium data] 2026-09-12 03:59:33.796821 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-12 03:59:33.798350 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-12 03:59:34.044811 | instance | ERROR 2026-09-12 03:59:34.045132 | instance | { 2026-09-12 03:59:34.045195 | instance | "delta": "0:00:00.005961", 2026-09-12 03:59:34.045237 | instance | "end": "2026-09-12 03:59:33.798681", 2026-09-12 03:59:34.045275 | instance | "msg": "non-zero return code", 2026-09-12 03:59:34.045311 | instance | "rc": 1, 2026-09-12 03:59:34.045347 | instance | "start": "2026-09-12 03:59:33.792720" 2026-09-12 03:59:34.045381 | instance | } 2026-09-12 03:59:34.045427 | instance | ERROR: Ignoring Errors 2026-09-12 03:59:34.054938 | 2026-09-12 03:59:34.055072 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-12 03:59:34.585464 | instance | changed: cd+++++++++ selenium/ 2026-09-12 03:59:34.596689 | 2026-09-12 03:59:34.596791 | PLAY RECAP 2026-09-12 03:59:34.596904 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-12 03:59:34.596972 | 2026-09-12 03:59:34.737157 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 03:59:34.741253 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 03:59:35.351499 | 2026-09-12 03:59:35.351654 | PLAY [all] 2026-09-12 03:59:35.366937 | 2026-09-12 03:59:35.367141 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-12 03:59:35.424423 | instance | skipping: Conditional result was False 2026-09-12 03:59:35.434180 | 2026-09-12 03:59:35.434393 | TASK [fetch-output : Set log path for single node] 2026-09-12 03:59:35.477901 | instance | ok 2026-09-12 03:59:35.486254 | 2026-09-12 03:59:35.486350 | LOOP [fetch-output : Ensure local output dirs] 2026-09-12 03:59:35.933809 | instance -> localhost | ok: "/var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/logs" 2026-09-12 03:59:36.181773 | instance -> localhost | changed: "/var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/artifacts" 2026-09-12 03:59:36.429070 | instance -> localhost | changed: "/var/lib/zuul/builds/67a1e08ede024b418efeae0f57cd70cc/work/docs" 2026-09-12 03:59:36.451855 | 2026-09-12 03:59:36.452069 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-12 03:59:37.177546 | instance | changed: .d..t...... ./ 2026-09-12 03:59:37.177868 | instance | changed: All items complete 2026-09-12 03:59:37.177923 | 2026-09-12 03:59:37.678330 | instance | changed: .d..t...... ./ 2026-09-12 03:59:38.198812 | instance | changed: .d..t...... ./ 2026-09-12 03:59:38.227571 | 2026-09-12 03:59:38.227795 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-12 03:59:38.694028 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008720 2026-09-12 03:59:38.985795 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009359 2026-09-12 03:59:39.009973 | 2026-09-12 03:59:39.010164 | PLAY [all] 2026-09-12 03:59:39.024675 | 2026-09-12 03:59:39.024815 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-12 03:59:39.639471 | instance | changed 2026-09-12 03:59:39.649785 | 2026-09-12 03:59:39.649915 | PLAY RECAP 2026-09-12 03:59:39.650008 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-12 03:59:39.650057 | 2026-09-12 03:59:39.804651 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 03:59:39.808845 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-12 03:59:40.506692 | 2026-09-12 03:59:40.506867 | PLAY [localhost] 2026-09-12 03:59:40.519090 | 2026-09-12 03:59:40.519186 | TASK [Generate Zuul manifest] 2026-09-12 03:59:40.537157 | localhost | ok 2026-09-12 03:59:40.558064 | 2026-09-12 03:59:40.558177 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-12 03:59:40.984064 | localhost | changed 2026-09-12 03:59:41.003798 | 2026-09-12 03:59:41.003974 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-12 03:59:41.036475 | localhost | ok 2026-09-12 03:59:41.050102 | 2026-09-12 03:59:41.050243 | TASK [Upload logs] 2026-09-12 03:59:41.072908 | localhost | ok 2026-09-12 03:59:41.143789 | 2026-09-12 03:59:41.143947 | TASK [Set zuul-log-path fact] 2026-09-12 03:59:41.160470 | localhost | ok 2026-09-12 03:59:41.171070 | 2026-09-12 03:59:41.171132 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 03:59:41.208989 | localhost | ok 2026-09-12 03:59:41.216902 | 2026-09-12 03:59:41.216984 | TASK [upload-logs : Create log directories] 2026-09-12 03:59:41.684201 | localhost | changed 2026-09-12 03:59:41.693740 | 2026-09-12 03:59:41.693896 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-12 03:59:42.109261 | localhost -> localhost | ok: Runtime: 0:00:00.006260 2026-09-12 03:59:42.114046 | 2026-09-12 03:59:42.114111 | TASK [upload-logs : Upload logs to log server] 2026-09-12 03:59:42.662937 | localhost | Output suppressed because no_log was given 2026-09-12 03:59:42.707403 | 2026-09-12 03:59:42.707547 | LOOP [upload-logs : Compress console log and json output] 2026-09-12 03:59:42.767589 | localhost | skipping: Conditional result was False 2026-09-12 03:59:42.776647 | localhost | skipping: Conditional result was False 2026-09-12 03:59:42.790496 | 2026-09-12 03:59:42.790688 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-12 03:59:42.847994 | localhost | skipping: Conditional result was False 2026-09-12 03:59:42.848402 | 2026-09-12 03:59:42.853185 | localhost | skipping: Conditional result was False 2026-09-12 03:59:42.865618 | 2026-09-12 03:59:42.865872 | LOOP [upload-logs : Upload console log and json output]