2026-07-29 07:47:07.379716 | Job console starting
2026-07-29 07:47:07.398779 | Updating git repos
2026-07-29 07:47:08.567415 | Cloning repos into workspace
2026-07-29 07:49:23.854978 | Restoring repo states
2026-07-29 07:49:33.839423 | Merging changes
2026-07-29 07:50:50.967406 | Checking out repos
2026-07-29 07:51:10.049002 | Preparing playbooks
2026-07-29 07:52:18.648987 | Running Ansible setup
2026-07-29 07:52:22.688206 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 07:52:23.453921 | 
2026-07-29 07:52:23.454089 | PLAY [localhost]
2026-07-29 07:52:23.462534 | 
2026-07-29 07:52:23.462615 | TASK [Gathering Facts]
2026-07-29 07:52:28.680008 | localhost | ok
2026-07-29 07:52:28.692307 | 
2026-07-29 07:52:28.692449 | TASK [Setup log path fact]
2026-07-29 07:52:28.714323 | localhost | ok
2026-07-29 07:52:28.728392 | 
2026-07-29 07:52:28.728508 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 07:52:28.758490 | localhost | ok
2026-07-29 07:52:28.766170 | 
2026-07-29 07:52:28.766262 | TASK [emit-job-header : Print job information]
2026-07-29 07:52:28.808701 | # Job Information
2026-07-29 07:52:28.869082 | Ansible Version: 2.16.18
2026-07-29 07:52:28.869266 | Job: atmosphere-molecule-keycloak-selective
2026-07-29 07:52:28.869326 | Pipeline: check
2026-07-29 07:52:28.869375 | Executor: 2d72f0692154
2026-07-29 07:52:28.869420 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4166
2026-07-29 07:52:28.869472 | Event ID: aa39b900-8b20-11f1-9f68-cc98513a6b98
2026-07-29 07:52:28.876281 | 
2026-07-29 07:52:28.876400 | LOOP [emit-job-header : Print node information]
2026-07-29 07:52:28.991945 | localhost | ok:
2026-07-29 07:52:28.992232 | localhost | # Node Information
2026-07-29 07:52:28.992274 | localhost | Inventory Hostname: instance
2026-07-29 07:52:28.992308 | localhost | Hostname: np0000187370
2026-07-29 07:52:28.992339 | localhost | Username: zuul
2026-07-29 07:52:28.992373 | localhost | Distro: Ubuntu 22.04
2026-07-29 07:52:28.992403 | localhost | Provider: yul1
2026-07-29 07:52:28.992432 | localhost | Region: ca-ymq-1
2026-07-29 07:52:28.992462 | localhost | Label: ubuntu-jammy
2026-07-29 07:52:28.992490 | localhost | Product Name: OpenStack Nova
2026-07-29 07:52:28.992516 | localhost | Interface IP: 199.19.213.114
2026-07-29 07:52:29.005123 | 
2026-07-29 07:52:29.005287 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-29 07:52:29.784117 | localhost -> localhost | changed
2026-07-29 07:52:29.792910 | 
2026-07-29 07:52:29.793063 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-29 07:52:31.277071 | localhost -> localhost | changed
2026-07-29 07:52:31.288180 | 
2026-07-29 07:52:31.288273 | PLAY [all]
2026-07-29 07:52:31.298355 | 
2026-07-29 07:52:31.298454 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-29 07:52:31.631012 | instance -> localhost | ok
2026-07-29 07:52:31.641402 | 
2026-07-29 07:52:31.641533 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-29 07:52:31.678212 | instance | ok
2026-07-29 07:52:31.697415 | instance | included: /var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-29 07:52:31.703973 | 
2026-07-29 07:52:31.704072 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-29 07:52:33.080617 | instance -> localhost | Generating public/private rsa key pair.
2026-07-29 07:52:33.080822 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/a64c4c2b3c8f416cac62707b8d30ee89_id_rsa
2026-07-29 07:52:33.080855 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/a64c4c2b3c8f416cac62707b8d30ee89_id_rsa.pub
2026-07-29 07:52:33.080880 | instance -> localhost | The key fingerprint is:
2026-07-29 07:52:33.080904 | instance -> localhost | SHA256:5JsRTBKuSGkwFc7h/IxUZprcaOGJV3vgg5b6u/37a1k zuul-build-sshkey
2026-07-29 07:52:33.080949 | instance -> localhost | The key's randomart image is:
2026-07-29 07:52:33.080997 | instance -> localhost | +---[RSA 3072]----+
2026-07-29 07:52:33.081027 | instance -> localhost | |o.=.* o..        |
2026-07-29 07:52:33.081064 | instance -> localhost | | @ ^ + +         |
2026-07-29 07:52:33.081088 | instance -> localhost | |. ^ = o +        |
2026-07-29 07:52:33.081110 | instance -> localhost | | O = + o .       |
2026-07-29 07:52:33.081132 | instance -> localhost | |. o +   S        |
2026-07-29 07:52:33.081153 | instance -> localhost | | .       +E      |
2026-07-29 07:52:33.081174 | instance -> localhost | |  .     oo       |
2026-07-29 07:52:33.081196 | instance -> localhost | |   o    o        |
2026-07-29 07:52:33.081220 | instance -> localhost | |  o...o+o.       |
2026-07-29 07:52:33.081242 | instance -> localhost | +----[SHA256]-----+
2026-07-29 07:52:33.081302 | instance -> localhost | ok: Runtime: 0:00:00.847861
2026-07-29 07:52:33.086627 | 
2026-07-29 07:52:33.086698 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-29 07:52:33.108519 | instance | ok
2026-07-29 07:52:33.118916 | instance | included: /var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-29 07:52:33.127792 | 
2026-07-29 07:52:33.127879 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-29 07:52:33.152544 | instance | skipping: Conditional result was False
2026-07-29 07:52:33.159610 | 
2026-07-29 07:52:33.159699 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-29 07:52:33.708508 | instance | changed
2026-07-29 07:52:33.716365 | 
2026-07-29 07:52:33.716493 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-29 07:52:33.955440 | instance | ok
2026-07-29 07:52:33.963160 | 
2026-07-29 07:52:33.963234 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-29 07:52:34.464225 | instance | changed
2026-07-29 07:52:42.294528 | 
2026-07-29 07:52:42.294704 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-29 07:52:42.815271 | instance | changed
2026-07-29 07:52:42.820714 | 
2026-07-29 07:52:42.820785 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-29 07:52:42.846874 | instance | skipping: Conditional result was False
2026-07-29 07:52:42.853633 | 
2026-07-29 07:52:42.853726 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-29 07:52:43.376081 | instance -> localhost | changed
2026-07-29 07:52:43.392650 | 
2026-07-29 07:52:43.392772 | TASK [add-build-sshkey : Add back temp key]
2026-07-29 07:52:43.770403 | instance -> localhost | Identity added: /var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/a64c4c2b3c8f416cac62707b8d30ee89_id_rsa (zuul-build-sshkey)
2026-07-29 07:52:43.770674 | instance -> localhost | ok: Runtime: 0:00:00.015514
2026-07-29 07:52:43.778285 | 
2026-07-29 07:52:43.778353 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-29 07:52:44.174676 | instance | ok
2026-07-29 07:52:44.180570 | 
2026-07-29 07:52:44.180665 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-29 07:52:44.216486 | instance | skipping: Conditional result was False
2026-07-29 07:52:44.272378 | 
2026-07-29 07:52:44.272507 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-29 07:52:44.560192 | instance | ok
2026-07-29 07:52:44.566058 | 
2026-07-29 07:52:44.566128 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-29 07:52:59.723286 | instance | Output suppressed because no_log was given
2026-07-29 07:52:59.942359 | 
2026-07-29 07:52:59.942503 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-29 07:53:00.141873 | instance | ok: "logs"
2026-07-29 07:53:00.142366 | instance | ok: All items complete
2026-07-29 07:53:00.142398 | 
2026-07-29 07:53:00.299129 | instance | ok: "artifacts"
2026-07-29 07:53:00.460769 | instance | ok: "docs"
2026-07-29 07:53:01.019530 | 
2026-07-29 07:53:01.019719 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-29 07:53:01.213655 | instance | changed: "logs"
2026-07-29 07:53:01.381858 | instance | changed: "artifacts"
2026-07-29 07:53:01.549766 | instance | changed: "docs"
2026-07-29 07:53:01.571891 | 
2026-07-29 07:53:01.572004 | PLAY RECAP
2026-07-29 07:53:01.572053 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-29 07:53:01.572082 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:53:01.572103 | 
2026-07-29 07:53:01.718859 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-29 07:53:01.722539 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 07:53:02.441085 | 
2026-07-29 07:53:02.477813 | PLAY [all]
2026-07-29 07:53:02.497705 | 
2026-07-29 07:53:02.497871 | TASK [setup-uv : Extract archive]
2026-07-29 07:53:04.989960 | instance | changed
2026-07-29 07:53:04.996278 | 
2026-07-29 07:53:04.996402 | TASK [setup-uv : Print version]
2026-07-29 07:53:05.384046 | instance | uv 0.8.13
2026-07-29 07:53:05.552466 | instance | ok: Runtime: 0:00:00.012353
2026-07-29 07:53:05.564010 | 
2026-07-29 07:53:05.564085 | TASK [Install Ansible collection siblings]
2026-07-29 07:53:15.019959 | instance | changed:
2026-07-29 07:53:15.020187 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-29 07:53:15.020216 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-29 07:53:15.020238 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-29 07:53:15.020258 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-29 07:53:15.020278 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-29 07:53:15.020301 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-29 07:53:15.020322 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-29 07:53:15.020343 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-29 07:53:15.020364 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-29 07:53:15.020383 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-29 07:53:15.020402 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-29 07:53:15.020421 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-29 07:53:15.020439 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-29 07:53:15.020458 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-29 07:53:15.020476 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-29 07:53:15.020495 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-29 07:53:15.020514 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-29 07:53:15.020533 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-29 07:53:15.020551 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-29 07:53:15.020569 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-29 07:53:15.020587 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-29 07:53:15.020605 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-29 07:53:15.020625 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 07:53:15.020664 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-29 07:53:15.020683 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-29 07:53:15.020702 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 07:53:15.020721 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 07:53:15.020739 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-29 07:53:15.020757 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-29 07:53:15.020775 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-29 07:53:15.020793 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-29 07:53:15.020811 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-29 07:53:15.020829 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-29 07:53:15.020848 | 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-29 07:53:15.020872 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.020890 | instance | Process install dependency map
2026-07-29 07:53:15.020909 | instance | Starting collection install process
2026-07-29 07:53:15.020927 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-29 07:53:15.020945 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-29 07:53:15.020985 | instance | ansible.netcommon:* was installed successfully
2026-07-29 07:53:15.021003 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-29 07:53:15.021021 | instance | Creating virtual environment at: .venv
2026-07-29 07:53:15.021039 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 07:53:15.021057 | instance | Downloading setuptools (1.1MiB)
2026-07-29 07:53:15.021074 | instance | Downloading pygments (1.2MiB)
2026-07-29 07:53:15.021097 | instance | Downloading rjsonnet (1.2MiB)
2026-07-29 07:53:15.021115 | instance | Downloading cryptography (4.2MiB)
2026-07-29 07:53:15.021132 | instance | Downloading kubernetes (1.9MiB)
2026-07-29 07:53:15.021150 | instance | Downloading openstacksdk (1.7MiB)
2026-07-29 07:53:15.021168 | instance | Downloading netaddr (2.2MiB)
2026-07-29 07:53:15.021186 | instance | Downloading pydantic-core (2.0MiB)
2026-07-29 07:53:15.021204 | instance | Downloading ansible-core (2.1MiB)
2026-07-29 07:53:15.021222 | instance |    Building pyperclip==1.9.0
2026-07-29 07:53:15.021242 | instance |  Downloading rjsonnet
2026-07-29 07:53:15.021260 | instance |  Downloading pydantic-core
2026-07-29 07:53:15.021278 | instance |  Downloading netaddr
2026-07-29 07:53:15.021296 | instance |  Downloading pygments
2026-07-29 07:53:15.021314 | instance |  Downloading cryptography
2026-07-29 07:53:15.021331 | instance |  Downloading setuptools
2026-07-29 07:53:15.021349 | instance |  Downloading kubernetes
2026-07-29 07:53:15.021367 | instance |  Downloading ansible-core
2026-07-29 07:53:15.021385 | instance |  Downloading openstacksdk
2026-07-29 07:53:15.021403 | instance |       Built pyperclip==1.9.0
2026-07-29 07:53:15.021421 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-29 07:53:15.021439 | instance | Installed 87 packages in 47ms
2026-07-29 07:53:15.021457 | instance | Checked out ansible.posix to 1.6.0
2026-07-29 07:53:15.021475 | 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-29 07:53:15.021493 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.021511 | instance | Process install dependency map
2026-07-29 07:53:15.021538 | instance | Starting collection install process
2026-07-29 07:53:15.021557 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-29 07:53:15.021575 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-29 07:53:15.021593 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-29 07:53:15.021612 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.021630 | instance | 2.17.12
2026-07-29 07:53:15.021648 | instance | Checked out ansible.utils to v6.0.3
2026-07-29 07:53:15.021666 | 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-29 07:53:15.021684 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.021702 | instance | Process install dependency map
2026-07-29 07:53:15.021720 | instance | Starting collection install process
2026-07-29 07:53:15.021739 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-29 07:53:15.021758 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-29 07:53:15.021776 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-29 07:53:15.021794 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.021813 | instance | 2.17.12
2026-07-29 07:53:15.021831 | instance | Checked out atmosphere.common to v0.7.0
2026-07-29 07:53:15.021849 | 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-29 07:53:15.021867 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.021885 | instance | Process install dependency map
2026-07-29 07:53:15.021903 | instance | Starting collection install process
2026-07-29 07:53:15.021920 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-29 07:53:15.021938 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-29 07:53:15.021956 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-29 07:53:15.021973 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.021991 | instance | 2.17.12
2026-07-29 07:53:15.022009 | instance | Checked out community.crypto to 2.2.3
2026-07-29 07:53:15.022027 | 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-29 07:53:15.022045 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.022063 | instance | Process install dependency map
2026-07-29 07:53:15.022081 | instance | Starting collection install process
2026-07-29 07:53:15.022098 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-29 07:53:15.022116 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-29 07:53:15.022135 | instance | community.crypto:2.2.3 was installed successfully
2026-07-29 07:53:15.022153 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.022172 | instance | 2.17.12
2026-07-29 07:53:15.022189 | instance | Checked out community.general to 7.3.0
2026-07-29 07:53:15.022208 | 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-29 07:53:15.022232 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.022250 | instance | Process install dependency map
2026-07-29 07:53:15.022268 | instance | Starting collection install process
2026-07-29 07:53:15.022285 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-29 07:53:15.022303 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-29 07:53:15.022321 | instance | community.general:7.3.0 was installed successfully
2026-07-29 07:53:15.022339 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.022357 | instance | 2.17.12
2026-07-29 07:53:15.022375 | instance | Checked out community.mysql to 3.6.0
2026-07-29 07:53:15.022396 | 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-29 07:53:15.022415 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.022433 | instance | Process install dependency map
2026-07-29 07:53:15.022451 | instance | Starting collection install process
2026-07-29 07:53:15.022469 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-29 07:53:15.022486 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-29 07:53:15.022504 | instance | community.mysql:3.6.0 was installed successfully
2026-07-29 07:53:15.022521 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.022540 | instance | 2.17.12
2026-07-29 07:53:15.022558 | instance | Checked out kubernetes.core to 2.4.0
2026-07-29 07:53:15.022576 | 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-29 07:53:15.022594 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.022611 | instance | Process install dependency map
2026-07-29 07:53:15.022629 | instance | Starting collection install process
2026-07-29 07:53:15.022647 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-29 07:53:15.022665 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-29 07:53:15.022683 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-29 07:53:15.022701 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.022719 | instance | 2.17.12
2026-07-29 07:53:15.022737 | instance | Checked out openstack.cloud to 2.6.0
2026-07-29 07:53:15.022755 | 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-29 07:53:15.022773 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.022791 | instance | Process install dependency map
2026-07-29 07:53:15.022808 | instance | Starting collection install process
2026-07-29 07:53:15.022826 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-29 07:53:15.022844 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-29 07:53:15.022862 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-29 07:53:15.022880 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.022929 | instance | 2.17.12
2026-07-29 07:53:15.022963 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-29 07:53:15.022990 | 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-29 07:53:15.023011 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.023029 | instance | Process install dependency map
2026-07-29 07:53:15.023047 | instance | Starting collection install process
2026-07-29 07:53:15.023065 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-29 07:53:15.023083 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-29 07:53:15.023100 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-29 07:53:15.023119 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.023137 | instance | 2.17.12
2026-07-29 07:53:15.023154 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-29 07:53:15.023173 | 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-29 07:53:15.023191 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.023209 | instance | Process install dependency map
2026-07-29 07:53:15.023227 | instance | Starting collection install process
2026-07-29 07:53:15.023245 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-29 07:53:15.023264 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-29 07:53:15.023282 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-29 07:53:15.023300 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.023318 | instance | 2.17.12
2026-07-29 07:53:15.023336 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-29 07:53:15.023354 | 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-29 07:53:15.023373 | instance | Starting galaxy collection install process
2026-07-29 07:53:15.023391 | instance | Process install dependency map
2026-07-29 07:53:15.023409 | instance | Starting collection install process
2026-07-29 07:53:15.023427 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-29 07:53:15.023445 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-29 07:53:15.023463 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-29 07:53:15.023481 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-29 07:53:15.023499 | instance | 2.17.12
2026-07-29 07:53:15.029939 | 
2026-07-29 07:53:15.029996 | PLAY RECAP
2026-07-29 07:53:15.030046 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:53:15.030068 | 
2026-07-29 07:53:15.214377 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-29 07:53:15.218516 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 07:53:15.851011 | 
2026-07-29 07:53:15.851629 | PLAY [all]
2026-07-29 07:53:15.862147 | 
2026-07-29 07:53:15.862226 | TASK [Install "jq" for log collection]
2026-07-29 07:53:32.786484 | instance | changed
2026-07-29 07:53:32.788813 | 
2026-07-29 07:53:32.788895 | PLAY RECAP
2026-07-29 07:53:32.789001 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:53:32.789132 | 
2026-07-29 07:53:32.899018 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-29 07:53:32.903981 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 07:53:33.575195 | 
2026-07-29 07:53:33.575432 | PLAY [Prepare selective Molecule plan]
2026-07-29 07:53:33.587997 | 
2026-07-29 07:53:33.588097 | TASK [Generate selective Molecule plan]
2026-07-29 07:53:34.623542 | instance | ok: Runtime: 0:00:00.161030
2026-07-29 07:53:34.630430 | 
2026-07-29 07:53:34.630547 | TASK [Render selective Molecule plan]
2026-07-29 07:53:34.923014 | instance | Selective Molecule plan: selective
2026-07-29 07:53:34.923209 | instance | Changed files:
2026-07-29 07:53:34.923234 | instance |   M roles/nova/README.md
2026-07-29 07:53:34.923245 | instance | Targets: nova
2026-07-29 07:53:34.923255 | instance | Job decisions:
2026-07-29 07:53:34.923269 | instance |   RUN  aio-openvswitch: selected by changed components
2026-07-29 07:53:34.923281 | instance |        components: ceph, ceph-provisioners, cert-manager, cluster-issuer, coredns, csi, glance, ingress-nginx, keycloak, keystone, kubernetes, libvirt, memcached, neutron, nova, openvswitch, percona-xtradb-cluster, percona-xtradb-cluster-operator, placement, rabbitmq-cluster-operator
2026-07-29 07:53:34.923299 | instance |        Tempest include: ^tempest\.api\.compute\., ^tempest\.scenario\.test_server_
2026-07-29 07:53:34.923310 | instance |   SKIP aio-ovn: no changed component requires this job
2026-07-29 07:53:34.923319 | instance |   SKIP csi-local-path-provisioner: no changed component requires this job
2026-07-29 07:53:34.923328 | instance |   SKIP csi-rbd: no changed component requires this job
2026-07-29 07:53:34.923337 | instance |   SKIP keycloak: no changed component requires this job
2026-07-29 07:53:35.188135 | instance | ok: Runtime: 0:00:00.098037
2026-07-29 07:53:35.190404 | 
2026-07-29 07:53:35.190544 | PLAY RECAP
2026-07-29 07:53:35.190625 | instance | ok: 2 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:53:35.190664 | 
2026-07-29 07:53:35.417296 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-29 07:53:35.419822 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 07:53:36.107468 | 
2026-07-29 07:53:36.107644 | PLAY [Run selected Molecule scenario]
2026-07-29 07:53:36.120767 | 
2026-07-29 07:53:36.120944 | TASK [Read selective Molecule plan]
2026-07-29 07:53:36.461706 | instance | ok
2026-07-29 07:53:36.468204 | 
2026-07-29 07:53:36.468396 | TASK [Resolve this job decision]
2026-07-29 07:53:36.532591 | instance | ok
2026-07-29 07:53:36.541266 | 
2026-07-29 07:53:36.541371 | TASK [Assert this job exists in the plan]
2026-07-29 07:53:36.578588 | instance | ok: All assertions passed
2026-07-29 07:53:36.583962 | 
2026-07-29 07:53:36.584062 | TASK [Resolve selected scenario inputs]
2026-07-29 07:53:36.620193 | instance | ok
2026-07-29 07:53:36.628755 | 
2026-07-29 07:53:36.628893 | TASK [Report this job decision]
2026-07-29 07:53:36.686853 | instance | ok:
2026-07-29 07:53:36.687079 | instance | Selective Molecule job: keycloak
2026-07-29 07:53:36.687110 | instance | Decision: SKIP
2026-07-29 07:53:36.687137 | instance | Reason: no changed component requires this job
2026-07-29 07:53:36.687165 | instance | Scenario: keycloak
2026-07-29 07:53:36.687188 | instance | Targets: (none)
2026-07-29 07:53:36.687210 | instance | Components: (full scenario)
2026-07-29 07:53:36.692602 | 
2026-07-29 07:53:36.692706 | LOOP [Check Molecule inventory directories]
2026-07-29 07:53:36.737784 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.739465 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.741778 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.759374 | 
2026-07-29 07:53:36.759573 | LOOP [Copy Zuul inventory for Molecule]
2026-07-29 07:53:36.801761 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.802148 | 
2026-07-29 07:53:36.802868 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.805909 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.817698 | 
2026-07-29 07:53:36.817864 | LOOP [Use private addresses in Molecule inventory]
2026-07-29 07:53:36.867990 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.868303 | 
2026-07-29 07:53:36.870021 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.872443 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.882049 | 
2026-07-29 07:53:36.882289 | TASK [Prepare Molecule command]
2026-07-29 07:53:36.932112 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.943656 | 
2026-07-29 07:53:36.943790 | TASK [Run standard Molecule lifecycle with progress output]
2026-07-29 07:53:36.990747 | instance | skipping: Conditional result was False
2026-07-29 07:53:36.993903 | 
2026-07-29 07:53:36.993978 | PLAY RECAP
2026-07-29 07:53:36.994050 | instance | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 5 rescued: 0 ignored: 0
2026-07-29 07:53:36.994094 | 
2026-07-29 07:53:37.184143 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-29 07:53:37.189119 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 07:53:37.855726 | 
2026-07-29 07:53:37.855875 | PLAY [Publish selective Molecule plan]
2026-07-29 07:53:37.867177 | 
2026-07-29 07:53:37.867257 | TASK [Check for selective Molecule plan]
2026-07-29 07:53:38.227583 | instance | ok
2026-07-29 07:53:38.234667 | 
2026-07-29 07:53:38.234751 | TASK [Create log directory]
2026-07-29 07:53:38.674773 | instance | changed
2026-07-29 07:53:38.683881 | 
2026-07-29 07:53:38.683963 | TASK [Copy selective Molecule plan to logs]
2026-07-29 07:53:39.191210 | instance | changed
2026-07-29 07:53:39.193708 | 
2026-07-29 07:53:39.193757 | PLAY RECAP
2026-07-29 07:53:39.193804 | instance | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-29 07:53:39.193827 | 
2026-07-29 07:53:39.360625 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main]
2026-07-29 07:53:39.366303 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 07:53:40.099954 | 
2026-07-29 07:53:40.100089 | PLAY [all]
2026-07-29 07:53:40.114092 | 
2026-07-29 07:53:40.114200 | TASK [gather-host-logs : creating directory for system status]
2026-07-29 07:53:40.482272 | instance | changed
2026-07-29 07:53:40.491692 | 
2026-07-29 07:53:40.491830 | TASK [gather-host-logs : Get logs for each host]
2026-07-29 07:53:40.877850 | instance | + systemd-cgls --full --all --no-pager
2026-07-29 07:53:40.888258 | instance | + ip addr
2026-07-29 07:53:40.890239 | instance | + ip route
2026-07-29 07:53:40.892930 | instance | + lsblk
2026-07-29 07:53:40.895937 | instance | + mount
2026-07-29 07:53:40.898657 | instance | + docker images
2026-07-29 07:53:40.899076 | instance | /bin/bash: line 7: docker: command not found
2026-07-29 07:53:40.899261 | instance | + brctl show
2026-07-29 07:53:40.899557 | instance | /bin/bash: line 8: brctl: command not found
2026-07-29 07:53:40.899718 | instance | + ps aux --sort=-%mem
2026-07-29 07:53:40.909425 | instance | + dpkg -l
2026-07-29 07:53:40.920461 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-29 07:53:40.920781 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-29 07:53:40.920855 | instance | /bin/bash: line 11: docker: command not found
2026-07-29 07:53:40.921090 | instance | + '[' '!' -z '' ']'
2026-07-29 07:53:41.029778 | instance | ok: Runtime: 0:00:00.046462
2026-07-29 07:53:41.037824 | 
2026-07-29 07:53:41.037905 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-29 07:53:41.692070 | instance | changed:
2026-07-29 07:53:41.692277 | instance | created directory /var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/logs/instance
2026-07-29 07:53:41.692326 | instance | cd+++++++++ system/
2026-07-29 07:53:41.692364 | instance | >f+++++++++ system/brctl-show.txt
2026-07-29 07:53:41.692398 | instance | >f+++++++++ system/docker-images.txt
2026-07-29 07:53:41.692432 | instance | >f+++++++++ system/ip-addr.txt
2026-07-29 07:53:41.692468 | instance | >f+++++++++ system/ip-route.txt
2026-07-29 07:53:41.692505 | instance | >f+++++++++ system/lsblk.txt
2026-07-29 07:53:41.692539 | instance | >f+++++++++ system/mount.txt
2026-07-29 07:53:41.692575 | instance | >f+++++++++ system/packages.txt
2026-07-29 07:53:41.692607 | instance | >f+++++++++ system/ps.txt
2026-07-29 07:53:41.692638 | instance | >f+++++++++ system/systemd-cgls.txt
2026-07-29 07:53:41.707861 | 
2026-07-29 07:53:41.708028 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-29 07:53:41.915703 | instance | changed: "values"
2026-07-29 07:53:42.074775 | instance | changed: "releases"
2026-07-29 07:53:42.087391 | 
2026-07-29 07:53:42.087598 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-29 07:53:42.303907 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-29 07:53:42.630256 | instance | ok: Runtime: 0:00:00.005481
2026-07-29 07:53:42.641654 | 
2026-07-29 07:53:42.641855 | TASK [helm-release-status : Downloads logs to executor]
2026-07-29 07:53:43.164584 | instance | changed:
2026-07-29 07:53:43.164803 | instance | cd+++++++++ helm/
2026-07-29 07:53:43.164833 | instance | cd+++++++++ helm/releases/
2026-07-29 07:53:43.164855 | instance | cd+++++++++ helm/values/
2026-07-29 07:53:43.183887 | 
2026-07-29 07:53:43.184364 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-29 07:53:43.417462 | instance | changed
2026-07-29 07:53:43.425796 | 
2026-07-29 07:53:43.425977 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-29 07:53:43.715181 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:53:43.715521 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:53:43.721082 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:53:43.722340 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:53:43.723438 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:53:43.724573 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:53:43.728295 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:53:43.729159 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:53:43.729714 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:53:43.730921 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:53:43.734130 | instance | environment: line 1: kubectl: command not found
2026-07-29 07:53:43.735297 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-29 07:53:44.028814 | instance | ok: Runtime: 0:00:00.031140
2026-07-29 07:53:44.035855 | 
2026-07-29 07:53:44.035930 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-29 07:53:44.258521 | instance | changed
2026-07-29 07:53:44.264362 | 
2026-07-29 07:53:44.264500 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-29 07:53:44.491099 | instance | environment: line 5: kubectl: command not found
2026-07-29 07:53:44.491195 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:53:44.492661 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:53:44.492698 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-29 07:53:44.800508 | instance | ok: Runtime: 0:00:00.007958
2026-07-29 07:53:44.807924 | 
2026-07-29 07:53:44.808005 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-29 07:53:45.348749 | instance | changed:
2026-07-29 07:53:45.349019 | instance | cd+++++++++ objects/
2026-07-29 07:53:45.349062 | instance | cd+++++++++ objects/cluster/
2026-07-29 07:53:45.349093 | instance | cd+++++++++ objects/namespaced/
2026-07-29 07:53:45.362221 | 
2026-07-29 07:53:45.362289 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-29 07:53:45.569038 | instance | changed
2026-07-29 07:53:45.576589 | 
2026-07-29 07:53:45.576688 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-29 07:53:45.782546 | instance | changed
2026-07-29 07:53:45.789760 | 
2026-07-29 07:53:45.789838 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-29 07:53:45.997607 | instance | environment: line 3: kubectl: command not found
2026-07-29 07:53:46.327389 | instance | ok: Runtime: 0:00:00.009146
2026-07-29 07:53:46.334091 | 
2026-07-29 07:53:46.334157 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-29 07:53:46.845059 | instance | changed:
2026-07-29 07:53:46.845282 | instance | cd+++++++++ pod-logs/
2026-07-29 07:53:46.845324 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-29 07:53:46.863287 | 
2026-07-29 07:53:46.863394 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-29 07:53:47.075364 | instance | changed
2026-07-29 07:53:47.080247 | 
2026-07-29 07:53:47.080324 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-29 07:53:47.294184 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 07:53:47.613900 | instance | ok: Runtime: 0:00:00.036543
2026-07-29 07:53:47.618951 | 
2026-07-29 07:53:47.619020 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-29 07:53:47.825572 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-29 07:53:47.855011 | instance | ceph-mgr endpoints:
2026-07-29 07:53:49.458011 | instance | ok: Runtime: 0:00:00.034576
2026-07-29 07:53:49.465521 | 
2026-07-29 07:53:49.465617 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-29 07:53:49.687656 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-29 07:53:50.006746 | instance | ok: Runtime: 0:00:00.037885
2026-07-29 07:53:50.014761 | 
2026-07-29 07:53:50.014883 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-29 07:53:50.564693 | instance | changed: cd+++++++++ prometheus/
2026-07-29 07:53:50.588012 | 
2026-07-29 07:53:50.588274 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-29 07:53:50.792721 | instance | changed
2026-07-29 07:53:50.950151 | 
2026-07-29 07:53:50.950250 | TASK [gather-selenium-data : Get selenium data]
2026-07-29 07:53:51.250427 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-29 07:53:51.251930 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-29 07:53:51.552686 | instance | ERROR
2026-07-29 07:53:51.552898 | instance | {
2026-07-29 07:53:51.552950 | instance |   "delta": "0:00:00.006183",
2026-07-29 07:53:51.553063 | instance |   "end": "2026-07-29 07:53:51.252295",
2026-07-29 07:53:51.553096 | instance |   "msg": "non-zero return code",
2026-07-29 07:53:51.553123 | instance |   "rc": 1,
2026-07-29 07:53:51.553161 | instance |   "start": "2026-07-29 07:53:51.246112"
2026-07-29 07:53:51.553188 | instance | }
2026-07-29 07:53:51.553222 | instance | ERROR: Ignoring Errors
2026-07-29 07:53:51.558818 | 
2026-07-29 07:53:51.558891 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-29 07:53:52.323912 | instance | changed: cd+++++++++ selenium/
2026-07-29 07:53:52.334412 | 
2026-07-29 07:53:52.334523 | PLAY RECAP
2026-07-29 07:53:52.334588 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-07-29 07:53:52.334624 | 
2026-07-29 07:53:52.511875 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-29 07:53:53.007584 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 07:53:53.634053 | 
2026-07-29 07:53:54.529496 | PLAY [all]
2026-07-29 07:53:54.548532 | 
2026-07-29 07:53:54.548661 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-29 07:53:54.595674 | instance | skipping: Conditional result was False
2026-07-29 07:53:54.606455 | 
2026-07-29 07:53:54.606608 | TASK [fetch-output : Set log path for single node]
2026-07-29 07:53:54.651751 | instance | ok
2026-07-29 07:53:54.658561 | 
2026-07-29 07:53:54.658648 | LOOP [fetch-output : Ensure local output dirs]
2026-07-29 07:53:55.224809 | instance -> localhost | ok: "/var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/logs"
2026-07-29 07:53:56.151955 | instance -> localhost | changed: "/var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/artifacts"
2026-07-29 07:53:57.442320 | instance -> localhost | changed: "/var/lib/zuul/builds/a64c4c2b3c8f416cac62707b8d30ee89/work/docs"
2026-07-29 07:53:57.457817 | 
2026-07-29 07:53:57.457973 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-29 07:53:58.187351 | instance | changed: .d..t...... ./
2026-07-29 07:53:58.187576 | instance | changed: All items complete
2026-07-29 07:53:58.187653 | 
2026-07-29 07:54:00.338187 | instance | changed: .d..t...... ./
2026-07-29 07:54:01.666817 | instance | changed: .d..t...... ./
2026-07-29 07:54:01.692668 | 
2026-07-29 07:54:01.692845 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-29 07:54:02.487050 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007449
2026-07-29 07:54:02.755076 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010050
2026-07-29 07:54:02.777926 | 
2026-07-29 07:54:02.872123 | PLAY [all]
2026-07-29 07:54:02.885652 | 
2026-07-29 07:54:02.885867 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-29 07:54:03.348718 | instance | changed
2026-07-29 07:54:03.397936 | 
2026-07-29 07:54:03.398043 | PLAY RECAP
2026-07-29 07:54:03.398094 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-29 07:54:03.398119 | 
2026-07-29 07:54:03.568046 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-29 07:54:03.571725 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-29 07:54:04.281990 | 
2026-07-29 07:54:04.439899 | PLAY [localhost]
2026-07-29 07:54:04.467677 | 
2026-07-29 07:54:04.467945 | TASK [Generate Zuul manifest]
2026-07-29 07:54:04.491208 | localhost | ok
2026-07-29 07:54:04.509757 | 
2026-07-29 07:54:04.509977 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-29 07:54:05.023151 | localhost | changed
2026-07-29 07:54:05.037952 | 
2026-07-29 07:54:05.038116 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-29 07:54:05.084515 | localhost | ok
2026-07-29 07:54:05.091567 | 
2026-07-29 07:54:05.091677 | TASK [Upload logs]
2026-07-29 07:54:05.116406 | localhost | ok
2026-07-29 07:54:05.192212 | 
2026-07-29 07:54:05.192442 | TASK [Set zuul-log-path fact]
2026-07-29 07:54:05.216178 | localhost | ok
2026-07-29 07:54:05.234878 | 
2026-07-29 07:54:05.412902 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-29 07:54:05.451538 | localhost | ok
2026-07-29 07:54:05.466552 | 
2026-07-29 07:54:05.466770 | TASK [upload-logs : Create log directories]
2026-07-29 07:54:06.775123 | localhost | changed
2026-07-29 07:54:06.780736 | 
2026-07-29 07:54:06.780841 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-29 07:54:07.423323 | localhost -> localhost | ok: Runtime: 0:00:00.006716
2026-07-29 07:54:07.430238 | 
2026-07-29 07:54:07.430379 | TASK [upload-logs : Upload logs to log server]
2026-07-29 07:54:07.931146 | localhost | Output suppressed because no_log was given
2026-07-29 07:54:08.677424 | 
2026-07-29 07:54:08.677591 | LOOP [upload-logs : Compress console log and json output]
2026-07-29 07:54:08.729255 | localhost | skipping: Conditional result was False
2026-07-29 07:54:08.737663 | localhost | skipping: Conditional result was False
2026-07-29 07:54:08.755055 | 
2026-07-29 07:54:08.755293 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-29 07:54:08.805800 | localhost | skipping: Conditional result was False
2026-07-29 07:54:08.806663 | 
2026-07-29 07:54:08.810225 | localhost | skipping: Conditional result was False
2026-07-29 07:54:08.824996 | 
2026-07-29 07:54:08.825183 | LOOP [upload-logs : Upload console log and json output]