2026-07-30 20:30:56.002494 | Job console starting 2026-07-30 20:30:56.013304 | Updating git repos 2026-07-30 20:30:59.350952 | Cloning repos into workspace 2026-07-30 20:31:15.109816 | Restoring repo states 2026-07-30 20:31:24.113444 | Merging changes 2026-07-30 20:31:28.674600 | Checking out repos 2026-07-30 20:31:32.301308 | Preparing playbooks 2026-07-30 20:31:36.095595 | Running Ansible setup 2026-07-30 20:31:40.409807 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-30 20:31:41.038445 | 2026-07-30 20:31:41.038589 | PLAY [localhost] 2026-07-30 20:31:41.045925 | 2026-07-30 20:31:41.045998 | TASK [Gathering Facts] 2026-07-30 20:31:41.965097 | localhost | ok 2026-07-30 20:31:41.974133 | 2026-07-30 20:31:41.974234 | TASK [Setup log path fact] 2026-07-30 20:31:41.994458 | localhost | ok 2026-07-30 20:31:42.026346 | 2026-07-30 20:31:42.026447 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-30 20:31:42.056986 | localhost | ok 2026-07-30 20:31:42.065458 | 2026-07-30 20:31:42.065533 | TASK [emit-job-header : Print job information] 2026-07-30 20:31:42.106652 | # Job Information 2026-07-30 20:31:42.106892 | Ansible Version: 2.16.18 2026-07-30 20:31:42.106965 | Job: atmosphere-molecule-keycloak-selective 2026-07-30 20:31:42.107014 | Pipeline: check 2026-07-30 20:31:42.107059 | Executor: 2d72f0692154 2026-07-30 20:31:42.107103 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4204 2026-07-30 20:31:42.107150 | Event ID: 2987e770-8c54-11f1-9fd9-1d04e45e2a2d 2026-07-30 20:31:42.110956 | 2026-07-30 20:31:42.111046 | LOOP [emit-job-header : Print node information] 2026-07-30 20:31:42.207011 | localhost | ok: 2026-07-30 20:31:42.207825 | localhost | # Node Information 2026-07-30 20:31:42.207939 | localhost | Inventory Hostname: instance 2026-07-30 20:31:42.207987 | localhost | Hostname: np0000187604 2026-07-30 20:31:42.208040 | localhost | Username: zuul 2026-07-30 20:31:42.208102 | localhost | Distro: Ubuntu 22.04 2026-07-30 20:31:42.208144 | localhost | Provider: yul1 2026-07-30 20:31:42.208169 | localhost | Region: ca-ymq-1 2026-07-30 20:31:42.208193 | localhost | Label: ubuntu-jammy 2026-07-30 20:31:42.208216 | localhost | Product Name: OpenStack Nova 2026-07-30 20:31:42.208239 | localhost | Interface IP: 199.204.45.113 2026-07-30 20:31:42.219220 | 2026-07-30 20:31:42.219361 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-30 20:31:42.678759 | localhost -> localhost | changed 2026-07-30 20:31:42.686362 | 2026-07-30 20:31:42.686523 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-30 20:31:43.493365 | localhost -> localhost | changed 2026-07-30 20:31:43.505499 | 2026-07-30 20:31:43.505643 | PLAY [all] 2026-07-30 20:31:43.513835 | 2026-07-30 20:31:43.513944 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-30 20:31:43.726816 | instance -> localhost | ok 2026-07-30 20:31:43.737573 | 2026-07-30 20:31:43.737728 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-30 20:31:43.771814 | instance | ok 2026-07-30 20:31:43.788829 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-30 20:31:43.795154 | 2026-07-30 20:31:43.795225 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-30 20:31:44.827509 | instance -> localhost | Generating public/private rsa key pair. 2026-07-30 20:31:44.827660 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/9473b741f0db4c0aa1258ff1ddc86536_id_rsa 2026-07-30 20:31:44.827690 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/9473b741f0db4c0aa1258ff1ddc86536_id_rsa.pub 2026-07-30 20:31:44.827713 | instance -> localhost | The key fingerprint is: 2026-07-30 20:31:44.827734 | instance -> localhost | SHA256:6mQ52vay6m6IQI8JZNaAZlPdKfcnN8/DJTbxmOrkJas zuul-build-sshkey 2026-07-30 20:31:44.827767 | instance -> localhost | The key's randomart image is: 2026-07-30 20:31:44.827788 | instance -> localhost | +---[RSA 3072]----+ 2026-07-30 20:31:44.827814 | instance -> localhost | |..+.. . . | 2026-07-30 20:31:44.827836 | instance -> localhost | |.B . o + . | 2026-07-30 20:31:44.827856 | instance -> localhost | |* . o . = | 2026-07-30 20:31:44.827876 | instance -> localhost | |.. o + * o | 2026-07-30 20:31:44.827896 | instance -> localhost | |o + S + B + | 2026-07-30 20:31:44.827916 | instance -> localhost | |.o . o + * | 2026-07-30 20:31:44.827937 | instance -> localhost | |.. . * + + . | 2026-07-30 20:31:44.827957 | instance -> localhost | |. . .*o. + | 2026-07-30 20:31:44.827979 | instance -> localhost | | +=o++.E. | 2026-07-30 20:31:44.828000 | instance -> localhost | +----[SHA256]-----+ 2026-07-30 20:31:44.828052 | instance -> localhost | ok: Runtime: 0:00:00.624915 2026-07-30 20:31:44.833627 | 2026-07-30 20:31:44.833718 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-30 20:31:44.866675 | instance | ok 2026-07-30 20:31:44.878755 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-30 20:31:44.888552 | 2026-07-30 20:31:44.888671 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-30 20:31:44.914509 | instance | skipping: Conditional result was False 2026-07-30 20:31:44.925341 | 2026-07-30 20:31:44.925540 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-30 20:31:45.354371 | instance | changed 2026-07-30 20:31:45.360152 | 2026-07-30 20:31:45.360272 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-30 20:31:45.543907 | instance | ok 2026-07-30 20:31:45.549885 | 2026-07-30 20:31:45.549981 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-30 20:31:46.034782 | instance | changed 2026-07-30 20:31:46.043953 | 2026-07-30 20:31:46.044061 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-30 20:31:46.503460 | instance | changed 2026-07-30 20:31:46.509564 | 2026-07-30 20:31:46.509659 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-30 20:31:46.534160 | instance | skipping: Conditional result was False 2026-07-30 20:31:46.544666 | 2026-07-30 20:31:46.544804 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-30 20:31:46.951953 | instance -> localhost | changed 2026-07-30 20:31:46.965022 | 2026-07-30 20:31:46.965090 | TASK [add-build-sshkey : Add back temp key] 2026-07-30 20:31:47.232641 | instance -> localhost | Identity added: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/9473b741f0db4c0aa1258ff1ddc86536_id_rsa (zuul-build-sshkey) 2026-07-30 20:31:47.232847 | instance -> localhost | ok: Runtime: 0:00:00.014607 2026-07-30 20:31:47.239626 | 2026-07-30 20:31:47.239688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-30 20:31:47.537493 | instance | ok 2026-07-30 20:31:47.543841 | 2026-07-30 20:31:47.543906 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-30 20:31:47.568162 | instance | skipping: Conditional result was False 2026-07-30 20:31:47.623705 | 2026-07-30 20:31:47.623817 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-07-30 20:31:47.900523 | instance | ok 2026-07-30 20:31:47.907469 | 2026-07-30 20:31:47.907543 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-07-30 20:31:51.032272 | instance | Output suppressed because no_log was given 2026-07-30 20:31:51.042735 | 2026-07-30 20:31:51.042815 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-07-30 20:31:51.224843 | instance | ok: "logs" 2026-07-30 20:31:51.225178 | instance | ok: All items complete 2026-07-30 20:31:51.225208 | 2026-07-30 20:31:51.377220 | instance | ok: "artifacts" 2026-07-30 20:31:51.536223 | instance | ok: "docs" 2026-07-30 20:31:51.554601 | 2026-07-30 20:31:51.554717 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-07-30 20:31:51.750629 | instance | changed: "logs" 2026-07-30 20:31:51.914676 | instance | changed: "artifacts" 2026-07-30 20:31:52.087149 | instance | changed: "docs" 2026-07-30 20:31:52.107332 | 2026-07-30 20:31:52.107456 | PLAY RECAP 2026-07-30 20:31:52.107511 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-07-30 20:31:52.107541 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-30 20:31:52.107563 | 2026-07-30 20:31:52.234249 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-07-30 20:31:52.239530 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-30 20:31:52.836922 | 2026-07-30 20:31:52.837157 | PLAY [all] 2026-07-30 20:31:52.851372 | 2026-07-30 20:31:52.851486 | TASK [setup-uv : Extract archive] 2026-07-30 20:31:55.164153 | instance | changed 2026-07-30 20:31:55.171480 | 2026-07-30 20:31:55.171567 | TASK [setup-uv : Print version] 2026-07-30 20:31:55.524910 | instance | uv 0.8.13 2026-07-30 20:31:55.711646 | instance | ok: Runtime: 0:00:00.012532 2026-07-30 20:31:55.721204 | 2026-07-30 20:31:55.721281 | TASK [Install Ansible collection siblings] 2026-07-30 20:32:04.974359 | instance | changed: 2026-07-30 20:32:04.974779 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon 2026-07-30 20:32:04.974813 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix 2026-07-30 20:32:04.974839 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils 2026-07-30 20:32:04.974860 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto 2026-07-30 20:32:04.974880 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general 2026-07-30 20:32:04.974904 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql 2026-07-30 20:32:04.974926 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core 2026-07-30 20:32:04.974949 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph 2026-07-30 20:32:04.974969 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers 2026-07-30 20:32:04.974988 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes 2026-07-30 20:32:04.975007 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common 2026-07-30 20:32:04.975026 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack 2026-07-30 20:32:04.975045 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0 2026-07-30 20:32:04.975063 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0 2026-07-30 20:32:04.975082 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0 2026-07-30 20:32:04.975101 | instance | Resolved community.crypto to 2.2.3 for 2.2.3 2026-07-30 20:32:04.975121 | instance | Resolved community.general to 7.3.0 for 7.3.0 2026-07-30 20:32:04.975142 | instance | Resolved community.mysql to 3.6.0 for 3.6.0 2026-07-30 20:32:04.975163 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0 2026-07-30 20:32:04.975183 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0 2026-07-30 20:32:04.975202 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0 2026-07-30 20:32:04.975220 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0 2026-07-30 20:32:04.975241 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-07-30 20:32:04.975270 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0 2026-07-30 20:32:04.975291 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0 2026-07-30 20:32:04.975311 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-07-30 20:32:04.975331 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-07-30 20:32:04.975351 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0 2026-07-30 20:32:04.975370 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0 2026-07-30 20:32:04.975389 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3 2026-07-30 20:32:04.975407 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2 2026-07-30 20:32:04.975427 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0 2026-07-30 20:32:04.975446 | instance | Checked out ansible.netcommon to 1.2.0 2026-07-30 20:32:04.975465 | 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-07-30 20:32:04.975489 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.975508 | instance | Process install dependency map 2026-07-30 20:32:04.975527 | instance | Starting collection install process 2026-07-30 20:32:04.975546 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-07-30 20:32:04.975565 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon 2026-07-30 20:32:04.975584 | instance | ansible.netcommon:* was installed successfully 2026-07-30 20:32:04.975602 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-07-30 20:32:04.975621 | instance | Creating virtual environment at: .venv 2026-07-30 20:32:04.975642 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-07-30 20:32:04.975663 | instance | Downloading openstacksdk (1.7MiB) 2026-07-30 20:32:04.975683 | instance | Downloading netaddr (2.2MiB) 2026-07-30 20:32:04.975707 | instance | Downloading rjsonnet (1.2MiB) 2026-07-30 20:32:04.975726 | instance | Downloading pygments (1.2MiB) 2026-07-30 20:32:04.975746 | instance | Downloading kubernetes (1.9MiB) 2026-07-30 20:32:04.975767 | instance | Downloading setuptools (1.1MiB) 2026-07-30 20:32:04.975787 | instance | Downloading cryptography (4.2MiB) 2026-07-30 20:32:04.975806 | instance | Downloading pydantic-core (2.0MiB) 2026-07-30 20:32:04.975827 | instance | Downloading ansible-core (2.1MiB) 2026-07-30 20:32:04.975846 | instance | Building pyperclip==1.9.0 2026-07-30 20:32:04.975867 | instance | Downloading rjsonnet 2026-07-30 20:32:04.975886 | instance | Downloading pydantic-core 2026-07-30 20:32:04.975905 | instance | Downloading netaddr 2026-07-30 20:32:04.975923 | instance | Downloading pygments 2026-07-30 20:32:04.975942 | instance | Downloading cryptography 2026-07-30 20:32:04.975960 | instance | Downloading setuptools 2026-07-30 20:32:04.975979 | instance | Downloading kubernetes 2026-07-30 20:32:04.975998 | instance | Downloading ansible-core 2026-07-30 20:32:04.976016 | instance | Downloading openstacksdk 2026-07-30 20:32:04.976035 | instance | Built pyperclip==1.9.0 2026-07-30 20:32:04.976053 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-07-30 20:32:04.976074 | instance | Installed 87 packages in 41ms 2026-07-30 20:32:04.976092 | instance | Checked out ansible.posix to 1.6.0 2026-07-30 20:32:04.976110 | 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-07-30 20:32:04.976129 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.976147 | instance | Process install dependency map 2026-07-30 20:32:04.976170 | instance | Starting collection install process 2026-07-30 20:32:04.976189 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-07-30 20:32:04.976208 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix 2026-07-30 20:32:04.976226 | instance | ansible.posix:1.6.0 was installed successfully 2026-07-30 20:32:04.976244 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.976262 | instance | 2.17.12 2026-07-30 20:32:04.976280 | instance | Checked out ansible.utils to v6.0.3 2026-07-30 20:32:04.976299 | 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-07-30 20:32:04.976317 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.976336 | instance | Process install dependency map 2026-07-30 20:32:04.976355 | instance | Starting collection install process 2026-07-30 20:32:04.976375 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-07-30 20:32:04.976394 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils 2026-07-30 20:32:04.976413 | instance | ansible.utils:6.0.3 was installed successfully 2026-07-30 20:32:04.976431 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.976449 | instance | 2.17.12 2026-07-30 20:32:04.976467 | instance | Checked out atmosphere.common to v0.7.0 2026-07-30 20:32:04.976485 | 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-07-30 20:32:04.976504 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.976521 | instance | Process install dependency map 2026-07-30 20:32:04.976539 | instance | Starting collection install process 2026-07-30 20:32:04.976557 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common' 2026-07-30 20:32:04.976610 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common 2026-07-30 20:32:04.976632 | instance | atmosphere.common:0.7.0 was installed successfully 2026-07-30 20:32:04.976650 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.976668 | instance | 2.17.12 2026-07-30 20:32:04.976686 | instance | Checked out community.crypto to 2.2.3 2026-07-30 20:32:04.976704 | 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-07-30 20:32:04.976722 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.976739 | instance | Process install dependency map 2026-07-30 20:32:04.976757 | instance | Starting collection install process 2026-07-30 20:32:04.976775 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-07-30 20:32:04.976793 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto 2026-07-30 20:32:04.976811 | instance | community.crypto:2.2.3 was installed successfully 2026-07-30 20:32:04.976829 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.976846 | instance | 2.17.12 2026-07-30 20:32:04.976865 | instance | Checked out community.general to 7.3.0 2026-07-30 20:32:04.976883 | 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-07-30 20:32:04.976906 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.976928 | instance | Process install dependency map 2026-07-30 20:32:04.976949 | instance | Starting collection install process 2026-07-30 20:32:04.976994 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-07-30 20:32:04.977020 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general 2026-07-30 20:32:04.977040 | instance | community.general:7.3.0 was installed successfully 2026-07-30 20:32:04.977059 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.977080 | instance | 2.17.12 2026-07-30 20:32:04.977100 | instance | Checked out community.mysql to 3.6.0 2026-07-30 20:32:04.977121 | 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-07-30 20:32:04.977140 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.977159 | instance | Process install dependency map 2026-07-30 20:32:04.977178 | instance | Starting collection install process 2026-07-30 20:32:04.977196 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-07-30 20:32:04.977215 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql 2026-07-30 20:32:04.977234 | instance | community.mysql:3.6.0 was installed successfully 2026-07-30 20:32:04.977253 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.977272 | instance | 2.17.12 2026-07-30 20:32:04.977292 | instance | Checked out kubernetes.core to 2.4.0 2026-07-30 20:32:04.977311 | 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-07-30 20:32:04.977330 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.977349 | instance | Process install dependency map 2026-07-30 20:32:04.977369 | instance | Starting collection install process 2026-07-30 20:32:04.977389 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-07-30 20:32:04.977408 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core 2026-07-30 20:32:04.977427 | instance | kubernetes.core:2.4.0 was installed successfully 2026-07-30 20:32:04.977447 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.977466 | instance | 2.17.12 2026-07-30 20:32:04.977485 | instance | Checked out openstack.cloud to 2.6.0 2026-07-30 20:32:04.977505 | 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-07-30 20:32:04.977525 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.977545 | instance | Process install dependency map 2026-07-30 20:32:04.977565 | instance | Starting collection install process 2026-07-30 20:32:04.977585 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-07-30 20:32:04.977605 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud 2026-07-30 20:32:04.977624 | instance | openstack.cloud:2.6.0 was installed successfully 2026-07-30 20:32:04.977643 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.977662 | instance | 2.17.12 2026-07-30 20:32:04.977685 | instance | Checked out vexxhost.ceph to v4.1.0 2026-07-30 20:32:04.977705 | 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-07-30 20:32:04.977723 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.977743 | instance | Process install dependency map 2026-07-30 20:32:04.977763 | instance | Starting collection install process 2026-07-30 20:32:04.977782 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-07-30 20:32:04.977801 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph 2026-07-30 20:32:04.977820 | instance | vexxhost.ceph:4.1.0 was installed successfully 2026-07-30 20:32:04.977839 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.977857 | instance | 2.17.12 2026-07-30 20:32:04.977875 | instance | Checked out vexxhost.containers to v1.6.8 2026-07-30 20:32:04.977894 | 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-07-30 20:32:04.977912 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.977934 | instance | Process install dependency map 2026-07-30 20:32:04.977953 | instance | Starting collection install process 2026-07-30 20:32:04.977972 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-07-30 20:32:04.977991 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers 2026-07-30 20:32:04.978009 | instance | vexxhost.containers:1.6.8 was installed successfully 2026-07-30 20:32:04.978028 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.978046 | instance | 2.17.12 2026-07-30 20:32:04.978065 | instance | Checked out vexxhost.kubernetes to v3.3.0 2026-07-30 20:32:04.978083 | 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-07-30 20:32:04.978102 | instance | Starting galaxy collection install process 2026-07-30 20:32:04.978120 | instance | Process install dependency map 2026-07-30 20:32:04.978139 | instance | Starting collection install process 2026-07-30 20:32:04.978158 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes' 2026-07-30 20:32:04.978177 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes 2026-07-30 20:32:04.978196 | instance | vexxhost.kubernetes:3.3.0 was installed successfully 2026-07-30 20:32:04.978215 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-07-30 20:32:04.978233 | instance | 2.17.12 2026-07-30 20:32:04.983710 | 2026-07-30 20:32:04.983782 | PLAY RECAP 2026-07-30 20:32:04.983903 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-30 20:32:04.983930 | 2026-07-30 20:32:05.100558 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-07-30 20:32:05.103312 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-07-30 20:32:05.716794 | 2026-07-30 20:32:05.716922 | PLAY [all] 2026-07-30 20:32:05.727931 | 2026-07-30 20:32:05.728004 | TASK [Install "jq" for log collection] 2026-07-30 20:32:21.802367 | instance | changed 2026-07-30 20:32:21.805667 | 2026-07-30 20:32:21.805724 | PLAY RECAP 2026-07-30 20:32:21.805775 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-30 20:32:21.805832 | 2026-07-30 20:32:21.927253 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main] 2026-07-30 20:32:21.934613 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main] 2026-07-30 20:32:22.577535 | 2026-07-30 20:32:22.577677 | PLAY [Prepare selective Molecule plan] 2026-07-30 20:32:22.590014 | 2026-07-30 20:32:22.590154 | TASK [Generate selective Molecule plan] 2026-07-30 20:32:23.628544 | instance | ok: Runtime: 0:00:00.198619 2026-07-30 20:32:23.637224 | 2026-07-30 20:32:23.637327 | TASK [Render selective Molecule plan] 2026-07-30 20:32:23.919928 | instance | Selective Molecule plan: selective 2026-07-30 20:32:23.920182 | instance | Changed files: 2026-07-30 20:32:23.920197 | instance | M playbooks/ceph.yml 2026-07-30 20:32:23.920210 | instance | A roles/ethtool/SELECTIVE_CI_GROUP_VALIDATION.md 2026-07-30 20:32:23.920221 | instance | A roles/keycloak/SELECTIVE_CI_GROUP_VALIDATION.md 2026-07-30 20:32:23.920234 | instance | Targets: ceph, keycloak, kubernetes 2026-07-30 20:32:23.920244 | instance | Job decisions: 2026-07-30 20:32:23.920256 | instance | RUN aio-openvswitch: selected by changed components 2026-07-30 20:32:23.920266 | instance | components: ceph, kubernetes 2026-07-30 20:32:23.920276 | instance | verification: host-command:cephadm shell -- ceph status, kubernetes-node:Ready 2026-07-30 20:32:23.920287 | instance | RUN aio-ovn: selected by changed components 2026-07-30 20:32:23.920297 | instance | components: kubernetes 2026-07-30 20:32:23.920307 | instance | verification: kubernetes-node:Ready 2026-07-30 20:32:23.920316 | instance | RUN csi-local-path-provisioner: selected by changed components 2026-07-30 20:32:23.920325 | instance | RUN csi-rbd: selected by changed components 2026-07-30 20:32:23.920334 | instance | RUN keycloak: selected by changed components 2026-07-30 20:32:24.184412 | instance | ok: Runtime: 0:00:00.100441 2026-07-30 20:32:24.186001 | 2026-07-30 20:32:24.186049 | PLAY RECAP 2026-07-30 20:32:24.186093 | instance | ok: 2 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-30 20:32:24.186116 | 2026-07-30 20:32:24.280552 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main] 2026-07-30 20:32:24.284451 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main] 2026-07-30 20:32:24.926155 | 2026-07-30 20:32:24.926288 | PLAY [Run selected Molecule scenario] 2026-07-30 20:32:24.938048 | 2026-07-30 20:32:24.938141 | TASK [Read selective Molecule plan] 2026-07-30 20:32:25.244862 | instance | ok 2026-07-30 20:32:25.249619 | 2026-07-30 20:32:25.249708 | TASK [Resolve this job decision] 2026-07-30 20:32:25.318126 | instance | ok 2026-07-30 20:32:25.329678 | 2026-07-30 20:32:25.329755 | TASK [Assert this job exists in the plan] 2026-07-30 20:32:25.364572 | instance | ok: All assertions passed 2026-07-30 20:32:25.372555 | 2026-07-30 20:32:25.372645 | TASK [Resolve selected scenario inputs] 2026-07-30 20:32:25.412364 | instance | ok 2026-07-30 20:32:25.421072 | 2026-07-30 20:32:25.421165 | TASK [Report this job decision] 2026-07-30 20:32:25.473030 | instance | ok: 2026-07-30 20:32:25.473214 | instance | Selective Molecule job: keycloak 2026-07-30 20:32:25.473253 | instance | Decision: RUN 2026-07-30 20:32:25.473286 | instance | Reason: selected by changed components 2026-07-30 20:32:25.473317 | instance | Scenario: keycloak 2026-07-30 20:32:25.473345 | instance | Targets: keycloak, kubernetes 2026-07-30 20:32:25.473372 | instance | Components: (full scenario) 2026-07-30 20:32:25.479075 | 2026-07-30 20:32:25.479161 | LOOP [Check Molecule inventory directories] 2026-07-30 20:32:25.820936 | instance | ok: "src/github.com/vexxhost/atmosphere" 2026-07-30 20:32:25.970525 | instance | ok: "src/github.com/vexxhost/atmosphere/molecule/keycloak/inventory" 2026-07-30 20:32:26.124071 | instance | ok: "src/github.com/vexxhost/atmosphere/extensions/molecule/keycloak/inventory" 2026-07-30 20:32:26.134353 | 2026-07-30 20:32:26.134464 | LOOP [Copy Zuul inventory for Molecule] 2026-07-30 20:32:26.745325 | instance | changed: 2026-07-30 20:32:26.745610 | instance | { 2026-07-30 20:32:26.745672 | instance | "ansible_loop_var": "item", 2026-07-30 20:32:26.745703 | instance | "changed": false, 2026-07-30 20:32:26.745735 | instance | "failed": false, 2026-07-30 20:32:26.745764 | instance | "invocation": { 2026-07-30 20:32:26.745839 | instance | "module_args": { 2026-07-30 20:32:26.745870 | instance | "checksum_algorithm": "sha1", 2026-07-30 20:32:26.745896 | instance | "follow": true, 2026-07-30 20:32:26.745923 | instance | "get_attributes": true, 2026-07-30 20:32:26.745949 | instance | "get_checksum": true, 2026-07-30 20:32:26.745974 | instance | "get_mime": true, 2026-07-30 20:32:26.745999 | instance | "path": "src/github.com/vexxhost/atmosphere" 2026-07-30 20:32:26.746026 | instance | } 2026-07-30 20:32:26.746051 | instance | }, 2026-07-30 20:32:26.746078 | instance | "item": "src/github.com/vexxhost/atmosphere", 2026-07-30 20:32:26.746103 | instance | "stat": { 2026-07-30 20:32:26.746357 | instance | "atime": 1785443543.870444, 2026-07-30 20:32:26.746416 | instance | "attr_flags": "e", 2026-07-30 20:32:26.746446 | instance | "attributes": [ 2026-07-30 20:32:26.746473 | instance | "extents" 2026-07-30 20:32:26.746499 | instance | ], 2026-07-30 20:32:26.746524 | instance | "block_size": 4096, 2026-07-30 20:32:26.746550 | instance | "blocks": 8, 2026-07-30 20:32:26.746575 | instance | "charset": "unknown", 2026-07-30 20:32:26.746600 | instance | "ctime": 1785443543.1424327, 2026-07-30 20:32:26.746626 | instance | "dev": 64513, 2026-07-30 20:32:26.746651 | instance | "device_type": 0, 2026-07-30 20:32:26.746676 | instance | "executable": true, 2026-07-30 20:32:26.746716 | instance | "exists": true, 2026-07-30 20:32:26.746742 | instance | "gid": 1000, 2026-07-30 20:32:26.746767 | instance | "gr_name": "zuul", 2026-07-30 20:32:26.746792 | instance | "inode": 1047375, 2026-07-30 20:32:26.746816 | instance | "isblk": false, 2026-07-30 20:32:26.746841 | instance | "ischr": false, 2026-07-30 20:32:26.746869 | instance | "isdir": true, 2026-07-30 20:32:26.746895 | instance | "isfifo": false, 2026-07-30 20:32:26.746920 | instance | "isgid": false, 2026-07-30 20:32:26.746945 | instance | "islnk": false, 2026-07-30 20:32:26.746969 | instance | "isreg": false, 2026-07-30 20:32:26.746994 | instance | "issock": false, 2026-07-30 20:32:26.747018 | instance | "isuid": false, 2026-07-30 20:32:26.747043 | instance | "mimetype": "unknown", 2026-07-30 20:32:26.747069 | instance | "mode": "0755", 2026-07-30 20:32:26.747093 | instance | "mtime": 1785443543.1424327, 2026-07-30 20:32:26.747118 | instance | "nlink": 25, 2026-07-30 20:32:26.747143 | instance | "path": "src/github.com/vexxhost/atmosphere", 2026-07-30 20:32:26.747168 | instance | "pw_name": "zuul", 2026-07-30 20:32:26.747193 | instance | "readable": true, 2026-07-30 20:32:26.747217 | instance | "rgrp": true, 2026-07-30 20:32:26.747241 | instance | "roth": true, 2026-07-30 20:32:26.747265 | instance | "rusr": true, 2026-07-30 20:32:26.747342 | instance | "size": 4096, 2026-07-30 20:32:26.747382 | instance | "uid": 1000, 2026-07-30 20:32:26.747408 | instance | "version": "2651989563", 2026-07-30 20:32:26.747435 | instance | "wgrp": false, 2026-07-30 20:32:26.747460 | instance | "woth": false, 2026-07-30 20:32:26.747485 | instance | "writeable": true, 2026-07-30 20:32:26.747510 | instance | "wusr": true, 2026-07-30 20:32:26.747535 | instance | "xgrp": true, 2026-07-30 20:32:26.747559 | instance | "xoth": true, 2026-07-30 20:32:26.747583 | instance | "xusr": true 2026-07-30 20:32:26.747616 | instance | } 2026-07-30 20:32:26.747636 | instance | } 2026-07-30 20:32:26.747670 | instance | ok: All items complete 2026-07-30 20:32:26.747691 | 2026-07-30 20:32:26.748710 | instance | skipping: Conditional result was False 2026-07-30 20:32:26.750123 | instance | skipping: Conditional result was False 2026-07-30 20:32:26.766011 | 2026-07-30 20:32:26.766166 | LOOP [Use private addresses in Molecule inventory] 2026-07-30 20:32:27.090097 | instance | changed: 1 replacements made 2026-07-30 20:32:27.093722 | instance | skipping: Conditional result was False 2026-07-30 20:32:27.098394 | instance | skipping: Conditional result was False 2026-07-30 20:32:27.112014 | 2026-07-30 20:32:27.112128 | TASK [Prepare Molecule command] 2026-07-30 20:32:27.176375 | instance | ok 2026-07-30 20:32:27.187196 | 2026-07-30 20:32:27.187314 | TASK [Run standard Molecule lifecycle with progress output] 2026-07-30 20:32:27.231378 | instance | ok 2026-07-30 20:32:27.250663 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/run-command.yml 2026-07-30 20:32:27.257137 | 2026-07-30 20:32:27.257203 | TASK [Create Molecule log directory] 2026-07-30 20:32:27.595173 | instance | changed 2026-07-30 20:32:27.601791 | 2026-07-30 20:32:27.601879 | TASK [Resolve live Molecule log path] 2026-07-30 20:32:27.650137 | instance | ok 2026-07-30 20:32:27.664459 | 2026-07-30 20:32:27.664574 | TASK [Start Molecule keycloak (keycloak)] 2026-07-30 20:32:28.307277 | instance | ok: ERROR (ignored) 2026-07-30 20:32:28.307478 | instance | { 2026-07-30 20:32:28.307509 | instance | "ansible_job_id": "j290289680441.1484", 2026-07-30 20:32:28.307530 | instance | "finished": 0, 2026-07-30 20:32:28.307549 | instance | "results_file": "/home/zuul/.ansible_async/j290289680441.1484", 2026-07-30 20:32:28.307567 | instance | "started": 1 2026-07-30 20:32:28.307585 | instance | } 2026-07-30 20:32:28.315622 | 2026-07-30 20:32:28.315707 | TASK [Initialize progress monitor for Molecule keycloak (keycloak)] 2026-07-30 20:32:28.370189 | instance | ok 2026-07-30 20:32:28.413643 | 2026-07-30 20:32:28.413757 | TASK [Bound progress monitor recursion for Molecule keycloak (keycloak)] 2026-07-30 20:32:28.447933 | instance | ok 2026-07-30 20:32:28.455859 | 2026-07-30 20:32:28.455955 | TASK [Calculate progress monitor chunks for Molecule keycloak (keycloak)] 2026-07-30 20:32:28.517730 | instance | ok 2026-07-30 20:32:28.526430 | 2026-07-30 20:32:28.526538 | LOOP [Monitor Molecule keycloak (keycloak)] 2026-07-30 20:32:28.570672 | instance | ok: 1 2026-07-30 20:32:28.570867 | instance | changed: All items complete 2026-07-30 20:32:28.570899 | 2026-07-30 20:32:28.580919 | instance | ok: 2 2026-07-30 20:32:28.588316 | instance | ok: 3 2026-07-30 20:32:28.593927 | instance | ok: 4 2026-07-30 20:32:28.606232 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml 2026-07-30 20:32:28.607645 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml 2026-07-30 20:32:28.608878 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml 2026-07-30 20:32:28.610127 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-chunk.yml 2026-07-30 20:32:28.621560 | 2026-07-30 20:32:28.621658 | TASK [Start progress monitor chunk for Molecule keycloak (keycloak)] 2026-07-30 20:32:28.677170 | instance | ok 2026-07-30 20:32:28.688896 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml 2026-07-30 20:32:28.696533 | 2026-07-30 20:32:28.696631 | TASK [Wait before polling Molecule keycloak (keycloak)] 2026-07-30 20:32:48.752560 | instance | ok 2026-07-30 20:32:48.759424 | 2026-07-30 20:32:48.759498 | TASK [Count progress poll for Molecule keycloak (keycloak)] 2026-07-30 20:32:48.824051 | instance | ok 2026-07-30 20:32:48.832774 | 2026-07-30 20:32:48.832862 | TASK [Poll status for Molecule keycloak (keycloak)] 2026-07-30 20:32:49.192306 | instance | ok 2026-07-30 20:32:49.199773 | 2026-07-30 20:32:49.199865 | TASK [Inspect live log for Molecule keycloak (keycloak)] 2026-07-30 20:32:49.400751 | instance | ok 2026-07-30 20:32:49.410195 | 2026-07-30 20:32:49.410320 | TASK [Calculate new output for Molecule keycloak (keycloak)] 2026-07-30 20:32:49.466088 | instance | ok 2026-07-30 20:32:49.476171 | 2026-07-30 20:32:49.476314 | TASK [Read new output for Molecule keycloak (keycloak)] 2026-07-30 20:32:49.677499 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-07-30 20:32:49.677594 | instance | INFO [keycloak > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-07-30 20:32:49.677618 | instance | INFO [keycloak > prerun] Performing prerun with role_name_check=0... 2026-07-30 20:32:49.677629 | instance | INFO [keycloak > dependency] Executing 2026-07-30 20:32:49.677639 | instance | WARNING [keycloak > dependency] Missing roles requirements file: requirements.yml 2026-07-30 20:32:49.677652 | instance | WARNING [keycloak > dependency] Missing collections requirements file: collections.yml 2026-07-30 20:32:49.677662 | instance | WARNING [keycloak > dependency] Executed: 2 missing (Remove from test_sequence to suppress) 2026-07-30 20:32:49.677671 | instance | INFO [keycloak > cleanup] Executing 2026-07-30 20:32:49.677680 | instance | WARNING [keycloak > cleanup] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-30 20:32:49.677689 | instance | INFO [keycloak > destroy] Executing 2026-07-30 20:32:49.677698 | instance | WARNING [keycloak > destroy] Skipping, '--destroy=never' requested. 2026-07-30 20:32:49.677707 | instance | INFO [keycloak > destroy] Executed: Successful 2026-07-30 20:32:49.677716 | instance | INFO [keycloak > syntax] Executing 2026-07-30 20:32:49.677725 | instance | 2026-07-30 20:32:49.677737 | instance | playbook: /home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/converge.yml 2026-07-30 20:32:49.677747 | instance | INFO [keycloak > syntax] Executed: Successful 2026-07-30 20:32:49.677755 | instance | INFO [keycloak > create] Executing 2026-07-30 20:32:49.677765 | instance | WARNING [keycloak > create] Executed: Missing playbook (Remove from test_sequence to suppress) 2026-07-30 20:32:49.677774 | instance | INFO [keycloak > prepare] Executing 2026-07-30 20:32:49.677784 | instance | 2026-07-30 20:32:49.677794 | instance | PLAY [Prepare] ***************************************************************** 2026-07-30 20:32:49.677803 | instance | 2026-07-30 20:32:49.677812 | instance | TASK [Gathering Facts] ********************************************************* 2026-07-30 20:32:49.677821 | instance | Thursday 30 July 2026 20:32:43 +0000 (0:00:00.057) 0:00:00.057 ********* 2026-07-30 20:32:49.677830 | instance | [WARNING]: Platform linux on host instance is using the discovered Python 2026-07-30 20:32:49.677839 | instance | interpreter at /usr/bin/python3.10, but future installation of another Python 2026-07-30 20:32:49.677848 | instance | interpreter could change the meaning of that path. See 2026-07-30 20:32:49.677857 | instance | https://docs.ansible.com/ansible- 2026-07-30 20:32:49.677866 | instance | core/2.17/reference_appendices/interpreter_discovery.html for more information. 2026-07-30 20:32:49.677875 | instance | ok: [instance] 2026-07-30 20:32:49.677884 | instance | 2026-07-30 20:32:49.677893 | instance | TASK [Configure short hostname] ************************************************ 2026-07-30 20:32:49.677901 | instance | Thursday 30 July 2026 20:32:44 +0000 (0:00:01.188) 0:00:01.245 ********* 2026-07-30 20:32:49.677910 | instance | changed: [instance] 2026-07-30 20:32:49.677919 | instance | 2026-07-30 20:32:49.677928 | instance | TASK [Ensure hostname inside hosts file] *************************************** 2026-07-30 20:32:49.677937 | instance | Thursday 30 July 2026 20:32:45 +0000 (0:00:00.705) 0:00:01.951 ********* 2026-07-30 20:32:49.677946 | instance | [WARNING]: Module remote_tmp /root/.ansible/tmp did not exist and was created 2026-07-30 20:32:49.678018 | instance | with a mode of 0700, this may cause issues when running as another user. To 2026-07-30 20:32:49.678031 | instance | avoid this, create the remote_tmp dir with the correct permissions manually 2026-07-30 20:32:49.678040 | instance | changed: [instance] 2026-07-30 20:32:49.678049 | instance | 2026-07-30 20:32:49.678058 | instance | TASK [Install "dirmngr" for GPG keyserver operations] ************************** 2026-07-30 20:32:49.678067 | instance | Thursday 30 July 2026 20:32:45 +0000 (0:00:00.271) 0:00:02.222 ********* 2026-07-30 20:32:49.678076 | instance | ok: [instance] 2026-07-30 20:32:49.678085 | instance | 2026-07-30 20:32:49.678094 | instance | TASK [Purge "snapd" package] *************************************************** 2026-07-30 20:32:49.678103 | instance | Thursday 30 July 2026 20:32:46 +0000 (0:00:01.226) 0:00:03.449 ********* 2026-07-30 20:32:49.678122 | instance | ok: [instance] 2026-07-30 20:32:49.678131 | instance | 2026-07-30 20:32:49.678140 | instance | PLAY [Generate workspace for Atmosphere] *************************************** 2026-07-30 20:32:49.678149 | instance | 2026-07-30 20:32:49.678158 | instance | TASK [Create folders for workspace] ******************************************** 2026-07-30 20:32:49.678167 | instance | Thursday 30 July 2026 20:32:47 +0000 (0:00:00.703) 0:00:04.152 ********* 2026-07-30 20:32:49.678175 | instance | changed: [localhost] => (item=group_vars) 2026-07-30 20:32:49.678184 | instance | changed: [localhost] => (item=group_vars/all) 2026-07-30 20:32:49.678193 | instance | changed: [localhost] => (item=group_vars/controllers) 2026-07-30 20:32:49.678202 | instance | changed: [localhost] => (item=group_vars/cephs) 2026-07-30 20:32:49.678211 | instance | changed: [localhost] => (item=group_vars/computes) 2026-07-30 20:32:49.678220 | instance | changed: [localhost] => (item=host_vars) 2026-07-30 20:32:49.678229 | instance | 2026-07-30 20:32:49.678238 | instance | PLAY [Generate Ceph control plane configuration for workspace] ***************** 2026-07-30 20:32:49.678246 | instance | 2026-07-30 20:32:49.678255 | instance | TASK [Ensure the Ceph control plane configuration file exists] ***************** 2026-07-30 20:32:49.678264 | instance | Thursday 30 July 2026 20:32:49 +0000 (0:00:01.433) 0:00:05.586 ********* 2026-07-30 20:32:49.678273 | instance | changed: [localhost] 2026-07-30 20:32:49.678282 | instance | 2026-07-30 20:32:49.678290 | instance | TASK [Load the current Ceph control plane configuration into a variable] ******* 2026-07-30 20:32:49.678321 | instance | Thursday 30 July 2026 20:32:49 +0000 (0:00:00.243) 0:00:05.829 ********* 2026-07-30 20:32:49.678334 | instance | ok: [localhost] 2026-07-30 20:32:49.678343 | instance | 2026-07-30 20:32:49.678351 | instance | TASK [Generate Ceph control plane values for missing variables] **************** 2026-07-30 20:32:49.678360 | instance | Thursday 30 July 2026 20:32:49 +0000 (0:00:00.029) 0:00:05.859 ********* 2026-07-30 20:32:50.013312 | instance | ok: Runtime: 0:00:00.008194 2026-07-30 20:32:50.028269 | 2026-07-30 20:32:50.028568 | TASK [Track last task for Molecule keycloak (keycloak)] 2026-07-30 20:32:50.117103 | instance | ok 2026-07-30 20:32:50.130714 | 2026-07-30 20:32:50.130838 | TASK [Advance live log offset for Molecule keycloak (keycloak)] 2026-07-30 20:32:50.186376 | instance | ok 2026-07-30 20:32:50.200456 | 2026-07-30 20:32:50.200559 | TASK [Report progress heartbeat for Molecule keycloak (keycloak)] 2026-07-30 20:32:50.260813 | instance | ok: 2026-07-30 20:32:50.261000 | instance | Molecule keycloak (keycloak) is still running after 20 seconds. 2026-07-30 20:32:50.261033 | instance | Last task: TASK [Generate Ceph control plane values for missing variables] 2026-07-30 20:32:50.267995 | 2026-07-30 20:32:50.268065 | TASK [Continue monitoring Molecule keycloak (keycloak)] 2026-07-30 20:32:50.325282 | instance | ok 2026-07-30 20:32:50.344764 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml 2026-07-30 20:32:50.376659 | 2026-07-30 20:32:50.377517 | TASK [Wait before polling Molecule keycloak (keycloak)] 2026-07-30 20:33:10.440467 | instance | ok 2026-07-30 20:33:10.446248 | 2026-07-30 20:33:10.446322 | TASK [Count progress poll for Molecule keycloak (keycloak)] 2026-07-30 20:33:10.500786 | instance | ok 2026-07-30 20:33:10.507044 | 2026-07-30 20:33:10.507134 | TASK [Poll status for Molecule keycloak (keycloak)] 2026-07-30 20:33:10.743919 | instance | ok 2026-07-30 20:33:10.752186 | 2026-07-30 20:33:10.752261 | TASK [Inspect live log for Molecule keycloak (keycloak)] 2026-07-30 20:33:10.966987 | instance | ok 2026-07-30 20:33:10.974895 | 2026-07-30 20:33:10.974969 | TASK [Calculate new output for Molecule keycloak (keycloak)] 2026-07-30 20:33:11.029016 | instance | ok 2026-07-30 20:33:11.037206 | 2026-07-30 20:33:11.037300 | TASK [Read new output for Molecule keycloak (keycloak)] 2026-07-30 20:33:11.263442 | instance | ok: [localhost] => (item={'key': 'ceph_fsid', 'value': '0174f89f-53aa-562a-be7a-1f3ebb9b1dd2'}) 2026-07-30 20:33:11.263814 | instance | ok: [localhost] => (item={'key': 'ceph_mon_public_network', 'value': '10.96.240.0/24'}) 2026-07-30 20:33:11.263836 | instance | 2026-07-30 20:33:11.263853 | instance | TASK [Write new Ceph control plane configuration file to disk] ***************** 2026-07-30 20:33:11.263872 | instance | Thursday 30 July 2026 20:32:49 +0000 (0:00:00.051) 0:00:05.910 ********* 2026-07-30 20:33:11.263887 | instance | changed: [localhost] 2026-07-30 20:33:11.263902 | instance | 2026-07-30 20:33:11.263917 | instance | PLAY [Generate Ceph OSD configuration for workspace] *************************** 2026-07-30 20:33:11.263931 | instance | 2026-07-30 20:33:11.263944 | instance | TASK [Ensure the Ceph OSDs configuration file exists] ************************** 2026-07-30 20:33:11.263957 | instance | Thursday 30 July 2026 20:32:50 +0000 (0:00:00.726) 0:00:06.637 ********* 2026-07-30 20:33:11.263970 | instance | changed: [localhost] 2026-07-30 20:33:11.263983 | instance | 2026-07-30 20:33:11.263995 | instance | TASK [Load the current Ceph OSDs configuration into a variable] **************** 2026-07-30 20:33:11.264008 | instance | Thursday 30 July 2026 20:32:50 +0000 (0:00:00.236) 0:00:06.874 ********* 2026-07-30 20:33:11.264022 | instance | ok: [localhost] 2026-07-30 20:33:11.264035 | instance | 2026-07-30 20:33:11.264049 | instance | TASK [Generate Ceph OSDs values for missing variables] ************************* 2026-07-30 20:33:11.264068 | instance | Thursday 30 July 2026 20:32:50 +0000 (0:00:00.029) 0:00:06.903 ********* 2026-07-30 20:33:11.264082 | instance | ok: [localhost] => (item={'key': 'ceph_osd_devices', 'value': ['/dev/vdb', '/dev/vdc', '/dev/vdd']}) 2026-07-30 20:33:11.264095 | instance | 2026-07-30 20:33:11.264108 | instance | TASK [Write new Ceph OSDs configuration file to disk] ************************** 2026-07-30 20:33:11.264121 | instance | Thursday 30 July 2026 20:32:50 +0000 (0:00:00.038) 0:00:06.942 ********* 2026-07-30 20:33:11.264134 | instance | changed: [localhost] 2026-07-30 20:33:11.264147 | instance | 2026-07-30 20:33:11.264261 | instance | PLAY [Generate Kubernetes configuration for workspace] ************************* 2026-07-30 20:33:11.264275 | instance | 2026-07-30 20:33:11.264285 | instance | TASK [Ensure the Kubernetes configuration file exists] ************************* 2026-07-30 20:33:11.264295 | instance | Thursday 30 July 2026 20:32:50 +0000 (0:00:00.512) 0:00:07.455 ********* 2026-07-30 20:33:11.264305 | instance | changed: [localhost] 2026-07-30 20:33:11.264315 | instance | 2026-07-30 20:33:11.264324 | instance | TASK [Load the current Kubernetes configuration into a variable] *************** 2026-07-30 20:33:11.264333 | instance | Thursday 30 July 2026 20:32:51 +0000 (0:00:00.242) 0:00:07.697 ********* 2026-07-30 20:33:11.264343 | instance | ok: [localhost] 2026-07-30 20:33:11.264352 | instance | 2026-07-30 20:33:11.264363 | instance | TASK [Generate Kubernetes values for missing variables] ************************ 2026-07-30 20:33:11.264373 | instance | Thursday 30 July 2026 20:32:51 +0000 (0:00:00.029) 0:00:07.726 ********* 2026-07-30 20:33:11.264382 | instance | ok: [localhost] => (item={'key': 'kubernetes_hostname', 'value': '10.96.240.10'}) 2026-07-30 20:33:11.264392 | instance | ok: [localhost] => (item={'key': 'kubernetes_keepalived_vrid', 'value': 42}) 2026-07-30 20:33:11.264402 | instance | ok: [localhost] => (item={'key': 'kubernetes_keepalived_vip', 'value': '10.96.240.10'}) 2026-07-30 20:33:11.264412 | instance | 2026-07-30 20:33:11.264421 | instance | TASK [Write new Kubernetes configuration file to disk] ************************* 2026-07-30 20:33:11.264430 | instance | Thursday 30 July 2026 20:32:51 +0000 (0:00:00.051) 0:00:07.778 ********* 2026-07-30 20:33:11.264440 | instance | changed: [localhost] 2026-07-30 20:33:11.264450 | instance | 2026-07-30 20:33:11.264459 | instance | PLAY [Generate Keepalived configuration for workspace] ************************* 2026-07-30 20:33:11.264468 | instance | 2026-07-30 20:33:11.264478 | instance | TASK [Ensure the Keeaplived configuration file exists] ************************* 2026-07-30 20:33:11.264487 | instance | Thursday 30 July 2026 20:32:51 +0000 (0:00:00.490) 0:00:08.269 ********* 2026-07-30 20:33:11.264497 | instance | changed: [localhost] 2026-07-30 20:33:11.264506 | instance | 2026-07-30 20:33:11.264516 | instance | TASK [Load the current Keepalived configuration into a variable] *************** 2026-07-30 20:33:11.264538 | instance | Thursday 30 July 2026 20:32:52 +0000 (0:00:00.272) 0:00:08.541 ********* 2026-07-30 20:33:11.264547 | instance | ok: [localhost] 2026-07-30 20:33:11.264555 | instance | 2026-07-30 20:33:11.264564 | instance | TASK [Generate Keepalived values for missing variables] ************************ 2026-07-30 20:33:11.264573 | instance | Thursday 30 July 2026 20:32:52 +0000 (0:00:00.026) 0:00:08.567 ********* 2026-07-30 20:33:11.264582 | instance | ok: [localhost] => (item={'key': 'keepalived_interface', 'value': 'br-ex'}) 2026-07-30 20:33:11.264597 | instance | ok: [localhost] => (item={'key': 'keepalived_vip', 'value': '10.96.250.10'}) 2026-07-30 20:33:11.264606 | instance | 2026-07-30 20:33:11.264615 | instance | TASK [Write new Keepalived configuration file to disk] ************************* 2026-07-30 20:33:11.264623 | instance | Thursday 30 July 2026 20:32:52 +0000 (0:00:00.041) 0:00:08.609 ********* 2026-07-30 20:33:11.264632 | instance | changed: [localhost] 2026-07-30 20:33:11.264641 | instance | 2026-07-30 20:33:11.264649 | instance | PLAY [Generate endpoints for workspace] **************************************** 2026-07-30 20:33:11.264658 | instance | 2026-07-30 20:33:11.264666 | instance | TASK [Gathering Facts] ********************************************************* 2026-07-30 20:33:11.264675 | instance | Thursday 30 July 2026 20:32:52 +0000 (0:00:00.478) 0:00:09.087 ********* 2026-07-30 20:33:11.264684 | instance | ok: [localhost] 2026-07-30 20:33:11.264692 | instance | 2026-07-30 20:33:11.264701 | instance | TASK [Ensure the endpoints file exists] **************************************** 2026-07-30 20:33:11.264710 | instance | Thursday 30 July 2026 20:32:53 +0000 (0:00:00.896) 0:00:09.983 ********* 2026-07-30 20:33:11.264719 | instance | changed: [localhost] 2026-07-30 20:33:11.264727 | instance | 2026-07-30 20:33:11.264736 | instance | TASK [Load the current endpoints into a variable] ****************************** 2026-07-30 20:33:11.264744 | instance | Thursday 30 July 2026 20:32:53 +0000 (0:00:00.239) 0:00:10.223 ********* 2026-07-30 20:33:11.264753 | instance | ok: [localhost] 2026-07-30 20:33:11.264762 | instance | 2026-07-30 20:33:11.264771 | instance | TASK [Generate endpoint skeleton for missing variables] ************************ 2026-07-30 20:33:11.264779 | instance | Thursday 30 July 2026 20:32:53 +0000 (0:00:00.029) 0:00:10.252 ********* 2026-07-30 20:33:11.264789 | instance | ok: [localhost] => (item=keycloak_host) 2026-07-30 20:33:11.264807 | instance | ok: [localhost] => (item=kube_prometheus_stack_grafana_host) 2026-07-30 20:33:11.264816 | instance | ok: [localhost] => (item=kube_prometheus_stack_alertmanager_host) 2026-07-30 20:33:11.264826 | instance | ok: [localhost] => (item=kube_prometheus_stack_prometheus_host) 2026-07-30 20:33:11.264835 | instance | ok: [localhost] => (item=openstack_helm_endpoints_region_name) 2026-07-30 20:33:11.264844 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_api_host) 2026-07-30 20:33:11.264853 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_api_host) 2026-07-30 20:33:11.264862 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_api_host) 2026-07-30 20:33:11.264870 | instance | ok: [localhost] => (item=openstack_helm_endpoints_placement_api_host) 2026-07-30 20:33:11.264879 | instance | ok: [localhost] => (item=openstack_helm_endpoints_barbican_api_host) 2026-07-30 20:33:11.264888 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_api_host) 2026-07-30 20:33:11.264897 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_api_host) 2026-07-30 20:33:11.264905 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_novnc_host) 2026-07-30 20:33:11.264914 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_api_host) 2026-07-30 20:33:11.264923 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_api_host) 2026-07-30 20:33:11.264935 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_api_host) 2026-07-30 20:33:11.264944 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_api_host) 2026-07-30 20:33:11.264953 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_registry_host) 2026-07-30 20:33:11.264961 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_api_host) 2026-07-30 20:33:11.264970 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_cfn_api_host) 2026-07-30 20:33:11.264985 | instance | ok: [localhost] => (item=openstack_helm_endpoints_horizon_api_host) 2026-07-30 20:33:11.264994 | instance | ok: [localhost] => (item=openstack_helm_endpoints_rgw_host) 2026-07-30 20:33:11.265003 | instance | ok: [localhost] => (item=openstack_helm_endpoints_manila_api_host) 2026-07-30 20:33:11.265012 | instance | 2026-07-30 20:33:11.265020 | instance | TASK [Write new endpoints file to disk] **************************************** 2026-07-30 20:33:11.265029 | instance | Thursday 30 July 2026 20:32:54 +0000 (0:00:00.729) 0:00:10.981 ********* 2026-07-30 20:33:11.265038 | instance | changed: [localhost] 2026-07-30 20:33:11.265047 | instance | 2026-07-30 20:33:11.265055 | instance | TASK [Ensure the endpoints file exists] **************************************** 2026-07-30 20:33:11.265064 | instance | Thursday 30 July 2026 20:32:54 +0000 (0:00:00.478) 0:00:11.459 ********* 2026-07-30 20:33:11.265073 | instance | changed: [localhost] 2026-07-30 20:33:11.265081 | instance | 2026-07-30 20:33:11.265090 | instance | PLAY [Generate Neutron configuration for workspace] **************************** 2026-07-30 20:33:11.265099 | instance | 2026-07-30 20:33:11.265107 | instance | TASK [Ensure the Neutron configuration file exists] **************************** 2026-07-30 20:33:11.265116 | instance | Thursday 30 July 2026 20:32:55 +0000 (0:00:00.255) 0:00:11.714 ********* 2026-07-30 20:33:11.265125 | instance | changed: [localhost] 2026-07-30 20:33:11.265133 | instance | 2026-07-30 20:33:11.265142 | instance | TASK [Load the current Neutron configuration into a variable] ****************** 2026-07-30 20:33:11.265150 | instance | Thursday 30 July 2026 20:32:55 +0000 (0:00:00.256) 0:00:11.971 ********* 2026-07-30 20:33:11.265159 | instance | ok: [localhost] 2026-07-30 20:33:11.265168 | instance | 2026-07-30 20:33:11.265177 | instance | TASK [Generate Neutron values for missing variables] *************************** 2026-07-30 20:33:11.265185 | instance | Thursday 30 July 2026 20:32:55 +0000 (0:00:00.032) 0:00:12.003 ********* 2026-07-30 20:33:11.265195 | instance | ok: [localhost] => (item={'key': 'neutron_networks', 'value': [{'name': 'public', 'external': True, 'shared': True, 'mtu_size': 1500, 'port_security_enabled': True, 'provider_network_type': 'flat', 'provider_physical_network': 'external', 'subnets': [{'name': 'public-subnet', 'cidr': '10.96.250.0/24', 'gateway_ip': '10.96.250.10', 'allocation_pool_start': '10.96.250.200', 'allocation_pool_end': '10.96.250.220', 'enable_dhcp': True}]}]}) 2026-07-30 20:33:11.265206 | instance | 2026-07-30 20:33:11.265214 | instance | TASK [Write new Neutron configuration file to disk] **************************** 2026-07-30 20:33:11.265223 | instance | Thursday 30 July 2026 20:32:55 +0000 (0:00:00.045) 0:00:12.049 ********* 2026-07-30 20:33:11.265232 | instance | changed: [localhost] 2026-07-30 20:33:11.265240 | instance | 2026-07-30 20:33:11.265249 | instance | PLAY [Generate Nova configuration for workspace] ******************************* 2026-07-30 20:33:11.265258 | instance | 2026-07-30 20:33:11.265266 | instance | TASK [Ensure the Nova configuration file exists] ******************************* 2026-07-30 20:33:11.265275 | instance | Thursday 30 July 2026 20:32:56 +0000 (0:00:00.515) 0:00:12.564 ********* 2026-07-30 20:33:11.265284 | instance | changed: [localhost] 2026-07-30 20:33:11.265293 | instance | 2026-07-30 20:33:11.265301 | instance | TASK [Load the current Nova configuration into a variable] ********************* 2026-07-30 20:33:11.265310 | instance | Thursday 30 July 2026 20:32:56 +0000 (0:00:00.262) 0:00:12.826 ********* 2026-07-30 20:33:11.265318 | instance | ok: [localhost] 2026-07-30 20:33:11.265327 | instance | 2026-07-30 20:33:11.265336 | instance | TASK [Generate Nova values for missing variables] ****************************** 2026-07-30 20:33:11.265345 | instance | Thursday 30 July 2026 20:32:56 +0000 (0:00:00.032) 0:00:12.859 ********* 2026-07-30 20:33:11.265354 | instance | ok: [localhost] => (item={'key': 'nova_flavors', 'value': [{'name': 'm1.tiny', 'ram': 512, 'disk': 1, 'vcpus': 1}, {'name': 'm1.small', 'ram': 2048, 'disk': 20, 'vcpus': 1}, {'name': 'm1.medium', 'ram': 4096, 'disk': 40, 'vcpus': 2}, {'name': 'm1.large', 'ram': 8192, 'disk': 80, 'vcpus': 4}, {'name': 'm1.xlarge', 'ram': 16384, 'disk': 160, 'vcpus': 8}]}) 2026-07-30 20:33:11.265364 | instance | 2026-07-30 20:33:11.265373 | instance | TASK [Write new Nova configuration file to disk] ******************************* 2026-07-30 20:33:11.265394 | instance | Thursday 30 July 2026 20:32:56 +0000 (0:00:00.045) 0:00:12.904 ********* 2026-07-30 20:33:11.265403 | instance | changed: [localhost] 2026-07-30 20:33:11.265411 | instance | 2026-07-30 20:33:11.265423 | instance | PLAY [Generate secrets for workspace] ****************************************** 2026-07-30 20:33:11.265432 | instance | 2026-07-30 20:33:11.265441 | instance | TASK [Ensure the secrets file exists] ****************************************** 2026-07-30 20:33:11.265450 | instance | Thursday 30 July 2026 20:32:56 +0000 (0:00:00.496) 0:00:13.400 ********* 2026-07-30 20:33:11.265458 | instance | changed: [localhost] 2026-07-30 20:33:11.265467 | instance | 2026-07-30 20:33:11.265476 | instance | TASK [Load the current secrets into a variable] ******************************** 2026-07-30 20:33:11.265484 | instance | Thursday 30 July 2026 20:32:57 +0000 (0:00:00.249) 0:00:13.650 ********* 2026-07-30 20:33:11.265493 | instance | ok: [localhost] 2026-07-30 20:33:11.265502 | instance | 2026-07-30 20:33:11.265510 | instance | TASK [Generate secrets for missing variables] ********************************** 2026-07-30 20:33:11.265519 | instance | Thursday 30 July 2026 20:32:57 +0000 (0:00:00.033) 0:00:13.684 ********* 2026-07-30 20:33:11.265528 | instance | ok: [localhost] => (item=heat_auth_encryption_key) 2026-07-30 20:33:11.265537 | instance | ok: [localhost] => (item=keepalived_password) 2026-07-30 20:33:11.265546 | instance | ok: [localhost] => (item=keycloak_admin_password) 2026-07-30 20:33:11.265555 | instance | ok: [localhost] => (item=keycloak_database_password) 2026-07-30 20:33:11.265564 | instance | ok: [localhost] => (item=keystone_keycloak_client_secret) 2026-07-30 20:33:11.265573 | instance | ok: [localhost] => (item=keystone_oidc_crypto_passphrase) 2026-07-30 20:33:11.265582 | instance | ok: [localhost] => (item=kube_prometheus_stack_grafana_admin_password) 2026-07-30 20:33:11.265591 | instance | ok: [localhost] => (item=octavia_heartbeat_key) 2026-07-30 20:33:11.265599 | instance | ok: [localhost] => (item=openstack_helm_endpoints_rabbitmq_admin_password) 2026-07-30 20:33:11.265608 | instance | ok: [localhost] => (item=openstack_helm_endpoints_memcached_secret_key) 2026-07-30 20:33:11.265617 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_admin_password) 2026-07-30 20:33:11.265626 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_mariadb_password) 2026-07-30 20:33:11.265635 | instance | ok: [localhost] => (item=openstack_helm_endpoints_keystone_rabbitmq_password) 2026-07-30 20:33:11.265643 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_keystone_password) 2026-07-30 20:33:11.265652 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_mariadb_password) 2026-07-30 20:33:11.265661 | instance | ok: [localhost] => (item=openstack_helm_endpoints_glance_rabbitmq_password) 2026-07-30 20:33:11.265669 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_keystone_password) 2026-07-30 20:33:11.265678 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_mariadb_password) 2026-07-30 20:33:11.265687 | instance | ok: [localhost] => (item=openstack_helm_endpoints_cinder_rabbitmq_password) 2026-07-30 20:33:11.265695 | instance | ok: [localhost] => (item=openstack_helm_endpoints_placement_keystone_password) 2026-07-30 20:33:11.265704 | instance | ok: [localhost] => (item=openstack_helm_endpoints_placement_mariadb_password) 2026-07-30 20:33:11.265714 | instance | ok: [localhost] => (item=openstack_helm_endpoints_barbican_keystone_password) 2026-07-30 20:33:11.265723 | instance | ok: [localhost] => (item=openstack_helm_endpoints_barbican_mariadb_password) 2026-07-30 20:33:11.265733 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_keystone_password) 2026-07-30 20:33:11.265742 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_mariadb_password) 2026-07-30 20:33:11.265752 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_rabbitmq_password) 2026-07-30 20:33:11.265762 | instance | ok: [localhost] => (item=openstack_helm_endpoints_neutron_metadata_secret) 2026-07-30 20:33:11.265772 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_keystone_password) 2026-07-30 20:33:11.265781 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_mariadb_password) 2026-07-30 20:33:11.265791 | instance | ok: [localhost] => (item=openstack_helm_endpoints_nova_rabbitmq_password) 2026-07-30 20:33:11.265808 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_keystone_password) 2026-07-30 20:33:11.265818 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_mariadb_password) 2026-07-30 20:33:11.265828 | instance | ok: [localhost] => (item=openstack_helm_endpoints_ironic_rabbitmq_password) 2026-07-30 20:33:11.265837 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_keystone_password) 2026-07-30 20:33:11.265847 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_mariadb_password) 2026-07-30 20:33:11.265857 | instance | ok: [localhost] => (item=openstack_helm_endpoints_designate_rabbitmq_password) 2026-07-30 20:33:11.265866 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_keystone_password) 2026-07-30 20:33:11.265876 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_mariadb_password) 2026-07-30 20:33:11.265886 | instance | ok: [localhost] => (item=openstack_helm_endpoints_octavia_rabbitmq_password) 2026-07-30 20:33:11.265896 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_keystone_password) 2026-07-30 20:33:11.265905 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_mariadb_password) 2026-07-30 20:33:11.265915 | instance | ok: [localhost] => (item=openstack_helm_endpoints_magnum_rabbitmq_password) 2026-07-30 20:33:11.265925 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_keystone_password) 2026-07-30 20:33:11.265938 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_trustee_keystone_password) 2026-07-30 20:33:11.265947 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_stack_user_keystone_password) 2026-07-30 20:33:11.265957 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_mariadb_password) 2026-07-30 20:33:11.265967 | instance | ok: [localhost] => (item=openstack_helm_endpoints_heat_rabbitmq_password) 2026-07-30 20:33:11.265983 | instance | ok: [localhost] => (item=openstack_helm_endpoints_horizon_mariadb_password) 2026-07-30 20:33:11.265993 | instance | ok: [localhost] => (item=openstack_helm_endpoints_tempest_keystone_password) 2026-07-30 20:33:11.266002 | instance | ok: [localhost] => (item=openstack_helm_endpoints_openstack_exporter_keystone_password) 2026-07-30 20:33:11.266012 | instance | ok: [localhost] => (item=openstack_helm_endpoints_rgw_keystone_password) 2026-07-30 20:33:11.266022 | instance | ok: [localhost] => (item=openstack_helm_endpoints_manila_keystone_password) 2026-07-30 20:33:11.266032 | instance | ok: [localhost] => (item=openstack_helm_endpoints_manila_mariadb_password) 2026-07-30 20:33:11.266041 | instance | ok: [localhost] => (item=openstack_helm_endpoints_staffeln_mariadb_password) 2026-07-30 20:33:11.266051 | instance | 2026-07-30 20:33:11.266061 | instance | TASK [Generate base64 encoded secrets] ***************************************** 2026-07-30 20:33:11.266070 | instance | Thursday 30 July 2026 20:32:57 +0000 (0:00:00.424) 0:00:14.109 ********* 2026-07-30 20:33:11.266080 | instance | ok: [localhost] => (item=barbican_kek) 2026-07-30 20:33:11.266090 | instance | 2026-07-30 20:33:11.266100 | instance | TASK [Generate temporary files for generating keys for missing variables] ****** 2026-07-30 20:33:11.266110 | instance | Thursday 30 July 2026 20:32:57 +0000 (0:00:00.050) 0:00:14.159 ********* 2026-07-30 20:33:11.266119 | instance | changed: [localhost] => (item=manila_ssh_key) 2026-07-30 20:33:11.266129 | instance | changed: [localhost] => (item=nova_ssh_key) 2026-07-30 20:33:11.266139 | instance | 2026-07-30 20:33:11.266148 | instance | TASK [Generate SSH keys for missing variables] ********************************* 2026-07-30 20:33:11.266158 | instance | Thursday 30 July 2026 20:32:58 +0000 (0:00:00.507) 0:00:14.667 ********* 2026-07-30 20:33:11.266167 | instance | changed: [localhost] => (item=manila_ssh_key) 2026-07-30 20:33:11.266177 | instance | changed: [localhost] => (item=nova_ssh_key) 2026-07-30 20:33:11.266186 | instance | 2026-07-30 20:33:11.266196 | instance | TASK [Set values for SSH keys] ************************************************* 2026-07-30 20:33:11.266205 | instance | Thursday 30 July 2026 20:33:02 +0000 (0:00:03.856) 0:00:18.524 ********* 2026-07-30 20:33:11.266215 | instance | ok: [localhost] => (item=manila_ssh_key) 2026-07-30 20:33:11.266225 | instance | ok: [localhost] => (item=nova_ssh_key) 2026-07-30 20:33:11.266242 | instance | 2026-07-30 20:33:11.266252 | instance | TASK [Delete the temporary files generated for SSH keys] *********************** 2026-07-30 20:33:11.266261 | instance | Thursday 30 July 2026 20:33:02 +0000 (0:00:00.057) 0:00:18.581 ********* 2026-07-30 20:33:11.266271 | instance | changed: [localhost] => (item=manila_ssh_key) 2026-07-30 20:33:11.266372 | instance | changed: [localhost] => (item=nova_ssh_key) 2026-07-30 20:33:11.266387 | instance | 2026-07-30 20:33:11.266397 | instance | TASK [Write new secrets file to disk] ****************************************** 2026-07-30 20:33:11.266408 | instance | Thursday 30 July 2026 20:33:02 +0000 (0:00:00.487) 0:00:19.068 ********* 2026-07-30 20:33:11.266418 | instance | changed: [localhost] 2026-07-30 20:33:11.266429 | instance | 2026-07-30 20:33:11.266438 | instance | TASK [Encrypt secrets file with Vault password] ******************************** 2026-07-30 20:33:11.266448 | instance | Thursday 30 July 2026 20:33:03 +0000 (0:00:00.488) 0:00:19.557 ********* 2026-07-30 20:33:11.266458 | instance | skipping: [localhost] 2026-07-30 20:33:11.266467 | instance | 2026-07-30 20:33:11.266476 | instance | PLAY [all] ********************************************************************* 2026-07-30 20:33:11.266485 | instance | 2026-07-30 20:33:11.266495 | instance | TASK [Gathering Facts] ********************************************************* 2026-07-30 20:33:11.266504 | instance | Thursday 30 July 2026 20:33:03 +0000 (0:00:00.044) 0:00:19.602 ********* 2026-07-30 20:33:11.266514 | instance | ok: [instance] 2026-07-30 20:33:11.266525 | instance | 2026-07-30 20:33:11.266534 | instance | TASK [vexxhost.atmosphere.sysctl : Configure sysctl values] ******************** 2026-07-30 20:33:11.266543 | instance | Thursday 30 July 2026 20:33:03 +0000 (0:00:00.685) 0:00:20.287 ********* 2026-07-30 20:33:11.266552 | instance | changed: [instance] => (item={'name': 'fs.aio-max-nr', 'value': 1048576}) 2026-07-30 20:33:11.266561 | instance | changed: [instance] => (item={'name': 'net.ipv4.tcp_timestamps', 'value': 0}) 2026-07-30 20:33:11.266570 | instance | changed: [instance] => (item={'name': 'net.ipv4.tcp_sack', 'value': 1}) 2026-07-30 20:33:11.266580 | instance | changed: [instance] => (item={'name': 'net.core.netdev_budget', 'value': 1000}) 2026-07-30 20:33:11.266589 | instance | changed: [instance] => (item={'name': 'net.core.netdev_max_backlog', 'value': 250000}) 2026-07-30 20:33:11.266598 | instance | changed: [instance] => (item={'name': 'net.core.rmem_max', 'value': 4194304}) 2026-07-30 20:33:11.266608 | instance | changed: [instance] => (item={'name': 'net.core.wmem_max', 'value': 4194304}) 2026-07-30 20:33:11.266617 | instance | changed: [instance] => (item={'name': 'net.core.rmem_default', 'value': 4194304}) 2026-07-30 20:33:11.266627 | instance | changed: [instance] => (item={'name': 'net.core.wmem_default', 'value': 4194304}) 2026-07-30 20:33:11.266636 | instance | changed: [instance] => (item={'name': 'net.core.optmem_max', 'value': 4194304}) 2026-07-30 20:33:11.266645 | instance | changed: [instance] => (item={'name': 'net.ipv4.tcp_rmem', 'value': '4096 87380 4194304'}) 2026-07-30 20:33:11.266655 | instance | changed: [instance] => (item={'name': 'net.ipv4.tcp_wmem', 'value': '4096 65536 4194304'}) 2026-07-30 20:33:11.266664 | instance | changed: [instance] => (item={'name': 'net.ipv4.tcp_low_latency', 'value': 1}) 2026-07-30 20:33:11.266673 | instance | changed: [instance] => (item={'name': 'net.ipv4.tcp_adv_win_scale', 'value': 1}) 2026-07-30 20:33:11.266683 | instance | changed: [instance] => (item={'name': 'net.ipv4.neigh.default.gc_thresh1', 'value': 128}) 2026-07-30 20:33:11.266692 | instance | changed: [instance] => (item={'name': 'net.ipv4.neigh.default.gc_thresh2', 'value': 28872}) 2026-07-30 20:33:11.266702 | instance | changed: [instance] => (item={'name': 'net.ipv4.neigh.default.gc_thresh3', 'value': 32768}) 2026-07-30 20:33:11.266710 | instance | changed: [instance] => (item={'name': 'net.ipv6.neigh.default.gc_thresh1', 'value': 128}) 2026-07-30 20:33:11.266726 | instance | changed: [instance] => (item={'name': 'net.ipv6.neigh.default.gc_thresh2', 'value': 28872}) 2026-07-30 20:33:11.266735 | instance | changed: [instance] => (item={'name': 'net.ipv6.neigh.default.gc_thresh3', 'value': 32768}) 2026-07-30 20:33:11.266744 | instance | 2026-07-30 20:33:11.266753 | instance | TASK [vexxhost.atmosphere.ethtool : Create folder for persistent configuration] *** 2026-07-30 20:33:11.266772 | instance | Thursday 30 July 2026 20:33:08 +0000 (0:00:04.785) 0:00:25.073 ********* 2026-07-30 20:33:11.266781 | instance | changed: [instance] 2026-07-30 20:33:11.266791 | instance | 2026-07-30 20:33:11.266800 | instance | TASK [vexxhost.atmosphere.ethtool : Install persistent "ethtool" tuning] ******* 2026-07-30 20:33:11.266809 | instance | Thursday 30 July 2026 20:33:08 +0000 (0:00:00.199) 0:00:25.272 ********* 2026-07-30 20:33:11.266818 | instance | changed: [instance] 2026-07-30 20:33:11.266828 | instance | 2026-07-30 20:33:11.266837 | instance | TASK [vexxhost.atmosphere.ethtool : Run "ethtool" tuning] ********************** 2026-07-30 20:33:11.266846 | instance | Thursday 30 July 2026 20:33:09 +0000 (0:00:00.467) 0:00:25.740 ********* 2026-07-30 20:33:11.266855 | instance | ok: [instance] 2026-07-30 20:33:11.266865 | instance | 2026-07-30 20:33:11.266874 | instance | TASK [Set a fact with the "atmosphere_images" for other plays] ***************** 2026-07-30 20:33:11.266883 | instance | Thursday 30 July 2026 20:33:09 +0000 (0:00:00.323) 0:00:26.064 ********* 2026-07-30 20:33:11.266889 | instance | ok: [instance] 2026-07-30 20:33:11.266897 | instance | 2026-07-30 20:33:11.266912 | instance | PLAY [Configure Kubernetes VIP] ************************************************ 2026-07-30 20:33:11.266920 | instance | 2026-07-30 20:33:11.266927 | instance | TASK [Gathering Facts] ********************************************************* 2026-07-30 20:33:11.266933 | instance | Thursday 30 July 2026 20:33:09 +0000 (0:00:00.153) 0:00:26.217 ********* 2026-07-30 20:33:11.266941 | instance | ok: [instance] 2026-07-30 20:33:11.266947 | instance | 2026-07-30 20:33:11.266954 | instance | TASK [vexxhost.containers.directory : Create directory (/etc/kubernetes/manifests)] *** 2026-07-30 20:33:11.266960 | instance | Thursday 30 July 2026 20:33:10 +0000 (0:00:00.725) 0:00:26.943 ********* 2026-07-30 20:33:11.266966 | instance | changed: [instance] 2026-07-30 20:33:11.266972 | instance | 2026-07-30 20:33:11.266979 | instance | TASK [vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack] **************** 2026-07-30 20:33:11.266985 | instance | Thursday 30 July 2026 20:33:10 +0000 (0:00:00.208) 0:00:27.151 ********* 2026-07-30 20:33:11.266992 | instance | ok: [instance] => (item=/etc/keepalived/keepalived.conf) 2026-07-30 20:33:11.586785 | instance | ok: Runtime: 0:00:00.011327 2026-07-30 20:33:11.614463 | 2026-07-30 20:33:11.614648 | TASK [Track last task for Molecule keycloak (keycloak)] 2026-07-30 20:33:11.683096 | instance | ok 2026-07-30 20:33:11.689956 | 2026-07-30 20:33:11.690033 | TASK [Advance live log offset for Molecule keycloak (keycloak)] 2026-07-30 20:33:11.747135 | instance | ok 2026-07-30 20:33:11.757036 | 2026-07-30 20:33:11.757161 | TASK [Report progress heartbeat for Molecule keycloak (keycloak)] 2026-07-30 20:33:11.819905 | instance | ok: 2026-07-30 20:33:11.820110 | instance | Molecule keycloak (keycloak) is still running after 40 seconds. 2026-07-30 20:33:11.820150 | instance | Last task: TASK [vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack] 2026-07-30 20:33:11.827318 | 2026-07-30 20:33:11.827387 | TASK [Continue monitoring Molecule keycloak (keycloak)] 2026-07-30 20:33:11.960346 | instance | ok 2026-07-30 20:33:11.970683 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml 2026-07-30 20:33:11.982751 | 2026-07-30 20:33:11.982841 | TASK [Wait before polling Molecule keycloak (keycloak)] 2026-07-30 20:33:32.048249 | instance | ok 2026-07-30 20:33:32.054066 | 2026-07-30 20:33:32.054136 | TASK [Count progress poll for Molecule keycloak (keycloak)] 2026-07-30 20:33:32.106894 | instance | ok 2026-07-30 20:33:32.115808 | 2026-07-30 20:33:32.115883 | TASK [Poll status for Molecule keycloak (keycloak)] 2026-07-30 20:33:32.330812 | instance | ok 2026-07-30 20:33:32.338656 | 2026-07-30 20:33:32.338733 | TASK [Inspect live log for Molecule keycloak (keycloak)] 2026-07-30 20:33:32.523118 | instance | ok 2026-07-30 20:33:32.532060 | 2026-07-30 20:33:32.532142 | TASK [Calculate new output for Molecule keycloak (keycloak)] 2026-07-30 20:33:32.587548 | instance | ok 2026-07-30 20:33:32.594857 | 2026-07-30 20:33:32.594955 | TASK [Read new output for Molecule keycloak (keycloak)] 2026-07-30 20:33:32.799382 | instance | ok: [instance] => (item=/etc/keepalived/check_apiserver.sh) 2026-07-30 20:33:32.799475 | instance | ok: [instance] => (item=/etc/kubernetes/manifests/keepalived.yaml) 2026-07-30 20:33:32.799489 | instance | ok: [instance] => (item=/etc/haproxy/haproxy.cfg) 2026-07-30 20:33:32.799503 | instance | ok: [instance] => (item=/etc/kubernetes/manifests/haproxy.yaml) 2026-07-30 20:33:32.799512 | instance | 2026-07-30 20:33:32.799525 | instance | TASK [vexxhost.kubernetes.kube_vip : Switch API server to run on port 6443] **** 2026-07-30 20:33:32.799537 | instance | Thursday 30 July 2026 20:33:11 +0000 (0:00:00.936) 0:00:28.088 ********* 2026-07-30 20:33:32.799547 | instance | 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-07-30 20:33:32.799562 | instance | 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-07-30 20:33:32.799581 | instance | 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-07-30 20:33:32.799591 | instance | ...ignoring 2026-07-30 20:33:32.799600 | instance | 2026-07-30 20:33:32.799608 | instance | TASK [vexxhost.kubernetes.kube_vip : Check if super-admin.conf exists] ********* 2026-07-30 20:33:32.799617 | instance | Thursday 30 July 2026 20:33:12 +0000 (0:00:00.673) 0:00:28.761 ********* 2026-07-30 20:33:32.799626 | instance | ok: [instance] 2026-07-30 20:33:32.799635 | instance | 2026-07-30 20:33:32.799644 | instance | TASK [vexxhost.kubernetes.kube_vip : Check if kubeadm has already run] ********* 2026-07-30 20:33:32.799653 | instance | Thursday 30 July 2026 20:33:12 +0000 (0:00:00.209) 0:00:28.970 ********* 2026-07-30 20:33:32.799661 | instance | ok: [instance] 2026-07-30 20:33:32.799670 | instance | 2026-07-30 20:33:32.799679 | instance | TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path] ************ 2026-07-30 20:33:32.799688 | instance | Thursday 30 July 2026 20:33:12 +0000 (0:00:00.191) 0:00:29.162 ********* 2026-07-30 20:33:32.799696 | instance | ok: [instance] 2026-07-30 20:33:32.799705 | instance | 2026-07-30 20:33:32.799714 | instance | TASK [vexxhost.kubernetes.kube_vip : Set fact with KUBECONFIG path (with super-admin.conf)] *** 2026-07-30 20:33:32.799723 | instance | Thursday 30 July 2026 20:33:12 +0000 (0:00:00.031) 0:00:29.194 ********* 2026-07-30 20:33:32.799732 | instance | ok: [instance] 2026-07-30 20:33:32.799740 | instance | 2026-07-30 20:33:32.799749 | instance | TASK [vexxhost.kubernetes.kube_vip : Upload Kubernetes manifest] *************** 2026-07-30 20:33:32.799758 | instance | Thursday 30 July 2026 20:33:12 +0000 (0:00:00.038) 0:00:29.233 ********* 2026-07-30 20:33:32.799767 | instance | ok: [instance] 2026-07-30 20:33:32.799775 | instance | 2026-07-30 20:33:32.799784 | instance | TASK [vexxhost.kubernetes.kube_vip : Ensure kube-vip configuration file] ******* 2026-07-30 20:33:32.799793 | instance | Thursday 30 July 2026 20:33:13 +0000 (0:00:00.550) 0:00:29.783 ********* 2026-07-30 20:33:32.799802 | instance | ok: [instance] 2026-07-30 20:33:32.799810 | instance | 2026-07-30 20:33:32.799819 | instance | TASK [vexxhost.kubernetes.kube_vip : Flush handlers] *************************** 2026-07-30 20:33:32.799828 | instance | Thursday 30 July 2026 20:33:13 +0000 (0:00:00.201) 0:00:29.985 ********* 2026-07-30 20:33:32.799837 | instance | 2026-07-30 20:33:32.799845 | instance | PLAY [Install Kubernetes] ****************************************************** 2026-07-30 20:33:32.799854 | instance | 2026-07-30 20:33:32.799863 | instance | TASK [Gathering Facts] ********************************************************* 2026-07-30 20:33:32.799872 | instance | Thursday 30 July 2026 20:33:13 +0000 (0:00:00.056) 0:00:30.042 ********* 2026-07-30 20:33:32.799880 | instance | ok: [instance] 2026-07-30 20:33:32.799889 | instance | 2026-07-30 20:33:32.799898 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-30 20:33:32.799906 | instance | Thursday 30 July 2026 20:33:14 +0000 (0:00:00.733) 0:00:30.775 ********* 2026-07-30 20:33:32.799923 | instance | ok: [instance] 2026-07-30 20:33:32.799932 | instance | 2026-07-30 20:33:32.800020 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-07-30 20:33:32.800033 | instance | Thursday 30 July 2026 20:33:14 +0000 (0:00:00.214) 0:00:30.989 ********* 2026-07-30 20:33:32.800042 | instance | skipping: [instance] 2026-07-30 20:33:32.800051 | instance | 2026-07-30 20:33:32.800060 | instance | TASK [vexxhost.containers.directory : Create directory (/var/lib/downloads)] *** 2026-07-30 20:33:32.800069 | instance | Thursday 30 July 2026 20:33:14 +0000 (0:00:00.039) 0:00:31.029 ********* 2026-07-30 20:33:32.800078 | instance | changed: [instance] 2026-07-30 20:33:32.800087 | instance | 2026-07-30 20:33:32.800096 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-07-30 20:33:32.800105 | instance | Thursday 30 July 2026 20:33:14 +0000 (0:00:00.218) 0:00:31.247 ********* 2026-07-30 20:33:32.800114 | instance | ok: [instance] => { 2026-07-30 20:33:32.800123 | instance | "msg": "https://github.com/opencontainers/runc/releases/download/v1.4.2/runc.amd64" 2026-07-30 20:33:32.800132 | instance | } 2026-07-30 20:33:32.800141 | instance | 2026-07-30 20:33:32.800149 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-07-30 20:33:32.800158 | instance | Thursday 30 July 2026 20:33:14 +0000 (0:00:00.080) 0:00:31.328 ********* 2026-07-30 20:33:32.800167 | instance | changed: [instance] 2026-07-30 20:33:32.800176 | instance | 2026-07-30 20:33:32.800185 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-07-30 20:33:32.800194 | instance | Thursday 30 July 2026 20:33:15 +0000 (0:00:00.665) 0:00:31.993 ********* 2026-07-30 20:33:32.800203 | instance | skipping: [instance] 2026-07-30 20:33:32.800211 | instance | 2026-07-30 20:33:32.800220 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-30 20:33:32.800229 | instance | Thursday 30 July 2026 20:33:15 +0000 (0:00:00.046) 0:00:32.039 ********* 2026-07-30 20:33:32.800238 | instance | ok: [instance] 2026-07-30 20:33:32.800247 | instance | 2026-07-30 20:33:32.800255 | instance | TASK [vexxhost.containers.package : Update state for tar] ********************** 2026-07-30 20:33:32.800273 | instance | Thursday 30 July 2026 20:33:15 +0000 (0:00:00.220) 0:00:32.260 ********* 2026-07-30 20:33:32.800283 | instance | ok: [instance] 2026-07-30 20:33:32.800291 | instance | 2026-07-30 20:33:32.800300 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-07-30 20:33:32.800309 | instance | Thursday 30 July 2026 20:33:16 +0000 (0:00:01.248) 0:00:33.508 ********* 2026-07-30 20:33:32.800318 | instance | ok: [instance] => { 2026-07-30 20:33:32.800327 | instance | "msg": "https://github.com/containerd/containerd/releases/download/v2.3.2/containerd-2.3.2-linux-amd64.tar.gz" 2026-07-30 20:33:32.800336 | instance | } 2026-07-30 20:33:32.800345 | instance | 2026-07-30 20:33:32.800354 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-07-30 20:33:32.800363 | instance | Thursday 30 July 2026 20:33:17 +0000 (0:00:00.064) 0:00:33.573 ********* 2026-07-30 20:33:32.800372 | instance | changed: [instance] 2026-07-30 20:33:32.800380 | instance | 2026-07-30 20:33:32.800389 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-07-30 20:33:32.800398 | instance | Thursday 30 July 2026 20:33:17 +0000 (0:00:00.733) 0:00:34.307 ********* 2026-07-30 20:33:32.800407 | instance | changed: [instance] 2026-07-30 20:33:32.800415 | instance | 2026-07-30 20:33:32.800428 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-07-30 20:33:32.800437 | instance | Thursday 30 July 2026 20:33:20 +0000 (0:00:02.794) 0:00:37.101 ********* 2026-07-30 20:33:32.800446 | instance | skipping: [instance] 2026-07-30 20:33:32.800455 | instance | 2026-07-30 20:33:32.800469 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-07-30 20:33:32.800478 | instance | Thursday 30 July 2026 20:33:20 +0000 (0:00:00.031) 0:00:37.132 ********* 2026-07-30 20:33:32.800487 | instance | skipping: [instance] 2026-07-30 20:33:32.800496 | instance | 2026-07-30 20:33:32.800506 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-07-30 20:33:32.800523 | instance | Thursday 30 July 2026 20:33:20 +0000 (0:00:00.031) 0:00:37.164 ********* 2026-07-30 20:33:32.800533 | instance | skipping: [instance] 2026-07-30 20:33:32.800541 | instance | 2026-07-30 20:33:32.800550 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-07-30 20:33:32.800559 | instance | Thursday 30 July 2026 20:33:20 +0000 (0:00:00.032) 0:00:37.196 ********* 2026-07-30 20:33:32.800568 | instance | changed: [instance] 2026-07-30 20:33:32.800579 | instance | 2026-07-30 20:33:32.800598 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-07-30 20:33:32.800630 | instance | Thursday 30 July 2026 20:33:26 +0000 (0:00:05.635) 0:00:42.831 ********* 2026-07-30 20:33:32.800644 | instance | changed: [instance] 2026-07-30 20:33:32.800653 | instance | 2026-07-30 20:33:32.800662 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-07-30 20:33:32.800671 | instance | Thursday 30 July 2026 20:33:26 +0000 (0:00:00.456) 0:00:43.287 ********* 2026-07-30 20:33:32.800680 | instance | changed: [instance] => (item={'path': '/etc/containerd'}) 2026-07-30 20:33:32.800688 | instance | changed: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-07-30 20:33:32.800705 | instance | changed: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-07-30 20:33:32.800714 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-07-30 20:33:32.800724 | instance | changed: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-07-30 20:33:32.800733 | instance | 2026-07-30 20:33:32.800742 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-07-30 20:33:32.800750 | instance | Thursday 30 July 2026 20:33:27 +0000 (0:00:00.917) 0:00:44.204 ********* 2026-07-30 20:33:32.800759 | instance | changed: [instance] 2026-07-30 20:33:32.800768 | instance | 2026-07-30 20:33:32.800776 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-07-30 20:33:32.800785 | instance | Thursday 30 July 2026 20:33:28 +0000 (0:00:00.573) 0:00:44.778 ********* 2026-07-30 20:33:32.800793 | instance | 2026-07-30 20:33:32.800802 | instance | RUNNING HANDLER [vexxhost.containers.containerd : Restart containerd] ********** 2026-07-30 20:33:32.800810 | instance | Thursday 30 July 2026 20:33:28 +0000 (0:00:00.007) 0:00:44.785 ********* 2026-07-30 20:33:32.800819 | instance | changed: [instance] 2026-07-30 20:33:32.800828 | instance | 2026-07-30 20:33:32.800836 | instance | RUNNING HANDLER [vexxhost.kubernetes.kubelet : Reload systemd] ***************** 2026-07-30 20:33:32.800845 | instance | Thursday 30 July 2026 20:33:28 +0000 (0:00:00.707) 0:00:45.493 ********* 2026-07-30 20:33:32.800854 | instance | ok: [instance] 2026-07-30 20:33:32.800862 | instance | 2026-07-30 20:33:32.800871 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-07-30 20:33:32.800880 | instance | Thursday 30 July 2026 20:33:29 +0000 (0:00:00.693) 0:00:46.187 ********* 2026-07-30 20:33:32.800888 | instance | changed: [instance] 2026-07-30 20:33:32.800897 | instance | 2026-07-30 20:33:32.800905 | instance | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Retrieve the "kubeadm-config" ConfigMap] *** 2026-07-30 20:33:32.800914 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.535) 0:00:46.722 ********* 2026-07-30 20:33:32.800924 | instance | fatal: [instance]: FAILED! => {"changed": false, "msg": "Failed to import the required Python library (kubernetes) on instance's Python /usr/bin/python3.10. Please read the module documentation and install it in the appropriate location. If the required library is installed, but Ansible is using the wrong Python interpreter, please consult the documentation on ansible_python_interpreter"} 2026-07-30 20:33:32.800934 | instance | ...ignoring 2026-07-30 20:33:32.800942 | instance | 2026-07-30 20:33:32.800951 | instance | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Parse the ClusterConfiguration] *** 2026-07-30 20:33:32.800959 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.543) 0:00:47.265 ********* 2026-07-30 20:33:32.800968 | instance | skipping: [instance] 2026-07-30 20:33:32.800986 | instance | 2026-07-30 20:33:32.800995 | instance | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Retrieve the current Kubernetes version] *** 2026-07-30 20:33:32.801012 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.025) 0:00:47.290 ********* 2026-07-30 20:33:32.801022 | instance | skipping: [instance] 2026-07-30 20:33:32.801030 | instance | 2026-07-30 20:33:32.801039 | instance | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Extract major, minor, and patch versions] *** 2026-07-30 20:33:32.801048 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.027) 0:00:47.317 ********* 2026-07-30 20:33:32.801056 | instance | skipping: [instance] 2026-07-30 20:33:32.801065 | instance | 2026-07-30 20:33:32.801073 | instance | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Fail if we're jumping more than one minor version] *** 2026-07-30 20:33:32.801082 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.034) 0:00:47.352 ********* 2026-07-30 20:33:32.801091 | instance | skipping: [instance] 2026-07-30 20:33:32.801100 | instance | 2026-07-30 20:33:32.801108 | instance | TASK [vexxhost.kubernetes.kubernetes_upgrade_check : Set fact if we need to upgrade] *** 2026-07-30 20:33:32.801117 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.032) 0:00:47.385 ********* 2026-07-30 20:33:32.801126 | instance | skipping: [instance] 2026-07-30 20:33:32.801135 | instance | 2026-07-30 20:33:32.801144 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-30 20:33:32.801152 | instance | Thursday 30 July 2026 20:33:30 +0000 (0:00:00.039) 0:00:47.424 ********* 2026-07-30 20:33:32.801161 | instance | ok: [instance] 2026-07-30 20:33:32.801229 | instance | 2026-07-30 20:33:32.801241 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-07-30 20:33:32.801250 | instance | Thursday 30 July 2026 20:33:31 +0000 (0:00:00.203) 0:00:47.628 ********* 2026-07-30 20:33:32.801260 | instance | ok: [instance] => { 2026-07-30 20:33:32.801273 | instance | "msg": "https://dl.k8s.io/release/v1.28.13/bin/linux/amd64/kubeadm" 2026-07-30 20:33:32.801282 | instance | } 2026-07-30 20:33:32.801291 | instance | 2026-07-30 20:33:32.801300 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-07-30 20:33:32.801308 | instance | Thursday 30 July 2026 20:33:31 +0000 (0:00:00.043) 0:00:47.672 ********* 2026-07-30 20:33:32.801317 | instance | changed: [instance] 2026-07-30 20:33:32.801325 | instance | 2026-07-30 20:33:32.801334 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-07-30 20:33:32.801343 | instance | Thursday 30 July 2026 20:33:31 +0000 (0:00:00.637) 0:00:48.309 ********* 2026-07-30 20:33:32.801351 | instance | skipping: [instance] 2026-07-30 20:33:32.801360 | instance | 2026-07-30 20:33:32.801368 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-30 20:33:32.801377 | instance | Thursday 30 July 2026 20:33:31 +0000 (0:00:00.039) 0:00:48.349 ********* 2026-07-30 20:33:32.801386 | instance | ok: [instance] 2026-07-30 20:33:32.801394 | instance | 2026-07-30 20:33:32.801403 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-07-30 20:33:32.801412 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.205) 0:00:48.554 ********* 2026-07-30 20:33:32.801420 | instance | ok: [instance] => { 2026-07-30 20:33:32.801429 | instance | "msg": "https://dl.k8s.io/release/v1.28.13/bin/linux/amd64/kubectl" 2026-07-30 20:33:32.801438 | instance | } 2026-07-30 20:33:32.801446 | instance | 2026-07-30 20:33:32.801455 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-07-30 20:33:32.801464 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.043) 0:00:48.598 ********* 2026-07-30 20:33:33.136416 | instance | ok: Runtime: 0:00:00.008564 2026-07-30 20:33:33.144527 | 2026-07-30 20:33:33.144624 | TASK [Track last task for Molecule keycloak (keycloak)] 2026-07-30 20:33:33.218726 | instance | ok 2026-07-30 20:33:33.227192 | 2026-07-30 20:33:33.227303 | TASK [Advance live log offset for Molecule keycloak (keycloak)] 2026-07-30 20:33:33.281727 | instance | ok 2026-07-30 20:33:33.289968 | 2026-07-30 20:33:33.290049 | TASK [Report progress heartbeat for Molecule keycloak (keycloak)] 2026-07-30 20:33:33.344328 | instance | ok: 2026-07-30 20:33:33.344473 | instance | Molecule keycloak (keycloak) is still running after 60 seconds. 2026-07-30 20:33:33.344499 | instance | Last task: TASK [vexxhost.containers.download_artifact : Download item] 2026-07-30 20:33:33.350439 | 2026-07-30 20:33:33.350510 | TASK [Continue monitoring Molecule keycloak (keycloak)] 2026-07-30 20:33:33.434242 | instance | ok 2026-07-30 20:33:33.446592 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml 2026-07-30 20:33:33.459629 | 2026-07-30 20:33:33.459700 | TASK [Wait before polling Molecule keycloak (keycloak)] 2026-07-30 20:33:53.522435 | instance | ok 2026-07-30 20:33:53.535626 | 2026-07-30 20:33:53.535712 | TASK [Count progress poll for Molecule keycloak (keycloak)] 2026-07-30 20:33:53.598955 | instance | ok 2026-07-30 20:33:53.605486 | 2026-07-30 20:33:53.605558 | TASK [Poll status for Molecule keycloak (keycloak)] 2026-07-30 20:33:53.835939 | instance | ok 2026-07-30 20:33:54.410275 | 2026-07-30 20:33:54.410379 | TASK [Inspect live log for Molecule keycloak (keycloak)] 2026-07-30 20:33:54.605228 | instance | ok 2026-07-30 20:33:54.612693 | 2026-07-30 20:33:54.612778 | TASK [Calculate new output for Molecule keycloak (keycloak)] 2026-07-30 20:33:54.669785 | instance | ok 2026-07-30 20:33:54.675860 | 2026-07-30 20:33:54.675932 | TASK [Read new output for Molecule keycloak (keycloak)] 2026-07-30 20:33:54.881833 | instance | changed: [instance] 2026-07-30 20:33:54.881926 | instance | 2026-07-30 20:33:54.881940 | instance | TASK [vexxhost.containers.download_artifact : Extract archive] ***************** 2026-07-30 20:33:54.881950 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.707) 0:00:49.306 ********* 2026-07-30 20:33:54.881960 | instance | skipping: [instance] 2026-07-30 20:33:54.881966 | instance | 2026-07-30 20:33:54.881973 | instance | TASK [vexxhost.containers.containerd : Install SELinux packages] *************** 2026-07-30 20:33:54.881980 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.052) 0:00:49.358 ********* 2026-07-30 20:33:54.881985 | instance | skipping: [instance] 2026-07-30 20:33:54.882010 | instance | 2026-07-30 20:33:54.882016 | instance | TASK [vexxhost.containers.containerd : Set SELinux to permissive at runtime] *** 2026-07-30 20:33:54.882023 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.030) 0:00:49.389 ********* 2026-07-30 20:33:54.882029 | instance | skipping: [instance] 2026-07-30 20:33:54.882035 | instance | 2026-07-30 20:33:54.882040 | instance | TASK [vexxhost.containers.containerd : Persist SELinux permissive mode] ******** 2026-07-30 20:33:54.882046 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.029) 0:00:49.418 ********* 2026-07-30 20:33:54.882051 | instance | skipping: [instance] 2026-07-30 20:33:54.882057 | instance | 2026-07-30 20:33:54.882062 | instance | TASK [vexxhost.containers.containerd : Install AppArmor packages] ************** 2026-07-30 20:33:54.882067 | instance | Thursday 30 July 2026 20:33:32 +0000 (0:00:00.033) 0:00:49.452 ********* 2026-07-30 20:33:54.882073 | instance | ok: [instance] 2026-07-30 20:33:54.882079 | instance | 2026-07-30 20:33:54.882085 | instance | TASK [vexxhost.containers.containerd : Create systemd service file for containerd] *** 2026-07-30 20:33:54.882094 | instance | Thursday 30 July 2026 20:33:34 +0000 (0:00:01.091) 0:00:50.543 ********* 2026-07-30 20:33:54.882100 | instance | ok: [instance] 2026-07-30 20:33:54.882105 | instance | 2026-07-30 20:33:54.882110 | instance | TASK [vexxhost.containers.containerd : Create folders for configuration] ******* 2026-07-30 20:33:54.882116 | instance | Thursday 30 July 2026 20:33:34 +0000 (0:00:00.513) 0:00:51.057 ********* 2026-07-30 20:33:54.882122 | instance | ok: [instance] => (item={'path': '/etc/containerd'}) 2026-07-30 20:33:54.882128 | instance | ok: [instance] => (item={'path': '/var/lib/containerd', 'mode': '0o700'}) 2026-07-30 20:33:54.882137 | instance | ok: [instance] => (item={'path': '/run/containerd', 'mode': '0o711'}) 2026-07-30 20:33:54.882143 | instance | ok: [instance] => (item={'path': '/run/containerd/io.containerd.grpc.v1.cri', 'mode': '0o700'}) 2026-07-30 20:33:54.882149 | instance | ok: [instance] => (item={'path': '/run/containerd/io.containerd.sandbox.controller.v1.shim', 'mode': '0o700'}) 2026-07-30 20:33:54.882155 | instance | 2026-07-30 20:33:54.882161 | instance | TASK [vexxhost.containers.containerd : Create containerd config file] ********** 2026-07-30 20:33:54.882166 | instance | Thursday 30 July 2026 20:33:35 +0000 (0:00:00.930) 0:00:51.987 ********* 2026-07-30 20:33:54.882172 | instance | ok: [instance] 2026-07-30 20:33:54.882177 | instance | 2026-07-30 20:33:54.882183 | instance | TASK [vexxhost.containers.containerd : Force any restarts if necessary] ******** 2026-07-30 20:33:54.882188 | instance | Thursday 30 July 2026 20:33:35 +0000 (0:00:00.517) 0:00:52.504 ********* 2026-07-30 20:33:54.882194 | instance | 2026-07-30 20:33:54.882199 | instance | TASK [vexxhost.containers.containerd : Enable and start service] *************** 2026-07-30 20:33:54.882204 | instance | Thursday 30 July 2026 20:33:36 +0000 (0:00:00.007) 0:00:52.511 ********* 2026-07-30 20:33:54.882210 | instance | ok: [instance] 2026-07-30 20:33:54.882215 | instance | 2026-07-30 20:33:54.882231 | instance | TASK [vexxhost.containers.forget_package : Forget package] ********************* 2026-07-30 20:33:54.882236 | instance | Thursday 30 July 2026 20:33:36 +0000 (0:00:00.374) 0:00:52.886 ********* 2026-07-30 20:33:54.882247 | instance | ok: [instance] 2026-07-30 20:33:54.882258 | instance | 2026-07-30 20:33:54.882267 | instance | TASK [vexxhost.containers.download_artifact : Starting download of file] ******* 2026-07-30 20:33:54.882277 | instance | Thursday 30 July 2026 20:33:36 +0000 (0:00:00.205) 0:00:53.091 ********* 2026-07-30 20:33:54.882286 | instance | ok: [instance] => { 2026-07-30 20:33:54.882296 | instance | "msg": "https://github.com/kubernetes-sigs/cri-tools/releases/download/v1.36.0/crictl-v1.36.0-linux-amd64.tar.gz" 2026-07-30 20:33:54.882362 | instance | } 2026-07-30 20:33:54.882384 | instance | 2026-07-30 20:33:54.882391 | instance | TASK [vexxhost.containers.download_artifact : Download item] ******************* 2026-07-30 20:33:54.882397 | instance | Thursday 30 July 2026 20:33:36 +0000 (0:00:00.053) 0:00:53.145 ********* 2026-07-30 20:33:54.882403 | instance | FAILED - RETRYING: [instance]: Download item (4 retries left). 2026-07-30 20:33:54.882408 | instance | FAILED - RETRYING: [instance]: Download item (3 retries left). 2026-07-30 20:33:54.882414 | instance | FAILED - RETRYING: [instance]: Download item (2 retries left). 2026-07-30 20:33:54.882419 | instance | FAILED - RETRYING: [instance]: Download item (1 retries left). 2026-07-30 20:33:55.213225 | instance | ok: Runtime: 0:00:00.008491 2026-07-30 20:33:55.222759 | 2026-07-30 20:33:55.222849 | TASK [Track last task for Molecule keycloak (keycloak)] 2026-07-30 20:33:55.295140 | instance | ok 2026-07-30 20:33:55.302973 | 2026-07-30 20:33:55.303054 | TASK [Advance live log offset for Molecule keycloak (keycloak)] 2026-07-30 20:33:55.357253 | instance | ok 2026-07-30 20:33:55.365615 | 2026-07-30 20:33:55.365701 | TASK [Report progress heartbeat for Molecule keycloak (keycloak)] 2026-07-30 20:33:55.418177 | instance | ok: 2026-07-30 20:33:55.418350 | instance | Molecule keycloak (keycloak) is still running after 80 seconds. 2026-07-30 20:33:55.418390 | instance | Last task: TASK [vexxhost.containers.download_artifact : Download item] 2026-07-30 20:33:55.432716 | 2026-07-30 20:33:55.432862 | TASK [Continue monitoring Molecule keycloak (keycloak)] 2026-07-30 20:33:55.485736 | instance | ok 2026-07-30 20:33:55.498927 | instance | included: /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml 2026-07-30 20:33:55.513790 | 2026-07-30 20:33:55.513890 | TASK [Wait before polling Molecule keycloak (keycloak)] 2026-07-30 20:34:15.572326 | instance | ok 2026-07-30 20:34:15.579162 | 2026-07-30 20:34:15.579249 | TASK [Count progress poll for Molecule keycloak (keycloak)] 2026-07-30 20:34:15.645502 | instance | ok 2026-07-30 20:34:15.653517 | 2026-07-30 20:34:15.653596 | TASK [Poll status for Molecule keycloak (keycloak)] 2026-07-30 20:34:15.882173 | instance | ok: non-zero return code 2026-07-30 20:34:15.891886 | 2026-07-30 20:34:15.892130 | TASK [Inspect live log for Molecule keycloak (keycloak)] 2026-07-30 20:34:16.079559 | instance | ok 2026-07-30 20:34:16.088239 | 2026-07-30 20:34:16.088330 | TASK [Calculate new output for Molecule keycloak (keycloak)] 2026-07-30 20:34:16.276495 | instance | ok 2026-07-30 20:34:16.282508 | 2026-07-30 20:34:16.282587 | TASK [Read new output for Molecule keycloak (keycloak)] 2026-07-30 20:34:16.485541 | instance | fatal: [instance]: FAILED! => {"attempts": 4, "censored": "the output has been hidden due to the fact that 'no_log: true' was specified for this result", "changed": false} 2026-07-30 20:34:16.485792 | instance | 2026-07-30 20:34:16.485808 | instance | PLAY RECAP ********************************************************************* 2026-07-30 20:34:16.485817 | instance | instance : ok=52 changed=18 unreachable=0 failed=1 skipped=15 rescued=0 ignored=2 2026-07-30 20:34:16.485824 | instance | localhost : ok=40 changed=21 unreachable=0 failed=0 skipped=1 rescued=0 ignored=0 2026-07-30 20:34:16.485829 | instance | 2026-07-30 20:34:16.485836 | instance | Thursday 30 July 2026 20:33:59 +0000 (0:00:22.365) 0:01:15.510 ********* 2026-07-30 20:34:16.485845 | instance | =============================================================================== 2026-07-30 20:34:16.485853 | instance | vexxhost.containers.download_artifact : Download item ------------------ 22.37s 2026-07-30 20:34:16.485861 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 5.64s 2026-07-30 20:34:16.485869 | instance | vexxhost.atmosphere.sysctl : Configure sysctl values -------------------- 4.79s 2026-07-30 20:34:16.485877 | instance | Generate SSH keys for missing variables --------------------------------- 3.86s 2026-07-30 20:34:16.485890 | instance | vexxhost.containers.download_artifact : Extract archive ----------------- 2.79s 2026-07-30 20:34:16.485899 | instance | Create folders for workspace -------------------------------------------- 1.43s 2026-07-30 20:34:16.485907 | instance | vexxhost.containers.package : Update state for tar ---------------------- 1.25s 2026-07-30 20:34:16.485914 | instance | Install "dirmngr" for GPG keyserver operations -------------------------- 1.23s 2026-07-30 20:34:16.485922 | instance | Gathering Facts --------------------------------------------------------- 1.19s 2026-07-30 20:34:16.485930 | instance | vexxhost.containers.containerd : Install AppArmor packages -------------- 1.09s 2026-07-30 20:34:16.485937 | instance | vexxhost.kubernetes.kube_vip : Uninstall legacy HA stack ---------------- 0.94s 2026-07-30 20:34:16.485945 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.93s 2026-07-30 20:34:16.485953 | instance | vexxhost.containers.containerd : Create folders for configuration ------- 0.92s 2026-07-30 20:34:16.485961 | instance | Gathering Facts --------------------------------------------------------- 0.90s 2026-07-30 20:34:16.485969 | instance | Gathering Facts --------------------------------------------------------- 0.73s 2026-07-30 20:34:16.485976 | instance | vexxhost.containers.download_artifact : Download item ------------------- 0.73s 2026-07-30 20:34:16.485984 | instance | Generate endpoint skeleton for missing variables ------------------------ 0.73s 2026-07-30 20:34:16.485992 | instance | Write new Ceph control plane configuration file to disk ----------------- 0.73s 2026-07-30 20:34:16.486000 | instance | Gathering Facts --------------------------------------------------------- 0.73s 2026-07-30 20:34:16.486008 | instance | vexxhost.containers.containerd : Restart containerd --------------------- 0.71s 2026-07-30 20:34:16.486021 | instance | CRITICAL Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.keycloak/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/prepare.yml 2026-07-30 20:34:16.486033 | instance | ERROR [keycloak > prepare] Executed: Failed 2026-07-30 20:34:16.486042 | instance | ERROR Ansible return code was 2, command was: ansible-playbook --inventory /home/zuul/.ansible/tmp/molecule.v9Wo.keycloak/inventory --skip-tags molecule-notest,notest --inventory=/home/zuul/src/github.com/vexxhost/atmosphere/inventory.yaml /home/zuul/src/github.com/vexxhost/atmosphere/molecule/keycloak/prepare.yml 2026-07-30 20:34:16.820291 | instance | ok: Runtime: 0:00:00.008005 2026-07-30 20:34:16.833618 | 2026-07-30 20:34:16.833718 | TASK [Track last task for Molecule keycloak (keycloak)] 2026-07-30 20:34:16.879950 | instance | skipping: Conditional result was False 2026-07-30 20:34:16.895854 | 2026-07-30 20:34:16.896016 | TASK [Advance live log offset for Molecule keycloak (keycloak)] 2026-07-30 20:34:16.941390 | instance | ok 2026-07-30 20:34:16.950596 | 2026-07-30 20:34:16.950677 | TASK [Report progress heartbeat for Molecule keycloak (keycloak)] 2026-07-30 20:34:16.986554 | instance | skipping: Conditional result was False 2026-07-30 20:34:17.000583 | 2026-07-30 20:34:17.000680 | TASK [Continue monitoring Molecule keycloak (keycloak)] 2026-07-30 20:34:17.055361 | instance | skipping: Conditional result was False 2026-07-30 20:34:17.063410 | 2026-07-30 20:34:17.063530 | TASK [Start progress monitor chunk for Molecule keycloak (keycloak)] 2026-07-30 20:34:17.108610 | instance | skipping: Conditional result was False 2026-07-30 20:34:17.123551 | 2026-07-30 20:34:17.123732 | TASK [Start progress monitor chunk for Molecule keycloak (keycloak)] 2026-07-30 20:34:17.168341 | instance | skipping: Conditional result was False 2026-07-30 20:34:17.179958 | 2026-07-30 20:34:17.180062 | TASK [Start progress monitor chunk for Molecule keycloak (keycloak)] 2026-07-30 20:34:17.224467 | instance | skipping: Conditional result was False 2026-07-30 20:34:17.234384 | 2026-07-30 20:34:17.234514 | TASK [Clean up async state for Molecule keycloak (keycloak)] 2026-07-30 20:34:17.461950 | instance | ok 2026-07-30 20:34:17.467989 | 2026-07-30 20:34:17.468088 | TASK [Assert success for Molecule keycloak (keycloak)] 2026-07-30 20:34:17.526238 | instance | ERROR 2026-07-30 20:34:17.526616 | instance | { 2026-07-30 20:34:17.526645 | instance | "assertion": "(_atmosphere_ci_phase_result.rc | default(1) | int) == 0", 2026-07-30 20:34:17.526666 | instance | "evaluated_to": false, 2026-07-30 20:34:17.526685 | instance | "msg": "Molecule keycloak (keycloak) failed with exit code 2. Status: non-zero return code. Command stderr: (none) Complete output is in /tmp/logs/molecule-keycloak.log." 2026-07-30 20:34:17.526706 | instance | } failure 2026-07-30 20:34:17.527761 | 2026-07-30 20:34:17.527805 | PLAY RECAP 2026-07-30 20:34:17.527850 | instance | ok: 69 changed: 4 unreachable: 0 failed: 1 skipped: 6 rescued: 0 ignored: 0 2026-07-30 20:34:17.527872 | 2026-07-30 20:34:17.666098 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main] 2026-07-30 20:34:17.669596 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-07-30 20:34:18.274076 | 2026-07-30 20:34:18.274200 | PLAY [Publish selective Molecule plan] 2026-07-30 20:34:18.287088 | 2026-07-30 20:34:18.287220 | TASK [Check for selective Molecule plan] 2026-07-30 20:34:18.644370 | instance | ok 2026-07-30 20:34:18.652310 | 2026-07-30 20:34:18.652389 | TASK [Create log directory] 2026-07-30 20:34:18.992172 | instance | ok 2026-07-30 20:34:18.998434 | 2026-07-30 20:34:18.998505 | TASK [Copy selective Molecule plan to logs] 2026-07-30 20:34:19.385961 | instance | changed 2026-07-30 20:34:19.387615 | 2026-07-30 20:34:19.387680 | PLAY RECAP 2026-07-30 20:34:19.387743 | instance | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-30 20:34:19.387775 | 2026-07-30 20:34:19.495471 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-07-30 20:34:19.554707 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-07-30 20:34:20.161616 | 2026-07-30 20:34:20.161729 | PLAY [all] 2026-07-30 20:34:20.174996 | 2026-07-30 20:34:20.175078 | TASK [gather-host-logs : creating directory for system status] 2026-07-30 20:34:20.513079 | instance | changed 2026-07-30 20:34:20.519300 | 2026-07-30 20:34:20.519387 | TASK [gather-host-logs : Get logs for each host] 2026-07-30 20:34:20.901830 | instance | + systemd-cgls --full --all --no-pager 2026-07-30 20:34:20.914076 | instance | + ip addr 2026-07-30 20:34:20.917022 | instance | + ip route 2026-07-30 20:34:20.919128 | instance | + lsblk 2026-07-30 20:34:20.923833 | instance | + mount 2026-07-30 20:34:20.926661 | instance | + docker images 2026-07-30 20:34:20.927349 | instance | /bin/bash: line 7: docker: command not found 2026-07-30 20:34:20.927684 | instance | + brctl show 2026-07-30 20:34:20.928363 | instance | /bin/bash: line 8: brctl: command not found 2026-07-30 20:34:20.928658 | instance | + ps aux --sort=-%mem 2026-07-30 20:34:20.945217 | instance | + dpkg -l 2026-07-30 20:34:20.958959 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-07-30 20:34:20.959603 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-07-30 20:34:20.959739 | instance | /bin/bash: line 11: docker: command not found 2026-07-30 20:34:20.960098 | instance | + '[' '!' -z '' ']' 2026-07-30 20:34:21.065600 | instance | ok: Runtime: 0:00:00.065063 2026-07-30 20:34:21.073640 | 2026-07-30 20:34:21.073721 | TASK [gather-host-logs : Downloads logs to executor] 2026-07-30 20:34:21.736582 | instance | changed: 2026-07-30 20:34:21.736765 | instance | created directory /var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/logs/instance 2026-07-30 20:34:21.736807 | instance | cd+++++++++ system/ 2026-07-30 20:34:21.736837 | instance | >f+++++++++ system/brctl-show.txt 2026-07-30 20:34:21.736867 | instance | >f+++++++++ system/docker-images.txt 2026-07-30 20:34:21.736895 | instance | >f+++++++++ system/ip-addr.txt 2026-07-30 20:34:21.736926 | instance | >f+++++++++ system/ip-route.txt 2026-07-30 20:34:21.736960 | instance | >f+++++++++ system/lsblk.txt 2026-07-30 20:34:21.736988 | instance | >f+++++++++ system/mount.txt 2026-07-30 20:34:21.737049 | instance | >f+++++++++ system/packages.txt 2026-07-30 20:34:21.737078 | instance | >f+++++++++ system/ps.txt 2026-07-30 20:34:21.737105 | instance | >f+++++++++ system/systemd-cgls.txt 2026-07-30 20:34:21.746846 | 2026-07-30 20:34:21.746912 | LOOP [helm-release-status : creating directory for helm release status] 2026-07-30 20:34:21.951171 | instance | changed: "values" 2026-07-30 20:34:22.122061 | instance | changed: "releases" 2026-07-30 20:34:22.133518 | 2026-07-30 20:34:22.133633 | TASK [helm-release-status : Gather get release status for helm charts] 2026-07-30 20:34:22.403600 | instance | E0730 20:34:22.403425 3650 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:22.404453 | instance | E0730 20:34:22.404402 3650 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:22.406689 | instance | E0730 20:34:22.406645 3650 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:22.407434 | instance | E0730 20:34:22.407394 3650 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:22.409500 | instance | E0730 20:34:22.409459 3650 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:22.409548 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:22.670554 | instance | ok: Runtime: 0:00:00.072781 2026-07-30 20:34:22.677948 | 2026-07-30 20:34:22.678052 | TASK [helm-release-status : Downloads logs to executor] 2026-07-30 20:34:23.143314 | instance | changed: 2026-07-30 20:34:23.143507 | instance | cd+++++++++ helm/ 2026-07-30 20:34:23.143544 | instance | cd+++++++++ helm/releases/ 2026-07-30 20:34:23.143571 | instance | cd+++++++++ helm/values/ 2026-07-30 20:34:23.153662 | 2026-07-30 20:34:23.153748 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-07-30 20:34:23.364807 | instance | changed 2026-07-30 20:34:23.703163 | 2026-07-30 20:34:23.703309 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-07-30 20:34:23.988286 | 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-07-30 20:34:23.988392 | instance | 2026-07-30 20:34:23.995348 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-07-30 20:34:23.998238 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-07-30 20:34:24.043541 | instance | E0730 20:34:24.043422 3699 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.044541 | instance | E0730 20:34:24.044513 3699 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.044798 | instance | E0730 20:34:24.044780 3699 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.046462 | instance | E0730 20:34:24.046428 3699 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.047149 | instance | E0730 20:34:24.047103 3699 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.048075 | instance | E0730 20:34:24.047813 3704 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.048299 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:24.049064 | instance | E0730 20:34:24.048955 3704 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.049854 | instance | E0730 20:34:24.049806 3704 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.051679 | instance | E0730 20:34:24.051628 3704 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.052358 | instance | E0730 20:34:24.052305 3704 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.053670 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:24.058799 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-07-30 20:34:24.063683 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-07-30 20:34:24.118631 | instance | E0730 20:34:24.118530 3726 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.119927 | instance | E0730 20:34:24.119872 3726 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.121417 | instance | E0730 20:34:24.121317 3726 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.122056 | instance | E0730 20:34:24.122008 3726 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.123904 | instance | E0730 20:34:24.123859 3726 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.123930 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:24.126420 | instance | E0730 20:34:24.126330 3733 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.127215 | instance | E0730 20:34:24.127158 3733 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.129542 | instance | E0730 20:34:24.129504 3733 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.130528 | instance | E0730 20:34:24.130486 3733 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.131445 | instance | E0730 20:34:24.131393 3733 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.132625 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-07-30 20:34:24.132649 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:24.196601 | instance | E0730 20:34:24.196515 3749 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.197619 | instance | E0730 20:34:24.197561 3749 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.198472 | instance | E0730 20:34:24.198432 3749 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.200216 | instance | E0730 20:34:24.200185 3749 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.201340 | instance | E0730 20:34:24.201275 3749 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.201437 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:24.314821 | instance | ok: Runtime: 0:00:00.226877 2026-07-30 20:34:24.322738 | 2026-07-30 20:34:24.322874 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-07-30 20:34:24.539324 | instance | changed 2026-07-30 20:34:24.544491 | 2026-07-30 20:34:24.544598 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-07-30 20:34:24.766233 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-07-30 20:34:24.766375 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-07-30 20:34:24.767013 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-07-30 20:34:24.814862 | instance | E0730 20:34:24.814722 3780 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.815633 | instance | E0730 20:34:24.815599 3780 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.818858 | instance | E0730 20:34:24.818808 3780 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.819987 | instance | E0730 20:34:24.819940 3780 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.820913 | instance | E0730 20:34:24.820872 3780 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:24.822206 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:25.080147 | instance | ok: Runtime: 0:00:00.068389 2026-07-30 20:34:25.085342 | 2026-07-30 20:34:25.085407 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-07-30 20:34:25.611982 | instance | changed: 2026-07-30 20:34:25.612189 | instance | cd+++++++++ objects/ 2026-07-30 20:34:25.612216 | instance | cd+++++++++ objects/cluster/ 2026-07-30 20:34:25.612240 | instance | cd+++++++++ objects/namespaced/ 2026-07-30 20:34:25.623904 | 2026-07-30 20:34:25.624048 | TASK [gather-pod-logs : creating directory for pod logs] 2026-07-30 20:34:25.831448 | instance | changed 2026-07-30 20:34:25.836745 | 2026-07-30 20:34:25.836814 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-07-30 20:34:26.029378 | instance | changed 2026-07-30 20:34:26.034500 | 2026-07-30 20:34:26.034569 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-07-30 20:34:26.309430 | instance | E0730 20:34:26.309282 3830 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:26.309968 | instance | E0730 20:34:26.309931 3830 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:26.312000 | instance | E0730 20:34:26.311942 3830 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:26.312870 | instance | E0730 20:34:26.312832 3830 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:26.314628 | instance | E0730 20:34:26.314590 3830 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:26.314657 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:26.572942 | instance | ok: Runtime: 0:00:00.069411 2026-07-30 20:34:26.580553 | 2026-07-30 20:34:26.580622 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-07-30 20:34:27.073663 | instance | changed: 2026-07-30 20:34:27.073923 | instance | cd+++++++++ pod-logs/ 2026-07-30 20:34:27.073963 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-07-30 20:34:27.084553 | 2026-07-30 20:34:27.084627 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-07-30 20:34:27.296009 | instance | changed 2026-07-30 20:34:27.301409 | 2026-07-30 20:34:27.301487 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-07-30 20:34:27.564634 | instance | E0730 20:34:27.564478 3873 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:27.565516 | instance | E0730 20:34:27.565487 3873 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:27.567703 | instance | E0730 20:34:27.567655 3873 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:27.568402 | instance | E0730 20:34:27.568367 3873 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:27.570201 | instance | E0730 20:34:27.570169 3873 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:27.570240 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:27.839579 | instance | ok: Runtime: 0:00:00.075720 2026-07-30 20:34:27.846393 | 2026-07-30 20:34:27.846468 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-07-30 20:34:28.102995 | instance | E0730 20:34:28.102777 3895 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.103594 | instance | E0730 20:34:28.103558 3895 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.105407 | instance | E0730 20:34:28.105342 3895 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.106039 | instance | E0730 20:34:28.105987 3895 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.108066 | instance | E0730 20:34:28.108044 3895 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.108151 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:28.112500 | instance | ceph-mgr endpoints: 2026-07-30 20:34:28.380646 | instance | ok: Runtime: 0:00:00.069296 2026-07-30 20:34:28.387375 | 2026-07-30 20:34:28.387473 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-07-30 20:34:28.653973 | instance | E0730 20:34:28.653789 3917 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.654611 | instance | E0730 20:34:28.654587 3917 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.656349 | instance | E0730 20:34:28.656295 3917 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.656910 | instance | E0730 20:34:28.656877 3917 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.658575 | instance | E0730 20:34:28.658546 3917 memcache.go:265] couldn't get current server API group list: Get "http://localhost:8080/api?timeout=32s": dial tcp [::1]:8080: connect: connection refused 2026-07-30 20:34:28.658636 | instance | The connection to the server localhost:8080 was refused - did you specify the right host or port? 2026-07-30 20:34:28.922928 | instance | ok: Runtime: 0:00:00.073271 2026-07-30 20:34:28.930049 | 2026-07-30 20:34:28.930149 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-07-30 20:34:29.444584 | instance | changed: cd+++++++++ prometheus/ 2026-07-30 20:34:29.455507 | 2026-07-30 20:34:29.455601 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-07-30 20:34:29.654873 | instance | changed 2026-07-30 20:34:29.661379 | 2026-07-30 20:34:29.661459 | TASK [gather-selenium-data : Get selenium data] 2026-07-30 20:34:29.939928 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-07-30 20:34:29.941200 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-07-30 20:34:30.267902 | instance | ERROR 2026-07-30 20:34:30.268674 | instance | { 2026-07-30 20:34:30.268877 | instance | "delta": "0:00:00.006673", 2026-07-30 20:34:30.268949 | instance | "end": "2026-07-30 20:34:29.941533", 2026-07-30 20:34:30.269060 | instance | "msg": "non-zero return code", 2026-07-30 20:34:30.269108 | instance | "rc": 1, 2026-07-30 20:34:30.269151 | instance | "start": "2026-07-30 20:34:29.934860" 2026-07-30 20:34:30.269191 | instance | } 2026-07-30 20:34:30.269277 | instance | ERROR: Ignoring Errors 2026-07-30 20:34:30.278831 | 2026-07-30 20:34:30.278949 | TASK [gather-selenium-data : Downloads logs to executor] 2026-07-30 20:34:30.802435 | instance | changed: cd+++++++++ selenium/ 2026-07-30 20:34:30.809148 | 2026-07-30 20:34:30.809208 | PLAY RECAP 2026-07-30 20:34:30.809258 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-07-30 20:34:30.809282 | 2026-07-30 20:34:31.027310 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main] 2026-07-30 20:34:31.044652 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-30 20:34:31.709759 | 2026-07-30 20:34:31.709882 | PLAY [all] 2026-07-30 20:34:31.721257 | 2026-07-30 20:34:31.721332 | TASK [fetch-output : Set log path for multiple nodes] 2026-07-30 20:34:31.767120 | instance | skipping: Conditional result was False 2026-07-30 20:34:31.776299 | 2026-07-30 20:34:31.776409 | TASK [fetch-output : Set log path for single node] 2026-07-30 20:34:31.823958 | instance | ok 2026-07-30 20:34:31.829136 | 2026-07-30 20:34:31.829204 | LOOP [fetch-output : Ensure local output dirs] 2026-07-30 20:34:32.227067 | instance -> localhost | ok: "/var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/logs" 2026-07-30 20:34:32.425072 | instance -> localhost | changed: "/var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/artifacts" 2026-07-30 20:34:32.652983 | instance -> localhost | changed: "/var/lib/zuul/builds/9473b741f0db4c0aa1258ff1ddc86536/work/docs" 2026-07-30 20:34:32.673802 | 2026-07-30 20:34:32.673917 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-30 20:34:33.306000 | instance | changed: .d..t...... ./ 2026-07-30 20:34:33.306229 | instance | changed: All items complete 2026-07-30 20:34:33.306275 | 2026-07-30 20:34:33.737907 | instance | changed: .d..t...... ./ 2026-07-30 20:34:34.215532 | instance | changed: .d..t...... ./ 2026-07-30 20:34:34.240931 | 2026-07-30 20:34:34.241096 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-30 20:34:34.661775 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009487 2026-07-30 20:34:34.902983 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008884 2026-07-30 20:34:34.920519 | 2026-07-30 20:34:34.920676 | PLAY [all] 2026-07-30 20:34:34.927123 | 2026-07-30 20:34:34.927188 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-30 20:34:35.320749 | instance | changed 2026-07-30 20:34:35.326454 | 2026-07-30 20:34:35.326518 | PLAY RECAP 2026-07-30 20:34:35.326560 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-07-30 20:34:35.326582 | 2026-07-30 20:34:35.444530 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-07-30 20:34:35.450287 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-30 20:34:36.016209 | 2026-07-30 20:34:36.016339 | PLAY [localhost] 2026-07-30 20:34:36.025986 | 2026-07-30 20:34:36.026092 | TASK [Generate Zuul manifest] 2026-07-30 20:34:36.043808 | localhost | ok 2026-07-30 20:34:36.059172 | 2026-07-30 20:34:36.059250 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-30 20:34:36.465700 | localhost | changed 2026-07-30 20:34:36.478308 | 2026-07-30 20:34:36.478413 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-30 20:34:36.508271 | localhost | ok 2026-07-30 20:34:36.516226 | 2026-07-30 20:34:36.516351 | TASK [Upload logs] 2026-07-30 20:34:36.534825 | localhost | ok 2026-07-30 20:34:36.595395 | 2026-07-30 20:34:36.595510 | TASK [Set zuul-log-path fact] 2026-07-30 20:34:36.617595 | localhost | ok 2026-07-30 20:34:36.631080 | 2026-07-30 20:34:36.631152 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-30 20:34:36.661757 | localhost | ok 2026-07-30 20:34:36.671494 | 2026-07-30 20:34:36.676806 | TASK [upload-logs : Create log directories] 2026-07-30 20:34:37.113577 | localhost | changed 2026-07-30 20:34:37.117958 | 2026-07-30 20:34:37.118022 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-30 20:34:37.483856 | localhost -> localhost | ok: Runtime: 0:00:00.006337 2026-07-30 20:34:37.491454 | 2026-07-30 20:34:37.491525 | TASK [upload-logs : Upload logs to log server] 2026-07-30 20:34:37.936534 | localhost | Output suppressed because no_log was given 2026-07-30 20:34:37.941100 | 2026-07-30 20:34:37.941203 | LOOP [upload-logs : Compress console log and json output] 2026-07-30 20:34:37.984797 | localhost | skipping: Conditional result was False 2026-07-30 20:34:37.993064 | localhost | skipping: Conditional result was False 2026-07-30 20:34:38.010399 | 2026-07-30 20:34:38.010555 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-30 20:34:38.055272 | localhost | skipping: Conditional result was False 2026-07-30 20:34:38.055722 | 2026-07-30 20:34:38.056953 | localhost | skipping: Conditional result was False 2026-07-30 20:34:38.066365 | 2026-07-30 20:34:38.066533 | LOOP [upload-logs : Upload console log and json output]