2026-09-14 00:47:52.343625 | Job console starting 2026-09-14 00:47:52.376689 | Updating git repos 2026-09-14 00:47:53.909146 | Cloning repos into workspace 2026-09-14 00:48:09.396316 | Restoring repo states 2026-09-14 00:48:20.913145 | Merging changes 2026-09-14 00:48:22.267025 | Checking out repos 2026-09-14 00:48:24.125347 | Preparing playbooks 2026-09-14 00:48:26.010885 | Running Ansible setup 2026-09-14 00:48:29.464064 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-14 00:48:30.020798 | 2026-09-14 00:48:30.060322 | PLAY [localhost] 2026-09-14 00:48:30.067975 | 2026-09-14 00:48:30.068069 | TASK [Gathering Facts] 2026-09-14 00:48:30.936851 | localhost | ok 2026-09-14 00:48:30.950126 | 2026-09-14 00:48:30.950301 | TASK [Setup log path fact] 2026-09-14 00:48:30.971895 | localhost | ok 2026-09-14 00:48:30.991697 | 2026-09-14 00:48:30.991842 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-14 00:48:31.032802 | localhost | ok 2026-09-14 00:48:31.043976 | 2026-09-14 00:48:31.044115 | TASK [emit-job-header : Print job information] 2026-09-14 00:48:31.086005 | # Job Information 2026-09-14 00:48:31.086233 | Ansible Version: 2.16.18 2026-09-14 00:48:31.086301 | Job: atmosphere-molecule-csi-rbd 2026-09-14 00:48:31.086345 | Pipeline: check 2026-09-14 00:48:31.086384 | Executor: 2d72f0692154 2026-09-14 00:48:31.086423 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-09-14 00:48:31.086470 | Event ID: 541197d0-afd5-11f1-87ca-7b66a1c3b4ff 2026-09-14 00:48:31.092653 | 2026-09-14 00:48:31.092788 | LOOP [emit-job-header : Print node information] 2026-09-14 00:48:31.198448 | localhost | ok: 2026-09-14 00:48:31.198715 | localhost | # Node Information 2026-09-14 00:48:31.198771 | localhost | Inventory Hostname: instance 2026-09-14 00:48:31.198813 | localhost | Hostname: np0000192732 2026-09-14 00:48:31.198853 | localhost | Username: zuul 2026-09-14 00:48:31.198896 | localhost | Distro: Ubuntu 22.04 2026-09-14 00:48:31.198937 | localhost | Provider: yul1 2026-09-14 00:48:31.198974 | localhost | Region: ca-ymq-1 2026-09-14 00:48:31.199011 | localhost | Label: ubuntu-jammy 2026-09-14 00:48:31.199047 | localhost | Product Name: OpenStack Nova 2026-09-14 00:48:31.199083 | localhost | Interface IP: 162.253.55.31 2026-09-14 00:48:31.211390 | 2026-09-14 00:48:31.211606 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-14 00:48:31.662776 | localhost -> localhost | changed 2026-09-14 00:48:31.669086 | 2026-09-14 00:48:31.669179 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-14 00:48:32.590864 | localhost -> localhost | changed 2026-09-14 00:48:32.604595 | 2026-09-14 00:48:32.604793 | PLAY [all] 2026-09-14 00:48:32.621411 | 2026-09-14 00:48:32.621545 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-14 00:48:32.879123 | instance -> localhost | ok 2026-09-14 00:48:32.891158 | 2026-09-14 00:48:32.891311 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-14 00:48:32.924975 | instance | ok 2026-09-14 00:48:32.949945 | instance | included: /var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-14 00:48:32.956773 | 2026-09-14 00:48:32.956854 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-14 00:48:33.723780 | instance -> localhost | Generating public/private rsa key pair. 2026-09-14 00:48:33.724076 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/3f433a05df72492284ff75a339544d6b_id_rsa 2026-09-14 00:48:33.724136 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/3f433a05df72492284ff75a339544d6b_id_rsa.pub 2026-09-14 00:48:33.724181 | instance -> localhost | The key fingerprint is: 2026-09-14 00:48:33.724247 | instance -> localhost | SHA256:P5716VNy/vifUYZEj5O0lQX5MDs7GbcCIGNUDPdnDDU zuul-build-sshkey 2026-09-14 00:48:33.724313 | instance -> localhost | The key's randomart image is: 2026-09-14 00:48:33.724373 | instance -> localhost | +---[RSA 3072]----+ 2026-09-14 00:48:33.724428 | instance -> localhost | | .o+o ..E+o=| 2026-09-14 00:48:33.724471 | instance -> localhost | | +.o. oo=B | 2026-09-14 00:48:33.724551 | instance -> localhost | | . o .. +*=.| 2026-09-14 00:48:33.724595 | instance -> localhost | | .o.+oo| 2026-09-14 00:48:33.724635 | instance -> localhost | | S . .*+| 2026-09-14 00:48:33.724675 | instance -> localhost | | . .=o+| 2026-09-14 00:48:33.724713 | instance -> localhost | | o . .B | 2026-09-14 00:48:33.724753 | instance -> localhost | | . + ..o+| 2026-09-14 00:48:33.724799 | instance -> localhost | | o .=+*| 2026-09-14 00:48:33.724838 | instance -> localhost | +----[SHA256]-----+ 2026-09-14 00:48:33.724930 | instance -> localhost | ok: Runtime: 0:00:00.345743 2026-09-14 00:48:33.744386 | 2026-09-14 00:48:33.744580 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-14 00:48:33.768662 | instance | ok 2026-09-14 00:48:33.784339 | instance | included: /var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-14 00:48:33.794419 | 2026-09-14 00:48:33.794493 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-14 00:48:33.809433 | instance | skipping: Conditional result was False 2026-09-14 00:48:33.819511 | 2026-09-14 00:48:33.819644 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-14 00:48:34.270548 | instance | changed 2026-09-14 00:48:34.281826 | 2026-09-14 00:48:34.281951 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-14 00:48:34.496413 | instance | ok 2026-09-14 00:48:34.508013 | 2026-09-14 00:48:34.508131 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-14 00:48:35.000563 | instance | changed 2026-09-14 00:48:35.007211 | 2026-09-14 00:48:35.007291 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-14 00:48:35.477621 | instance | changed 2026-09-14 00:48:35.488687 | 2026-09-14 00:48:35.488800 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-14 00:48:35.513969 | instance | skipping: Conditional result was False 2026-09-14 00:48:35.520835 | 2026-09-14 00:48:35.520950 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-14 00:48:35.903475 | instance -> localhost | changed 2026-09-14 00:48:35.925605 | 2026-09-14 00:48:35.925734 | TASK [add-build-sshkey : Add back temp key] 2026-09-14 00:48:36.277219 | instance -> localhost | Identity added: /var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/3f433a05df72492284ff75a339544d6b_id_rsa (zuul-build-sshkey) 2026-09-14 00:48:36.277526 | instance -> localhost | ok: Runtime: 0:00:00.015616 2026-09-14 00:48:36.290454 | 2026-09-14 00:48:36.290701 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-14 00:48:36.609252 | instance | ok 2026-09-14 00:48:36.613292 | 2026-09-14 00:48:36.613353 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-14 00:48:36.647403 | instance | skipping: Conditional result was False 2026-09-14 00:48:36.656192 | 2026-09-14 00:48:36.656249 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-09-14 00:48:37.011319 | instance | ok 2026-09-14 00:48:37.027271 | 2026-09-14 00:48:37.027400 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-09-14 00:48:40.571366 | instance | Output suppressed because no_log was given 2026-09-14 00:48:40.590695 | 2026-09-14 00:48:40.590814 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-09-14 00:48:40.828756 | instance | ok: "logs" 2026-09-14 00:48:40.830041 | instance | ok: All items complete 2026-09-14 00:48:40.830124 | 2026-09-14 00:48:40.988957 | instance | ok: "artifacts" 2026-09-14 00:48:41.146667 | instance | ok: "docs" 2026-09-14 00:48:41.164781 | 2026-09-14 00:48:41.165087 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-09-14 00:48:41.358317 | instance | changed: "logs" 2026-09-14 00:48:41.514124 | instance | changed: "artifacts" 2026-09-14 00:48:41.684270 | instance | changed: "docs" 2026-09-14 00:48:41.699639 | 2026-09-14 00:48:41.699721 | PLAY RECAP 2026-09-14 00:48:41.699783 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-14 00:48:41.699823 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-14 00:48:41.699854 | 2026-09-14 00:48:41.822979 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-09-14 00:48:41.824750 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-14 00:48:42.370907 | 2026-09-14 00:48:42.371019 | PLAY [all] 2026-09-14 00:48:42.380724 | 2026-09-14 00:48:42.380795 | TASK [setup-uv : Extract archive] 2026-09-14 00:48:44.723643 | instance | changed 2026-09-14 00:48:44.736359 | 2026-09-14 00:48:44.736555 | TASK [setup-uv : Print version] 2026-09-14 00:48:45.104637 | instance | uv 0.8.13 2026-09-14 00:48:45.283540 | instance | ok: Runtime: 0:00:00.012531 2026-09-14 00:48:45.300595 | 2026-09-14 00:48:45.300733 | TASK [Install Ansible collection siblings] 2026-09-14 00:48:54.914841 | instance | changed: 2026-09-14 00:48:54.915090 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-09-14 00:48:54.915171 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-09-14 00:48:54.915215 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-09-14 00:48:54.915254 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-09-14 00:48:54.915293 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-09-14 00:48:54.915338 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-09-14 00:48:54.915379 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-09-14 00:48:54.915420 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-09-14 00:48:54.915458 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-09-14 00:48:54.915495 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-09-14 00:48:54.915532 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-09-14 00:48:54.915568 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-09-14 00:48:54.915604 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-09-14 00:48:54.915640 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-09-14 00:48:54.915675 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0 2026-09-14 00:48:54.915712 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-09-14 00:48:54.915747 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-09-14 00:48:54.915784 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-09-14 00:48:54.915820 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-09-14 00:48:54.915855 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-09-14 00:48:54.915891 | instance | Resolved vexxhost.ceph to v4.2.0 for >=3.2.0 2026-09-14 00:48:54.915926 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-09-14 00:48:54.915964 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-14 00:48:54.916018 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-09-14 00:48:54.916055 | instance | Resolved ansible.utils to v6.1.0 for >=2.9.0,>=6.0.0 2026-09-14 00:48:54.916090 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-14 00:48:54.916143 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-14 00:48:54.916180 | instance | Resolved vexxhost.kubernetes to v3.4.0 for >=3.3.0,>=2.5.0 2026-09-14 00:48:54.916215 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-09-14 00:48:54.916251 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-09-14 00:48:54.916286 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-09-14 00:48:54.916322 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-09-14 00:48:54.916357 | instance | Checked out ansible.netcommon to 1.2.0 2026-09-14 00:48:54.916392 | 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-14 00:48:54.916438 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.916473 | instance | Process install dependency map 2026-09-14 00:48:54.916536 | instance | Starting collection install process 2026-09-14 00:48:54.916575 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-09-14 00:48:54.916611 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-09-14 00:48:54.916646 | instance | ansible.netcommon:* was installed successfully 2026-09-14 00:48:54.916682 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-09-14 00:48:54.916717 | instance | Creating virtual environment at: .venv 2026-09-14 00:48:54.916753 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-14 00:48:54.916788 | instance | Downloading cryptography (4.2MiB) 2026-09-14 00:48:54.916823 | instance | Downloading netaddr (2.2MiB) 2026-09-14 00:48:54.916861 | instance | Downloading ansible-core (2.1MiB) 2026-09-14 00:48:54.916897 | instance | Downloading pydantic-core (2.0MiB) 2026-09-14 00:48:54.916933 | instance | Downloading kubernetes (1.9MiB) 2026-09-14 00:48:54.916969 | instance | Downloading openstacksdk (1.7MiB) 2026-09-14 00:48:54.917006 | instance | Downloading pygments (1.2MiB) 2026-09-14 00:48:54.917041 | instance | Downloading setuptools (1.1MiB) 2026-09-14 00:48:54.917077 | instance | Downloading rjsonnet (1.2MiB) 2026-09-14 00:48:54.917112 | instance | Downloading rjsonnet 2026-09-14 00:48:54.917174 | instance | Building pyperclip==1.9.0 2026-09-14 00:48:54.917211 | instance | Downloading pydantic-core 2026-09-14 00:48:54.917247 | instance | Downloading netaddr 2026-09-14 00:48:54.917283 | instance | Downloading pygments 2026-09-14 00:48:54.917319 | instance | Downloading cryptography 2026-09-14 00:48:54.917355 | instance | Downloading setuptools 2026-09-14 00:48:54.917389 | instance | Downloading kubernetes 2026-09-14 00:48:54.917424 | instance | Downloading ansible-core 2026-09-14 00:48:54.917461 | instance | Downloading openstacksdk 2026-09-14 00:48:54.917497 | instance | Built pyperclip==1.9.0 2026-09-14 00:48:54.917533 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-09-14 00:48:54.917569 | instance | Installed 83 packages in 147ms 2026-09-14 00:48:54.917604 | instance | Checked out ansible.posix to 1.6.0 2026-09-14 00:48:54.917640 | 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-14 00:48:54.917676 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.917711 | instance | Process install dependency map 2026-09-14 00:48:54.917756 | instance | Starting collection install process 2026-09-14 00:48:54.917792 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-09-14 00:48:54.917828 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-09-14 00:48:54.917863 | instance | ansible.posix:1.6.0 was installed successfully 2026-09-14 00:48:54.917899 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.917935 | instance | 2.17.12 2026-09-14 00:48:54.917970 | instance | Checked out ansible.utils to v6.1.0 2026-09-14 00:48:54.918006 | 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-14 00:48:54.918041 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.918076 | instance | Process install dependency map 2026-09-14 00:48:54.918110 | instance | Starting collection install process 2026-09-14 00:48:54.918164 | instance | Installing 'ansible.utils:6.1.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-09-14 00:48:54.918202 | instance | Created collection for ansible.utils:6.1.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-09-14 00:48:54.918238 | instance | ansible.utils:6.1.0 was installed successfully 2026-09-14 00:48:54.918274 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.918310 | instance | 2.17.12 2026-09-14 00:48:54.918346 | instance | Checked out atmosphere.common to v0.7.0 2026-09-14 00:48:54.918382 | 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-14 00:48:54.918417 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.918451 | instance | Process install dependency map 2026-09-14 00:48:54.918486 | instance | Starting collection install process 2026-09-14 00:48:54.918521 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-09-14 00:48:54.918557 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-09-14 00:48:54.918592 | instance | atmosphere.common:0.7.0 was installed successfully 2026-09-14 00:48:54.918627 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.918662 | instance | 2.17.12 2026-09-14 00:48:54.918696 | instance | Checked out community.crypto to 2.2.3 2026-09-14 00:48:54.918731 | 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-14 00:48:54.918766 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.918802 | instance | Process install dependency map 2026-09-14 00:48:54.918836 | instance | Starting collection install process 2026-09-14 00:48:54.918871 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-09-14 00:48:54.918906 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-09-14 00:48:54.918941 | instance | community.crypto:2.2.3 was installed successfully 2026-09-14 00:48:54.918977 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.919011 | instance | 2.17.12 2026-09-14 00:48:54.919046 | instance | Checked out community.general to 7.3.0 2026-09-14 00:48:54.919080 | 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-14 00:48:54.919142 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.919181 | instance | Process install dependency map 2026-09-14 00:48:54.919217 | instance | Starting collection install process 2026-09-14 00:48:54.919252 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-09-14 00:48:54.919287 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-09-14 00:48:54.919323 | instance | community.general:7.3.0 was installed successfully 2026-09-14 00:48:54.919360 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.919397 | instance | 2.17.12 2026-09-14 00:48:54.919432 | instance | Checked out community.mysql to 3.6.0 2026-09-14 00:48:54.919472 | 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-14 00:48:54.919509 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.919545 | instance | Process install dependency map 2026-09-14 00:48:54.919580 | instance | Starting collection install process 2026-09-14 00:48:54.919616 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-09-14 00:48:54.919651 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-09-14 00:48:54.919687 | instance | community.mysql:3.6.0 was installed successfully 2026-09-14 00:48:54.919722 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.919757 | instance | 2.17.12 2026-09-14 00:48:54.919793 | instance | Checked out kubernetes.core to 2.4.0 2026-09-14 00:48:54.919830 | 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-14 00:48:54.919866 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.919901 | instance | Process install dependency map 2026-09-14 00:48:54.919937 | instance | Starting collection install process 2026-09-14 00:48:54.919973 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-09-14 00:48:54.920008 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-09-14 00:48:54.920044 | instance | kubernetes.core:2.4.0 was installed successfully 2026-09-14 00:48:54.920079 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.920114 | instance | 2.17.12 2026-09-14 00:48:54.920169 | instance | Checked out openstack.cloud to 2.6.0 2026-09-14 00:48:54.920205 | 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-14 00:48:54.920240 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.920276 | instance | Process install dependency map 2026-09-14 00:48:54.920311 | instance | Starting collection install process 2026-09-14 00:48:54.920346 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-09-14 00:48:54.920381 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-09-14 00:48:54.920416 | instance | openstack.cloud:2.6.0 was installed successfully 2026-09-14 00:48:54.920450 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.920486 | instance | 2.17.12 2026-09-14 00:48:54.920558 | instance | Checked out vexxhost.ceph to v4.2.0 2026-09-14 00:48:54.920596 | 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-14 00:48:54.920632 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.920667 | instance | Process install dependency map 2026-09-14 00:48:54.920701 | instance | Starting collection install process 2026-09-14 00:48:54.920736 | instance | Installing 'vexxhost.ceph:4.2.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-09-14 00:48:54.920772 | instance | Created collection for vexxhost.ceph:4.2.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-09-14 00:48:54.920807 | instance | vexxhost.ceph:4.2.0 was installed successfully 2026-09-14 00:48:54.920841 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.920876 | instance | 2.17.12 2026-09-14 00:48:54.920910 | instance | Checked out vexxhost.containers to v1.6.8 2026-09-14 00:48:54.920946 | 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-14 00:48:54.920982 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.921018 | instance | Process install dependency map 2026-09-14 00:48:54.921053 | instance | Starting collection install process 2026-09-14 00:48:54.921087 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-09-14 00:48:54.921144 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-09-14 00:48:54.921184 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-09-14 00:48:54.921220 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.921256 | instance | 2.17.12 2026-09-14 00:48:54.921291 | instance | Checked out vexxhost.kubernetes to v3.4.0 2026-09-14 00:48:54.921326 | 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-14 00:48:54.921362 | instance | Starting galaxy collection install process 2026-09-14 00:48:54.921396 | instance | Process install dependency map 2026-09-14 00:48:54.921431 | instance | Starting collection install process 2026-09-14 00:48:54.921467 | instance | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-09-14 00:48:54.921503 | instance | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-09-14 00:48:54.921538 | instance | vexxhost.kubernetes:3.4.0 was installed successfully 2026-09-14 00:48:54.921573 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-09-14 00:48:54.921609 | instance | 2.17.12 2026-09-14 00:48:54.931862 | 2026-09-14 00:48:54.931946 | PLAY RECAP 2026-09-14 00:48:54.932025 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-14 00:48:54.932067 | 2026-09-14 00:48:55.050447 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-09-14 00:48:55.054151 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-14 00:48:55.589740 | 2026-09-14 00:48:55.589862 | PLAY [all] 2026-09-14 00:48:55.599807 | 2026-09-14 00:48:55.599895 | TASK [Install "jq" for log collection] 2026-09-14 00:49:04.422709 | instance | changed 2026-09-14 00:49:04.425606 | 2026-09-14 00:49:04.425713 | PLAY RECAP 2026-09-14 00:49:04.425806 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-14 00:49:04.425919 | 2026-09-14 00:49:04.550755 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-09-14 00:49:04.554262 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-14 00:49:05.088816 | 2026-09-14 00:49:05.088942 | PLAY [all] 2026-09-14 00:49:05.098592 | 2026-09-14 00:49:05.098656 | LOOP [Check inventory directories] 2026-09-14 00:49:05.403899 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-09-14 00:49:05.568260 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/csi/inventory" 2026-09-14 00:49:05.735636 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/csi/inventory" 2026-09-14 00:49:05.754024 | 2026-09-14 00:49:05.754291 | LOOP [Copy inventory file for Zuul] 2026-09-14 00:49:06.590195 | instance | changed: 2026-09-14 00:49:06.590472 | instance | { 2026-09-14 00:49:06.590527 | instance | "ansible_loop_var": "item", 2026-09-14 00:49:06.590572 | instance | "changed": false, 2026-09-14 00:49:06.590610 | instance | "failed": false, 2026-09-14 00:49:06.590652 | instance | "invocation": { 2026-09-14 00:49:06.590688 | instance | "module_args": { 2026-09-14 00:49:06.590722 | instance | "checksum_algorithm": "sha1", 2026-09-14 00:49:06.590755 | instance | "follow": true, 2026-09-14 00:49:06.590789 | instance | "get_attributes": true, 2026-09-14 00:49:06.590825 | instance | "get_checksum": true, 2026-09-14 00:49:06.590861 | instance | "get_mime": true, 2026-09-14 00:49:06.590895 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-09-14 00:49:06.590928 | instance | } 2026-09-14 00:49:06.590961 | instance | }, 2026-09-14 00:49:06.590994 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-09-14 00:49:06.591027 | instance | "stat": { 2026-09-14 00:49:06.591059 | instance | "atime": 1789346927.642398, 2026-09-14 00:49:06.591112 | instance | "attr_flags": "e", 2026-09-14 00:49:06.591147 | instance | "attributes": [ 2026-09-14 00:49:06.591180 | instance | "extents" 2026-09-14 00:49:06.591213 | instance | ], 2026-09-14 00:49:06.591245 | instance | "block_size": 4096, 2026-09-14 00:49:06.591277 | instance | "blocks": 8, 2026-09-14 00:49:06.591310 | instance | "charset": "unknown", 2026-09-14 00:49:06.591345 | instance | "ctime": 1789346926.1543972, 2026-09-14 00:49:06.591379 | instance | "dev": 64513, 2026-09-14 00:49:06.591412 | instance | "device_type": 0, 2026-09-14 00:49:06.591445 | instance | "executable": true, 2026-09-14 00:49:06.591477 | instance | "exists": true, 2026-09-14 00:49:06.591508 | instance | "gid": 1000, 2026-09-14 00:49:06.591540 | instance | "gr_name": "zuul", 2026-09-14 00:49:06.591571 | instance | "inode": 1049107, 2026-09-14 00:49:06.591603 | instance | "isblk": false, 2026-09-14 00:49:06.591636 | instance | "ischr": false, 2026-09-14 00:49:06.591669 | instance | "isdir": true, 2026-09-14 00:49:06.591700 | instance | "isfifo": false, 2026-09-14 00:49:06.591732 | instance | "isgid": false, 2026-09-14 00:49:06.591763 | instance | "islnk": false, 2026-09-14 00:49:06.591795 | instance | "isreg": false, 2026-09-14 00:49:06.591825 | instance | "issock": false, 2026-09-14 00:49:06.591856 | instance | "isuid": false, 2026-09-14 00:49:06.591888 | instance | "mimetype": "unknown", 2026-09-14 00:49:06.591923 | instance | "mode": "0755", 2026-09-14 00:49:06.591954 | instance | "mtime": 1789346926.1543972, 2026-09-14 00:49:06.591985 | instance | "nlink": 23, 2026-09-14 00:49:06.592017 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-09-14 00:49:06.592049 | instance | "pw_name": "zuul", 2026-09-14 00:49:06.592106 | instance | "readable": true, 2026-09-14 00:49:06.592141 | instance | "rgrp": true, 2026-09-14 00:49:06.592174 | instance | "roth": true, 2026-09-14 00:49:06.592226 | instance | "rusr": true, 2026-09-14 00:49:06.592260 | instance | "size": 4096, 2026-09-14 00:49:06.592293 | instance | "uid": 1000, 2026-09-14 00:49:06.592326 | instance | "version": "7912930", 2026-09-14 00:49:06.592359 | instance | "wgrp": false, 2026-09-14 00:49:06.592395 | instance | "woth": false, 2026-09-14 00:49:06.592428 | instance | "writeable": true, 2026-09-14 00:49:06.592461 | instance | "wusr": true, 2026-09-14 00:49:06.592545 | instance | "xgrp": true, 2026-09-14 00:49:06.592587 | instance | "xoth": true, 2026-09-14 00:49:06.592619 | instance | "xusr": true 2026-09-14 00:49:06.592652 | instance | } 2026-09-14 00:49:06.592686 | instance | } 2026-09-14 00:49:06.592750 | instance | ok: All items complete 2026-09-14 00:49:06.592790 | 2026-09-14 00:49:06.595013 | instance | skipping: Conditional result was False 2026-09-14 00:49:06.597362 | instance | skipping: Conditional result was False 2026-09-14 00:49:06.606214 | 2026-09-14 00:49:06.606354 | LOOP [Switch "ansible_host" to private IP] 2026-09-14 00:49:06.963320 | instance | changed: 1 replacements made 2026-09-14 00:49:06.965541 | instance | skipping: Conditional result was False 2026-09-14 00:49:06.971798 | instance | skipping: Conditional result was False 2026-09-14 00:49:06.982397 | 2026-09-14 00:49:06.982524 | TASK [Run Molecule scenario] 2026-09-14 00:49:07.941544 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-09-14 00:49:08.358077 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-09-14 00:49:08.358186 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-09-14 00:49:19.316623 | instance | INFO [csi > dependency] Executing 2026-09-14 00:49:19.316970 | instance | WARNING [csi > dependency] Missing roles requirements file: requirements.yml 2026-09-14 00:49:19.317392 | instance | WARNING [csi > dependency] Missing collections requirements file: collections.yml 2026-09-14 00:49:19.317593 | instance | WARNING [csi > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-09-14 00:49:19.331817 | instance | INFO [csi > cleanup] Executing 2026-09-14 00:49:19.332441 | instance | WARNING [csi > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-14 00:49:19.347134 | instance | INFO [csi > destroy] Executing 2026-09-14 00:49:19.347336 | instance | WARNING [csi > destroy] Skipping, '--destroy=never' requested. 2026-09-14 00:49:19.347507 | instance | INFO [csi > destroy] Executed: Successful 2026-09-14 00:49:19.356510 | instance | INFO [csi > syntax] Executing 2026-09-14 00:49:20.877732 | instance | 2026-09-14 00:49:20.877946 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/csi/converge.yml 2026-09-14 00:49:20.946816 | instance | INFO [csi > syntax] Executed: Successful 2026-09-14 00:49:20.961703 | instance | INFO [csi > create] Executing 2026-09-14 00:49:20.965127 | instance | WARNING [csi > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-09-14 00:49:20.979186 | instance | INFO [csi > prepare] Executing 2026-09-14 00:49:21.689734 | instance | 2026-09-14 00:49:21.689961 | instance | PLAY [Prepare] ***************************************************************** 2026-09-14 00:49:21.690188 | instance | 2026-09-14 00:49:21.690435 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-14 00:49:21.690659 | instance | Monday 14 September 2026 00:49:21 +0000 (0:00:00.025) 0:00:00.025 ****** 2026-09-14 00:49:22.997154 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-14 00:49:22.997390 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-14 00:49:22.997630 | instance | interpreter could change the meaning of that path. See 2026-09-14 00:49:22.997894 | instance | https://docs.ansible.com/ansible- 2026-09-14 00:49:22.998130 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-14 00:49:23.009427 | instance | ok: [instance] 2026-09-14 00:49:23.009636 | instance | 2026-09-14 00:49:23.009900 | instance | TASK [Configure short hostname] ************************************************ 2026-09-14 00:49:23.010136 | instance | Monday 14 September 2026 00:49:23 +0000 (0:00:01.320) 0:00:01.345 ****** 2026-09-14 00:49:23.783834 | instance | changed: [instance] 2026-09-14 00:49:23.784007 | instance | 2026-09-14 00:49:23.784263 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-09-14 00:49:23.784575 | instance | Monday 14 September 2026 00:49:23 +0000 (0:00:00.774) 0:00:02.119 ****** 2026-09-14 00:49:24.072897 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-09-14 00:49:24.073047 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-09-14 00:49:24.073205 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-09-14 00:49:24.085021 | instance | changed: [instance] 2026-09-14 00:49:24.085238 | instance | 2026-09-14 00:49:24.085506 | instance | TASK [Purge "snapd" package] *************************************************** 2026-09-14 00:49:24.085751 | instance | Monday 14 September 2026 00:49:24 +0000 (0:00:00.301) 0:00:02.420 ****** 2026-09-14 00:49:24.975291 | instance | ok: [instance] 2026-09-14 00:49:24.975460 | instance | 2026-09-14 00:49:24.975621 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-09-14 00:49:24.975831 | instance | 2026-09-14 00:49:24.976009 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-14 00:49:24.976194 | instance | Monday 14 September 2026 00:49:24 +0000 (0:00:00.889) 0:00:03.310 ****** 2026-09-14 00:49:25.760352 | instance | ok: [instance] 2026-09-14 00:49:25.760573 | instance | 2026-09-14 00:49:25.760808 | instance | TASK [Install depedencies] ***************************************************** 2026-09-14 00:49:25.761037 | instance | Monday 14 September 2026 00:49:25 +0000 (0:00:00.784) 0:00:04.095 ****** 2026-09-14 00:49:49.655637 | instance | changed: [instance] 2026-09-14 00:49:49.655839 | instance | 2026-09-14 00:49:49.656089 | instance | TASK [Start up service] ******************************************************** 2026-09-14 00:49:49.656382 | instance | Monday 14 September 2026 00:49:49 +0000 (0:00:23.895) 0:00:27.991 ****** 2026-09-14 00:49:50.233583 | instance | ok: [instance] 2026-09-14 00:49:50.233747 | instance | 2026-09-14 00:49:50.234037 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-09-14 00:49:50.234269 | instance | Monday 14 September 2026 00:49:50 +0000 (0:00:00.578) 0:00:28.569 ****** 2026-09-14 00:49:50.550776 | instance | ok: [instance] 2026-09-14 00:49:50.550977 | instance | 2026-09-14 00:49:50.551297 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-09-14 00:49:50.551566 | instance | Monday 14 September 2026 00:49:50 +0000 (0:00:00.317) 0:00:28.886 ****** 2026-09-14 00:49:51.302327 | instance | changed: [instance] 2026-09-14 00:49:51.302639 | instance | 2026-09-14 00:49:51.302798 | instance | TASK [Get list of all loopback devices] **************************************** 2026-09-14 00:49:51.303154 | instance | Monday 14 September 2026 00:49:51 +0000 (0:00:00.751) 0:00:29.638 ****** 2026-09-14 00:49:51.525063 | instance | ok: [instance] 2026-09-14 00:49:51.525297 | instance | 2026-09-14 00:49:51.525698 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-09-14 00:49:51.526085 | instance | Monday 14 September 2026 00:49:51 +0000 (0:00:00.219) 0:00:29.857 ****** 2026-09-14 00:49:51.552651 | instance | skipping: [instance] 2026-09-14 00:49:51.553339 | instance | 2026-09-14 00:49:51.553702 | instance | TASK [Create devices for Ceph] ************************************************* 2026-09-14 00:49:51.554066 | instance | Monday 14 September 2026 00:49:51 +0000 (0:00:00.028) 0:00:29.888 ****** 2026-09-14 00:49:52.147339 | instance | changed: [instance] => (item=osd0) 2026-09-14 00:49:52.147766 | instance | changed: [instance] => (item=osd1) 2026-09-14 00:49:52.148153 | instance | changed: [instance] => (item=osd2) 2026-09-14 00:49:52.148486 | instance | 2026-09-14 00:49:52.148831 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-09-14 00:49:52.149195 | instance | Monday 14 September 2026 00:49:52 +0000 (0:00:00.593) 0:00:30.482 ****** 2026-09-14 00:49:52.837946 | instance | changed: [instance] => (item=osd0) 2026-09-14 00:49:52.838030 | instance | changed: [instance] => (item=osd1) 2026-09-14 00:49:52.838044 | instance | changed: [instance] => (item=osd2) 2026-09-14 00:49:52.838065 | instance | 2026-09-14 00:49:52.838212 | instance | TASK [Start loop devices] ****************************************************** 2026-09-14 00:49:52.838470 | instance | Monday 14 September 2026 00:49:52 +0000 (0:00:00.689) 0:00:31.172 ****** 2026-09-14 00:49:53.630940 | instance | changed: [instance] => (item=osd0) 2026-09-14 00:49:53.631172 | instance | changed: [instance] => (item=osd1) 2026-09-14 00:49:53.631431 | instance | changed: [instance] => (item=osd2) 2026-09-14 00:49:53.631760 | instance | 2026-09-14 00:49:53.632055 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-09-14 00:49:53.632301 | instance | Monday 14 September 2026 00:49:53 +0000 (0:00:00.793) 0:00:31.966 ****** 2026-09-14 00:49:56.919206 | instance | changed: [instance] => (item=osd0) 2026-09-14 00:49:56.919392 | instance | changed: [instance] => (item=osd1) 2026-09-14 00:49:56.919641 | instance | changed: [instance] => (item=osd2) 2026-09-14 00:49:56.919851 | instance | 2026-09-14 00:49:56.920077 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-09-14 00:49:56.920301 | instance | Monday 14 September 2026 00:49:56 +0000 (0:00:03.288) 0:00:35.254 ****** 2026-09-14 00:49:58.869892 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-09-14 00:49:58.869958 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-09-14 00:49:58.869987 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-09-14 00:49:58.869996 | instance | 2026-09-14 00:49:58.870005 | instance | PLAY RECAP ********************************************************************* 2026-09-14 00:49:58.870566 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-09-14 00:49:58.870642 | instance | 2026-09-14 00:49:58.873881 | instance | Monday 14 September 2026 00:49:58 +0000 (0:00:01.949) 0:00:37.204 ****** 2026-09-14 00:49:58.873920 | instance | =============================================================================== 2026-09-14 00:49:58.873935 | instance | Install depedencies ---------------------------------------------------- 23.90s 2026-09-14 00:49:58.873949 | instance | Create a volume group for each loop device ------------------------------ 3.29s 2026-09-14 00:49:58.873962 | instance | Create a logical volume for each loop device ---------------------------- 1.95s 2026-09-14 00:49:58.873978 | instance | Gathering Facts --------------------------------------------------------- 1.32s 2026-09-14 00:49:58.873992 | instance | Purge "snapd" package --------------------------------------------------- 0.89s 2026-09-14 00:49:58.874005 | instance | Start loop devices ------------------------------------------------------ 0.79s 2026-09-14 00:49:58.874018 | instance | Gathering Facts --------------------------------------------------------- 0.78s 2026-09-14 00:49:58.874032 | instance | Configure short hostname ------------------------------------------------ 0.77s 2026-09-14 00:49:58.874045 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.75s 2026-09-14 00:49:58.874058 | instance | Set permissions on loopback devices ------------------------------------- 0.69s 2026-09-14 00:49:58.874072 | instance | Create devices for Ceph ------------------------------------------------- 0.59s 2026-09-14 00:49:58.874085 | instance | Start up service -------------------------------------------------------- 0.58s 2026-09-14 00:49:58.874103 | instance | Generate lvm.conf ------------------------------------------------------- 0.32s 2026-09-14 00:49:58.874352 | instance | Ensure hostname inside hosts file --------------------------------------- 0.30s 2026-09-14 00:49:58.874694 | instance | Get list of all loopback devices ---------------------------------------- 0.22s 2026-09-14 00:49:58.874988 | instance | Fail if there is any existing loopback devices -------------------------- 0.03s 2026-09-14 00:49:58.961138 | instance | INFO [csi > prepare] Executed: Successful 2026-09-14 00:49:58.975625 | instance | INFO [csi > converge] Executing 2026-09-14 00:50:00.445079 | instance | 2026-09-14 00:50:00.445615 | instance | PLAY [Debug CSI driver value] ************************************************** 2026-09-14 00:50:00.446063 | instance | 2026-09-14 00:50:00.446516 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-14 00:50:00.446936 | instance | Monday 14 September 2026 00:50:00 +0000 (0:00:00.008) 0:00:00.008 ****** 2026-09-14 00:50:01.488992 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-09-14 00:50:01.489062 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-09-14 00:50:01.489074 | instance | interpreter could change the meaning of that path. See 2026-09-14 00:50:01.489083 | instance | https://docs.ansible.com/ansible- 2026-09-14 00:50:01.489092 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-09-14 00:50:01.499190 | instance | ok: [instance] 2026-09-14 00:50:01.499222 | instance | 2026-09-14 00:50:01.499234 | instance | TASK [Display CSI driver value and environment variable] *********************** 2026-09-14 00:50:01.499244 | instance | Monday 14 September 2026 00:50:01 +0000 (0:00:01.053) 0:00:01.062 ****** 2026-09-14 00:50:01.560606 | instance | ok: [instance] => { 2026-09-14 00:50:01.560667 | instance | "msg": "csi_driver=rbd, MOLECULE_CSI_DRIVER=" 2026-09-14 00:50:01.560679 | instance | } 2026-09-14 00:50:01.560688 | instance | 2026-09-14 00:50:01.560697 | instance | PLAY [all] ********************************************************************* 2026-09-14 00:50:01.560706 | instance | 2026-09-14 00:50:01.560863 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-14 00:50:01.561194 | instance | Monday 14 September 2026 00:50:01 +0000 (0:00:00.060) 0:00:01.122 ****** 2026-09-14 00:50:02.480221 | instance | ok: [instance] 2026-09-14 00:50:02.480385 | instance | 2026-09-14 00:50:02.480660 | instance | TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-09-14 00:50:02.480896 | instance | Monday 14 September 2026 00:50:02 +0000 (0:00:00.921) 0:00:02.043 ****** 2026-09-14 00:50:02.536884 | instance | skipping: [instance] 2026-09-14 00:50:02.537202 | instance | 2026-09-14 00:50:02.537528 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-09-14 00:50:02.537923 | instance | Monday 14 September 2026 00:50:02 +0000 (0:00:00.056) 0:00:02.100 ****** 2026-09-14 00:50:02.758520 | instance | ok: [instance] 2026-09-14 00:50:02.758617 | instance | 2026-09-14 00:50:02.758629 | instance | PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-09-14 00:50:02.758687 | instance | 2026-09-14 00:50:02.758814 | instance | TASK [Gathering Facts] ********************************************************* 2026-09-14 00:50:02.759012 | instance | Monday 14 September 2026 00:50:02 +0000 (0:00:00.221) 0:00:02.322 ****** 2026-09-14 00:50:03.774628 | instance | ok: [instance] 2026-09-14 00:50:03.774812 | instance | 2026-09-14 00:50:03.775174 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-14 00:50:03.777065 | instance | Monday 14 September 2026 00:50:03 +0000 (0:00:01.014) 0:00:03.336 ****** 2026-09-14 00:50:04.098860 | instance | ok: [instance] 2026-09-14 00:50:04.099241 | instance | 2026-09-14 00:50:04.099617 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-14 00:50:04.099924 | instance | Monday 14 September 2026 00:50:04 +0000 (0:00:00.324) 0:00:03.660 ****** 2026-09-14 00:50:04.150658 | instance | skipping: [instance] 2026-09-14 00:50:04.150719 | instance | 2026-09-14 00:50:04.150911 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-09-14 00:50:04.151150 | instance | Monday 14 September 2026 00:50:04 +0000 (0:00:00.052) 0:00:03.712 ****** 2026-09-14 00:50:04.526613 | instance | changed: [instance] 2026-09-14 00:50:04.526767 | instance | 2026-09-14 00:50:04.527032 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-14 00:50:04.527233 | instance | Monday 14 September 2026 00:50:04 +0000 (0:00:00.374) 0:00:04.086 ****** 2026-09-14 00:50:04.626754 | instance | ok: [instance] => { 2026-09-14 00:50:04.627265 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-09-14 00:50:04.627605 | instance | } 2026-09-14 00:50:04.627904 | instance | 2026-09-14 00:50:04.628218 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-14 00:50:04.628558 | instance | Monday 14 September 2026 00:50:04 +0000 (0:00:00.100) 0:00:04.187 ****** 2026-09-14 00:50:05.417016 | instance | changed: [instance] 2026-09-14 00:50:05.417393 | instance | 2026-09-14 00:50:05.417741 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-14 00:50:05.418125 | instance | Monday 14 September 2026 00:50:05 +0000 (0:00:00.790) 0:00:04.978 ****** 2026-09-14 00:50:05.475581 | instance | skipping: [instance] 2026-09-14 00:50:05.475904 | instance | 2026-09-14 00:50:05.476223 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-14 00:50:05.476557 | instance | Monday 14 September 2026 00:50:05 +0000 (0:00:00.061) 0:00:05.040 ****** 2026-09-14 00:50:05.532560 | instance | skipping: [instance] 2026-09-14 00:50:05.532883 | instance | 2026-09-14 00:50:05.533202 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-14 00:50:05.533533 | instance | Monday 14 September 2026 00:50:05 +0000 (0:00:00.056) 0:00:05.097 ****** 2026-09-14 00:50:05.758888 | instance | ok: [instance] 2026-09-14 00:50:05.759188 | instance | 2026-09-14 00:50:05.759448 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-09-14 00:50:05.759698 | instance | Monday 14 September 2026 00:50:05 +0000 (0:00:00.226) 0:00:05.323 ****** 2026-09-14 00:50:07.040194 | instance | ok: [instance] 2026-09-14 00:50:07.040259 | instance | 2026-09-14 00:50:07.040270 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-14 00:50:07.040294 | instance | Monday 14 September 2026 00:50:07 +0000 (0:00:01.279) 0:00:06.603 ****** 2026-09-14 00:50:07.119076 | instance | ok: [instance] => { 2026-09-14 00:50:07.119119 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-09-14 00:50:07.119130 | instance | } 2026-09-14 00:50:07.119139 | instance | 2026-09-14 00:50:07.119148 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-14 00:50:07.119157 | instance | Monday 14 September 2026 00:50:07 +0000 (0:00:00.078) 0:00:06.682 ****** 2026-09-14 00:50:08.155236 | instance | changed: [instance] 2026-09-14 00:50:08.155295 | instance | 2026-09-14 00:50:08.155307 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-14 00:50:08.155318 | instance | Monday 14 September 2026 00:50:08 +0000 (0:00:01.036) 0:00:07.718 ****** 2026-09-14 00:50:11.150364 | instance | changed: [instance] 2026-09-14 00:50:11.150494 | instance | 2026-09-14 00:50:11.151070 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-09-14 00:50:11.151143 | instance | Monday 14 September 2026 00:50:11 +0000 (0:00:02.994) 0:00:10.713 ****** 2026-09-14 00:50:11.195565 | instance | skipping: [instance] 2026-09-14 00:50:11.196850 | instance | 2026-09-14 00:50:11.196889 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-09-14 00:50:11.196904 | instance | Monday 14 September 2026 00:50:11 +0000 (0:00:00.045) 0:00:10.759 ****** 2026-09-14 00:50:11.235944 | instance | skipping: [instance] 2026-09-14 00:50:11.236682 | instance | 2026-09-14 00:50:11.236748 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-09-14 00:50:11.236760 | instance | Monday 14 September 2026 00:50:11 +0000 (0:00:00.040) 0:00:10.799 ****** 2026-09-14 00:50:11.277635 | instance | skipping: [instance] 2026-09-14 00:50:11.277874 | instance | 2026-09-14 00:50:11.278229 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-09-14 00:50:11.278570 | instance | Monday 14 September 2026 00:50:11 +0000 (0:00:00.041) 0:00:10.841 ****** 2026-09-14 00:50:16.489450 | instance | changed: [instance] 2026-09-14 00:50:16.489595 | instance | 2026-09-14 00:50:16.489938 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-09-14 00:50:16.490297 | instance | Monday 14 September 2026 00:50:16 +0000 (0:00:05.211) 0:00:16.052 ****** 2026-09-14 00:50:17.110484 | instance | changed: [instance] 2026-09-14 00:50:17.110674 | instance | 2026-09-14 00:50:17.110819 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-09-14 00:50:17.110969 | instance | Monday 14 September 2026 00:50:17 +0000 (0:00:00.618) 0:00:16.671 ****** 2026-09-14 00:50:18.119877 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-09-14 00:50:18.119942 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-09-14 00:50:18.119955 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-09-14 00:50:18.119975 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-09-14 00:50:18.119987 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-09-14 00:50:18.119997 | instance | 2026-09-14 00:50:18.120007 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-09-14 00:50:18.120016 | instance | Monday 14 September 2026 00:50:18 +0000 (0:00:01.009) 0:00:17.681 ****** 2026-09-14 00:50:18.823019 | instance | changed: [instance] 2026-09-14 00:50:18.823120 | instance | 2026-09-14 00:50:18.823855 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-09-14 00:50:18.824012 | instance | Monday 14 September 2026 00:50:18 +0000 (0:00:00.677) 0:00:18.359 ****** 2026-09-14 00:50:18.824021 | instance | 2026-09-14 00:50:18.824028 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-14 00:50:18.824033 | instance | Monday 14 September 2026 00:50:18 +0000 (0:00:00.027) 0:00:18.387 ****** 2026-09-14 00:50:19.769716 | instance | ok: [instance] 2026-09-14 00:50:19.769860 | instance | 2026-09-14 00:50:19.769875 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-09-14 00:50:19.769886 | instance | Monday 14 September 2026 00:50:19 +0000 (0:00:00.945) 0:00:19.333 ****** 2026-09-14 00:50:20.238728 | instance | changed: [instance] 2026-09-14 00:50:20.238800 | instance | 2026-09-14 00:50:20.238811 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-09-14 00:50:20.238821 | instance | Monday 14 September 2026 00:50:20 +0000 (0:00:00.468) 0:00:19.801 ****** 2026-09-14 00:50:20.889503 | instance | changed: [instance] 2026-09-14 00:50:20.889573 | instance | 2026-09-14 00:50:20.889981 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-09-14 00:50:20.890066 | instance | Monday 14 September 2026 00:50:20 +0000 (0:00:00.651) 0:00:20.453 ****** 2026-09-14 00:50:21.135268 | instance | ok: [instance] 2026-09-14 00:50:21.135371 | instance | 2026-09-14 00:50:21.135821 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-09-14 00:50:21.135882 | instance | Monday 14 September 2026 00:50:21 +0000 (0:00:00.245) 0:00:20.699 ****** 2026-09-14 00:50:21.206605 | instance | ok: [instance] => { 2026-09-14 00:50:21.206657 | instance | "msg": "https://download.docker.com/linux/static/stable/x86_64/docker-24.0.9.tgz" 2026-09-14 00:50:21.206665 | instance | } 2026-09-14 00:50:21.206672 | instance | 2026-09-14 00:50:21.206677 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-09-14 00:50:21.206683 | instance | Monday 14 September 2026 00:50:21 +0000 (0:00:00.070) 0:00:20.770 ****** 2026-09-14 00:50:27.220210 | instance | changed: [instance] 2026-09-14 00:50:27.220331 | instance | 2026-09-14 00:50:27.220633 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-09-14 00:50:27.220687 | instance | Monday 14 September 2026 00:50:27 +0000 (0:00:06.014) 0:00:26.784 ****** 2026-09-14 00:50:31.630307 | instance | changed: [instance] 2026-09-14 00:50:31.630395 | instance | 2026-09-14 00:50:31.630741 | instance | TASK [vexxhost.containers.docker : Install AppArmor packages] ****************** 2026-09-14 00:50:31.630975 | instance | Monday 14 September 2026 00:50:31 +0000 (0:00:04.409) 0:00:31.194 ****** 2026-09-14 00:50:33.054884 | instance | ok: [instance] 2026-09-14 00:50:33.054946 | instance | 2026-09-14 00:50:33.054958 | instance | TASK [vexxhost.containers.docker : Ensure group "docker" exists] *************** 2026-09-14 00:50:33.054968 | instance | Monday 14 September 2026 00:50:33 +0000 (0:00:01.423) 0:00:32.617 ****** 2026-09-14 00:50:33.509276 | instance | changed: [instance] 2026-09-14 00:50:33.509361 | instance | 2026-09-14 00:50:33.509424 | instance | TASK [vexxhost.containers.docker : Create systemd service file for docker] ***** 2026-09-14 00:50:33.509572 | instance | Monday 14 September 2026 00:50:33 +0000 (0:00:00.455) 0:00:33.073 ****** 2026-09-14 00:50:34.034859 | instance | changed: [instance] 2026-09-14 00:50:34.035595 | instance | 2026-09-14 00:50:34.035659 | instance | TASK [vexxhost.containers.docker : Create folders for configuration] *********** 2026-09-14 00:50:34.035671 | instance | Monday 14 September 2026 00:50:34 +0000 (0:00:00.525) 0:00:33.598 ****** 2026-09-14 00:50:34.684474 | instance | changed: [instance] => (item={'path': '/etc/docker'}) 2026-09-14 00:50:34.684544 | instance | changed: [instance] => (item={'path': '/var/lib/docker', 'mode': '0o710'}) 2026-09-14 00:50:34.684557 | instance | changed: [instance] => (item={'path': '/run/docker', 'mode': '0o711'}) 2026-09-14 00:50:34.684567 | instance | 2026-09-14 00:50:34.684577 | instance | TASK [vexxhost.containers.docker : Create systemd socket file for docker] ****** 2026-09-14 00:50:34.684586 | instance | Monday 14 September 2026 00:50:34 +0000 (0:00:00.647) 0:00:34.246 ****** 2026-09-14 00:50:35.202615 | instance | changed: [instance] 2026-09-14 00:50:35.203354 | instance | 2026-09-14 00:50:35.203420 | instance | TASK [vexxhost.containers.docker : Create docker daemon config file] *********** 2026-09-14 00:50:35.203433 | instance | Monday 14 September 2026 00:50:35 +0000 (0:00:00.519) 0:00:34.766 ****** 2026-09-14 00:50:35.746821 | instance | changed: [instance] 2026-09-14 00:50:35.746909 | instance | 2026-09-14 00:50:35.747658 | instance | TASK [vexxhost.containers.docker : Force any restarts if necessary] ************ 2026-09-14 00:50:35.747733 | instance | Monday 14 September 2026 00:50:35 +0000 (0:00:00.517) 0:00:35.283 ****** 2026-09-14 00:50:35.747741 | instance | 2026-09-14 00:50:35.747747 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Reload systemd] ************** 2026-09-14 00:50:35.747759 | instance | Monday 14 September 2026 00:50:35 +0000 (0:00:00.027) 0:00:35.311 ****** 2026-09-14 00:50:36.587872 | instance | ok: [instance] 2026-09-14 00:50:36.588077 | instance | 2026-09-14 00:50:36.588090 | instance | RUNNING HANDLER [vexxhost.containers.docker : Restart docker] ****************** 2026-09-14 00:50:36.588100 | instance | Monday 14 September 2026 00:50:36 +0000 (0:00:00.839) 0:00:36.150 ****** 2026-09-14 00:50:37.654505 | instance | changed: [instance] 2026-09-14 00:50:37.655233 | instance | 2026-09-14 00:50:37.655303 | instance | TASK [vexxhost.containers.docker : Enable and start service] ******************* 2026-09-14 00:50:37.655315 | instance | Monday 14 September 2026 00:50:37 +0000 (0:00:01.067) 0:00:37.218 ****** 2026-09-14 00:50:38.284185 | instance | changed: [instance] 2026-09-14 00:50:38.284296 | instance | 2026-09-14 00:50:38.284371 | instance | TASK [vexxhost.ceph.cephadm : Gather variables for each operating system] ****** 2026-09-14 00:50:38.284541 | instance | Monday 14 September 2026 00:50:38 +0000 (0:00:00.630) 0:00:37.848 ****** 2026-09-14 00:50:38.360436 | instance | ok: [instance] => (item=/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/cephadm/vars/ubuntu-22.04.yml) 2026-09-14 00:50:38.361127 | instance | 2026-09-14 00:50:38.361215 | instance | TASK [vexxhost.ceph.cephadm : Install packages] ******************************** 2026-09-14 00:50:38.361227 | instance | Monday 14 September 2026 00:50:38 +0000 (0:00:00.075) 0:00:37.924 ****** 2026-09-14 00:50:44.002149 | instance | changed: [instance] 2026-09-14 00:50:44.002479 | instance | 2026-09-14 00:50:44.002493 | instance | TASK [vexxhost.ceph.cephadm : Ensure services are started] ********************* 2026-09-14 00:50:44.002506 | instance | Monday 14 September 2026 00:50:43 +0000 (0:00:05.642) 0:00:43.566 ****** 2026-09-14 00:50:45.727127 | instance | ok: [instance] => (item=chronyd) 2026-09-14 00:50:45.728027 | instance | ok: [instance] => (item=sshd) 2026-09-14 00:50:45.728078 | instance | 2026-09-14 00:50:45.728089 | instance | TASK [vexxhost.ceph.cephadm : Download "cephadm"] ****************************** 2026-09-14 00:50:45.728099 | instance | Monday 14 September 2026 00:50:45 +0000 (0:00:01.724) 0:00:45.291 ****** 2026-09-14 00:50:59.831665 | instance | changed: [instance] 2026-09-14 00:50:59.831818 | instance | 2026-09-14 00:50:59.832370 | instance | TASK [vexxhost.ceph.cephadm : Remove cephadm from old path] ******************** 2026-09-14 00:50:59.832440 | instance | Monday 14 September 2026 00:50:59 +0000 (0:00:14.104) 0:00:59.395 ****** 2026-09-14 00:51:00.070891 | instance | ok: [instance] 2026-09-14 00:51:00.071584 | instance | 2026-09-14 00:51:00.071649 | instance | TASK [vexxhost.ceph.cephadm : Ensure "cephadm" user is present] **************** 2026-09-14 00:51:00.071660 | instance | Monday 14 September 2026 00:51:00 +0000 (0:00:00.239) 0:00:59.634 ****** 2026-09-14 00:51:00.609350 | instance | changed: [instance] 2026-09-14 00:51:00.609455 | instance | 2026-09-14 00:51:00.609868 | instance | TASK [vexxhost.ceph.cephadm : Allow "cephadm" user to have passwordless sudo] *** 2026-09-14 00:51:00.609939 | instance | Monday 14 September 2026 00:51:00 +0000 (0:00:00.538) 0:01:00.173 ****** 2026-09-14 00:51:00.997704 | instance | changed: [instance] 2026-09-14 00:51:00.997824 | instance | 2026-09-14 00:51:00.998141 | instance | TASK [vexxhost.ceph.mon : Set Ceph Monitor IP address] ************************* 2026-09-14 00:51:00.998200 | instance | Monday 14 September 2026 00:51:00 +0000 (0:00:00.388) 0:01:00.561 ****** 2026-09-14 00:51:01.137458 | instance | ok: [instance] 2026-09-14 00:51:01.137881 | instance | 2026-09-14 00:51:01.137976 | instance | TASK [vexxhost.ceph.mon : Check if any node is bootstrapped] ******************* 2026-09-14 00:51:01.138277 | instance | Monday 14 September 2026 00:51:01 +0000 (0:00:00.138) 0:01:00.700 ****** 2026-09-14 00:51:01.401683 | instance | ok: [instance] => (item=instance) 2026-09-14 00:51:01.401978 | instance | 2026-09-14 00:51:01.401992 | instance | TASK [vexxhost.ceph.mon : Select pre-existing bootstrap node if exists] ******** 2026-09-14 00:51:01.402006 | instance | Monday 14 September 2026 00:51:01 +0000 (0:00:00.263) 0:01:00.964 ****** 2026-09-14 00:51:01.459385 | instance | ok: [instance] 2026-09-14 00:51:01.459476 | instance | 2026-09-14 00:51:01.459571 | instance | TASK [vexxhost.ceph.mon : Bootstrap cluster] *********************************** 2026-09-14 00:51:01.459698 | instance | Monday 14 September 2026 00:51:01 +0000 (0:00:00.058) 0:01:01.023 ****** 2026-09-14 00:51:01.533557 | instance | included: /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph/roles/mon/tasks/bootstrap-ceph.yml for instance 2026-09-14 00:51:01.533620 | instance | 2026-09-14 00:51:01.534049 | instance | TASK [vexxhost.ceph.mon : Generate temporary file for "ceph.conf"] ************* 2026-09-14 00:51:01.534137 | instance | Monday 14 September 2026 00:51:01 +0000 (0:00:00.074) 0:01:01.097 ****** 2026-09-14 00:51:01.884234 | instance | changed: [instance] 2026-09-14 00:51:01.884953 | instance | 2026-09-14 00:51:01.885023 | instance | TASK [vexxhost.ceph.mon : Include extra configuration values] ****************** 2026-09-14 00:51:01.885035 | instance | Monday 14 September 2026 00:51:01 +0000 (0:00:00.350) 0:01:01.447 ****** 2026-09-14 00:51:02.621389 | instance | changed: [instance] => (item={'option': 'mon allow pool size one', 'section': 'global', 'value': True}) 2026-09-14 00:51:02.621541 | instance | changed: [instance] => (item={'option': 'osd crush chooseleaf type', 'section': 'global', 'value': 0}) 2026-09-14 00:51:02.621901 | instance | changed: [instance] => (item={'option': 'auth allow insecure global id reclaim', 'section': 'mon', 'value': False}) 2026-09-14 00:51:02.621974 | instance | 2026-09-14 00:51:02.622219 | instance | TASK [vexxhost.ceph.mon : Run Bootstrap command] ******************************* 2026-09-14 00:51:02.622280 | instance | Monday 14 September 2026 00:51:02 +0000 (0:00:00.737) 0:01:02.185 ****** 2026-09-14 00:52:42.826997 | instance | ok: [instance] 2026-09-14 00:52:42.827510 | instance | 2026-09-14 00:52:42.827572 | instance | TASK [vexxhost.ceph.mon : Remove temporary file for "ceph.conf"] *************** 2026-09-14 00:52:42.827703 | instance | Monday 14 September 2026 00:52:42 +0000 (0:01:40.205) 0:02:42.390 ****** 2026-09-14 00:52:43.065303 | instance | changed: [instance] 2026-09-14 00:52:43.065422 | instance | 2026-09-14 00:52:43.065593 | instance | TASK [vexxhost.ceph.mon : Set bootstrap node] ********************************** 2026-09-14 00:52:43.065747 | instance | Monday 14 September 2026 00:52:43 +0000 (0:00:00.238) 0:02:42.629 ****** 2026-09-14 00:52:43.106745 | instance | ok: [instance] 2026-09-14 00:52:43.106873 | instance | 2026-09-14 00:52:43.107024 | instance | TASK [Install Ceph host] ******************************************************* 2026-09-14 00:52:43.107225 | instance | Monday 14 September 2026 00:52:43 +0000 (0:00:00.041) 0:02:42.671 ****** 2026-09-14 00:52:43.218236 | instance | included: vexxhost.ceph.cephadm_host for instance 2026-09-14 00:52:43.218566 | instance | 2026-09-14 00:52:43.218610 | instance | TASK [vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user] ****** 2026-09-14 00:52:43.218616 | instance | Monday 14 September 2026 00:52:43 +0000 (0:00:00.111) 0:02:42.782 ****** 2026-09-14 00:52:44.833749 | instance | ok: [instance] 2026-09-14 00:52:44.834557 | instance | 2026-09-14 00:52:44.834632 | instance | TASK [vexxhost.ceph.cephadm_host : Set fact with public SSH key for "cephadm" user] *** 2026-09-14 00:52:44.834646 | instance | Monday 14 September 2026 00:52:44 +0000 (0:00:01.614) 0:02:44.397 ****** 2026-09-14 00:52:44.918748 | instance | ok: [instance] => (item=instance) 2026-09-14 00:52:44.919530 | instance | 2026-09-14 00:52:44.919581 | instance | TASK [vexxhost.ceph.cephadm_host : Set authorized key for "cephadm"] *********** 2026-09-14 00:52:44.919593 | instance | Monday 14 September 2026 00:52:44 +0000 (0:00:00.084) 0:02:44.482 ****** 2026-09-14 00:52:45.295090 | instance | ok: [instance] 2026-09-14 00:52:45.295274 | instance | 2026-09-14 00:52:45.295562 | instance | TASK [vexxhost.ceph.cephadm_host : Add new host to Ceph] *********************** 2026-09-14 00:52:45.295804 | instance | Monday 14 September 2026 00:52:45 +0000 (0:00:00.376) 0:02:44.858 ****** 2026-09-14 00:52:47.773691 | instance | ok: [instance] 2026-09-14 00:52:47.773770 | instance | 2026-09-14 00:52:47.774197 | instance | TASK [vexxhost.ceph.mon : Configure "mon" label for monitors] ****************** 2026-09-14 00:52:47.774277 | instance | Monday 14 September 2026 00:52:47 +0000 (0:00:02.478) 0:02:47.337 ****** 2026-09-14 00:53:57.127978 | 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-14 00:53:57.128815 | 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_00b0e_ps/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_00b0e_ps/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_00b0e_ps/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_00b0e_ps/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-14T00:53:56.935+0000 7f66a303b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAwRadq1DZTLSAAQJpwv3xY5yXSIv1fQ6PXqDIN4TfVsSV/m5mzzY3gLYg=: Malformed input [buffer:3]\n2026-09-14T00:53:56.935+0000 7f66a303b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-14T00:53:56.939+0000 7f66a303b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAwRadq1DZTLSAAQJpwv3xY5yXSIv1fQ6PXqDIN4TfVsSV/m5mzzY3gLYg=: Malformed input [buffer:3]\n2026-09-14T00:53:56.939+0000 7f66a303b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-14T00:53:56.939+0000 7f66a303b640 -1 auth: error parsing file /etc/ceph/ceph.keyring: error setting modifier for [client.admin] type=key val=AgAwRadq1DZTLSAAQJpwv3xY5yXSIv1fQ6PXqDIN4TfVsSV/m5mzzY3gLYg=: Malformed input [buffer:3]\n2026-09-14T00:53:56.939+0000 7f66a303b640 -1 auth: failed to load /etc/ceph/ceph.keyring: (5) Input/output error\n2026-09-14T00:53:56.939+0000 7f66a303b640 -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-14 00:53:57.131755 | instance | 2026-09-14 00:53:57.131977 | instance | PLAY RECAP ********************************************************************* 2026-09-14 00:53:57.133862 | instance | instance : ok=55 changed=26 unreachable=0 failed=1 skipped=7 rescued=0 ignored=0 2026-09-14 00:53:57.133896 | instance | 2026-09-14 00:53:57.133908 | instance | Monday 14 September 2026 00:53:57 +0000 (0:01:09.359) 0:03:56.696 ****** 2026-09-14 00:53:57.133917 | instance | =============================================================================== 2026-09-14 00:53:57.133926 | instance | vexxhost.ceph.mon : Run Bootstrap command ----------------------------- 100.21s 2026-09-14 00:53:57.133934 | instance | vexxhost.ceph.mon : Configure "mon" label for monitors ----------------- 69.36s 2026-09-14 00:53:57.133943 | instance | vexxhost.ceph.cephadm : Download "cephadm" ----------------------------- 14.10s 2026-09-14 00:53:57.133956 | instance | vexxhost.containers.download_artifact : Download item ------------------- 6.01s 2026-09-14 00:53:57.137421 | instance | vexxhost.ceph.cephadm : Install packages -------------------------------- 5.64s 2026-09-14 00:53:57.137475 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.21s 2026-09-14 00:53:57.137502 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 4.41s 2026-09-14 00:53:57.137511 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.99s 2026-09-14 00:53:57.137520 | instance | vexxhost.ceph.cephadm_host : Add new host to Ceph ----------------------- 2.48s 2026-09-14 00:53:57.137528 | instance | vexxhost.containers.containerd : Reload systemd ------------------------- 1.79s 2026-09-14 00:53:57.137537 | instance | vexxhost.ceph.cephadm : Ensure services are started --------------------- 1.72s 2026-09-14 00:53:57.137545 | instance | vexxhost.ceph.cephadm_host : Get public SSH key for "cephadm" user ------ 1.61s 2026-09-14 00:53:57.137554 | instance | vexxhost.containers.docker : Install AppArmor packages ------------------ 1.42s 2026-09-14 00:53:57.137562 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.28s 2026-09-14 00:53:57.137571 | instance | vexxhost.containers.docker : Restart docker ----------------------------- 1.07s 2026-09-14 00:53:57.137579 | instance | Gathering Facts --------------------------------------------------------- 1.05s 2026-09-14 00:53:57.137588 | instance | vexxhost.containers.download_artifact : Download item ------------------- 1.04s 2026-09-14 00:53:57.137596 | instance | Gathering Facts --------------------------------------------------------- 1.01s 2026-09-14 00:53:57.137604 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 1.01s 2026-09-14 00:53:57.137613 | instance | Gathering Facts --------------------------------------------------------- 0.92s 2026-09-14 00:53:57.241325 | 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-14 00:53:57.241568 | instance | ERROR [csi > converge] Executed: Failed 2026-09-14 00:53:57.241743 | 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-14 00:53:57.397536 | instance | ERROR 2026-09-14 00:53:57.398459 | instance | { 2026-09-14 00:53:57.398564 | instance | "delta": "0:04:49.928763", 2026-09-14 00:53:57.398623 | instance | "end": "2026-09-14 00:53:57.306969", 2026-09-14 00:53:57.398670 | instance | "msg": "non-zero return code", 2026-09-14 00:53:57.398715 | instance | "rc": 2, 2026-09-14 00:53:57.398760 | instance | "start": "2026-09-14 00:49:07.378206" 2026-09-14 00:53:57.398806 | instance | } failure 2026-09-14 00:53:57.408689 | 2026-09-14 00:53:57.408803 | PLAY RECAP 2026-09-14 00:53:57.408883 | instance | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-09-14 00:53:57.408972 | 2026-09-14 00:53:57.557541 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-09-14 00:53:57.561356 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-14 00:53:58.239386 | 2026-09-14 00:53:58.239506 | PLAY [all] 2026-09-14 00:53:58.252601 | 2026-09-14 00:53:58.252675 | TASK [gather-host-logs : creating directory for system status] 2026-09-14 00:53:58.620469 | instance | changed 2026-09-14 00:53:58.631483 | 2026-09-14 00:53:58.631691 | TASK [gather-host-logs : Get logs for each host] 2026-09-14 00:53:59.016538 | instance | + systemd-cgls --full --all --no-pager 2026-09-14 00:53:59.031804 | instance | + ip addr 2026-09-14 00:53:59.034502 | instance | + ip route 2026-09-14 00:53:59.036739 | instance | + lsblk 2026-09-14 00:53:59.042264 | instance | + mount 2026-09-14 00:53:59.045914 | instance | + docker images 2026-09-14 00:53:59.068640 | instance | + brctl show 2026-09-14 00:53:59.069193 | instance | /bin/bash: line 8: brctl: command not found 2026-09-14 00:53:59.069555 | instance | + ip neigh 2026-09-14 00:53:59.071925 | instance | + bridge fdb show 2026-09-14 00:53:59.075036 | instance | + ps aux --sort=-%mem 2026-09-14 00:53:59.097474 | instance | + dpkg -l 2026-09-14 00:53:59.108707 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-09-14 00:53:59.109309 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-09-14 00:53:59.131597 | instance | + '[' '!' -z '' ']' 2026-09-14 00:53:59.188757 | instance | ok: Runtime: 0:00:00.120091 2026-09-14 00:53:59.199847 | 2026-09-14 00:53:59.199988 | TASK [gather-host-logs : Downloads logs to executor] 2026-09-14 00:53:59.955853 | instance | changed: 2026-09-14 00:53:59.956101 | instance | created directory /var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/logs/instance 2026-09-14 00:53:59.956157 | instance | cd+++++++++ system/ 2026-09-14 00:53:59.956199 | instance | >f+++++++++ system/brctl-show.txt 2026-09-14 00:53:59.956238 | instance | >f+++++++++ system/bridge-fdb.txt 2026-09-14 00:53:59.956275 | instance | >f+++++++++ system/docker-images.txt 2026-09-14 00:53:59.956341 | instance | >f+++++++++ system/ip-addr.txt 2026-09-14 00:53:59.956380 | instance | >f+++++++++ system/ip-neigh.txt 2026-09-14 00:53:59.956417 | instance | >f+++++++++ system/ip-route.txt 2026-09-14 00:53:59.956458 | instance | >f+++++++++ system/lsblk.txt 2026-09-14 00:53:59.956520 | instance | >f+++++++++ system/mount.txt 2026-09-14 00:53:59.956563 | instance | >f+++++++++ system/packages.txt 2026-09-14 00:53:59.956600 | instance | >f+++++++++ system/ps.txt 2026-09-14 00:53:59.956636 | instance | >f+++++++++ system/systemd-cgls.txt 2026-09-14 00:53:59.972944 | 2026-09-14 00:53:59.973067 | LOOP [helm-release-status : creating directory for helm release status] 2026-09-14 00:54:00.196671 | instance | changed: "values" 2026-09-14 00:54:00.380757 | instance | changed: "releases" 2026-09-14 00:54:00.398880 | 2026-09-14 00:54:00.399086 | TASK [helm-release-status : Gather get release status for helm charts] 2026-09-14 00:54:00.655389 | instance | /bin/bash: line 3: kubectl: command not found 2026-09-14 00:54:00.951253 | instance | ok: Runtime: 0:00:00.003817 2026-09-14 00:54:00.960607 | 2026-09-14 00:54:00.960737 | TASK [helm-release-status : Downloads logs to executor] 2026-09-14 00:54:01.470262 | instance | changed: 2026-09-14 00:54:01.470516 | instance | cd+++++++++ helm/ 2026-09-14 00:54:01.470571 | instance | cd+++++++++ helm/releases/ 2026-09-14 00:54:01.470613 | instance | cd+++++++++ helm/values/ 2026-09-14 00:54:01.487047 | 2026-09-14 00:54:01.487199 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-09-14 00:54:01.713769 | instance | changed 2026-09-14 00:54:01.724617 | 2026-09-14 00:54:01.724754 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-09-14 00:54:02.018631 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-14 00:54:02.018787 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-14 00:54:02.024965 | instance | environment: line 1: kubectl: command not found 2026-09-14 00:54:02.026167 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-14 00:54:02.027335 | instance | environment: line 1: kubectl: command not found 2026-09-14 00:54:02.028631 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-14 00:54:02.029899 | instance | environment: line 1: kubectl: command not found 2026-09-14 00:54:02.031154 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-14 00:54:02.032358 | instance | environment: line 1: kubectl: command not found 2026-09-14 00:54:02.034540 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-14 00:54:02.035682 | instance | environment: line 1: kubectl: command not found 2026-09-14 00:54:02.036833 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-09-14 00:54:02.271438 | instance | ok: Runtime: 0:00:00.026427 2026-09-14 00:54:02.280349 | 2026-09-14 00:54:02.280477 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-09-14 00:54:02.497244 | instance | changed 2026-09-14 00:54:02.509248 | 2026-09-14 00:54:02.509442 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-09-14 00:54:02.777267 | instance | environment: line 5: kubectl: command not found 2026-09-14 00:54:02.778073 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-14 00:54:02.778136 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-14 00:54:02.778235 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-09-14 00:54:03.059619 | instance | ok: Runtime: 0:00:00.009323 2026-09-14 00:54:03.069538 | 2026-09-14 00:54:03.069659 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-09-14 00:54:03.569131 | instance | changed: 2026-09-14 00:54:03.569413 | instance | cd+++++++++ objects/ 2026-09-14 00:54:03.569470 | instance | cd+++++++++ objects/cluster/ 2026-09-14 00:54:03.569512 | instance | cd+++++++++ objects/namespaced/ 2026-09-14 00:54:03.585765 | 2026-09-14 00:54:03.585887 | TASK [gather-pod-logs : creating directory for pod logs] 2026-09-14 00:54:03.802151 | instance | changed 2026-09-14 00:54:03.812241 | 2026-09-14 00:54:03.812484 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-09-14 00:54:04.041444 | instance | changed 2026-09-14 00:54:04.050849 | 2026-09-14 00:54:04.050999 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-09-14 00:54:04.319792 | instance | environment: line 3: kubectl: command not found 2026-09-14 00:54:04.602902 | instance | ok: Runtime: 0:00:00.010273 2026-09-14 00:54:04.612004 | 2026-09-14 00:54:04.612125 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-09-14 00:54:05.162053 | instance | changed: 2026-09-14 00:54:05.162304 | instance | cd+++++++++ pod-logs/ 2026-09-14 00:54:05.162359 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-09-14 00:54:05.179551 | 2026-09-14 00:54:05.179769 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-09-14 00:54:05.425974 | instance | changed 2026-09-14 00:54:05.435260 | 2026-09-14 00:54:05.435444 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-09-14 00:54:05.732323 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-14 00:54:05.982685 | instance | ok: Runtime: 0:00:00.053921 2026-09-14 00:54:05.991711 | 2026-09-14 00:54:05.991830 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-09-14 00:54:06.246141 | instance | /bin/bash: line 2: kubectl: command not found 2026-09-14 00:54:06.291948 | instance | ceph-mgr endpoints: 2026-09-14 00:54:06.540711 | instance | ok: Runtime: 0:00:00.053179 2026-09-14 00:54:06.550101 | 2026-09-14 00:54:06.550229 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-09-14 00:54:06.809449 | instance | /bin/bash: line 4: kubectl: command not found 2026-09-14 00:54:07.097899 | instance | ok: Runtime: 0:00:00.035325 2026-09-14 00:54:07.106981 | 2026-09-14 00:54:07.107102 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-09-14 00:54:07.628335 | instance | changed: cd+++++++++ prometheus/ 2026-09-14 00:54:07.644904 | 2026-09-14 00:54:07.645026 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-09-14 00:54:07.852742 | instance | changed 2026-09-14 00:54:07.861760 | 2026-09-14 00:54:07.861894 | TASK [gather-selenium-data : Get selenium data] 2026-09-14 00:54:08.145568 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-09-14 00:54:08.147125 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-09-14 00:54:08.410663 | instance | ERROR 2026-09-14 00:54:08.411011 | instance | { 2026-09-14 00:54:08.411089 | instance | "delta": "0:00:00.007106", 2026-09-14 00:54:08.411147 | instance | "end": "2026-09-14 00:54:08.147473", 2026-09-14 00:54:08.411202 | instance | "msg": "non-zero return code", 2026-09-14 00:54:08.411251 | instance | "rc": 1, 2026-09-14 00:54:08.411324 | instance | "start": "2026-09-14 00:54:08.140367" 2026-09-14 00:54:08.411373 | instance | } 2026-09-14 00:54:08.411433 | instance | ERROR: Ignoring Errors 2026-09-14 00:54:08.421643 | 2026-09-14 00:54:08.421745 | TASK [gather-selenium-data : Downloads logs to executor] 2026-09-14 00:54:08.941138 | instance | changed: cd+++++++++ selenium/ 2026-09-14 00:54:08.951034 | 2026-09-14 00:54:08.951122 | PLAY RECAP 2026-09-14 00:54:08.951203 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-09-14 00:54:08.951244 | 2026-09-14 00:54:09.071144 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-09-14 00:54:09.075335 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-14 00:54:09.633755 | 2026-09-14 00:54:09.633894 | PLAY [all] 2026-09-14 00:54:09.644681 | 2026-09-14 00:54:09.644756 | TASK [fetch-output : Set log path for multiple nodes] 2026-09-14 00:54:09.698351 | instance | skipping: Conditional result was False 2026-09-14 00:54:09.705301 | 2026-09-14 00:54:09.705399 | TASK [fetch-output : Set log path for single node] 2026-09-14 00:54:09.734156 | instance | ok 2026-09-14 00:54:09.739620 | 2026-09-14 00:54:09.739703 | LOOP [fetch-output : Ensure local output dirs] 2026-09-14 00:54:10.077838 | instance -> localhost | ok: "/var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/logs" 2026-09-14 00:54:10.301457 | instance -> localhost | changed: "/var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/artifacts" 2026-09-14 00:54:10.497067 | instance -> localhost | changed: "/var/lib/zuul/builds/3f433a05df72492284ff75a339544d6b/work/docs" 2026-09-14 00:54:10.518904 | 2026-09-14 00:54:10.519099 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-14 00:54:11.168781 | instance | changed: .d..t...... ./ 2026-09-14 00:54:11.169103 | instance | changed: All items complete 2026-09-14 00:54:11.169161 | 2026-09-14 00:54:11.653660 | instance | changed: .d..t...... ./ 2026-09-14 00:54:12.146881 | instance | changed: .d..t...... ./ 2026-09-14 00:54:12.162545 | 2026-09-14 00:54:12.162618 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-14 00:54:12.603774 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.010593 2026-09-14 00:54:12.842483 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008174 2026-09-14 00:54:12.856463 | 2026-09-14 00:54:12.856634 | PLAY [all] 2026-09-14 00:54:12.863419 | 2026-09-14 00:54:12.863502 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-14 00:54:13.311122 | instance | changed 2026-09-14 00:54:13.321156 | 2026-09-14 00:54:13.321302 | PLAY RECAP 2026-09-14 00:54:13.321399 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-14 00:54:13.321446 | 2026-09-14 00:54:13.445070 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-09-14 00:54:13.448812 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-14 00:54:13.979766 | 2026-09-14 00:54:13.980323 | PLAY [localhost] 2026-09-14 00:54:13.989108 | 2026-09-14 00:54:13.989172 | TASK [Generate Zuul manifest] 2026-09-14 00:54:14.005749 | localhost | ok 2026-09-14 00:54:14.019795 | 2026-09-14 00:54:14.019911 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-14 00:54:14.413927 | localhost | changed 2026-09-14 00:54:14.438955 | 2026-09-14 00:54:14.439124 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-14 00:54:14.480429 | localhost | ok 2026-09-14 00:54:14.492873 | 2026-09-14 00:54:14.493001 | TASK [Upload logs] 2026-09-14 00:54:14.516848 | localhost | ok 2026-09-14 00:54:14.600339 | 2026-09-14 00:54:14.600524 | TASK [Set zuul-log-path fact] 2026-09-14 00:54:14.619852 | localhost | ok 2026-09-14 00:54:14.633359 | 2026-09-14 00:54:14.633442 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-14 00:54:14.671709 | localhost | ok 2026-09-14 00:54:14.679745 | 2026-09-14 00:54:14.679829 | TASK [upload-logs : Create log directories] 2026-09-14 00:54:15.092946 | localhost | changed 2026-09-14 00:54:15.098440 | 2026-09-14 00:54:15.098525 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-14 00:54:15.505069 | localhost -> localhost | ok: Runtime: 0:00:00.005589 2026-09-14 00:54:15.514730 | 2026-09-14 00:54:15.514858 | TASK [upload-logs : Upload logs to log server] 2026-09-14 00:54:15.993922 | localhost | Output suppressed because no_log was given 2026-09-14 00:54:16.038004 | 2026-09-14 00:54:16.038152 | LOOP [upload-logs : Compress console log and json output] 2026-09-14 00:54:16.101390 | localhost | skipping: Conditional result was False 2026-09-14 00:54:16.109628 | localhost | skipping: Conditional result was False 2026-09-14 00:54:16.121270 | 2026-09-14 00:54:16.121441 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-14 00:54:16.166953 | localhost | skipping: Conditional result was False 2026-09-14 00:54:16.167444 | 2026-09-14 00:54:16.170400 | localhost | skipping: Conditional result was False 2026-09-14 00:54:16.185743 | 2026-09-14 00:54:16.185911 | LOOP [upload-logs : Upload console log and json output]