2026-02-04 10:50:56.536811 | Job console starting 2026-02-04 10:50:56.546118 | Updating git repos 2026-02-04 10:50:56.603425 | Cloning repos into workspace 2026-02-04 10:50:56.675801 | Restoring repo states 2026-02-04 10:50:56.693427 | Merging changes 2026-02-04 10:50:57.288330 | Checking out repos 2026-02-04 10:50:57.409863 | Preparing playbooks 2026-02-04 10:50:59.420143 | Running Ansible setup 2026-02-04 10:51:02.807756 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-04 10:51:03.408993 | 2026-02-04 10:51:03.409455 | PLAY [localhost] 2026-02-04 10:51:03.417247 | 2026-02-04 10:51:03.417315 | TASK [Gathering Facts] 2026-02-04 10:51:04.287144 | localhost | ok 2026-02-04 10:51:04.298193 | 2026-02-04 10:51:04.298302 | TASK [Setup log path fact] 2026-02-04 10:51:04.321251 | localhost | ok 2026-02-04 10:51:04.332732 | 2026-02-04 10:51:04.332803 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-04 10:51:04.363788 | localhost | ok 2026-02-04 10:51:04.371811 | 2026-02-04 10:51:04.371906 | TASK [emit-job-header : Print job information] 2026-02-04 10:51:04.414141 | # Job Information 2026-02-04 10:51:04.414370 | Ansible Version: 2.16.15 2026-02-04 10:51:04.414440 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-02-04 10:51:04.414492 | Pipeline: check 2026-02-04 10:51:04.414538 | Executor: 3a2793d2bd32 2026-02-04 10:51:04.414581 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-02-04 10:51:04.414630 | Event ID: 4ce958c0-01b7-11f1-9081-d3e9eb3e2f45 2026-02-04 10:51:04.420394 | 2026-02-04 10:51:04.420528 | LOOP [emit-job-header : Print node information] 2026-02-04 10:51:04.518191 | localhost | ok: 2026-02-04 10:51:04.518618 | localhost | # Node Information 2026-02-04 10:51:04.518700 | localhost | Inventory Hostname: instance 2026-02-04 10:51:04.518753 | localhost | Hostname: np0000154839 2026-02-04 10:51:04.518798 | localhost | Username: zuul 2026-02-04 10:51:04.518848 | localhost | Distro: Ubuntu 22.04 2026-02-04 10:51:04.518893 | localhost | Provider: yul1 2026-02-04 10:51:04.518934 | localhost | Region: ca-ymq-1 2026-02-04 10:51:04.518975 | localhost | Label: ubuntu-jammy 2026-02-04 10:51:04.519015 | localhost | Product Name: OpenStack Nova 2026-02-04 10:51:04.519056 | localhost | Interface IP: 162.253.55.84 2026-02-04 10:51:04.538760 | 2026-02-04 10:51:04.538901 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-04 10:51:04.928457 | localhost -> localhost | changed 2026-02-04 10:51:04.938505 | 2026-02-04 10:51:04.938650 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-04 10:51:05.897973 | localhost -> localhost | changed 2026-02-04 10:51:05.905692 | 2026-02-04 10:51:05.905770 | PLAY [all] 2026-02-04 10:51:05.913726 | 2026-02-04 10:51:05.913796 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-04 10:51:06.190906 | instance -> localhost | ok 2026-02-04 10:51:06.202184 | 2026-02-04 10:51:06.202258 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-04 10:51:06.235422 | instance | ok 2026-02-04 10:51:06.252334 | instance | included: /var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-04 10:51:06.257861 | 2026-02-04 10:51:06.257923 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-04 10:51:07.299492 | instance -> localhost | Generating public/private rsa key pair. 2026-02-04 10:51:07.299707 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/60caa93149244d8c9a2edddba7c24249_id_rsa 2026-02-04 10:51:07.299738 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/60caa93149244d8c9a2edddba7c24249_id_rsa.pub 2026-02-04 10:51:07.299761 | instance -> localhost | The key fingerprint is: 2026-02-04 10:51:07.299781 | instance -> localhost | SHA256:xQFGfti65y58yEdopyD2YDVlI5idBej+rFyLLVDIPaE zuul-build-sshkey 2026-02-04 10:51:07.299816 | instance -> localhost | The key's randomart image is: 2026-02-04 10:51:07.299837 | instance -> localhost | +---[RSA 3072]----+ 2026-02-04 10:51:07.299862 | instance -> localhost | | =.==.. | 2026-02-04 10:51:07.299886 | instance -> localhost | | = +o++ . | 2026-02-04 10:51:07.299906 | instance -> localhost | | . = . +o.= | 2026-02-04 10:51:07.299926 | instance -> localhost | | E = o + | 2026-02-04 10:51:07.299946 | instance -> localhost | | o o .S. | 2026-02-04 10:51:07.299966 | instance -> localhost | | . * . o.o | 2026-02-04 10:51:07.299984 | instance -> localhost | | + *.=.=. | 2026-02-04 10:51:07.300004 | instance -> localhost | | ..++.*oo | 2026-02-04 10:51:07.300025 | instance -> localhost | | +oo =o | 2026-02-04 10:51:07.300045 | instance -> localhost | +----[SHA256]-----+ 2026-02-04 10:51:07.300095 | instance -> localhost | ok: Runtime: 0:00:00.599054 2026-02-04 10:51:07.309190 | 2026-02-04 10:51:07.309269 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-04 10:51:07.340682 | instance | ok 2026-02-04 10:51:07.350885 | instance | included: /var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-04 10:51:07.358191 | 2026-02-04 10:51:07.358255 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-04 10:51:07.382854 | instance | skipping: Conditional result was False 2026-02-04 10:51:07.393097 | 2026-02-04 10:51:07.393183 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-04 10:51:07.832694 | instance | changed 2026-02-04 10:51:07.840180 | 2026-02-04 10:51:07.840284 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-04 10:51:08.027033 | instance | ok 2026-02-04 10:51:08.031974 | 2026-02-04 10:51:08.032052 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-04 10:51:08.474970 | instance | changed 2026-02-04 10:51:08.481546 | 2026-02-04 10:51:08.481632 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-04 10:51:08.907251 | instance | changed 2026-02-04 10:51:08.912676 | 2026-02-04 10:51:08.912743 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-04 10:51:08.937959 | instance | skipping: Conditional result was False 2026-02-04 10:51:08.957855 | 2026-02-04 10:51:08.957932 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-04 10:51:09.346138 | instance -> localhost | changed 2026-02-04 10:51:09.359193 | 2026-02-04 10:51:09.359263 | TASK [add-build-sshkey : Add back temp key] 2026-02-04 10:51:09.627646 | instance -> localhost | Identity added: /var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/60caa93149244d8c9a2edddba7c24249_id_rsa (zuul-build-sshkey) 2026-02-04 10:51:09.627888 | instance -> localhost | ok: Runtime: 0:00:00.011838 2026-02-04 10:51:09.634471 | 2026-02-04 10:51:09.634554 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-04 10:51:09.951018 | instance | ok 2026-02-04 10:51:09.959451 | 2026-02-04 10:51:09.959537 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-04 10:51:09.984704 | instance | skipping: Conditional result was False 2026-02-04 10:51:10.001918 | 2026-02-04 10:51:10.001981 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-04 10:51:10.319807 | instance | ok 2026-02-04 10:51:10.325803 | 2026-02-04 10:51:10.325871 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-04 10:51:11.904320 | instance | Output suppressed because no_log was given 2026-02-04 10:51:11.916331 | 2026-02-04 10:51:11.916396 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-04 10:51:12.104537 | instance | ok: "logs" 2026-02-04 10:51:12.104923 | instance | ok: All items complete 2026-02-04 10:51:12.104981 | 2026-02-04 10:51:12.246788 | instance | ok: "artifacts" 2026-02-04 10:51:12.402404 | instance | ok: "docs" 2026-02-04 10:51:12.414000 | 2026-02-04 10:51:12.414184 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-04 10:51:12.592065 | instance | changed: "logs" 2026-02-04 10:51:12.758031 | instance | changed: "artifacts" 2026-02-04 10:51:12.908936 | instance | changed: "docs" 2026-02-04 10:51:12.924581 | 2026-02-04 10:51:12.924714 | PLAY RECAP 2026-02-04 10:51:12.924764 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-04 10:51:12.924794 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 10:51:12.924815 | 2026-02-04 10:51:13.076047 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-02-04 10:51:13.078203 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-04 10:51:13.683405 | 2026-02-04 10:51:13.683543 | PLAY [all] 2026-02-04 10:51:13.694745 | 2026-02-04 10:51:13.694828 | TASK [setup-uv : Extract archive] 2026-02-04 10:51:15.781724 | instance | changed 2026-02-04 10:51:15.788971 | 2026-02-04 10:51:15.789052 | TASK [setup-uv : Print version] 2026-02-04 10:51:16.478880 | instance | uv 0.8.13 2026-02-04 10:51:16.325545 | instance | ok: Runtime: 0:00:00.011331 2026-02-04 10:51:16.333817 | 2026-02-04 10:51:16.333887 | PLAY RECAP 2026-02-04 10:51:16.333951 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 10:51:16.333984 | 2026-02-04 10:51:16.455868 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-04 10:51:16.459851 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-02-04 10:51:17.019637 | 2026-02-04 10:51:17.020198 | PLAY [all] 2026-02-04 10:51:17.031283 | 2026-02-04 10:51:17.031353 | TASK [Install "jq" for log collection] 2026-02-04 10:51:25.687273 | instance | changed 2026-02-04 10:51:25.689843 | 2026-02-04 10:51:25.689927 | PLAY RECAP 2026-02-04 10:51:25.689998 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-04 10:51:25.690067 | 2026-02-04 10:51:25.805463 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-02-04 10:51:25.809525 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-04 10:51:26.402729 | 2026-02-04 10:51:26.402866 | PLAY [all] 2026-02-04 10:51:26.413933 | 2026-02-04 10:51:26.414001 | TASK [Copy inventory file for Zuul] 2026-02-04 10:51:27.209087 | instance | changed 2026-02-04 10:51:27.216683 | 2026-02-04 10:51:27.216767 | TASK [Switch "ansible_host" to private IP] 2026-02-04 10:51:27.476255 | instance | changed: 1 replacements made 2026-02-04 10:51:27.481649 | 2026-02-04 10:51:27.481717 | TASK [Run Molecule scenario] 2026-02-04 10:51:28.108902 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-02-04 10:51:28.109052 | instance | Creating virtual environment at: .venv 2026-02-04 10:51:28.136001 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-02-04 10:51:28.168963 | instance | Downloading cryptography (4.2MiB) 2026-02-04 10:51:28.170695 | instance | Downloading rjsonnet (1.2MiB) 2026-02-04 10:51:28.174643 | instance | Downloading netaddr (2.2MiB) 2026-02-04 10:51:28.174948 | instance | Downloading kubernetes (1.9MiB) 2026-02-04 10:51:28.175280 | instance | Downloading pygments (1.2MiB) 2026-02-04 10:51:28.175874 | instance | Downloading openstacksdk (1.7MiB) 2026-02-04 10:51:28.176240 | instance | Downloading setuptools (1.1MiB) 2026-02-04 10:51:28.176593 | instance | Downloading ansible-core (2.1MiB) 2026-02-04 10:51:28.474391 | instance | Building pyperclip==1.9.0 2026-02-04 10:51:28.476389 | instance | Downloading rjsonnet 2026-02-04 10:51:28.617567 | instance | Downloading netaddr 2026-02-04 10:51:28.630790 | instance | Downloading pygments 2026-02-04 10:51:28.656295 | instance | Downloading cryptography 2026-02-04 10:51:28.688427 | instance | Downloading setuptools 2026-02-04 10:51:28.747839 | instance | Downloading kubernetes 2026-02-04 10:51:28.791980 | instance | Downloading ansible-core 2026-02-04 10:51:28.816000 | instance | Downloading openstacksdk 2026-02-04 10:51:29.179176 | instance | Built pyperclip==1.9.0 2026-02-04 10:51:29.400320 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-02-04 10:51:29.460406 | instance | Installed 79 packages in 59ms 2026-02-04 10:51:30.064632 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-02-04 10:51:30.607366 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-02-04 10:51:30.607435 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-02-04 10:51:40.009518 | instance | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv community.crypto:2.2.3 2026-02-04 10:51:40.009633 | instance | ERROR Command ansible-galaxy collection install -vvv community.crypto:2.2.3, returned 250 code: 2026-02-04 10:51:40.009640 | instance | Starting galaxy collection install process 2026-02-04 10:51:40.009645 | instance | Process install dependency map 2026-02-04 10:51:40.009650 | instance | the full traceback was: 2026-02-04 10:51:40.009654 | instance | 2026-02-04 10:51:40.009658 | instance | Traceback (most recent call last): 2026-02-04 10:51:40.009663 | 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-02-04 10:51:40.009667 | instance | exit_code = cli.run() 2026-02-04 10:51:40.009672 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-02-04 10:51:40.009676 | instance | return context.CLIARGS() 2026-02-04 10:51:40.009680 | 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-02-04 10:51:40.009684 | instance | return wrapped_method(*args, **kwargs) 2026-02-04 10:51:40.009689 | 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-02-04 10:51:40.009693 | instance | self._execute_install_collection( 2026-02-04 10:51:40.009697 | 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-02-04 10:51:40.009702 | instance | install_collections( 2026-02-04 10:51:40.009706 | 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-02-04 10:51:40.009710 | instance | dependency_map = _resolve_depenency_map( 2026-02-04 10:51:40.009715 | 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-02-04 10:51:40.009720 | instance | return collection_dep_resolver.resolve( 2026-02-04 10:51:40.009724 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-02-04 10:51:40.009738 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-02-04 10:51:40.009743 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-02-04 10:51:40.009747 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-02-04 10:51:40.009751 | 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-02-04 10:51:40.009755 | instance | matches = self._p.find_matches( 2026-02-04 10:51:40.009759 | 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-02-04 10:51:40.009763 | instance | match for match in self._find_matches(list(requirements)) 2026-02-04 10:51:40.009768 | 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-02-04 10:51:40.009772 | instance | coll_versions = if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable] 2026-02-04 10:51:40.009779 | 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-02-04 10:51:40.009783 | instance | return set( 2026-02-04 10:51:40.009788 | 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-02-04 10:51:40.009792 | instance | return set( 2026-02-04 10:51:40.009796 | 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-02-04 10:51:40.009800 | instance | raise last_error 2026-02-04 10:51:40.009804 | 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-02-04 10:51:40.009808 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-02-04 10:51:40.009812 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-02-04 10:51:40.009816 | instance | return method(self, *args, **kwargs) 2026-02-04 10:51:40.009820 | 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-02-04 10:51:40.009824 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-02-04 10:51:40.009829 | 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-02-04 10:51:40.009833 | instance | return call_retryable_function() 2026-02-04 10:51:40.009839 | 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-02-04 10:51:40.009844 | instance | res = path_cache 2026-02-04 10:51:40.009848 | instance | KeyError: 'results' 2026-02-04 10:51:40.009852 | instance | 2026-02-04 10:51:40.009856 | instance | ansible-galaxy 2026-02-04 10:51:40.009860 | instance | config file = None 2026-02-04 10:51:40.009864 | instance | configured module search path = 2026-02-04 10:51:40.009868 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-02-04 10:51:40.009873 | 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-02-04 10:51:40.009881 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-02-04 10:51:40.009885 | instance | python version = 3.10.12 (main, Jan 8 2026, 06:52:19) (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-02-04 10:51:40.009889 | instance | jinja version = 3.1.6 2026-02-04 10:51:40.009893 | instance | libyaml = True 2026-02-04 10:51:40.009897 | instance | No config file found; using defaults 2026-02-04 10:51:40.009901 | instance | : Collection ansible.netcommon does not support Ansible version 2026-02-04 10:51:40.009905 | instance | 2.17.12 2026-02-04 10:51:40.009909 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-02-04 10:51:40.009913 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-02-04 10:51:40.009917 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-02-04 10:51:40.009921 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-02-04 10:51:40.009925 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-02-04 10:51:40.009929 | instance | unexpected error when getting available versions of collection 2026-02-04 10:51:40.009933 | instance | community.crypto: 'results' 2026-02-04 10:51:40.009937 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-02-04 10:51:40.009941 | instance | 2026-02-04 10:51:40.010259 | instance | Traceback (most recent call last): 2026-02-04 10:51:40.010604 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-02-04 10:51:40.011031 | instance | sys.exit(main()) 2026-02-04 10:51:40.011318 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-02-04 10:51:40.011983 | instance | return self.main(*args, **kwargs) 2026-02-04 10:51:40.012271 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-02-04 10:51:40.012980 | instance | rv = self.invoke(ctx) 2026-02-04 10:51:40.013227 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-02-04 10:51:40.013711 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-02-04 10:51:40.013884 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-02-04 10:51:40.014259 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-02-04 10:51:40.014460 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-02-04 10:51:40.014778 | instance | return callback(*args, **kwargs) 2026-02-04 10:51:40.014954 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-02-04 10:51:40.015250 | instance | return func(*args, **kwargs) 2026-02-04 10:51:40.015427 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func 2026-02-04 10:51:40.015648 | instance | return f(get_current_context(), *args, **kwargs) 2026-02-04 10:51:40.015824 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-02-04 10:51:40.016091 | instance | return func(ctx) 2026-02-04 10:51:40.016272 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test 2026-02-04 10:51:40.016552 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-02-04 10:51:40.016727 | 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-02-04 10:51:40.016977 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-02-04 10:51:40.017152 | 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-02-04 10:51:40.017410 | instance | scenario.config.runtime.prepare_environment( 2026-02-04 10:51:40.017586 | 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-02-04 10:51:40.017902 | instance | self.install_collection( 2026-02-04 10:51:40.018077 | 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-02-04 10:51:40.018390 | instance | raise InvalidPrerequisiteError(msg) 2026-02-04 10:51:40.020799 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.crypto:2.2.3, returned 250 code: 2026-02-04 10:51:40.020809 | instance | Starting galaxy collection install process 2026-02-04 10:51:40.020815 | instance | Process install dependency map 2026-02-04 10:51:40.020821 | instance | the full traceback was: 2026-02-04 10:51:40.020826 | instance | 2026-02-04 10:51:40.020832 | instance | Traceback (most recent call last): 2026-02-04 10:51:40.020837 | 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-02-04 10:51:40.020843 | instance | exit_code = cli.run() 2026-02-04 10:51:40.020849 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-02-04 10:51:40.020854 | instance | return context.CLIARGS['func']() 2026-02-04 10:51:40.020860 | 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-02-04 10:51:40.020865 | instance | return wrapped_method(*args, **kwargs) 2026-02-04 10:51:40.020870 | 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-02-04 10:51:40.020876 | instance | self._execute_install_collection( 2026-02-04 10:51:40.020881 | 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-02-04 10:51:40.020887 | instance | install_collections( 2026-02-04 10:51:40.020893 | 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-02-04 10:51:40.020898 | instance | dependency_map = _resolve_depenency_map( 2026-02-04 10:51:40.020904 | 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-02-04 10:51:40.020909 | instance | return collection_dep_resolver.resolve( 2026-02-04 10:51:40.020915 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-02-04 10:51:40.020920 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-02-04 10:51:40.020926 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-02-04 10:51:40.020931 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-02-04 10:51:40.020937 | 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-02-04 10:51:40.020942 | instance | matches = self._p.find_matches( 2026-02-04 10:51:40.020948 | 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-02-04 10:51:40.020954 | instance | match for match in self._find_matches(list(requirements[identifier])) 2026-02-04 10:51:40.020959 | 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-02-04 10:51:40.020972 | instance | coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable[t.Tuple[str, GalaxyAPI]] 2026-02-04 10:51:40.020979 | 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-02-04 10:51:40.020986 | instance | return set( 2026-02-04 10:51:40.020998 | 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-02-04 10:51:40.021005 | instance | return set( 2026-02-04 10:51:40.021012 | 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-02-04 10:51:40.021019 | instance | raise last_error 2026-02-04 10:51:40.021026 | 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-02-04 10:51:40.021033 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-02-04 10:51:40.021040 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-02-04 10:51:40.021047 | instance | return method(self, *args, **kwargs) 2026-02-04 10:51:40.021054 | 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-02-04 10:51:40.021061 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-02-04 10:51:40.021073 | 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-02-04 10:51:40.021081 | instance | return call_retryable_function() 2026-02-04 10:51:40.021088 | 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-02-04 10:51:40.021095 | instance | res = path_cache['results'] 2026-02-04 10:51:40.021102 | instance | KeyError: 'results' 2026-02-04 10:51:40.021109 | instance | 2026-02-04 10:51:40.021116 | instance | ansible-galaxy [core 2.17.12] 2026-02-04 10:51:40.021123 | instance | config file = None 2026-02-04 10:51:40.021130 | 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-02-04 10:51:40.021138 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-02-04 10:51:40.021147 | 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-02-04 10:51:40.021158 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-02-04 10:51:40.021169 | instance | python version = 3.10.12 (main, Jan 8 2026, 06:52:19) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-02-04 10:51:40.021179 | instance | jinja version = 3.1.6 2026-02-04 10:51:40.021189 | instance | libyaml = True 2026-02-04 10:51:40.021200 | instance | No config file found; using defaults 2026-02-04 10:51:40.021210 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-02-04 10:51:40.021219 | instance | 2.17.12 2026-02-04 10:51:40.021228 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-02-04 10:51:40.021242 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-02-04 10:51:40.021252 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-02-04 10:51:40.021260 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-02-04 10:51:40.021269 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-02-04 10:51:40.021278 | instance | unexpected error when getting available versions of collection 2026-02-04 10:51:40.021287 | instance | community.crypto: 'results' 2026-02-04 10:51:40.021296 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-02-04 10:51:40.021304 | instance | 2026-02-04 10:51:40.256536 | instance | ERROR 2026-02-04 10:51:40.256802 | instance | { 2026-02-04 10:51:40.256833 | instance | "delta": "0:00:12.024911", 2026-02-04 10:51:40.256854 | instance | "end": "2026-02-04 10:51:40.078638", 2026-02-04 10:51:40.256873 | instance | "msg": "non-zero return code", 2026-02-04 10:51:40.256891 | instance | "rc": 1, 2026-02-04 10:51:40.256911 | instance | "start": "2026-02-04 10:51:28.053727" 2026-02-04 10:51:40.256930 | instance | } failure 2026-02-04 10:51:40.259912 | 2026-02-04 10:51:40.260433 | PLAY RECAP 2026-02-04 10:51:40.260485 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-04 10:51:40.260507 | 2026-02-04 10:51:40.389405 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-02-04 10:51:40.392198 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-02-04 10:51:40.991455 | 2026-02-04 10:51:40.991590 | PLAY [all] 2026-02-04 10:51:41.005743 | 2026-02-04 10:51:41.005820 | TASK [gather-host-logs : creating directory for system status] 2026-02-04 10:51:41.337561 | instance | changed 2026-02-04 10:51:41.345211 | 2026-02-04 10:51:41.345314 | TASK [gather-host-logs : Get logs for each host] 2026-02-04 10:51:41.743794 | instance | + systemd-cgls --full --all --no-pager 2026-02-04 10:51:41.749693 | instance | + ip addr 2026-02-04 10:51:41.751037 | instance | + ip route 2026-02-04 10:51:41.752416 | instance | + lsblk 2026-02-04 10:51:41.754871 | instance | + mount 2026-02-04 10:51:41.756402 | instance | + docker images 2026-02-04 10:51:41.756732 | instance | /bin/bash: line 7: docker: command not found 2026-02-04 10:51:41.756959 | instance | + brctl show 2026-02-04 10:51:41.757280 | instance | /bin/bash: line 8: brctl: command not found 2026-02-04 10:51:41.757505 | instance | + ps aux --sort=-%mem 2026-02-04 10:51:41.763683 | instance | + dpkg -l 2026-02-04 10:51:41.769643 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-02-04 10:51:41.770022 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-02-04 10:51:41.770102 | instance | /bin/bash: line 11: docker: command not found 2026-02-04 10:51:41.770423 | instance | + '[' '!' -z '' ']' 2026-02-04 10:51:41.886676 | instance | ok: Runtime: 0:00:00.031340 2026-02-04 10:51:41.893763 | 2026-02-04 10:51:41.893829 | TASK [gather-host-logs : Downloads logs to executor] 2026-02-04 10:51:42.526859 | instance | changed: 2026-02-04 10:51:42.527048 | instance | created directory /var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/logs/instance 2026-02-04 10:51:42.527076 | instance | cd+++++++++ system/ 2026-02-04 10:51:42.527098 | instance | >f+++++++++ system/brctl-show.txt 2026-02-04 10:51:42.527117 | instance | >f+++++++++ system/docker-images.txt 2026-02-04 10:51:42.527136 | instance | >f+++++++++ system/ip-addr.txt 2026-02-04 10:51:42.527159 | instance | >f+++++++++ system/ip-route.txt 2026-02-04 10:51:42.527180 | instance | >f+++++++++ system/lsblk.txt 2026-02-04 10:51:42.527199 | instance | >f+++++++++ system/mount.txt 2026-02-04 10:51:42.527220 | instance | >f+++++++++ system/packages.txt 2026-02-04 10:51:42.527239 | instance | >f+++++++++ system/ps.txt 2026-02-04 10:51:42.527258 | instance | >f+++++++++ system/systemd-cgls.txt 2026-02-04 10:51:42.537013 | 2026-02-04 10:51:42.537144 | LOOP [helm-release-status : creating directory for helm release status] 2026-02-04 10:51:42.716174 | instance | changed: "values" 2026-02-04 10:51:42.871002 | instance | changed: "releases" 2026-02-04 10:51:42.883319 | 2026-02-04 10:51:42.883493 | TASK [helm-release-status : Gather get release status for helm charts] 2026-02-04 10:51:43.115654 | instance | /bin/bash: line 3: kubectl: command not found 2026-02-04 10:51:43.423710 | instance | ok: Runtime: 0:00:00.005212 2026-02-04 10:51:43.430371 | 2026-02-04 10:51:43.430438 | TASK [helm-release-status : Downloads logs to executor] 2026-02-04 10:51:43.911271 | instance | changed: 2026-02-04 10:51:43.911547 | instance | cd+++++++++ helm/ 2026-02-04 10:51:43.911575 | instance | cd+++++++++ helm/releases/ 2026-02-04 10:51:43.911597 | instance | cd+++++++++ helm/values/ 2026-02-04 10:51:43.920852 | 2026-02-04 10:51:43.921025 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-02-04 10:51:44.107499 | instance | changed 2026-02-04 10:51:44.113786 | 2026-02-04 10:51:44.113852 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-02-04 10:51:44.330752 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-02-04 10:51:44.330797 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-02-04 10:51:44.334494 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-02-04 10:51:44.334667 | instance | environment: line 1: kubectl: command not found 2026-02-04 10:51:44.334797 | instance | environment: line 1: kubectl: command not found 2026-02-04 10:51:44.335569 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-02-04 10:51:44.336794 | instance | environment: line 1: kubectl: command not found 2026-02-04 10:51:44.337606 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-02-04 10:51:44.338531 | instance | environment: line 1: kubectl: command not found 2026-02-04 10:51:44.339588 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-02-04 10:51:44.340393 | instance | environment: line 1: kubectl: command not found 2026-02-04 10:51:44.341219 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-02-04 10:51:44.650632 | instance | ok: Runtime: 0:00:00.015394 2026-02-04 10:51:44.657415 | 2026-02-04 10:51:44.657491 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-02-04 10:51:44.843324 | instance | changed 2026-02-04 10:51:44.849802 | 2026-02-04 10:51:44.849870 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-02-04 10:51:45.095049 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-02-04 10:51:45.096028 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-02-04 10:51:45.096270 | instance | environment: line 5: kubectl: command not found 2026-02-04 10:51:45.096805 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-02-04 10:51:45.412927 | instance | ok: Runtime: 0:00:00.008119 2026-02-04 10:51:45.418181 | 2026-02-04 10:51:45.418246 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-02-04 10:51:45.900550 | instance | changed: 2026-02-04 10:51:45.900745 | instance | cd+++++++++ objects/ 2026-02-04 10:51:45.900772 | instance | cd+++++++++ objects/cluster/ 2026-02-04 10:51:45.900794 | instance | cd+++++++++ objects/namespaced/ 2026-02-04 10:51:45.909763 | 2026-02-04 10:51:45.909824 | TASK [gather-pod-logs : creating directory for pod logs] 2026-02-04 10:51:46.092612 | instance | changed 2026-02-04 10:51:46.099552 | 2026-02-04 10:51:46.099613 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-02-04 10:51:46.302416 | instance | changed 2026-02-04 10:51:46.310586 | 2026-02-04 10:51:46.310656 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-02-04 10:51:46.529988 | instance | environment: line 3: kubectl: command not found 2026-02-04 10:51:46.845172 | instance | ok: Runtime: 0:00:00.004858 2026-02-04 10:51:46.852416 | 2026-02-04 10:51:46.852486 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-02-04 10:51:47.349121 | instance | changed: 2026-02-04 10:51:47.349434 | instance | cd+++++++++ pod-logs/ 2026-02-04 10:51:47.349494 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-02-04 10:51:47.358677 | 2026-02-04 10:51:47.358740 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-02-04 10:51:47.546729 | instance | changed 2026-02-04 10:51:47.551502 | 2026-02-04 10:51:47.551566 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-02-04 10:51:47.765588 | instance | /bin/bash: line 2: kubectl: command not found 2026-02-04 10:51:48.092438 | instance | ok: Runtime: 0:00:00.032830 2026-02-04 10:51:48.098131 | 2026-02-04 10:51:48.098199 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-02-04 10:51:48.348602 | instance | /bin/bash: line 2: kubectl: command not found 2026-02-04 10:51:48.371446 | instance | ceph-mgr endpoints: 2026-02-04 10:51:48.635307 | instance | ok: Runtime: 0:00:00.027963 2026-02-04 10:51:48.642808 | 2026-02-04 10:51:48.642943 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-02-04 10:51:48.850577 | instance | /bin/bash: line 4: kubectl: command not found 2026-02-04 10:51:49.176350 | instance | ok: Runtime: 0:00:00.030410 2026-02-04 10:51:49.181337 | 2026-02-04 10:51:49.181402 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-02-04 10:51:49.679166 | instance | changed: cd+++++++++ prometheus/ 2026-02-04 10:51:49.689674 | 2026-02-04 10:51:49.689747 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-02-04 10:51:49.877339 | instance | changed 2026-02-04 10:51:49.883631 | 2026-02-04 10:51:49.883697 | TASK [gather-selenium-data : Get selenium data] 2026-02-04 10:51:50.085579 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-02-04 10:51:50.086251 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-02-04 10:51:50.418798 | instance | ERROR 2026-02-04 10:51:50.419018 | instance | { 2026-02-04 10:51:50.419061 | instance | "delta": "0:00:00.003801", 2026-02-04 10:51:50.419090 | instance | "end": "2026-02-04 10:51:50.086507", 2026-02-04 10:51:50.419135 | instance | "msg": "non-zero return code", 2026-02-04 10:51:50.419161 | instance | "rc": 1, 2026-02-04 10:51:50.419186 | instance | "start": "2026-02-04 10:51:50.082706" 2026-02-04 10:51:50.419211 | instance | } 2026-02-04 10:51:50.419245 | instance | ERROR: Ignoring Errors 2026-02-04 10:51:50.425200 | 2026-02-04 10:51:50.425269 | TASK [gather-selenium-data : Downloads logs to executor] 2026-02-04 10:51:50.906437 | instance | changed: cd+++++++++ selenium/ 2026-02-04 10:51:50.913998 | 2026-02-04 10:51:50.914051 | PLAY RECAP 2026-02-04 10:51:50.914130 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-02-04 10:51:50.914160 | 2026-02-04 10:51:51.042610 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-02-04 10:51:51.046871 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-04 10:51:51.655380 | 2026-02-04 10:51:51.656153 | PLAY [all] 2026-02-04 10:51:51.669346 | 2026-02-04 10:51:51.669439 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-04 10:51:51.715453 | instance | skipping: Conditional result was False 2026-02-04 10:51:51.727682 | 2026-02-04 10:51:51.727773 | TASK [fetch-output : Set log path for single node] 2026-02-04 10:51:51.769575 | instance | ok 2026-02-04 10:51:51.775229 | 2026-02-04 10:51:51.775297 | LOOP [fetch-output : Ensure local output dirs] 2026-02-04 10:51:52.150277 | instance -> localhost | ok: "/var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/logs" 2026-02-04 10:51:52.371269 | instance -> localhost | changed: "/var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/artifacts" 2026-02-04 10:51:52.579070 | instance -> localhost | changed: "/var/lib/zuul/builds/60caa93149244d8c9a2edddba7c24249/work/docs" 2026-02-04 10:51:52.595743 | 2026-02-04 10:51:52.595921 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-04 10:51:53.233148 | instance | changed: .d..t...... ./ 2026-02-04 10:51:53.233445 | instance | changed: All items complete 2026-02-04 10:51:53.233480 | 2026-02-04 10:51:53.702939 | instance | changed: .d..t...... ./ 2026-02-04 10:51:54.133646 | instance | changed: .d..t...... ./ 2026-02-04 10:51:54.157497 | 2026-02-04 10:51:54.157680 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-04 10:51:54.598924 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007435 2026-02-04 10:51:54.821520 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007895 2026-02-04 10:51:54.838220 | 2026-02-04 10:51:54.838340 | PLAY [all] 2026-02-04 10:51:54.847751 | 2026-02-04 10:51:54.847814 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-04 10:51:55.246054 | instance | changed 2026-02-04 10:51:55.253246 | 2026-02-04 10:51:55.253296 | PLAY RECAP 2026-02-04 10:51:55.253343 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-04 10:51:55.253365 | 2026-02-04 10:51:55.380125 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-02-04 10:51:55.382268 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-04 10:51:55.941761 | 2026-02-04 10:51:55.941903 | PLAY [localhost] 2026-02-04 10:51:55.952267 | 2026-02-04 10:51:55.952341 | TASK [Generate Zuul manifest] 2026-02-04 10:51:55.973976 | localhost | ok 2026-02-04 10:51:55.990874 | 2026-02-04 10:51:55.990959 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-04 10:51:56.288010 | localhost | changed 2026-02-04 10:51:56.298799 | 2026-02-04 10:51:56.298875 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-04 10:51:56.329635 | localhost | ok 2026-02-04 10:51:56.338259 | 2026-02-04 10:51:56.338328 | TASK [Upload logs] 2026-02-04 10:51:56.358221 | localhost | ok 2026-02-04 10:51:56.416295 | 2026-02-04 10:51:56.416413 | TASK [Set zuul-log-path fact] 2026-02-04 10:51:56.438098 | localhost | ok 2026-02-04 10:51:56.449640 | 2026-02-04 10:51:56.449708 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-04 10:51:56.491195 | localhost | ok 2026-02-04 10:51:56.499091 | 2026-02-04 10:51:56.499161 | TASK [upload-logs : Create log directories] 2026-02-04 10:51:56.891473 | localhost | changed 2026-02-04 10:51:56.897796 | 2026-02-04 10:51:56.897865 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-04 10:51:57.305239 | localhost -> localhost | ok: Runtime: 0:00:00.006132 2026-02-04 10:51:57.310504 | 2026-02-04 10:51:57.310572 | TASK [upload-logs : Upload logs to log server] 2026-02-04 10:51:57.770616 | localhost | Output suppressed because no_log was given 2026-02-04 10:51:57.775609 | 2026-02-04 10:51:57.775696 | LOOP [upload-logs : Compress console log and json output] 2026-02-04 10:51:57.821155 | localhost | skipping: Conditional result was False 2026-02-04 10:51:57.826808 | localhost | skipping: Conditional result was False 2026-02-04 10:51:57.841006 | 2026-02-04 10:51:57.841171 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-04 10:51:57.882134 | localhost | skipping: Conditional result was False 2026-02-04 10:51:57.882508 | 2026-02-04 10:51:57.884887 | localhost | skipping: Conditional result was False 2026-02-04 10:51:57.894244 | 2026-02-04 10:51:57.894379 | LOOP [upload-logs : Upload console log and json output]