2026-04-28 07:11:13.735585 | Job console starting 2026-04-28 07:11:13.754129 | Updating git repos 2026-04-28 07:11:13.808641 | Cloning repos into workspace 2026-04-28 07:11:13.875381 | Restoring repo states 2026-04-28 07:11:13.895839 | Merging changes 2026-04-28 07:11:14.857499 | Checking out repos 2026-04-28 07:11:15.194419 | Preparing playbooks 2026-04-28 07:11:17.673762 | Running Ansible setup 2026-04-28 07:11:21.269607 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 07:11:21.838392 | 2026-04-28 07:11:21.838827 | PLAY [localhost] 2026-04-28 07:11:21.846469 | 2026-04-28 07:11:21.846534 | TASK [Gathering Facts] 2026-04-28 07:11:22.863969 | localhost | ok 2026-04-28 07:11:22.873461 | 2026-04-28 07:11:22.873549 | TASK [Setup log path fact] 2026-04-28 07:11:22.893195 | localhost | ok 2026-04-28 07:11:22.906673 | 2026-04-28 07:11:22.906739 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 07:11:22.934757 | localhost | ok 2026-04-28 07:11:22.941819 | 2026-04-28 07:11:22.941892 | TASK [emit-job-header : Print job information] 2026-04-28 07:11:22.983103 | # Job Information 2026-04-28 07:11:22.983280 | Ansible Version: 2.16.16 2026-04-28 07:11:22.983323 | Job: atmosphere-molecule-csi-rbd 2026-04-28 07:11:22.983353 | Pipeline: check 2026-04-28 07:11:22.983381 | Executor: 0a8996d2b663 2026-04-28 07:11:22.983410 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3877 2026-04-28 07:11:22.983442 | Event ID: 55d02b20-42d1-11f1-8457-10c22e2839dd 2026-04-28 07:11:22.988922 | 2026-04-28 07:11:22.989050 | LOOP [emit-job-header : Print node information] 2026-04-28 07:11:23.079192 | localhost | ok: 2026-04-28 07:11:23.079441 | localhost | # Node Information 2026-04-28 07:11:23.079502 | localhost | Inventory Hostname: instance 2026-04-28 07:11:23.079549 | localhost | Hostname: np0000171033 2026-04-28 07:11:23.079593 | localhost | Username: zuul 2026-04-28 07:11:23.079643 | localhost | Distro: Ubuntu 22.04 2026-04-28 07:11:23.079687 | localhost | Provider: yul1 2026-04-28 07:11:23.079882 | localhost | Region: ca-ymq-1 2026-04-28 07:11:23.079934 | localhost | Label: ubuntu-jammy 2026-04-28 07:11:23.079976 | localhost | Product Name: OpenStack Nova 2026-04-28 07:11:23.080017 | localhost | Interface IP: 199.19.213.206 2026-04-28 07:11:23.098064 | 2026-04-28 07:11:23.098256 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-28 07:11:23.471175 | localhost -> localhost | changed 2026-04-28 07:11:23.482025 | 2026-04-28 07:11:23.482106 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-28 07:11:24.549661 | localhost -> localhost | changed 2026-04-28 07:11:24.559356 | 2026-04-28 07:11:24.559456 | PLAY [all] 2026-04-28 07:11:24.569008 | 2026-04-28 07:11:24.569087 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-28 07:11:25.313176 | instance -> localhost | ok 2026-04-28 07:11:25.322639 | 2026-04-28 07:11:25.322760 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-28 07:11:25.357653 | instance | ok 2026-04-28 07:11:25.373953 | instance | included: /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-28 07:11:25.379524 | 2026-04-28 07:11:25.379587 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-28 07:11:26.800624 | instance -> localhost | Generating public/private rsa key pair. 2026-04-28 07:11:26.800814 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/cfa9ac09f9564801a950be8e83cc8a3b_id_rsa 2026-04-28 07:11:26.800856 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/cfa9ac09f9564801a950be8e83cc8a3b_id_rsa.pub 2026-04-28 07:11:26.800889 | instance -> localhost | The key fingerprint is: 2026-04-28 07:11:26.800919 | instance -> localhost | SHA256:JVWCmjIulFgEuqOsN0Vt34hSpbdQQ9ucrEXi8huS7C0 zuul-build-sshkey 2026-04-28 07:11:26.801016 | instance -> localhost | The key's randomart image is: 2026-04-28 07:11:26.801054 | instance -> localhost | +---[RSA 3072]----+ 2026-04-28 07:11:26.801089 | instance -> localhost | |.o. .o.+.. | 2026-04-28 07:11:26.801119 | instance -> localhost | |. . .=O o | 2026-04-28 07:11:26.801148 | instance -> localhost | |.o . ..*+.B | 2026-04-28 07:11:26.801176 | instance -> localhost | |..o +.O+.= | 2026-04-28 07:11:26.801203 | instance -> localhost | |o. o =+=S+ | 2026-04-28 07:11:26.801231 | instance -> localhost | |o.. +..o+o. | 2026-04-28 07:11:26.801258 | instance -> localhost | |.. o .E o | 2026-04-28 07:11:26.801286 | instance -> localhost | |. o . | 2026-04-28 07:11:26.801317 | instance -> localhost | |.. . | 2026-04-28 07:11:26.801345 | instance -> localhost | +----[SHA256]-----+ 2026-04-28 07:11:26.801403 | instance -> localhost | ok: Runtime: 0:00:01.032315 2026-04-28 07:11:26.808200 | 2026-04-28 07:11:26.808260 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-28 07:11:26.836162 | instance | ok 2026-04-28 07:11:26.845133 | instance | included: /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-28 07:11:26.857582 | 2026-04-28 07:11:26.857667 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-28 07:11:26.882270 | instance | skipping: Conditional result was False 2026-04-28 07:11:26.890537 | 2026-04-28 07:11:26.890643 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-28 07:11:27.321461 | instance | changed 2026-04-28 07:11:27.396900 | 2026-04-28 07:11:27.397009 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-28 07:11:27.583494 | instance | ok 2026-04-28 07:11:27.589648 | 2026-04-28 07:11:27.589761 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-28 07:11:28.075996 | instance | changed 2026-04-28 07:11:28.083106 | 2026-04-28 07:11:28.083195 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-28 07:11:28.542702 | instance | changed 2026-04-28 07:11:28.549572 | 2026-04-28 07:11:28.549640 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-28 07:11:28.574538 | instance | skipping: Conditional result was False 2026-04-28 07:11:28.585620 | 2026-04-28 07:11:28.585943 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-28 07:11:28.940481 | instance -> localhost | changed 2026-04-28 07:11:28.956713 | 2026-04-28 07:11:28.956831 | TASK [add-build-sshkey : Add back temp key] 2026-04-28 07:11:29.438125 | instance -> localhost | Identity added: /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/cfa9ac09f9564801a950be8e83cc8a3b_id_rsa (zuul-build-sshkey) 2026-04-28 07:11:29.438441 | instance -> localhost | ok: Runtime: 0:00:00.013051 2026-04-28 07:11:29.447625 | 2026-04-28 07:11:29.447723 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-28 07:11:29.777900 | instance | ok 2026-04-28 07:11:29.784249 | 2026-04-28 07:11:29.784330 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-28 07:11:29.810037 | instance | skipping: Conditional result was False 2026-04-28 07:11:29.824133 | 2026-04-28 07:11:29.824252 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-28 07:11:30.141482 | instance | ok 2026-04-28 07:11:30.155513 | 2026-04-28 07:11:30.155645 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-28 07:11:33.029272 | instance | Output suppressed because no_log was given 2026-04-28 07:11:33.041814 | 2026-04-28 07:11:33.041891 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-28 07:11:33.230727 | instance | ok: "logs" 2026-04-28 07:11:33.231060 | instance | ok: All items complete 2026-04-28 07:11:33.231096 | 2026-04-28 07:11:33.377175 | instance | ok: "artifacts" 2026-04-28 07:11:33.535597 | instance | ok: "docs" 2026-04-28 07:11:33.543362 | 2026-04-28 07:11:33.543496 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-28 07:11:33.731113 | instance | changed: "logs" 2026-04-28 07:11:33.888429 | instance | changed: "artifacts" 2026-04-28 07:11:34.048530 | instance | changed: "docs" 2026-04-28 07:11:34.129718 | 2026-04-28 07:11:34.129823 | PLAY RECAP 2026-04-28 07:11:34.129872 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-28 07:11:34.129901 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 07:11:34.129920 | 2026-04-28 07:11:34.281863 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-28 07:11:34.286585 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 07:11:34.916779 | 2026-04-28 07:11:34.916990 | PLAY [all] 2026-04-28 07:11:34.930116 | 2026-04-28 07:11:34.930401 | TASK [setup-uv : Extract archive] 2026-04-28 07:11:37.281504 | instance | changed 2026-04-28 07:11:37.287605 | 2026-04-28 07:11:37.287683 | TASK [setup-uv : Print version] 2026-04-28 07:11:37.458306 | instance | uv 0.8.13 2026-04-28 07:11:37.822698 | instance | ok: Runtime: 0:00:00.012498 2026-04-28 07:11:37.830366 | 2026-04-28 07:11:37.830410 | PLAY RECAP 2026-04-28 07:11:37.830455 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 07:11:37.830479 | 2026-04-28 07:11:37.927367 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-28 07:11:37.931702 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-28 07:11:38.546607 | 2026-04-28 07:11:38.682604 | PLAY [all] 2026-04-28 07:11:38.703105 | 2026-04-28 07:11:38.703229 | TASK [Install "jq" for log collection] 2026-04-28 07:12:00.740896 | instance | changed 2026-04-28 07:12:00.747582 | 2026-04-28 07:12:00.747660 | TASK [Install pip3 for Python package management] 2026-04-28 07:12:04.590504 | instance | changed 2026-04-28 07:12:04.595749 | 2026-04-28 07:12:04.595813 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-28 07:12:07.756793 | instance | changed 2026-04-28 07:12:07.759939 | 2026-04-28 07:12:07.759999 | PLAY [all] 2026-04-28 07:12:07.768393 | 2026-04-28 07:12:07.768463 | TASK [ensure-go : Check installed go version] 2026-04-28 07:12:08.304544 | instance | ok: ERROR (ignored) 2026-04-28 07:12:08.305006 | instance | { 2026-04-28 07:12:08.305087 | instance | "failed_when_result": false, 2026-04-28 07:12:08.305206 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-28 07:12:08.305257 | instance | "rc": 2 2026-04-28 07:12:08.305309 | instance | } 2026-04-28 07:12:08.310807 | 2026-04-28 07:12:08.310877 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-28 07:12:08.367760 | instance | ok 2026-04-28 07:12:08.379643 | instance | included: /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-28 07:12:08.387247 | 2026-04-28 07:12:08.387348 | TASK [ensure-go : Create temp directory] 2026-04-28 07:12:08.735104 | instance | changed 2026-04-28 07:12:08.742073 | 2026-04-28 07:12:08.742148 | TASK [ensure-go : Get archive checksum] 2026-04-28 07:12:09.363390 | instance | ok: OK (64 bytes) 2026-04-28 07:12:10.775770 | 2026-04-28 07:12:10.775977 | TASK [ensure-go : Download go archive] 2026-04-28 07:12:12.138795 | instance | changed: OK (78559214 bytes) 2026-04-28 07:12:12.147420 | 2026-04-28 07:12:12.147505 | TASK [ensure-go : Install go] 2026-04-28 07:12:17.829455 | instance | changed 2026-04-28 07:12:18.330520 | 2026-04-28 07:12:18.330609 | PLAY [all] 2026-04-28 07:12:18.344338 | 2026-04-28 07:12:18.344472 | TASK [Copy inventory file for Zuul] 2026-04-28 07:12:19.087767 | instance | changed 2026-04-28 07:12:19.094480 | 2026-04-28 07:12:19.094572 | TASK [Switch "ansible_host" to private IP] 2026-04-28 07:12:19.432142 | instance | changed: 1 replacements made 2026-04-28 07:12:19.437804 | 2026-04-28 07:12:19.437868 | TASK [Run molecule prepare] 2026-04-28 07:12:19.695650 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-04-28 07:12:19.695833 | instance | Creating virtual environment at: .venv 2026-04-28 07:12:19.717427 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-28 07:12:19.762867 | instance | Downloading netaddr (2.2MiB) 2026-04-28 07:12:19.763345 | instance | Downloading ansible-core (2.1MiB) 2026-04-28 07:12:19.763826 | instance | Downloading pygments (1.2MiB) 2026-04-28 07:12:19.763993 | instance | Downloading rjsonnet (1.2MiB) 2026-04-28 07:12:19.764176 | instance | Downloading openstacksdk (1.7MiB) 2026-04-28 07:12:19.764748 | instance | Downloading kubernetes (1.9MiB) 2026-04-28 07:12:19.767108 | instance | Downloading setuptools (1.1MiB) 2026-04-28 07:12:19.767806 | instance | Downloading cryptography (4.2MiB) 2026-04-28 07:12:19.768736 | instance | Downloading pydantic-core (2.0MiB) 2026-04-28 07:12:20.069140 | instance | Building pyperclip==1.9.0 2026-04-28 07:12:20.100688 | instance | Downloading rjsonnet 2026-04-28 07:12:20.185283 | instance | Downloading pydantic-core 2026-04-28 07:12:20.219145 | instance | Downloading pygments 2026-04-28 07:12:20.222482 | instance | Downloading netaddr 2026-04-28 07:12:20.256246 | instance | Downloading setuptools 2026-04-28 07:12:20.309217 | instance | Downloading kubernetes 2026-04-28 07:12:20.336904 | instance | Downloading ansible-core 2026-04-28 07:12:20.367849 | instance | Downloading openstacksdk 2026-04-28 07:12:20.483000 | instance | Downloading cryptography 2026-04-28 07:12:20.891608 | instance | Built pyperclip==1.9.0 2026-04-28 07:12:20.977563 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-28 07:12:21.018384 | instance | Installed 83 packages in 39ms 2026-04-28 07:12:21.618411 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-04-28 07:12:22.194199 | instance | INFO [csi > discovery] scenario test matrix: prepare 2026-04-28 07:12:22.194269 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-04-28 07:13:05.238787 | instance | INFO [csi > prepare] Executing 2026-04-28 07:13:06.090437 | instance | 2026-04-28 07:13:06.090945 | instance | PLAY [Prepare] ***************************************************************** 2026-04-28 07:13:06.091212 | instance | 2026-04-28 07:13:06.091495 | instance | TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:06.091772 | instance | Tuesday 28 April 2026 07:13:06 +0000 (0:00:00.028) 0:00:00.028 ********* 2026-04-28 07:13:07.194126 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-04-28 07:13:07.194259 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-04-28 07:13:07.194504 | instance | interpreter could change the meaning of that path. See 2026-04-28 07:13:07.194653 | instance | https://docs.ansible.com/ansible- 2026-04-28 07:13:07.194876 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-04-28 07:13:07.202049 | instance | ok: [instance] 2026-04-28 07:13:07.202279 | instance | 2026-04-28 07:13:07.202628 | instance | TASK [Configure short hostname] ************************************************ 2026-04-28 07:13:07.202900 | instance | Tuesday 28 April 2026 07:13:07 +0000 (0:00:01.113) 0:00:01.141 ********* 2026-04-28 07:13:07.874509 | instance | changed: [instance] 2026-04-28 07:13:07.874756 | instance | 2026-04-28 07:13:07.875054 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-04-28 07:13:07.875343 | instance | Tuesday 28 April 2026 07:13:07 +0000 (0:00:00.672) 0:00:01.813 ********* 2026-04-28 07:13:08.129811 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-04-28 07:13:08.129952 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-04-28 07:13:08.130128 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-04-28 07:13:08.140328 | instance | changed: [instance] 2026-04-28 07:13:08.140562 | instance | 2026-04-28 07:13:08.140902 | instance | TASK [Purge "snapd" package] *************************************************** 2026-04-28 07:13:08.141150 | instance | Tuesday 28 April 2026 07:13:08 +0000 (0:00:00.266) 0:00:02.080 ********* 2026-04-28 07:13:08.916065 | instance | ok: [instance] 2026-04-28 07:13:08.916299 | instance | 2026-04-28 07:13:08.916579 | instance | PLAY [Create devices for Ceph] ************************************************* 2026-04-28 07:13:08.916841 | instance | 2026-04-28 07:13:08.917116 | instance | TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:08.917397 | instance | Tuesday 28 April 2026 07:13:08 +0000 (0:00:00.775) 0:00:02.855 ********* 2026-04-28 07:13:09.573638 | instance | ok: [instance] 2026-04-28 07:13:09.573849 | instance | 2026-04-28 07:13:09.574110 | instance | TASK [Install depedencies] ***************************************************** 2026-04-28 07:13:09.574408 | instance | Tuesday 28 April 2026 07:13:09 +0000 (0:00:00.657) 0:00:03.513 ********* 2026-04-28 07:13:28.127641 | instance | changed: [instance] 2026-04-28 07:13:28.127859 | instance | 2026-04-28 07:13:28.128166 | instance | TASK [Start up service] ******************************************************** 2026-04-28 07:13:28.128446 | instance | Tuesday 28 April 2026 07:13:28 +0000 (0:00:18.553) 0:00:22.067 ********* 2026-04-28 07:13:28.657963 | instance | ok: [instance] 2026-04-28 07:13:28.658176 | instance | 2026-04-28 07:13:28.658448 | instance | TASK [Generate lvm.conf] ******************************************************* 2026-04-28 07:13:28.658696 | instance | Tuesday 28 April 2026 07:13:28 +0000 (0:00:00.530) 0:00:22.597 ********* 2026-04-28 07:13:28.932607 | instance | ok: [instance] 2026-04-28 07:13:28.932864 | instance | 2026-04-28 07:13:28.933172 | instance | TASK [Write /etc/lvm/lvm.conf] ************************************************* 2026-04-28 07:13:28.933443 | instance | Tuesday 28 April 2026 07:13:28 +0000 (0:00:00.274) 0:00:22.872 ********* 2026-04-28 07:13:29.540081 | instance | changed: [instance] 2026-04-28 07:13:29.540349 | instance | 2026-04-28 07:13:29.540626 | instance | TASK [Get list of all loopback devices] **************************************** 2026-04-28 07:13:29.540895 | instance | Tuesday 28 April 2026 07:13:29 +0000 (0:00:00.607) 0:00:23.479 ********* 2026-04-28 07:13:29.715699 | instance | ok: [instance] 2026-04-28 07:13:29.715949 | instance | 2026-04-28 07:13:29.716264 | instance | TASK [Fail if there is any existing loopback devices] ************************** 2026-04-28 07:13:29.716539 | instance | Tuesday 28 April 2026 07:13:29 +0000 (0:00:00.175) 0:00:23.655 ********* 2026-04-28 07:13:29.734865 | instance | skipping: [instance] 2026-04-28 07:13:29.735128 | instance | 2026-04-28 07:13:29.735407 | instance | TASK [Create devices for Ceph] ************************************************* 2026-04-28 07:13:29.735683 | instance | Tuesday 28 April 2026 07:13:29 +0000 (0:00:00.019) 0:00:23.674 ********* 2026-04-28 07:13:30.244001 | instance | changed: [instance] => (item=osd0) 2026-04-28 07:13:30.244303 | instance | changed: [instance] => (item=osd1) 2026-04-28 07:13:30.244566 | instance | changed: [instance] => (item=osd2) 2026-04-28 07:13:30.244814 | instance | 2026-04-28 07:13:30.245085 | instance | TASK [Set permissions on loopback devices] ************************************* 2026-04-28 07:13:30.245348 | instance | Tuesday 28 April 2026 07:13:30 +0000 (0:00:00.508) 0:00:24.183 ********* 2026-04-28 07:13:30.823257 | instance | changed: [instance] => (item=osd0) 2026-04-28 07:13:30.823377 | instance | changed: [instance] => (item=osd1) 2026-04-28 07:13:30.823527 | instance | changed: [instance] => (item=osd2) 2026-04-28 07:13:30.823670 | instance | 2026-04-28 07:13:30.823823 | instance | TASK [Start loop devices] ****************************************************** 2026-04-28 07:13:30.823980 | instance | Tuesday 28 April 2026 07:13:30 +0000 (0:00:00.579) 0:00:24.762 ********* 2026-04-28 07:13:31.438149 | instance | changed: [instance] => (item=osd0) 2026-04-28 07:13:31.438271 | instance | changed: [instance] => (item=osd1) 2026-04-28 07:13:31.438529 | instance | changed: [instance] => (item=osd2) 2026-04-28 07:13:31.438740 | instance | 2026-04-28 07:13:31.438919 | instance | TASK [Create a volume group for each loop device] ****************************** 2026-04-28 07:13:31.439089 | instance | Tuesday 28 April 2026 07:13:31 +0000 (0:00:00.614) 0:00:25.377 ********* 2026-04-28 07:13:34.247345 | instance | changed: [instance] => (item=osd0) 2026-04-28 07:13:34.247625 | instance | changed: [instance] => (item=osd1) 2026-04-28 07:13:34.248042 | instance | changed: [instance] => (item=osd2) 2026-04-28 07:13:34.248306 | instance | 2026-04-28 07:13:34.248591 | instance | TASK [Create a logical volume for each loop device] **************************** 2026-04-28 07:13:34.248867 | instance | Tuesday 28 April 2026 07:13:34 +0000 (0:00:02.809) 0:00:28.186 ********* 2026-04-28 07:13:35.974127 | instance | changed: [instance] => (item=ceph-instance-osd0) 2026-04-28 07:13:35.974400 | instance | changed: [instance] => (item=ceph-instance-osd1) 2026-04-28 07:13:35.974693 | instance | changed: [instance] => (item=ceph-instance-osd2) 2026-04-28 07:13:35.974957 | instance | 2026-04-28 07:13:35.975225 | instance | PLAY RECAP ********************************************************************* 2026-04-28 07:13:35.975509 | instance | instance : ok=15 changed=9 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-04-28 07:13:35.975757 | instance | 2026-04-28 07:13:35.976057 | instance | Tuesday 28 April 2026 07:13:35 +0000 (0:00:01.727) 0:00:29.913 ********* 2026-04-28 07:13:35.976305 | instance | =============================================================================== 2026-04-28 07:13:35.976569 | instance | Install depedencies ---------------------------------------------------- 18.55s 2026-04-28 07:13:35.976888 | instance | Create a volume group for each loop device ------------------------------ 2.81s 2026-04-28 07:13:35.977163 | instance | Create a logical volume for each loop device ---------------------------- 1.73s 2026-04-28 07:13:35.977428 | instance | Gathering Facts --------------------------------------------------------- 1.11s 2026-04-28 07:13:35.977687 | instance | Purge "snapd" package --------------------------------------------------- 0.78s 2026-04-28 07:13:35.977952 | instance | Configure short hostname ------------------------------------------------ 0.67s 2026-04-28 07:13:35.978229 | instance | Gathering Facts --------------------------------------------------------- 0.66s 2026-04-28 07:13:35.978540 | instance | Start loop devices ------------------------------------------------------ 0.61s 2026-04-28 07:13:35.978804 | instance | Write /etc/lvm/lvm.conf ------------------------------------------------- 0.61s 2026-04-28 07:13:35.979079 | instance | Set permissions on loopback devices ------------------------------------- 0.58s 2026-04-28 07:13:35.979345 | instance | Start up service -------------------------------------------------------- 0.53s 2026-04-28 07:13:35.979605 | instance | Create devices for Ceph ------------------------------------------------- 0.51s 2026-04-28 07:13:35.979863 | instance | Generate lvm.conf ------------------------------------------------------- 0.27s 2026-04-28 07:13:35.980157 | instance | Ensure hostname inside hosts file --------------------------------------- 0.27s 2026-04-28 07:13:35.980426 | instance | Get list of all loopback devices ---------------------------------------- 0.18s 2026-04-28 07:13:35.980691 | instance | Fail if there is any existing loopback devices -------------------------- 0.02s 2026-04-28 07:13:36.045730 | instance | INFO [csi > prepare] Executed: Successful 2026-04-28 07:13:36.046232 | instance | INFO Molecule executed 1 scenario (1 successful) 2026-04-28 07:13:36.577604 | instance | ok: Runtime: 0:01:16.475591 2026-04-28 07:13:36.580672 | 2026-04-28 07:13:36.580733 | PLAY RECAP 2026-04-28 07:13:36.580799 | instance | ok: 12 changed: 9 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-28 07:13:36.580829 | 2026-04-28 07:13:36.696936 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-28 07:13:36.702295 | RUN START: [untrusted : github.com/vexxhost/atmosphere/molecule/csi/converge.yml@main] 2026-04-28 07:13:37.381083 | 2026-04-28 07:13:37.381390 | PLAY [all] 2026-04-28 07:13:37.391943 | 2026-04-28 07:13:37.392018 | TASK [Build atmosphere binary] 2026-04-28 07:13:37.771717 | instance | go: downloading github.com/spf13/cobra v1.9.1 2026-04-28 07:13:37.774427 | instance | go: downloading golang.org/x/sync v0.18.0 2026-04-28 07:13:37.986897 | instance | go: downloading github.com/spf13/pflag v1.0.7 2026-04-28 07:13:43.934598 | instance | ok: Runtime: 0:00:05.925674 2026-04-28 07:13:43.941305 | 2026-04-28 07:13:43.941373 | TASK [Deploy with parallel orchestrator] 2026-04-28 07:13:44.140851 | instance | ==> Multi-tag mode: ceph, kubernetes, csi 2026-04-28 07:13:44.141006 | instance | ==> Running preflight checks 2026-04-28 07:13:44.580730 | instance | [preflight] 2026-04-28 07:13:44.580768 | instance | [preflight] PLAY [Preflight checks] ******************************************************** 2026-04-28 07:13:44.580776 | instance | [preflight] 2026-04-28 07:13:44.580782 | instance | [preflight] TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-04-28 07:13:44.603791 | instance | [preflight] skipping: [instance] 2026-04-28 07:13:44.603824 | instance | [preflight] 2026-04-28 07:13:44.603836 | instance | [preflight] PLAY RECAP ********************************************************************* 2026-04-28 07:13:44.603852 | instance | [preflight] instance : ok=0 changed=0 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-04-28 07:13:44.603863 | instance | [preflight] 2026-04-28 07:13:44.658772 | instance | ==> Preflight checks passed 2026-04-28 07:13:44.658941 | instance | ==> Starting parallel deployment (subgraph) 2026-04-28 07:13:44.659075 | instance | ==> [ceph] Starting deployment 2026-04-28 07:13:44.659082 | instance | ==> [kubernetes] Starting deployment 2026-04-28 07:13:45.394657 | instance | [ceph/ceph] 2026-04-28 07:13:45.394728 | instance | [ceph/ceph] PLAY [all] ********************************************************************* 2026-04-28 07:13:45.394740 | instance | [ceph/ceph] 2026-04-28 07:13:45.394750 | instance | [ceph/ceph] TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:45.441611 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:45.441639 | instance | [kubernetes/kubernetes] PLAY [all] ********************************************************************* 2026-04-28 07:13:45.441650 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:45.441654 | instance | [kubernetes/kubernetes] TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:46.746489 | instance | [ceph/ceph] [WARNING]: Platform linux on host instance is using the discovered Python 2026-04-28 07:13:46.746536 | instance | [ceph/ceph] interpreter at /usr/bin/python3.10, but future installation of another Python 2026-04-28 07:13:46.746548 | instance | [ceph/ceph] interpreter could change the meaning of that path. See 2026-04-28 07:13:46.746558 | instance | [ceph/ceph] https://docs.ansible.com/ansible- 2026-04-28 07:13:46.746567 | instance | [ceph/ceph] core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-04-28 07:13:46.755068 | instance | [ceph/ceph] ok: [instance] 2026-04-28 07:13:46.755101 | instance | [ceph/ceph] 2026-04-28 07:13:46.755113 | instance | [ceph/ceph] TASK [Fail if atmosphere_ceph_enabled is set] ********************************** 2026-04-28 07:13:46.778579 | instance | [kubernetes/kubernetes] [WARNING]: Platform linux on host instance is using the discovered Python 2026-04-28 07:13:46.778823 | instance | [kubernetes/kubernetes] interpreter at /usr/bin/python3.10, but future installation of another Python 2026-04-28 07:13:46.778838 | instance | [kubernetes/kubernetes] interpreter could change the meaning of that path. See 2026-04-28 07:13:46.778852 | instance | [kubernetes/kubernetes] https://docs.ansible.com/ansible- 2026-04-28 07:13:46.778878 | instance | [kubernetes/kubernetes] core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-04-28 07:13:46.792060 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:46.792094 | instance | [ceph/ceph] 2026-04-28 07:13:46.792106 | instance | [ceph/ceph] TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-04-28 07:13:46.792542 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:46.792578 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:46.792591 | instance | [kubernetes/kubernetes] TASK [vexxhost.atmosphere.sysctl : Configure sysctl values] ******************** 2026-04-28 07:13:46.962067 | instance | [ceph/ceph] ok: [instance] 2026-04-28 07:13:46.962108 | instance | [ceph/ceph] 2026-04-28 07:13:46.962120 | instance | [ceph/ceph] PLAY [Deploy Ceph monitors & managers] ***************************************** 2026-04-28 07:13:46.962129 | instance | [ceph/ceph] 2026-04-28 07:13:46.962138 | instance | [ceph/ceph] TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:47.950087 | instance | [ceph/ceph] ok: [instance] 2026-04-28 07:13:47.950171 | instance | [ceph/ceph] 2026-04-28 07:13:47.950183 | instance | [ceph/ceph] TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-28 07:13:48.348599 | instance | [ceph/ceph] ok: [instance] 2026-04-28 07:13:48.348673 | instance | [ceph/ceph] 2026-04-28 07:13:48.348687 | instance | [ceph/ceph] TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 07:13:48.386675 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:48.386706 | instance | [ceph/ceph] 2026-04-28 07:13:48.386716 | instance | [ceph/ceph] TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-28 07:13:48.803790 | instance | [ceph/ceph] changed: [instance] 2026-04-28 07:13:48.803829 | instance | [ceph/ceph] 2026-04-28 07:13:48.803839 | instance | [ceph/ceph] TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-28 07:13:48.866987 | instance | [ceph/ceph] ok: [instance] => { 2026-04-28 07:13:48.867049 | instance | [ceph/ceph] "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-04-28 07:13:48.867062 | instance | [ceph/ceph] } 2026-04-28 07:13:48.867072 | instance | [ceph/ceph] 2026-04-28 07:13:48.867082 | instance | [ceph/ceph] TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-28 07:13:49.729311 | instance | [ceph/ceph] changed: [instance] 2026-04-28 07:13:49.729363 | instance | [ceph/ceph] 2026-04-28 07:13:49.729372 | instance | [ceph/ceph] TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-28 07:13:49.777245 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:49.777305 | instance | [ceph/ceph] 2026-04-28 07:13:49.777317 | instance | [ceph/ceph] TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 07:13:49.828938 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:49.828973 | instance | [ceph/ceph] 2026-04-28 07:13:49.828982 | instance | [ceph/ceph] TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-28 07:13:50.138133 | instance | [ceph/ceph] ok: [instance] 2026-04-28 07:13:50.138197 | instance | [ceph/ceph] 2026-04-28 07:13:50.138208 | instance | [ceph/ceph] TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 07:13:51.467806 | instance | [ceph/ceph] ok: [instance] 2026-04-28 07:13:51.467873 | instance | [ceph/ceph] 2026-04-28 07:13:51.467885 | instance | [ceph/ceph] TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-28 07:13:51.529166 | instance | [ceph/ceph] ok: [instance] => { 2026-04-28 07:13:51.529204 | instance | [ceph/ceph] "msg": "https://github.com/containerd/containerd/releases/download/v2.2.3/containerd-2.2.3-linux-amd64.tar.gz" 2026-04-28 07:13:51.529231 | instance | [ceph/ceph] } 2026-04-28 07:13:51.529241 | instance | [ceph/ceph] 2026-04-28 07:13:51.529250 | instance | [ceph/ceph] TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-28 07:13:52.223807 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'fs.aio-max-nr', 'value': 1048576}) 2026-04-28 07:13:52.223880 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.tcp_timestamps', 'value': 0}) 2026-04-28 07:13:52.223892 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.tcp_sack', 'value': 1}) 2026-04-28 07:13:52.223902 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.netdev_budget', 'value': 1000}) 2026-04-28 07:13:52.223922 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.netdev_max_backlog', 'value': 250000}) 2026-04-28 07:13:52.223931 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.rmem_max', 'value': 4194304}) 2026-04-28 07:13:52.223940 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.wmem_max', 'value': 4194304}) 2026-04-28 07:13:52.223949 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.rmem_default', 'value': 4194304}) 2026-04-28 07:13:52.223957 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.wmem_default', 'value': 4194304}) 2026-04-28 07:13:52.223966 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.core.optmem_max', 'value': 4194304}) 2026-04-28 07:13:52.223975 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.tcp_rmem', 'value': '4096 87380 4194304'}) 2026-04-28 07:13:52.223995 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.tcp_wmem', 'value': '4096 65536 4194304'}) 2026-04-28 07:13:52.224004 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.tcp_low_latency', 'value': 1}) 2026-04-28 07:13:52.224012 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.tcp_adv_win_scale', 'value': 1}) 2026-04-28 07:13:52.224021 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.neigh.default.gc_thresh1', 'value': 128}) 2026-04-28 07:13:52.224029 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.neigh.default.gc_thresh2', 'value': 28872}) 2026-04-28 07:13:52.224038 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv4.neigh.default.gc_thresh3', 'value': 32768}) 2026-04-28 07:13:52.224047 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv6.neigh.default.gc_thresh1', 'value': 128}) 2026-04-28 07:13:52.224056 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv6.neigh.default.gc_thresh2', 'value': 28872}) 2026-04-28 07:13:52.224064 | instance | [kubernetes/kubernetes] changed: [instance] => (item={'name': 'net.ipv6.neigh.default.gc_thresh3', 'value': 32768}) 2026-04-28 07:13:52.224073 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:52.224083 | instance | [kubernetes/kubernetes] TASK [vexxhost.atmosphere.ethtool : Create folder for persistent configuration] *** 2026-04-28 07:13:52.406997 | instance | [ceph/ceph] changed: [instance] 2026-04-28 07:13:52.407051 | instance | [ceph/ceph] 2026-04-28 07:13:52.407059 | instance | [ceph/ceph] TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-28 07:13:52.604105 | instance | [kubernetes/kubernetes] changed: [instance] 2026-04-28 07:13:52.604150 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:52.604159 | instance | [kubernetes/kubernetes] TASK [vexxhost.atmosphere.ethtool : Install persistent "ethtool" tuning] ******* 2026-04-28 07:13:53.291273 | instance | [kubernetes/kubernetes] changed: [instance] 2026-04-28 07:13:53.291372 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:53.291391 | instance | [kubernetes/kubernetes] TASK [vexxhost.atmosphere.ethtool : Run "ethtool" tuning] ********************** 2026-04-28 07:13:53.705855 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:53.705905 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:53.705917 | instance | [kubernetes/kubernetes] TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-04-28 07:13:53.834621 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:53.834653 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:53.834664 | instance | [kubernetes/kubernetes] PLAY [Configure Kubernetes VIP] ************************************************ 2026-04-28 07:13:53.834673 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:53.834682 | instance | [kubernetes/kubernetes] TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:54.753851 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:54.753889 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:54.753895 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.directory : Create directory (/etc/kubernetes/manifests)] *** 2026-04-28 07:13:55.027721 | instance | [kubernetes/kubernetes] changed: [instance] 2026-04-28 07:13:55.027771 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:55.027784 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack] **************** 2026-04-28 07:13:55.367640 | instance | [ceph/ceph] changed: [instance] 2026-04-28 07:13:55.367698 | instance | [ceph/ceph] 2026-04-28 07:13:55.367711 | instance | [ceph/ceph] TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-04-28 07:13:55.397867 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:55.397883 | instance | [ceph/ceph] 2026-04-28 07:13:55.397888 | instance | [ceph/ceph] TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-04-28 07:13:55.430040 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:55.430087 | instance | [ceph/ceph] 2026-04-28 07:13:55.430111 | instance | [ceph/ceph] TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-04-28 07:13:55.460546 | instance | [ceph/ceph] skipping: [instance] 2026-04-28 07:13:55.460808 | instance | [ceph/ceph] 2026-04-28 07:13:55.460821 | instance | [ceph/ceph] TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-04-28 07:13:56.389536 | instance | [kubernetes/kubernetes] ok: [instance] => (item=/etc/keepalived/keepalived.conf) 2026-04-28 07:13:56.389587 | instance | [kubernetes/kubernetes] ok: [instance] => (item=/etc/keepalived/check_apiserver.sh) 2026-04-28 07:13:56.389599 | instance | [kubernetes/kubernetes] ok: [instance] => (item=/etc/kubernetes/manifests/keepalived.yaml) 2026-04-28 07:13:56.389608 | instance | [kubernetes/kubernetes] ok: [instance] => (item=/etc/haproxy/haproxy.cfg) 2026-04-28 07:13:56.389618 | instance | [kubernetes/kubernetes] ok: [instance] => (item=/etc/kubernetes/manifests/haproxy.yaml) 2026-04-28 07:13:56.389626 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:56.389636 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Switch API server to run on port 6443] **** 2026-04-28 07:13:57.286037 | instance | [kubernetes/kubernetes] failed: [instance] (item=/etc/kubernetes/manifests/kube-apiserver.yaml) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/manifests/kube-apiserver.yaml", "msg": "Path /etc/kubernetes/manifests/kube-apiserver.yaml does not exist !", "rc": 257} 2026-04-28 07:13:57.286099 | instance | [kubernetes/kubernetes] failed: [instance] (item=/etc/kubernetes/controller-manager.conf) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/controller-manager.conf", "msg": "Path /etc/kubernetes/controller-manager.conf does not exist !", "rc": 257} 2026-04-28 07:13:57.286115 | instance | [kubernetes/kubernetes] failed: [instance] (item=/etc/kubernetes/scheduler.conf) => {"ansible_loop_var": "item", "changed": false, "item": "/etc/kubernetes/scheduler.conf", "msg": "Path /etc/kubernetes/scheduler.conf does not exist !", "rc": 257} 2026-04-28 07:13:57.286124 | instance | [kubernetes/kubernetes] ...ignoring 2026-04-28 07:13:57.286135 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:57.286144 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Check if super-admin.conf exists] ********* 2026-04-28 07:13:57.565630 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:57.565679 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:57.565692 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Check if kubeadm has already run] ********* 2026-04-28 07:13:57.839721 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:57.839766 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:57.839774 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path] ************ 2026-04-28 07:13:57.863814 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:57.863867 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:57.863881 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path (with super-admin.conf)] *** 2026-04-28 07:13:57.893241 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:57.893273 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:57.893284 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Upload Kubernetes manifest] *************** 2026-04-28 07:13:58.525217 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:58.525269 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:58.525281 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Ensure kube-vip configuration file] ******* 2026-04-28 07:13:58.881456 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:58.881521 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:58.881533 | instance | [kubernetes/kubernetes] TASK [vexxhost.kubernetes.kube_vip : Flush handlers] *************************** 2026-04-28 07:13:58.881543 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:58.881552 | instance | [kubernetes/kubernetes] PLAY [Install Kubernetes] ****************************************************** 2026-04-28 07:13:58.881561 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:58.881570 | instance | [kubernetes/kubernetes] TASK [Gathering Facts] ********************************************************* 2026-04-28 07:13:59.871426 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:13:59.871513 | instance | [kubernetes/kubernetes] 2026-04-28 07:13:59.871528 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-28 07:14:00.174254 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:00.174344 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:00.174362 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 07:14:00.212637 | instance | [kubernetes/kubernetes] skipping: [instance] 2026-04-28 07:14:00.212714 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:00.212727 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-04-28 07:14:00.585532 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:00.585604 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:00.585618 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-28 07:14:00.635885 | instance | [kubernetes/kubernetes] ok: [instance] => { 2026-04-28 07:14:00.635919 | instance | [kubernetes/kubernetes] "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-04-28 07:14:00.635931 | instance | [kubernetes/kubernetes] } 2026-04-28 07:14:00.635941 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:00.635950 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-28 07:14:01.105794 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:01.105850 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:01.105863 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-28 07:14:01.144659 | instance | [kubernetes/kubernetes] skipping: [instance] 2026-04-28 07:14:01.144693 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:01.144704 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-04-28 07:14:01.441063 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:01.441113 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:01.441121 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-04-28 07:14:02.602934 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:02.602987 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:02.602999 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-04-28 07:14:02.663333 | instance | [kubernetes/kubernetes] ok: [instance] => { 2026-04-28 07:14:02.663368 | instance | [kubernetes/kubernetes] "msg": "https://github.com/containerd/containerd/releases/download/v2.2.3/containerd-2.2.3-linux-amd64.tar.gz" 2026-04-28 07:14:02.663379 | instance | [kubernetes/kubernetes] } 2026-04-28 07:14:02.663389 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:02.663398 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-04-28 07:14:03.089902 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:03.089961 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:03.089975 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-04-28 07:14:05.181726 | instance | [kubernetes/kubernetes] ok: [instance] 2026-04-28 07:14:05.181803 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:05.181816 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-04-28 07:14:05.209143 | instance | [kubernetes/kubernetes] skipping: [instance] 2026-04-28 07:14:05.209175 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:05.209185 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-04-28 07:14:05.236739 | instance | [kubernetes/kubernetes] skipping: [instance] 2026-04-28 07:14:05.236770 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:05.236781 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-04-28 07:14:05.268092 | instance | [kubernetes/kubernetes] skipping: [instance] 2026-04-28 07:14:05.268129 | instance | [kubernetes/kubernetes] 2026-04-28 07:14:05.268143 | instance | [kubernetes/kubernetes] TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-04-28 07:15:01.860305 | instance | [kubernetes/kubernetes] FAILED - RETRYING: [instance]: Install AppArmor packages (5 retries left). 2026-04-28 07:15:01.860357 | instance | [kubernetes/kubernetes] FAILED - RETRYING: [instance]: Install AppArmor packages (4 retries left). 2026-04-28 07:15:01.860364 | instance | [kubernetes/kubernetes] FAILED - RETRYING: [instance]: Install AppArmor packages (3 retries left). 2026-04-28 07:15:01.860370 | instance | [kubernetes/kubernetes] FAILED - RETRYING: [instance]: Install AppArmor packages (2 retries left). 2026-04-28 07:15:01.860375 | instance | [kubernetes/kubernetes] FAILED - RETRYING: [instance]: Install AppArmor packages (1 retries left). 2026-04-28 07:15:01.860394 | instance | [kubernetes/kubernetes] fatal: [instance]: FAILED! => {"attempts": 5, "cache_update_time": 1777360316, "cache_updated": false, "changed": false, "msg": "'/usr/bin/apt-get -y -o \"Dpkg::Options::=--force-confdef\" -o \"Dpkg::Options::=--force-confold\" install 'apparmor=3.0.4-2ubuntu2.5' 'apparmor-utils=3.0.4-2ubuntu2.5'' failed: E: Could not get lock /var/lib/dpkg/lock-frontend. It is held by process 11298 (apt-get)\nE: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it?\n", "rc": 100, "stderr": "E: Could not get lock /var/lib/dpkg/lock-frontend. It is held by process 11298 (apt-get)\nE: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it?\n", "stderr_lines": ["E: Could not get lock /var/lib/dpkg/lock-frontend. It is held by process 11298 (apt-get)", "E: Unable to acquire the dpkg frontend lock (/var/lib/dpkg/lock-frontend), is another process using it?"], "stdout": "", "stdout_lines": []} 2026-04-28 07:15:01.860402 | instance | [kubernetes/kubernetes] 2026-04-28 07:15:01.860409 | instance | [kubernetes/kubernetes] PLAY RECAP ********************************************************************* 2026-04-28 07:15:01.860415 | instance | [kubernetes/kubernetes] instance : ok=26 changed=4 unreachable=0 failed=1 skipped=5 rescued=0 ignored=1 2026-04-28 07:15:01.860421 | instance | [kubernetes/kubernetes] 2026-04-28 07:15:01.934209 | instance | Error: component kubernetes failed: ansible-playbook failed for kubernetes/kubernetes: exit status 2 2026-04-28 07:15:01.934364 | instance | Usage: 2026-04-28 07:15:01.934374 | instance | atmosphere deploy [flags] 2026-04-28 07:15:01.934380 | instance | 2026-04-28 07:15:01.934386 | instance | Flags: 2026-04-28 07:15:01.934392 | instance | --concurrency int Max concurrent deployments per wave (0 = unlimited) 2026-04-28 07:15:01.934398 | instance | -h, --help help for deploy 2026-04-28 07:15:01.934404 | instance | -i, --inventory string Path to Ansible inventory file (required) 2026-04-28 07:15:01.934410 | instance | -t, --tags string Comma-separated list of component tags to deploy 2026-04-28 07:15:01.934415 | instance | 2026-04-28 07:15:01.934421 | instance | component kubernetes failed: ansible-playbook failed for kubernetes/kubernetes: exit status 2 2026-04-28 07:15:02.064572 | instance | ERROR 2026-04-28 07:15:02.064748 | instance | { 2026-04-28 07:15:02.064780 | instance | "delta": "0:01:17.804323", 2026-04-28 07:15:02.064801 | instance | "end": "2026-04-28 07:15:01.935219", 2026-04-28 07:15:02.064821 | instance | "msg": "non-zero return code", 2026-04-28 07:15:02.064901 | instance | "rc": 1, 2026-04-28 07:15:02.064937 | instance | "start": "2026-04-28 07:13:44.130896" 2026-04-28 07:15:02.065122 | instance | } failure 2026-04-28 07:15:02.068738 | 2026-04-28 07:15:02.068781 | PLAY RECAP 2026-04-28 07:15:02.068826 | instance | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-28 07:15:02.068848 | 2026-04-28 07:15:02.227388 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/molecule/csi/converge.yml@main] 2026-04-28 07:15:02.234313 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-28 07:15:02.821961 | 2026-04-28 07:15:02.822345 | PLAY [all] 2026-04-28 07:15:02.837666 | 2026-04-28 07:15:02.837748 | TASK [gather-host-logs : creating directory for system status] 2026-04-28 07:15:03.207283 | instance | changed 2026-04-28 07:15:03.281127 | 2026-04-28 07:15:03.281289 | TASK [gather-host-logs : Get logs for each host] 2026-04-28 07:15:03.693948 | instance | + systemd-cgls --full --all --no-pager 2026-04-28 07:15:03.705754 | instance | + ip addr 2026-04-28 07:15:03.708144 | instance | + ip route 2026-04-28 07:15:03.709983 | instance | + lsblk 2026-04-28 07:15:03.714903 | instance | + mount 2026-04-28 07:15:03.717700 | instance | + docker images 2026-04-28 07:15:03.718228 | instance | /bin/bash: line 7: docker: command not found 2026-04-28 07:15:03.718511 | instance | + brctl show 2026-04-28 07:15:03.718937 | instance | /bin/bash: line 8: brctl: command not found 2026-04-28 07:15:03.719184 | instance | + ps aux --sort=-%mem 2026-04-28 07:15:03.731013 | instance | + dpkg -l 2026-04-28 07:15:03.741847 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-28 07:15:03.742152 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-28 07:15:03.742188 | instance | /bin/bash: line 11: docker: command not found 2026-04-28 07:15:03.742451 | instance | + '[' '!' -z '' ']' 2026-04-28 07:15:03.841215 | instance | ok: Runtime: 0:00:00.053589 2026-04-28 07:15:03.852316 | 2026-04-28 07:15:03.852428 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-28 07:15:04.487682 | instance | changed: 2026-04-28 07:15:04.487948 | instance | created directory /var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/logs/instance 2026-04-28 07:15:04.488010 | instance | cd+++++++++ system/ 2026-04-28 07:15:04.488057 | instance | >f+++++++++ system/brctl-show.txt 2026-04-28 07:15:04.488100 | instance | >f+++++++++ system/docker-images.txt 2026-04-28 07:15:04.488142 | instance | >f+++++++++ system/ip-addr.txt 2026-04-28 07:15:04.488191 | instance | >f+++++++++ system/ip-route.txt 2026-04-28 07:15:04.488236 | instance | >f+++++++++ system/lsblk.txt 2026-04-28 07:15:04.488279 | instance | >f+++++++++ system/mount.txt 2026-04-28 07:15:04.488325 | instance | >f+++++++++ system/packages.txt 2026-04-28 07:15:04.488366 | instance | >f+++++++++ system/ps.txt 2026-04-28 07:15:04.488407 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-28 07:15:04.503360 | 2026-04-28 07:15:04.503467 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-28 07:15:04.703708 | instance | changed: "values" 2026-04-28 07:15:04.867692 | instance | changed: "releases" 2026-04-28 07:15:04.886055 | 2026-04-28 07:15:04.886206 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-28 07:15:05.111886 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-28 07:15:05.441209 | instance | ok: Runtime: 0:00:00.005922 2026-04-28 07:15:05.448623 | 2026-04-28 07:15:05.448716 | TASK [helm-release-status : Downloads logs to executor] 2026-04-28 07:15:05.963704 | instance | changed: 2026-04-28 07:15:05.963906 | instance | cd+++++++++ helm/ 2026-04-28 07:15:05.963945 | instance | cd+++++++++ helm/releases/ 2026-04-28 07:15:05.963974 | instance | cd+++++++++ helm/values/ 2026-04-28 07:15:05.975162 | 2026-04-28 07:15:05.975232 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-28 07:15:06.160144 | instance | changed 2026-04-28 07:15:06.166697 | 2026-04-28 07:15:06.166790 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-28 07:15:06.387580 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-28 07:15:06.388510 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-28 07:15:06.393931 | instance | environment: line 1: kubectl: command not found 2026-04-28 07:15:06.395720 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-28 07:15:06.396562 | instance | environment: line 1: kubectl: command not found 2026-04-28 07:15:06.397300 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-28 07:15:06.398435 | instance | environment: line 1: kubectl: command not found 2026-04-28 07:15:06.399478 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-28 07:15:06.399997 | instance | environment: line 1: kubectl: command not found 2026-04-28 07:15:06.401033 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-28 07:15:06.401657 | instance | environment: line 1: kubectl: command not found 2026-04-28 07:15:06.403030 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-28 07:15:06.700084 | instance | ok: Runtime: 0:00:00.024583 2026-04-28 07:15:06.708291 | 2026-04-28 07:15:06.708390 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-28 07:15:06.907887 | instance | changed 2026-04-28 07:15:06.914147 | 2026-04-28 07:15:06.914227 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-28 07:15:07.175522 | instance | environment: line 5: kubectl: command not found 2026-04-28 07:15:07.176686 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-28 07:15:07.176722 | instance | 2026-04-28 07:15:07.177560 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-28 07:15:07.504864 | instance | ok: Runtime: 0:00:00.008954 2026-04-28 07:15:07.511227 | 2026-04-28 07:15:07.790086 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-28 07:15:08.305630 | instance | changed: 2026-04-28 07:15:08.305779 | instance | cd+++++++++ objects/ 2026-04-28 07:15:08.305807 | instance | cd+++++++++ objects/cluster/ 2026-04-28 07:15:08.305829 | instance | cd+++++++++ objects/namespaced/ 2026-04-28 07:15:08.314631 | 2026-04-28 07:15:08.314695 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-28 07:15:08.514069 | instance | changed 2026-04-28 07:15:08.524694 | 2026-04-28 07:15:08.524861 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-28 07:15:08.727161 | instance | changed 2026-04-28 07:15:08.732256 | 2026-04-28 07:15:08.732324 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-28 07:15:08.980771 | instance | environment: line 3: kubectl: command not found 2026-04-28 07:15:09.283413 | instance | ok: Runtime: 0:00:00.009399 2026-04-28 07:15:09.291265 | 2026-04-28 07:15:09.291395 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-28 07:15:09.963656 | instance | changed: 2026-04-28 07:15:09.963859 | instance | cd+++++++++ pod-logs/ 2026-04-28 07:15:09.963903 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-28 07:15:09.976546 | 2026-04-28 07:15:09.976731 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-28 07:15:10.180662 | instance | changed 2026-04-28 07:15:10.187540 | 2026-04-28 07:15:10.187638 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-28 07:15:10.388683 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-28 07:15:10.727319 | instance | ok: Runtime: 0:00:00.036408 2026-04-28 07:15:10.733434 | 2026-04-28 07:15:10.733531 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-28 07:15:10.950870 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-28 07:15:10.981063 | instance | ceph-mgr endpoints: 2026-04-28 07:15:11.284609 | instance | ok: Runtime: 0:00:00.036337 2026-04-28 07:15:11.302294 | 2026-04-28 07:15:11.302600 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-28 07:15:11.526070 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-28 07:15:12.022147 | instance | ok: Runtime: 0:00:00.036179 2026-04-28 07:15:12.027852 | 2026-04-28 07:15:12.027921 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-28 07:15:12.507254 | instance | changed: cd+++++++++ prometheus/ 2026-04-28 07:15:12.519964 | 2026-04-28 07:15:12.520071 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-28 07:15:12.712062 | instance | changed 2026-04-28 07:15:12.718711 | 2026-04-28 07:15:12.718783 | TASK [gather-selenium-data : Get selenium data] 2026-04-28 07:15:12.936337 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-28 07:15:12.937580 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-28 07:15:13.266057 | instance | ERROR 2026-04-28 07:15:13.266227 | instance | { 2026-04-28 07:15:13.266271 | instance | "delta": "0:00:00.005759", 2026-04-28 07:15:13.266302 | instance | "end": "2026-04-28 07:15:12.937841", 2026-04-28 07:15:13.266328 | instance | "msg": "non-zero return code", 2026-04-28 07:15:13.266354 | instance | "rc": 1, 2026-04-28 07:15:13.266380 | instance | "start": "2026-04-28 07:15:12.932082" 2026-04-28 07:15:13.266405 | instance | } 2026-04-28 07:15:13.266438 | instance | ERROR: Ignoring Errors 2026-04-28 07:15:13.273226 | 2026-04-28 07:15:13.273321 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-28 07:15:13.759172 | instance | changed: cd+++++++++ selenium/ 2026-04-28 07:15:13.765243 | 2026-04-28 07:15:13.765294 | PLAY RECAP 2026-04-28 07:15:13.765338 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-28 07:15:13.765362 | 2026-04-28 07:15:13.881904 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-28 07:15:13.884858 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 07:15:14.488869 | 2026-04-28 07:15:14.488995 | PLAY [all] 2026-04-28 07:15:14.501967 | 2026-04-28 07:15:14.502083 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-28 07:15:14.547452 | instance | skipping: Conditional result was False 2026-04-28 07:15:14.557257 | 2026-04-28 07:15:14.557352 | TASK [fetch-output : Set log path for single node] 2026-04-28 07:15:14.588505 | instance | ok 2026-04-28 07:15:14.594428 | 2026-04-28 07:15:14.594586 | LOOP [fetch-output : Ensure local output dirs] 2026-04-28 07:15:14.986729 | instance -> localhost | ok: "/var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/logs" 2026-04-28 07:15:15.193115 | instance -> localhost | changed: "/var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/artifacts" 2026-04-28 07:15:15.427306 | instance -> localhost | changed: "/var/lib/zuul/builds/cfa9ac09f9564801a950be8e83cc8a3b/work/docs" 2026-04-28 07:15:15.446594 | 2026-04-28 07:15:15.446732 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-28 07:15:16.388050 | instance | changed: .d..t...... ./ 2026-04-28 07:15:16.388248 | instance | changed: All items complete 2026-04-28 07:15:16.388277 | 2026-04-28 07:15:16.852270 | instance | changed: .d..t...... ./ 2026-04-28 07:15:17.311148 | instance | changed: .d..t...... ./ 2026-04-28 07:15:17.325754 | 2026-04-28 07:15:17.326384 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-28 07:15:17.864503 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008708 2026-04-28 07:15:18.162027 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007624 2026-04-28 07:15:18.172090 | 2026-04-28 07:15:18.172190 | PLAY [all] 2026-04-28 07:15:18.178081 | 2026-04-28 07:15:18.178149 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-28 07:15:18.662982 | instance | changed 2026-04-28 07:15:20.154659 | 2026-04-28 07:15:20.154765 | PLAY RECAP 2026-04-28 07:15:20.154855 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-28 07:15:20.154890 | 2026-04-28 07:15:20.276208 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-28 07:15:20.281938 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-28 07:15:20.894134 | 2026-04-28 07:15:20.894275 | PLAY [localhost] 2026-04-28 07:15:20.904498 | 2026-04-28 07:15:20.904609 | TASK [Generate Zuul manifest] 2026-04-28 07:15:20.926850 | localhost | ok 2026-04-28 07:15:20.942943 | 2026-04-28 07:15:20.943049 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-28 07:15:21.288369 | localhost | changed 2026-04-28 07:15:21.299583 | 2026-04-28 07:15:21.299726 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-28 07:15:21.341328 | localhost | ok 2026-04-28 07:15:21.351897 | 2026-04-28 07:15:21.352059 | TASK [Upload logs] 2026-04-28 07:15:21.379928 | localhost | ok 2026-04-28 07:15:21.454960 | 2026-04-28 07:15:21.455093 | TASK [Set zuul-log-path fact] 2026-04-28 07:15:21.472736 | localhost | ok 2026-04-28 07:15:21.483913 | 2026-04-28 07:15:21.483982 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-28 07:15:21.515582 | localhost | ok 2026-04-28 07:15:21.527914 | 2026-04-28 07:15:21.528225 | TASK [upload-logs : Create log directories] 2026-04-28 07:15:22.094674 | localhost | changed 2026-04-28 07:15:22.099677 | 2026-04-28 07:15:22.099753 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-28 07:15:22.505421 | localhost -> localhost | ok: Runtime: 0:00:00.006115 2026-04-28 07:15:22.512522 | 2026-04-28 07:15:22.512609 | TASK [upload-logs : Upload logs to log server] 2026-04-28 07:15:23.037563 | localhost | Output suppressed because no_log was given 2026-04-28 07:15:23.042459 | 2026-04-28 07:15:23.042549 | LOOP [upload-logs : Compress console log and json output] 2026-04-28 07:15:23.104230 | localhost | skipping: Conditional result was False 2026-04-28 07:15:23.112669 | localhost | skipping: Conditional result was False 2026-04-28 07:15:23.121074 | 2026-04-28 07:15:23.121248 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-28 07:15:23.165442 | localhost | skipping: Conditional result was False 2026-04-28 07:15:23.166219 | 2026-04-28 07:15:23.169666 | localhost | skipping: Conditional result was False 2026-04-28 07:15:23.184364 | 2026-04-28 07:15:23.184507 | LOOP [upload-logs : Upload console log and json output]