2026-07-28 05:16:15.821782 | Job console starting
2026-07-28 05:16:15.835166 | Updating git repos
2026-07-28 05:16:18.884939 | Cloning repos into workspace
2026-07-28 05:16:31.826156 | Restoring repo states
2026-07-28 05:16:42.469683 | Merging changes
2026-07-28 05:16:43.449880 | Checking out repos
2026-07-28 05:16:44.885266 | Preparing playbooks
2026-07-28 05:16:47.377767 | Running Ansible setup
2026-07-28 05:16:50.707097 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-28 05:16:51.332054 | 
2026-07-28 05:16:51.332223 | PLAY [localhost]
2026-07-28 05:16:51.340216 | 
2026-07-28 05:16:51.340288 | TASK [Gathering Facts]
2026-07-28 05:16:52.204217 | localhost | ok
2026-07-28 05:16:52.215480 | 
2026-07-28 05:16:52.215563 | TASK [Setup log path fact]
2026-07-28 05:16:52.236513 | localhost | ok
2026-07-28 05:16:52.250195 | 
2026-07-28 05:16:52.250319 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-28 05:16:52.281662 | localhost | ok
2026-07-28 05:16:52.288704 | 
2026-07-28 05:16:52.288769 | TASK [emit-job-header : Print job information]
2026-07-28 05:16:52.331316 | # Job Information
2026-07-28 05:16:52.331633 | Ansible Version: 2.16.18
2026-07-28 05:16:52.331760 | Job: atmosphere-molecule-keycloak-selective
2026-07-28 05:16:52.331812 | Pipeline: check
2026-07-28 05:16:52.331858 | Executor: 2d72f0692154
2026-07-28 05:16:52.331903 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4152
2026-07-28 05:16:52.331956 | Event ID: f82624c0-8a42-11f1-86dd-541e7fd58861
2026-07-28 05:16:52.335087 | 
2026-07-28 05:16:52.335159 | LOOP [emit-job-header : Print node information]
2026-07-28 05:16:52.429906 | localhost | ok:
2026-07-28 05:16:52.430142 | localhost | # Node Information
2026-07-28 05:16:52.430182 | localhost | Inventory Hostname: instance
2026-07-28 05:16:52.430212 | localhost | Hostname: np0000187243
2026-07-28 05:16:52.430239 | localhost | Username: zuul
2026-07-28 05:16:52.430270 | localhost | Distro: Ubuntu 22.04
2026-07-28 05:16:52.430297 | localhost | Provider: yul1
2026-07-28 05:16:52.430324 | localhost | Region: ca-ymq-1
2026-07-28 05:16:52.430349 | localhost | Label: ubuntu-jammy
2026-07-28 05:16:52.430375 | localhost | Product Name: OpenStack Nova
2026-07-28 05:16:52.430401 | localhost | Interface IP: 199.204.45.53
2026-07-28 05:16:52.442466 | 
2026-07-28 05:16:52.442584 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-28 05:16:52.824037 | localhost -> localhost | changed
2026-07-28 05:16:52.831850 | 
2026-07-28 05:16:52.831947 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-28 05:16:53.661284 | localhost -> localhost | changed
2026-07-28 05:16:53.672566 | 
2026-07-28 05:16:53.672629 | PLAY [all]
2026-07-28 05:16:53.680515 | 
2026-07-28 05:16:53.680582 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-28 05:16:53.909695 | instance -> localhost | ok
2026-07-28 05:16:53.920866 | 
2026-07-28 05:16:53.921032 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-28 05:16:53.953795 | instance | ok
2026-07-28 05:16:53.967507 | instance | included: /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-28 05:16:53.972890 | 
2026-07-28 05:16:53.972952 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-28 05:16:55.408560 | instance -> localhost | Generating public/private rsa key pair.
2026-07-28 05:16:55.408763 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/2f90ea2a7779470d89c31080a7ba0e08_id_rsa
2026-07-28 05:16:55.408793 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/2f90ea2a7779470d89c31080a7ba0e08_id_rsa.pub
2026-07-28 05:16:55.408815 | instance -> localhost | The key fingerprint is:
2026-07-28 05:16:55.408837 | instance -> localhost | SHA256:/ObjpC+qjAy7H+jYHqpnRBl0b2DFIG4n9pQaG1RBuzk zuul-build-sshkey
2026-07-28 05:16:55.408869 | instance -> localhost | The key's randomart image is:
2026-07-28 05:16:55.408891 | instance -> localhost | +---[RSA 3072]----+
2026-07-28 05:16:55.408920 | instance -> localhost | | .+oO=.          |
2026-07-28 05:16:55.408942 | instance -> localhost | | o.+ =.          |
2026-07-28 05:16:55.408962 | instance -> localhost | |  Bo= o          |
2026-07-28 05:16:55.409003 | instance -> localhost | | ooX + .         |
2026-07-28 05:16:55.409024 | instance -> localhost | | .o E   S        |
2026-07-28 05:16:55.409044 | instance -> localhost | | ..  .   .       |
2026-07-28 05:16:55.409063 | instance -> localhost | |o.o       +      |
2026-07-28 05:16:55.409083 | instance -> localhost | |o*o=    .=.      |
2026-07-28 05:16:55.409105 | instance -> localhost | |BB* o....++.     |
2026-07-28 05:16:55.409126 | instance -> localhost | +----[SHA256]-----+
2026-07-28 05:16:55.409184 | instance -> localhost | ok: Runtime: 0:00:00.947774
2026-07-28 05:16:55.414392 | 
2026-07-28 05:16:55.414462 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-28 05:16:55.446882 | instance | ok
2026-07-28 05:16:55.457737 | instance | included: /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-28 05:16:55.464943 | 
2026-07-28 05:16:55.465023 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-28 05:16:55.489793 | instance | skipping: Conditional result was False
2026-07-28 05:16:55.497977 | 
2026-07-28 05:16:55.498081 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-28 05:16:55.956053 | instance | changed
2026-07-28 05:16:55.961905 | 
2026-07-28 05:16:55.961967 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-28 05:16:56.146908 | instance | ok
2026-07-28 05:16:56.154024 | 
2026-07-28 05:16:56.154085 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-28 05:16:56.602920 | instance | changed
2026-07-28 05:16:56.608449 | 
2026-07-28 05:16:56.608516 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-28 05:16:57.052251 | instance | changed
2026-07-28 05:16:57.059295 | 
2026-07-28 05:16:57.059398 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-28 05:16:57.075568 | instance | skipping: Conditional result was False
2026-07-28 05:16:57.081357 | 
2026-07-28 05:16:57.081427 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-28 05:16:57.434194 | instance -> localhost | changed
2026-07-28 05:16:57.448727 | 
2026-07-28 05:16:57.448796 | TASK [add-build-sshkey : Add back temp key]
2026-07-28 05:16:57.721822 | instance -> localhost | Identity added: /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/2f90ea2a7779470d89c31080a7ba0e08_id_rsa (zuul-build-sshkey)
2026-07-28 05:16:57.722052 | instance -> localhost | ok: Runtime: 0:00:00.014057
2026-07-28 05:16:57.726970 | 
2026-07-28 05:16:57.727034 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-28 05:16:58.055369 | instance | ok
2026-07-28 05:16:58.061962 | 
2026-07-28 05:16:58.062031 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-28 05:16:58.088372 | instance | skipping: Conditional result was False
2026-07-28 05:16:58.153883 | 
2026-07-28 05:16:58.154128 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-28 05:16:58.421645 | instance | ok
2026-07-28 05:16:58.463929 | 
2026-07-28 05:16:58.463997 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-28 05:17:01.559933 | instance | Output suppressed because no_log was given
2026-07-28 05:17:01.571125 | 
2026-07-28 05:17:01.571199 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-28 05:17:01.756058 | instance | ok: "logs"
2026-07-28 05:17:01.756342 | instance | ok: All items complete
2026-07-28 05:17:01.756371 | 
2026-07-28 05:17:01.898124 | instance | ok: "artifacts"
2026-07-28 05:17:02.057161 | instance | ok: "docs"
2026-07-28 05:17:02.066057 | 
2026-07-28 05:17:02.066145 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-28 05:17:02.254329 | instance | changed: "logs"
2026-07-28 05:17:02.403503 | instance | changed: "artifacts"
2026-07-28 05:17:02.552416 | instance | changed: "docs"
2026-07-28 05:17:02.572479 | 
2026-07-28 05:17:02.572614 | PLAY RECAP
2026-07-28 05:17:02.572668 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-28 05:17:02.572699 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:02.572723 | 
2026-07-28 05:17:02.735595 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-28 05:17:02.738619 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-28 05:17:03.322110 | 
2026-07-28 05:17:03.322480 | PLAY [all]
2026-07-28 05:17:03.332856 | 
2026-07-28 05:17:03.332930 | TASK [setup-uv : Extract archive]
2026-07-28 05:17:05.554109 | instance | changed
2026-07-28 05:17:05.562537 | 
2026-07-28 05:17:05.562652 | TASK [setup-uv : Print version]
2026-07-28 05:17:05.899273 | instance | uv 0.8.13
2026-07-28 05:17:06.106385 | instance | ok: Runtime: 0:00:00.012325
2026-07-28 05:17:06.119788 | 
2026-07-28 05:17:06.119999 | TASK [Install Ansible collection siblings]
2026-07-28 05:17:16.108778 | instance | changed:
2026-07-28 05:17:16.108946 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-28 05:17:16.108988 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-28 05:17:16.109011 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-28 05:17:16.109031 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-28 05:17:16.109051 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-28 05:17:16.109073 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-28 05:17:16.109094 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-28 05:17:16.109115 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-28 05:17:16.109135 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-28 05:17:16.109154 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-28 05:17:16.109173 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-28 05:17:16.109191 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-28 05:17:16.109209 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-28 05:17:16.109227 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-28 05:17:16.109245 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-28 05:17:16.109264 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-28 05:17:16.109282 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-28 05:17:16.109300 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-28 05:17:16.109318 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-28 05:17:16.109335 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-28 05:17:16.109353 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-28 05:17:16.109371 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-28 05:17:16.109390 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-28 05:17:16.109423 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-28 05:17:16.109442 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-28 05:17:16.109461 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-28 05:17:16.109479 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-28 05:17:16.109496 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-28 05:17:16.109514 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-28 05:17:16.109534 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-28 05:17:16.109552 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-28 05:17:16.109570 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-28 05:17:16.109588 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-28 05:17:16.109606 | 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-28 05:17:16.109631 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.109653 | instance | Process install dependency map
2026-07-28 05:17:16.109671 | instance | Starting collection install process
2026-07-28 05:17:16.109689 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-28 05:17:16.109707 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-28 05:17:16.109724 | instance | ansible.netcommon:* was installed successfully
2026-07-28 05:17:16.109742 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-28 05:17:16.109759 | instance | Creating virtual environment at: .venv
2026-07-28 05:17:16.109777 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-28 05:17:16.109794 | instance | Downloading pygments (1.2MiB)
2026-07-28 05:17:16.109812 | instance | Downloading openstacksdk (1.7MiB)
2026-07-28 05:17:16.109834 | instance | Downloading netaddr (2.2MiB)
2026-07-28 05:17:16.109852 | instance | Downloading kubernetes (1.9MiB)
2026-07-28 05:17:16.109869 | instance | Downloading setuptools (1.1MiB)
2026-07-28 05:17:16.109887 | instance | Downloading ansible-core (2.1MiB)
2026-07-28 05:17:16.109905 | instance | Downloading cryptography (4.2MiB)
2026-07-28 05:17:16.109923 | instance | Downloading rjsonnet (1.2MiB)
2026-07-28 05:17:16.109941 | instance | Downloading pydantic-core (2.0MiB)
2026-07-28 05:17:16.109959 | instance |    Building pyperclip==1.9.0
2026-07-28 05:17:16.109979 | instance |  Downloading rjsonnet
2026-07-28 05:17:16.109997 | instance |  Downloading pydantic-core
2026-07-28 05:17:16.110015 | instance |  Downloading netaddr
2026-07-28 05:17:16.110033 | instance |  Downloading pygments
2026-07-28 05:17:16.110051 | instance |  Downloading cryptography
2026-07-28 05:17:16.110068 | instance |  Downloading setuptools
2026-07-28 05:17:16.110086 | instance |  Downloading kubernetes
2026-07-28 05:17:16.110103 | instance |  Downloading ansible-core
2026-07-28 05:17:16.110121 | instance |  Downloading openstacksdk
2026-07-28 05:17:16.110139 | instance |       Built pyperclip==1.9.0
2026-07-28 05:17:16.110157 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-28 05:17:16.110175 | instance | Installed 83 packages in 43ms
2026-07-28 05:17:16.110194 | instance | Checked out ansible.posix to 1.6.0
2026-07-28 05:17:16.110214 | 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-28 05:17:16.110234 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.110252 | instance | Process install dependency map
2026-07-28 05:17:16.110276 | instance | Starting collection install process
2026-07-28 05:17:16.110295 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-28 05:17:16.110314 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-28 05:17:16.110331 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-28 05:17:16.110349 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.110367 | instance | 2.17.12
2026-07-28 05:17:16.110385 | instance | Checked out ansible.utils to v6.0.3
2026-07-28 05:17:16.110403 | 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-28 05:17:16.110421 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.110439 | instance | Process install dependency map
2026-07-28 05:17:16.110456 | instance | Starting collection install process
2026-07-28 05:17:16.110475 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-28 05:17:16.110494 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-28 05:17:16.110516 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-28 05:17:16.110535 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.110553 | instance | 2.17.12
2026-07-28 05:17:16.110571 | instance | Checked out atmosphere.common to v0.7.0
2026-07-28 05:17:16.110588 | 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-28 05:17:16.110607 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.110629 | instance | Process install dependency map
2026-07-28 05:17:16.110646 | instance | Starting collection install process
2026-07-28 05:17:16.110664 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-28 05:17:16.110687 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-28 05:17:16.110704 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-28 05:17:16.110722 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.110739 | instance | 2.17.12
2026-07-28 05:17:16.110764 | instance | Checked out community.crypto to 2.2.3
2026-07-28 05:17:16.110782 | 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-28 05:17:16.110800 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.110818 | instance | Process install dependency map
2026-07-28 05:17:16.110836 | instance | Starting collection install process
2026-07-28 05:17:16.110853 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-28 05:17:16.110870 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-28 05:17:16.110888 | instance | community.crypto:2.2.3 was installed successfully
2026-07-28 05:17:16.110905 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.110923 | instance | 2.17.12
2026-07-28 05:17:16.110940 | instance | Checked out community.general to 7.3.0
2026-07-28 05:17:16.110958 | 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-28 05:17:16.110980 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.110998 | instance | Process install dependency map
2026-07-28 05:17:16.111015 | instance | Starting collection install process
2026-07-28 05:17:16.111033 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-28 05:17:16.111050 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-28 05:17:16.111068 | instance | community.general:7.3.0 was installed successfully
2026-07-28 05:17:16.111086 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.111104 | instance | 2.17.12
2026-07-28 05:17:16.111122 | instance | Checked out community.mysql to 3.6.0
2026-07-28 05:17:16.111142 | 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-28 05:17:16.111161 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.111180 | instance | Process install dependency map
2026-07-28 05:17:16.111197 | instance | Starting collection install process
2026-07-28 05:17:16.111215 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-28 05:17:16.111233 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-28 05:17:16.111250 | instance | community.mysql:3.6.0 was installed successfully
2026-07-28 05:17:16.111268 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.111285 | instance | 2.17.12
2026-07-28 05:17:16.111303 | instance | Checked out kubernetes.core to 2.4.0
2026-07-28 05:17:16.111321 | 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-28 05:17:16.111339 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.111357 | instance | Process install dependency map
2026-07-28 05:17:16.111374 | instance | Starting collection install process
2026-07-28 05:17:16.111392 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-28 05:17:16.111410 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-28 05:17:16.111428 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-28 05:17:16.111445 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.111463 | instance | 2.17.12
2026-07-28 05:17:16.111494 | instance | Checked out openstack.cloud to 2.6.0
2026-07-28 05:17:16.111513 | 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-28 05:17:16.111532 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.111550 | instance | Process install dependency map
2026-07-28 05:17:16.111567 | instance | Starting collection install process
2026-07-28 05:17:16.111588 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-28 05:17:16.111606 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-28 05:17:16.111624 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-28 05:17:16.111641 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.111659 | instance | 2.17.12
2026-07-28 05:17:16.111682 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-28 05:17:16.111700 | 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-28 05:17:16.111721 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.111742 | instance | Process install dependency map
2026-07-28 05:17:16.111759 | instance | Starting collection install process
2026-07-28 05:17:16.111777 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-28 05:17:16.111798 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-28 05:17:16.111819 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-28 05:17:16.111836 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.111854 | instance | 2.17.12
2026-07-28 05:17:16.111871 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-28 05:17:16.111889 | 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-28 05:17:16.111907 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.111925 | instance | Process install dependency map
2026-07-28 05:17:16.111943 | instance | Starting collection install process
2026-07-28 05:17:16.111961 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-28 05:17:16.111979 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-28 05:17:16.111997 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-28 05:17:16.112015 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.112033 | instance | 2.17.12
2026-07-28 05:17:16.112051 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-28 05:17:16.112070 | 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-28 05:17:16.112088 | instance | Starting galaxy collection install process
2026-07-28 05:17:16.112106 | instance | Process install dependency map
2026-07-28 05:17:16.112123 | instance | Starting collection install process
2026-07-28 05:17:16.112141 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-28 05:17:16.112159 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-28 05:17:16.112176 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-28 05:17:16.112194 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-28 05:17:16.112212 | instance | 2.17.12
2026-07-28 05:17:16.117743 | 
2026-07-28 05:17:16.117790 | PLAY RECAP
2026-07-28 05:17:16.117832 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:16.117852 | 
2026-07-28 05:17:16.226956 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-28 05:17:16.231981 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-28 05:17:16.795554 | 
2026-07-28 05:17:16.795673 | PLAY [all]
2026-07-28 05:17:16.806355 | 
2026-07-28 05:17:16.806426 | TASK [Install "jq" for log collection]
2026-07-28 05:17:40.368644 | instance | changed
2026-07-28 05:17:40.371312 | 
2026-07-28 05:17:40.371395 | PLAY RECAP
2026-07-28 05:17:40.371464 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:40.371543 | 
2026-07-28 05:17:40.497136 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-28 05:17:40.503055 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-28 05:17:41.084634 | 
2026-07-28 05:17:41.084781 | PLAY [Prepare selective Molecule plan]
2026-07-28 05:17:41.096790 | 
2026-07-28 05:17:41.096878 | TASK [Generate selective Molecule plan]
2026-07-28 05:17:41.652776 | instance | ok: Runtime: 0:00:00.161315
2026-07-28 05:17:41.662940 | 
2026-07-28 05:17:41.663040 | TASK [Render selective Molecule plan]
2026-07-28 05:17:41.940897 | instance | Selective Molecule plan: full
2026-07-28 05:17:41.941207 | instance | Changed files:
2026-07-28 05:17:41.941221 | instance |   M .zuul.yaml
2026-07-28 05:17:41.941231 | instance |   A atmosphere/ci/__init__.py
2026-07-28 05:17:41.941245 | instance |   A atmosphere/ci/molecule_plan.py
2026-07-28 05:17:41.941256 | instance |   A charts/patches/tempest/0002-mount-only-ca-certificate.patch
2026-07-28 05:17:41.941270 | instance |   A charts/patches/tempest/0003-use-ca-for-python-ssl.patch
2026-07-28 05:17:41.941284 | instance |   A charts/patches/tempest/0004-support-ca-only-identity-trust.patch
2026-07-28 05:17:41.941295 | instance |   M charts/tempest/templates/job-ks-user.yaml
2026-07-28 05:17:41.941305 | instance |   M charts/tempest/templates/job-run-tests.yaml
2026-07-28 05:17:41.941318 | instance |   M charts/tempest/values.yaml
2026-07-28 05:17:41.941327 | instance |   A ci/molecule-plan.yaml
2026-07-28 05:17:41.941336 | instance |   M doc/source/deploy/index.rst
2026-07-28 05:17:41.941347 | instance |   A doc/source/deploy/selective-ci.rst
2026-07-28 05:17:41.941355 | instance |   M molecule/aio/converge.yml
2026-07-28 05:17:41.941394 | instance |   M molecule/aio/prepare.yml
2026-07-28 05:17:41.941404 | instance |   M molecule/aio/verify.yml
2026-07-28 05:17:41.941413 | instance |   M playbooks/tempest.yml
2026-07-28 05:17:41.941422 | instance |   M pyproject.toml
2026-07-28 05:17:41.941431 | instance |   A releasenotes/notes/selective-molecule-ci-3233e7ee8348d528.yaml
2026-07-28 05:17:41.941440 | instance |   M roles/tempest/defaults/main.yml
2026-07-28 05:17:41.941449 | instance |   M roles/tempest/tasks/main.yml
2026-07-28 05:17:41.941458 | instance |   M roles/tempest/vars/main.yml
2026-07-28 05:17:41.941467 | instance |   A test-playbooks/molecule/poll-command.yml
2026-07-28 05:17:41.941475 | instance |   A test-playbooks/molecule/post.yml
2026-07-28 05:17:41.941492 | instance |   A test-playbooks/molecule/prepare.yml
2026-07-28 05:17:41.941502 | instance |   A test-playbooks/molecule/run-command.yml
2026-07-28 05:17:41.941510 | instance |   A test-playbooks/molecule/selective-run.yml
2026-07-28 05:17:41.941519 | instance |   A tests/unit/ci/__init__.py
2026-07-28 05:17:41.941528 | instance |   A tests/unit/ci/test_molecule_plan.py
2026-07-28 05:17:41.941537 | instance |   M uv.lock
2026-07-28 05:17:41.941546 | instance | Job decisions:
2026-07-28 05:17:41.941555 | instance |   RUN  aio-openvswitch: full fallback
2026-07-28 05:17:41.941563 | instance |   RUN  aio-ovn: full fallback
2026-07-28 05:17:41.941572 | instance |   RUN  csi-local-path-provisioner: full fallback
2026-07-28 05:17:41.941581 | instance |   RUN  csi-rbd: full fallback
2026-07-28 05:17:41.941590 | instance |   RUN  keycloak: full fallback
2026-07-28 05:17:41.941599 | instance | Reason: the CI selection policy changed
2026-07-28 05:17:41.941607 | instance | Reason: shared deployment code can affect unrelated components
2026-07-28 05:17:41.941616 | instance | Reason: operational workflows need the complete integration environment
2026-07-28 05:17:42.200503 | instance | ok: Runtime: 0:00:00.093818
2026-07-28 05:17:42.206841 | 
2026-07-28 05:17:42.206918 | TASK [Report selective Molecule plan]
2026-07-28 05:17:42.239442 | instance | ok:
2026-07-28 05:17:42.239640 | instance | Selective Molecule plan: full
2026-07-28 05:17:42.239694 | instance | Changed files:
2026-07-28 05:17:42.239727 | instance |   M .zuul.yaml
2026-07-28 05:17:42.239756 | instance |   A atmosphere/ci/__init__.py
2026-07-28 05:17:42.239784 | instance |   A atmosphere/ci/molecule_plan.py
2026-07-28 05:17:42.239820 | instance |   A charts/patches/tempest/0002-mount-only-ca-certificate.patch
2026-07-28 05:17:42.239854 | instance |   A charts/patches/tempest/0003-use-ca-for-python-ssl.patch
2026-07-28 05:17:42.239883 | instance |   A charts/patches/tempest/0004-support-ca-only-identity-trust.patch
2026-07-28 05:17:42.239910 | instance |   M charts/tempest/templates/job-ks-user.yaml
2026-07-28 05:17:42.239937 | instance |   M charts/tempest/templates/job-run-tests.yaml
2026-07-28 05:17:42.239963 | instance |   M charts/tempest/values.yaml
2026-07-28 05:17:42.239990 | instance |   A ci/molecule-plan.yaml
2026-07-28 05:17:42.240017 | instance |   M doc/source/deploy/index.rst
2026-07-28 05:17:42.240043 | instance |   A doc/source/deploy/selective-ci.rst
2026-07-28 05:17:42.240073 | instance |   M molecule/aio/converge.yml
2026-07-28 05:17:42.240101 | instance |   M molecule/aio/prepare.yml
2026-07-28 05:17:42.240128 | instance |   M molecule/aio/verify.yml
2026-07-28 05:17:42.240155 | instance |   M playbooks/tempest.yml
2026-07-28 05:17:42.240182 | instance |   M pyproject.toml
2026-07-28 05:17:42.240208 | instance |   A releasenotes/notes/selective-molecule-ci-3233e7ee8348d528.yaml
2026-07-28 05:17:42.240235 | instance |   M roles/tempest/defaults/main.yml
2026-07-28 05:17:42.240262 | instance |   M roles/tempest/tasks/main.yml
2026-07-28 05:17:42.240289 | instance |   M roles/tempest/vars/main.yml
2026-07-28 05:17:42.240316 | instance |   A test-playbooks/molecule/poll-command.yml
2026-07-28 05:17:42.240343 | instance |   A test-playbooks/molecule/post.yml
2026-07-28 05:17:42.240369 | instance |   A test-playbooks/molecule/prepare.yml
2026-07-28 05:17:42.240396 | instance |   A test-playbooks/molecule/run-command.yml
2026-07-28 05:17:42.240422 | instance |   A test-playbooks/molecule/selective-run.yml
2026-07-28 05:17:42.240448 | instance |   A tests/unit/ci/__init__.py
2026-07-28 05:17:42.240473 | instance |   A tests/unit/ci/test_molecule_plan.py
2026-07-28 05:17:42.240499 | instance |   M uv.lock
2026-07-28 05:17:42.240526 | instance | Job decisions:
2026-07-28 05:17:42.240552 | instance |   RUN  aio-openvswitch: full fallback
2026-07-28 05:17:42.240578 | instance |   RUN  aio-ovn: full fallback
2026-07-28 05:17:42.240605 | instance |   RUN  csi-local-path-provisioner: full fallback
2026-07-28 05:17:42.240632 | instance |   RUN  csi-rbd: full fallback
2026-07-28 05:17:42.240658 | instance |   RUN  keycloak: full fallback
2026-07-28 05:17:42.240684 | instance | Reason: the CI selection policy changed
2026-07-28 05:17:42.240710 | instance | Reason: shared deployment code can affect unrelated components
2026-07-28 05:17:42.240737 | instance | Reason: operational workflows need the complete integration environment
2026-07-28 05:17:42.241999 | 
2026-07-28 05:17:42.242059 | PLAY RECAP
2026-07-28 05:17:42.242115 | instance | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:17:42.242146 | 
2026-07-28 05:17:42.363324 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-28 05:17:42.368640 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-28 05:17:42.930416 | 
2026-07-28 05:17:42.930535 | PLAY [Run selected Molecule scenario]
2026-07-28 05:17:42.941117 | 
2026-07-28 05:17:42.941191 | TASK [Read selective Molecule plan]
2026-07-28 05:17:43.236296 | instance | ok
2026-07-28 05:17:43.242988 | 
2026-07-28 05:17:43.243088 | TASK [Resolve this job decision]
2026-07-28 05:17:43.298885 | instance | ok
2026-07-28 05:17:43.308608 | 
2026-07-28 05:17:43.308672 | TASK [Assert this job exists in the plan]
2026-07-28 05:17:43.340330 | instance | ok: All assertions passed
2026-07-28 05:17:43.345807 | 
2026-07-28 05:17:43.345868 | TASK [Resolve selected scenario inputs]
2026-07-28 05:17:43.379058 | instance | ok
2026-07-28 05:17:43.384678 | 
2026-07-28 05:17:43.384738 | TASK [Report this job decision]
2026-07-28 05:17:43.440157 | instance | ok:
2026-07-28 05:17:43.440399 | instance | Selective Molecule job: keycloak
2026-07-28 05:17:43.440462 | instance | Decision: RUN
2026-07-28 05:17:43.440514 | instance | Reason: full fallback
2026-07-28 05:17:43.440568 | instance | Scenario: keycloak
2026-07-28 05:17:43.440613 | instance | Targets: (none)
2026-07-28 05:17:43.440657 | instance | Components: (full scenario)
2026-07-28 05:17:43.444911 | 
2026-07-28 05:17:43.444983 | TASK [Prepare Molecule command]
2026-07-28 05:17:43.508160 | instance | ok
2026-07-28 05:17:43.514452 | 
2026-07-28 05:17:43.514537 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-28 05:17:43.558108 | instance | ok
2026-07-28 05:17:43.567205 | instance | included: /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/run-command.yml
2026-07-28 05:17:43.572866 | 
2026-07-28 05:17:43.572926 | TASK [Create Molecule log directory]
2026-07-28 05:17:43.890260 | instance | changed
2026-07-28 05:17:43.896344 | 
2026-07-28 05:17:43.896410 | TASK [Resolve live Molecule log path]
2026-07-28 05:17:43.928641 | instance | ok
2026-07-28 05:17:43.936925 | 
2026-07-28 05:17:43.937018 | TASK [Start Molecule keycloak (keycloak)]
2026-07-28 05:17:44.614274 | instance | ok: ERROR (ignored)
2026-07-28 05:17:44.614545 | instance | {
2026-07-28 05:17:44.614575 | instance |   "ansible_job_id": "j230478609524.1434",
2026-07-28 05:17:44.614596 | instance |   "finished": 0,
2026-07-28 05:17:44.614615 | instance |   "results_file": "/home/zuul/.ansible_async/j230478609524.1434",
2026-07-28 05:17:44.614634 | instance |   "started": 1
2026-07-28 05:17:44.614652 | instance | }
2026-07-28 05:17:44.623688 | 
2026-07-28 05:17:44.623768 | TASK [Initialize Molecule keycloak (keycloak) progress monitor]
2026-07-28 05:17:44.676252 | instance | ok
2026-07-28 05:17:44.683904 | 
2026-07-28 05:17:44.683976 | TASK [Monitor Molecule keycloak (keycloak)]
2026-07-28 05:17:44.716664 | instance | ok
2026-07-28 05:17:44.729912 | instance | included: /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/untrusted/project_5/github.com/vexxhost/atmosphere/test-playbooks/molecule/poll-command.yml
2026-07-28 05:17:44.737571 | 
2026-07-28 05:17:44.737635 | TASK [Wait before polling Molecule keycloak (keycloak)]
2026-07-28 05:18:04.799509 | instance | ok
2026-07-28 05:18:04.807883 | 
2026-07-28 05:18:04.807968 | TASK [Count Molecule keycloak (keycloak) progress poll]
2026-07-28 05:18:04.863251 | instance | ok
2026-07-28 05:18:04.869708 | 
2026-07-28 05:18:04.869780 | TASK [Poll Molecule keycloak (keycloak) status]
2026-07-28 05:18:05.276916 | instance | ok: non-zero return code
2026-07-28 05:18:05.283244 | 
2026-07-28 05:18:05.283316 | TASK [Inspect Molecule keycloak (keycloak) live log]
2026-07-28 05:18:05.561751 | instance | ok
2026-07-28 05:18:05.572027 | 
2026-07-28 05:18:05.572105 | TASK [Calculate new Molecule keycloak (keycloak) output]
2026-07-28 05:18:05.628487 | instance | ok
2026-07-28 05:18:05.638356 | 
2026-07-28 05:18:05.638432 | TASK [Read new Molecule keycloak (keycloak) output]
2026-07-28 05:18:06.167773 | instance | skipping: Conditional result was False
2026-07-28 05:18:06.180840 | 
2026-07-28 05:18:06.180918 | TASK [Print new Molecule keycloak (keycloak) output]
2026-07-28 05:18:06.225734 | instance | skipping: Conditional result was False
2026-07-28 05:18:06.237295 | 
2026-07-28 05:18:06.237366 | TASK [Advance Molecule keycloak (keycloak) live log offset]
2026-07-28 05:18:06.289473 | instance | ok
2026-07-28 05:18:06.297694 | 
2026-07-28 05:18:06.297792 | TASK [Report Molecule keycloak (keycloak) progress heartbeat]
2026-07-28 05:18:06.333962 | instance | skipping: Conditional result was False
2026-07-28 05:18:06.344608 | 
2026-07-28 05:18:06.344711 | TASK [Continue monitoring Molecule keycloak (keycloak)]
2026-07-28 05:18:06.390330 | instance | skipping: Conditional result was False
2026-07-28 05:18:06.402091 | 
2026-07-28 05:18:06.402189 | TASK [Clean up Molecule keycloak (keycloak) async state]
2026-07-28 05:18:06.601887 | instance | ok
2026-07-28 05:18:06.617201 | 
2026-07-28 05:18:06.617288 | TASK [Assert Molecule keycloak (keycloak) succeeded]
2026-07-28 05:18:06.676590 | instance | ERROR
2026-07-28 05:18:06.676829 | instance | {
2026-07-28 05:18:06.676871 | instance |   "assertion": "(_atmosphere_ci_phase_result.rc | default(1) | int) == 0",
2026-07-28 05:18:06.676903 | instance |   "evaluated_to": false,
2026-07-28 05:18:06.676931 | instance |   "msg": "Molecule keycloak (keycloak) failed with exit code 1. Complete output is in /tmp/logs/molecule-keycloak.log."
2026-07-28 05:18:06.676991 | instance | }
failure
2026-07-28 05:18:06.678306 | 
2026-07-28 05:18:06.678371 | PLAY RECAP
2026-07-28 05:18:06.678436 | instance | ok: 19 changed: 2 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0
2026-07-28 05:18:06.678467 | 
2026-07-28 05:18:06.817533 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-28 05:18:06.823055 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-28 05:18:07.424805 | 
2026-07-28 05:18:07.425120 | PLAY [Publish selective Molecule plan]
2026-07-28 05:18:07.437822 | 
2026-07-28 05:18:07.437922 | TASK [Check for selective Molecule plan]
2026-07-28 05:18:07.753209 | instance | ok
2026-07-28 05:18:07.760819 | 
2026-07-28 05:18:07.760920 | TASK [Create log directory]
2026-07-28 05:18:08.096099 | instance | ok
2026-07-28 05:18:08.107748 | 
2026-07-28 05:18:08.107865 | TASK [Copy selective Molecule plan to logs]
2026-07-28 05:18:08.536323 | instance | changed
2026-07-28 05:18:08.538205 | 
2026-07-28 05:18:08.538258 | PLAY RECAP
2026-07-28 05:18:08.538311 | instance | ok: 3 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-28 05:18:08.538333 | 
2026-07-28 05:18:08.678964 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-28 05:18:08.682450 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-28 05:18:09.294020 | 
2026-07-28 05:18:09.294419 | PLAY [all]
2026-07-28 05:18:09.308220 | 
2026-07-28 05:18:09.308294 | TASK [gather-host-logs : creating directory for system status]
2026-07-28 05:18:09.667019 | instance | changed
2026-07-28 05:18:09.676349 | 
2026-07-28 05:18:09.676493 | TASK [gather-host-logs : Get logs for each host]
2026-07-28 05:18:10.018995 | instance | + systemd-cgls --full --all --no-pager
2026-07-28 05:18:10.028833 | instance | + ip addr
2026-07-28 05:18:10.032199 | instance | + ip route
2026-07-28 05:18:10.034026 | instance | + lsblk
2026-07-28 05:18:10.036755 | instance | + mount
2026-07-28 05:18:10.038444 | instance | + docker images
2026-07-28 05:18:10.038888 | instance | /bin/bash: line 7: docker: command not found
2026-07-28 05:18:10.039104 | instance | + brctl show
2026-07-28 05:18:10.039503 | instance | /bin/bash: line 8: brctl: command not found
2026-07-28 05:18:10.039731 | instance | + ps aux --sort=-%mem
2026-07-28 05:18:10.050171 | instance | + dpkg -l
2026-07-28 05:18:10.056055 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-28 05:18:10.056443 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-28 05:18:10.056491 | instance | /bin/bash: line 11: docker: command not found
2026-07-28 05:18:10.056734 | instance | + '[' '!' -z '' ']'
2026-07-28 05:18:10.213536 | instance | ok: Runtime: 0:00:00.043522
2026-07-28 05:18:10.220116 | 
2026-07-28 05:18:10.220183 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-28 05:18:10.913094 | instance | changed:
2026-07-28 05:18:10.913442 | instance | created directory /var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/logs/instance
2026-07-28 05:18:10.913480 | instance | cd+++++++++ system/
2026-07-28 05:18:10.913503 | instance | >f+++++++++ system/brctl-show.txt
2026-07-28 05:18:10.913524 | instance | >f+++++++++ system/docker-images.txt
2026-07-28 05:18:10.913543 | instance | >f+++++++++ system/ip-addr.txt
2026-07-28 05:18:10.913565 | instance | >f+++++++++ system/ip-route.txt
2026-07-28 05:18:10.913585 | instance | >f+++++++++ system/lsblk.txt
2026-07-28 05:18:10.913626 | instance | >f+++++++++ system/mount.txt
2026-07-28 05:18:10.913651 | instance | >f+++++++++ system/packages.txt
2026-07-28 05:18:10.913670 | instance | >f+++++++++ system/ps.txt
2026-07-28 05:18:10.913688 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-28 05:18:10.926362 | 
2026-07-28 05:18:10.926460 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-28 05:18:11.130444 | instance | changed: "values"
2026-07-28 05:18:11.283627 | instance | changed: "releases"
2026-07-28 05:18:11.303570 | 
2026-07-28 05:18:11.303705 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-28 05:18:11.526270 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-28 05:18:11.850497 | instance | ok: Runtime: 0:00:00.006128
2026-07-28 05:18:11.859372 | 
2026-07-28 05:18:11.859470 | TASK [helm-release-status : Downloads logs to executor]
2026-07-28 05:18:12.355624 | instance | changed:
2026-07-28 05:18:12.355804 | instance | cd+++++++++ helm/
2026-07-28 05:18:12.355842 | instance | cd+++++++++ helm/releases/
2026-07-28 05:18:12.355870 | instance | cd+++++++++ helm/values/
2026-07-28 05:18:12.370885 | 
2026-07-28 05:18:12.370991 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-28 05:18:12.564490 | instance | changed
2026-07-28 05:18:12.570047 | 
2026-07-28 05:18:12.570117 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-28 05:18:13.075909 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:13.076286 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:13.081113 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:18:13.083354 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:18:13.084652 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:18:13.085940 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:18:13.086566 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:18:13.088029 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:18:13.088259 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:18:13.089356 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:18:13.091547 | instance | environment: line 1: kubectl: command not found
2026-07-28 05:18:13.092649 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-28 05:18:13.137909 | instance | ok: Runtime: 0:00:00.025250
2026-07-28 05:18:13.152739 | 
2026-07-28 05:18:13.152849 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-28 05:18:13.497779 | instance | changed
2026-07-28 05:18:13.502751 | 
2026-07-28 05:18:13.502823 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-28 05:18:13.724902 | instance | environment: line 5: kubectl: command not found
2026-07-28 05:18:13.725882 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:13.726019 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:13.726304 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-28 05:18:14.030448 | instance | ok: Runtime: 0:00:00.009238
2026-07-28 05:18:14.037752 | 
2026-07-28 05:18:14.037842 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-28 05:18:14.519163 | instance | changed:
2026-07-28 05:18:14.519358 | instance | cd+++++++++ objects/
2026-07-28 05:18:14.519387 | instance | cd+++++++++ objects/cluster/
2026-07-28 05:18:14.519409 | instance | cd+++++++++ objects/namespaced/
2026-07-28 05:18:14.529347 | 
2026-07-28 05:18:14.529415 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-28 05:18:14.739181 | instance | changed
2026-07-28 05:18:14.744294 | 
2026-07-28 05:18:14.744362 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-28 05:18:14.939848 | instance | changed
2026-07-28 05:18:15.243357 | 
2026-07-28 05:18:15.243595 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-28 05:18:15.472166 | instance | environment: line 3: kubectl: command not found
2026-07-28 05:18:15.782148 | instance | ok: Runtime: 0:00:00.009071
2026-07-28 05:18:15.788260 | 
2026-07-28 05:18:15.788329 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-28 05:18:16.358926 | instance | changed:
2026-07-28 05:18:16.359154 | instance | cd+++++++++ pod-logs/
2026-07-28 05:18:16.359188 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-28 05:18:16.368477 | 
2026-07-28 05:18:16.368541 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-28 05:18:16.564521 | instance | changed
2026-07-28 05:18:16.571455 | 
2026-07-28 05:18:16.571555 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-28 05:18:16.790389 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-28 05:18:17.110465 | instance | ok: Runtime: 0:00:00.036844
2026-07-28 05:18:17.116424 | 
2026-07-28 05:18:17.116502 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-28 05:18:17.326634 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-28 05:18:17.355167 | instance | ceph-mgr endpoints:
2026-07-28 05:18:17.652160 | instance | ok: Runtime: 0:00:00.036475
2026-07-28 05:18:17.659284 | 
2026-07-28 05:18:17.659373 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-28 05:18:17.870324 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-28 05:18:18.193702 | instance | ok: Runtime: 0:00:00.038756
2026-07-28 05:18:18.200788 | 
2026-07-28 05:18:18.200876 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-28 05:18:18.969470 | instance | changed: cd+++++++++ prometheus/
2026-07-28 05:18:18.980358 | 
2026-07-28 05:18:18.980424 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-28 05:18:19.177231 | instance | changed
2026-07-28 05:18:19.184324 | 
2026-07-28 05:18:19.184386 | TASK [gather-selenium-data : Get selenium data]
2026-07-28 05:18:19.435840 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-28 05:18:19.436970 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-28 05:18:19.760197 | instance | ERROR
2026-07-28 05:18:19.760457 | instance | {
2026-07-28 05:18:19.760491 | instance |   "delta": "0:00:00.007013",
2026-07-28 05:18:19.760512 | instance |   "end": "2026-07-28 05:18:19.437344",
2026-07-28 05:18:19.760531 | instance |   "msg": "non-zero return code",
2026-07-28 05:18:19.760549 | instance |   "rc": 1,
2026-07-28 05:18:19.760567 | instance |   "start": "2026-07-28 05:18:19.430331"
2026-07-28 05:18:19.760585 | instance | }
2026-07-28 05:18:19.760608 | instance | ERROR: Ignoring Errors
2026-07-28 05:18:19.766237 | 
2026-07-28 05:18:19.766321 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-28 05:18:20.232392 | instance | changed: cd+++++++++ selenium/
2026-07-28 05:18:20.238484 | 
2026-07-28 05:18:20.238537 | PLAY RECAP
2026-07-28 05:18:20.238583 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-28 05:18:20.238604 | 
2026-07-28 05:18:20.356514 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-28 05:18:20.361092 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-28 05:18:20.989126 | 
2026-07-28 05:18:20.989248 | PLAY [all]
2026-07-28 05:18:21.002633 | 
2026-07-28 05:18:21.002747 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-28 05:18:21.048255 | instance | skipping: Conditional result was False
2026-07-28 05:18:21.053840 | 
2026-07-28 05:18:21.053925 | TASK [fetch-output : Set log path for single node]
2026-07-28 05:18:21.094850 | instance | ok
2026-07-28 05:18:21.099485 | 
2026-07-28 05:18:21.099559 | LOOP [fetch-output : Ensure local output dirs]
2026-07-28 05:18:21.488085 | instance -> localhost | ok: "/var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/logs"
2026-07-28 05:18:21.693266 | instance -> localhost | changed: "/var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/artifacts"
2026-07-28 05:18:21.891212 | instance -> localhost | changed: "/var/lib/zuul/builds/2f90ea2a7779470d89c31080a7ba0e08/work/docs"
2026-07-28 05:18:21.909741 | 
2026-07-28 05:18:21.909907 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-28 05:18:22.510848 | instance | changed: .d..t...... ./
2026-07-28 05:18:22.511129 | instance | changed: All items complete
2026-07-28 05:18:22.511157 | 
2026-07-28 05:18:22.938605 | instance | changed: .d..t...... ./
2026-07-28 05:18:23.389855 | instance | changed: .d..t...... ./
2026-07-28 05:18:23.414302 | 
2026-07-28 05:18:23.414499 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-28 05:18:24.147252 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007845
2026-07-28 05:18:24.373449 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008310
2026-07-28 05:18:24.394547 | 
2026-07-28 05:18:24.394728 | PLAY [all]
2026-07-28 05:18:24.401634 | 
2026-07-28 05:18:24.401702 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-28 05:18:24.803676 | instance | changed
2026-07-28 05:18:24.809496 | 
2026-07-28 05:18:24.809552 | PLAY RECAP
2026-07-28 05:18:24.809603 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-28 05:18:24.809626 | 
2026-07-28 05:18:24.963100 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-28 05:18:24.968660 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-28 05:18:25.576043 | 
2026-07-28 05:18:25.576195 | PLAY [localhost]
2026-07-28 05:18:25.586728 | 
2026-07-28 05:18:25.586805 | TASK [Generate Zuul manifest]
2026-07-28 05:18:25.618673 | localhost | ok
2026-07-28 05:18:25.635831 | 
2026-07-28 05:18:25.635918 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-28 05:18:25.994631 | localhost | changed
2026-07-28 05:18:26.008968 | 
2026-07-28 05:18:26.009059 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-28 05:18:26.103721 | localhost | ok
2026-07-28 05:18:26.111221 | 
2026-07-28 05:18:26.111290 | TASK [Upload logs]
2026-07-28 05:18:26.127493 | localhost | ok
2026-07-28 05:18:26.185377 | 
2026-07-28 05:18:26.185489 | TASK [Set zuul-log-path fact]
2026-07-28 05:18:26.207220 | localhost | ok
2026-07-28 05:18:26.219361 | 
2026-07-28 05:18:26.219428 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-28 05:18:26.251042 | localhost | ok
2026-07-28 05:18:26.260824 | 
2026-07-28 05:18:26.260944 | TASK [upload-logs : Create log directories]
2026-07-28 05:18:26.681945 | localhost | changed
2026-07-28 05:18:26.686749 | 
2026-07-28 05:18:26.686820 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-28 05:18:27.025976 | localhost -> localhost | ok: Runtime: 0:00:00.005545
2026-07-28 05:18:27.033500 | 
2026-07-28 05:18:27.033569 | TASK [upload-logs : Upload logs to log server]
2026-07-28 05:18:27.442505 | localhost | Output suppressed because no_log was given
2026-07-28 05:18:27.447626 | 
2026-07-28 05:18:27.447712 | LOOP [upload-logs : Compress console log and json output]
2026-07-28 05:18:27.492093 | localhost | skipping: Conditional result was False
2026-07-28 05:18:27.498916 | localhost | skipping: Conditional result was False
2026-07-28 05:18:27.511049 | 
2026-07-28 05:18:27.511178 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-28 05:18:27.554802 | localhost | skipping: Conditional result was False
2026-07-28 05:18:27.555168 | 
2026-07-28 05:18:27.559822 | localhost | skipping: Conditional result was False
2026-07-28 05:18:27.575789 | 
2026-07-28 05:18:27.575923 | LOOP [upload-logs : Upload console log and json output]