2026-04-12 20:36:40.581219 | Job console starting 2026-04-12 20:36:40.589908 | Updating git repos 2026-04-12 20:36:40.638750 | Cloning repos into workspace 2026-04-12 20:36:44.864867 | Restoring repo states 2026-04-12 20:36:44.896732 | Merging changes 2026-04-12 20:36:46.346812 | Checking out repos 2026-04-12 20:36:46.893487 | Preparing playbooks 2026-04-12 20:37:20.821263 | Running Ansible setup 2026-04-12 20:37:26.093645 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-12 20:37:27.030169 | 2026-04-12 20:37:27.134882 | PLAY [localhost] 2026-04-12 20:37:27.153450 | 2026-04-12 20:37:27.153663 | TASK [Gathering Facts] 2026-04-12 20:37:28.393120 | localhost | ok 2026-04-12 20:37:28.406690 | 2026-04-12 20:37:28.406848 | TASK [Setup log path fact] 2026-04-12 20:37:28.430225 | localhost | ok 2026-04-12 20:37:28.546304 | 2026-04-12 20:37:28.546453 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-12 20:37:28.576198 | localhost | ok 2026-04-12 20:37:28.585346 | 2026-04-12 20:37:28.585440 | TASK [emit-job-header : Print job information] 2026-04-12 20:37:28.625884 | # Job Information 2026-04-12 20:37:28.721370 | Ansible Version: 2.16.16 2026-04-12 20:37:28.721536 | Job: atmosphere-molecule-aio-ovn 2026-04-12 20:37:28.721577 | Pipeline: check 2026-04-12 20:37:28.721608 | Executor: 0a8996d2b663 2026-04-12 20:37:28.721638 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3813 2026-04-12 20:37:28.721673 | Event ID: 3720a8e0-36af-11f1-88a0-e9ac3d590fe4 2026-04-12 20:37:28.726288 | 2026-04-12 20:37:28.726402 | LOOP [emit-job-header : Print node information] 2026-04-12 20:37:28.823030 | localhost | ok: 2026-04-12 20:37:28.823262 | localhost | # Node Information 2026-04-12 20:37:28.823311 | localhost | Inventory Hostname: instance 2026-04-12 20:37:28.823347 | localhost | Hostname: np0000165328 2026-04-12 20:37:28.823378 | localhost | Username: zuul 2026-04-12 20:37:28.823414 | localhost | Distro: Ubuntu 22.04 2026-04-12 20:37:28.823449 | localhost | Provider: yul1 2026-04-12 20:37:28.823480 | localhost | Region: ca-ymq-1 2026-04-12 20:37:28.823511 | localhost | Label: ubuntu-jammy-16 2026-04-12 20:37:28.823539 | localhost | Product Name: OpenStack Nova 2026-04-12 20:37:28.823570 | localhost | Interface IP: 162.253.55.43 2026-04-12 20:37:28.838786 | 2026-04-12 20:37:29.050343 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-12 20:37:29.618356 | localhost -> localhost | changed 2026-04-12 20:37:29.625073 | 2026-04-12 20:37:29.625237 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-12 20:37:31.343115 | localhost -> localhost | changed 2026-04-12 20:37:31.354798 | 2026-04-12 20:37:31.354940 | PLAY [all] 2026-04-12 20:37:31.367829 | 2026-04-12 20:37:31.367911 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-12 20:37:31.834645 | instance -> localhost | ok 2026-04-12 20:37:31.840659 | 2026-04-12 20:37:31.840771 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-12 20:37:31.881247 | instance | ok 2026-04-12 20:37:31.911305 | instance | included: /var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-12 20:37:31.916983 | 2026-04-12 20:37:31.917056 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-12 20:37:33.317255 | instance -> localhost | Generating public/private rsa key pair. 2026-04-12 20:37:33.317520 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/6c977dbaaedb4032b081eb2778df5602_id_rsa 2026-04-12 20:37:33.317560 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/6c977dbaaedb4032b081eb2778df5602_id_rsa.pub 2026-04-12 20:37:33.317590 | instance -> localhost | The key fingerprint is: 2026-04-12 20:37:33.317618 | instance -> localhost | SHA256:AhWFRiVUfBhRD2oxBtXa3kXt2MYn3EISRHlHKzsIvNw zuul-build-sshkey 2026-04-12 20:37:33.317666 | instance -> localhost | The key's randomart image is: 2026-04-12 20:37:33.317696 | instance -> localhost | +---[RSA 3072]----+ 2026-04-12 20:37:33.317735 | instance -> localhost | | oBX@*oo+o o.| 2026-04-12 20:37:33.317766 | instance -> localhost | | .oo++ooo + +| 2026-04-12 20:37:33.317793 | instance -> localhost | | .. o* .B O | 2026-04-12 20:37:33.317821 | instance -> localhost | | . .o = . O B| 2026-04-12 20:37:33.317917 | instance -> localhost | | . S+ E + +.| 2026-04-12 20:37:33.317963 | instance -> localhost | | . . . . | 2026-04-12 20:37:33.317992 | instance -> localhost | | | 2026-04-12 20:37:33.318021 | instance -> localhost | | | 2026-04-12 20:37:33.318051 | instance -> localhost | | | 2026-04-12 20:37:33.318077 | instance -> localhost | +----[SHA256]-----+ 2026-04-12 20:37:33.318170 | instance -> localhost | ok: Runtime: 0:00:00.674449 2026-04-12 20:37:33.328176 | 2026-04-12 20:37:33.328383 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-12 20:37:33.361483 | instance | ok 2026-04-12 20:37:33.523271 | instance | included: /var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-12 20:37:33.538095 | 2026-04-12 20:37:33.538261 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-12 20:37:33.563399 | instance | skipping: Conditional result was False 2026-04-12 20:37:33.572634 | 2026-04-12 20:37:33.572794 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-12 20:37:34.120126 | instance | changed 2026-04-12 20:37:34.171975 | 2026-04-12 20:37:34.172159 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-12 20:37:34.379966 | instance | ok 2026-04-12 20:37:34.483344 | 2026-04-12 20:37:34.483517 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-12 20:37:34.961435 | instance | changed 2026-04-12 20:37:34.969349 | 2026-04-12 20:37:34.969545 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-12 20:37:35.477204 | instance | changed 2026-04-12 20:37:35.642613 | 2026-04-12 20:37:35.642781 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-12 20:37:35.657989 | instance | skipping: Conditional result was False 2026-04-12 20:37:35.667405 | 2026-04-12 20:37:35.667616 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-12 20:37:36.475985 | instance -> localhost | changed 2026-04-12 20:37:36.568703 | 2026-04-12 20:37:36.569254 | TASK [add-build-sshkey : Add back temp key] 2026-04-12 20:37:37.125736 | instance -> localhost | Identity added: /var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/6c977dbaaedb4032b081eb2778df5602_id_rsa (zuul-build-sshkey) 2026-04-12 20:37:37.125915 | instance -> localhost | ok: Runtime: 0:00:00.015140 2026-04-12 20:37:37.130893 | 2026-04-12 20:37:37.130963 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-12 20:37:37.404528 | instance | ok 2026-04-12 20:37:37.439396 | 2026-04-12 20:37:37.439547 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-12 20:37:37.464384 | instance | skipping: Conditional result was False 2026-04-12 20:37:37.476395 | 2026-04-12 20:37:37.476533 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-12 20:37:37.776454 | instance | ok 2026-04-12 20:37:37.785023 | 2026-04-12 20:37:37.785223 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-12 20:37:39.857952 | instance | Output suppressed because no_log was given 2026-04-12 20:37:39.873854 | 2026-04-12 20:37:40.046533 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-12 20:37:40.225487 | instance | ok: "logs" 2026-04-12 20:37:40.225717 | instance | ok: All items complete 2026-04-12 20:37:40.225746 | 2026-04-12 20:37:40.370327 | instance | ok: "artifacts" 2026-04-12 20:37:40.521714 | instance | ok: "docs" 2026-04-12 20:37:40.599613 | 2026-04-12 20:37:40.599715 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-12 20:37:40.774489 | instance | changed: "logs" 2026-04-12 20:37:40.947426 | instance | changed: "artifacts" 2026-04-12 20:37:41.117194 | instance | changed: "docs" 2026-04-12 20:37:41.128947 | 2026-04-12 20:37:41.129101 | PLAY RECAP 2026-04-12 20:37:41.129157 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-12 20:37:41.129188 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-12 20:37:41.129212 | 2026-04-12 20:37:41.366781 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-12 20:37:41.375538 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-12 20:37:42.371220 | 2026-04-12 20:37:42.448170 | PLAY [all] 2026-04-12 20:37:42.480004 | 2026-04-12 20:37:42.480212 | TASK [setup-uv : Extract archive] 2026-04-12 20:37:45.314813 | instance | changed 2026-04-12 20:37:45.321470 | 2026-04-12 20:37:45.321632 | TASK [setup-uv : Print version] 2026-04-12 20:37:45.766753 | instance | uv 0.8.13 2026-04-12 20:37:45.873289 | instance | ok: Runtime: 0:00:00.013186 2026-04-12 20:37:45.880190 | 2026-04-12 20:37:45.880278 | PLAY RECAP 2026-04-12 20:37:45.880331 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-12 20:37:45.880383 | 2026-04-12 20:37:46.212897 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-12 20:37:46.367457 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.2] 2026-04-12 20:37:47.221748 | 2026-04-12 20:37:47.221939 | PLAY [all] 2026-04-12 20:37:47.246897 | 2026-04-12 20:37:47.247070 | TASK [Install "jq" for log collection] 2026-04-12 20:37:58.884661 | instance | changed 2026-04-12 20:37:58.886554 | 2026-04-12 20:37:58.886611 | PLAY RECAP 2026-04-12 20:37:58.886664 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-12 20:37:58.886718 | 2026-04-12 20:37:59.020058 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.2] 2026-04-12 20:37:59.029987 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-12 20:37:59.767027 | 2026-04-12 20:37:59.767216 | PLAY [all] 2026-04-12 20:37:59.780314 | 2026-04-12 20:37:59.780455 | TASK [Copy inventory file for Zuul] 2026-04-12 20:38:00.697338 | instance | changed 2026-04-12 20:38:00.705494 | 2026-04-12 20:38:00.705604 | TASK [Switch "ansible_host" to private IP] 2026-04-12 20:38:01.073060 | instance | changed: 1 replacements made 2026-04-12 20:38:01.084243 | 2026-04-12 20:38:01.084341 | TASK [Run Molecule scenario] 2026-04-12 20:38:01.560216 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-04-12 20:38:01.560334 | instance | Creating virtual environment at: .venv 2026-04-12 20:38:01.583591 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-12 20:38:01.611898 | instance | Downloading netaddr (2.2MiB) 2026-04-12 20:38:01.612253 | instance | Downloading cryptography (4.2MiB) 2026-04-12 20:38:01.612473 | instance | Downloading rjsonnet (1.2MiB) 2026-04-12 20:38:01.612678 | instance | Downloading setuptools (1.1MiB) 2026-04-12 20:38:01.612833 | instance | Downloading kubernetes (1.9MiB) 2026-04-12 20:38:01.613027 | instance | Downloading ansible-core (2.1MiB) 2026-04-12 20:38:01.614042 | instance | Downloading openstacksdk (1.7MiB) 2026-04-12 20:38:01.619576 | instance | Downloading pygments (1.2MiB) 2026-04-12 20:38:01.857951 | instance | Building pyperclip==1.9.0 2026-04-12 20:38:01.917624 | instance | Downloading rjsonnet 2026-04-12 20:38:02.038373 | instance | Downloading netaddr 2026-04-12 20:38:02.041600 | instance | Downloading pygments 2026-04-12 20:38:02.061937 | instance | Downloading cryptography 2026-04-12 20:38:02.095910 | instance | Downloading setuptools 2026-04-12 20:38:02.157374 | instance | Downloading kubernetes 2026-04-12 20:38:02.192020 | instance | Downloading ansible-core 2026-04-12 20:38:02.227531 | instance | Downloading openstacksdk 2026-04-12 20:38:02.639954 | instance | Built pyperclip==1.9.0 2026-04-12 20:38:02.866925 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-12 20:38:02.907223 | instance | Installed 79 packages in 38ms 2026-04-12 20:38:03.561490 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-04-12 20:38:04.169827 | instance | INFO [aio > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-04-12 20:38:04.169932 | instance | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-04-12 20:38:09.940394 | instance | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv ansible.netcommon:1.2.0 2026-04-12 20:38:09.940501 | instance | ERROR Command ansible-galaxy collection install -vvv ansible.netcommon:1.2.0, returned 250 code: 2026-04-12 20:38:09.940514 | instance | Starting galaxy collection install process 2026-04-12 20:38:09.940525 | instance | Process install dependency map 2026-04-12 20:38:09.940534 | instance | the full traceback was: 2026-04-12 20:38:09.940544 | instance | 2026-04-12 20:38:09.940553 | instance | Traceback (most recent call last): 2026-04-12 20:38:09.940563 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor 2026-04-12 20:38:09.940573 | instance | exit_code = cli.run() 2026-04-12 20:38:09.940582 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-04-12 20:38:09.940591 | instance | return context.CLIARGS() 2026-04-12 20:38:09.940600 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper 2026-04-12 20:38:09.940609 | instance | return wrapped_method(*args, **kwargs) 2026-04-12 20:38:09.940618 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install 2026-04-12 20:38:09.940627 | instance | self._execute_install_collection( 2026-04-12 20:38:09.940637 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection 2026-04-12 20:38:09.940647 | instance | install_collections( 2026-04-12 20:38:09.940656 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections 2026-04-12 20:38:09.940665 | instance | dependency_map = _resolve_depenency_map( 2026-04-12 20:38:09.940674 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map 2026-04-12 20:38:09.940684 | instance | return collection_dep_resolver.resolve( 2026-04-12 20:38:09.940693 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-04-12 20:38:09.940736 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-04-12 20:38:09.940746 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-04-12 20:38:09.940755 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-04-12 20:38:09.940764 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria 2026-04-12 20:38:09.940773 | instance | matches = self._p.find_matches( 2026-04-12 20:38:09.940782 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches 2026-04-12 20:38:09.940791 | instance | match for match in self._find_matches(list(requirements)) 2026-04-12 20:38:09.940800 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches 2026-04-12 20:38:09.940810 | instance | coll_versions = if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable] 2026-04-12 20:38:09.940819 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions 2026-04-12 20:38:09.940828 | instance | return set( 2026-04-12 20:38:09.940837 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in 2026-04-12 20:38:09.940846 | instance | return set( 2026-04-12 20:38:09.940854 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions 2026-04-12 20:38:09.940863 | instance | raise last_error 2026-04-12 20:38:09.940873 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions 2026-04-12 20:38:09.940882 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-04-12 20:38:09.940891 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-04-12 20:38:09.940900 | instance | return method(self, *args, **kwargs) 2026-04-12 20:38:09.940909 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions 2026-04-12 20:38:09.940918 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-04-12 20:38:09.940939 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function 2026-04-12 20:38:09.940948 | instance | return call_retryable_function() 2026-04-12 20:38:09.940967 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy 2026-04-12 20:38:09.940976 | instance | res = path_cache 2026-04-12 20:38:09.940985 | instance | KeyError: 'results' 2026-04-12 20:38:09.940994 | instance | 2026-04-12 20:38:09.941003 | instance | ansible-galaxy 2026-04-12 20:38:09.941012 | instance | config file = None 2026-04-12 20:38:09.941020 | instance | configured module search path = 2026-04-12 20:38:09.941029 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-04-12 20:38:09.941040 | 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-12 20:38:09.941057 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-04-12 20:38:09.941094 | 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-12 20:38:09.941104 | instance | jinja version = 3.1.6 2026-04-12 20:38:09.941113 | instance | libyaml = True 2026-04-12 20:38:09.941122 | instance | No config file found; using defaults 2026-04-12 20:38:09.941131 | instance | Created /home/zuul/.ansible/galaxy_token 2026-04-12 20:38:09.941140 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-04-12 20:38:09.941149 | instance | unexpected error when getting available versions of collection 2026-04-12 20:38:09.941158 | instance | ansible.netcommon: 'results' 2026-04-12 20:38:09.941167 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-04-12 20:38:09.941177 | instance | 2026-04-12 20:38:09.941388 | instance | Traceback (most recent call last): 2026-04-12 20:38:09.941719 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-04-12 20:38:09.942177 | instance | sys.exit(main()) 2026-04-12 20:38:09.942474 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-04-12 20:38:09.943147 | instance | return self.main(*args, **kwargs) 2026-04-12 20:38:09.943406 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-04-12 20:38:09.944053 | instance | rv = self.invoke(ctx) 2026-04-12 20:38:09.944431 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-04-12 20:38:09.945173 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-04-12 20:38:09.945505 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-04-12 20:38:09.946116 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-04-12 20:38:09.946339 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-04-12 20:38:09.946608 | instance | return callback(*args, **kwargs) 2026-04-12 20:38:09.946783 | 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-12 20:38:09.947095 | instance | return func(*args, **kwargs) 2026-04-12 20:38:09.947259 | 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-12 20:38:09.947483 | instance | return f(get_current_context(), *args, **kwargs) 2026-04-12 20:38:09.947659 | 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-12 20:38:09.947931 | instance | return func(ctx) 2026-04-12 20:38:09.948106 | 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-12 20:38:09.948375 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-04-12 20:38:09.948544 | 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-12 20:38:09.948793 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-04-12 20:38:09.948967 | 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-12 20:38:09.949269 | instance | scenario.config.runtime.prepare_environment( 2026-04-12 20:38:09.949452 | 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-12 20:38:09.949778 | instance | self.install_collection( 2026-04-12 20:38:09.949974 | 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-12 20:38:09.950251 | instance | raise InvalidPrerequisiteError(msg) 2026-04-12 20:38:09.952867 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv ansible.netcommon:1.2.0, returned 250 code: 2026-04-12 20:38:09.952901 | instance | Starting galaxy collection install process 2026-04-12 20:38:09.952913 | instance | Process install dependency map 2026-04-12 20:38:09.952922 | instance | the full traceback was: 2026-04-12 20:38:09.952931 | instance | 2026-04-12 20:38:09.952940 | instance | Traceback (most recent call last): 2026-04-12 20:38:09.952949 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor 2026-04-12 20:38:09.952958 | instance | exit_code = cli.run() 2026-04-12 20:38:09.952967 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-04-12 20:38:09.952976 | instance | return context.CLIARGS['func']() 2026-04-12 20:38:09.952985 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper 2026-04-12 20:38:09.952993 | instance | return wrapped_method(*args, **kwargs) 2026-04-12 20:38:09.953003 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install 2026-04-12 20:38:09.953012 | instance | self._execute_install_collection( 2026-04-12 20:38:09.953021 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection 2026-04-12 20:38:09.953030 | instance | install_collections( 2026-04-12 20:38:09.953039 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections 2026-04-12 20:38:09.953048 | instance | dependency_map = _resolve_depenency_map( 2026-04-12 20:38:09.953057 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map 2026-04-12 20:38:09.953066 | instance | return collection_dep_resolver.resolve( 2026-04-12 20:38:09.953075 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-04-12 20:38:09.953084 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-04-12 20:38:09.953117 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-04-12 20:38:09.953126 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-04-12 20:38:09.953135 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria 2026-04-12 20:38:09.953144 | instance | matches = self._p.find_matches( 2026-04-12 20:38:09.953154 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches 2026-04-12 20:38:09.953164 | instance | match for match in self._find_matches(list(requirements[identifier])) 2026-04-12 20:38:09.953173 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches 2026-04-12 20:38:09.953182 | instance | coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable[t.Tuple[str, GalaxyAPI]] 2026-04-12 20:38:09.953192 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions 2026-04-12 20:38:09.953201 | instance | return set( 2026-04-12 20:38:09.953219 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in 2026-04-12 20:38:09.953229 | instance | return set( 2026-04-12 20:38:09.953238 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions 2026-04-12 20:38:09.953246 | instance | raise last_error 2026-04-12 20:38:09.953255 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions 2026-04-12 20:38:09.953264 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-04-12 20:38:09.953273 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-04-12 20:38:09.953282 | instance | return method(self, *args, **kwargs) 2026-04-12 20:38:09.953297 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions 2026-04-12 20:38:09.953306 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-04-12 20:38:09.953324 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function 2026-04-12 20:38:09.953334 | instance | return call_retryable_function() 2026-04-12 20:38:09.953343 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy 2026-04-12 20:38:09.953352 | instance | res = path_cache['results'] 2026-04-12 20:38:09.953361 | instance | KeyError: 'results' 2026-04-12 20:38:09.953369 | instance | 2026-04-12 20:38:09.953378 | instance | ansible-galaxy [core 2.17.12] 2026-04-12 20:38:09.953387 | instance | config file = None 2026-04-12 20:38:09.953396 | 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-12 20:38:09.953405 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-04-12 20:38:09.953415 | 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-12 20:38:09.953425 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-04-12 20:38:09.953434 | 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-12 20:38:09.953443 | instance | jinja version = 3.1.6 2026-04-12 20:38:09.953452 | instance | libyaml = True 2026-04-12 20:38:09.953460 | instance | No config file found; using defaults 2026-04-12 20:38:09.953469 | instance | Created /home/zuul/.ansible/galaxy_token 2026-04-12 20:38:09.953478 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-04-12 20:38:09.953487 | instance | unexpected error when getting available versions of collection 2026-04-12 20:38:09.953496 | instance | ansible.netcommon: 'results' 2026-04-12 20:38:09.953505 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-04-12 20:38:09.953514 | instance | 2026-04-12 20:38:10.180771 | instance | ERROR 2026-04-12 20:38:10.181094 | instance | { 2026-04-12 20:38:10.181141 | instance | "delta": "0:00:08.552084", 2026-04-12 20:38:10.181172 | instance | "end": "2026-04-12 20:38:10.046371", 2026-04-12 20:38:10.181201 | instance | "msg": "non-zero return code", 2026-04-12 20:38:10.181228 | instance | "rc": 1, 2026-04-12 20:38:10.181288 | instance | "start": "2026-04-12 20:38:01.494287" 2026-04-12 20:38:10.181320 | instance | } failure 2026-04-12 20:38:10.186216 | 2026-04-12 20:38:10.186359 | PLAY RECAP 2026-04-12 20:38:10.186425 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-12 20:38:10.186452 | 2026-04-12 20:38:10.415473 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-12 20:38:10.541934 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.2] 2026-04-12 20:38:11.207711 | 2026-04-12 20:38:11.207924 | PLAY [all] 2026-04-12 20:38:11.225277 | 2026-04-12 20:38:11.225439 | TASK [gather-host-logs : creating directory for system status] 2026-04-12 20:38:11.597869 | instance | changed 2026-04-12 20:38:11.603711 | 2026-04-12 20:38:11.603832 | TASK [gather-host-logs : Get logs for each host] 2026-04-12 20:38:11.968839 | instance | + systemd-cgls --full --all --no-pager 2026-04-12 20:38:11.981967 | instance | + ip addr 2026-04-12 20:38:11.983726 | instance | + ip route 2026-04-12 20:38:11.985201 | instance | + lsblk 2026-04-12 20:38:11.987851 | instance | + mount 2026-04-12 20:38:11.989140 | instance | + docker images 2026-04-12 20:38:11.989596 | instance | /bin/bash: line 7: docker: command not found 2026-04-12 20:38:11.989728 | instance | + brctl show 2026-04-12 20:38:11.990091 | instance | /bin/bash: line 8: brctl: command not found 2026-04-12 20:38:11.990290 | instance | + ps aux --sort=-%mem 2026-04-12 20:38:12.002805 | instance | + dpkg -l 2026-04-12 20:38:12.009037 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-12 20:38:12.009415 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-12 20:38:12.009433 | instance | /bin/bash: line 11: docker: command not found 2026-04-12 20:38:12.009791 | instance | + '[' '!' -z '' ']' 2026-04-12 20:38:12.146113 | instance | ok: Runtime: 0:00:00.047766 2026-04-12 20:38:12.152879 | 2026-04-12 20:38:12.152985 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-12 20:38:12.862810 | instance | changed: 2026-04-12 20:38:12.862977 | instance | created directory /var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/logs/instance 2026-04-12 20:38:12.863006 | instance | cd+++++++++ system/ 2026-04-12 20:38:12.863028 | instance | >f+++++++++ system/brctl-show.txt 2026-04-12 20:38:12.863083 | instance | >f+++++++++ system/docker-images.txt 2026-04-12 20:38:12.863107 | instance | >f+++++++++ system/ip-addr.txt 2026-04-12 20:38:12.863131 | instance | >f+++++++++ system/ip-route.txt 2026-04-12 20:38:12.863153 | instance | >f+++++++++ system/lsblk.txt 2026-04-12 20:38:12.863174 | instance | >f+++++++++ system/mount.txt 2026-04-12 20:38:12.863197 | instance | >f+++++++++ system/packages.txt 2026-04-12 20:38:12.863218 | instance | >f+++++++++ system/ps.txt 2026-04-12 20:38:12.863239 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-12 20:38:12.872603 | 2026-04-12 20:38:12.872706 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-12 20:38:13.078106 | instance | changed: "values" 2026-04-12 20:38:13.238405 | instance | changed: "releases" 2026-04-12 20:38:13.250999 | 2026-04-12 20:38:13.251173 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-12 20:38:13.461069 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-12 20:38:13.788108 | instance | ok: Runtime: 0:00:00.005499 2026-04-12 20:38:13.794493 | 2026-04-12 20:38:13.794608 | TASK [helm-release-status : Downloads logs to executor] 2026-04-12 20:38:14.311787 | instance | changed: 2026-04-12 20:38:14.311939 | instance | cd+++++++++ helm/ 2026-04-12 20:38:14.311966 | instance | cd+++++++++ helm/releases/ 2026-04-12 20:38:14.311987 | instance | cd+++++++++ helm/values/ 2026-04-12 20:38:14.320820 | 2026-04-12 20:38:14.320888 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-12 20:38:14.536809 | instance | changed 2026-04-12 20:38:14.603081 | 2026-04-12 20:38:14.603272 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-12 20:38:14.823824 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-12 20:38:14.823996 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-12 20:38:14.829993 | instance | environment: line 1: kubectl: command not found 2026-04-12 20:38:14.832065 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-12 20:38:14.833098 | instance | environment: line 1: kubectl: command not found 2026-04-12 20:38:14.834666 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-12 20:38:14.836190 | instance | environment: line 1: kubectl: command not found 2026-04-12 20:38:14.836975 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-12 20:38:14.838698 | instance | environment: line 1: kubectl: command not found 2026-04-12 20:38:14.839553 | instance | environment: line 1: kubectl: command not found 2026-04-12 20:38:14.840035 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-12 20:38:14.841010 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-12 20:38:15.146432 | instance | ok: Runtime: 0:00:00.027266 2026-04-12 20:38:15.152448 | 2026-04-12 20:38:15.152569 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-12 20:38:15.350842 | instance | changed 2026-04-12 20:38:15.356925 | 2026-04-12 20:38:15.357360 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-12 20:38:15.573924 | instance | environment: line 5: kubectl: command not found 2026-04-12 20:38:15.574864 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args valuewarning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-12 20:38:15.574883 | instance | 2026-04-12 20:38:15.575324 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-12 20:38:15.893472 | instance | ok: Runtime: 0:00:00.009762 2026-04-12 20:38:15.901293 | 2026-04-12 20:38:15.901380 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-12 20:38:16.431410 | instance | changed: 2026-04-12 20:38:16.431603 | instance | cd+++++++++ objects/ 2026-04-12 20:38:16.431739 | instance | cd+++++++++ objects/cluster/ 2026-04-12 20:38:16.431788 | instance | cd+++++++++ objects/namespaced/ 2026-04-12 20:38:16.443705 | 2026-04-12 20:38:16.443852 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-12 20:38:16.640429 | instance | changed 2026-04-12 20:38:16.647703 | 2026-04-12 20:38:16.647801 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-12 20:38:16.851882 | instance | changed 2026-04-12 20:38:16.858587 | 2026-04-12 20:38:16.858664 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-12 20:38:17.082526 | instance | environment: line 3: kubectl: command not found 2026-04-12 20:38:17.402826 | instance | ok: Runtime: 0:00:00.009678 2026-04-12 20:38:17.408334 | 2026-04-12 20:38:17.408439 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-12 20:38:17.910576 | instance | changed: 2026-04-12 20:38:17.910837 | instance | cd+++++++++ pod-logs/ 2026-04-12 20:38:17.910882 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-12 20:38:17.928349 | 2026-04-12 20:38:17.928723 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-12 20:38:18.147219 | instance | changed 2026-04-12 20:38:18.155067 | 2026-04-12 20:38:18.155150 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-12 20:38:18.383464 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-12 20:38:18.694622 | instance | ok: Runtime: 0:00:00.037834 2026-04-12 20:38:18.701468 | 2026-04-12 20:38:18.701557 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-12 20:38:18.907926 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-12 20:38:18.935773 | instance | ceph-mgr endpoints: 2026-04-12 20:38:19.242665 | instance | ok: Runtime: 0:00:00.034733 2026-04-12 20:38:19.253130 | 2026-04-12 20:38:19.253329 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-12 20:38:19.481110 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-12 20:38:19.800350 | instance | ok: Runtime: 0:00:00.034224 2026-04-12 20:38:19.806774 | 2026-04-12 20:38:19.806839 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-12 20:38:20.356489 | instance | changed: cd+++++++++ prometheus/ 2026-04-12 20:38:20.370720 | 2026-04-12 20:38:20.370848 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-12 20:38:20.614099 | instance | changed 2026-04-12 20:38:20.621002 | 2026-04-12 20:38:20.621076 | TASK [gather-selenium-data : Get selenium data] 2026-04-12 20:38:20.834419 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-12 20:38:20.835959 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-12 20:38:21.166828 | instance | ERROR 2026-04-12 20:38:21.167134 | instance | { 2026-04-12 20:38:21.167190 | instance | "delta": "0:00:00.006764", 2026-04-12 20:38:21.167226 | instance | "end": "2026-04-12 20:38:20.836342", 2026-04-12 20:38:21.167257 | instance | "msg": "non-zero return code", 2026-04-12 20:38:21.167287 | instance | "rc": 1, 2026-04-12 20:38:21.167316 | instance | "start": "2026-04-12 20:38:20.829578" 2026-04-12 20:38:21.167345 | instance | } 2026-04-12 20:38:21.167384 | instance | ERROR: Ignoring Errors 2026-04-12 20:38:21.175164 | 2026-04-12 20:38:21.175253 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-12 20:38:21.677037 | instance | changed: cd+++++++++ selenium/ 2026-04-12 20:38:21.684853 | 2026-04-12 20:38:21.684936 | PLAY RECAP 2026-04-12 20:38:21.685007 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-12 20:38:21.685032 | 2026-04-12 20:38:21.853704 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.2] 2026-04-12 20:38:21.869312 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-12 20:38:22.475474 | 2026-04-12 20:38:22.475588 | PLAY [all] 2026-04-12 20:38:22.486706 | 2026-04-12 20:38:22.486783 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-12 20:38:22.541690 | instance | skipping: Conditional result was False 2026-04-12 20:38:22.553212 | 2026-04-12 20:38:22.553309 | TASK [fetch-output : Set log path for single node] 2026-04-12 20:38:22.598398 | instance | ok 2026-04-12 20:38:22.603643 | 2026-04-12 20:38:22.603712 | LOOP [fetch-output : Ensure local output dirs] 2026-04-12 20:38:23.002772 | instance -> localhost | ok: "/var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/logs" 2026-04-12 20:38:23.280563 | instance -> localhost | changed: "/var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/artifacts" 2026-04-12 20:38:23.505769 | instance -> localhost | changed: "/var/lib/zuul/builds/6c977dbaaedb4032b081eb2778df5602/work/docs" 2026-04-12 20:38:23.522577 | 2026-04-12 20:38:23.522779 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-12 20:38:24.252671 | instance | changed: .d..t...... ./ 2026-04-12 20:38:24.253138 | instance | changed: All items complete 2026-04-12 20:38:24.253194 | 2026-04-12 20:38:24.707869 | instance | changed: .d..t...... ./ 2026-04-12 20:38:25.220267 | instance | changed: .d..t...... ./ 2026-04-12 20:38:25.245324 | 2026-04-12 20:38:25.246020 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-12 20:38:25.669126 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008997 2026-04-12 20:38:25.942553 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.009356 2026-04-12 20:38:25.964180 | 2026-04-12 20:38:25.964321 | PLAY [all] 2026-04-12 20:38:25.971554 | 2026-04-12 20:38:25.971653 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-12 20:38:26.411415 | instance | changed 2026-04-12 20:38:26.419463 | 2026-04-12 20:38:26.419539 | PLAY RECAP 2026-04-12 20:38:26.419601 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-12 20:38:26.419634 | 2026-04-12 20:38:26.548335 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-12 20:38:26.560501 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-12 20:38:27.182290 | 2026-04-12 20:38:27.182410 | PLAY [localhost] 2026-04-12 20:38:27.192052 | 2026-04-12 20:38:27.192122 | TASK [Generate Zuul manifest] 2026-04-12 20:38:27.209068 | localhost | ok 2026-04-12 20:38:27.222658 | 2026-04-12 20:38:27.222764 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-12 20:38:27.622821 | localhost | changed 2026-04-12 20:38:27.639027 | 2026-04-12 20:38:27.639283 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-12 20:38:27.674341 | localhost | ok 2026-04-12 20:38:27.682013 | 2026-04-12 20:38:27.682221 | TASK [Upload logs] 2026-04-12 20:38:27.705529 | localhost | ok 2026-04-12 20:38:27.856032 | 2026-04-12 20:38:27.856231 | TASK [Set zuul-log-path fact] 2026-04-12 20:38:27.880687 | localhost | ok 2026-04-12 20:38:27.894107 | 2026-04-12 20:38:27.894318 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-12 20:38:27.927556 | localhost | ok 2026-04-12 20:38:27.939329 | 2026-04-12 20:38:27.939480 | TASK [upload-logs : Create log directories] 2026-04-12 20:38:28.318237 | localhost | changed 2026-04-12 20:38:28.325491 | 2026-04-12 20:38:28.325606 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-12 20:38:28.673777 | localhost -> localhost | ok: Runtime: 0:00:00.004903 2026-04-12 20:38:28.681470 | 2026-04-12 20:38:28.681620 | TASK [upload-logs : Upload logs to log server] 2026-04-12 20:38:29.118511 | localhost | Output suppressed because no_log was given 2026-04-12 20:38:29.123854 | 2026-04-12 20:38:29.123982 | LOOP [upload-logs : Compress console log and json output] 2026-04-12 20:38:29.170131 | localhost | skipping: Conditional result was False 2026-04-12 20:38:29.177908 | localhost | skipping: Conditional result was False 2026-04-12 20:38:29.187066 | 2026-04-12 20:38:29.187235 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-12 20:38:29.230519 | localhost | skipping: Conditional result was False 2026-04-12 20:38:29.231089 | 2026-04-12 20:38:29.234307 | localhost | skipping: Conditional result was False 2026-04-12 20:38:29.248883 | 2026-04-12 20:38:29.249099 | LOOP [upload-logs : Upload console log and json output]