2026-04-17 03:59:07.784719 | Job console starting 2026-04-17 03:59:07.801919 | Updating git repos 2026-04-17 03:59:07.877669 | Cloning repos into workspace 2026-04-17 03:59:07.946875 | Restoring repo states 2026-04-17 03:59:07.965366 | Merging changes 2026-04-17 03:59:09.519786 | Checking out repos 2026-04-17 03:59:09.642747 | Preparing playbooks 2026-04-17 03:59:13.723409 | Running Ansible setup 2026-04-17 03:59:17.807515 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-17 03:59:18.456687 | 2026-04-17 03:59:18.456880 | PLAY [localhost] 2026-04-17 03:59:18.468869 | 2026-04-17 03:59:18.469040 | TASK [Gathering Facts] 2026-04-17 03:59:19.458896 | localhost | ok 2026-04-17 03:59:19.469985 | 2026-04-17 03:59:19.470097 | TASK [Setup log path fact] 2026-04-17 03:59:19.492464 | localhost | ok 2026-04-17 03:59:19.506491 | 2026-04-17 03:59:19.506575 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-17 03:59:19.537149 | localhost | ok 2026-04-17 03:59:19.547027 | 2026-04-17 03:59:19.547119 | TASK [emit-job-header : Print job information] 2026-04-17 03:59:19.588945 | # Job Information 2026-04-17 03:59:20.436033 | Ansible Version: 2.16.16 2026-04-17 03:59:20.436255 | Job: atmosphere-molecule-keycloak 2026-04-17 03:59:20.436355 | Pipeline: check 2026-04-17 03:59:20.436427 | Executor: 0a8996d2b663 2026-04-17 03:59:20.436473 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3736 2026-04-17 03:59:20.436524 | Event ID: a888d1f0-3a11-11f1-80f9-133233eba8ad 2026-04-17 03:59:20.444527 | 2026-04-17 03:59:20.444702 | LOOP [emit-job-header : Print node information] 2026-04-17 03:59:20.539578 | localhost | ok: 2026-04-17 03:59:20.539810 | localhost | # Node Information 2026-04-17 03:59:20.539850 | localhost | Inventory Hostname: instance 2026-04-17 03:59:20.539880 | localhost | Hostname: np0000166962 2026-04-17 03:59:20.539907 | localhost | Username: zuul 2026-04-17 03:59:20.539937 | localhost | Distro: Ubuntu 22.04 2026-04-17 03:59:20.539966 | localhost | Provider: yul1 2026-04-17 03:59:20.539995 | localhost | Region: ca-ymq-1 2026-04-17 03:59:20.540022 | localhost | Label: ubuntu-jammy 2026-04-17 03:59:20.540047 | localhost | Product Name: OpenStack Nova 2026-04-17 03:59:20.540073 | localhost | Interface IP: 199.204.45.72 2026-04-17 03:59:20.556767 | 2026-04-17 03:59:20.556901 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-17 03:59:20.962141 | localhost -> localhost | changed 2026-04-17 03:59:20.971878 | 2026-04-17 03:59:20.972062 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-17 03:59:21.854250 | localhost -> localhost | changed 2026-04-17 03:59:21.864484 | 2026-04-17 03:59:21.864560 | PLAY [all] 2026-04-17 03:59:21.876446 | 2026-04-17 03:59:21.876539 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-17 03:59:22.214627 | instance -> localhost | ok 2026-04-17 03:59:22.224063 | 2026-04-17 03:59:22.224218 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-17 03:59:22.261109 | instance | ok 2026-04-17 03:59:22.275118 | instance | included: /var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-17 03:59:22.286299 | 2026-04-17 03:59:22.286486 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-17 03:59:23.603881 | instance -> localhost | Generating public/private rsa key pair. 2026-04-17 03:59:23.604068 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/78b0b0b821274c3386f331779dcfe6bc_id_rsa 2026-04-17 03:59:23.604099 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/78b0b0b821274c3386f331779dcfe6bc_id_rsa.pub 2026-04-17 03:59:23.604123 | instance -> localhost | The key fingerprint is: 2026-04-17 03:59:23.604146 | instance -> localhost | SHA256:gK+qL8h0EyRLSzqjPitQZ6Za+T7Ab0gTCMKAcM56Qj0 zuul-build-sshkey 2026-04-17 03:59:23.604182 | instance -> localhost | The key's randomart image is: 2026-04-17 03:59:23.604204 | instance -> localhost | +---[RSA 3072]----+ 2026-04-17 03:59:23.604232 | instance -> localhost | |B . | 2026-04-17 03:59:23.604255 | instance -> localhost | |+O.. . | 2026-04-17 03:59:23.604275 | instance -> localhost | |*oBE. . | 2026-04-17 03:59:23.604295 | instance -> localhost | |*+o.=. . | 2026-04-17 03:59:23.604315 | instance -> localhost | |+=.B. . S | 2026-04-17 03:59:23.604334 | instance -> localhost | |o+Oo . | 2026-04-17 03:59:23.604354 | instance -> localhost | |*+.*o | 2026-04-17 03:59:23.604373 | instance -> localhost | |=+..= | 2026-04-17 03:59:23.604395 | instance -> localhost | |o==o.. | 2026-04-17 03:59:23.604416 | instance -> localhost | +----[SHA256]-----+ 2026-04-17 03:59:23.604462 | instance -> localhost | ok: Runtime: 0:00:00.886281 2026-04-17 03:59:23.609665 | 2026-04-17 03:59:23.609732 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-17 03:59:23.640735 | instance | ok 2026-04-17 03:59:23.653259 | instance | included: /var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-17 03:59:23.665393 | 2026-04-17 03:59:23.665569 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-17 03:59:23.691475 | instance | skipping: Conditional result was False 2026-04-17 03:59:23.701057 | 2026-04-17 03:59:23.701198 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-17 03:59:24.142335 | instance | changed 2026-04-17 03:59:24.147430 | 2026-04-17 03:59:24.147501 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-17 03:59:24.347369 | instance | ok 2026-04-17 03:59:24.352546 | 2026-04-17 03:59:24.352615 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-17 03:59:24.876268 | instance | changed 2026-04-17 03:59:24.882750 | 2026-04-17 03:59:24.882822 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-17 03:59:25.360469 | instance | changed 2026-04-17 03:59:25.367969 | 2026-04-17 03:59:25.368066 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-17 03:59:25.394304 | instance | skipping: Conditional result was False 2026-04-17 03:59:25.407704 | 2026-04-17 03:59:25.407829 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-17 03:59:25.850234 | instance -> localhost | changed 2026-04-17 03:59:25.861337 | 2026-04-17 03:59:25.861445 | TASK [add-build-sshkey : Add back temp key] 2026-04-17 03:59:26.163766 | instance -> localhost | Identity added: /var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/78b0b0b821274c3386f331779dcfe6bc_id_rsa (zuul-build-sshkey) 2026-04-17 03:59:26.164097 | instance -> localhost | ok: Runtime: 0:00:00.013468 2026-04-17 03:59:26.169567 | 2026-04-17 03:59:26.169637 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-17 03:59:26.482961 | instance | ok 2026-04-17 03:59:26.493293 | 2026-04-17 03:59:26.493376 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-17 03:59:26.518145 | instance | skipping: Conditional result was False 2026-04-17 03:59:26.532644 | 2026-04-17 03:59:26.532739 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-17 03:59:26.869603 | instance | ok 2026-04-17 03:59:26.875271 | 2026-04-17 03:59:26.875342 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-17 03:59:28.627708 | instance | Output suppressed because no_log was given 2026-04-17 03:59:28.641434 | 2026-04-17 03:59:28.641605 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-17 03:59:29.442336 | instance | ok: "logs" 2026-04-17 03:59:29.442610 | instance | ok: All items complete 2026-04-17 03:59:29.442647 | 2026-04-17 03:59:29.965237 | instance | ok: "artifacts" 2026-04-17 03:59:30.545314 | instance | ok: "docs" 2026-04-17 03:59:30.554219 | 2026-04-17 03:59:30.554305 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-17 03:59:31.110827 | instance | changed: "logs" 2026-04-17 03:59:31.273066 | instance | changed: "artifacts" 2026-04-17 03:59:31.459911 | instance | changed: "docs" 2026-04-17 03:59:31.475486 | 2026-04-17 03:59:31.475550 | PLAY RECAP 2026-04-17 03:59:31.475621 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-17 03:59:31.475663 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-17 03:59:31.475691 | 2026-04-17 03:59:31.599452 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-17 03:59:31.604467 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-17 03:59:32.240586 | 2026-04-17 03:59:32.240713 | PLAY [all] 2026-04-17 03:59:32.252328 | 2026-04-17 03:59:32.252403 | TASK [setup-uv : Extract archive] 2026-04-17 03:59:34.516109 | instance | changed 2026-04-17 03:59:34.523057 | 2026-04-17 03:59:34.523139 | TASK [setup-uv : Print version] 2026-04-17 03:59:34.845070 | instance | uv 0.8.13 2026-04-17 03:59:35.059909 | instance | ok: Runtime: 0:00:00.011116 2026-04-17 03:59:35.069193 | 2026-04-17 03:59:35.069300 | PLAY RECAP 2026-04-17 03:59:35.069366 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-17 03:59:35.069394 | 2026-04-17 03:59:35.202803 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-17 03:59:35.207419 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2024.1] 2026-04-17 03:59:35.795951 | 2026-04-17 03:59:35.796113 | PLAY [all] 2026-04-17 03:59:35.813253 | 2026-04-17 03:59:35.813414 | TASK [Install "jq" for log collection] 2026-04-17 03:59:53.240405 | instance | changed 2026-04-17 03:59:53.242411 | 2026-04-17 03:59:53.242472 | PLAY RECAP 2026-04-17 03:59:53.242526 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-17 03:59:53.242579 | 2026-04-17 03:59:53.377137 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2024.1] 2026-04-17 03:59:53.379500 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-17 03:59:53.971255 | 2026-04-17 03:59:53.971395 | PLAY [all] 2026-04-17 03:59:53.982762 | 2026-04-17 03:59:53.982838 | TASK [Copy inventory file for Zuul] 2026-04-17 03:59:54.888844 | instance | changed 2026-04-17 03:59:54.899331 | 2026-04-17 03:59:54.899502 | TASK [Switch "ansible_host" to private IP] 2026-04-17 03:59:55.167057 | instance | changed: 1 replacements made 2026-04-17 03:59:55.174770 | 2026-04-17 03:59:55.174868 | TASK [Run Molecule scenario] 2026-04-17 03:59:55.553344 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-04-17 03:59:55.553522 | instance | Creating virtual environment at: .venv 2026-04-17 03:59:55.580900 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-17 03:59:55.608782 | instance | Downloading kubernetes (1.9MiB) 2026-04-17 03:59:55.609093 | instance | Downloading netaddr (2.2MiB) 2026-04-17 03:59:55.609278 | instance | Downloading openstacksdk (1.7MiB) 2026-04-17 03:59:55.609476 | instance | Downloading cryptography (4.2MiB) 2026-04-17 03:59:55.611029 | instance | Downloading ansible-core (2.1MiB) 2026-04-17 03:59:55.626197 | instance | Downloading pygments (1.2MiB) 2026-04-17 03:59:55.626562 | instance | Downloading rjsonnet (1.2MiB) 2026-04-17 03:59:55.704258 | instance | Downloading setuptools (1.1MiB) 2026-04-17 03:59:55.881408 | instance | Building pyperclip==1.9.0 2026-04-17 03:59:55.888056 | instance | Downloading rjsonnet 2026-04-17 03:59:56.021053 | instance | Downloading netaddr 2026-04-17 03:59:56.030447 | instance | Downloading pygments 2026-04-17 03:59:56.042442 | instance | Downloading cryptography 2026-04-17 03:59:56.076279 | instance | Downloading setuptools 2026-04-17 03:59:56.125428 | instance | Downloading kubernetes 2026-04-17 03:59:56.152198 | instance | Downloading ansible-core 2026-04-17 03:59:56.176329 | instance | Downloading openstacksdk 2026-04-17 03:59:56.559154 | instance | Built pyperclip==1.9.0 2026-04-17 03:59:56.769526 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-17 03:59:56.808962 | instance | Installed 79 packages in 37ms 2026-04-17 03:59:57.487866 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-04-17 03:59:58.154109 | instance | INFO [keycloak > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-17 03:59:58.154185 | instance | INFO [keycloak > prerun] Performing prerun with role_name_check=0... 2026-04-17 04:01:38.266229 | instance | WARNING Retrying execution failure 1 of: ansible-galaxy collection install -vvv vexxhost.ceph:>=3.1.2 2026-04-17 04:01:38.266381 | instance | ERROR Command ansible-galaxy collection install -vvv vexxhost.ceph:>=3.1.2, returned 1 code: 2026-04-17 04:01:38.266396 | instance | Starting galaxy collection install process 2026-04-17 04:01:38.266407 | instance | Process install dependency map 2026-04-17 04:01:38.266418 | instance | 2026-04-17 04:01:38.266428 | instance | ansible-galaxy 2026-04-17 04:01:38.266438 | instance | config file = None 2026-04-17 04:01:38.266448 | instance | configured module search path = 2026-04-17 04:01:38.266459 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-04-17 04:01:38.266470 | instance | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages 2026-04-17 04:01:38.266501 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-04-17 04:01:38.266538 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-04-17 04:01:38.266548 | instance | jinja version = 3.1.6 2026-04-17 04:01:38.266558 | instance | libyaml = True 2026-04-17 04:01:38.266568 | instance | No config file found; using defaults 2026-04-17 04:01:38.266578 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-04-17 04:01:38.266588 | instance | Found installed collection ansible.utils:6.0.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-04-17 04:01:38.266598 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-04-17 04:01:38.266608 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-04-17 04:01:38.266618 | instance | Found installed collection openstack.cloud:2.5.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-04-17 04:01:38.266647 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-04-17 04:01:38.266657 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-04-17 04:01:38.266667 | instance | : Collection ansible.netcommon does not support Ansible version 2026-04-17 04:01:38.266677 | instance | 2.17.12 2026-04-17 04:01:38.266699 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-04-17 04:01:38.266709 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-04-17 04:01:38.266719 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-04-17 04:01:38.266729 | instance | unexpected error when getting available versions of collection 2026-04-17 04:01:38.266739 | instance | vexxhost.containers: Unknown error when attempting to call Galaxy at 'https://g 2026-04-17 04:01:38.266750 | instance | alaxy.ansible.com/api/v3/collections/vexxhost/containers/versions/?limit=100': 2026-04-17 04:01:38.266759 | instance | . 2026-04-17 04:01:38.266782 | instance | ERROR! Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/vexxhost/containers/versions/?limit=100': . 2026-04-17 04:01:38.266792 | instance | 2026-04-17 04:01:38.266812 | instance | Traceback (most recent call last): 2026-04-17 04:01:38.266822 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-04-17 04:01:38.266832 | instance | sys.exit(main()) 2026-04-17 04:01:38.266843 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-04-17 04:01:38.267077 | instance | return self.main(*args, **kwargs) 2026-04-17 04:01:38.267225 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-04-17 04:01:38.267607 | instance | rv = self.invoke(ctx) 2026-04-17 04:01:38.267754 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-04-17 04:01:38.268259 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-04-17 04:01:38.268408 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-04-17 04:01:38.268772 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-04-17 04:01:38.268928 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-04-17 04:01:38.269209 | instance | return callback(*args, **kwargs) 2026-04-17 04:01:38.269371 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-04-17 04:01:38.269676 | instance | return func(*args, **kwargs) 2026-04-17 04:01:38.269851 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func 2026-04-17 04:01:38.270054 | instance | return f(get_current_context(), *args, **kwargs) 2026-04-17 04:01:38.270215 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-04-17 04:01:38.270515 | instance | return func(ctx) 2026-04-17 04:01:38.270692 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test 2026-04-17 04:01:38.271683 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-04-17 04:01:38.272382 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios 2026-04-17 04:01:38.272954 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-04-17 04:01:38.273347 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 240, in _run_scenarios 2026-04-17 04:01:38.273845 | instance | scenario.config.runtime.prepare_environment( 2026-04-17 04:01:38.274160 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment 2026-04-17 04:01:38.274784 | instance | self.install_collection( 2026-04-17 04:01:38.275137 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 552, in install_collection 2026-04-17 04:01:38.275767 | instance | raise InvalidPrerequisiteError(msg) 2026-04-17 04:01:38.278501 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv vexxhost.ceph:>=3.1.2, returned 1 code: 2026-04-17 04:01:38.278522 | instance | Starting galaxy collection install process 2026-04-17 04:01:38.278530 | instance | Process install dependency map 2026-04-17 04:01:38.278537 | instance | 2026-04-17 04:01:38.278543 | instance | ansible-galaxy [core 2.17.12] 2026-04-17 04:01:38.278549 | instance | config file = None 2026-04-17 04:01:38.278555 | instance | configured module search path = ['/home/zuul/src/github.com/vexxhost/atmosphere/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-04-17 04:01:38.278564 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-04-17 04:01:38.278573 | instance | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages 2026-04-17 04:01:38.278581 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-04-17 04:01:38.278589 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-04-17 04:01:38.278595 | instance | jinja version = 3.1.6 2026-04-17 04:01:38.278601 | instance | libyaml = True 2026-04-17 04:01:38.278607 | instance | No config file found; using defaults 2026-04-17 04:01:38.278614 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-04-17 04:01:38.278620 | instance | Found installed collection ansible.utils:6.0.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-04-17 04:01:38.278626 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-04-17 04:01:38.278632 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-04-17 04:01:38.278638 | instance | Found installed collection openstack.cloud:2.5.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-04-17 04:01:38.278644 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-04-17 04:01:38.278650 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-04-17 04:01:38.278655 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-04-17 04:01:38.278662 | instance | 2.17.12 2026-04-17 04:01:38.278668 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-04-17 04:01:38.278674 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-04-17 04:01:38.278691 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-04-17 04:01:38.278698 | instance | unexpected error when getting available versions of collection 2026-04-17 04:01:38.278713 | instance | vexxhost.containers: Unknown error when attempting to call Galaxy at 'https://g 2026-04-17 04:01:38.278719 | instance | alaxy.ansible.com/api/v3/collections/vexxhost/containers/versions/?limit=100': 2026-04-17 04:01:38.278725 | instance | . 2026-04-17 04:01:38.278769 | instance | ERROR! Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/vexxhost/containers/versions/?limit=100': . 2026-04-17 04:01:38.278781 | instance | 2026-04-17 04:01:38.423351 | instance | ERROR 2026-04-17 04:01:38.423724 | instance | { 2026-04-17 04:01:38.423791 | instance | "delta": "0:01:42.864267", 2026-04-17 04:01:38.423838 | instance | "end": "2026-04-17 04:01:38.358086", 2026-04-17 04:01:38.423880 | instance | "msg": "non-zero return code", 2026-04-17 04:01:38.423921 | instance | "rc": 1, 2026-04-17 04:01:38.423966 | instance | "start": "2026-04-17 03:59:55.493819" 2026-04-17 04:01:38.424008 | instance | } failure 2026-04-17 04:01:38.428358 | 2026-04-17 04:01:38.428454 | PLAY RECAP 2026-04-17 04:01:38.428541 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-17 04:01:38.428585 | 2026-04-17 04:01:38.535253 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-17 04:01:38.538095 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2024.1] 2026-04-17 04:01:39.159407 | 2026-04-17 04:01:39.159569 | PLAY [all] 2026-04-17 04:01:39.174698 | 2026-04-17 04:01:39.174811 | TASK [gather-host-logs : creating directory for system status] 2026-04-17 04:01:39.512462 | instance | changed 2026-04-17 04:01:39.520400 | 2026-04-17 04:01:39.520528 | TASK [gather-host-logs : Get logs for each host] 2026-04-17 04:01:39.873925 | instance | + systemd-cgls --full --all --no-pager 2026-04-17 04:01:39.885127 | instance | + ip addr 2026-04-17 04:01:39.886439 | instance | + ip route 2026-04-17 04:01:39.887547 | instance | + lsblk 2026-04-17 04:01:39.889472 | instance | + mount 2026-04-17 04:01:39.890774 | instance | + docker images 2026-04-17 04:01:39.891070 | instance | /bin/bash: line 7: docker: command not found 2026-04-17 04:01:39.891293 | instance | + brctl show 2026-04-17 04:01:39.891584 | instance | /bin/bash: line 8: brctl: command not found 2026-04-17 04:01:39.891761 | instance | + ps aux --sort=-%mem 2026-04-17 04:01:39.902736 | instance | + dpkg -l 2026-04-17 04:01:39.910674 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-17 04:01:39.911082 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-17 04:01:39.911143 | instance | /bin/bash: line 11: docker: command not found 2026-04-17 04:01:39.911470 | instance | + '[' '!' -z '' ']' 2026-04-17 04:01:40.055638 | instance | ok: Runtime: 0:00:00.041631 2026-04-17 04:01:40.062344 | 2026-04-17 04:01:40.062411 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-17 04:01:40.726437 | instance | changed: 2026-04-17 04:01:40.726623 | instance | created directory /var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/logs/instance 2026-04-17 04:01:40.726652 | instance | cd+++++++++ system/ 2026-04-17 04:01:40.726673 | instance | >f+++++++++ system/brctl-show.txt 2026-04-17 04:01:40.726694 | instance | >f+++++++++ system/docker-images.txt 2026-04-17 04:01:40.726713 | instance | >f+++++++++ system/ip-addr.txt 2026-04-17 04:01:40.726734 | instance | >f+++++++++ system/ip-route.txt 2026-04-17 04:01:40.726754 | instance | >f+++++++++ system/lsblk.txt 2026-04-17 04:01:40.726774 | instance | >f+++++++++ system/mount.txt 2026-04-17 04:01:40.726807 | instance | >f+++++++++ system/packages.txt 2026-04-17 04:01:40.726828 | instance | >f+++++++++ system/ps.txt 2026-04-17 04:01:40.726847 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-17 04:01:40.735730 | 2026-04-17 04:01:40.735796 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-17 04:01:40.933190 | instance | changed: "values" 2026-04-17 04:01:41.101661 | instance | changed: "releases" 2026-04-17 04:01:41.122952 | 2026-04-17 04:01:41.123116 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-17 04:01:41.335653 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-17 04:01:41.659477 | instance | ok: Runtime: 0:00:00.004649 2026-04-17 04:01:41.666337 | 2026-04-17 04:01:41.666423 | TASK [helm-release-status : Downloads logs to executor] 2026-04-17 04:01:42.159379 | instance | changed: 2026-04-17 04:01:42.159650 | instance | cd+++++++++ helm/ 2026-04-17 04:01:42.159714 | instance | cd+++++++++ helm/releases/ 2026-04-17 04:01:42.159764 | instance | cd+++++++++ helm/values/ 2026-04-17 04:01:42.171589 | 2026-04-17 04:01:42.171655 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-17 04:01:42.366646 | instance | changed 2026-04-17 04:01:42.373091 | 2026-04-17 04:01:42.373159 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-17 04:01:42.617895 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-17 04:01:42.618190 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-17 04:01:42.623208 | instance | environment: line 1: kubectl: command not found 2026-04-17 04:01:42.624064 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-17 04:01:42.624833 | instance | environment: line 1: kubectl: command not found 2026-04-17 04:01:42.626846 | instance | environment: line 1: kubectl: command not found 2026-04-17 04:01:42.627000 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-17 04:01:42.627740 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-17 04:01:42.630330 | instance | environment: line 1: kubectl: command not found 2026-04-17 04:01:42.632222 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-17 04:01:42.632370 | instance | environment: line 1: kubectl: command not found 2026-04-17 04:01:42.633852 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-17 04:01:42.904181 | instance | ok: Runtime: 0:00:00.026997 2026-04-17 04:01:42.909044 | 2026-04-17 04:01:42.909111 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-17 04:01:43.105280 | instance | changed 2026-04-17 04:01:43.125835 | 2026-04-17 04:01:43.125954 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-17 04:01:43.391500 | instance | environment: line 5: kubectl: command not found 2026-04-17 04:01:43.391915 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-17 04:01:43.392633 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-17 04:01:43.393011 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-17 04:01:43.704530 | instance | ok: Runtime: 0:00:00.010722 2026-04-17 04:01:43.711041 | 2026-04-17 04:01:43.711111 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-17 04:01:44.230167 | instance | changed: 2026-04-17 04:01:44.230412 | instance | cd+++++++++ objects/ 2026-04-17 04:01:44.230453 | instance | cd+++++++++ objects/cluster/ 2026-04-17 04:01:44.230484 | instance | cd+++++++++ objects/namespaced/ 2026-04-17 04:01:44.242188 | 2026-04-17 04:01:44.242265 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-17 04:01:44.488080 | instance | changed 2026-04-17 04:01:44.495655 | 2026-04-17 04:01:44.495745 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-17 04:01:44.696283 | instance | changed 2026-04-17 04:01:44.703644 | 2026-04-17 04:01:44.703750 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-17 04:01:44.909214 | instance | environment: line 3: kubectl: command not found 2026-04-17 04:01:45.240399 | instance | ok: Runtime: 0:00:00.007263 2026-04-17 04:01:45.246708 | 2026-04-17 04:01:45.246773 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-17 04:01:45.725098 | instance | changed: 2026-04-17 04:01:45.725329 | instance | cd+++++++++ pod-logs/ 2026-04-17 04:01:45.725367 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-17 04:01:45.737404 | 2026-04-17 04:01:45.737480 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-17 04:01:45.935705 | instance | changed 2026-04-17 04:01:45.942600 | 2026-04-17 04:01:45.942691 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-17 04:01:46.154913 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-17 04:01:46.482395 | instance | ok: Runtime: 0:00:00.032369 2026-04-17 04:01:46.487651 | 2026-04-17 04:01:46.487721 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-17 04:01:46.682895 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-17 04:01:46.707988 | instance | ceph-mgr endpoints: 2026-04-17 04:01:47.024384 | instance | ok: Runtime: 0:00:00.030943 2026-04-17 04:01:47.030400 | 2026-04-17 04:01:47.030526 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-17 04:01:47.236318 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-17 04:01:47.565352 | instance | ok: Runtime: 0:00:00.030969 2026-04-17 04:01:47.572565 | 2026-04-17 04:01:47.572663 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-17 04:01:48.085305 | instance | changed: cd+++++++++ prometheus/ 2026-04-17 04:01:48.098136 | 2026-04-17 04:01:48.098236 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-17 04:01:48.299288 | instance | changed 2026-04-17 04:01:48.305576 | 2026-04-17 04:01:48.305643 | TASK [gather-selenium-data : Get selenium data] 2026-04-17 04:01:48.508816 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-17 04:01:48.510463 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-17 04:01:48.840803 | instance | ERROR 2026-04-17 04:01:48.841077 | instance | { 2026-04-17 04:01:48.841123 | instance | "delta": "0:00:00.007562", 2026-04-17 04:01:48.841153 | instance | "end": "2026-04-17 04:01:48.510842", 2026-04-17 04:01:48.841179 | instance | "msg": "non-zero return code", 2026-04-17 04:01:48.841205 | instance | "rc": 1, 2026-04-17 04:01:48.841229 | instance | "start": "2026-04-17 04:01:48.503280" 2026-04-17 04:01:48.841253 | instance | } 2026-04-17 04:01:48.841285 | instance | ERROR: Ignoring Errors 2026-04-17 04:01:48.848070 | 2026-04-17 04:01:48.848155 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-17 04:01:49.347801 | instance | changed: cd+++++++++ selenium/ 2026-04-17 04:01:49.359407 | 2026-04-17 04:01:49.359591 | PLAY RECAP 2026-04-17 04:01:49.359683 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-17 04:01:49.359732 | 2026-04-17 04:01:49.548372 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2024.1] 2026-04-17 04:01:49.553826 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-17 04:01:50.184226 | 2026-04-17 04:01:50.184389 | PLAY [all] 2026-04-17 04:01:50.198156 | 2026-04-17 04:01:50.198302 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-17 04:01:50.244315 | instance | skipping: Conditional result was False 2026-04-17 04:01:50.255009 | 2026-04-17 04:01:50.255135 | TASK [fetch-output : Set log path for single node] 2026-04-17 04:01:50.300538 | instance | ok 2026-04-17 04:01:50.308633 | 2026-04-17 04:01:50.308737 | LOOP [fetch-output : Ensure local output dirs] 2026-04-17 04:01:50.708541 | instance -> localhost | ok: "/var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/logs" 2026-04-17 04:01:50.919167 | instance -> localhost | changed: "/var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/artifacts" 2026-04-17 04:01:51.167205 | instance -> localhost | changed: "/var/lib/zuul/builds/78b0b0b821274c3386f331779dcfe6bc/work/docs" 2026-04-17 04:01:51.188738 | 2026-04-17 04:01:51.188912 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-17 04:01:51.821055 | instance | changed: .d..t...... ./ 2026-04-17 04:01:51.821327 | instance | changed: All items complete 2026-04-17 04:01:51.821367 | 2026-04-17 04:01:52.271646 | instance | changed: .d..t...... ./ 2026-04-17 04:01:52.722685 | instance | changed: .d..t...... ./ 2026-04-17 04:01:52.741848 | 2026-04-17 04:01:52.742015 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-17 04:01:53.189351 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008863 2026-04-17 04:01:53.436978 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008409 2026-04-17 04:01:53.456646 | 2026-04-17 04:01:53.456806 | PLAY [all] 2026-04-17 04:01:53.463691 | 2026-04-17 04:01:53.463764 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-17 04:01:53.869863 | instance | changed 2026-04-17 04:01:53.875603 | 2026-04-17 04:01:53.875658 | PLAY RECAP 2026-04-17 04:01:53.875707 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-17 04:01:53.875728 | 2026-04-17 04:01:54.003912 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-17 04:01:54.006767 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-17 04:01:54.562587 | 2026-04-17 04:01:54.562716 | PLAY [localhost] 2026-04-17 04:01:54.572949 | 2026-04-17 04:01:54.573056 | TASK [Generate Zuul manifest] 2026-04-17 04:01:54.594489 | localhost | ok 2026-04-17 04:01:54.611549 | 2026-04-17 04:01:54.611627 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-17 04:01:54.988765 | localhost | changed 2026-04-17 04:01:55.003913 | 2026-04-17 04:01:55.004045 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-17 04:01:55.034979 | localhost | ok 2026-04-17 04:01:55.046573 | 2026-04-17 04:01:55.046711 | TASK [Upload logs] 2026-04-17 04:01:55.069190 | localhost | ok 2026-04-17 04:01:55.135613 | 2026-04-17 04:01:55.135800 | TASK [Set zuul-log-path fact] 2026-04-17 04:01:55.158912 | localhost | ok 2026-04-17 04:01:55.171732 | 2026-04-17 04:01:55.171839 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-17 04:01:55.203477 | localhost | ok 2026-04-17 04:01:55.212322 | 2026-04-17 04:01:55.212410 | TASK [upload-logs : Create log directories] 2026-04-17 04:01:55.619454 | localhost | changed 2026-04-17 04:01:55.627997 | 2026-04-17 04:01:55.628076 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-17 04:01:56.038768 | localhost -> localhost | ok: Runtime: 0:00:00.005236 2026-04-17 04:01:56.044219 | 2026-04-17 04:01:56.044297 | TASK [upload-logs : Upload logs to log server] 2026-04-17 04:01:56.521920 | localhost | Output suppressed because no_log was given 2026-04-17 04:01:56.526658 | 2026-04-17 04:01:56.526728 | LOOP [upload-logs : Compress console log and json output] 2026-04-17 04:01:56.571238 | localhost | skipping: Conditional result was False 2026-04-17 04:01:56.578305 | localhost | skipping: Conditional result was False 2026-04-17 04:01:56.593944 | 2026-04-17 04:01:56.594200 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-17 04:01:56.641856 | localhost | skipping: Conditional result was False 2026-04-17 04:01:56.642274 | 2026-04-17 04:01:56.646421 | localhost | skipping: Conditional result was False 2026-04-17 04:01:56.663042 | 2026-04-17 04:01:56.663278 | LOOP [upload-logs : Upload console log and json output]