2026-07-25 19:50:06.238486 | Job console starting
2026-07-25 19:50:06.247178 | Updating git repos
2026-07-25 19:50:11.280645 | Cloning repos into workspace
2026-07-25 19:50:35.296593 | Restoring repo states
2026-07-25 19:50:41.835275 | Merging changes
2026-07-25 19:50:48.208658 | Checking out repos
2026-07-25 19:50:54.307870 | Preparing playbooks
2026-07-25 19:51:41.289494 | Running Ansible setup
2026-07-25 19:51:45.789123 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-25 19:51:46.520774 | 
2026-07-25 19:51:46.521005 | PLAY [localhost]
2026-07-25 19:51:46.531326 | 
2026-07-25 19:51:46.531425 | TASK [Gathering Facts]
2026-07-25 19:51:48.316712 | localhost | ok
2026-07-25 19:51:48.325442 | 
2026-07-25 19:51:48.325588 | TASK [Setup log path fact]
2026-07-25 19:51:48.347062 | localhost | ok
2026-07-25 19:51:48.359497 | 
2026-07-25 19:51:48.359622 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-25 19:51:48.389031 | localhost | ok
2026-07-25 19:51:48.399337 | 
2026-07-25 19:51:48.399480 | TASK [emit-job-header : Print job information]
2026-07-25 19:51:48.439320 | # Job Information
2026-07-25 19:51:48.439507 | Ansible Version: 2.16.18
2026-07-25 19:51:48.439541 | Job: atmosphere-molecule-keycloak-selective
2026-07-25 19:51:48.439566 | Pipeline: check
2026-07-25 19:51:48.439588 | Executor: 2d72f0692154
2026-07-25 19:51:48.439610 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4163
2026-07-25 19:51:48.439635 | Event ID: 908ea9f0-8861-11f1-98b3-070c85963e10
2026-07-25 19:51:48.442858 | 
2026-07-25 19:51:48.442929 | LOOP [emit-job-header : Print node information]
2026-07-25 19:51:48.546019 | localhost | ok:
2026-07-25 19:51:48.547105 | localhost | # Node Information
2026-07-25 19:51:48.547167 | localhost | Inventory Hostname: instance
2026-07-25 19:51:48.547201 | localhost | Hostname: np0000186874
2026-07-25 19:51:48.547229 | localhost | Username: zuul
2026-07-25 19:51:48.547259 | localhost | Distro: Ubuntu 22.04
2026-07-25 19:51:48.547288 | localhost | Provider: yul1
2026-07-25 19:51:48.547314 | localhost | Region: ca-ymq-1
2026-07-25 19:51:48.547340 | localhost | Label: ubuntu-jammy
2026-07-25 19:51:48.547365 | localhost | Product Name: OpenStack Nova
2026-07-25 19:51:48.547390 | localhost | Interface IP: 199.19.213.47
2026-07-25 19:51:48.558369 | 
2026-07-25 19:51:48.558571 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-25 19:51:48.981627 | localhost -> localhost | changed
2026-07-25 19:51:49.374236 | 
2026-07-25 19:51:49.374375 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-25 19:51:50.719313 | localhost -> localhost | changed
2026-07-25 19:51:50.733099 | 
2026-07-25 19:51:50.733324 | PLAY [all]
2026-07-25 19:51:50.743295 | 
2026-07-25 19:51:50.743361 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-25 19:51:50.962542 | instance -> localhost | ok
2026-07-25 19:51:50.970127 | 
2026-07-25 19:51:50.970217 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-25 19:51:51.004213 | instance | ok
2026-07-25 19:51:51.016803 | instance | included: /var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-25 19:51:51.022809 | 
2026-07-25 19:51:51.022882 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-25 19:51:52.997584 | instance -> localhost | Generating public/private rsa key pair.
2026-07-25 19:51:52.997870 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/6414c14493ce458888b16852379dbba3_id_rsa
2026-07-25 19:51:52.997925 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/6414c14493ce458888b16852379dbba3_id_rsa.pub
2026-07-25 19:51:52.997974 | instance -> localhost | The key fingerprint is:
2026-07-25 19:51:52.998013 | instance -> localhost | SHA256:kAD5j4qoEUNkx6kia8Ly/QPsF6pFnIx87Hp7i6qPPxU zuul-build-sshkey
2026-07-25 19:51:52.998076 | instance -> localhost | The key's randomart image is:
2026-07-25 19:51:52.998116 | instance -> localhost | +---[RSA 3072]----+
2026-07-25 19:51:52.998207 | instance -> localhost | | oo+o            |
2026-07-25 19:51:52.998256 | instance -> localhost | |o oo . .         |
2026-07-25 19:51:52.998295 | instance -> localhost | | ...  o          |
2026-07-25 19:51:52.998332 | instance -> localhost | |+o =E. .         |
2026-07-25 19:51:52.998367 | instance -> localhost | |*.o.B+  S        |
2026-07-25 19:51:52.998404 | instance -> localhost | |+= += o          |
2026-07-25 19:51:52.998438 | instance -> localhost | |*o =oo .         |
2026-07-25 19:51:52.998475 | instance -> localhost | |oo+o=.+          |
2026-07-25 19:51:52.998512 | instance -> localhost | |=+*=o=oo         |
2026-07-25 19:51:52.998558 | instance -> localhost | +----[SHA256]-----+
2026-07-25 19:51:52.998654 | instance -> localhost | ok: Runtime: 0:00:00.543668
2026-07-25 19:51:53.005965 | 
2026-07-25 19:51:53.006074 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-25 19:51:53.039943 | instance | ok
2026-07-25 19:51:53.050354 | instance | included: /var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-25 19:51:53.057767 | 
2026-07-25 19:51:53.057852 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-25 19:51:53.086425 | instance | skipping: Conditional result was False
2026-07-25 19:51:53.097945 | 
2026-07-25 19:51:53.098185 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-25 19:51:53.574589 | instance | changed
2026-07-25 19:51:53.579908 | 
2026-07-25 19:51:53.580000 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-25 19:51:53.766287 | instance | ok
2026-07-25 19:51:53.772560 | 
2026-07-25 19:51:53.772627 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-25 19:51:54.255024 | instance | changed
2026-07-25 19:51:54.293499 | 
2026-07-25 19:51:54.293622 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-25 19:51:54.756080 | instance | changed
2026-07-25 19:51:54.763415 | 
2026-07-25 19:51:54.763480 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-25 19:51:54.789525 | instance | skipping: Conditional result was False
2026-07-25 19:51:54.796807 | 
2026-07-25 19:51:54.796935 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-25 19:51:55.175484 | instance -> localhost | changed
2026-07-25 19:51:55.189114 | 
2026-07-25 19:51:55.189264 | TASK [add-build-sshkey : Add back temp key]
2026-07-25 19:51:55.664239 | instance -> localhost | Identity added: /var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/6414c14493ce458888b16852379dbba3_id_rsa (zuul-build-sshkey)
2026-07-25 19:51:55.664470 | instance -> localhost | ok: Runtime: 0:00:00.013163
2026-07-25 19:51:55.669894 | 
2026-07-25 19:51:55.669956 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-25 19:51:55.994700 | instance | ok
2026-07-25 19:51:56.005868 | 
2026-07-25 19:51:56.006034 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-25 19:51:56.030622 | instance | skipping: Conditional result was False
2026-07-25 19:51:56.084254 | 
2026-07-25 19:51:56.084405 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-25 19:51:56.392694 | instance | ok
2026-07-25 19:51:56.398872 | 
2026-07-25 19:51:56.398949 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-25 19:52:04.218574 | instance | Output suppressed because no_log was given
2026-07-25 19:52:04.850092 | 
2026-07-25 19:52:04.850222 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-25 19:52:05.041589 | instance | ok: "logs"
2026-07-25 19:52:05.041963 | instance | ok: All items complete
2026-07-25 19:52:05.042006 | 
2026-07-25 19:52:05.179672 | instance | ok: "artifacts"
2026-07-25 19:52:05.367941 | instance | ok: "docs"
2026-07-25 19:52:05.402080 | 
2026-07-25 19:52:05.402306 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-25 19:52:05.598089 | instance | changed: "logs"
2026-07-25 19:52:05.754227 | instance | changed: "artifacts"
2026-07-25 19:52:05.933817 | instance | changed: "docs"
2026-07-25 19:52:05.966594 | 
2026-07-25 19:52:05.966680 | PLAY RECAP
2026-07-25 19:52:05.966726 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-25 19:52:05.966756 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 19:52:05.966779 | 
2026-07-25 19:52:06.084782 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-25 19:52:06.093948 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-25 19:52:06.715215 | 
2026-07-25 19:52:06.715548 | PLAY [all]
2026-07-25 19:52:06.738881 | 
2026-07-25 19:52:06.739073 | TASK [setup-uv : Extract archive]
2026-07-25 19:52:10.986501 | instance | changed
2026-07-25 19:52:10.996360 | 
2026-07-25 19:52:10.996470 | TASK [setup-uv : Print version]
2026-07-25 19:52:11.365758 | instance | uv 0.8.13
2026-07-25 19:52:11.539821 | instance | ok: Runtime: 0:00:00.015249
2026-07-25 19:52:11.550683 | 
2026-07-25 19:52:11.550751 | TASK [Install Ansible collection siblings]
2026-07-25 19:52:23.566159 | instance | changed:
2026-07-25 19:52:23.566323 | instance | Sibling collection ansible.netcommon at /home/zuul/src/github.com/ansible-collections/ansible.netcommon
2026-07-25 19:52:23.566352 | instance | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-07-25 19:52:23.566373 | instance | Sibling collection ansible.utils at /home/zuul/src/github.com/ansible-collections/ansible.utils
2026-07-25 19:52:23.566393 | instance | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-07-25 19:52:23.566412 | instance | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-07-25 19:52:23.566434 | instance | Sibling collection community.mysql at /home/zuul/src/github.com/ansible-collections/community.mysql
2026-07-25 19:52:23.566455 | instance | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-07-25 19:52:23.566476 | instance | Sibling collection vexxhost.ceph at /home/zuul/src/github.com/vexxhost/ansible-collection-ceph
2026-07-25 19:52:23.566495 | instance | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-07-25 19:52:23.566514 | instance | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-07-25 19:52:23.566533 | instance | Sibling collection atmosphere.common at /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-07-25 19:52:23.566551 | instance | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-07-25 19:52:23.566569 | instance | Resolved ansible.netcommon to 1.2.0 for 1.2.0
2026-07-25 19:52:23.566587 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0
2026-07-25 19:52:23.566605 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0
2026-07-25 19:52:23.566624 | instance | Resolved community.crypto to 2.2.3 for 2.2.3
2026-07-25 19:52:23.566642 | instance | Resolved community.general to 7.3.0 for 7.3.0
2026-07-25 19:52:23.566660 | instance | Resolved community.mysql to 3.6.0 for 3.6.0
2026-07-25 19:52:23.566678 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0
2026-07-25 19:52:23.566696 | instance | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-07-25 19:52:23.566714 | instance | Resolved vexxhost.ceph to v4.1.0 for >=3.2.0
2026-07-25 19:52:23.566731 | instance | Resolved atmosphere.common to v0.7.0 for >=0.7.0
2026-07-25 19:52:23.566750 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-25 19:52:23.566780 | instance | Resolved community.general to 7.3.0 for 7.3.0,>=4.5.0
2026-07-25 19:52:23.566799 | instance | Resolved ansible.utils to v6.0.3 for >=2.9.0,>=6.0.0
2026-07-25 19:52:23.566817 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-25 19:52:23.566835 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-25 19:52:23.566852 | instance | Resolved vexxhost.kubernetes to v3.3.0 for >=3.3.0,>=2.5.0
2026-07-25 19:52:23.566870 | instance | Resolved ansible.posix to 1.6.0 for 1.6.0,>=1.6.0
2026-07-25 19:52:23.566887 | instance | Resolved community.crypto to 2.2.3 for 2.2.3,>=2.2.3
2026-07-25 19:52:23.566904 | instance | Resolved kubernetes.core to 2.4.0 for 2.4.0,>=2.4.0,>=2.3.2
2026-07-25 19:52:23.566922 | instance | Resolved vexxhost.containers to v1.6.8 for >=1.1.1,>=1.6.0
2026-07-25 19:52:23.566940 | instance | Checked out ansible.netcommon to 1.2.0
2026-07-25 19:52:23.566958 | 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-25 19:52:23.566982 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.567000 | instance | Process install dependency map
2026-07-25 19:52:23.567018 | instance | Starting collection install process
2026-07-25 19:52:23.567036 | instance | Installing 'ansible.netcommon:*' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-07-25 19:52:23.567053 | instance | Created collection for ansible.netcommon:* at /home/zuul/.ansible/collections/ansible_collections/ansible/netcommon
2026-07-25 19:52:23.567071 | instance | ansible.netcommon:* was installed successfully
2026-07-25 19:52:23.567088 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-07-25 19:52:23.567106 | instance | Creating virtual environment at: .venv
2026-07-25 19:52:23.567123 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-25 19:52:23.567141 | instance | Downloading setuptools (1.1MiB)
2026-07-25 19:52:23.567158 | instance | Downloading rjsonnet (1.2MiB)
2026-07-25 19:52:23.567178 | instance | Downloading pydantic-core (2.0MiB)
2026-07-25 19:52:23.567196 | instance | Downloading openstacksdk (1.7MiB)
2026-07-25 19:52:23.567214 | instance | Downloading cryptography (4.2MiB)
2026-07-25 19:52:23.567232 | instance | Downloading ansible-core (2.1MiB)
2026-07-25 19:52:23.567249 | instance | Downloading pygments (1.2MiB)
2026-07-25 19:52:23.567266 | instance | Downloading kubernetes (1.9MiB)
2026-07-25 19:52:23.567284 | instance | Downloading netaddr (2.2MiB)
2026-07-25 19:52:23.567301 | instance |    Building pyperclip==1.9.0
2026-07-25 19:52:23.567320 | instance |  Downloading rjsonnet
2026-07-25 19:52:23.567338 | instance |  Downloading pygments
2026-07-25 19:52:23.567355 | instance |  Downloading setuptools
2026-07-25 19:52:23.567373 | instance |  Downloading openstacksdk
2026-07-25 19:52:23.567389 | instance |  Downloading pydantic-core
2026-07-25 19:52:23.567406 | instance |  Downloading kubernetes
2026-07-25 19:52:23.567423 | instance |  Downloading ansible-core
2026-07-25 19:52:23.567441 | instance |  Downloading netaddr
2026-07-25 19:52:23.567470 | instance |  Downloading cryptography
2026-07-25 19:52:23.567513 | instance |       Built pyperclip==1.9.0
2026-07-25 19:52:23.567541 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-07-25 19:52:23.567566 | instance | Installed 83 packages in 200ms
2026-07-25 19:52:23.567592 | instance | Checked out ansible.posix to 1.6.0
2026-07-25 19:52:23.567618 | 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-25 19:52:23.567641 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.567663 | instance | Process install dependency map
2026-07-25 19:52:23.567697 | instance | Starting collection install process
2026-07-25 19:52:23.567723 | instance | Installing 'ansible.posix:1.6.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-07-25 19:52:23.567749 | instance | Created collection for ansible.posix:1.6.0 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-07-25 19:52:23.567773 | instance | ansible.posix:1.6.0 was installed successfully
2026-07-25 19:52:23.567796 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.567817 | instance | 2.17.12
2026-07-25 19:52:23.567836 | instance | Checked out ansible.utils to v6.0.3
2026-07-25 19:52:23.567854 | 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-25 19:52:23.567872 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.567892 | instance | Process install dependency map
2026-07-25 19:52:23.567918 | instance | Starting collection install process
2026-07-25 19:52:23.567942 | instance | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-07-25 19:52:23.567966 | instance | Created collection for ansible.utils:6.0.3 at /home/zuul/.ansible/collections/ansible_collections/ansible/utils
2026-07-25 19:52:23.567991 | instance | ansible.utils:6.0.3 was installed successfully
2026-07-25 19:52:23.568016 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.568038 | instance | 2.17.12
2026-07-25 19:52:23.568061 | instance | Checked out atmosphere.common to v0.7.0
2026-07-25 19:52:23.568082 | 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-25 19:52:23.568103 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.568121 | instance | Process install dependency map
2026-07-25 19:52:23.568138 | instance | Starting collection install process
2026-07-25 19:52:23.568156 | instance | Installing 'atmosphere.common:0.7.0' to '/home/zuul/.ansible/collections/ansible_collections/atmosphere/common'
2026-07-25 19:52:23.568174 | instance | Created collection for atmosphere.common:0.7.0 at /home/zuul/.ansible/collections/ansible_collections/atmosphere/common
2026-07-25 19:52:23.568191 | instance | atmosphere.common:0.7.0 was installed successfully
2026-07-25 19:52:23.568209 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.568226 | instance | 2.17.12
2026-07-25 19:52:23.568243 | instance | Checked out community.crypto to 2.2.3
2026-07-25 19:52:23.568261 | 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-25 19:52:23.568284 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.568302 | instance | Process install dependency map
2026-07-25 19:52:23.568320 | instance | Starting collection install process
2026-07-25 19:52:23.568337 | instance | Installing 'community.crypto:2.2.3' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-07-25 19:52:23.568355 | instance | Created collection for community.crypto:2.2.3 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-07-25 19:52:23.568372 | instance | community.crypto:2.2.3 was installed successfully
2026-07-25 19:52:23.568390 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.568407 | instance | 2.17.12
2026-07-25 19:52:23.568424 | instance | Checked out community.general to 7.3.0
2026-07-25 19:52:23.568442 | 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-25 19:52:23.568465 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.568484 | instance | Process install dependency map
2026-07-25 19:52:23.568502 | instance | Starting collection install process
2026-07-25 19:52:23.568520 | instance | Installing 'community.general:7.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-07-25 19:52:23.568538 | instance | Created collection for community.general:7.3.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-07-25 19:52:23.568556 | instance | community.general:7.3.0 was installed successfully
2026-07-25 19:52:23.568574 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.568592 | instance | 2.17.12
2026-07-25 19:52:23.568611 | instance | Checked out community.mysql to 3.6.0
2026-07-25 19:52:23.568631 | 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-25 19:52:23.568668 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.568688 | instance | Process install dependency map
2026-07-25 19:52:23.568706 | instance | Starting collection install process
2026-07-25 19:52:23.568724 | instance | Installing 'community.mysql:3.6.0' to '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-07-25 19:52:23.568743 | instance | Created collection for community.mysql:3.6.0 at /home/zuul/.ansible/collections/ansible_collections/community/mysql
2026-07-25 19:52:23.568761 | instance | community.mysql:3.6.0 was installed successfully
2026-07-25 19:52:23.568779 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.568797 | instance | 2.17.12
2026-07-25 19:52:23.568818 | instance | Checked out kubernetes.core to 2.4.0
2026-07-25 19:52:23.568839 | 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-25 19:52:23.568867 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.568896 | instance | Process install dependency map
2026-07-25 19:52:23.568921 | instance | Starting collection install process
2026-07-25 19:52:23.568945 | instance | Installing 'kubernetes.core:2.4.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-07-25 19:52:23.568984 | instance | Created collection for kubernetes.core:2.4.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-07-25 19:52:23.569004 | instance | kubernetes.core:2.4.0 was installed successfully
2026-07-25 19:52:23.569023 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.569041 | instance | 2.17.12
2026-07-25 19:52:23.569060 | instance | Checked out openstack.cloud to 2.6.0
2026-07-25 19:52:23.569079 | 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-25 19:52:23.569097 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.569116 | instance | Process install dependency map
2026-07-25 19:52:23.569135 | instance | Starting collection install process
2026-07-25 19:52:23.569154 | instance | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-07-25 19:52:23.569172 | instance | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-07-25 19:52:23.569191 | instance | openstack.cloud:2.6.0 was installed successfully
2026-07-25 19:52:23.569209 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.569228 | instance | 2.17.12
2026-07-25 19:52:23.569253 | instance | Checked out vexxhost.ceph to v4.1.0
2026-07-25 19:52:23.569273 | 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-25 19:52:23.569291 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.569310 | instance | Process install dependency map
2026-07-25 19:52:23.569328 | instance | Starting collection install process
2026-07-25 19:52:23.569347 | instance | Installing 'vexxhost.ceph:4.1.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph'
2026-07-25 19:52:23.569366 | instance | Created collection for vexxhost.ceph:4.1.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph
2026-07-25 19:52:23.569384 | instance | vexxhost.ceph:4.1.0 was installed successfully
2026-07-25 19:52:23.569402 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.569420 | instance | 2.17.12
2026-07-25 19:52:23.569438 | instance | Checked out vexxhost.containers to v1.6.8
2026-07-25 19:52:23.569456 | 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-25 19:52:23.569474 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.569493 | instance | Process install dependency map
2026-07-25 19:52:23.569511 | instance | Starting collection install process
2026-07-25 19:52:23.569530 | instance | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-07-25 19:52:23.569548 | instance | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-07-25 19:52:23.569567 | instance | vexxhost.containers:1.6.8 was installed successfully
2026-07-25 19:52:23.569585 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.569604 | instance | 2.17.12
2026-07-25 19:52:23.569622 | instance | Checked out vexxhost.kubernetes to v3.3.0
2026-07-25 19:52:23.569641 | 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-25 19:52:23.569660 | instance | Starting galaxy collection install process
2026-07-25 19:52:23.569678 | instance | Process install dependency map
2026-07-25 19:52:23.569697 | instance | Starting collection install process
2026-07-25 19:52:23.569715 | instance | Installing 'vexxhost.kubernetes:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-07-25 19:52:23.569734 | instance | Created collection for vexxhost.kubernetes:3.3.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-07-25 19:52:23.569752 | instance | vexxhost.kubernetes:3.3.0 was installed successfully
2026-07-25 19:52:23.569771 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-07-25 19:52:23.569789 | instance | 2.17.12
2026-07-25 19:52:23.575437 | 
2026-07-25 19:52:23.575485 | PLAY RECAP
2026-07-25 19:52:23.575529 | instance | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 19:52:23.575550 | 
2026-07-25 19:52:23.678337 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-25 19:52:23.684394 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-25 19:52:24.290354 | 
2026-07-25 19:52:24.290471 | PLAY [all]
2026-07-25 19:52:24.301195 | 
2026-07-25 19:52:24.301270 | TASK [Install "jq" for log collection]
2026-07-25 19:52:38.673795 | instance | changed
2026-07-25 19:52:38.675316 | 
2026-07-25 19:52:38.675366 | PLAY RECAP
2026-07-25 19:52:38.675413 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-25 19:52:38.675468 | 
2026-07-25 19:52:38.771972 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/pre.yml@main]
2026-07-25 19:52:38.778427 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-25 19:52:39.379137 | 
2026-07-25 19:52:39.379263 | PLAY [Prepare Molecule dependencies]
2026-07-25 19:52:39.389597 | 
2026-07-25 19:52:39.389685 | TASK [Install jq for log collection]
2026-07-25 19:52:41.998742 | instance | ok
2026-07-25 19:52:42.002569 | 
2026-07-25 19:52:42.002638 | PLAY [Install Go for the Atmosphere deployment binary]
2026-07-25 19:52:42.013494 | 
2026-07-25 19:52:42.013867 | TASK [ensure-go : Check installed go version]
2026-07-25 19:52:42.553955 | instance | ok: ERROR (ignored)
2026-07-25 19:52:42.554311 | instance | {
2026-07-25 19:52:42.554357 | instance |   "failed_when_result": false,
2026-07-25 19:52:42.554391 | instance |   "msg": "[Errno 2] No such file or directory: b'go'",
2026-07-25 19:52:42.554420 | instance |   "rc": 2
2026-07-25 19:52:42.554446 | instance | }
2026-07-25 19:52:42.561196 | 
2026-07-25 19:52:42.561282 | TASK [ensure-go : Skip if correct version of go is installed]
2026-07-25 19:52:42.616454 | instance | ok
2026-07-25 19:52:42.627035 | instance | included: /var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml
2026-07-25 19:52:42.631803 | 
2026-07-25 19:52:42.631868 | TASK [ensure-go : Create temp directory]
2026-07-25 19:52:42.930972 | instance | changed
2026-07-25 19:52:42.939373 | 
2026-07-25 19:52:42.939526 | TASK [ensure-go : Get archive checksum]
2026-07-25 19:52:43.592540 | instance | ok: OK (64 bytes)
2026-07-25 19:52:43.602060 | 
2026-07-25 19:52:43.602233 | TASK [ensure-go : Download go archive]
2026-07-25 19:52:45.045731 | instance | changed: OK (78559214 bytes)
2026-07-25 19:52:45.342114 | 
2026-07-25 19:52:45.342368 | TASK [ensure-go : Install go]
2026-07-25 19:52:51.171266 | instance | changed
2026-07-25 19:52:51.184149 | 
2026-07-25 19:52:51.184252 | PLAY [Prepare selective Molecule execution plan]
2026-07-25 19:52:51.191808 | 
2026-07-25 19:52:51.191924 | TASK [Build atmosphere binary]
2026-07-25 19:52:51.408843 | instance | go: downloading github.com/spf13/cobra v1.9.1
2026-07-25 19:52:51.412517 | instance | go: downloading golang.org/x/sync v0.18.0
2026-07-25 19:52:51.416156 | instance | go: downloading github.com/goccy/go-yaml v1.18.0
2026-07-25 19:52:51.702992 | instance | go: downloading github.com/spf13/pflag v1.0.7
2026-07-25 19:52:59.243332 | instance | ok: Runtime: 0:00:07.559441
2026-07-25 19:52:59.249625 | 
2026-07-25 19:52:59.249745 | TASK [Generate selective CI plan]
2026-07-25 19:52:59.830730 | instance | ok: Runtime: 0:00:00.030199
2026-07-25 19:52:59.836564 | 
2026-07-25 19:52:59.836645 | TASK [Explain selective CI plan]
2026-07-25 19:53:00.054316 | instance | CI plan mode: selective
2026-07-25 19:53:00.054495 | instance | Changed files:
2026-07-25 19:53:00.054505 | instance |   A	roles/nova/.ci-selective-test
2026-07-25 19:53:00.054512 | instance | Matches:
2026-07-25 19:53:00.054518 | instance |   roles/nova/.ci-selective-test: component:nova (targets) -> nova
2026-07-25 19:53:00.054525 | instance | Direct targets: nova
2026-07-25 19:53:00.054533 | instance | Deployment roots: glance-images, neutron, nova
2026-07-25 19:53:00.054541 | instance | Verification profiles: compute
2026-07-25 19:53:00.054550 | instance | Variant openvswitch (21 components): ceph, ceph-provisioners, cert-manager, cluster-issuer, coredns, csi, glance, glance-images, ingress-nginx, keycloak, keystone, kubernetes, libvirt, memcached, neutron, nova, openvswitch, percona-xtradb-cluster, percona-xtradb-cluster-operator, placement, rabbitmq-cluster-operator
2026-07-25 19:53:00.054556 | instance | Molecule jobs:
2026-07-25 19:53:00.054560 | instance |   aio-openvswitch: run (21 components) - executes the openvswitch deployment variant
2026-07-25 19:53:00.054565 | instance |   aio-ovn: skip - plan has no ovn deployment variant
2026-07-25 19:53:00.054570 | instance |   csi-local-path-provisioner: skip - none of the job's verification profiles were requested
2026-07-25 19:53:00.054574 | instance |   csi-rbd: skip - none of the job's verification profiles were requested
2026-07-25 19:53:00.054581 | instance |   keycloak: skip - none of the job's verification profiles were requested
2026-07-25 19:53:00.371279 | instance | ok: Runtime: 0:00:00.030000
2026-07-25 19:53:00.378213 | 
2026-07-25 19:53:00.378272 | TASK [Print selective CI plan]
2026-07-25 19:53:00.411178 | instance | ok
2026-07-25 19:53:00.416883 | 
2026-07-25 19:53:00.416945 | TASK [Report conservative fallback]
2026-07-25 19:53:00.441095 | instance | skipping: Conditional result was False
2026-07-25 19:53:00.449768 | 
2026-07-25 19:53:00.449862 | TASK [Create selective CI plan log directory]
2026-07-25 19:53:00.739114 | instance | changed
2026-07-25 19:53:00.747135 | 
2026-07-25 19:53:00.747226 | TASK [Publish selective CI plan with job logs]
2026-07-25 19:53:02.270380 | instance | changed
2026-07-25 19:53:02.272029 | 
2026-07-25 19:53:02.272080 | PLAY RECAP
2026-07-25 19:53:02.272125 | instance | ok: 13 changed: 5 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-25 19:53:02.272148 | 
2026-07-25 19:53:02.409101 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/prepare.yml@main]
2026-07-25 19:53:02.412616 | RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-25 19:53:03.041382 | 
2026-07-25 19:53:03.041504 | PLAY [Run selected Molecule deployment and verification]
2026-07-25 19:53:03.052719 | 
2026-07-25 19:53:03.052836 | TASK [Check for selective CI plan]
2026-07-25 19:53:03.444808 | instance | ok
2026-07-25 19:53:03.454240 | 
2026-07-25 19:53:03.454367 | TASK [Validate selective CI plan]
2026-07-25 19:53:03.998818 | instance | ok: Runtime: 0:00:00.031937
2026-07-25 19:53:04.006308 | 
2026-07-25 19:53:04.006410 | TASK [Resolve selective CI plan availability]
2026-07-25 19:53:04.057997 | instance | ok
2026-07-25 19:53:04.065311 | 
2026-07-25 19:53:04.065396 | TASK [Read selective CI plan]
2026-07-25 19:53:04.360710 | instance | ok
2026-07-25 19:53:04.367128 | 
2026-07-25 19:53:04.367333 | TASK [Load selective CI plan or full fallback]
2026-07-25 19:53:04.420395 | instance | ok
2026-07-25 19:53:04.427771 | 
2026-07-25 19:53:04.427855 | TASK [Find this job in the selective CI plan]
2026-07-25 19:53:04.479113 | instance | ok
2026-07-25 19:53:04.484847 | 
2026-07-25 19:53:04.484926 | TASK [Resolve this job or use full fallback]
2026-07-25 19:53:04.538261 | instance | ok
2026-07-25 19:53:04.543821 | 
2026-07-25 19:53:04.543887 | TASK [Resolve deployment and verification inputs]
2026-07-25 19:53:04.596885 | instance | ok
2026-07-25 19:53:04.602768 | 
2026-07-25 19:53:04.602883 | TASK [Resolve deployment tags]
2026-07-25 19:53:04.667395 | instance | ok
2026-07-25 19:53:04.672467 | 
2026-07-25 19:53:04.672548 | TASK [Report Molecule job decision]
2026-07-25 19:53:04.721576 | instance | ok: {'job': 'keycloak', 'mode': 'selective', 'run': False, 'reason': "none of the job's verification profiles were requested", 'network_backend': 'openvswitch', 'csi_driver': 'rbd', 'components': [], 'verification_profiles': ['compute'], 'extra_vars': {}}
2026-07-25 19:53:04.729884 | 
2026-07-25 19:53:04.729977 | TASK [Copy inventory file for Zuul]
2026-07-25 19:53:04.755797 | instance | skipping: Conditional result was False
2026-07-25 19:53:04.764776 | 
2026-07-25 19:53:04.764837 | TASK [Switch ansible_host to private IP]
2026-07-25 19:53:04.788473 | instance | skipping: Conditional result was False
2026-07-25 19:53:04.798652 | 
2026-07-25 19:53:04.798752 | TASK [Prepare Molecule scenario]
2026-07-25 19:53:05.369360 | instance | skipping: Conditional result was False
2026-07-25 19:53:05.378778 | 
2026-07-25 19:53:05.378851 | TASK [Prepare host for Ceph-backed storage]
2026-07-25 19:53:05.905503 | instance | skipping: Conditional result was False
2026-07-25 19:53:05.916656 | 
2026-07-25 19:53:05.916780 | TASK [Build deployment command]
2026-07-25 19:53:05.955427 | instance | skipping: Conditional result was False
2026-07-25 19:53:05.961289 | 
2026-07-25 19:53:05.961366 | TASK [Run initial deployment with progress heartbeat]
2026-07-25 19:53:05.985788 | instance | skipping: Conditional result was False
2026-07-25 19:53:05.991169 | 
2026-07-25 19:53:05.991288 | TASK [Run idempotence deployment with progress heartbeat]
2026-07-25 19:53:06.015416 | instance | skipping: Conditional result was False
2026-07-25 19:53:06.022883 | 
2026-07-25 19:53:06.022982 | TASK [Check for br-ex interface]
2026-07-25 19:53:06.562464 | instance | skipping: Conditional result was False
2026-07-25 19:53:06.570550 | 
2026-07-25 19:53:06.570679 | TASK [Add IP address to br-ex]
2026-07-25 19:53:07.106513 | instance | skipping: Conditional result was False
2026-07-25 19:53:07.111721 | 
2026-07-25 19:53:07.111790 | TASK [Set br-ex interface up]
2026-07-25 19:53:07.660718 | instance | skipping: Conditional result was False
2026-07-25 19:53:07.665881 | 
2026-07-25 19:53:07.665947 | TASK [Resolve Molecule verification command]
2026-07-25 19:53:07.689803 | instance | skipping: Conditional result was False
2026-07-25 19:53:07.698410 | 
2026-07-25 19:53:07.698499 | TASK [Run Molecule verification with progress heartbeat]
2026-07-25 19:53:07.721165 | instance | skipping: Conditional result was False
2026-07-25 19:53:07.722807 | 
2026-07-25 19:53:07.722895 | PLAY RECAP
2026-07-25 19:53:07.723012 | instance | ok: 10 changed: 0 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 0
2026-07-25 19:53:07.723063 | 
2026-07-25 19:53:07.822201 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/selective-run.yml@main]
2026-07-25 19:53:07.828363 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-25 19:53:08.486616 | 
2026-07-25 19:53:08.486729 | PLAY [all]
2026-07-25 19:53:08.500017 | 
2026-07-25 19:53:08.500109 | TASK [gather-host-logs : creating directory for system status]
2026-07-25 19:53:08.838670 | instance | ok
2026-07-25 19:53:08.846524 | 
2026-07-25 19:53:08.846609 | TASK [gather-host-logs : Get logs for each host]
2026-07-25 19:53:09.209288 | instance | + systemd-cgls --full --all --no-pager
2026-07-25 19:53:09.237944 | instance | + ip addr
2026-07-25 19:53:09.240378 | instance | + ip route
2026-07-25 19:53:09.243776 | instance | + lsblk
2026-07-25 19:53:09.247396 | instance | + mount
2026-07-25 19:53:09.250171 | instance | + docker images
2026-07-25 19:53:09.250679 | instance | /bin/bash: line 7: docker: command not found
2026-07-25 19:53:09.250950 | instance | + brctl show
2026-07-25 19:53:09.251414 | instance | /bin/bash: line 8: brctl: command not found
2026-07-25 19:53:09.251673 | instance | + ps aux --sort=-%mem
2026-07-25 19:53:09.279163 | instance | + dpkg -l
2026-07-25 19:53:09.289990 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-07-25 19:53:09.290123 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-07-25 19:53:09.290200 | instance | /bin/bash: line 11: docker: command not found
2026-07-25 19:53:09.290902 | instance | + '[' '!' -z '' ']'
2026-07-25 19:53:09.381944 | instance | ok: Runtime: 0:00:00.087579
2026-07-25 19:53:09.389387 | 
2026-07-25 19:53:09.389475 | TASK [gather-host-logs : Downloads logs to executor]
2026-07-25 19:53:10.008399 | instance | ERROR
2026-07-25 19:53:10.106309 | instance | {
2026-07-25 19:53:10.106484 | instance |   "msg": "Warning: Permanently added '199.19.213.47' (ED25519) to the list of known hosts.\r\nrsync: [generator] chown \"/var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/logs/instance/system\" failed: Invalid argument (22)\nrsync: [receiver] chown \"/var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/logs/instance/system/.ci-plan.json.aswJGJ\" failed: Invalid argument (22)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [generator=3.2.7]\n",
2026-07-25 19:53:10.106570 | instance |   "rc": 23
2026-07-25 19:53:10.106620 | instance | }
2026-07-25 19:53:10.106702 | instance | ERROR: Ignoring Errors
2026-07-25 19:53:10.122140 | 
2026-07-25 19:53:10.122247 | LOOP [helm-release-status : creating directory for helm release status]
2026-07-25 19:53:10.393744 | instance | changed: "values"
2026-07-25 19:53:10.533266 | instance | changed: "releases"
2026-07-25 19:53:10.550213 | 
2026-07-25 19:53:10.550366 | TASK [helm-release-status : Gather get release status for helm charts]
2026-07-25 19:53:10.801972 | instance | /bin/bash: line 3: kubectl: command not found
2026-07-25 19:53:11.097225 | instance | ok: Runtime: 0:00:00.038541
2026-07-25 19:53:11.102200 | 
2026-07-25 19:53:11.102270 | TASK [helm-release-status : Downloads logs to executor]
2026-07-25 19:53:11.603494 | instance | changed:
2026-07-25 19:53:11.603670 | instance | cd+++++++++ helm/
2026-07-25 19:53:11.603713 | instance | cd+++++++++ helm/releases/
2026-07-25 19:53:11.603745 | instance | cd+++++++++ helm/values/
2026-07-25 19:53:11.614615 | 
2026-07-25 19:53:11.614685 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-07-25 19:53:11.820056 | instance | changed
2026-07-25 19:53:11.825987 | 
2026-07-25 19:53:11.826055 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-07-25 19:53:12.051466 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 19:53:12.051596 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 19:53:12.056380 | instance | environment: line 1: kubectl: command not found
2026-07-25 19:53:12.057280 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 19:53:12.057892 | instance | environment: line 1: kubectl: command not found
2026-07-25 19:53:12.059587 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 19:53:12.059965 | instance | environment: line 1: kubectl: command not found
2026-07-25 19:53:12.061181 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 19:53:12.062812 | instance | environment: line 1: kubectl: command not found
2026-07-25 19:53:12.063718 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 19:53:12.064079 | instance | environment: line 1: kubectl: command not found
2026-07-25 19:53:12.065335 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-07-25 19:53:12.384087 | instance | ok: Runtime: 0:00:00.024250
2026-07-25 19:53:12.390843 | 
2026-07-25 19:53:12.390926 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-07-25 19:53:12.599901 | instance | changed
2026-07-25 19:53:12.604808 | 
2026-07-25 19:53:12.604933 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-07-25 19:53:12.834910 | instance | environment: line 5: kubectl: command not found
2026-07-25 19:53:12.835748 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 19:53:12.836071 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 19:53:12.836098 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-07-25 19:53:13.143115 | instance | ok: Runtime: 0:00:00.010080
2026-07-25 19:53:13.150115 | 
2026-07-25 19:53:13.150208 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-07-25 19:53:13.609300 | instance | changed:
2026-07-25 19:53:13.609505 | instance | cd+++++++++ objects/
2026-07-25 19:53:13.609543 | instance | cd+++++++++ objects/cluster/
2026-07-25 19:53:13.609574 | instance | cd+++++++++ objects/namespaced/
2026-07-25 19:53:13.623764 | 
2026-07-25 19:53:13.623923 | TASK [gather-pod-logs : creating directory for pod logs]
2026-07-25 19:53:13.835489 | instance | changed
2026-07-25 19:53:13.840818 | 
2026-07-25 19:53:13.840884 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-07-25 19:53:14.049325 | instance | changed
2026-07-25 19:53:14.054290 | 
2026-07-25 19:53:14.054357 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-07-25 19:53:14.267470 | instance | environment: line 3: kubectl: command not found
2026-07-25 19:53:14.585485 | instance | ok: Runtime: 0:00:00.010807
2026-07-25 19:53:14.592068 | 
2026-07-25 19:53:14.592159 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-07-25 19:53:15.417908 | instance | changed:
2026-07-25 19:53:15.418547 | instance | cd+++++++++ pod-logs/
2026-07-25 19:53:15.418589 | instance | cd+++++++++ pod-logs/failed-pods/
2026-07-25 19:53:15.429248 | 
2026-07-25 19:53:15.429422 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-07-25 19:53:15.624237 | instance | changed
2026-07-25 19:53:15.631115 | 
2026-07-25 19:53:15.631274 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-07-25 19:53:15.846081 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-25 19:53:16.173438 | instance | ok: Runtime: 0:00:00.034505
2026-07-25 19:53:16.180348 | 
2026-07-25 19:53:16.180474 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-07-25 19:53:16.387900 | instance | /bin/bash: line 2: kubectl: command not found
2026-07-25 19:53:16.421743 | instance | ceph-mgr endpoints:
2026-07-25 19:53:16.722600 | instance | ok: Runtime: 0:00:00.038420
2026-07-25 19:53:16.728838 | 
2026-07-25 19:53:16.728918 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-07-25 19:53:16.931282 | instance | /bin/bash: line 4: kubectl: command not found
2026-07-25 19:53:17.261577 | instance | ok: Runtime: 0:00:00.034451
2026-07-25 19:53:17.266484 | 
2026-07-25 19:53:17.266559 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-07-25 19:53:17.749981 | instance | changed: cd+++++++++ prometheus/
2026-07-25 19:53:17.760530 | 
2026-07-25 19:53:17.760635 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-07-25 19:53:17.956257 | instance | changed
2026-07-25 19:53:17.962002 | 
2026-07-25 19:53:17.962107 | TASK [gather-selenium-data : Get selenium data]
2026-07-25 19:53:18.233133 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-07-25 19:53:18.234888 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-07-25 19:53:18.532276 | instance | ERROR
2026-07-25 19:53:18.532436 | instance | {
2026-07-25 19:53:18.532468 | instance |   "delta": "0:00:00.007295",
2026-07-25 19:53:18.532490 | instance |   "end": "2026-07-25 19:53:18.235225",
2026-07-25 19:53:18.532509 | instance |   "msg": "non-zero return code",
2026-07-25 19:53:18.532530 | instance |   "rc": 1,
2026-07-25 19:53:18.532549 | instance |   "start": "2026-07-25 19:53:18.227930"
2026-07-25 19:53:18.532568 | instance | }
2026-07-25 19:53:18.532594 | instance | ERROR: Ignoring Errors
2026-07-25 19:53:18.537598 | 
2026-07-25 19:53:18.537680 | TASK [gather-selenium-data : Downloads logs to executor]
2026-07-25 19:53:19.006636 | instance | changed: cd+++++++++ selenium/
2026-07-25 19:53:19.014589 | 
2026-07-25 19:53:19.014661 | PLAY RECAP
2026-07-25 19:53:19.014723 | instance | ok: 23 changed: 21 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-07-25 19:53:19.014754 | 
2026-07-25 19:53:19.125972 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere-zuul-jobs/playbooks/molecule/post.yml@main]
2026-07-25 19:53:19.130163 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-25 19:53:19.715205 | 
2026-07-25 19:53:19.715315 | PLAY [all]
2026-07-25 19:53:19.726279 | 
2026-07-25 19:53:19.726353 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-25 19:53:19.770812 | instance | skipping: Conditional result was False
2026-07-25 19:53:19.779589 | 
2026-07-25 19:53:19.779696 | TASK [fetch-output : Set log path for single node]
2026-07-25 19:53:19.823704 | instance | ok
2026-07-25 19:53:19.829536 | 
2026-07-25 19:53:19.829637 | LOOP [fetch-output : Ensure local output dirs]
2026-07-25 19:53:20.780401 | instance -> localhost | ok: "/var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/logs"
2026-07-25 19:53:20.985984 | instance -> localhost | changed: "/var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/artifacts"
2026-07-25 19:53:21.202205 | instance -> localhost | changed: "/var/lib/zuul/builds/6414c14493ce458888b16852379dbba3/work/docs"
2026-07-25 19:53:21.224566 | 
2026-07-25 19:53:21.224715 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-25 19:53:22.145537 | instance | changed: .d..t...... ./
2026-07-25 19:53:22.145743 | instance | changed: All items complete
2026-07-25 19:53:22.145785 | 
2026-07-25 19:53:23.002036 | instance | changed: .d..t...... ./
2026-07-25 19:53:23.448553 | instance | changed: .d..t...... ./
2026-07-25 19:53:23.471974 | 
2026-07-25 19:53:23.472094 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-25 19:53:23.918391 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008285
2026-07-25 19:53:24.141790 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.005239
2026-07-25 19:53:24.157822 | 
2026-07-25 19:53:24.157959 | PLAY [all]
2026-07-25 19:53:24.171069 | 
2026-07-25 19:53:24.171137 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-25 19:53:24.640610 | instance | changed
2026-07-25 19:53:24.647574 | 
2026-07-25 19:53:24.647638 | PLAY RECAP
2026-07-25 19:53:24.647693 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-25 19:53:24.647723 | 
2026-07-25 19:53:24.743783 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-25 19:53:24.746864 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-25 19:53:25.322424 | 
2026-07-25 19:53:25.322547 | PLAY [localhost]
2026-07-25 19:53:25.332337 | 
2026-07-25 19:53:25.332412 | TASK [Generate Zuul manifest]
2026-07-25 19:53:25.352431 | localhost | ok
2026-07-25 19:53:25.370742 | 
2026-07-25 19:53:25.370858 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-25 19:53:25.724883 | localhost | changed
2026-07-25 19:53:25.737419 | 
2026-07-25 19:53:25.737497 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-25 19:53:25.890384 | localhost | ok
2026-07-25 19:53:25.899901 | 
2026-07-25 19:53:25.899996 | TASK [Upload logs]
2026-07-25 19:53:25.920951 | localhost | ok
2026-07-25 19:53:25.984068 | 
2026-07-25 19:53:25.984190 | TASK [Set zuul-log-path fact]
2026-07-25 19:53:26.005581 | localhost | ok
2026-07-25 19:53:26.020221 | 
2026-07-25 19:53:26.020303 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-25 19:53:26.051703 | localhost | ok
2026-07-25 19:53:26.062520 | 
2026-07-25 19:53:26.062630 | TASK [upload-logs : Create log directories]
2026-07-25 19:53:26.497826 | localhost | changed
2026-07-25 19:53:26.502541 | 
2026-07-25 19:53:26.502608 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-25 19:53:27.091933 | localhost -> localhost | ok: Runtime: 0:00:00.195171
2026-07-25 19:53:27.098670 | 
2026-07-25 19:53:27.098765 | TASK [upload-logs : Upload logs to log server]
2026-07-25 19:53:27.678846 | localhost | Output suppressed because no_log was given
2026-07-25 19:53:27.683362 | 
2026-07-25 19:53:27.683427 | LOOP [upload-logs : Compress console log and json output]
2026-07-25 19:53:27.723625 | localhost | skipping: Conditional result was False
2026-07-25 19:53:27.731347 | localhost | skipping: Conditional result was False
2026-07-25 19:53:27.746084 | 
2026-07-25 19:53:27.746243 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-25 19:53:27.786458 | localhost | skipping: Conditional result was False
2026-07-25 19:53:27.786842 | 
2026-07-25 19:53:27.790912 | localhost | skipping: Conditional result was False
2026-07-25 19:53:27.799557 | 
2026-07-25 19:53:27.799709 | LOOP [upload-logs : Upload console log and json output]