2026-09-08 15:37:46.505211 | Job console starting 2026-09-08 15:37:46.514005 | Updating git repos 2026-09-08 15:37:49.477317 | Cloning repos into workspace 2026-09-08 15:38:06.821741 | Restoring repo states 2026-09-08 15:38:21.216366 | Merging changes 2026-09-08 15:38:22.297886 | Checking out repos 2026-09-08 15:38:24.182894 | Preparing playbooks 2026-09-08 15:38:26.660634 | Running Ansible setup 2026-09-08 15:38:30.560010 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-08 15:38:31.238786 | 2026-09-08 15:38:31.238951 | PLAY [localhost] 2026-09-08 15:38:31.246719 | 2026-09-08 15:38:31.246825 | TASK [Gathering Facts] 2026-09-08 15:38:32.135381 | localhost | ok 2026-09-08 15:38:32.145633 | 2026-09-08 15:38:32.145772 | TASK [Setup log path fact] 2026-09-08 15:38:32.172759 | localhost | ok 2026-09-08 15:38:32.194706 | 2026-09-08 15:38:32.194853 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-08 15:38:32.242015 | localhost | ok 2026-09-08 15:38:32.252151 | 2026-09-08 15:38:32.252293 | TASK [emit-job-header : Print job information] 2026-09-08 15:38:32.295114 | # Job Information 2026-09-08 15:38:32.295304 | Ansible Version: 2.16.18 2026-09-08 15:38:32.295592 | Job: atmosphere-molecule-csi-rbd 2026-09-08 15:38:32.295641 | Pipeline: check 2026-09-08 15:38:32.295672 | Executor: 2d72f0692154 2026-09-08 15:38:32.295714 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4092 2026-09-08 15:38:32.295746 | Event ID: b7c21820-ab9a-11f1-8214-297e545473a3 2026-09-08 15:38:32.299894 | 2026-09-08 15:38:32.299983 | LOOP [emit-job-header : Print node information] 2026-09-08 15:38:32.383039 | localhost | ok: 2026-09-08 15:38:32.383185 | localhost | # Node Information 2026-09-08 15:38:32.383211 | localhost | Inventory Hostname: instance 2026-09-08 15:38:32.383240 | localhost | Hostname: np0000192233 2026-09-08 15:38:32.383284 | localhost | Username: zuul 2026-09-08 15:38:32.383311 | localhost | Distro: Ubuntu 22.04 2026-09-08 15:38:32.383330 | localhost | Provider: yul1 2026-09-08 15:38:32.383359 | localhost | Region: ca-ymq-1 2026-09-08 15:38:32.383376 | localhost | Label: ubuntu-jammy 2026-09-08 15:38:32.383391 | localhost | Product Name: OpenStack Nova 2026-09-08 15:38:32.383406 | localhost | Interface IP: 162.253.55.108 2026-09-08 15:38:32.390057 | 2026-09-08 15:38:32.390168 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-08 15:38:32.799571 | localhost -> localhost | changed 2026-09-08 15:38:32.808933 | 2026-09-08 15:38:32.809216 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-08 15:38:33.780617 | localhost -> localhost | changed 2026-09-08 15:38:33.787890 | 2026-09-08 15:38:33.787966 | PLAY [all] 2026-09-08 15:38:33.798590 | 2026-09-08 15:38:33.798677 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-08 15:38:34.059709 | instance -> localhost | ok 2026-09-08 15:38:34.070013 | 2026-09-08 15:38:34.070145 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-08 15:38:34.102899 | instance | ok 2026-09-08 15:38:34.127690 | instance | included: /var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-08 15:38:34.135046 | 2026-09-08 15:38:34.135124 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-08 15:38:35.079177 | instance -> localhost | Generating public/private rsa key pair. 2026-09-08 15:38:35.079379 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/bd12c303111b485db78fea9d6fd7f706_id_rsa 2026-09-08 15:38:35.079418 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/bd12c303111b485db78fea9d6fd7f706_id_rsa.pub 2026-09-08 15:38:35.079474 | instance -> localhost | The key fingerprint is: 2026-09-08 15:38:35.079503 | instance -> localhost | SHA256:hMTjnUGz2ZcRPpQZ61hdHYJCEWP8hDBRtHYvt2Qc71Y zuul-build-sshkey 2026-09-08 15:38:35.079549 | instance -> localhost | The key's randomart image is: 2026-09-08 15:38:35.079578 | instance -> localhost | +---[RSA 3072]----+ 2026-09-08 15:38:35.079614 | instance -> localhost | | ..=XO+ ** .+| 2026-09-08 15:38:35.079643 | instance -> localhost | | .o.+Oo=o=...| 2026-09-08 15:38:35.079670 | instance -> localhost | | ..o+==.B.. | 2026-09-08 15:38:35.079699 | instance -> localhost | | ..+ .*o.o | 2026-09-08 15:38:35.079726 | instance -> localhost | | S ...* .E| 2026-09-08 15:38:35.079752 | instance -> localhost | | = o .| 2026-09-08 15:38:35.079777 | instance -> localhost | | . o | 2026-09-08 15:38:35.079804 | instance -> localhost | | . | 2026-09-08 15:38:35.079834 | instance -> localhost | | | 2026-09-08 15:38:35.079861 | instance -> localhost | +----[SHA256]-----+ 2026-09-08 15:38:35.079933 | instance -> localhost | ok: Runtime: 0:00:00.404798 2026-09-08 15:38:35.087454 | 2026-09-08 15:38:35.087515 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-08 15:38:35.131186 | instance | ok 2026-09-08 15:38:35.140980 | instance | included: /var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-08 15:38:35.148377 | 2026-09-08 15:38:35.148519 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-08 15:38:35.171492 | instance | skipping: Conditional result was False 2026-09-08 15:38:35.181760 | 2026-09-08 15:38:35.181893 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-08 15:38:35.658929 | instance | changed 2026-09-08 15:38:35.666251 | 2026-09-08 15:38:35.666338 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-08 15:38:35.878782 | instance | ok 2026-09-08 15:38:35.885481 | 2026-09-08 15:38:35.885565 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-08 15:38:36.363767 | instance | changed 2026-09-08 15:38:36.373515 | 2026-09-08 15:38:36.373666 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-08 15:38:36.893690 | instance | changed 2026-09-08 15:38:36.901618 | 2026-09-08 15:38:36.901729 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-08 15:38:36.924863 | instance | skipping: Conditional result was False 2026-09-08 15:38:36.936603 | 2026-09-08 15:38:36.936716 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-08 15:38:37.385382 | instance -> localhost | changed 2026-09-08 15:38:37.409275 | 2026-09-08 15:38:37.409722 | TASK [add-build-sshkey : Add back temp key] 2026-09-08 15:38:37.747098 | instance -> localhost | Identity added: /var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/bd12c303111b485db78fea9d6fd7f706_id_rsa (zuul-build-sshkey) 2026-09-08 15:38:37.747336 | instance -> localhost | ok: Runtime: 0:00:00.016137 2026-09-08 15:38:37.755046 | 2026-09-08 15:38:37.755363 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-08 15:38:38.099495 | instance | ok 2026-09-08 15:38:38.108819 | 2026-09-08 15:38:38.108949 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-08 15:38:38.146617 | instance | skipping: Conditional result was False 2026-09-08 15:38:38.162175 | 2026-09-08 15:38:38.162262 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-08 15:38:38.491092 | instance | ok 2026-09-08 15:38:38.502730 | 2026-09-08 15:38:38.502895 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-08 15:38:42.278073 | instance | Output suppressed because no_log was given 2026-09-08 15:38:42.295165 | 2026-09-08 15:38:42.295358 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-08 15:38:42.517899 | instance | ok: "logs" 2026-09-08 15:38:42.518246 | instance | ok: All items complete 2026-09-08 15:38:42.518302 | 2026-09-08 15:38:42.697026 | instance | ok: "artifacts" 2026-09-08 15:38:42.874150 | instance | ok: "docs" 2026-09-08 15:38:42.891720 | 2026-09-08 15:38:42.891860 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-08 15:38:43.082273 | instance | changed: "logs" 2026-09-08 15:38:43.223533 | instance | changed: "artifacts" 2026-09-08 15:38:43.364858 | instance | changed: "docs" 2026-09-08 15:38:43.387409 | 2026-09-08 15:38:43.387513 | PLAY RECAP 2026-09-08 15:38:43.387570 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-08 15:38:43.387658 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-08 15:38:43.387699 | 2026-09-08 15:38:43.532198 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-08 15:38:43.534576 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-08 15:38:44.131985 | 2026-09-08 15:38:44.132088 | PLAY [all] 2026-09-08 15:38:44.141645 | 2026-09-08 15:38:44.141726 | TASK [setup-uv : Extract archive] 2026-09-08 15:38:46.393729 | instance | changed 2026-09-08 15:38:46.404976 | 2026-09-08 15:38:46.405305 | TASK [setup-uv : Print version] 2026-09-08 15:38:46.787704 | instance | uv 0.8.13 2026-09-08 15:38:46.951187 | instance | ok: Runtime: 0:00:00.015343 2026-09-08 15:38:46.969186 | 2026-09-08 15:38:46.969369 | TASK [Install Ansible collection siblings] 2026-09-08 15:38:56.597478 | instance | changed: 2026-09-08 15:38:56.597886 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-08 15:38:56.597961 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-08 15:38:56.598005 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-08 15:38:56.598043 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-08 15:38:56.598079 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-08 15:38:56.598124 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-08 15:38:56.598163 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-08 15:38:56.598205 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-08 15:38:56.598246 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-08 15:38:56.598286 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-08 15:38:56.598325 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-08 15:38:56.598364 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-08 15:38:56.598402 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-08 15:38:56.598461 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-08 15:38:56.598500 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-08 15:38:56.598536 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-08 15:38:56.598570 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-08 15:38:56.598605 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-08 15:38:56.598640 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-08 15:38:56.598808 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-08 15:38:56.598849 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-08 15:38:56.598891 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-08 15:38:56.598950 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-08 15:38:56.599036 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-08 15:38:56.599101 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-08 15:38:56.599159 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-08 15:38:56.599219 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-08 15:38:56.599277 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-09-08 15:38:56.599334 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-08 15:38:56.599391 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-08 15:38:56.599474 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-08 15:38:56.599537 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-08 15:38:56.599595 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-08 15:38:56.599654 | 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-08 15:38:56.599731 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.599791 | instance | Process install dependency map 2026-09-08 15:38:56.599849 | instance | Starting collection install process 2026-09-08 15:38:56.599906 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-08 15:38:56.599962 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-08 15:38:56.600019 | instance | ansible.netcommon:* was installed successfully 2026-09-08 15:38:56.600072 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-08 15:38:56.600111 | instance | Creating virtual environment at: .venv 2026-09-08 15:38:56.600145 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-08 15:38:56.600182 | instance | Downloading pygments (1.2MiB) 2026-09-08 15:38:56.600217 | instance | Downloading ansible-core (2.1MiB) 2026-09-08 15:38:56.600256 | instance | Downloading netaddr (2.2MiB) 2026-09-08 15:38:56.600292 | instance | Downloading rjsonnet (1.2MiB) 2026-09-08 15:38:56.600329 | instance | Downloading setuptools (1.1MiB) 2026-09-08 15:38:56.600363 | instance | Downloading openstacksdk (1.7MiB) 2026-09-08 15:38:56.600400 | instance | Downloading kubernetes (1.9MiB) 2026-09-08 15:38:56.600465 | instance | Downloading cryptography (4.2MiB) 2026-09-08 15:38:56.600546 | instance | Building pyperclip==1.9.0 2026-09-08 15:38:56.600595 | instance | Downloading rjsonnet 2026-09-08 15:38:56.600635 | instance | Downloading netaddr 2026-09-08 15:38:56.600671 | instance | Downloading pygments 2026-09-08 15:38:56.600705 | instance | Downloading cryptography 2026-09-08 15:38:56.600739 | instance | Downloading setuptools 2026-09-08 15:38:56.600772 | instance | Downloading kubernetes 2026-09-08 15:38:56.600805 | instance | Downloading ansible-core 2026-09-08 15:38:56.600838 | instance | Downloading openstacksdk 2026-09-08 15:38:56.600871 | instance | Built pyperclip==1.9.0 2026-09-08 15:38:56.600908 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-08 15:38:56.600943 | instance | Installed 79 packages in 81ms 2026-09-08 15:38:56.600978 | instance | Checked out ansible.posix to 1.6.0 2026-09-08 15:38:56.601013 | 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-08 15:38:56.601048 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.601082 | instance | Process install dependency map 2026-09-08 15:38:56.601118 | instance | Starting collection install process 2026-09-08 15:38:56.601152 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-08 15:38:56.601203 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-08 15:38:56.601239 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-08 15:38:56.601273 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.601306 | instance | 2.17.12 2026-09-08 15:38:56.601340 | instance | Checked out ansible.utils to v6.1.0 2026-09-08 15:38:56.601374 | 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-08 15:38:56.601407 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.601466 | instance | Process install dependency map 2026-09-08 15:38:56.601500 | instance | Starting collection install process 2026-09-08 15:38:56.601534 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-08 15:38:56.601567 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-08 15:38:56.601601 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-08 15:38:56.601634 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.601669 | instance | 2.17.12 2026-09-08 15:38:56.601703 | instance | Checked out atmosphere.common to v0.7.0 2026-09-08 15:38:56.601737 | 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-08 15:38:56.601772 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.601805 | instance | Process install dependency map 2026-09-08 15:38:56.601839 | instance | Starting collection install process 2026-09-08 15:38:56.601872 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-08 15:38:56.601906 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-08 15:38:56.601939 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-08 15:38:56.601972 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.602005 | instance | 2.17.12 2026-09-08 15:38:56.602039 | instance | Checked out community.crypto to 2.2.3 2026-09-08 15:38:56.602073 | 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-08 15:38:56.602106 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.602139 | instance | Process install dependency map 2026-09-08 15:38:56.602172 | instance | Starting collection install process 2026-09-08 15:38:56.602204 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-08 15:38:56.602238 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-08 15:38:56.602273 | instance | community.crypto:2.2.3 was installed successfully 2026-09-08 15:38:56.602305 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.602338 | instance | 2.17.12 2026-09-08 15:38:56.602371 | instance | Checked out community.general to 7.3.0 2026-09-08 15:38:56.602405 | 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-08 15:38:56.602454 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.602497 | instance | Process install dependency map 2026-09-08 15:38:56.602531 | instance | Starting collection install process 2026-09-08 15:38:56.602564 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-08 15:38:56.602597 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-08 15:38:56.602629 | instance | community.general:7.3.0 was installed successfully 2026-09-08 15:38:56.602662 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.602695 | instance | 2.17.12 2026-09-08 15:38:56.602729 | instance | Checked out community.mysql to 3.6.0 2026-09-08 15:38:56.602763 | 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-08 15:38:56.602797 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.602835 | instance | Process install dependency map 2026-09-08 15:38:56.602870 | instance | Starting collection install process 2026-09-08 15:38:56.602906 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-08 15:38:56.602941 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-08 15:38:56.602975 | instance | community.mysql:3.6.0 was installed successfully 2026-09-08 15:38:56.603008 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.603042 | instance | 2.17.12 2026-09-08 15:38:56.603076 | instance | Checked out kubernetes.core to 2.4.0 2026-09-08 15:38:56.603110 | 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-08 15:38:56.603144 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.603178 | instance | Process install dependency map 2026-09-08 15:38:56.603212 | instance | Starting collection install process 2026-09-08 15:38:56.603246 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-08 15:38:56.603281 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-08 15:38:56.603315 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-08 15:38:56.603349 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.603382 | instance | 2.17.12 2026-09-08 15:38:56.603416 | instance | Checked out openstack.cloud to 2.6.0 2026-09-08 15:38:56.603473 | 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-08 15:38:56.603510 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.603544 | instance | Process install dependency map 2026-09-08 15:38:56.603577 | instance | Starting collection install process 2026-09-08 15:38:56.603609 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-08 15:38:56.603643 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-08 15:38:56.603676 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-08 15:38:56.603710 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.603862 | instance | 2.17.12 2026-09-08 15:38:56.603901 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-08 15:38:56.603937 | 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-08 15:38:56.603982 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.604017 | instance | Process install dependency map 2026-09-08 15:38:56.604050 | instance | Starting collection install process 2026-09-08 15:38:56.604084 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-08 15:38:56.604118 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-08 15:38:56.604152 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-08 15:38:56.604185 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.604218 | instance | 2.17.12 2026-09-08 15:38:56.604253 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-08 15:38:56.604287 | 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-08 15:38:56.604320 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.604353 | instance | Process install dependency map 2026-09-08 15:38:56.604386 | instance | Starting collection install process 2026-09-08 15:38:56.604419 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-08 15:38:56.604475 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-08 15:38:56.604551 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-08 15:38:56.604602 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.604638 | instance | 2.17.12 2026-09-08 15:38:56.604672 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-09-08 15:38:56.604706 | 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-08 15:38:56.604740 | instance | Starting galaxy collection install process 2026-09-08 15:38:56.604774 | instance | Process install dependency map 2026-09-08 15:38:56.604808 | instance | Starting collection install process 2026-09-08 15:38:56.604842 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-08 15:38:56.604875 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-08 15:38:56.604909 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-09-08 15:38:56.604942 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-08 15:38:56.604975 | instance | 2.17.12 2026-09-08 15:38:56.614449 | 2026-09-08 15:38:56.614513 | PLAY RECAP 2026-09-08 15:38:56.614576 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-08 15:38:56.614603 | 2026-09-08 15:38:56.795125 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-08 15:38:56.797566 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-08 15:38:57.403251 | 2026-09-08 15:38:57.403369 | PLAY [all] 2026-09-08 15:38:57.413859 | 2026-09-08 15:38:57.413930 | TASK [Install "jq" for log collection] 2026-09-08 15:39:06.260479 | instance | changed 2026-09-08 15:39:06.263730 | 2026-09-08 15:39:06.263856 | PLAY RECAP 2026-09-08 15:39:06.263958 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-08 15:39:06.264058 | 2026-09-08 15:39:06.458970 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-08 15:39:06.461868 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-08 15:39:07.064441 | 2026-09-08 15:39:07.064578 | PLAY [all] 2026-09-08 15:39:07.075184 | 2026-09-08 15:39:07.075269 | LOOP [Check inventory directories] 2026-09-08 15:39:07.444607 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-08 15:39:07.618349 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-08 15:39:07.783554 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-08 15:39:07.803607 | 2026-09-08 15:39:07.803913 | LOOP [Copy inventory file for Zuul] 2026-09-08 15:39:08.654182 | instance | changed: 2026-09-08 15:39:08.654419 | instance | { 2026-09-08 15:39:08.654490 | instance | "ansible_loop_var": "item", 2026-09-08 15:39:08.654539 | instance | "changed": false, 2026-09-08 15:39:08.654587 | instance | "failed": false, 2026-09-08 15:39:08.654656 | instance | "invocation": { 2026-09-08 15:39:08.654700 | instance | "module_args": { 2026-09-08 15:39:08.654740 | instance | "checksum_algorithm": "sha1", 2026-09-08 15:39:08.654779 | instance | "follow": true, 2026-09-08 15:39:08.654817 | instance | "get_attributes": true, 2026-09-08 15:39:08.654860 | instance | "get_checksum": true, 2026-09-08 15:39:08.654900 | instance | "get_mime": true, 2026-09-08 15:39:08.654939 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-08 15:39:08.654980 | instance | } 2026-09-08 15:39:08.655021 | instance | }, 2026-09-08 15:39:08.655062 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-08 15:39:08.655105 | instance | "stat": { 2026-09-08 15:39:08.655146 | instance | "atime": 1788881929.4123614, 2026-09-08 15:39:08.655190 | instance | "attr_flags": "e", 2026-09-08 15:39:08.655232 | instance | "attributes": [ 2026-09-08 15:39:08.655273 | instance | "extents" 2026-09-08 15:39:08.655314 | instance | ], 2026-09-08 15:39:08.655354 | instance | "block_size": 4096, 2026-09-08 15:39:08.655398 | instance | "blocks": 8, 2026-09-08 15:39:08.655450 | instance | "charset": "unknown", 2026-09-08 15:39:08.655496 | instance | "ctime": 1788881927.8042128, 2026-09-08 15:39:08.655536 | instance | "dev": 64513, 2026-09-08 15:39:08.655573 | instance | "device_type": 0, 2026-09-08 15:39:08.655610 | instance | "executable": true, 2026-09-08 15:39:08.655646 | instance | "exists": true, 2026-09-08 15:39:08.655760 | instance | "gid": 1000, 2026-09-08 15:39:08.655828 | instance | "gr_name": "zuul", 2026-09-08 15:39:08.655863 | instance | "inode": 1048782, 2026-09-08 15:39:08.655894 | instance | "isblk": false, 2026-09-08 15:39:08.655926 | instance | "ischr": false, 2026-09-08 15:39:08.655955 | instance | "isdir": true, 2026-09-08 15:39:08.655984 | instance | "isfifo": false, 2026-09-08 15:39:08.656013 | instance | "isgid": false, 2026-09-08 15:39:08.656041 | instance | "islnk": false, 2026-09-08 15:39:08.656070 | instance | "isreg": false, 2026-09-08 15:39:08.656099 | instance | "issock": false, 2026-09-08 15:39:08.656128 | instance | "isuid": false, 2026-09-08 15:39:08.656158 | instance | "mimetype": "unknown", 2026-09-08 15:39:08.656188 | instance | "mode": "0755", 2026-09-08 15:39:08.656224 | instance | "mtime": 1788881927.8042128, 2026-09-08 15:39:08.656259 | instance | "nlink": 21, 2026-09-08 15:39:08.656291 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-08 15:39:08.656324 | instance | "pw_name": "zuul", 2026-09-08 15:39:08.656358 | instance | "readable": true, 2026-09-08 15:39:08.656391 | instance | "rgrp": true, 2026-09-08 15:39:08.656421 | instance | "roth": true, 2026-09-08 15:39:08.656480 | instance | "rusr": true, 2026-09-08 15:39:08.656533 | instance | "size": 4096, 2026-09-08 15:39:08.656572 | instance | "uid": 1000, 2026-09-08 15:39:08.656622 | instance | "version": "4273892603", 2026-09-08 15:39:08.656653 | instance | "wgrp": false, 2026-09-08 15:39:08.656685 | instance | "woth": false, 2026-09-08 15:39:08.656715 | instance | "writeable": true, 2026-09-08 15:39:08.656744 | instance | "wusr": true, 2026-09-08 15:39:08.656773 | instance | "xgrp": true, 2026-09-08 15:39:08.656802 | instance | "xoth": true, 2026-09-08 15:39:08.656831 | instance | "xusr": true 2026-09-08 15:39:08.656859 | instance | } 2026-09-08 15:39:08.656889 | instance | } 2026-09-08 15:39:08.656957 | instance | ok: All items complete 2026-09-08 15:39:08.656996 | 2026-09-08 15:39:08.659157 | instance | skipping: Conditional result was False 2026-09-08 15:39:08.662319 | instance | skipping: Conditional result was False 2026-09-08 15:39:08.678795 | 2026-09-08 15:39:08.678977 | LOOP [Switch "ansible_host" to private IP] 2026-09-08 15:39:09.039980 | instance | changed: 1 replacements made 2026-09-08 15:39:09.043508 | instance | skipping: Conditional result was False 2026-09-08 15:39:09.048453 | instance | skipping: Conditional result was False 2026-09-08 15:39:09.068332 | 2026-09-08 15:39:09.068573 | TASK [Run Molecule scenario] 2026-09-08 15:39:09.970933 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-08 15:39:10.372233 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-08 15:39:10.372322 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-08 15:39:20.806763 | instance | INFO [csi > dependency] Executing 2026-09-08 15:39:20.807154 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-08 15:39:20.807580 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-08 15:39:20.807907 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-08 15:39:20.823858 | instance | INFO [csi > cleanup] Executing 2026-09-08 15:39:20.824448 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-08 15:39:20.838415 | instance | INFO [csi > destroy] Executing 2026-09-08 15:39:20.838469 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-08 15:39:20.838562 | instance | INFO [csi > destroy] Executed: Successful 2026-09-08 15:39:20.849294 | instance | INFO [csi > syntax] Executing 2026-09-08 15:39:22.245770 | instance | 2026-09-08 15:39:22.246015 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-08 15:39:22.310067 | instance | INFO [csi > syntax] Executed: Successful 2026-09-08 15:39:22.326368 | instance | INFO [csi > create] Executing 2026-09-08 15:39:22.330250 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-08 15:39:22.346487 | instance | INFO [csi > prepare] Executing 2026-09-08 15:39:23.055896 | instance | 2026-09-08 15:39:23.056161 | instance | PLAY [Prepare] ***************************************************************** 2026-09-08 15:39:23.056323 | instance | 2026-09-08 15:39:23.056562 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-08 15:39:23.056793 | instance | Tuesday 08 September 2026 15:39:23 +0000 (0:00:00.022) 0:00:00.022 ***** 2026-09-08 15:39:24.309870 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-08 15:39:24.310220 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-08 15:39:24.310514 | instance | interpreter could change the meaning of that path. See 2026-09-08 15:39:24.310838 | instance | https://docs.ansible.com/ansible- 2026-09-08 15:39:24.311125 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-08 15:39:24.320326 | instance | ok: [instance] 2026-09-08 15:39:24.320590 | instance | 2026-09-08 15:39:24.320909 | instance | TASK [Configure short hostname] ************************************************ 2026-09-08 15:39:24.321195 | instance | Tuesday 08 September 2026 15:39:24 +0000 (0:00:01.264) 0:00:01.287 ***** 2026-09-08 15:39:25.082352 | instance | changed: [instance] 2026-09-08 15:39:25.082421 | instance | 2026-09-08 15:39:25.082589 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-08 15:39:25.082902 | instance | Tuesday 08 September 2026 15:39:25 +0000 (0:00:00.761) 0:00:02.049 ***** 2026-09-08 15:39:25.366882 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-08 15:39:25.367113 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-08 15:39:25.367391 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-08 15:39:25.372472 | instance | changed: [instance] 2026-09-08 15:39:25.372758 | instance | 2026-09-08 15:39:25.373009 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-08 15:39:25.373248 | instance | Tuesday 08 September 2026 15:39:25 +0000 (0:00:00.289) 0:00:02.338 ***** 2026-09-08 15:39:26.125392 | instance | ok: [instance] 2026-09-08 15:39:26.125595 | instance | 2026-09-08 15:39:26.125860 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-08 15:39:26.126118 | instance | 2026-09-08 15:39:26.126347 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-08 15:39:26.126586 | instance | Tuesday 08 September 2026 15:39:26 +0000 (0:00:00.753) 0:00:03.092 ***** 2026-09-08 15:39:26.875774 | instance | ok: [instance] 2026-09-08 15:39:26.875892 | instance | 2026-09-08 15:39:26.876047 | instance | TASK [Install depedencies] ***************************************************** 2026-09-08 15:39:26.876194 | instance | Tuesday 08 September 2026 15:39:26 +0000 (0:00:00.750) 0:00:03.842 ***** 2026-09-08 15:39:49.829539 | instance | changed: [instance] 2026-09-08 15:39:49.829590 | instance | 2026-09-08 15:39:49.829602 | instance | TASK [Start up service] ******************************************************** 2026-09-08 15:39:49.829613 | instance | Tuesday 08 September 2026 15:39:49 +0000 (0:00:22.952) 0:00:26.795 ***** 2026-09-08 15:39:50.342903 | instance | ok: [instance] 2026-09-08 15:39:50.343597 | instance | 2026-09-08 15:39:50.343664 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-08 15:39:50.343677 | instance | Tuesday 08 September 2026 15:39:50 +0000 (0:00:00.514) 0:00:27.309 ***** 2026-09-08 15:39:50.655121 | instance | ok: [instance] 2026-09-08 15:39:50.655910 | instance | 2026-09-08 15:39:50.655955 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-08 15:39:50.655967 | instance | Tuesday 08 September 2026 15:39:50 +0000 (0:00:00.312) 0:00:27.621 ***** 2026-09-08 15:39:51.344956 | instance | changed: [instance] 2026-09-08 15:39:51.345754 | instance | 2026-09-08 15:39:51.345808 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-08 15:39:51.345820 | instance | Tuesday 08 September 2026 15:39:51 +0000 (0:00:00.689) 0:00:28.311 ***** 2026-09-08 15:39:51.550825 | instance | ok: [instance] 2026-09-08 15:39:51.551709 | instance | 2026-09-08 15:39:51.551763 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-08 15:39:51.551776 | instance | Tuesday 08 September 2026 15:39:51 +0000 (0:00:00.205) 0:00:28.517 ***** 2026-09-08 15:39:51.577607 | instance | skipping: [instance] 2026-09-08 15:39:51.578089 | instance | 2026-09-08 15:39:51.578175 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-08 15:39:51.578422 | instance | Tuesday 08 September 2026 15:39:51 +0000 (0:00:00.027) 0:00:28.544 ***** 2026-09-08 15:39:52.129156 | instance | changed: [instance] => (item=osd0) 2026-09-08 15:39:52.129368 | instance | changed: [instance] => (item=osd1) 2026-09-08 15:39:52.129776 | instance | changed: [instance] => (item=osd2) 2026-09-08 15:39:52.130139 | instance | 2026-09-08 15:39:52.130457 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-08 15:39:52.130860 | instance | Tuesday 08 September 2026 15:39:52 +0000 (0:00:00.551) 0:00:29.095 ***** 2026-09-08 15:39:52.788369 | instance | changed: [instance] => (item=osd0) 2026-09-08 15:39:52.788570 | instance | changed: [instance] => (item=osd1) 2026-09-08 15:39:52.789055 | instance | changed: [instance] => (item=osd2) 2026-09-08 15:39:52.789348 | instance | 2026-09-08 15:39:52.789647 | instance | TASK [Start loop devices] ****************************************************** 2026-09-08 15:39:52.790018 | instance | Tuesday 08 September 2026 15:39:52 +0000 (0:00:00.659) 0:00:29.755 ***** 2026-09-08 15:39:53.554090 | instance | changed: [instance] => (item=osd0) 2026-09-08 15:39:53.555531 | instance | changed: [instance] => (item=osd1) 2026-09-08 15:39:53.555591 | instance | changed: [instance] => (item=osd2) 2026-09-08 15:39:53.555602 | instance | 2026-09-08 15:39:53.555613 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-08 15:39:53.555623 | instance | Tuesday 08 September 2026 15:39:53 +0000 (0:00:00.765) 0:00:30.520 ***** 2026-09-08 15:39:56.773260 | instance | changed: [instance] => (item=osd0) 2026-09-08 15:39:56.774077 | instance | changed: [instance] => (item=osd1) 2026-09-08 15:39:56.774134 | instance | changed: [instance] => (item=osd2) 2026-09-08 15:39:56.775046 | instance | 2026-09-08 15:39:56.775109 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-08 15:39:56.775124 | instance | Tuesday 08 September 2026 15:39:56 +0000 (0:00:03.219) 0:00:33.739 ***** 2026-09-08 15:39:58.638940 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-08 15:39:58.639003 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-08 15:39:58.639035 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-08 15:39:58.639487 | instance | 2026-09-08 15:39:58.639501 | instance | PLAY RECAP ********************************************************************* 2026-09-08 15:39:58.639508 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-08 15:39:58.642028 | instance | 2026-09-08 15:39:58.642181 | instance | Tuesday 08 September 2026 15:39:58 +0000 (0:00:01.866) 0:00:35.606 ***** 2026-09-08 15:39:58.642188 | instance | =============================================================================== 2026-09-08 15:39:58.642192 | instance | Install depedencies ---------------------------------------------------- 22.95s 2026-09-08 15:39:58.642196 | instance | Create a volume group for each loop device ------------------------------ 3.22s 2026-09-08 15:39:58.642200 | instance | Create a logical volume for each loop device ---------------------------- 1.87s 2026-09-08 15:39:58.642205 | instance | Gathering Facts --------------------------------------------------------- 1.26s 2026-09-08 15:39:58.642209 | instance | Start loop devices ------------------------------------------------------ 0.77s 2026-09-08 15:39:58.642213 | instance | Configure short hostname ------------------------------------------------ 0.76s 2026-09-08 15:39:58.642217 | instance | Purge "snapd" package --------------------------------------------------- 0.75s 2026-09-08 15:39:58.642221 | instance | Gathering Facts --------------------------------------------------------- 0.75s 2026-09-08 15:39:58.642225 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s 2026-09-08 15:39:58.642228 | instance | Set permissions on loopback devices ------------------------------------- 0.66s 2026-09-08 15:39:58.642232 | instance | Create devices for Ceph ------------------------------------------------- 0.55s 2026-09-08 15:39:58.642236 | instance | Start up service -------------------------------------------------------- 0.51s 2026-09-08 15:39:58.642240 | instance | Generate lvm.conf ------------------------------------------------------- 0.31s 2026-09-08 15:39:58.642244 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s 2026-09-08 15:39:58.642252 | instance | Get list of all loopback devices ---------------------------------------- 0.21s 2026-09-08 15:39:58.642255 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-08 15:39:58.735861 | instance | INFO [csi > prepare] Executed: Successful 2026-09-08 15:39:58.743051 | instance | INFO [csi > converge] Executing 2026-09-08 15:40:00.156428 | instance | 2026-09-08 15:40:00.156829 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-08 15:40:00.157037 | instance | 2026-09-08 15:40:00.157280 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-08 15:40:00.157555 | instance | Tuesday 08 September 2026 15:40:00 +0000 (0:00:00.007) 0:00:00.007 ***** 2026-09-08 15:40:01.206030 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-08 15:40:01.206508 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-08 15:40:01.206984 | instance | interpreter could change the meaning of that path. See 2026-09-08 15:40:01.207162 | instance | https://docs.ansible.com/ansible- 2026-09-08 15:40:01.207324 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-08 15:40:01.220312 | instance | ok: [instance] 2026-09-08 15:40:01.220442 | instance | 2026-09-08 15:40:01.220614 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-08 15:40:01.220782 | instance | Tuesday 08 September 2026 15:40:01 +0000 (0:00:01.063) 0:00:01.070 ***** 2026-09-08 15:40:01.274040 | instance | ok: [instance] => { 2026-09-08 15:40:01.274168 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-08 15:40:01.274331 | instance | } 2026-09-08 15:40:01.274494 | instance | 2026-09-08 15:40:01.274667 | instance | PLAY [all] ********************************************************************* 2026-09-08 15:40:01.274859 | instance | 2026-09-08 15:40:01.274981 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-08 15:40:01.275148 | instance | Tuesday 08 September 2026 15:40:01 +0000 (0:00:00.053) 0:00:01.124 ***** 2026-09-08 15:40:02.243315 | instance | ok: [instance] 2026-09-08 15:40:02.243692 | instance | 2026-09-08 15:40:02.244026 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-08 15:40:02.244328 | instance | Tuesday 08 September 2026 15:40:02 +0000 (0:00:00.968) 0:00:02.093 ***** 2026-09-08 15:40:02.465359 | instance | ok: [instance] 2026-09-08 15:40:02.465480 | instance | 2026-09-08 15:40:02.465492 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-08 15:40:02.465623 | instance | 2026-09-08 15:40:02.465871 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-08 15:40:02.466070 | instance | Tuesday 08 September 2026 15:40:02 +0000 (0:00:00.222) 0:00:02.315 ***** 2026-09-08 15:40:03.426787 | instance | ok: [instance] 2026-09-08 15:40:03.427286 | instance | 2026-09-08 15:40:03.427714 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-08 15:40:03.428178 | instance | Tuesday 08 September 2026 15:40:03 +0000 (0:00:00.958) 0:00:03.274 ***** 2026-09-08 15:40:03.747831 | instance | ok: [instance] 2026-09-08 15:40:03.747907 | instance | 2026-09-08 15:40:03.747918 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-08 15:40:03.747928 | instance | Tuesday 08 September 2026 15:40:03 +0000 (0:00:00.322) 0:00:03.597 ***** 2026-09-08 15:40:03.801004 | instance | skipping: [instance] 2026-09-08 15:40:03.801050 | instance | 2026-09-08 15:40:03.801058 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-08 15:40:03.801065 | instance | Tuesday 08 September 2026 15:40:03 +0000 (0:00:00.053) 0:00:03.651 ***** 2026-09-08 15:40:04.180175 | instance | changed: [instance] 2026-09-08 15:40:04.180284 | instance | 2026-09-08 15:40:04.180947 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-08 15:40:04.181034 | instance | Tuesday 08 September 2026 15:40:04 +0000 (0:00:00.378) 0:00:04.029 ***** 2026-09-08 15:40:04.279515 | instance | ok: [instance] => { 2026-09-08 15:40:04.280931 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-08 15:40:04.280998 | instance | } 2026-09-08 15:40:04.281012 | instance | 2026-09-08 15:40:04.281023 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-08 15:40:04.281033 | instance | Tuesday 08 September 2026 15:40:04 +0000 (0:00:00.099) 0:00:04.129 ***** 2026-09-08 15:40:05.345239 | instance | changed: [instance] 2026-09-08 15:40:05.345574 | instance | 2026-09-08 15:40:05.346070 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-08 15:40:05.346411 | instance | Tuesday 08 September 2026 15:40:05 +0000 (0:00:01.065) 0:00:05.194 ***** 2026-09-08 15:40:05.413470 | instance | skipping: [instance] 2026-09-08 15:40:05.413826 | instance | 2026-09-08 15:40:05.414205 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-08 15:40:05.414539 | instance | Tuesday 08 September 2026 15:40:05 +0000 (0:00:00.069) 0:00:05.263 ***** 2026-09-08 15:40:05.474812 | instance | skipping: [instance] 2026-09-08 15:40:05.475286 | instance | 2026-09-08 15:40:05.475714 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-08 15:40:05.476129 | instance | Tuesday 08 September 2026 15:40:05 +0000 (0:00:00.061) 0:00:05.325 ***** 2026-09-08 15:40:05.718563 | instance | ok: [instance] 2026-09-08 15:40:05.718791 | instance | 2026-09-08 15:40:05.719024 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-08 15:40:05.719220 | instance | Tuesday 08 September 2026 15:40:05 +0000 (0:00:00.243) 0:00:05.569 ***** 2026-09-08 15:40:06.988282 | instance | ok: [instance] 2026-09-08 15:40:06.988317 | instance | 2026-09-08 15:40:06.988323 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-08 15:40:06.988327 | instance | Tuesday 08 September 2026 15:40:06 +0000 (0:00:01.269) 0:00:06.839 ***** 2026-09-08 15:40:07.080420 | instance | ok: [instance] => { 2026-09-08 15:40:07.080479 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-08 15:40:07.080507 | instance | } 2026-09-08 15:40:07.080518 | instance | 2026-09-08 15:40:07.080528 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-08 15:40:07.080538 | instance | Tuesday 08 September 2026 15:40:07 +0000 (0:00:00.089) 0:00:06.928 ***** 2026-09-08 15:40:07.845190 | instance | changed: [instance] 2026-09-08 15:40:07.845249 | instance | 2026-09-08 15:40:07.845260 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-08 15:40:07.845270 | instance | Tuesday 08 September 2026 15:40:07 +0000 (0:00:00.765) 0:00:07.694 ***** 2026-09-08 15:40:10.747892 | instance | changed: [instance] 2026-09-08 15:40:10.747944 | instance | 2026-09-08 15:40:10.747952 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-08 15:40:10.747959 | instance | Tuesday 08 September 2026 15:40:10 +0000 (0:00:02.903) 0:00:10.597 ***** 2026-09-08 15:40:10.785879 | instance | skipping: [instance] 2026-09-08 15:40:10.786515 | instance | 2026-09-08 15:40:10.786555 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-08 15:40:10.786563 | instance | Tuesday 08 September 2026 15:40:10 +0000 (0:00:00.038) 0:00:10.636 ***** 2026-09-08 15:40:10.829112 | instance | skipping: [instance] 2026-09-08 15:40:10.829307 | instance | 2026-09-08 15:40:10.830021 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-08 15:40:10.830363 | instance | Tuesday 08 September 2026 15:40:10 +0000 (0:00:00.042) 0:00:10.679 ***** 2026-09-08 15:40:10.868157 | instance | skipping: [instance] 2026-09-08 15:40:10.868292 | instance | 2026-09-08 15:40:10.868527 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-08 15:40:10.868738 | instance | Tuesday 08 September 2026 15:40:10 +0000 (0:00:00.039) 0:00:10.718 ***** 2026-09-08 15:40:16.781637 | instance | changed: [instance] 2026-09-08 15:40:16.781905 | instance | 2026-09-08 15:40:16.782208 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-08 15:40:16.782540 | instance | Tuesday 08 September 2026 15:40:16 +0000 (0:00:05.912) 0:00:16.631 ***** 2026-09-08 15:40:17.402645 | instance | changed: [instance] 2026-09-08 15:40:17.402780 | instance | 2026-09-08 15:40:17.403022 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-08 15:40:17.403246 | instance | Tuesday 08 September 2026 15:40:17 +0000 (0:00:00.621) 0:00:17.252 ***** 2026-09-08 15:40:18.407896 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-08 15:40:18.407991 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-08 15:40:18.408138 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-08 15:40:18.408401 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-08 15:40:18.408607 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-08 15:40:18.408792 | instance | 2026-09-08 15:40:18.408989 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-08 15:40:18.409207 | instance | Tuesday 08 September 2026 15:40:18 +0000 (0:00:01.005) 0:00:18.258 ***** 2026-09-08 15:40:19.046002 | instance | changed: [instance] 2026-09-08 15:40:19.046454 | instance | 2026-09-08 15:40:19.046678 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-08 15:40:19.046878 | instance | Tuesday 08 September 2026 15:40:19 +0000 (0:00:00.609) 0:00:18.868 ***** 2026-09-08 15:40:19.047066 | instance | 2026-09-08 15:40:19.047321 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-08 15:40:19.047519 | instance | Tuesday 08 September 2026 15:40:19 +0000 (0:00:00.026) 0:00:18.894 ***** 2026-09-08 15:40:20.013248 | instance | ok: [instance] 2026-09-08 15:40:20.013297 | instance | 2026-09-08 15:40:20.013307 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-08 15:40:20.013316 | instance | Tuesday 08 September 2026 15:40:20 +0000 (0:00:00.968) 0:00:19.863 ***** 2026-09-08 15:40:20.516728 | instance | changed: [instance] 2026-09-08 15:40:20.516815 | instance | 2026-09-08 15:40:20.516827 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-08 15:40:20.516837 | instance | Tuesday 08 September 2026 15:40:20 +0000 (0:00:00.502) 0:00:20.366 ***** 2026-09-08 15:40:21.105296 | instance | changed: [instance] 2026-09-08 15:40:21.105349 | instance | 2026-09-08 15:40:21.105360 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-08 15:40:21.105370 | instance | Tuesday 08 September 2026 15:40:21 +0000 (0:00:00.588) 0:00:20.955 ***** 2026-09-08 15:40:21.351493 | instance | ok: [instance] 2026-09-08 15:40:21.352316 | instance | 2026-09-08 15:40:21.352370 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-08 15:40:21.352384 | instance | Tuesday 08 September 2026 15:40:21 +0000 (0:00:00.246) 0:00:21.201 ***** 2026-09-08 15:40:21.432980 | instance | ok: [instance] => { 2026-09-08 15:40:21.433145 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-08 15:40:21.434123 | instance | } 2026-09-08 15:40:21.434440 | instance | 2026-09-08 15:40:21.434464 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-08 15:40:21.434475 | instance | Tuesday 08 September 2026 15:40:21 +0000 (0:00:00.081) 0:00:21.283 ***** 2026-09-08 15:41:05.435079 | instance | changed: [instance] 2026-09-08 15:41:05.435791 | instance | 2026-09-08 15:41:05.435866 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-08 15:41:05.435880 | instance | Tuesday 08 September 2026 15:41:05 +0000 (0:00:44.001) 0:01:05.285 ***** 2026-09-08 15:41:09.791338 | instance | changed: [instance] 2026-09-08 15:41:09.791388 | instance | 2026-09-08 15:41:09.791399 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-08 15:41:09.791409 | instance | Tuesday 08 September 2026 15:41:09 +0000 (0:00:04.355) 0:01:09.640 ***** 2026-09-08 15:41:10.970534 | instance | ok: [instance] 2026-09-08 15:41:10.970590 | instance | 2026-09-08 15:41:10.970598 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-08 15:41:10.970604 | instance | Tuesday 08 September 2026 15:41:10 +0000 (0:00:01.179) 0:01:10.820 ***** 2026-09-08 15:41:11.331183 | instance | changed: [instance] 2026-09-08 15:41:11.331220 | instance | 2026-09-08 15:41:11.331225 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-08 15:41:11.331230 | instance | Tuesday 08 September 2026 15:41:11 +0000 (0:00:00.360) 0:01:11.181 ***** 2026-09-08 15:41:11.839011 | instance | changed: [instance] 2026-09-08 15:41:11.839069 | instance | 2026-09-08 15:41:11.839080 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-08 15:41:11.839090 | instance | Tuesday 08 September 2026 15:41:11 +0000 (0:00:00.506) 0:01:11.688 ***** 2026-09-08 15:41:12.448654 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-08 15:41:12.448708 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-08 15:41:12.449182 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-08 15:41:12.449296 | instance | 2026-09-08 15:41:12.449301 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-08 15:41:12.449306 | instance | Tuesday 08 September 2026 15:41:12 +0000 (0:00:00.611) 0:01:12.299 ***** 2026-09-08 15:41:12.948684 | instance | changed: [instance] 2026-09-08 15:41:12.948785 | instance | 2026-09-08 15:41:12.948797 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-08 15:41:12.948807 | instance | Tuesday 08 September 2026 15:41:12 +0000 (0:00:00.498) 0:01:12.797 ***** 2026-09-08 15:41:13.495441 | instance | changed: [instance] 2026-09-08 15:41:13.495519 | instance | 2026-09-08 15:41:13.496348 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-08 15:41:13.496396 | instance | Tuesday 08 September 2026 15:41:13 +0000 (0:00:00.512) 0:01:13.310 ***** 2026-09-08 15:41:13.496405 | instance | 2026-09-08 15:41:13.496411 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-08 15:41:13.496418 | instance | Tuesday 08 September 2026 15:41:13 +0000 (0:00:00.035) 0:01:13.345 ***** 2026-09-08 15:41:14.248762 | instance | ok: [instance] 2026-09-08 15:41:14.249790 | instance | 2026-09-08 15:41:14.249828 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-08 15:41:14.249853 | instance | Tuesday 08 September 2026 15:41:14 +0000 (0:00:00.753) 0:01:14.099 ***** 2026-09-08 15:41:15.246117 | instance | changed: [instance] 2026-09-08 15:41:15.247078 | instance | 2026-09-08 15:41:15.247116 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-08 15:41:15.247131 | instance | Tuesday 08 September 2026 15:41:15 +0000 (0:00:00.997) 0:01:15.096 ***** 2026-09-08 15:41:15.839529 | instance | changed: [instance] 2026-09-08 15:41:15.839597 | instance | 2026-09-08 15:41:15.839842 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-08 15:41:15.839875 | instance | Tuesday 08 September 2026 15:41:15 +0000 (0:00:00.594) 0:01:15.690 ***** 2026-09-08 15:41:15.912997 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-08 15:41:15.913148 | instance | 2026-09-08 15:41:15.913678 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-08 15:41:15.913733 | instance | Tuesday 08 September 2026 15:41:15 +0000 (0:00:00.072) 0:01:15.763 ***** 2026-09-08 15:41:21.571951 | instance | changed: [instance] 2026-09-08 15:41:21.572023 | instance | 2026-09-08 15:41:21.572263 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-08 15:41:21.572301 | instance | Tuesday 08 September 2026 15:41:21 +0000 (0:00:05.659) 0:01:21.423 ***** 2026-09-08 15:41:22.244163 | instance | ok: [instance] => (item=chronyd) 2026-09-08 15:41:22.244196 | instance | ok: [instance] => (item=sshd) 2026-09-08 15:41:22.244201 | instance | 2026-09-08 15:41:22.244206 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-08 15:41:22.244211 | instance | Tuesday 08 September 2026 15:41:22 +0000 (0:00:00.671) 0:01:22.094 ***** 2026-09-08 15:41:32.092649 | instance | changed: [instance] 2026-09-08 15:41:32.093506 | instance | 2026-09-08 15:41:32.093582 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-08 15:41:32.093598 | instance | Tuesday 08 September 2026 15:41:32 +0000 (0:00:09.848) 0:01:31.942 ***** 2026-09-08 15:41:32.326500 | instance | ok: [instance] 2026-09-08 15:41:32.326572 | instance | 2026-09-08 15:41:32.327156 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-08 15:41:32.327385 | instance | Tuesday 08 September 2026 15:41:32 +0000 (0:00:00.233) 0:01:32.176 ***** 2026-09-08 15:41:32.811730 | instance | changed: [instance] 2026-09-08 15:41:32.811783 | instance | 2026-09-08 15:41:32.811795 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-08 15:41:32.811805 | instance | Tuesday 08 September 2026 15:41:32 +0000 (0:00:00.484) 0:01:32.661 ***** 2026-09-08 15:41:33.176446 | instance | changed: [instance] 2026-09-08 15:41:33.176495 | instance | 2026-09-08 15:41:33.176507 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-08 15:41:33.176518 | instance | Tuesday 08 September 2026 15:41:33 +0000 (0:00:00.364) 0:01:33.025 ***** 2026-09-08 15:41:33.304891 | instance | ok: [instance] 2026-09-08 15:41:33.304930 | instance | 2026-09-08 15:41:33.304938 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-08 15:41:33.304945 | instance | Tuesday 08 September 2026 15:41:33 +0000 (0:00:00.129) 0:01:33.155 ***** 2026-09-08 15:41:33.554889 | instance | ok: [instance] => (item=instance) 2026-09-08 15:41:33.555678 | instance | 2026-09-08 15:41:33.555713 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-08 15:41:33.555725 | instance | Tuesday 08 September 2026 15:41:33 +0000 (0:00:00.249) 0:01:33.405 ***** 2026-09-08 15:41:33.616724 | instance | ok: [instance] 2026-09-08 15:41:33.617175 | instance | 2026-09-08 15:41:33.617194 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-08 15:41:33.617202 | instance | Tuesday 08 September 2026 15:41:33 +0000 (0:00:00.062) 0:01:33.467 ***** 2026-09-08 15:41:33.700354 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-08 15:41:33.700698 | instance | 2026-09-08 15:41:33.700746 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-08 15:41:33.700753 | instance | Tuesday 08 September 2026 15:41:33 +0000 (0:00:00.083) 0:01:33.551 ***** 2026-09-08 15:41:33.999447 | instance | changed: [instance] 2026-09-08 15:41:33.999531 | instance | 2026-09-08 15:41:33.999737 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-08 15:41:33.999779 | instance | Tuesday 08 September 2026 15:41:33 +0000 (0:00:00.299) 0:01:33.850 ***** 2026-09-08 15:41:34.669377 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-08 15:41:34.670055 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-08 15:41:34.670149 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-08 15:41:34.670165 | instance | 2026-09-08 15:41:34.670198 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-08 15:41:34.670207 | instance | Tuesday 08 September 2026 15:41:34 +0000 (0:00:00.669) 0:01:34.520 ***** 2026-09-08 15:48:26.998904 | instance | ok: [instance] 2026-09-08 15:48:26.998965 | instance | 2026-09-08 15:48:26.998977 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-08 15:48:26.998987 | instance | Tuesday 08 September 2026 15:48:26 +0000 (0:06:52.328) 0:08:26.848 ***** 2026-09-08 15:48:27.257455 | instance | changed: [instance] 2026-09-08 15:48:27.257657 | instance | 2026-09-08 15:48:27.257933 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-08 15:48:27.258316 | instance | Tuesday 08 September 2026 15:48:27 +0000 (0:00:00.259) 0:08:27.107 ***** 2026-09-08 15:48:27.349895 | instance | ok: [instance] 2026-09-08 15:48:27.349986 | instance | 2026-09-08 15:48:27.350205 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-08 15:48:27.350248 | instance | Tuesday 08 September 2026 15:48:27 +0000 (0:00:00.093) 0:08:27.200 ***** 2026-09-08 15:48:27.438943 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-08 15:48:27.439012 | instance | 2026-09-08 15:48:27.439276 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-08 15:48:27.439314 | instance | Tuesday 08 September 2026 15:48:27 +0000 (0:00:00.088) 0:08:27.289 ***** 2026-09-08 15:48:29.069098 | instance | ok: [instance] 2026-09-08 15:48:29.069140 | instance | 2026-09-08 15:48:29.069146 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-08 15:48:29.069194 | instance | Tuesday 08 September 2026 15:48:29 +0000 (0:00:01.630) 0:08:28.919 ***** 2026-09-08 15:48:29.141017 | instance | ok: [instance] => (item=instance) 2026-09-08 15:48:29.141050 | instance | 2026-09-08 15:48:29.141058 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-08 15:48:29.141065 | instance | Tuesday 08 September 2026 15:48:29 +0000 (0:00:00.070) 0:08:28.990 ***** 2026-09-08 15:48:29.454099 | instance | ok: [instance] 2026-09-08 15:48:29.454152 | instance | 2026-09-08 15:48:29.454163 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-08 15:48:29.454173 | instance | Tuesday 08 September 2026 15:48:29 +0000 (0:00:00.312) 0:08:29.303 ***** 2026-09-08 15:48:31.804103 | instance | ok: [instance] 2026-09-08 15:48:31.804147 | instance | 2026-09-08 15:48:31.804154 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-08 15:48:31.804161 | instance | Tuesday 08 September 2026 15:48:31 +0000 (0:00:02.350) 0:08:31.654 ***** 2026-09-08 15:51:33.270876 | 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-08 15:51:33.271258 | 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_hecdkj4o/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_hecdkj4o/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_hecdkj4o/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_hecdkj4o/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-08T15:51:33.082+0000 7fb0431be640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAgLqBqNzm/FCAAVEnOkWx4M3Ympbll7II9is/oo0GTKybhwsYhqdpNnWY=: Malformed input [buffer:3]\n2026-09-08T15:51:33.082+0000 7fb0431be640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-08T15:51:33.082+0000 7fb0431be640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAgLqBqNzm/FCAAVEnOkWx4M3Ympbll7II9is/oo0GTKybhwsYhqdpNnWY=: Malformed input [buffer:3]\n2026-09-08T15:51:33.082+0000 7fb0431be640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-08T15:51:33.082+0000 7fb0431be640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAgLqBqNzm/FCAAVEnOkWx4M3Ympbll7II9is/oo0GTKybhwsYhqdpNnWY=: Malformed input [buffer:3]\n2026-09-08T15:51:33.082+0000 7fb0431be640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-08T15:51:33.082+0000 7fb0431be640 -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-08 15:51:33.273091 | instance | 2026-09-08 15:51:33.273251 | instance | PLAY RECAP ********************************************************************* 2026-09-08 15:51:33.274124 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=6 rescued=0 ignored=0 2026-09-08 15:51:33.274197 | instance | 2026-09-08 15:51:33.274208 | instance | Tuesday 08 September 2026 15:51:33 +0000 (0:03:01.470) 0:11:33.124 ***** 2026-09-08 15:51:33.274218 | instance | =============================================================================== 2026-09-08 15:51:33.274234 | instance | vexxhost.ceph.mon : Run Bootstrap command ----------------------------- 412.33s 2026-09-08 15:51:33.274245 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ---------------- 181.47s 2026-09-08 15:51:33.276774 | instance | vexxhost.containers.download_artifact : Download item ------------------ 44.00s 2026-09-08 15:51:33.276799 | instance | vexxhost.ceph.cephadm : Download "cephadm" ------------------------------ 9.85s 2026-09-08 15:51:33.276806 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.91s 2026-09-08 15:51:33.276812 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.66s 2026-09-08 15:51:33.276817 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.36s 2026-09-08 15:51:33.276823 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.90s 2026-09-08 15:51:33.276835 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.35s 2026-09-08 15:51:33.276841 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.72s 2026-09-08 15:51:33.276846 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.63s 2026-09-08 15:51:33.276852 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.27s 2026-09-08 15:51:33.276857 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.18s 2026-09-08 15:51:33.276862 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.07s 2026-09-08 15:51:33.276868 | instance | Gathering Facts --------------------------------------------------------- 1.06s 2026-09-08 15:51:33.276873 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-08 15:51:33.276878 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.00s 2026-09-08 15:51:33.276884 | instance | Gathering Facts --------------------------------------------------------- 0.97s 2026-09-08 15:51:33.276889 | instance | Gathering Facts --------------------------------------------------------- 0.96s 2026-09-08 15:51:33.276895 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.77s 2026-09-08 15:51:33.372655 | 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-08 15:51:33.372955 | instance | ERROR [csi > converge] Executed: Failed 2026-09-08 15:51:33.373165 | 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-08 15:51:33.622585 | instance | ERROR 2026-09-08 15:51:33.623357 | instance | { 2026-09-08 15:51:33.623443 | instance | "delta": "0:12:24.007976", 2026-09-08 15:51:33.623487 | instance | "end": "2026-09-08 15:51:33.442617", 2026-09-08 15:51:33.623527 | instance | "msg": "non-zero return code", 2026-09-08 15:51:33.623564 | instance | "rc": 2, 2026-09-08 15:51:33.623602 | instance | "start": "2026-09-08 15:39:09.434641" 2026-09-08 15:51:33.623637 | instance | } failure 2026-09-08 15:51:33.633076 | 2026-09-08 15:51:33.633257 | PLAY RECAP 2026-09-08 15:51:33.633364 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-08 15:51:33.633425 | 2026-09-08 15:51:33.748909 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-08 15:51:33.753438 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-08 15:51:34.347581 | 2026-09-08 15:51:34.347697 | PLAY [all] 2026-09-08 15:51:34.361871 | 2026-09-08 15:51:34.361964 | TASK [gather-host-logs : creating directory for system status] 2026-09-08 15:51:34.779753 | instance | changed 2026-09-08 15:51:34.790911 | 2026-09-08 15:51:34.791149 | TASK [gather-host-logs : Get logs for each host] 2026-09-08 15:51:35.200420 | instance | + systemd-cgls --full --all --no-pager 2026-09-08 15:51:35.215969 | instance | + ip addr 2026-09-08 15:51:35.218887 | instance | + ip route 2026-09-08 15:51:35.221215 | instance | + lsblk 2026-09-08 15:51:35.226441 | instance | + mount 2026-09-08 15:51:35.229239 | instance | + docker images 2026-09-08 15:51:35.254647 | instance | + brctl show 2026-09-08 15:51:35.255140 | instance | /bin/bash: line 8: brctl: command not found 2026-09-08 15:51:35.255439 | instance | + ps aux --sort=-%mem 2026-09-08 15:51:35.266971 | instance | + dpkg -l 2026-09-08 15:51:35.278338 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-08 15:51:35.278521 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-08 15:51:35.289445 | instance | + '[' '!' -z '' ']' 2026-09-08 15:51:35.341998 | instance | ok: Runtime: 0:00:00.094024 2026-09-08 15:51:35.350247 | 2026-09-08 15:51:35.350347 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-08 15:51:35.943704 | instance | changed: 2026-09-08 15:51:35.943947 | instance | created directory /var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/logs/instance 2026-09-08 15:51:35.944008 | instance | cd+++++++++ system/ 2026-09-08 15:51:35.944052 | instance | >f+++++++++ system/brctl-show.txt 2026-09-08 15:51:35.944092 | instance | >f+++++++++ system/docker-images.txt 2026-09-08 15:51:35.944160 | instance | >f+++++++++ system/ip-addr.txt 2026-09-08 15:51:35.944206 | instance | >f+++++++++ system/ip-route.txt 2026-09-08 15:51:35.944247 | instance | >f+++++++++ system/lsblk.txt 2026-09-08 15:51:35.944285 | instance | >f+++++++++ system/mount.txt 2026-09-08 15:51:35.944322 | instance | >f+++++++++ system/packages.txt 2026-09-08 15:51:35.944359 | instance | >f+++++++++ system/ps.txt 2026-09-08 15:51:35.944513 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-08 15:51:35.962270 | 2026-09-08 15:51:35.962400 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-08 15:51:36.149378 | instance | changed: "values" 2026-09-08 15:51:36.337175 | instance | changed: "releases" 2026-09-08 15:51:36.355336 | 2026-09-08 15:51:36.355534 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-08 15:51:36.592750 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-08 15:51:36.905030 | instance | ok: Runtime: 0:00:00.005418 2026-09-08 15:51:36.915282 | 2026-09-08 15:51:36.915412 | TASK [helm-release-status : Downloads logs to executor] 2026-09-08 15:51:37.436435 | instance | changed: 2026-09-08 15:51:37.436761 | instance | cd+++++++++ helm/ 2026-09-08 15:51:37.436831 | instance | cd+++++++++ helm/releases/ 2026-09-08 15:51:37.436875 | instance | cd+++++++++ helm/values/ 2026-09-08 15:51:37.454080 | 2026-09-08 15:51:37.454253 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-08 15:51:37.682827 | instance | changed 2026-09-08 15:51:37.698834 | 2026-09-08 15:51:37.699020 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-08 15:51:37.961954 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 15:51:37.962171 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 15:51:37.967633 | instance | environment: line 1: kubectl: command not found 2026-09-08 15:51:37.969041 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 15:51:37.970362 | instance | environment: line 1: kubectl: command not found 2026-09-08 15:51:37.971855 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 15:51:37.973184 | instance | environment: line 1: kubectl: command not found 2026-09-08 15:51:37.975210 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 15:51:37.975766 | instance | environment: line 1: kubectl: command not found 2026-09-08 15:51:37.977196 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 15:51:37.978424 | instance | environment: line 1: kubectl: command not found 2026-09-08 15:51:37.979627 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-08 15:51:38.236278 | instance | ok: Runtime: 0:00:00.025605 2026-09-08 15:51:38.247701 | 2026-09-08 15:51:38.247876 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-08 15:51:38.469871 | instance | changed 2026-09-08 15:51:38.476232 | 2026-09-08 15:51:38.476324 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-08 15:51:38.733585 | instance | environment: line 5: kubectl: command not found 2026-09-08 15:51:38.734023 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 15:51:38.735117 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 15:51:38.735219 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-08 15:51:39.016810 | instance | ok: Runtime: 0:00:00.007043 2026-09-08 15:51:39.026582 | 2026-09-08 15:51:39.026705 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-08 15:51:39.546272 | instance | changed: 2026-09-08 15:51:39.546499 | instance | cd+++++++++ objects/ 2026-09-08 15:51:39.546554 | instance | cd+++++++++ objects/cluster/ 2026-09-08 15:51:39.546600 | instance | cd+++++++++ objects/namespaced/ 2026-09-08 15:51:39.563487 | 2026-09-08 15:51:39.563570 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-08 15:51:39.778327 | instance | changed 2026-09-08 15:51:39.788034 | 2026-09-08 15:51:39.788224 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-08 15:51:40.006120 | instance | changed 2026-09-08 15:51:40.015575 | 2026-09-08 15:51:40.015711 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-08 15:51:40.268900 | instance | environment: line 3: kubectl: command not found 2026-09-08 15:51:40.561419 | instance | ok: Runtime: 0:00:00.008385 2026-09-08 15:51:40.570293 | 2026-09-08 15:51:40.570411 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-08 15:51:41.099060 | instance | changed: 2026-09-08 15:51:41.099317 | instance | cd+++++++++ pod-logs/ 2026-09-08 15:51:41.099390 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-08 15:51:41.116731 | 2026-09-08 15:51:41.116847 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-08 15:51:41.332791 | instance | changed 2026-09-08 15:51:41.342719 | 2026-09-08 15:51:41.342848 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-08 15:51:41.597123 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-08 15:51:41.890493 | instance | ok: Runtime: 0:00:00.058122 2026-09-08 15:51:41.900198 | 2026-09-08 15:51:41.900349 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-08 15:51:42.161659 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-08 15:51:42.209724 | instance | ceph-mgr endpoints: 2026-09-08 15:51:42.449444 | instance | ok: Runtime: 0:00:00.054507 2026-09-08 15:51:42.458886 | 2026-09-08 15:51:42.459010 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-08 15:51:42.721770 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-08 15:51:43.005008 | instance | ok: Runtime: 0:00:00.053976 2026-09-08 15:51:43.014476 | 2026-09-08 15:51:43.014614 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-08 15:51:43.482817 | instance | changed: cd+++++++++ prometheus/ 2026-09-08 15:51:43.500153 | 2026-09-08 15:51:43.500291 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-08 15:51:43.717476 | instance | changed 2026-09-08 15:51:43.727465 | 2026-09-08 15:51:43.727601 | TASK [gather-selenium-data : Get selenium data] 2026-09-08 15:51:44.014974 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-08 15:51:44.016469 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-08 15:51:44.273696 | instance | ERROR 2026-09-08 15:51:44.273915 | instance | { 2026-09-08 15:51:44.273973 | instance | "delta": "0:00:00.006068", 2026-09-08 15:51:44.274015 | instance | "end": "2026-09-08 15:51:44.016834", 2026-09-08 15:51:44.274057 | instance | "msg": "non-zero return code", 2026-09-08 15:51:44.274121 | instance | "rc": 1, 2026-09-08 15:51:44.274157 | instance | "start": "2026-09-08 15:51:44.010766" 2026-09-08 15:51:44.274192 | instance | } 2026-09-08 15:51:44.274235 | instance | ERROR: Ignoring Errors 2026-09-08 15:51:44.283487 | 2026-09-08 15:51:44.283762 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-08 15:51:44.810291 | instance | changed: cd+++++++++ selenium/ 2026-09-08 15:51:44.817723 | 2026-09-08 15:51:44.817801 | PLAY RECAP 2026-09-08 15:51:44.817857 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-08 15:51:44.817887 | 2026-09-08 15:51:44.937112 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-08 15:51:44.941103 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-08 15:51:45.501379 | 2026-09-08 15:51:45.501496 | PLAY [all] 2026-09-08 15:51:45.514434 | 2026-09-08 15:51:45.514578 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-08 15:51:45.568626 | instance | skipping: Conditional result was False 2026-09-08 15:51:45.574400 | 2026-09-08 15:51:45.574516 | TASK [fetch-output : Set log path for single node] 2026-09-08 15:51:45.623574 | instance | ok 2026-09-08 15:51:45.629508 | 2026-09-08 15:51:45.629630 | LOOP [fetch-output : Ensure local output dirs] 2026-09-08 15:51:46.059023 | instance -> localhost | ok: "/var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/logs" 2026-09-08 15:51:46.271102 | instance -> localhost | changed: "/var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/artifacts" 2026-09-08 15:51:46.515258 | instance -> localhost | changed: "/var/lib/zuul/builds/bd12c303111b485db78fea9d6fd7f706/work/docs" 2026-09-08 15:51:46.544684 | 2026-09-08 15:51:46.545653 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-08 15:51:47.231106 | instance | changed: .d..t...... ./ 2026-09-08 15:51:47.231325 | instance | changed: All items complete 2026-09-08 15:51:47.231372 | 2026-09-08 15:51:47.726644 | instance | changed: .d..t...... ./ 2026-09-08 15:51:48.206040 | instance | changed: .d..t...... ./ 2026-09-08 15:51:48.229585 | 2026-09-08 15:51:48.229766 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-08 15:51:48.656932 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.005756 2026-09-08 15:51:48.901567 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005323 2026-09-08 15:51:48.913904 | 2026-09-08 15:51:48.914018 | PLAY [all] 2026-09-08 15:51:48.920866 | 2026-09-08 15:51:48.920942 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-08 15:51:49.338569 | instance | changed 2026-09-08 15:51:49.350317 | 2026-09-08 15:51:49.350448 | PLAY RECAP 2026-09-08 15:51:49.350534 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-08 15:51:49.350581 | 2026-09-08 15:51:49.499340 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-08 15:51:49.503211 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-08 15:51:50.040557 | 2026-09-08 15:51:50.040853 | PLAY [localhost] 2026-09-08 15:51:50.050073 | 2026-09-08 15:51:50.050171 | TASK [Generate Zuul manifest] 2026-09-08 15:51:50.066013 | localhost | ok 2026-09-08 15:51:50.080920 | 2026-09-08 15:51:50.081052 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-08 15:51:50.445326 | localhost | changed 2026-09-08 15:51:50.465731 | 2026-09-08 15:51:50.465929 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-08 15:51:50.497121 | localhost | ok 2026-09-08 15:51:50.510256 | 2026-09-08 15:51:50.510435 | TASK [Upload logs] 2026-09-08 15:51:50.533107 | localhost | ok 2026-09-08 15:51:50.610930 | 2026-09-08 15:51:50.611052 | TASK [Set zuul-log-path fact] 2026-09-08 15:51:50.626716 | localhost | ok 2026-09-08 15:51:50.637221 | 2026-09-08 15:51:50.637284 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-08 15:51:50.665020 | localhost | ok 2026-09-08 15:51:50.674016 | 2026-09-08 15:51:50.674120 | TASK [upload-logs : Create log directories] 2026-09-08 15:51:51.089706 | localhost | changed 2026-09-08 15:51:51.098846 | 2026-09-08 15:51:51.098983 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-08 15:51:51.536909 | localhost -> localhost | ok: Runtime: 0:00:00.003970 2026-09-08 15:51:51.546297 | 2026-09-08 15:51:51.546432 | TASK [upload-logs : Upload logs to log server] 2026-09-08 15:51:52.056960 | localhost | Output suppressed because no_log was given 2026-09-08 15:51:52.099254 | 2026-09-08 15:51:52.099382 | LOOP [upload-logs : Compress console log and json output] 2026-09-08 15:51:52.161813 | localhost | skipping: Conditional result was False 2026-09-08 15:51:52.171666 | localhost | skipping: Conditional result was False 2026-09-08 15:51:52.181506 | 2026-09-08 15:51:52.181682 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-08 15:51:52.216592 | localhost | skipping: Conditional result was False 2026-09-08 15:51:52.217059 | 2026-09-08 15:51:52.218684 | localhost | skipping: Conditional result was False 2026-09-08 15:51:52.236806 | 2026-09-08 15:51:52.237027 | LOOP [upload-logs : Upload console log and json output]