2026-09-12 06:23:15.223862 | Job console starting 2026-09-12 06:23:15.233581 | Updating git repos 2026-09-12 06:23:24.136341 | Cloning repos into workspace 2026-09-12 06:23:40.266110 | Restoring repo states 2026-09-12 06:23:48.100810 | Merging changes 2026-09-12 06:23:49.563607 | Checking out repos 2026-09-12 06:23:51.259775 | Preparing playbooks 2026-09-12 06:23:54.232999 | Running Ansible setup 2026-09-12 06:23:57.969344 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 06:23:58.584520 | 2026-09-12 06:23:58.585654 | PLAY [localhost] 2026-09-12 06:23:58.593282 | 2026-09-12 06:23:58.593375 | TASK [Gathering Facts] 2026-09-12 06:23:59.499272 | localhost | ok 2026-09-12 06:23:59.512836 | 2026-09-12 06:23:59.512985 | TASK [Setup log path fact] 2026-09-12 06:23:59.529197 | localhost | ok 2026-09-12 06:23:59.539419 | 2026-09-12 06:23:59.539571 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 06:23:59.566503 | localhost | ok 2026-09-12 06:23:59.575002 | 2026-09-12 06:23:59.575106 | TASK [emit-job-header : Print job information] 2026-09-12 06:23:59.613328 | # Job Information 2026-09-12 06:23:59.613519 | Ansible Version: 2.16.18 2026-09-12 06:23:59.613562 | Job: atmosphere-molecule-csi-rbd 2026-09-12 06:23:59.613591 | Pipeline: check 2026-09-12 06:23:59.613616 | Executor: 2d72f0692154 2026-09-12 06:23:59.613641 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4092 2026-09-12 06:23:59.613671 | Event ID: e9af3ff0-ae71-11f1-86a8-856e47d25d0d 2026-09-12 06:23:59.617171 | 2026-09-12 06:23:59.617253 | LOOP [emit-job-header : Print node information] 2026-09-12 06:23:59.716828 | localhost | ok: 2026-09-12 06:23:59.717419 | localhost | # Node Information 2026-09-12 06:23:59.717504 | localhost | Inventory Hostname: instance 2026-09-12 06:23:59.717551 | localhost | Hostname: np0000192719 2026-09-12 06:23:59.717592 | localhost | Username: zuul 2026-09-12 06:23:59.717639 | localhost | Distro: Ubuntu 22.04 2026-09-12 06:23:59.717679 | localhost | Provider: yul1 2026-09-12 06:23:59.717717 | localhost | Region: ca-ymq-1 2026-09-12 06:23:59.717754 | localhost | Label: ubuntu-jammy 2026-09-12 06:23:59.717790 | localhost | Product Name: OpenStack Nova 2026-09-12 06:23:59.717825 | localhost | Interface IP: 162.253.55.115 2026-09-12 06:23:59.734262 | 2026-09-12 06:23:59.734469 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-12 06:24:00.157312 | localhost -> localhost | changed 2026-09-12 06:24:00.165981 | 2026-09-12 06:24:00.166117 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-12 06:24:01.154479 | localhost -> localhost | changed 2026-09-12 06:24:01.170581 | 2026-09-12 06:24:01.171093 | PLAY [all] 2026-09-12 06:24:01.190903 | 2026-09-12 06:24:01.191054 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-12 06:24:01.449497 | instance -> localhost | ok 2026-09-12 06:24:01.465008 | 2026-09-12 06:24:01.465134 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-12 06:24:01.493962 | instance | ok 2026-09-12 06:24:01.507028 | instance | included: /var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-12 06:24:01.512706 | 2026-09-12 06:24:01.512813 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-12 06:24:03.123258 | instance -> localhost | Generating public/private rsa key pair. 2026-09-12 06:24:03.123421 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/581aa1f2253d42b9876c8cec3a83c3e0_id_rsa 2026-09-12 06:24:03.123461 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/581aa1f2253d42b9876c8cec3a83c3e0_id_rsa.pub 2026-09-12 06:24:03.123484 | instance -> localhost | The key fingerprint is: 2026-09-12 06:24:03.123504 | instance -> localhost | SHA256:rVoJeW8Okxjz3jVWe7SqGXv//EIguBe9IQa/knBJVWY zuul-build-sshkey 2026-09-12 06:24:03.123535 | instance -> localhost | The key's randomart image is: 2026-09-12 06:24:03.123554 | instance -> localhost | +---[RSA 3072]----+ 2026-09-12 06:24:03.123574 | instance -> localhost | | ...E | 2026-09-12 06:24:03.123593 | instance -> localhost | | o o | 2026-09-12 06:24:03.123611 | instance -> localhost | | . = . | 2026-09-12 06:24:03.123629 | instance -> localhost | | ..+.* + | 2026-09-12 06:24:03.123646 | instance -> localhost | | =oS+.= +. .| 2026-09-12 06:24:03.123663 | instance -> localhost | | B+=o ...o.| 2026-09-12 06:24:03.123680 | instance -> localhost | | . Ooo.+....| 2026-09-12 06:24:03.123697 | instance -> localhost | | + * o+ooo | 2026-09-12 06:24:03.123717 | instance -> localhost | | . . o+o..o*| 2026-09-12 06:24:03.123735 | instance -> localhost | +----[SHA256]-----+ 2026-09-12 06:24:03.123779 | instance -> localhost | ok: Runtime: 0:00:01.067474 2026-09-12 06:24:03.129411 | 2026-09-12 06:24:03.129495 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-12 06:24:03.159415 | instance | ok 2026-09-12 06:24:03.169832 | instance | included: /var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-12 06:24:03.180319 | 2026-09-12 06:24:03.180634 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-12 06:24:03.204379 | instance | skipping: Conditional result was False 2026-09-12 06:24:03.211160 | 2026-09-12 06:24:03.211242 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-12 06:24:03.738552 | instance | changed 2026-09-12 06:24:03.747241 | 2026-09-12 06:24:03.747386 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-12 06:24:03.949792 | instance | ok 2026-09-12 06:24:03.955128 | 2026-09-12 06:24:03.955240 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-12 06:24:04.463815 | instance | changed 2026-09-12 06:24:04.470572 | 2026-09-12 06:24:04.470683 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-12 06:24:04.972040 | instance | changed 2026-09-12 06:24:04.977312 | 2026-09-12 06:24:04.977375 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-12 06:24:05.000269 | instance | skipping: Conditional result was False 2026-09-12 06:24:05.007814 | 2026-09-12 06:24:05.007910 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-12 06:24:05.456218 | instance -> localhost | changed 2026-09-12 06:24:05.469852 | 2026-09-12 06:24:05.469990 | TASK [add-build-sshkey : Add back temp key] 2026-09-12 06:24:05.786949 | instance -> localhost | Identity added: /var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/581aa1f2253d42b9876c8cec3a83c3e0_id_rsa (zuul-build-sshkey) 2026-09-12 06:24:05.787170 | instance -> localhost | ok: Runtime: 0:00:00.011486 2026-09-12 06:24:05.791884 | 2026-09-12 06:24:05.791966 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-12 06:24:06.112274 | instance | ok 2026-09-12 06:24:06.124740 | 2026-09-12 06:24:06.124900 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-12 06:24:06.160752 | instance | skipping: Conditional result was False 2026-09-12 06:24:06.178262 | 2026-09-12 06:24:06.178514 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-12 06:24:06.549749 | instance | ok 2026-09-12 06:24:06.561314 | 2026-09-12 06:24:06.561524 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-12 06:24:10.135984 | instance | Output suppressed because no_log was given 2026-09-12 06:24:10.147582 | 2026-09-12 06:24:10.147772 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-12 06:24:10.358951 | instance | ok: "logs" 2026-09-12 06:24:10.360812 | instance | ok: All items complete 2026-09-12 06:24:10.361023 | 2026-09-12 06:24:10.520842 | instance | ok: "artifacts" 2026-09-12 06:24:10.698580 | instance | ok: "docs" 2026-09-12 06:24:10.714496 | 2026-09-12 06:24:10.714734 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-12 06:24:10.909643 | instance | changed: "logs" 2026-09-12 06:24:11.067814 | instance | changed: "artifacts" 2026-09-12 06:24:11.209605 | instance | changed: "docs" 2026-09-12 06:24:11.229302 | 2026-09-12 06:24:11.229432 | PLAY RECAP 2026-09-12 06:24:11.229580 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-12 06:24:11.229654 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 06:24:11.229718 | 2026-09-12 06:24:11.440162 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-12 06:24:11.445470 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 06:24:12.018653 | 2026-09-12 06:24:12.178935 | PLAY [all] 2026-09-12 06:24:12.202347 | 2026-09-12 06:24:12.202571 | TASK [setup-uv : Extract archive] 2026-09-12 06:24:14.352817 | instance | changed 2026-09-12 06:24:14.359531 | 2026-09-12 06:24:14.359636 | TASK [setup-uv : Print version] 2026-09-12 06:24:14.725571 | instance | uv 0.8.13 2026-09-12 06:24:14.893529 | instance | ok: Runtime: 0:00:00.013412 2026-09-12 06:24:14.912363 | 2026-09-12 06:24:14.912616 | TASK [Install Ansible collection siblings] 2026-09-12 06:24:26.147080 | instance | changed: 2026-09-12 06:24:26.147243 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 06:24:26.147269 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 06:24:26.147289 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 06:24:26.147306 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 06:24:26.147323 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 06:24:26.147344 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 06:24:26.147363 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 06:24:26.147382 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 06:24:26.147399 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 06:24:26.147416 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 06:24:26.147432 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 06:24:26.147460 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 06:24:26.147477 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-12 06:24:26.147494 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-12 06:24:26.147511 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-12 06:24:26.147527 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-12 06:24:26.147543 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-12 06:24:26.147559 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-12 06:24:26.147575 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-12 06:24:26.147590 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-12 06:24:26.147606 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-12 06:24:26.147622 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-12 06:24:26.147639 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 06:24:26.147668 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-12 06:24:26.147685 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-12 06:24:26.147701 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 06:24:26.147717 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 06:24:26.147732 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-12 06:24:26.147748 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-12 06:24:26.147763 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-12 06:24:26.147778 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-12 06:24:26.147795 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-12 06:24:26.147810 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-12 06:24:26.147826 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-12 06:24:26.147847 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.147863 | instance | Process install dependency map 2026-09-12 06:24:26.147878 | instance | Starting collection install process 2026-09-12 06:24:26.147894 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-12 06:24:26.147910 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-12 06:24:26.147925 | instance | ansible.netcommon:* was installed successfully 2026-09-12 06:24:26.147940 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-12 06:24:26.147956 | instance | Creating virtual environment at: .venv 2026-09-12 06:24:26.147971 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 06:24:26.147986 | instance | Downloading openstacksdk (1.7MiB) 2026-09-12 06:24:26.148001 | instance | Downloading ansible-core (2.1MiB) 2026-09-12 06:24:26.148017 | instance | Downloading rjsonnet (1.2MiB) 2026-09-12 06:24:26.148033 | instance | Downloading setuptools (1.1MiB) 2026-09-12 06:24:26.148048 | instance | Downloading netaddr (2.2MiB) 2026-09-12 06:24:26.148064 | instance | Downloading pygments (1.2MiB) 2026-09-12 06:24:26.148080 | instance | Downloading kubernetes (1.9MiB) 2026-09-12 06:24:26.148096 | instance | Downloading cryptography (4.2MiB) 2026-09-12 06:24:26.148111 | instance | Building pyperclip==1.9.0 2026-09-12 06:24:26.148126 | instance | Downloading rjsonnet 2026-09-12 06:24:26.148143 | instance | Downloading netaddr 2026-09-12 06:24:26.148159 | instance | Downloading pygments 2026-09-12 06:24:26.148175 | instance | Downloading cryptography 2026-09-12 06:24:26.148190 | instance | Downloading setuptools 2026-09-12 06:24:26.148205 | instance | Downloading kubernetes 2026-09-12 06:24:26.148220 | instance | Downloading ansible-core 2026-09-12 06:24:26.148235 | instance | Downloading openstacksdk 2026-09-12 06:24:26.148250 | instance | Built pyperclip==1.9.0 2026-09-12 06:24:26.148266 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-12 06:24:26.148281 | instance | Installed 79 packages in 59ms 2026-09-12 06:24:26.148296 | instance | Checked out ansible.posix to 1.6.0 2026-09-12 06:24:26.148313 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-12 06:24:26.148328 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.148344 | instance | Process install dependency map 2026-09-12 06:24:26.148359 | instance | Starting collection install process 2026-09-12 06:24:26.148374 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-12 06:24:26.148394 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-12 06:24:26.148410 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-12 06:24:26.148426 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.148441 | instance | 2.17.12 2026-09-12 06:24:26.148469 | instance | Checked out ansible.utils to v6.1.0 2026-09-12 06:24:26.148485 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-12 06:24:26.148537 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.148568 | instance | Process install dependency map 2026-09-12 06:24:26.148586 | instance | Starting collection install process 2026-09-12 06:24:26.148603 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-12 06:24:26.148619 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-12 06:24:26.148634 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-12 06:24:26.148650 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.148666 | instance | 2.17.12 2026-09-12 06:24:26.148682 | instance | Checked out atmosphere.common to v0.7.0 2026-09-12 06:24:26.148696 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-12 06:24:26.148712 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.148727 | instance | Process install dependency map 2026-09-12 06:24:26.148742 | instance | Starting collection install process 2026-09-12 06:24:26.148757 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-12 06:24:26.148772 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-12 06:24:26.148787 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-12 06:24:26.148802 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.148818 | instance | 2.17.12 2026-09-12 06:24:26.148836 | instance | Checked out community.crypto to 2.2.3 2026-09-12 06:24:26.148852 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-12 06:24:26.148867 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.148882 | instance | Process install dependency map 2026-09-12 06:24:26.148897 | instance | Starting collection install process 2026-09-12 06:24:26.148912 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-12 06:24:26.148927 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-12 06:24:26.148942 | instance | community.crypto:2.2.3 was installed successfully 2026-09-12 06:24:26.148957 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.148972 | instance | 2.17.12 2026-09-12 06:24:26.148987 | instance | Checked out community.general to 7.3.0 2026-09-12 06:24:26.149002 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general 2026-09-12 06:24:26.149017 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149038 | instance | Process install dependency map 2026-09-12 06:24:26.149053 | instance | Starting collection install process 2026-09-12 06:24:26.149068 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-12 06:24:26.149083 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-12 06:24:26.149098 | instance | community.general:7.3.0 was installed successfully 2026-09-12 06:24:26.149113 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.149128 | instance | 2.17.12 2026-09-12 06:24:26.149143 | instance | Checked out community.mysql to 3.6.0 2026-09-12 06:24:26.149158 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-12 06:24:26.149173 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149190 | instance | Process install dependency map 2026-09-12 06:24:26.149206 | instance | Starting collection install process 2026-09-12 06:24:26.149221 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-12 06:24:26.149236 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-12 06:24:26.149251 | instance | community.mysql:3.6.0 was installed successfully 2026-09-12 06:24:26.149265 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.149280 | instance | 2.17.12 2026-09-12 06:24:26.149295 | instance | Checked out kubernetes.core to 2.4.0 2026-09-12 06:24:26.149309 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-12 06:24:26.149324 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149339 | instance | Process install dependency map 2026-09-12 06:24:26.149354 | instance | Starting collection install process 2026-09-12 06:24:26.149369 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-12 06:24:26.149385 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-12 06:24:26.149400 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-12 06:24:26.149415 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.149430 | instance | 2.17.12 2026-09-12 06:24:26.149458 | instance | Checked out openstack.cloud to 2.6.0 2026-09-12 06:24:26.149475 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-12 06:24:26.149490 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149505 | instance | Process install dependency map 2026-09-12 06:24:26.149520 | instance | Starting collection install process 2026-09-12 06:24:26.149535 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-12 06:24:26.149550 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-12 06:24:26.149565 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-12 06:24:26.149580 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.149595 | instance | 2.17.12 2026-09-12 06:24:26.149610 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-12 06:24:26.149624 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-12 06:24:26.149643 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149658 | instance | Process install dependency map 2026-09-12 06:24:26.149673 | instance | Starting collection install process 2026-09-12 06:24:26.149687 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-12 06:24:26.149702 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-12 06:24:26.149717 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-12 06:24:26.149732 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.149746 | instance | 2.17.12 2026-09-12 06:24:26.149761 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-12 06:24:26.149775 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-12 06:24:26.149790 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149805 | instance | Process install dependency map 2026-09-12 06:24:26.149820 | instance | Starting collection install process 2026-09-12 06:24:26.149835 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-12 06:24:26.149850 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-12 06:24:26.149865 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-12 06:24:26.149880 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.149894 | instance | 2.17.12 2026-09-12 06:24:26.149909 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-12 06:24:26.149924 | instance | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-12 06:24:26.149939 | instance | Starting galaxy collection install process 2026-09-12 06:24:26.149954 | instance | Process install dependency map 2026-09-12 06:24:26.149969 | instance | Starting collection install process 2026-09-12 06:24:26.149984 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-12 06:24:26.149999 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-12 06:24:26.150014 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-12 06:24:26.150029 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-12 06:24:26.150044 | instance | 2.17.12 2026-09-12 06:24:26.154849 | 2026-09-12 06:24:26.154891 | PLAY RECAP 2026-09-12 06:24:26.154927 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 06:24:26.154946 | 2026-09-12 06:24:26.283522 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-12 06:24:26.287978 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 06:24:26.945806 | 2026-09-12 06:24:26.946026 | PLAY [all] 2026-09-12 06:24:26.959867 | 2026-09-12 06:24:26.960107 | TASK [Install "jq" for log collection] 2026-09-12 06:24:37.344251 | instance | changed 2026-09-12 06:24:37.346692 | 2026-09-12 06:24:37.346768 | PLAY RECAP 2026-09-12 06:24:37.346837 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-12 06:24:37.346915 | 2026-09-12 06:24:37.511323 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-12 06:24:37.513305 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 06:24:38.161008 | 2026-09-12 06:24:38.161431 | PLAY [all] 2026-09-12 06:24:38.172326 | 2026-09-12 06:24:38.172427 | LOOP [Check inventory directories] 2026-09-12 06:24:38.507367 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-12 06:24:38.675378 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-12 06:24:38.841574 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-12 06:24:38.865294 | 2026-09-12 06:24:38.865645 | LOOP [Copy inventory file for Zuul] 2026-09-12 06:24:39.747187 | instance | changed: 2026-09-12 06:24:39.747733 | instance | { 2026-09-12 06:24:39.747794 | instance | "ansible_loop_var": "item", 2026-09-12 06:24:39.747840 | instance | "changed": false, 2026-09-12 06:24:39.747880 | instance | "failed": false, 2026-09-12 06:24:39.747930 | instance | "invocation": { 2026-09-12 06:24:39.747969 | instance | "module_args": { 2026-09-12 06:24:39.748006 | instance | "checksum_algorithm": "sha1", 2026-09-12 06:24:39.748043 | instance | "follow": true, 2026-09-12 06:24:39.748080 | instance | "get_attributes": true, 2026-09-12 06:24:39.748119 | instance | "get_checksum": true, 2026-09-12 06:24:39.748155 | instance | "get_mime": true, 2026-09-12 06:24:39.748190 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-12 06:24:39.748226 | instance | } 2026-09-12 06:24:39.748262 | instance | }, 2026-09-12 06:24:39.748297 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-12 06:24:39.748333 | instance | "stat": { 2026-09-12 06:24:39.748367 | instance | "atime": 1789194259.2228427, 2026-09-12 06:24:39.748403 | instance | "attr_flags": "e", 2026-09-12 06:24:39.748437 | instance | "attributes": [ 2026-09-12 06:24:39.748533 | instance | "extents" 2026-09-12 06:24:39.748591 | instance | ], 2026-09-12 06:24:39.748636 | instance | "block_size": 4096, 2026-09-12 06:24:39.748671 | instance | "blocks": 8, 2026-09-12 06:24:39.748707 | instance | "charset": "unknown", 2026-09-12 06:24:39.748745 | instance | "ctime": 1789194255.866839, 2026-09-12 06:24:39.748780 | instance | "dev": 64513, 2026-09-12 06:24:39.748815 | instance | "device_type": 0, 2026-09-12 06:24:39.748850 | instance | "executable": true, 2026-09-12 06:24:39.748885 | instance | "exists": true, 2026-09-12 06:24:39.748920 | instance | "gid": 1000, 2026-09-12 06:24:39.748956 | instance | "gr_name": "zuul", 2026-09-12 06:24:39.748990 | instance | "inode": 1049087, 2026-09-12 06:24:39.749024 | instance | "isblk": false, 2026-09-12 06:24:39.749061 | instance | "ischr": false, 2026-09-12 06:24:39.749096 | instance | "isdir": true, 2026-09-12 06:24:39.749131 | instance | "isfifo": false, 2026-09-12 06:24:39.749165 | instance | "isgid": false, 2026-09-12 06:24:39.749198 | instance | "islnk": false, 2026-09-12 06:24:39.749231 | instance | "isreg": false, 2026-09-12 06:24:39.749264 | instance | "issock": false, 2026-09-12 06:24:39.749297 | instance | "isuid": false, 2026-09-12 06:24:39.749330 | instance | "mimetype": "unknown", 2026-09-12 06:24:39.749364 | instance | "mode": "0755", 2026-09-12 06:24:39.749399 | instance | "mtime": 1789194255.866839, 2026-09-12 06:24:39.749434 | instance | "nlink": 21, 2026-09-12 06:24:39.749495 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-12 06:24:39.749530 | instance | "pw_name": "zuul", 2026-09-12 06:24:39.749571 | instance | "readable": true, 2026-09-12 06:24:39.749606 | instance | "rgrp": true, 2026-09-12 06:24:39.749641 | instance | "roth": true, 2026-09-12 06:24:39.749675 | instance | "rusr": true, 2026-09-12 06:24:39.749709 | instance | "size": 4096, 2026-09-12 06:24:39.749743 | instance | "uid": 1000, 2026-09-12 06:24:39.749797 | instance | "version": "4186222903", 2026-09-12 06:24:39.749834 | instance | "wgrp": false, 2026-09-12 06:24:39.749872 | instance | "woth": false, 2026-09-12 06:24:39.749907 | instance | "writeable": true, 2026-09-12 06:24:39.749942 | instance | "wusr": true, 2026-09-12 06:24:39.749976 | instance | "xgrp": true, 2026-09-12 06:24:39.750009 | instance | "xoth": true, 2026-09-12 06:24:39.750042 | instance | "xusr": true 2026-09-12 06:24:39.750076 | instance | } 2026-09-12 06:24:39.750111 | instance | } 2026-09-12 06:24:39.750180 | instance | ok: All items complete 2026-09-12 06:24:39.750222 | 2026-09-12 06:24:39.753104 | instance | skipping: Conditional result was False 2026-09-12 06:24:39.756680 | instance | skipping: Conditional result was False 2026-09-12 06:24:39.775511 | 2026-09-12 06:24:39.775851 | LOOP [Switch "ansible_host" to private IP] 2026-09-12 06:24:40.130702 | instance | changed: 1 replacements made 2026-09-12 06:24:40.132484 | instance | skipping: Conditional result was False 2026-09-12 06:24:40.136676 | instance | skipping: Conditional result was False 2026-09-12 06:24:40.159048 | 2026-09-12 06:24:40.159204 | TASK [Run Molecule scenario] 2026-09-12 06:24:41.071857 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-12 06:24:41.459988 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-12 06:24:41.460122 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-12 06:24:52.045433 | instance | INFO [csi > dependency] Executing 2026-09-12 06:24:52.045822 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-12 06:24:52.046302 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-12 06:24:52.046569 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-12 06:24:52.063223 | instance | INFO [csi > cleanup] Executing 2026-09-12 06:24:52.063852 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 06:24:52.077390 | instance | INFO [csi > destroy] Executing 2026-09-12 06:24:52.077440 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-12 06:24:52.077567 | instance | INFO [csi > destroy] Executed: Successful 2026-09-12 06:24:52.087247 | instance | INFO [csi > syntax] Executing 2026-09-12 06:24:53.482653 | instance | 2026-09-12 06:24:53.482788 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 06:24:53.541780 | instance | INFO [csi > syntax] Executed: Successful 2026-09-12 06:24:53.553779 | instance | INFO [csi > create] Executing 2026-09-12 06:24:53.556444 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-12 06:24:53.568378 | instance | INFO [csi > prepare] Executing 2026-09-12 06:24:54.317485 | instance | 2026-09-12 06:24:54.317606 | instance | PLAY [Prepare] ***************************************************************** 2026-09-12 06:24:54.317845 | instance | 2026-09-12 06:24:54.318144 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 06:24:54.318395 | instance | Saturday 12 September 2026 06:24:54 +0000 (0:00:00.024) 0:00:00.024 **** 2026-09-12 06:24:55.543883 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 06:24:55.544128 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 06:24:55.544442 | instance | interpreter could change the meaning of that path. See 2026-09-12 06:24:55.544818 | instance | https://docs.ansible.com/ansible- 2026-09-12 06:24:55.545148 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 06:24:55.550159 | instance | ok: [instance] 2026-09-12 06:24:55.550381 | instance | 2026-09-12 06:24:55.550640 | instance | TASK [Configure short hostname] ************************************************ 2026-09-12 06:24:55.550893 | instance | Saturday 12 September 2026 06:24:55 +0000 (0:00:01.233) 0:00:01.258 **** 2026-09-12 06:24:56.233319 | instance | changed: [instance] 2026-09-12 06:24:56.233611 | instance | 2026-09-12 06:24:56.233985 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-12 06:24:56.234349 | instance | Saturday 12 September 2026 06:24:56 +0000 (0:00:00.682) 0:00:01.940 **** 2026-09-12 06:24:56.512571 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-12 06:24:56.512827 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-12 06:24:56.513199 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-12 06:24:56.521953 | instance | changed: [instance] 2026-09-12 06:24:56.522219 | instance | 2026-09-12 06:24:56.522550 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-12 06:24:56.522806 | instance | Saturday 12 September 2026 06:24:56 +0000 (0:00:00.288) 0:00:02.229 **** 2026-09-12 06:24:57.327502 | instance | ok: [instance] 2026-09-12 06:24:57.327597 | instance | 2026-09-12 06:24:57.327706 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-12 06:24:57.327809 | instance | 2026-09-12 06:24:57.327921 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 06:24:57.328047 | instance | Saturday 12 September 2026 06:24:57 +0000 (0:00:00.805) 0:00:03.035 **** 2026-09-12 06:24:58.080076 | instance | ok: [instance] 2026-09-12 06:24:58.080306 | instance | 2026-09-12 06:24:58.080557 | instance | TASK [Install depedencies] ***************************************************** 2026-09-12 06:24:58.080848 | instance | Saturday 12 September 2026 06:24:58 +0000 (0:00:00.752) 0:00:03.787 **** 2026-09-12 06:25:21.368069 | instance | changed: [instance] 2026-09-12 06:25:21.368318 | instance | 2026-09-12 06:25:21.368664 | instance | TASK [Start up service] ******************************************************** 2026-09-12 06:25:21.368990 | instance | Saturday 12 September 2026 06:25:21 +0000 (0:00:23.287) 0:00:27.075 **** 2026-09-12 06:25:21.919852 | instance | ok: [instance] 2026-09-12 06:25:21.920049 | instance | 2026-09-12 06:25:21.920293 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-12 06:25:21.920545 | instance | Saturday 12 September 2026 06:25:21 +0000 (0:00:00.552) 0:00:27.627 **** 2026-09-12 06:25:22.251945 | instance | ok: [instance] 2026-09-12 06:25:22.252175 | instance | 2026-09-12 06:25:22.252478 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-12 06:25:22.252836 | instance | Saturday 12 September 2026 06:25:22 +0000 (0:00:00.331) 0:00:27.959 **** 2026-09-12 06:25:22.944476 | instance | changed: [instance] 2026-09-12 06:25:22.944560 | instance | 2026-09-12 06:25:22.944788 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-12 06:25:22.944942 | instance | Saturday 12 September 2026 06:25:22 +0000 (0:00:00.693) 0:00:28.652 **** 2026-09-12 06:25:23.158301 | instance | ok: [instance] 2026-09-12 06:25:23.158506 | instance | 2026-09-12 06:25:23.158784 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-12 06:25:23.159038 | instance | Saturday 12 September 2026 06:25:23 +0000 (0:00:00.213) 0:00:28.865 **** 2026-09-12 06:25:23.182335 | instance | skipping: [instance] 2026-09-12 06:25:23.182560 | instance | 2026-09-12 06:25:23.182865 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-12 06:25:23.183127 | instance | Saturday 12 September 2026 06:25:23 +0000 (0:00:00.024) 0:00:28.889 **** 2026-09-12 06:25:23.753430 | instance | changed: [instance] => (item=osd0) 2026-09-12 06:25:23.753697 | instance | changed: [instance] => (item=osd1) 2026-09-12 06:25:23.753942 | instance | changed: [instance] => (item=osd2) 2026-09-12 06:25:23.754173 | instance | 2026-09-12 06:25:23.754491 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-12 06:25:23.754862 | instance | Saturday 12 September 2026 06:25:23 +0000 (0:00:00.570) 0:00:29.460 **** 2026-09-12 06:25:24.418002 | instance | changed: [instance] => (item=osd0) 2026-09-12 06:25:24.418814 | instance | changed: [instance] => (item=osd1) 2026-09-12 06:25:24.418858 | instance | changed: [instance] => (item=osd2) 2026-09-12 06:25:24.418866 | instance | 2026-09-12 06:25:24.418873 | instance | TASK [Start loop devices] ****************************************************** 2026-09-12 06:25:24.418879 | instance | Saturday 12 September 2026 06:25:24 +0000 (0:00:00.665) 0:00:30.125 **** 2026-09-12 06:25:26.190455 | instance | changed: [instance] => (item=osd0) 2026-09-12 06:25:26.190588 | instance | changed: [instance] => (item=osd1) 2026-09-12 06:25:26.191228 | instance | changed: [instance] => (item=osd2) 2026-09-12 06:25:26.191248 | instance | 2026-09-12 06:25:26.191255 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-12 06:25:26.191262 | instance | Saturday 12 September 2026 06:25:26 +0000 (0:00:01.772) 0:00:31.898 **** 2026-09-12 06:25:29.549970 | instance | changed: [instance] => (item=osd0) 2026-09-12 06:25:29.551186 | instance | changed: [instance] => (item=osd1) 2026-09-12 06:25:29.551218 | instance | changed: [instance] => (item=osd2) 2026-09-12 06:25:29.551230 | instance | 2026-09-12 06:25:29.551240 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-12 06:25:29.551250 | instance | Saturday 12 September 2026 06:25:29 +0000 (0:00:03.359) 0:00:35.257 **** 2026-09-12 06:25:31.521070 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-12 06:25:31.521122 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-12 06:25:31.524781 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-12 06:25:31.524857 | instance | 2026-09-12 06:25:31.524870 | instance | PLAY RECAP ********************************************************************* 2026-09-12 06:25:31.524881 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-12 06:25:31.524891 | instance | 2026-09-12 06:25:31.524901 | instance | Saturday 12 September 2026 06:25:31 +0000 (0:00:01.971) 0:00:37.228 **** 2026-09-12 06:25:31.524911 | instance | =============================================================================== 2026-09-12 06:25:31.524921 | instance | Install depedencies ---------------------------------------------------- 23.29s 2026-09-12 06:25:31.524930 | instance | Create a volume group for each loop device ------------------------------ 3.36s 2026-09-12 06:25:31.524940 | instance | Create a logical volume for each loop device ---------------------------- 1.97s 2026-09-12 06:25:31.524951 | instance | Start loop devices ------------------------------------------------------ 1.77s 2026-09-12 06:25:31.524960 | instance | Gathering Facts --------------------------------------------------------- 1.23s 2026-09-12 06:25:31.524970 | instance | Purge "snapd" package --------------------------------------------------- 0.81s 2026-09-12 06:25:31.524980 | instance | Gathering Facts --------------------------------------------------------- 0.75s 2026-09-12 06:25:31.524990 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.69s 2026-09-12 06:25:31.525010 | instance | Configure short hostname ------------------------------------------------ 0.68s 2026-09-12 06:25:31.525102 | instance | Set permissions on loopback devices ------------------------------------- 0.67s 2026-09-12 06:25:31.525468 | instance | Create devices for Ceph ------------------------------------------------- 0.57s 2026-09-12 06:25:31.525764 | instance | Start up service -------------------------------------------------------- 0.55s 2026-09-12 06:25:31.526053 | instance | Generate lvm.conf ------------------------------------------------------- 0.33s 2026-09-12 06:25:31.526346 | instance | Ensure hostname inside hosts file --------------------------------------- 0.29s 2026-09-12 06:25:31.526659 | instance | Get list of all loopback devices ---------------------------------------- 0.21s 2026-09-12 06:25:31.526952 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-09-12 06:25:31.595434 | instance | INFO [csi > prepare] Executed: Successful 2026-09-12 06:25:31.610120 | instance | INFO [csi > converge] Executing 2026-09-12 06:25:32.981307 | instance | 2026-09-12 06:25:32.983381 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-12 06:25:32.986020 | instance | 2026-09-12 06:25:32.986462 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 06:25:32.986899 | instance | Saturday 12 September 2026 06:25:32 +0000 (0:00:00.007) 0:00:00.007 **** 2026-09-12 06:25:34.060447 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-12 06:25:34.060508 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-12 06:25:34.060521 | instance | interpreter could change the meaning of that path. See 2026-09-12 06:25:34.060532 | instance | https://docs.ansible.com/ansible- 2026-09-12 06:25:34.060542 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-12 06:25:34.075384 | instance | ok: [instance] 2026-09-12 06:25:34.075439 | instance | 2026-09-12 06:25:34.075452 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-12 06:25:34.075462 | instance | Saturday 12 September 2026 06:25:34 +0000 (0:00:01.093) 0:00:01.100 **** 2026-09-12 06:25:34.138670 | instance | ok: [instance] => { 2026-09-12 06:25:34.138726 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-12 06:25:34.138737 | instance | } 2026-09-12 06:25:34.138747 | instance | 2026-09-12 06:25:34.138756 | instance | PLAY [all] ********************************************************************* 2026-09-12 06:25:34.138765 | instance | 2026-09-12 06:25:34.138779 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 06:25:34.139049 | instance | Saturday 12 September 2026 06:25:34 +0000 (0:00:00.061) 0:00:01.162 **** 2026-09-12 06:25:35.088769 | instance | ok: [instance] 2026-09-12 06:25:35.089162 | instance | 2026-09-12 06:25:35.089539 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-12 06:25:35.089968 | instance | Saturday 12 September 2026 06:25:35 +0000 (0:00:00.952) 0:00:02.114 **** 2026-09-12 06:25:35.272277 | instance | ok: [instance] 2026-09-12 06:25:35.272491 | instance | 2026-09-12 06:25:35.272707 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-12 06:25:35.272915 | instance | 2026-09-12 06:25:35.273129 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-12 06:25:35.273320 | instance | Saturday 12 September 2026 06:25:35 +0000 (0:00:00.182) 0:00:02.297 **** 2026-09-12 06:25:36.192144 | instance | ok: [instance] 2026-09-12 06:25:36.192203 | instance | 2026-09-12 06:25:36.192216 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 06:25:36.192238 | instance | Saturday 12 September 2026 06:25:36 +0000 (0:00:00.920) 0:00:03.217 **** 2026-09-12 06:25:36.504716 | instance | ok: [instance] 2026-09-12 06:25:36.504788 | instance | 2026-09-12 06:25:36.504991 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 06:25:36.505250 | instance | Saturday 12 September 2026 06:25:36 +0000 (0:00:00.312) 0:00:03.529 **** 2026-09-12 06:25:36.546645 | instance | skipping: [instance] 2026-09-12 06:25:36.546845 | instance | 2026-09-12 06:25:36.547177 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-12 06:25:36.547347 | instance | Saturday 12 September 2026 06:25:36 +0000 (0:00:00.043) 0:00:03.572 **** 2026-09-12 06:25:36.878244 | instance | changed: [instance] 2026-09-12 06:25:36.878459 | instance | 2026-09-12 06:25:36.878800 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 06:25:36.879040 | instance | Saturday 12 September 2026 06:25:36 +0000 (0:00:00.330) 0:00:03.903 **** 2026-09-12 06:25:36.973336 | instance | ok: [instance] => { 2026-09-12 06:25:36.973701 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-12 06:25:36.974003 | instance | } 2026-09-12 06:25:36.974276 | instance | 2026-09-12 06:25:36.974608 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 06:25:36.974898 | instance | Saturday 12 September 2026 06:25:36 +0000 (0:00:00.094) 0:00:03.998 **** 2026-09-12 06:25:37.672813 | instance | changed: [instance] 2026-09-12 06:25:37.673229 | instance | 2026-09-12 06:25:37.673465 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 06:25:37.673661 | instance | Saturday 12 September 2026 06:25:37 +0000 (0:00:00.699) 0:00:04.698 **** 2026-09-12 06:25:37.737232 | instance | skipping: [instance] 2026-09-12 06:25:37.737313 | instance | 2026-09-12 06:25:37.737509 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 06:25:37.737790 | instance | Saturday 12 September 2026 06:25:37 +0000 (0:00:00.064) 0:00:04.762 **** 2026-09-12 06:25:37.805243 | instance | skipping: [instance] 2026-09-12 06:25:37.805325 | instance | 2026-09-12 06:25:37.805515 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 06:25:37.805758 | instance | Saturday 12 September 2026 06:25:37 +0000 (0:00:00.066) 0:00:04.829 **** 2026-09-12 06:25:38.057294 | instance | ok: [instance] 2026-09-12 06:25:38.057429 | instance | 2026-09-12 06:25:38.057637 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-12 06:25:38.057834 | instance | Saturday 12 September 2026 06:25:38 +0000 (0:00:00.252) 0:00:05.081 **** 2026-09-12 06:25:39.469419 | instance | ok: [instance] 2026-09-12 06:25:39.469820 | instance | 2026-09-12 06:25:39.470145 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 06:25:39.470576 | instance | Saturday 12 September 2026 06:25:39 +0000 (0:00:01.412) 0:00:06.494 **** 2026-09-12 06:25:39.556311 | instance | ok: [instance] => { 2026-09-12 06:25:39.556579 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-12 06:25:39.556833 | instance | } 2026-09-12 06:25:39.557026 | instance | 2026-09-12 06:25:39.557225 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 06:25:39.557472 | instance | Saturday 12 September 2026 06:25:39 +0000 (0:00:00.085) 0:00:06.580 **** 2026-09-12 06:25:40.388729 | instance | changed: [instance] 2026-09-12 06:25:40.388973 | instance | 2026-09-12 06:25:40.389189 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 06:25:40.389465 | instance | Saturday 12 September 2026 06:25:40 +0000 (0:00:00.832) 0:00:07.412 **** 2026-09-12 06:25:43.351364 | instance | changed: [instance] 2026-09-12 06:25:43.351573 | instance | 2026-09-12 06:25:43.351840 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-12 06:25:43.352123 | instance | Saturday 12 September 2026 06:25:43 +0000 (0:00:02.963) 0:00:10.376 **** 2026-09-12 06:25:43.393737 | instance | skipping: [instance] 2026-09-12 06:25:43.393966 | instance | 2026-09-12 06:25:43.394326 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-12 06:25:43.394593 | instance | Saturday 12 September 2026 06:25:43 +0000 (0:00:00.042) 0:00:10.419 **** 2026-09-12 06:25:43.435287 | instance | skipping: [instance] 2026-09-12 06:25:43.435541 | instance | 2026-09-12 06:25:43.436061 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-12 06:25:43.436354 | instance | Saturday 12 September 2026 06:25:43 +0000 (0:00:00.041) 0:00:10.461 **** 2026-09-12 06:25:43.473574 | instance | skipping: [instance] 2026-09-12 06:25:43.473868 | instance | 2026-09-12 06:25:43.474171 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-12 06:25:43.474506 | instance | Saturday 12 September 2026 06:25:43 +0000 (0:00:00.038) 0:00:10.499 **** 2026-09-12 06:25:49.326339 | instance | changed: [instance] 2026-09-12 06:25:49.326548 | instance | 2026-09-12 06:25:49.327475 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-12 06:25:49.327544 | instance | Saturday 12 September 2026 06:25:49 +0000 (0:00:05.852) 0:00:16.351 **** 2026-09-12 06:25:50.037722 | instance | changed: [instance] 2026-09-12 06:25:50.037859 | instance | 2026-09-12 06:25:50.038336 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-12 06:25:50.038426 | instance | Saturday 12 September 2026 06:25:50 +0000 (0:00:00.711) 0:00:17.063 **** 2026-09-12 06:25:51.048764 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-12 06:25:51.048956 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-12 06:25:51.048970 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-12 06:25:51.048980 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-12 06:25:51.048990 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-12 06:25:51.048999 | instance | 2026-09-12 06:25:51.049032 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-12 06:25:51.049042 | instance | Saturday 12 September 2026 06:25:51 +0000 (0:00:01.009) 0:00:18.073 **** 2026-09-12 06:25:51.667678 | instance | changed: [instance] 2026-09-12 06:25:51.667777 | instance | 2026-09-12 06:25:51.668557 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-12 06:25:51.668603 | instance | Saturday 12 September 2026 06:25:51 +0000 (0:00:00.600) 0:00:18.674 **** 2026-09-12 06:25:51.668611 | instance | 2026-09-12 06:25:51.668617 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 06:25:51.668642 | instance | Saturday 12 September 2026 06:25:51 +0000 (0:00:00.019) 0:00:18.693 **** 2026-09-12 06:25:52.660025 | instance | ok: [instance] 2026-09-12 06:25:52.660725 | instance | 2026-09-12 06:25:52.660791 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-12 06:25:52.660910 | instance | Saturday 12 September 2026 06:25:52 +0000 (0:00:00.991) 0:00:19.685 **** 2026-09-12 06:25:53.176339 | instance | changed: [instance] 2026-09-12 06:25:53.176702 | instance | 2026-09-12 06:25:53.177387 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-12 06:25:53.177416 | instance | Saturday 12 September 2026 06:25:53 +0000 (0:00:00.516) 0:00:20.202 **** 2026-09-12 06:25:53.766008 | instance | changed: [instance] 2026-09-12 06:25:53.766104 | instance | 2026-09-12 06:25:53.766117 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-12 06:25:53.766128 | instance | Saturday 12 September 2026 06:25:53 +0000 (0:00:00.589) 0:00:20.791 **** 2026-09-12 06:25:53.986471 | instance | ok: [instance] 2026-09-12 06:25:53.986541 | instance | 2026-09-12 06:25:53.986567 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-12 06:25:53.986581 | instance | Saturday 12 September 2026 06:25:53 +0000 (0:00:00.220) 0:00:21.011 **** 2026-09-12 06:25:54.043058 | instance | ok: [instance] => { 2026-09-12 06:25:54.043105 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-12 06:25:54.043113 | instance | } 2026-09-12 06:25:54.043120 | instance | 2026-09-12 06:25:54.043282 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-12 06:25:54.043507 | instance | Saturday 12 September 2026 06:25:54 +0000 (0:00:00.056) 0:00:21.068 **** 2026-09-12 06:25:54.884754 | instance | changed: [instance] 2026-09-12 06:25:54.884810 | instance | 2026-09-12 06:25:54.884826 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-12 06:25:54.885083 | instance | Saturday 12 September 2026 06:25:54 +0000 (0:00:00.841) 0:00:21.909 **** 2026-09-12 06:26:00.270786 | instance | changed: [instance] 2026-09-12 06:26:00.270902 | instance | 2026-09-12 06:26:00.270939 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-12 06:26:00.271086 | instance | Saturday 12 September 2026 06:26:00 +0000 (0:00:05.387) 0:00:27.297 **** 2026-09-12 06:26:01.416747 | instance | ok: [instance] 2026-09-12 06:26:01.417687 | instance | 2026-09-12 06:26:01.417728 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-12 06:26:01.417745 | instance | Saturday 12 September 2026 06:26:01 +0000 (0:00:01.144) 0:00:28.442 **** 2026-09-12 06:26:01.833239 | instance | changed: [instance] 2026-09-12 06:26:01.833734 | instance | 2026-09-12 06:26:01.833759 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-12 06:26:01.833768 | instance | Saturday 12 September 2026 06:26:01 +0000 (0:00:00.417) 0:00:28.859 **** 2026-09-12 06:26:02.341323 | instance | changed: [instance] 2026-09-12 06:26:02.341402 | instance | 2026-09-12 06:26:02.341411 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-12 06:26:02.341419 | instance | Saturday 12 September 2026 06:26:02 +0000 (0:00:00.507) 0:00:29.366 **** 2026-09-12 06:26:03.007086 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-12 06:26:03.007157 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-12 06:26:03.007609 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-12 06:26:03.007658 | instance | 2026-09-12 06:26:03.007666 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-12 06:26:03.007673 | instance | Saturday 12 September 2026 06:26:03 +0000 (0:00:00.666) 0:00:30.033 **** 2026-09-12 06:26:03.525109 | instance | changed: [instance] 2026-09-12 06:26:03.525169 | instance | 2026-09-12 06:26:03.525393 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-12 06:26:03.525410 | instance | Saturday 12 September 2026 06:26:03 +0000 (0:00:00.518) 0:00:30.551 **** 2026-09-12 06:26:04.019165 | instance | changed: [instance] 2026-09-12 06:26:04.019225 | instance | 2026-09-12 06:26:04.019907 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-12 06:26:04.019924 | instance | Saturday 12 September 2026 06:26:03 +0000 (0:00:00.476) 0:00:31.028 **** 2026-09-12 06:26:04.019929 | instance | 2026-09-12 06:26:04.019933 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-12 06:26:04.019938 | instance | Saturday 12 September 2026 06:26:04 +0000 (0:00:00.017) 0:00:31.045 **** 2026-09-12 06:26:04.807434 | instance | ok: [instance] 2026-09-12 06:26:04.807480 | instance | 2026-09-12 06:26:04.807488 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-12 06:26:04.807503 | instance | Saturday 12 September 2026 06:26:04 +0000 (0:00:00.787) 0:00:31.832 **** 2026-09-12 06:26:06.011275 | instance | changed: [instance] 2026-09-12 06:26:06.011983 | instance | 2026-09-12 06:26:06.012040 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-12 06:26:06.012052 | instance | Saturday 12 September 2026 06:26:06 +0000 (0:00:01.204) 0:00:33.037 **** 2026-09-12 06:26:06.605857 | instance | changed: [instance] 2026-09-12 06:26:06.605933 | instance | 2026-09-12 06:26:06.606133 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-12 06:26:06.606166 | instance | Saturday 12 September 2026 06:26:06 +0000 (0:00:00.595) 0:00:33.632 **** 2026-09-12 06:26:06.670023 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-12 06:26:06.670100 | instance | 2026-09-12 06:26:06.670316 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-12 06:26:06.670351 | instance | Saturday 12 September 2026 06:26:06 +0000 (0:00:00.064) 0:00:33.696 **** 2026-09-12 06:26:11.842522 | instance | changed: [instance] 2026-09-12 06:26:11.843195 | instance | 2026-09-12 06:26:11.843220 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-12 06:26:11.843231 | instance | Saturday 12 September 2026 06:26:11 +0000 (0:00:05.172) 0:00:38.868 **** 2026-09-12 06:26:12.501839 | instance | ok: [instance] => (item=chronyd) 2026-09-12 06:26:12.502925 | instance | ok: [instance] => (item=sshd) 2026-09-12 06:26:12.502979 | instance | 2026-09-12 06:26:12.502992 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-12 06:26:12.503003 | instance | Saturday 12 September 2026 06:26:12 +0000 (0:00:00.658) 0:00:39.527 **** 2026-09-12 06:26:29.178478 | instance | changed: [instance] 2026-09-12 06:26:29.179695 | instance | 2026-09-12 06:26:29.179770 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-12 06:26:29.179789 | instance | Saturday 12 September 2026 06:26:29 +0000 (0:00:16.675) 0:00:56.203 **** 2026-09-12 06:26:29.413624 | instance | ok: [instance] 2026-09-12 06:26:29.414372 | instance | 2026-09-12 06:26:29.414580 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-12 06:26:29.414593 | instance | Saturday 12 September 2026 06:26:29 +0000 (0:00:00.235) 0:00:56.439 **** 2026-09-12 06:26:29.876676 | instance | changed: [instance] 2026-09-12 06:26:29.877426 | instance | 2026-09-12 06:26:29.877487 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-12 06:26:29.877499 | instance | Saturday 12 September 2026 06:26:29 +0000 (0:00:00.462) 0:00:56.902 **** 2026-09-12 06:26:30.216526 | instance | changed: [instance] 2026-09-12 06:26:30.217182 | instance | 2026-09-12 06:26:30.217207 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-12 06:26:30.217216 | instance | Saturday 12 September 2026 06:26:30 +0000 (0:00:00.340) 0:00:57.242 **** 2026-09-12 06:26:30.340491 | instance | ok: [instance] 2026-09-12 06:26:30.340559 | instance | 2026-09-12 06:26:30.340699 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-12 06:26:30.340777 | instance | Saturday 12 September 2026 06:26:30 +0000 (0:00:00.123) 0:00:57.366 **** 2026-09-12 06:26:30.559765 | instance | ok: [instance] => (item=instance) 2026-09-12 06:26:30.559800 | instance | 2026-09-12 06:26:30.559806 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-12 06:26:30.559810 | instance | Saturday 12 September 2026 06:26:30 +0000 (0:00:00.219) 0:00:57.585 **** 2026-09-12 06:26:30.624164 | instance | ok: [instance] 2026-09-12 06:26:30.624206 | instance | 2026-09-12 06:26:30.624213 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-12 06:26:30.624220 | instance | Saturday 12 September 2026 06:26:30 +0000 (0:00:00.063) 0:00:57.649 **** 2026-09-12 06:26:30.712261 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-12 06:26:30.712297 | instance | 2026-09-12 06:26:30.712303 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-12 06:26:30.712308 | instance | Saturday 12 September 2026 06:26:30 +0000 (0:00:00.088) 0:00:57.738 **** 2026-09-12 06:26:31.056446 | instance | changed: [instance] 2026-09-12 06:26:31.056788 | instance | 2026-09-12 06:26:31.057314 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-12 06:26:31.057370 | instance | Saturday 12 September 2026 06:26:31 +0000 (0:00:00.343) 0:00:58.081 **** 2026-09-12 06:26:31.807522 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-12 06:26:31.808750 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-12 06:26:31.808809 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-12 06:26:31.808822 | instance | 2026-09-12 06:26:31.808833 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-12 06:26:31.808843 | instance | Saturday 12 September 2026 06:26:31 +0000 (0:00:00.751) 0:00:58.833 **** 2026-09-12 06:28:06.566307 | instance | ok: [instance] 2026-09-12 06:28:06.566385 | instance | 2026-09-12 06:28:06.566789 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-12 06:28:06.566856 | instance | Saturday 12 September 2026 06:28:06 +0000 (0:01:34.759) 0:02:33.592 **** 2026-09-12 06:28:06.867056 | instance | changed: [instance] 2026-09-12 06:28:06.867235 | instance | 2026-09-12 06:28:06.867420 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-12 06:28:06.867590 | instance | Saturday 12 September 2026 06:28:06 +0000 (0:00:00.300) 0:02:33.892 **** 2026-09-12 06:28:06.912089 | instance | ok: [instance] 2026-09-12 06:28:06.912175 | instance | 2026-09-12 06:28:06.912347 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-12 06:28:06.912532 | instance | Saturday 12 September 2026 06:28:06 +0000 (0:00:00.045) 0:02:33.938 **** 2026-09-12 06:28:06.990377 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-12 06:28:06.990428 | instance | 2026-09-12 06:28:06.990555 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-12 06:28:06.990693 | instance | Saturday 12 September 2026 06:28:06 +0000 (0:00:00.078) 0:02:34.016 **** 2026-09-12 06:28:09.177248 | instance | ok: [instance] 2026-09-12 06:28:09.177568 | instance | 2026-09-12 06:28:09.177925 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-12 06:28:09.178231 | instance | Saturday 12 September 2026 06:28:09 +0000 (0:00:02.185) 0:02:36.202 **** 2026-09-12 06:28:09.248507 | instance | ok: [instance] => (item=instance) 2026-09-12 06:28:09.248753 | instance | 2026-09-12 06:28:09.249038 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-12 06:28:09.249298 | instance | Saturday 12 September 2026 06:28:09 +0000 (0:00:00.071) 0:02:36.274 **** 2026-09-12 06:28:09.638790 | instance | ok: [instance] 2026-09-12 06:28:09.638936 | instance | 2026-09-12 06:28:09.639083 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-12 06:28:09.639233 | instance | Saturday 12 September 2026 06:28:09 +0000 (0:00:00.391) 0:02:36.665 **** 2026-09-12 06:28:12.077251 | instance | ok: [instance] 2026-09-12 06:28:12.077341 | instance | 2026-09-12 06:28:12.077683 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-12 06:28:12.077730 | instance | Saturday 12 September 2026 06:28:12 +0000 (0:00:02.437) 0:02:39.103 **** 2026-09-12 06:29:01.344385 | instance | An exception occurred during task execution. To see the full traceback, use -vvv. The error was: [errno 5] RADOS I/O error (error connecting to the cluster) 2026-09-12 06:29:01.345515 | 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_opiec9sh/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_opiec9sh/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_opiec9sh/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_opiec9sh/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-12T06:29:01.155+0000 7f7897993640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDK8KRqSzy0DCAA903kymmXs7fSsPHGBkYl4Hm7c7IkXUyy5K6c+GdEL2E=: Malformed input [buffer:3]\n2026-09-12T06:29:01.155+0000 7f7897993640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T06:29:01.159+0000 7f7897993640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDK8KRqSzy0DCAA903kymmXs7fSsPHGBkYl4Hm7c7IkXUyy5K6c+GdEL2E=: Malformed input [buffer:3]\n2026-09-12T06:29:01.159+0000 7f7897993640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T06:29:01.159+0000 7f7897993640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgDK8KRqSzy0DCAA903kymmXs7fSsPHGBkYl4Hm7c7IkXUyy5K6c+GdEL2E=: Malformed input [buffer:3]\n2026-09-12T06:29:01.159+0000 7f7897993640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-12T06:29:01.159+0000 7f7897993640 -1 monclient: keyring not found\n[errno 5] RADOS I/O error (error connecting to the cluster)\n\n", "module_stdout": "", "msg": "MODULE FAILURE\nSee stdout/stderr for the exact error", "rc": 1} 2026-09-12 06:29:01.348577 | instance | 2026-09-12 06:29:01.352782 | instance | PLAY RECAP ********************************************************************* 2026-09-12 06:29:01.352846 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=6 rescued=0 ignored=0 2026-09-12 06:29:01.352859 | instance | 2026-09-12 06:29:01.352869 | instance | Saturday 12 September 2026 06:29:01 +0000 (0:00:49.271) 0:03:28.375 **** 2026-09-12 06:29:01.352880 | instance | =============================================================================== 2026-09-12 06:29:01.352890 | instance | vexxhost.ceph.mon : Run Bootstrap command ------------------------------ 94.76s 2026-09-12 06:29:01.352899 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 49.27s 2026-09-12 06:29:01.352909 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 16.68s 2026-09-12 06:29:01.352918 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.85s 2026-09-12 06:29:01.352928 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 5.39s 2026-09-12 06:29:01.352937 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.17s 2026-09-12 06:29:01.352947 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.96s 2026-09-12 06:29:01.352956 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.44s 2026-09-12 06:29:01.353010 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 2.19s 2026-09-12 06:29:01.353020 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.78s 2026-09-12 06:29:01.353030 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.41s 2026-09-12 06:29:01.353040 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.20s 2026-09-12 06:29:01.353056 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.15s 2026-09-12 06:29:01.353110 | instance | Gathering Facts --------------------------------------------------------- 1.09s 2026-09-12 06:29:01.353358 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-12 06:29:01.353692 | instance | Gathering Facts --------------------------------------------------------- 0.95s 2026-09-12 06:29:01.354006 | instance | Gathering Facts --------------------------------------------------------- 0.92s 2026-09-12 06:29:01.354301 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.84s 2026-09-12 06:29:01.354596 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.83s 2026-09-12 06:29:01.354885 | instance | vexxhost.ceph.mon : Include extra configuration values ------------------ 0.75s 2026-09-12 06:29:01.462097 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 06:29:01.462435 | instance | ERROR [csi > converge] Executed: Failed 2026-09-12 06:29:01.462643 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.csi/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-12 06:29:01.582130 | instance | ERROR 2026-09-12 06:29:01.582394 | instance | { 2026-09-12 06:29:01.582501 | instance | "delta": "0:04:21.002909", 2026-09-12 06:29:01.582546 | instance | "end": "2026-09-12 06:29:01.535121", 2026-09-12 06:29:01.582582 | instance | "msg": "non-zero return code", 2026-09-12 06:29:01.582617 | instance | "rc": 2, 2026-09-12 06:29:01.582653 | instance | "start": "2026-09-12 06:24:40.532212" 2026-09-12 06:29:01.582688 | instance | } failure 2026-09-12 06:29:01.592311 | 2026-09-12 06:29:01.592474 | PLAY RECAP 2026-09-12 06:29:01.592607 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-12 06:29:01.592654 | 2026-09-12 06:29:01.734517 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-12 06:29:01.738403 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 06:29:02.370761 | 2026-09-12 06:29:02.370928 | PLAY [all] 2026-09-12 06:29:02.386363 | 2026-09-12 06:29:02.386508 | TASK [gather-host-logs : creating directory for system status] 2026-09-12 06:29:02.753133 | instance | changed 2026-09-12 06:29:02.760082 | 2026-09-12 06:29:02.760174 | TASK [gather-host-logs : Get logs for each host] 2026-09-12 06:29:03.127563 | instance | + systemd-cgls --full --all --no-pager 2026-09-12 06:29:03.149572 | instance | + ip addr 2026-09-12 06:29:03.152197 | instance | + ip route 2026-09-12 06:29:03.154693 | instance | + lsblk 2026-09-12 06:29:03.159833 | instance | + mount 2026-09-12 06:29:03.162269 | instance | + docker images 2026-09-12 06:29:03.186555 | instance | + brctl show 2026-09-12 06:29:03.187042 | instance | /bin/bash: line 8: brctl: command not found 2026-09-12 06:29:03.187382 | instance | + ip neigh 2026-09-12 06:29:03.189448 | instance | + bridge fdb show 2026-09-12 06:29:03.193775 | instance | + ps aux --sort=-%mem 2026-09-12 06:29:03.229867 | instance | + dpkg -l 2026-09-12 06:29:03.241107 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-12 06:29:03.241636 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-12 06:29:03.261706 | instance | + '[' '!' -z '' ']' 2026-09-12 06:29:03.315961 | instance | ok: Runtime: 0:00:00.139201 2026-09-12 06:29:03.327804 | 2026-09-12 06:29:03.327971 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-12 06:29:04.026609 | instance | changed: 2026-09-12 06:29:04.026850 | instance | created directory /var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/logs/instance 2026-09-12 06:29:04.026906 | instance | cd+++++++++ system/ 2026-09-12 06:29:04.026947 | instance | >f+++++++++ system/brctl-show.txt 2026-09-12 06:29:04.026986 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-12 06:29:04.027023 | instance | >f+++++++++ system/docker-images.txt 2026-09-12 06:29:04.027067 | instance | >f+++++++++ system/ip-addr.txt 2026-09-12 06:29:04.027107 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-12 06:29:04.027144 | instance | >f+++++++++ system/ip-route.txt 2026-09-12 06:29:04.027185 | instance | >f+++++++++ system/lsblk.txt 2026-09-12 06:29:04.027222 | instance | >f+++++++++ system/mount.txt 2026-09-12 06:29:04.027259 | instance | >f+++++++++ system/packages.txt 2026-09-12 06:29:04.027294 | instance | >f+++++++++ system/ps.txt 2026-09-12 06:29:04.027328 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-12 06:29:04.044298 | 2026-09-12 06:29:04.044463 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-12 06:29:04.262420 | instance | changed: "values" 2026-09-12 06:29:04.449612 | instance | changed: "releases" 2026-09-12 06:29:04.457593 | 2026-09-12 06:29:04.457733 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-12 06:29:04.731758 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-12 06:29:05.017061 | instance | ok: Runtime: 0:00:00.005510 2026-09-12 06:29:05.021322 | 2026-09-12 06:29:05.021385 | TASK [helm-release-status : Downloads logs to executor] 2026-09-12 06:29:05.490312 | instance | changed: 2026-09-12 06:29:05.490562 | instance | cd+++++++++ helm/ 2026-09-12 06:29:05.490618 | instance | cd+++++++++ helm/releases/ 2026-09-12 06:29:05.490659 | instance | cd+++++++++ helm/values/ 2026-09-12 06:29:05.507146 | 2026-09-12 06:29:05.507264 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-12 06:29:05.722024 | instance | changed 2026-09-12 06:29:05.732293 | 2026-09-12 06:29:05.732424 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-12 06:29:06.012337 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 06:29:06.012451 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 06:29:06.017606 | instance | environment: line 1: kubectl: command not found 2026-09-12 06:29:06.018263 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 06:29:06.021514 | instance | environment: line 1: kubectl: command not found 2026-09-12 06:29:06.021638 | instance | environment: line 1: kubectl: command not found 2026-09-12 06:29:06.022273 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 06:29:06.023029 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 06:29:06.023989 | instance | environment: line 1: kubectl: command not found 2026-09-12 06:29:06.024857 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 06:29:06.025226 | instance | environment: line 1: kubectl: command not found 2026-09-12 06:29:06.025841 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-12 06:29:06.292323 | instance | ok: Runtime: 0:00:00.020918 2026-09-12 06:29:06.301998 | 2026-09-12 06:29:06.302145 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-12 06:29:06.528745 | instance | changed 2026-09-12 06:29:06.538483 | 2026-09-12 06:29:06.538633 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-12 06:29:06.797573 | instance | environment: line 5: kubectl: command not found 2026-09-12 06:29:06.798500 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 06:29:06.798552 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 06:29:06.798840 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-12 06:29:07.087700 | instance | ok: Runtime: 0:00:00.007072 2026-09-12 06:29:07.099519 | 2026-09-12 06:29:07.099869 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-12 06:29:07.636553 | instance | changed: 2026-09-12 06:29:07.636865 | instance | cd+++++++++ objects/ 2026-09-12 06:29:07.636949 | instance | cd+++++++++ objects/cluster/ 2026-09-12 06:29:07.637020 | instance | cd+++++++++ objects/namespaced/ 2026-09-12 06:29:07.657269 | 2026-09-12 06:29:07.657479 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-12 06:29:07.894253 | instance | changed 2026-09-12 06:29:07.903639 | 2026-09-12 06:29:07.903763 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-12 06:29:08.122997 | instance | changed 2026-09-12 06:29:08.132169 | 2026-09-12 06:29:08.132317 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-12 06:29:08.381832 | instance | environment: line 3: kubectl: command not found 2026-09-12 06:29:08.670300 | instance | ok: Runtime: 0:00:00.008966 2026-09-12 06:29:08.678162 | 2026-09-12 06:29:08.678229 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-12 06:29:09.165707 | instance | changed: 2026-09-12 06:29:09.165966 | instance | cd+++++++++ pod-logs/ 2026-09-12 06:29:09.166023 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-12 06:29:09.185002 | 2026-09-12 06:29:09.185133 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-12 06:29:09.411188 | instance | changed 2026-09-12 06:29:09.421194 | 2026-09-12 06:29:09.421342 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-12 06:29:09.691383 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 06:29:09.970287 | instance | ok: Runtime: 0:00:00.052824 2026-09-12 06:29:09.976319 | 2026-09-12 06:29:09.976415 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-12 06:29:10.213388 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-12 06:29:10.261377 | instance | ceph-mgr endpoints: 2026-09-12 06:29:10.514469 | instance | ok: Runtime: 0:00:00.054210 2026-09-12 06:29:10.519720 | 2026-09-12 06:29:10.519802 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-12 06:29:10.754640 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-12 06:29:11.050699 | instance | ok: Runtime: 0:00:00.050431 2026-09-12 06:29:11.057703 | 2026-09-12 06:29:11.057804 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-12 06:29:11.578114 | instance | changed: cd+++++++++ prometheus/ 2026-09-12 06:29:11.594858 | 2026-09-12 06:29:11.594988 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-12 06:29:11.800466 | instance | changed 2026-09-12 06:29:11.807747 | 2026-09-12 06:29:11.807899 | TASK [gather-selenium-data : Get selenium data] 2026-09-12 06:29:12.086914 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-12 06:29:12.088451 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-12 06:29:12.348386 | instance | ERROR 2026-09-12 06:29:12.348694 | instance | { 2026-09-12 06:29:12.348754 | instance | "delta": "0:00:00.006088", 2026-09-12 06:29:12.348795 | instance | "end": "2026-09-12 06:29:12.088818", 2026-09-12 06:29:12.348832 | instance | "msg": "non-zero return code", 2026-09-12 06:29:12.348867 | instance | "rc": 1, 2026-09-12 06:29:12.348902 | instance | "start": "2026-09-12 06:29:12.082730" 2026-09-12 06:29:12.348936 | instance | } 2026-09-12 06:29:12.348981 | instance | ERROR: Ignoring Errors 2026-09-12 06:29:12.357840 | 2026-09-12 06:29:12.357960 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-12 06:29:12.956990 | instance | changed: cd+++++++++ selenium/ 2026-09-12 06:29:12.967165 | 2026-09-12 06:29:12.967259 | PLAY RECAP 2026-09-12 06:29:12.967340 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-12 06:29:12.967382 | 2026-09-12 06:29:13.113881 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-12 06:29:13.118092 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 06:29:13.699660 | 2026-09-12 06:29:13.699795 | PLAY [all] 2026-09-12 06:29:13.710920 | 2026-09-12 06:29:13.711027 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-12 06:29:13.765238 | instance | skipping: Conditional result was False 2026-09-12 06:29:13.772140 | 2026-09-12 06:29:13.772249 | TASK [fetch-output : Set log path for single node] 2026-09-12 06:29:13.812636 | instance | ok 2026-09-12 06:29:13.820526 | 2026-09-12 06:29:13.820636 | LOOP [fetch-output : Ensure local output dirs] 2026-09-12 06:29:14.337571 | instance -> localhost | ok: "/var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/logs" 2026-09-12 06:29:14.580006 | instance -> localhost | changed: "/var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/artifacts" 2026-09-12 06:29:14.800009 | instance -> localhost | changed: "/var/lib/zuul/builds/581aa1f2253d42b9876c8cec3a83c3e0/work/docs" 2026-09-12 06:29:14.822627 | 2026-09-12 06:29:14.822845 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-12 06:29:15.517057 | instance | changed: .d..t...... ./ 2026-09-12 06:29:15.517354 | instance | changed: All items complete 2026-09-12 06:29:15.517409 | 2026-09-12 06:29:16.018671 | instance | changed: .d..t...... ./ 2026-09-12 06:29:16.503959 | instance | changed: .d..t...... ./ 2026-09-12 06:29:16.528954 | 2026-09-12 06:29:16.529132 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-12 06:29:16.953478 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007165 2026-09-12 06:29:17.221924 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006942 2026-09-12 06:29:17.236654 | 2026-09-12 06:29:17.236827 | PLAY [all] 2026-09-12 06:29:17.249608 | 2026-09-12 06:29:17.249736 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-12 06:29:17.698830 | instance | changed 2026-09-12 06:29:17.709763 | 2026-09-12 06:29:17.709888 | PLAY RECAP 2026-09-12 06:29:17.709970 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-12 06:29:17.710012 | 2026-09-12 06:29:17.869106 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-12 06:29:17.873223 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-12 06:29:18.425327 | 2026-09-12 06:29:18.425475 | PLAY [localhost] 2026-09-12 06:29:18.434411 | 2026-09-12 06:29:18.434503 | TASK [Generate Zuul manifest] 2026-09-12 06:29:18.450142 | localhost | ok 2026-09-12 06:29:18.465344 | 2026-09-12 06:29:18.465496 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-12 06:29:18.846650 | localhost | changed 2026-09-12 06:29:18.855498 | 2026-09-12 06:29:18.855604 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-12 06:29:18.885873 | localhost | ok 2026-09-12 06:29:18.898696 | 2026-09-12 06:29:18.898858 | TASK [Upload logs] 2026-09-12 06:29:18.920335 | localhost | ok 2026-09-12 06:29:18.992128 | 2026-09-12 06:29:18.992251 | TASK [Set zuul-log-path fact] 2026-09-12 06:29:19.007838 | localhost | ok 2026-09-12 06:29:19.017605 | 2026-09-12 06:29:19.017707 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-12 06:29:19.044394 | localhost | ok 2026-09-12 06:29:19.052944 | 2026-09-12 06:29:19.053036 | TASK [upload-logs : Create log directories] 2026-09-12 06:29:19.446961 | localhost | changed 2026-09-12 06:29:19.455667 | 2026-09-12 06:29:19.455794 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-12 06:29:19.840756 | localhost -> localhost | ok: Runtime: 0:00:00.003682 2026-09-12 06:29:19.849122 | 2026-09-12 06:29:19.849271 | TASK [upload-logs : Upload logs to log server] 2026-09-12 06:29:20.304100 | localhost | Output suppressed because no_log was given 2026-09-12 06:29:20.339031 | 2026-09-12 06:29:20.339153 | LOOP [upload-logs : Compress console log and json output] 2026-09-12 06:29:20.405294 | localhost | skipping: Conditional result was False 2026-09-12 06:29:20.417964 | localhost | skipping: Conditional result was False 2026-09-12 06:29:20.430659 | 2026-09-12 06:29:20.430821 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-12 06:29:20.467221 | localhost | skipping: Conditional result was False 2026-09-12 06:29:20.467754 | 2026-09-12 06:29:20.472117 | localhost | skipping: Conditional result was False 2026-09-12 06:29:20.484206 | 2026-09-12 06:29:20.484451 | LOOP [upload-logs : Upload console log and json output]