2026-05-11 17:50:40.654652 | Job console starting 2026-05-11 17:50:40.668828 | Updating git repos 2026-05-11 17:50:40.788000 | Cloning repos into workspace 2026-05-11 17:50:40.838982 | Restoring repo states 2026-05-11 17:50:40.911227 | Merging changes 2026-05-11 17:50:42.682219 | Checking out repos 2026-05-11 17:50:44.200976 | Preparing playbooks 2026-05-11 17:51:00.928751 | Running Ansible setup 2026-05-11 17:51:05.487979 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 17:51:06.139276 | 2026-05-11 17:51:06.139392 | PLAY [localhost] 2026-05-11 17:51:06.147043 | 2026-05-11 17:51:06.147112 | TASK [Gathering Facts] 2026-05-11 17:51:07.843066 | localhost | ok 2026-05-11 17:51:07.855896 | 2026-05-11 17:51:07.856006 | TASK [Setup log path fact] 2026-05-11 17:51:07.878257 | localhost | ok 2026-05-11 17:51:07.894319 | 2026-05-11 17:51:07.894476 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 17:51:07.927746 | localhost | ok 2026-05-11 17:51:07.936917 | 2026-05-11 17:51:07.937096 | TASK [emit-job-header : Print job information] 2026-05-11 17:51:07.980201 | # Job Information 2026-05-11 17:51:07.980484 | Ansible Version: 2.16.18 2026-05-11 17:51:07.980555 | Job: atmosphere-molecule-csi-local-path-provisioner 2026-05-11 17:51:07.980605 | Pipeline: check 2026-05-11 17:51:07.980651 | Executor: 2d72f0692154 2026-05-11 17:51:07.980695 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-05-11 17:51:07.980745 | Event ID: d4c283c0-4d61-11f1-9e52-22fffb4b8d6b 2026-05-11 17:51:07.985296 | 2026-05-11 17:51:07.985368 | LOOP [emit-job-header : Print node information] 2026-05-11 17:51:08.076904 | localhost | ok: 2026-05-11 17:51:08.077242 | localhost | # Node Information 2026-05-11 17:51:08.077307 | localhost | Inventory Hostname: instance 2026-05-11 17:51:08.077356 | localhost | Hostname: np0000173581 2026-05-11 17:51:08.077402 | localhost | Username: zuul 2026-05-11 17:51:08.077453 | localhost | Distro: Ubuntu 22.04 2026-05-11 17:51:08.077498 | localhost | Provider: yul1 2026-05-11 17:51:08.077543 | localhost | Region: ca-ymq-1 2026-05-11 17:51:08.077585 | localhost | Label: ubuntu-jammy 2026-05-11 17:51:08.077626 | localhost | Product Name: OpenStack Nova 2026-05-11 17:51:08.077667 | localhost | Interface IP: 199.19.213.223 2026-05-11 17:51:08.094621 | 2026-05-11 17:51:08.094803 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 17:51:08.495224 | localhost -> localhost | changed 2026-05-11 17:51:08.500749 | 2026-05-11 17:51:08.500820 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 17:51:09.485389 | localhost -> localhost | changed 2026-05-11 17:51:09.494994 | 2026-05-11 17:51:09.495046 | PLAY [all] 2026-05-11 17:51:09.502492 | 2026-05-11 17:51:09.502557 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 17:51:09.853050 | instance -> localhost | ok 2026-05-11 17:51:09.864077 | 2026-05-11 17:51:09.864218 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 17:51:09.901811 | instance | ok 2026-05-11 17:51:09.915776 | instance | included: /var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 17:51:09.921488 | 2026-05-11 17:51:09.921548 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 17:51:11.241618 | instance -> localhost | Generating public/private rsa key pair. 2026-05-11 17:51:11.241844 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/2ec21f8bb4f1476ab5588faaae5472e9_id_rsa 2026-05-11 17:51:11.241913 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/2ec21f8bb4f1476ab5588faaae5472e9_id_rsa.pub 2026-05-11 17:51:11.241949 | instance -> localhost | The key fingerprint is: 2026-05-11 17:51:11.241984 | instance -> localhost | SHA256:dFjoAJ4z0hh/+6SDT6julxi3E924bTemE2b5Al/wTrg zuul-build-sshkey 2026-05-11 17:51:11.242029 | instance -> localhost | The key's randomart image is: 2026-05-11 17:51:11.242062 | instance -> localhost | +---[RSA 3072]----+ 2026-05-11 17:51:11.242099 | instance -> localhost | | . .. .. | 2026-05-11 17:51:11.242132 | instance -> localhost | | * .. .o | 2026-05-11 17:51:11.242162 | instance -> localhost | | o B .oo . | 2026-05-11 17:51:11.242192 | instance -> localhost | | . + oo. | 2026-05-11 17:51:11.242221 | instance -> localhost | | ..oS= | 2026-05-11 17:51:11.242251 | instance -> localhost | | . oo++B + | 2026-05-11 17:51:11.242280 | instance -> localhost | | +o++B.B | 2026-05-11 17:51:11.242310 | instance -> localhost | | ..=o..E * | 2026-05-11 17:51:11.242343 | instance -> localhost | | o+. ....* . | 2026-05-11 17:51:11.242374 | instance -> localhost | +----[SHA256]-----+ 2026-05-11 17:51:11.242443 | instance -> localhost | ok: Runtime: 0:00:00.904846 2026-05-11 17:51:11.249700 | 2026-05-11 17:51:11.249790 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 17:51:11.284613 | instance | ok 2026-05-11 17:51:11.295314 | instance | included: /var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 17:51:11.307991 | 2026-05-11 17:51:11.308075 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 17:51:11.333432 | instance | skipping: Conditional result was False 2026-05-11 17:51:11.343145 | 2026-05-11 17:51:11.422989 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 17:51:11.857197 | instance | changed 2026-05-11 17:51:11.862303 | 2026-05-11 17:51:11.862366 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 17:51:12.059967 | instance | ok 2026-05-11 17:51:12.089463 | 2026-05-11 17:51:12.089602 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 17:51:12.539906 | instance | changed 2026-05-11 17:51:12.545641 | 2026-05-11 17:51:12.545708 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 17:51:13.010868 | instance | changed 2026-05-11 17:51:13.017796 | 2026-05-11 17:51:13.017888 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 17:51:13.042942 | instance | skipping: Conditional result was False 2026-05-11 17:51:13.053793 | 2026-05-11 17:51:13.053878 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 17:51:13.392466 | instance -> localhost | changed 2026-05-11 17:51:13.407574 | 2026-05-11 17:51:13.407638 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 17:51:13.723766 | instance -> localhost | Identity added: /var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/2ec21f8bb4f1476ab5588faaae5472e9_id_rsa (zuul-build-sshkey) 2026-05-11 17:51:13.724084 | instance -> localhost | ok: Runtime: 0:00:00.013582 2026-05-11 17:51:13.731479 | 2026-05-11 17:51:13.758479 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 17:51:14.099018 | instance | ok 2026-05-11 17:51:14.107587 | 2026-05-11 17:51:14.107653 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 17:51:14.134712 | instance | skipping: Conditional result was False 2026-05-11 17:51:14.149355 | 2026-05-11 17:51:14.149462 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-11 17:51:14.483424 | instance | ok 2026-05-11 17:51:14.489766 | 2026-05-11 17:51:14.489830 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-11 17:51:16.046455 | instance | Output suppressed because no_log was given 2026-05-11 17:51:16.058795 | 2026-05-11 17:51:16.058860 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-11 17:51:16.257409 | instance | ok: "logs" 2026-05-11 17:51:16.257726 | instance | ok: All items complete 2026-05-11 17:51:16.257765 | 2026-05-11 17:51:16.421135 | instance | ok: "artifacts" 2026-05-11 17:51:16.584975 | instance | ok: "docs" 2026-05-11 17:51:16.603129 | 2026-05-11 17:51:16.603226 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-11 17:51:16.792553 | instance | changed: "logs" 2026-05-11 17:51:16.945424 | instance | changed: "artifacts" 2026-05-11 17:51:17.111618 | instance | changed: "docs" 2026-05-11 17:51:17.121020 | 2026-05-11 17:51:17.121073 | PLAY RECAP 2026-05-11 17:51:17.121117 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-11 17:51:17.121146 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 17:51:17.121168 | 2026-05-11 17:51:17.266448 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 17:51:17.268457 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-11 17:51:17.865935 | 2026-05-11 17:51:17.866087 | PLAY [all] 2026-05-11 17:51:17.877893 | 2026-05-11 17:51:17.877973 | TASK [setup-uv : Extract archive] 2026-05-11 17:51:20.112229 | instance | changed 2026-05-11 17:51:20.124026 | 2026-05-11 17:51:20.124147 | TASK [setup-uv : Print version] 2026-05-11 17:51:20.486996 | instance | uv 0.8.13 2026-05-11 17:51:20.665907 | instance | ok: Runtime: 0:00:00.011225 2026-05-11 17:51:20.672624 | 2026-05-11 17:51:20.672689 | PLAY RECAP 2026-05-11 17:51:20.672735 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 17:51:20.672758 | 2026-05-11 17:51:20.814903 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-11 17:51:20.819202 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-05-11 17:51:21.445113 | 2026-05-11 17:51:21.445778 | PLAY [all] 2026-05-11 17:51:21.457537 | 2026-05-11 17:51:21.457648 | TASK [Install "jq" for log collection] 2026-05-11 17:51:30.608584 | instance | changed 2026-05-11 17:51:30.611410 | 2026-05-11 17:51:30.611653 | PLAY RECAP 2026-05-11 17:51:30.611773 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 17:51:30.611885 | 2026-05-11 17:51:30.743819 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-05-11 17:51:30.747941 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-11 17:51:31.330660 | 2026-05-11 17:51:31.330799 | PLAY [all] 2026-05-11 17:51:31.342372 | 2026-05-11 17:51:31.342451 | TASK [Copy inventory file for Zuul] 2026-05-11 17:51:32.156004 | instance | changed 2026-05-11 17:51:32.163446 | 2026-05-11 17:51:32.163569 | TASK [Switch "ansible_host" to private IP] 2026-05-11 17:51:32.478118 | instance | changed: 1 replacements made 2026-05-11 17:51:32.485459 | 2026-05-11 17:51:32.485557 | TASK [Run Molecule scenario] 2026-05-11 17:51:32.911214 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-05-11 17:51:32.911359 | instance | Creating virtual environment at: .venv 2026-05-11 17:51:32.936356 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-05-11 17:51:32.960080 | instance | Downloading openstacksdk (1.7MiB) 2026-05-11 17:51:32.964138 | instance | Downloading ansible-core (2.1MiB) 2026-05-11 17:51:32.965101 | instance | Downloading setuptools (1.1MiB) 2026-05-11 17:51:32.965828 | instance | Downloading kubernetes (1.9MiB) 2026-05-11 17:51:32.966033 | instance | Downloading pydantic-core (2.0MiB) 2026-05-11 17:51:32.966259 | instance | Downloading cryptography (4.2MiB) 2026-05-11 17:51:32.966470 | instance | Downloading netaddr (2.2MiB) 2026-05-11 17:51:32.966677 | instance | Downloading rjsonnet (1.2MiB) 2026-05-11 17:51:32.968166 | instance | Downloading pygments (1.2MiB) 2026-05-11 17:51:33.286699 | instance | Building pyperclip==1.9.0 2026-05-11 17:51:33.287165 | instance | Downloading rjsonnet 2026-05-11 17:51:33.429842 | instance | Downloading pygments 2026-05-11 17:51:33.467858 | instance | Downloading pydantic-core 2026-05-11 17:51:33.510560 | instance | Downloading setuptools 2026-05-11 17:51:33.520066 | instance | Downloading netaddr 2026-05-11 17:51:33.550191 | instance | Downloading cryptography 2026-05-11 17:51:33.588283 | instance | Downloading kubernetes 2026-05-11 17:51:33.613915 | instance | Downloading ansible-core 2026-05-11 17:51:33.633939 | instance | Downloading openstacksdk 2026-05-11 17:51:34.036784 | instance | Built pyperclip==1.9.0 2026-05-11 17:51:34.247459 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-05-11 17:51:34.290123 | instance | Installed 83 packages in 40ms 2026-05-11 17:51:34.925585 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-05-11 17:51:35.602921 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-05-11 17:51:35.603050 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-05-11 17:51:47.236273 | instance | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv community.crypto:2.2.3 2026-05-11 17:51:47.236459 | instance | ERROR Command ansible-galaxy collection install -vvv community.crypto:2.2.3, returned 250 code: 2026-05-11 17:51:47.236473 | instance | Starting galaxy collection install process 2026-05-11 17:51:47.236484 | instance | Process install dependency map 2026-05-11 17:51:47.236494 | instance | the full traceback was: 2026-05-11 17:51:47.236504 | instance | 2026-05-11 17:51:47.236513 | instance | Traceback (most recent call last): 2026-05-11 17:51:47.236523 | 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-05-11 17:51:47.236533 | instance | exit_code = cli.run() 2026-05-11 17:51:47.236542 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-05-11 17:51:47.236552 | instance | return context.CLIARGS() 2026-05-11 17:51:47.236561 | 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-05-11 17:51:47.236570 | instance | return wrapped_method(*args, **kwargs) 2026-05-11 17:51:47.236579 | 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-05-11 17:51:47.236588 | instance | self._execute_install_collection( 2026-05-11 17:51:47.236597 | 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-05-11 17:51:47.236607 | instance | install_collections( 2026-05-11 17:51:47.236617 | 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-05-11 17:51:47.236626 | instance | dependency_map = _resolve_depenency_map( 2026-05-11 17:51:47.236635 | 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-05-11 17:51:47.236667 | instance | return collection_dep_resolver.resolve( 2026-05-11 17:51:47.236677 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-05-11 17:51:47.236686 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-05-11 17:51:47.236695 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 427, in resolve 2026-05-11 17:51:47.236704 | instance | failure_causes = self._attempt_to_pin_criterion(name) 2026-05-11 17:51:47.236713 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion 2026-05-11 17:51:47.236722 | instance | criteria = self._get_updated_criteria(candidate) 2026-05-11 17:51:47.236731 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria 2026-05-11 17:51:47.236740 | instance | for requirement in self._p.get_dependencies(candidate=candidate): 2026-05-11 17:51:47.236749 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies 2026-05-11 17:51:47.236758 | instance | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-05-11 17:51:47.236767 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 176, in get_collection_dependencies 2026-05-11 17:51:47.236776 | instance | get_collection_version_metadata(collection_candidate). 2026-05-11 17:51:47.236785 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 161, in get_collection_version_metadata 2026-05-11 17:51:47.236808 | instance | raise last_err 2026-05-11 17:51:47.236818 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 128, in get_collection_version_metadata 2026-05-11 17:51:47.236827 | instance | version_metadata = api.get_collection_version_metadata( 2026-05-11 17:51:47.236843 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-05-11 17:51:47.236853 | instance | return method(self, *args, **kwargs) 2026-05-11 17:51:47.236862 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 816, in get_collection_version_metadata 2026-05-11 17:51:47.236871 | instance | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-05-11 17:51:47.236880 | 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-05-11 17:51:47.236889 | instance | return call_retryable_function() 2026-05-11 17:51:47.236898 | 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-05-11 17:51:47.236907 | instance | res = path_cache 2026-05-11 17:51:47.236916 | instance | KeyError: 'results' 2026-05-11 17:51:47.236925 | instance | 2026-05-11 17:51:47.236934 | instance | ansible-galaxy 2026-05-11 17:51:47.236943 | instance | config file = None 2026-05-11 17:51:47.236960 | instance | configured module search path = 2026-05-11 17:51:47.236969 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-05-11 17:51:47.236979 | 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-05-11 17:51:47.237003 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-05-11 17:51:47.237012 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-05-11 17:51:47.237021 | instance | jinja version = 3.1.6 2026-05-11 17:51:47.237030 | instance | libyaml = True 2026-05-11 17:51:47.237039 | instance | No config file found; using defaults 2026-05-11 17:51:47.237048 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-05-11 17:51:47.237057 | instance | : Collection ansible.netcommon does not support Ansible version 2026-05-11 17:51:47.237066 | instance | 2.17.12 2026-05-11 17:51:47.237075 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-05-11 17:51:47.237084 | instance | Found installed collection ansible.utils:6.0.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-05-11 17:51:47.237093 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-05-11 17:51:47.237102 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-05-11 17:51:47.237111 | instance | unexpected error when getting available versions of collection 2026-05-11 17:51:47.237120 | instance | community.crypto: 'results' 2026-05-11 17:51:47.237129 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-05-11 17:51:47.237137 | instance | 2026-05-11 17:51:47.237150 | instance | Traceback (most recent call last): 2026-05-11 17:51:47.237350 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-05-11 17:51:47.237765 | instance | sys.exit(main()) 2026-05-11 17:51:47.238128 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-05-11 17:51:47.239115 | instance | return self.main(*args, **kwargs) 2026-05-11 17:51:47.239224 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-05-11 17:51:47.239856 | instance | rv = self.invoke(ctx) 2026-05-11 17:51:47.240150 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-05-11 17:51:47.240949 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-05-11 17:51:47.241238 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-05-11 17:51:47.241910 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-05-11 17:51:47.242233 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-05-11 17:51:47.242841 | instance | return callback(*args, **kwargs) 2026-05-11 17:51:47.243129 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-05-11 17:51:47.243615 | instance | return func(*args, **kwargs) 2026-05-11 17:51:47.243946 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func 2026-05-11 17:51:47.244314 | instance | return f(get_current_context(), *args, **kwargs) 2026-05-11 17:51:47.244606 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-05-11 17:51:47.245091 | instance | return func(ctx) 2026-05-11 17:51:47.245376 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test 2026-05-11 17:51:47.245772 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-05-11 17:51:47.246136 | 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-05-11 17:51:47.246593 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-05-11 17:51:47.246942 | 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-05-11 17:51:47.247469 | instance | scenario.config.runtime.prepare_environment( 2026-05-11 17:51:47.247689 | 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-05-11 17:51:47.247930 | instance | self.install_collection( 2026-05-11 17:51:47.248058 | 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-05-11 17:51:47.248268 | instance | raise InvalidPrerequisiteError(msg) 2026-05-11 17:51:47.250017 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv community.crypto:2.2.3, returned 250 code: 2026-05-11 17:51:47.250059 | instance | Starting galaxy collection install process 2026-05-11 17:51:47.250069 | instance | Process install dependency map 2026-05-11 17:51:47.250078 | instance | the full traceback was: 2026-05-11 17:51:47.250088 | instance | 2026-05-11 17:51:47.250097 | instance | Traceback (most recent call last): 2026-05-11 17:51:47.250107 | 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-05-11 17:51:47.250116 | instance | exit_code = cli.run() 2026-05-11 17:51:47.250125 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-05-11 17:51:47.250134 | instance | return context.CLIARGS['func']() 2026-05-11 17:51:47.250143 | 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-05-11 17:51:47.250152 | instance | return wrapped_method(*args, **kwargs) 2026-05-11 17:51:47.250161 | 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-05-11 17:51:47.250170 | instance | self._execute_install_collection( 2026-05-11 17:51:47.250178 | 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-05-11 17:51:47.250187 | instance | install_collections( 2026-05-11 17:51:47.250196 | 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-05-11 17:51:47.250205 | instance | dependency_map = _resolve_depenency_map( 2026-05-11 17:51:47.250214 | 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-05-11 17:51:47.250225 | instance | return collection_dep_resolver.resolve( 2026-05-11 17:51:47.250235 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-05-11 17:51:47.250245 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-05-11 17:51:47.250254 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 427, in resolve 2026-05-11 17:51:47.250264 | instance | failure_causes = self._attempt_to_pin_criterion(name) 2026-05-11 17:51:47.250273 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 239, in _attempt_to_pin_criterion 2026-05-11 17:51:47.250282 | instance | criteria = self._get_updated_criteria(candidate) 2026-05-11 17:51:47.250292 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 229, in _get_updated_criteria 2026-05-11 17:51:47.250302 | instance | for requirement in self._p.get_dependencies(candidate=candidate): 2026-05-11 17:51:47.250311 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 441, in get_dependencies 2026-05-11 17:51:47.250330 | instance | req_map = self._api_proxy.get_collection_dependencies(candidate) 2026-05-11 17:51:47.250338 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 176, in get_collection_dependencies 2026-05-11 17:51:47.250348 | instance | get_collection_version_metadata(collection_candidate). 2026-05-11 17:51:47.250356 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 161, in get_collection_version_metadata 2026-05-11 17:51:47.250366 | instance | raise last_err 2026-05-11 17:51:47.250375 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 128, in get_collection_version_metadata 2026-05-11 17:51:47.250384 | instance | version_metadata = api.get_collection_version_metadata( 2026-05-11 17:51:47.250398 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-05-11 17:51:47.250406 | instance | return method(self, *args, **kwargs) 2026-05-11 17:51:47.250415 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 816, in get_collection_version_metadata 2026-05-11 17:51:47.250424 | instance | data = self._call_galaxy(n_collection_url, error_context_msg=error_context_msg, cache=True) 2026-05-11 17:51:47.250433 | 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-05-11 17:51:47.250442 | instance | return call_retryable_function() 2026-05-11 17:51:47.250450 | 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-05-11 17:51:47.250459 | instance | res = path_cache['results'] 2026-05-11 17:51:47.250476 | instance | KeyError: 'results' 2026-05-11 17:51:47.250505 | instance | 2026-05-11 17:51:47.250514 | instance | ansible-galaxy [core 2.17.12] 2026-05-11 17:51:47.250523 | instance | config file = None 2026-05-11 17:51:47.250532 | 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-05-11 17:51:47.250541 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-05-11 17:51:47.250551 | 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-05-11 17:51:47.250562 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-05-11 17:51:47.250571 | 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-05-11 17:51:47.250580 | instance | jinja version = 3.1.6 2026-05-11 17:51:47.250589 | instance | libyaml = True 2026-05-11 17:51:47.250597 | instance | No config file found; using defaults 2026-05-11 17:51:47.250606 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-05-11 17:51:47.250615 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-05-11 17:51:47.250624 | instance | 2.17.12 2026-05-11 17:51:47.250634 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-05-11 17:51:47.250642 | instance | Found installed collection ansible.utils:6.0.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-05-11 17:51:47.250651 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-05-11 17:51:47.250666 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-05-11 17:51:47.250675 | instance | unexpected error when getting available versions of collection 2026-05-11 17:51:47.250684 | instance | community.crypto: 'results' 2026-05-11 17:51:47.250692 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-05-11 17:51:47.250701 | instance | 2026-05-11 17:51:47.551204 | instance | ERROR 2026-05-11 17:51:47.551454 | instance | { 2026-05-11 17:51:47.551521 | instance | "delta": "0:00:14.477661", 2026-05-11 17:51:47.551568 | instance | "end": "2026-05-11 17:51:47.323142", 2026-05-11 17:51:47.551611 | instance | "msg": "non-zero return code", 2026-05-11 17:51:47.551652 | instance | "rc": 1, 2026-05-11 17:51:47.551846 | instance | "start": "2026-05-11 17:51:32.845481" 2026-05-11 17:51:47.551910 | instance | } failure 2026-05-11 17:51:47.554895 | 2026-05-11 17:51:47.554939 | PLAY RECAP 2026-05-11 17:51:47.554984 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-05-11 17:51:47.555005 | 2026-05-11 17:51:47.694580 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-11 17:51:47.698295 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-05-11 17:51:48.295967 | 2026-05-11 17:51:48.296111 | PLAY [all] 2026-05-11 17:51:48.310293 | 2026-05-11 17:51:48.310371 | TASK [gather-host-logs : creating directory for system status] 2026-05-11 17:51:48.649889 | instance | changed 2026-05-11 17:51:48.656325 | 2026-05-11 17:51:48.656425 | TASK [gather-host-logs : Get logs for each host] 2026-05-11 17:51:49.010859 | instance | + systemd-cgls --full --all --no-pager 2026-05-11 17:51:49.021888 | instance | + ip addr 2026-05-11 17:51:49.023531 | instance | + ip route 2026-05-11 17:51:49.024845 | instance | + lsblk 2026-05-11 17:51:49.027374 | instance | + mount 2026-05-11 17:51:49.028624 | instance | + docker images 2026-05-11 17:51:49.028938 | instance | /bin/bash: line 7: docker: command not found 2026-05-11 17:51:49.029112 | instance | + brctl show 2026-05-11 17:51:49.029418 | instance | /bin/bash: line 8: brctl: command not found 2026-05-11 17:51:49.029569 | instance | + ps aux --sort=-%mem 2026-05-11 17:51:49.040801 | instance | + dpkg -l 2026-05-11 17:51:49.047094 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-05-11 17:51:49.047419 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-05-11 17:51:49.047462 | instance | /bin/bash: line 11: docker: command not found 2026-05-11 17:51:49.047751 | instance | + '[' '!' -z '' ']' 2026-05-11 17:51:49.193713 | instance | ok: Runtime: 0:00:00.041676 2026-05-11 17:51:49.201194 | 2026-05-11 17:51:49.201267 | TASK [gather-host-logs : Downloads logs to executor] 2026-05-11 17:51:49.854199 | instance | changed: 2026-05-11 17:51:49.854404 | instance | created directory /var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/logs/instance 2026-05-11 17:51:49.854447 | instance | cd+++++++++ system/ 2026-05-11 17:51:49.854469 | instance | >f+++++++++ system/brctl-show.txt 2026-05-11 17:51:49.854489 | instance | >f+++++++++ system/docker-images.txt 2026-05-11 17:51:49.854508 | instance | >f+++++++++ system/ip-addr.txt 2026-05-11 17:51:49.854528 | instance | >f+++++++++ system/ip-route.txt 2026-05-11 17:51:49.854548 | instance | >f+++++++++ system/lsblk.txt 2026-05-11 17:51:49.854567 | instance | >f+++++++++ system/mount.txt 2026-05-11 17:51:49.854587 | instance | >f+++++++++ system/packages.txt 2026-05-11 17:51:49.854606 | instance | >f+++++++++ system/ps.txt 2026-05-11 17:51:49.854624 | instance | >f+++++++++ system/systemd-cgls.txt 2026-05-11 17:51:49.864312 | 2026-05-11 17:51:49.864387 | LOOP [helm-release-status : creating directory for helm release status] 2026-05-11 17:51:50.060926 | instance | changed: "values" 2026-05-11 17:51:50.241073 | instance | changed: "releases" 2026-05-11 17:51:50.259715 | 2026-05-11 17:51:50.259898 | TASK [helm-release-status : Gather get release status for helm charts] 2026-05-11 17:51:50.476586 | instance | /bin/bash: line 3: kubectl: command not found 2026-05-11 17:51:50.800186 | instance | ok: Runtime: 0:00:00.005891 2026-05-11 17:51:50.806249 | 2026-05-11 17:51:50.806323 | TASK [helm-release-status : Downloads logs to executor] 2026-05-11 17:51:51.299331 | instance | changed: 2026-05-11 17:51:51.299585 | instance | cd+++++++++ helm/ 2026-05-11 17:51:51.299625 | instance | cd+++++++++ helm/releases/ 2026-05-11 17:51:51.299658 | instance | cd+++++++++ helm/values/ 2026-05-11 17:51:51.312678 | 2026-05-11 17:51:51.312849 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-05-11 17:51:51.517955 | instance | changed 2026-05-11 17:51:51.524640 | 2026-05-11 17:51:51.524728 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-05-11 17:51:51.736786 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-11 17:51:51.737062 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-11 17:51:51.742162 | instance | environment: line 1: kubectl: command not found 2026-05-11 17:51:51.743634 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-11 17:51:51.745138 | instance | environment: line 1: kubectl: command not found 2026-05-11 17:51:51.746403 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-11 17:51:51.746484 | instance | environment: line 1: kubectl: command not found 2026-05-11 17:51:51.748125 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-11 17:51:51.748946 | instance | environment: line 1: kubectl: command not found 2026-05-11 17:51:51.750296 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-11 17:51:51.751281 | instance | environment: line 1: kubectl: command not found 2026-05-11 17:51:51.752099 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-05-11 17:51:52.069927 | instance | ok: Runtime: 0:00:00.024431 2026-05-11 17:51:52.115728 | 2026-05-11 17:51:52.115904 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-05-11 17:51:52.303576 | instance | changed 2026-05-11 17:51:52.310714 | 2026-05-11 17:51:52.310816 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-05-11 17:51:52.520796 | instance | environment: line 5: kubectl: command not found 2026-05-11 17:51:52.522252 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-11 17:51:52.522394 | instance | xargs: xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-11 17:51:52.522430 | instance | warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-05-11 17:51:52.854881 | instance | ok: Runtime: 0:00:00.009956 2026-05-11 17:51:52.860262 | 2026-05-11 17:51:52.860336 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-05-11 17:51:53.339846 | instance | changed: 2026-05-11 17:51:53.340076 | instance | cd+++++++++ objects/ 2026-05-11 17:51:53.340115 | instance | cd+++++++++ objects/cluster/ 2026-05-11 17:51:53.340147 | instance | cd+++++++++ objects/namespaced/ 2026-05-11 17:51:53.350981 | 2026-05-11 17:51:53.351056 | TASK [gather-pod-logs : creating directory for pod logs] 2026-05-11 17:51:53.536133 | instance | changed 2026-05-11 17:51:53.542286 | 2026-05-11 17:51:53.542354 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-05-11 17:51:53.738364 | instance | changed 2026-05-11 17:51:53.743331 | 2026-05-11 17:51:53.743402 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-05-11 17:51:53.953185 | instance | environment: line 3: kubectl: command not found 2026-05-11 17:51:54.280899 | instance | ok: Runtime: 0:00:00.008191 2026-05-11 17:51:54.287574 | 2026-05-11 17:51:54.287645 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-05-11 17:51:54.779446 | instance | changed: 2026-05-11 17:51:54.779650 | instance | cd+++++++++ pod-logs/ 2026-05-11 17:51:54.779679 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-05-11 17:51:54.788628 | 2026-05-11 17:51:54.788697 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-05-11 17:51:54.994224 | instance | changed 2026-05-11 17:51:55.000706 | 2026-05-11 17:51:55.000775 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-05-11 17:51:55.197811 | instance | /bin/bash: line 2: kubectl: command not found 2026-05-11 17:51:55.534606 | instance | ok: Runtime: 0:00:00.038497 2026-05-11 17:51:55.542016 | 2026-05-11 17:51:55.542124 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-05-11 17:51:55.762076 | instance | /bin/bash: line 2: kubectl: command not found 2026-05-11 17:51:55.791690 | instance | ceph-mgr endpoints: 2026-05-11 17:51:56.090724 | instance | ok: Runtime: 0:00:00.036153 2026-05-11 17:51:56.097223 | 2026-05-11 17:51:56.097294 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-05-11 17:51:56.305957 | instance | /bin/bash: line 4: kubectl: command not found 2026-05-11 17:51:56.633651 | instance | ok: Runtime: 0:00:00.037203 2026-05-11 17:51:56.640731 | 2026-05-11 17:51:56.640799 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-05-11 17:51:57.146610 | instance | changed: cd+++++++++ prometheus/ 2026-05-11 17:51:57.158428 | 2026-05-11 17:51:57.161095 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-05-11 17:51:57.361971 | instance | changed 2026-05-11 17:51:57.366892 | 2026-05-11 17:51:57.366961 | TASK [gather-selenium-data : Get selenium data] 2026-05-11 17:51:57.639409 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-05-11 17:51:57.641083 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-05-11 17:51:57.949943 | instance | ERROR 2026-05-11 17:51:57.950146 | instance | { 2026-05-11 17:51:57.950178 | instance | "delta": "0:00:00.006094", 2026-05-11 17:51:57.950200 | instance | "end": "2026-05-11 17:51:57.641396", 2026-05-11 17:51:57.950220 | instance | "msg": "non-zero return code", 2026-05-11 17:51:57.950238 | instance | "rc": 1, 2026-05-11 17:51:57.950256 | instance | "start": "2026-05-11 17:51:57.635302" 2026-05-11 17:51:57.950274 | instance | } 2026-05-11 17:51:57.950298 | instance | ERROR: Ignoring Errors 2026-05-11 17:51:57.955292 | 2026-05-11 17:51:57.955352 | TASK [gather-selenium-data : Downloads logs to executor] 2026-05-11 17:51:58.463345 | instance | changed: cd+++++++++ selenium/ 2026-05-11 17:51:58.469321 | 2026-05-11 17:51:58.469373 | PLAY RECAP 2026-05-11 17:51:58.469420 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-05-11 17:51:58.469441 | 2026-05-11 17:51:58.596606 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-05-11 17:51:58.598760 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 17:51:59.235611 | 2026-05-11 17:51:59.235752 | PLAY [all] 2026-05-11 17:51:59.247686 | 2026-05-11 17:51:59.247768 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-11 17:51:59.290390 | instance | skipping: Conditional result was False 2026-05-11 17:51:59.299131 | 2026-05-11 17:51:59.299226 | TASK [fetch-output : Set log path for single node] 2026-05-11 17:51:59.328620 | instance | ok 2026-05-11 17:51:59.356294 | 2026-05-11 17:51:59.356388 | LOOP [fetch-output : Ensure local output dirs] 2026-05-11 17:51:59.789569 | instance -> localhost | ok: "/var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/logs" 2026-05-11 17:52:00.012939 | instance -> localhost | changed: "/var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/artifacts" 2026-05-11 17:52:00.226613 | instance -> localhost | changed: "/var/lib/zuul/builds/2ec21f8bb4f1476ab5588faaae5472e9/work/docs" 2026-05-11 17:52:00.245010 | 2026-05-11 17:52:00.245112 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 17:52:00.874796 | instance | changed: .d..t...... ./ 2026-05-11 17:52:00.875020 | instance | changed: All items complete 2026-05-11 17:52:00.875048 | 2026-05-11 17:52:01.318611 | instance | changed: .d..t...... ./ 2026-05-11 17:52:01.762603 | instance | changed: .d..t...... ./ 2026-05-11 17:52:01.780793 | 2026-05-11 17:52:01.780999 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 17:52:02.238572 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.031424 2026-05-11 17:52:02.474273 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008213 2026-05-11 17:52:02.489975 | 2026-05-11 17:52:02.490195 | PLAY [all] 2026-05-11 17:52:02.496406 | 2026-05-11 17:52:02.496473 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 17:52:02.921088 | instance | changed 2026-05-11 17:52:02.927753 | 2026-05-11 17:52:02.927798 | PLAY RECAP 2026-05-11 17:52:02.927845 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 17:52:02.927867 | 2026-05-11 17:52:03.065743 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 17:52:03.070080 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 17:52:03.641648 | 2026-05-11 17:52:03.641801 | PLAY [localhost] 2026-05-11 17:52:03.652162 | 2026-05-11 17:52:03.652235 | TASK [Generate Zuul manifest] 2026-05-11 17:52:03.675127 | localhost | ok 2026-05-11 17:52:03.692224 | 2026-05-11 17:52:03.692304 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 17:52:04.068962 | localhost | changed 2026-05-11 17:52:04.082072 | 2026-05-11 17:52:04.082158 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 17:52:04.113674 | localhost | ok 2026-05-11 17:52:04.121928 | 2026-05-11 17:52:04.121999 | TASK [Upload logs] 2026-05-11 17:52:04.145246 | localhost | ok 2026-05-11 17:52:04.210193 | 2026-05-11 17:52:04.210363 | TASK [Set zuul-log-path fact] 2026-05-11 17:52:04.232847 | localhost | ok 2026-05-11 17:52:04.246507 | 2026-05-11 17:52:04.246585 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 17:52:04.278796 | localhost | ok 2026-05-11 17:52:04.287088 | 2026-05-11 17:52:04.287156 | TASK [upload-logs : Create log directories] 2026-05-11 17:52:04.709699 | localhost | changed 2026-05-11 17:52:04.714773 | 2026-05-11 17:52:04.714846 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 17:52:05.137374 | localhost -> localhost | ok: Runtime: 0:00:00.006713 2026-05-11 17:52:05.150572 | 2026-05-11 17:52:05.151209 | TASK [upload-logs : Upload logs to log server] 2026-05-11 17:52:05.654642 | localhost | Output suppressed because no_log was given 2026-05-11 17:52:05.660143 | 2026-05-11 17:52:05.660256 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 17:52:05.751144 | localhost | skipping: Conditional result was False 2026-05-11 17:52:05.758212 | localhost | skipping: Conditional result was False 2026-05-11 17:52:05.777464 | 2026-05-11 17:52:05.777670 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 17:52:05.866111 | localhost | skipping: Conditional result was False 2026-05-11 17:52:05.866487 | 2026-05-11 17:52:05.870868 | localhost | skipping: Conditional result was False 2026-05-11 17:52:05.927021 | 2026-05-11 17:52:05.927206 | LOOP [upload-logs : Upload console log and json output]