2026-03-05 04:50:25.599431 | Job console starting 2026-03-05 04:50:25.641757 | Updating git repos 2026-03-05 04:50:25.697416 | Cloning repos into workspace 2026-03-05 04:50:25.829767 | Restoring repo states 2026-03-05 04:50:25.855196 | Merging changes 2026-03-05 04:50:27.471870 | Checking out repos 2026-03-05 04:50:27.853106 | Preparing playbooks 2026-03-05 04:50:37.214369 | Running Ansible setup 2026-03-05 04:50:40.818230 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 04:50:41.463247 | 2026-03-05 04:50:41.463995 | PLAY [localhost] 2026-03-05 04:50:41.474687 | 2026-03-05 04:50:41.474798 | TASK [Gathering Facts] 2026-03-05 04:50:42.410081 | localhost | ok 2026-03-05 04:50:42.419735 | 2026-03-05 04:50:42.419863 | TASK [Setup log path fact] 2026-03-05 04:50:42.442801 | localhost | ok 2026-03-05 04:50:42.458336 | 2026-03-05 04:50:42.458405 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 04:50:42.490275 | localhost | ok 2026-03-05 04:50:42.497660 | 2026-03-05 04:50:42.497731 | TASK [emit-job-header : Print job information] 2026-03-05 04:50:42.544595 | # Job Information 2026-03-05 04:50:42.545139 | Ansible Version: 2.16.16 2026-03-05 04:50:42.545185 | Job: atmosphere-molecule-csi-rbd 2026-03-05 04:50:42.545216 | Pipeline: check 2026-03-05 04:50:42.545245 | Executor: 0a8996d2b663 2026-03-05 04:50:42.545273 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3501 2026-03-05 04:50:42.545304 | Event ID: b5de5a80-184e-11f1-80a4-6982b447e2f7 2026-03-05 04:50:42.551167 | 2026-03-05 04:50:42.551235 | LOOP [emit-job-header : Print node information] 2026-03-05 04:50:42.657282 | localhost | ok: 2026-03-05 04:50:42.657583 | localhost | # Node Information 2026-03-05 04:50:42.657655 | localhost | Inventory Hostname: instance 2026-03-05 04:50:42.657712 | localhost | Hostname: np0000157828 2026-03-05 04:50:42.657765 | localhost | Username: zuul 2026-03-05 04:50:42.657828 | localhost | Distro: Ubuntu 22.04 2026-03-05 04:50:42.657877 | localhost | Provider: yul1 2026-03-05 04:50:42.657928 | localhost | Region: ca-ymq-1 2026-03-05 04:50:42.657978 | localhost | Label: ubuntu-jammy 2026-03-05 04:50:42.658028 | localhost | Product Name: OpenStack Nova 2026-03-05 04:50:42.658077 | localhost | Interface IP: 162.253.55.52 2026-03-05 04:50:42.670049 | 2026-03-05 04:50:42.670218 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 04:50:43.068113 | localhost -> localhost | changed 2026-03-05 04:50:43.073731 | 2026-03-05 04:50:43.073862 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 04:50:43.987530 | localhost -> localhost | changed 2026-03-05 04:50:43.997492 | 2026-03-05 04:50:43.997591 | PLAY [all] 2026-03-05 04:50:44.007381 | 2026-03-05 04:50:44.007453 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 04:50:44.225515 | instance -> localhost | ok 2026-03-05 04:50:44.235701 | 2026-03-05 04:50:44.235804 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 04:50:44.270612 | instance | ok 2026-03-05 04:50:44.284887 | instance | included: /var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 04:50:44.290513 | 2026-03-05 04:50:44.290577 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 04:50:45.670364 | instance -> localhost | Generating public/private rsa key pair. 2026-03-05 04:50:45.670559 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/6978583d5284456c9420bbaf910f5aae_id_rsa 2026-03-05 04:50:45.670589 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/6978583d5284456c9420bbaf910f5aae_id_rsa.pub 2026-03-05 04:50:45.670613 | instance -> localhost | The key fingerprint is: 2026-03-05 04:50:45.670635 | instance -> localhost | SHA256:I2ghpcoZbtqtsUcwIIJjIeidImFCpVxqGlyWK3dM1wU zuul-build-sshkey 2026-03-05 04:50:45.670669 | instance -> localhost | The key's randomart image is: 2026-03-05 04:50:45.670692 | instance -> localhost | +---[RSA 3072]----+ 2026-03-05 04:50:45.670720 | instance -> localhost | |=oo=o .Eo. | 2026-03-05 04:50:45.670742 | instance -> localhost | |&o*+ . . . | 2026-03-05 04:50:45.670762 | instance -> localhost | |O@+ * . | 2026-03-05 04:50:45.670783 | instance -> localhost | |**=* = | 2026-03-05 04:50:45.670803 | instance -> localhost | |+=+o+ . S | 2026-03-05 04:50:45.670823 | instance -> localhost | |o. o. . . | 2026-03-05 04:50:45.670843 | instance -> localhost | |. o.. | 2026-03-05 04:50:45.670863 | instance -> localhost | | +. | 2026-03-05 04:50:45.670887 | instance -> localhost | | o. | 2026-03-05 04:50:45.670908 | instance -> localhost | +----[SHA256]-----+ 2026-03-05 04:50:45.670963 | instance -> localhost | ok: Runtime: 0:00:00.948646 2026-03-05 04:50:45.682371 | 2026-03-05 04:50:45.682466 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 04:50:45.717478 | instance | ok 2026-03-05 04:50:45.728052 | instance | included: /var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 04:50:45.735455 | 2026-03-05 04:50:45.735522 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 04:50:45.761557 | instance | skipping: Conditional result was False 2026-03-05 04:50:45.771360 | 2026-03-05 04:50:45.771462 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 04:50:46.241031 | instance | changed 2026-03-05 04:50:46.246141 | 2026-03-05 04:50:46.246210 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 04:50:46.433377 | instance | ok 2026-03-05 04:50:46.438611 | 2026-03-05 04:50:46.438715 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 04:50:46.906339 | instance | changed 2026-03-05 04:50:46.911628 | 2026-03-05 04:50:46.911697 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 04:50:47.352753 | instance | changed 2026-03-05 04:50:47.358245 | 2026-03-05 04:50:47.358315 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 04:50:47.384026 | instance | skipping: Conditional result was False 2026-03-05 04:50:47.394696 | 2026-03-05 04:50:47.394764 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 04:50:47.765633 | instance -> localhost | changed 2026-03-05 04:50:47.777585 | 2026-03-05 04:50:47.777661 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 04:50:48.086428 | instance -> localhost | Identity added: /var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/6978583d5284456c9420bbaf910f5aae_id_rsa (zuul-build-sshkey) 2026-03-05 04:50:48.086692 | instance -> localhost | ok: Runtime: 0:00:00.012815 2026-03-05 04:50:48.096217 | 2026-03-05 04:50:48.096375 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 04:50:48.412358 | instance | ok 2026-03-05 04:50:48.417197 | 2026-03-05 04:50:48.417277 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 04:50:48.442460 | instance | skipping: Conditional result was False 2026-03-05 04:50:48.452663 | 2026-03-05 04:50:48.452735 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 04:50:48.768358 | instance | ok 2026-03-05 04:50:48.777044 | 2026-03-05 04:50:48.777147 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 04:50:50.434395 | instance | Output suppressed because no_log was given 2026-03-05 04:50:50.445763 | 2026-03-05 04:50:50.445841 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 04:50:50.639591 | instance | ok: "logs" 2026-03-05 04:50:50.639913 | instance | ok: All items complete 2026-03-05 04:50:50.639953 | 2026-03-05 04:50:50.788305 | instance | ok: "artifacts" 2026-03-05 04:50:50.943106 | instance | ok: "docs" 2026-03-05 04:50:50.962074 | 2026-03-05 04:50:50.962259 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 04:50:51.141998 | instance | changed: "logs" 2026-03-05 04:50:51.294518 | instance | changed: "artifacts" 2026-03-05 04:50:51.453841 | instance | changed: "docs" 2026-03-05 04:50:51.473009 | 2026-03-05 04:50:51.473161 | PLAY RECAP 2026-03-05 04:50:51.473214 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-05 04:50:51.473245 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 04:50:51.473268 | 2026-03-05 04:50:51.627814 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 04:50:51.631780 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 04:50:52.185528 | 2026-03-05 04:50:52.185667 | PLAY [all] 2026-03-05 04:50:52.196485 | 2026-03-05 04:50:52.196568 | TASK [setup-uv : Extract archive] 2026-03-05 04:50:54.375379 | instance | changed 2026-03-05 04:50:54.381667 | 2026-03-05 04:50:54.381740 | TASK [setup-uv : Print version] 2026-03-05 04:50:54.653956 | instance | uv 0.8.13 2026-03-05 04:50:54.913821 | instance | ok: Runtime: 0:00:00.011989 2026-03-05 04:50:54.922547 | 2026-03-05 04:50:54.922595 | PLAY RECAP 2026-03-05 04:50:54.922641 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 04:50:54.922667 | 2026-03-05 04:50:55.025261 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 04:50:55.029390 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-03-05 04:50:55.568049 | 2026-03-05 04:50:55.568168 | PLAY [all] 2026-03-05 04:50:55.578989 | 2026-03-05 04:50:55.579058 | TASK [Install "jq" for log collection] 2026-03-05 04:51:05.112072 | instance | changed 2026-03-05 04:51:05.114112 | 2026-03-05 04:51:05.114167 | PLAY RECAP 2026-03-05 04:51:05.114218 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 04:51:05.114266 | 2026-03-05 04:51:05.211850 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-03-05 04:51:05.213841 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 04:51:05.797084 | 2026-03-05 04:51:05.797255 | PLAY [all] 2026-03-05 04:51:05.808807 | 2026-03-05 04:51:05.808882 | TASK [Copy inventory file for Zuul] 2026-03-05 04:51:06.655105 | instance | changed 2026-03-05 04:51:06.663307 | 2026-03-05 04:51:06.663415 | TASK [Switch "ansible_host" to private IP] 2026-03-05 04:51:06.931876 | instance | changed: 1 replacements made 2026-03-05 04:51:06.939447 | 2026-03-05 04:51:06.939531 | TASK [Run Molecule scenario] 2026-03-05 04:51:07.342795 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-03-05 04:51:07.342966 | instance | Creating virtual environment at: .venv 2026-03-05 04:51:07.363341 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-03-05 04:51:07.412830 | instance | Downloading netaddr (2.2MiB) 2026-03-05 04:51:07.458789 | instance | Downloading ansible-core (2.1MiB) 2026-03-05 04:51:07.458954 | instance | Downloading rjsonnet (1.2MiB) 2026-03-05 04:51:07.464380 | instance | Downloading setuptools (1.1MiB) 2026-03-05 04:51:07.465025 | instance | Downloading pygments (1.2MiB) 2026-03-05 04:51:07.465732 | instance | Downloading kubernetes (1.9MiB) 2026-03-05 04:51:07.466533 | instance | Downloading pydantic-core (2.0MiB) 2026-03-05 04:51:07.469296 | instance | Downloading cryptography (4.2MiB) 2026-03-05 04:51:07.469518 | instance | Downloading openstacksdk (1.7MiB) 2026-03-05 04:51:07.745375 | instance | Building pyperclip==1.9.0 2026-03-05 04:51:07.772366 | instance | Downloading rjsonnet 2026-03-05 04:51:07.865608 | instance | Downloading pydantic-core 2026-03-05 04:51:07.867997 | instance | Downloading pygments 2026-03-05 04:51:07.903517 | instance | Downloading netaddr 2026-03-05 04:51:07.929101 | instance | Downloading cryptography 2026-03-05 04:51:07.937154 | instance | Downloading setuptools 2026-03-05 04:51:07.988468 | instance | Downloading kubernetes 2026-03-05 04:51:08.013060 | instance | Downloading ansible-core 2026-03-05 04:51:08.046002 | instance | Downloading openstacksdk 2026-03-05 04:51:08.449803 | instance | Built pyperclip==1.9.0 2026-03-05 04:51:08.703329 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-03-05 04:51:08.748327 | instance | Installed 83 packages in 43ms 2026-03-05 04:51:09.387185 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-03-05 04:51:10.018087 | instance | INFO [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy 2026-03-05 04:51:10.018372 | instance | INFO [csi > prerun] Performing prerun with role_name_check=0... 2026-03-05 04:51:37.787021 | instance | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv atmosphere.common:>=0.6.0 2026-03-05 04:51:37.787175 | instance | ERROR Command ansible-galaxy collection install -vvv atmosphere.common:>=0.6.0, returned 250 code: 2026-03-05 04:51:37.787188 | instance | Starting galaxy collection install process 2026-03-05 04:51:37.787198 | instance | Process install dependency map 2026-03-05 04:51:37.787208 | instance | the full traceback was: 2026-03-05 04:51:37.787218 | instance | 2026-03-05 04:51:37.787227 | instance | Traceback (most recent call last): 2026-03-05 04:51:37.787237 | 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-03-05 04:51:37.787248 | instance | exit_code = cli.run() 2026-03-05 04:51:37.787257 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-03-05 04:51:37.787267 | instance | return context.CLIARGS() 2026-03-05 04:51:37.787276 | 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-03-05 04:51:37.787286 | instance | return wrapped_method(*args, **kwargs) 2026-03-05 04:51:37.787295 | 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-03-05 04:51:37.787304 | instance | self._execute_install_collection( 2026-03-05 04:51:37.787314 | 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-03-05 04:51:37.787324 | instance | install_collections( 2026-03-05 04:51:37.787333 | 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-03-05 04:51:37.787343 | instance | dependency_map = _resolve_depenency_map( 2026-03-05 04:51:37.787352 | 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-03-05 04:51:37.787389 | instance | return collection_dep_resolver.resolve( 2026-03-05 04:51:37.787399 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-03-05 04:51:37.787408 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-03-05 04:51:37.787417 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-03-05 04:51:37.787426 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-03-05 04:51:37.787435 | 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-03-05 04:51:37.787444 | instance | matches = self._p.find_matches( 2026-03-05 04:51:37.787454 | 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-03-05 04:51:37.787463 | instance | match for match in self._find_matches(list(requirements)) 2026-03-05 04:51:37.787472 | 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-03-05 04:51:37.787482 | instance | coll_versions = if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable] 2026-03-05 04:51:37.787492 | 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-03-05 04:51:37.787501 | instance | return set( 2026-03-05 04:51:37.787510 | 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-03-05 04:51:37.787519 | instance | return set( 2026-03-05 04:51:37.787528 | 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-03-05 04:51:37.787538 | instance | raise last_error 2026-03-05 04:51:37.787560 | 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-03-05 04:51:37.787569 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-03-05 04:51:37.787578 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-03-05 04:51:37.787587 | instance | return method(self, *args, **kwargs) 2026-03-05 04:51:37.787596 | 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-03-05 04:51:37.787606 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-03-05 04:51:37.787615 | 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-03-05 04:51:37.787624 | instance | return call_retryable_function() 2026-03-05 04:51:37.787640 | 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-03-05 04:51:37.787650 | instance | res = path_cache 2026-03-05 04:51:37.787659 | instance | KeyError: 'results' 2026-03-05 04:51:37.787668 | instance | 2026-03-05 04:51:37.787677 | instance | ansible-galaxy 2026-03-05 04:51:37.787685 | instance | config file = None 2026-03-05 04:51:37.787695 | instance | configured module search path = 2026-03-05 04:51:37.787704 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-03-05 04:51:37.787714 | 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-03-05 04:51:37.787731 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-03-05 04:51:37.787740 | instance | python version = 3.10.12 (main, Jan 26 2026, 14:55:28) (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-03-05 04:51:37.787750 | instance | jinja version = 3.1.6 2026-03-05 04:51:37.787759 | instance | libyaml = True 2026-03-05 04:51:37.787768 | instance | No config file found; using defaults 2026-03-05 04:51:37.787777 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-03-05 04:51:37.787786 | instance | : Collection ansible.netcommon does not support Ansible version 2026-03-05 04:51:37.787795 | instance | 2.17.12 2026-03-05 04:51:37.787804 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-03-05 04:51:37.787814 | instance | Found installed collection openstack.cloud:2.5.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-03-05 04:51:37.787823 | instance | Found installed collection vexxhost.containers:1.6.4 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-03-05 04:51:37.787832 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-03-05 04:51:37.787841 | instance | Found installed collection vexxhost.ceph:3.2.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-03-05 04:51:37.787851 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-03-05 04:51:37.787860 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-03-05 04:51:37.787869 | instance | Found installed collection ansible.utils:6.0.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-03-05 04:51:37.787878 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-03-05 04:51:37.787887 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-03-05 04:51:37.787896 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-05 04:51:37.787905 | instance | unexpected error when getting available versions of collection 2026-03-05 04:51:37.787914 | instance | atmosphere.common: 'results' 2026-03-05 04:51:37.787924 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-03-05 04:51:37.787933 | instance | 2026-03-05 04:51:37.787945 | instance | Traceback (most recent call last): 2026-03-05 04:51:37.788171 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-03-05 04:51:37.788647 | instance | sys.exit(main()) 2026-03-05 04:51:37.788948 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-03-05 04:51:37.789649 | instance | return self.main(*args, **kwargs) 2026-03-05 04:51:37.789942 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-03-05 04:51:37.790635 | instance | rv = self.invoke(ctx) 2026-03-05 04:51:37.790923 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-03-05 04:51:37.791631 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-03-05 04:51:37.791915 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-03-05 04:51:37.792553 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-03-05 04:51:37.792842 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-03-05 04:51:37.793352 | instance | return callback(*args, **kwargs) 2026-03-05 04:51:37.793640 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-03-05 04:51:37.794141 | instance | return func(*args, **kwargs) 2026-03-05 04:51:37.794474 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func 2026-03-05 04:51:37.794833 | instance | return f(get_current_context(), *args, **kwargs) 2026-03-05 04:51:37.795120 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-03-05 04:51:37.795562 | instance | return func(ctx) 2026-03-05 04:51:37.795831 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test 2026-03-05 04:51:37.796273 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude) 2026-03-05 04:51:37.796561 | 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-03-05 04:51:37.796946 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-03-05 04:51:37.797127 | 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-03-05 04:51:37.797390 | instance | scenario.config.runtime.prepare_environment( 2026-03-05 04:51:37.797568 | 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-03-05 04:51:37.797926 | instance | self.install_collection( 2026-03-05 04:51:37.798124 | 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-03-05 04:51:37.798495 | instance | raise InvalidPrerequisiteError(msg) 2026-03-05 04:51:37.801158 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv atmosphere.common:>=0.6.0, returned 250 code: 2026-03-05 04:51:37.801171 | instance | Starting galaxy collection install process 2026-03-05 04:51:37.801180 | instance | Process install dependency map 2026-03-05 04:51:37.801190 | instance | the full traceback was: 2026-03-05 04:51:37.801199 | instance | 2026-03-05 04:51:37.801208 | instance | Traceback (most recent call last): 2026-03-05 04:51:37.801218 | 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-03-05 04:51:37.801227 | instance | exit_code = cli.run() 2026-03-05 04:51:37.801236 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-03-05 04:51:37.801245 | instance | return context.CLIARGS['func']() 2026-03-05 04:51:37.801254 | 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-03-05 04:51:37.801263 | instance | return wrapped_method(*args, **kwargs) 2026-03-05 04:51:37.801273 | 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-03-05 04:51:37.801282 | instance | self._execute_install_collection( 2026-03-05 04:51:37.801291 | 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-03-05 04:51:37.801300 | instance | install_collections( 2026-03-05 04:51:37.801309 | 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-03-05 04:51:37.801318 | instance | dependency_map = _resolve_depenency_map( 2026-03-05 04:51:37.801328 | 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-03-05 04:51:37.801346 | instance | return collection_dep_resolver.resolve( 2026-03-05 04:51:37.801355 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-03-05 04:51:37.801365 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-03-05 04:51:37.801374 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-03-05 04:51:37.801383 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-03-05 04:51:37.801392 | 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-03-05 04:51:37.801401 | instance | matches = self._p.find_matches( 2026-03-05 04:51:37.801410 | 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-03-05 04:51:37.801425 | instance | match for match in self._find_matches(list(requirements[identifier])) 2026-03-05 04:51:37.801435 | 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-03-05 04:51:37.801444 | instance | coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable[t.Tuple[str, GalaxyAPI]] 2026-03-05 04:51:37.801453 | 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-03-05 04:51:37.801463 | instance | return set( 2026-03-05 04:51:37.801472 | 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-03-05 04:51:37.801482 | instance | return set( 2026-03-05 04:51:37.801491 | 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-03-05 04:51:37.801501 | instance | raise last_error 2026-03-05 04:51:37.801510 | 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-03-05 04:51:37.801519 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-03-05 04:51:37.801528 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-03-05 04:51:37.801537 | instance | return method(self, *args, **kwargs) 2026-03-05 04:51:37.801546 | 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-03-05 04:51:37.801556 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-03-05 04:51:37.801571 | 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-03-05 04:51:37.801581 | instance | return call_retryable_function() 2026-03-05 04:51:37.801590 | 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-03-05 04:51:37.801599 | instance | res = path_cache['results'] 2026-03-05 04:51:37.801608 | instance | KeyError: 'results' 2026-03-05 04:51:37.801617 | instance | 2026-03-05 04:51:37.801626 | instance | ansible-galaxy [core 2.17.12] 2026-03-05 04:51:37.801635 | instance | config file = None 2026-03-05 04:51:37.801645 | 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-03-05 04:51:37.801654 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-03-05 04:51:37.801670 | 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-03-05 04:51:37.801680 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-03-05 04:51:37.801689 | instance | python version = 3.10.12 (main, Jan 26 2026, 14:55:28) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-03-05 04:51:37.801698 | instance | jinja version = 3.1.6 2026-03-05 04:51:37.801708 | instance | libyaml = True 2026-03-05 04:51:37.801717 | instance | No config file found; using defaults 2026-03-05 04:51:37.801726 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-03-05 04:51:37.801735 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-03-05 04:51:37.801744 | instance | 2.17.12 2026-03-05 04:51:37.801753 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-03-05 04:51:37.801762 | instance | Found installed collection openstack.cloud:2.5.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-03-05 04:51:37.801775 | instance | Found installed collection vexxhost.containers:1.6.4 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-03-05 04:51:37.801784 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-03-05 04:51:37.801794 | instance | Found installed collection vexxhost.ceph:3.2.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-03-05 04:51:37.801803 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-03-05 04:51:37.801812 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-03-05 04:51:37.801821 | instance | Found installed collection ansible.utils:6.0.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-03-05 04:51:37.801830 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-03-05 04:51:37.801839 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-03-05 04:51:37.801848 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-05 04:51:37.801857 | instance | unexpected error when getting available versions of collection 2026-03-05 04:51:37.801866 | instance | atmosphere.common: 'results' 2026-03-05 04:51:37.801875 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-03-05 04:51:37.801885 | instance | 2026-03-05 04:51:38.006452 | instance | ERROR 2026-03-05 04:51:38.006760 | instance | { 2026-03-05 04:51:38.006798 | instance | "delta": "0:00:30.601682", 2026-03-05 04:51:38.006825 | instance | "end": "2026-03-05 04:51:37.880210", 2026-03-05 04:51:38.006852 | instance | "msg": "non-zero return code", 2026-03-05 04:51:38.006875 | instance | "rc": 1, 2026-03-05 04:51:38.006900 | instance | "start": "2026-03-05 04:51:07.278528" 2026-03-05 04:51:38.006926 | instance | } failure 2026-03-05 04:51:38.010024 | 2026-03-05 04:51:38.010076 | PLAY RECAP 2026-03-05 04:51:38.010136 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-05 04:51:38.010160 | 2026-03-05 04:51:38.179370 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 04:51:38.186624 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-03-05 04:51:38.813143 | 2026-03-05 04:51:38.813297 | PLAY [all] 2026-03-05 04:51:38.827725 | 2026-03-05 04:51:38.827809 | TASK [gather-host-logs : creating directory for system status] 2026-03-05 04:51:39.186628 | instance | changed 2026-03-05 04:51:39.192128 | 2026-03-05 04:51:39.192210 | TASK [gather-host-logs : Get logs for each host] 2026-03-05 04:51:39.551463 | instance | + systemd-cgls --full --all --no-pager 2026-03-05 04:51:39.564566 | instance | + ip addr 2026-03-05 04:51:39.566195 | instance | + ip route 2026-03-05 04:51:39.567469 | instance | + lsblk 2026-03-05 04:51:39.569770 | instance | + mount 2026-03-05 04:51:39.571261 | instance | + docker images 2026-03-05 04:51:39.571604 | instance | /bin/bash: line 7: docker: command not found 2026-03-05 04:51:39.571860 | instance | + brctl show 2026-03-05 04:51:39.572234 | instance | /bin/bash: line 8: brctl: command not found 2026-03-05 04:51:39.572461 | instance | + ps aux --sort=-%mem 2026-03-05 04:51:39.582928 | instance | + dpkg -l 2026-03-05 04:51:39.589036 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-03-05 04:51:39.589440 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-03-05 04:51:39.589475 | instance | /bin/bash: line 11: docker: command not found 2026-03-05 04:51:39.589740 | instance | + '[' '!' -z '' ']' 2026-03-05 04:51:39.730879 | instance | ok: Runtime: 0:00:00.043864 2026-03-05 04:51:39.740048 | 2026-03-05 04:51:39.740127 | TASK [gather-host-logs : Downloads logs to executor] 2026-03-05 04:51:40.394535 | instance | changed: 2026-03-05 04:51:40.394749 | instance | created directory /var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/logs/instance 2026-03-05 04:51:40.394788 | instance | cd+++++++++ system/ 2026-03-05 04:51:40.394819 | instance | >f+++++++++ system/brctl-show.txt 2026-03-05 04:51:40.394849 | instance | >f+++++++++ system/docker-images.txt 2026-03-05 04:51:40.394876 | instance | >f+++++++++ system/ip-addr.txt 2026-03-05 04:51:40.394906 | instance | >f+++++++++ system/ip-route.txt 2026-03-05 04:51:40.394934 | instance | >f+++++++++ system/lsblk.txt 2026-03-05 04:51:40.394961 | instance | >f+++++++++ system/mount.txt 2026-03-05 04:51:40.394990 | instance | >f+++++++++ system/packages.txt 2026-03-05 04:51:40.395017 | instance | >f+++++++++ system/ps.txt 2026-03-05 04:51:40.395046 | instance | >f+++++++++ system/systemd-cgls.txt 2026-03-05 04:51:40.410706 | 2026-03-05 04:51:40.410785 | LOOP [helm-release-status : creating directory for helm release status] 2026-03-05 04:51:40.607986 | instance | changed: "values" 2026-03-05 04:51:40.779803 | instance | changed: "releases" 2026-03-05 04:51:40.798458 | 2026-03-05 04:51:40.798681 | TASK [helm-release-status : Gather get release status for helm charts] 2026-03-05 04:51:41.014157 | instance | /bin/bash: line 3: kubectl: command not found 2026-03-05 04:51:41.340751 | instance | ok: Runtime: 0:00:00.006823 2026-03-05 04:51:41.346287 | 2026-03-05 04:51:41.346397 | TASK [helm-release-status : Downloads logs to executor] 2026-03-05 04:51:41.835579 | instance | changed: 2026-03-05 04:51:41.835758 | instance | cd+++++++++ helm/ 2026-03-05 04:51:41.835796 | instance | cd+++++++++ helm/releases/ 2026-03-05 04:51:41.835827 | instance | cd+++++++++ helm/values/ 2026-03-05 04:51:41.847743 | 2026-03-05 04:51:41.847816 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-03-05 04:51:42.041324 | instance | changed 2026-03-05 04:51:42.048291 | 2026-03-05 04:51:42.048380 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-03-05 04:51:42.248599 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-05 04:51:42.248854 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-05 04:51:42.255423 | instance | environment: line 1: kubectl: command not found 2026-03-05 04:51:42.256268 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-05 04:51:42.257299 | instance | environment: line 1: kubectl: command not found 2026-03-05 04:51:42.258525 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-05 04:51:42.260736 | instance | environment: line 1: kubectl: command not found 2026-03-05 04:51:42.261855 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-05 04:51:42.263270 | instance | environment: line 1: kubectl: command not found 2026-03-05 04:51:42.264876 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-05 04:51:42.265817 | instance | environment: line 1: kubectl: command not found 2026-03-05 04:51:42.267380 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-03-05 04:51:42.584223 | instance | ok: Runtime: 0:00:00.027253 2026-03-05 04:51:42.591110 | 2026-03-05 04:51:42.591198 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-03-05 04:51:42.806921 | instance | changed 2026-03-05 04:51:42.813613 | 2026-03-05 04:51:42.813704 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-03-05 04:51:43.042035 | instance | environment: line 5: kubectl: command not found 2026-03-05 04:51:43.042940 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-05 04:51:43.043077 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-05 04:51:43.043245 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-03-05 04:51:43.399421 | instance | ok: Runtime: 0:00:00.010676 2026-03-05 04:51:43.407651 | 2026-03-05 04:51:43.407782 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-03-05 04:51:43.886570 | instance | changed: 2026-03-05 04:51:43.886728 | instance | cd+++++++++ objects/ 2026-03-05 04:51:43.886756 | instance | cd+++++++++ objects/cluster/ 2026-03-05 04:51:43.886778 | instance | cd+++++++++ objects/namespaced/ 2026-03-05 04:51:43.895439 | 2026-03-05 04:51:43.895503 | TASK [gather-pod-logs : creating directory for pod logs] 2026-03-05 04:51:44.100230 | instance | changed 2026-03-05 04:51:44.106927 | 2026-03-05 04:51:44.107009 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-03-05 04:51:44.311309 | instance | changed 2026-03-05 04:51:44.318168 | 2026-03-05 04:51:44.318257 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-03-05 04:51:44.515852 | instance | environment: line 3: kubectl: command not found 2026-03-05 04:51:44.853928 | instance | ok: Runtime: 0:00:00.009876 2026-03-05 04:51:44.861128 | 2026-03-05 04:51:44.861221 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-03-05 04:51:45.352202 | instance | changed: 2026-03-05 04:51:45.352393 | instance | cd+++++++++ pod-logs/ 2026-03-05 04:51:45.352430 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-03-05 04:51:45.366955 | 2026-03-05 04:51:45.367021 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-03-05 04:51:45.583164 | instance | changed 2026-03-05 04:51:45.588131 | 2026-03-05 04:51:45.588202 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-03-05 04:51:45.816002 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-05 04:51:46.124150 | instance | ok: Runtime: 0:00:00.046479 2026-03-05 04:51:46.130276 | 2026-03-05 04:51:46.130340 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-03-05 04:51:46.354667 | instance | /bin/bash: line 2: kubectl: command not found 2026-03-05 04:51:46.378310 | instance | ceph-mgr endpoints: 2026-03-05 04:51:46.677993 | instance | ok: Runtime: 0:00:00.031239 2026-03-05 04:51:46.687707 | 2026-03-05 04:51:46.687822 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-03-05 04:51:46.915258 | instance | /bin/bash: line 4: kubectl: command not found 2026-03-05 04:51:47.231515 | instance | ok: Runtime: 0:00:00.039363 2026-03-05 04:51:47.236610 | 2026-03-05 04:51:47.236676 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-03-05 04:51:47.736863 | instance | changed: cd+++++++++ prometheus/ 2026-03-05 04:51:47.748476 | 2026-03-05 04:51:47.748539 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-03-05 04:51:47.955903 | instance | changed 2026-03-05 04:51:47.962611 | 2026-03-05 04:51:47.962699 | TASK [gather-selenium-data : Get selenium data] 2026-03-05 04:51:48.167441 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-03-05 04:51:48.168883 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-03-05 04:51:48.501041 | instance | ERROR 2026-03-05 04:51:48.501405 | instance | { 2026-03-05 04:51:48.501471 | instance | "delta": "0:00:00.006374", 2026-03-05 04:51:48.501518 | instance | "end": "2026-03-05 04:51:48.169258", 2026-03-05 04:51:48.501559 | instance | "msg": "non-zero return code", 2026-03-05 04:51:48.501598 | instance | "rc": 1, 2026-03-05 04:51:48.501637 | instance | "start": "2026-03-05 04:51:48.162884" 2026-03-05 04:51:48.501676 | instance | } 2026-03-05 04:51:48.501725 | instance | ERROR: Ignoring Errors 2026-03-05 04:51:48.508121 | 2026-03-05 04:51:48.508195 | TASK [gather-selenium-data : Downloads logs to executor] 2026-03-05 04:51:49.019275 | instance | changed: cd+++++++++ selenium/ 2026-03-05 04:51:49.025492 | 2026-03-05 04:51:49.025549 | PLAY RECAP 2026-03-05 04:51:49.025597 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-03-05 04:51:49.025618 | 2026-03-05 04:51:49.227147 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-03-05 04:51:49.232201 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 04:51:49.825544 | 2026-03-05 04:51:49.825702 | PLAY [all] 2026-03-05 04:51:49.837812 | 2026-03-05 04:51:49.837923 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 04:51:49.883973 | instance | skipping: Conditional result was False 2026-03-05 04:51:49.894034 | 2026-03-05 04:51:49.894151 | TASK [fetch-output : Set log path for single node] 2026-03-05 04:51:49.939693 | instance | ok 2026-03-05 04:51:49.946595 | 2026-03-05 04:51:49.946709 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 04:51:50.361378 | instance -> localhost | ok: "/var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/logs" 2026-03-05 04:51:50.581824 | instance -> localhost | changed: "/var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/artifacts" 2026-03-05 04:51:50.821938 | instance -> localhost | changed: "/var/lib/zuul/builds/6978583d5284456c9420bbaf910f5aae/work/docs" 2026-03-05 04:51:50.846223 | 2026-03-05 04:51:50.846480 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 04:51:51.462946 | instance | changed: .d..t...... ./ 2026-03-05 04:51:51.463204 | instance | changed: All items complete 2026-03-05 04:51:51.463244 | 2026-03-05 04:51:51.895637 | instance | changed: .d..t...... ./ 2026-03-05 04:51:52.334643 | instance | changed: .d..t...... ./ 2026-03-05 04:51:52.352842 | 2026-03-05 04:51:52.353031 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 04:51:52.755757 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.007836 2026-03-05 04:51:52.973233 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006867 2026-03-05 04:51:52.992531 | 2026-03-05 04:51:52.992700 | PLAY [all] 2026-03-05 04:51:52.999563 | 2026-03-05 04:51:52.999628 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-05 04:51:53.412479 | instance | changed 2026-03-05 04:51:53.418164 | 2026-03-05 04:51:53.418219 | PLAY RECAP 2026-03-05 04:51:53.418270 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-05 04:51:53.418294 | 2026-03-05 04:51:53.556539 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 04:51:53.558894 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-05 04:51:54.114748 | 2026-03-05 04:51:54.114872 | PLAY [localhost] 2026-03-05 04:51:54.124292 | 2026-03-05 04:51:54.124363 | TASK [Generate Zuul manifest] 2026-03-05 04:51:54.146993 | localhost | ok 2026-03-05 04:51:54.163373 | 2026-03-05 04:51:54.163448 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 04:51:54.473435 | localhost | changed 2026-03-05 04:51:54.483154 | 2026-03-05 04:51:54.483226 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 04:51:54.511069 | localhost | ok 2026-03-05 04:51:54.518978 | 2026-03-05 04:51:54.519066 | TASK [Upload logs] 2026-03-05 04:51:54.552522 | localhost | ok 2026-03-05 04:51:54.610260 | 2026-03-05 04:51:54.610341 | TASK [Set zuul-log-path fact] 2026-03-05 04:51:54.631619 | localhost | ok 2026-03-05 04:51:54.645835 | 2026-03-05 04:51:54.645899 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 04:51:54.676514 | localhost | ok 2026-03-05 04:51:54.685475 | 2026-03-05 04:51:54.685558 | TASK [upload-logs : Create log directories] 2026-03-05 04:51:55.091079 | localhost | changed 2026-03-05 04:51:55.097470 | 2026-03-05 04:51:55.097557 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-05 04:51:55.504068 | localhost -> localhost | ok: Runtime: 0:00:00.004898 2026-03-05 04:51:55.510563 | 2026-03-05 04:51:55.510623 | TASK [upload-logs : Upload logs to log server] 2026-03-05 04:51:55.970000 | localhost | Output suppressed because no_log was given 2026-03-05 04:51:55.973939 | 2026-03-05 04:51:55.974002 | LOOP [upload-logs : Compress console log and json output] 2026-03-05 04:51:56.022595 | localhost | skipping: Conditional result was False 2026-03-05 04:51:56.029412 | localhost | skipping: Conditional result was False 2026-03-05 04:51:56.046791 | 2026-03-05 04:51:56.046889 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-05 04:51:56.090091 | localhost | skipping: Conditional result was False 2026-03-05 04:51:56.090586 | 2026-03-05 04:51:56.093534 | localhost | skipping: Conditional result was False 2026-03-05 04:51:56.109385 | 2026-03-05 04:51:56.109538 | LOOP [upload-logs : Upload console log and json output]