2026-04-16 09:06:55.403695 | Job console starting 2026-04-16 09:06:55.418465 | Updating git repos 2026-04-16 09:06:55.477466 | Cloning repos into workspace 2026-04-16 09:07:40.656852 | Restoring repo states 2026-04-16 09:07:40.680569 | Merging changes 2026-04-16 09:07:49.523706 | Checking out repos 2026-04-16 09:07:55.887511 | Preparing playbooks 2026-04-16 09:09:12.301261 | Running Ansible setup 2026-04-16 09:09:16.243172 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-16 09:09:16.892270 | 2026-04-16 09:09:16.892447 | PLAY [localhost] 2026-04-16 09:09:16.901968 | 2026-04-16 09:09:16.902085 | TASK [Gathering Facts] 2026-04-16 09:09:17.789700 | localhost | ok 2026-04-16 09:09:17.801695 | 2026-04-16 09:09:17.801854 | TASK [Setup log path fact] 2026-04-16 09:09:17.822556 | localhost | ok 2026-04-16 09:09:17.836683 | 2026-04-16 09:09:17.836791 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 09:09:17.868410 | localhost | ok 2026-04-16 09:09:17.879875 | 2026-04-16 09:09:17.880093 | TASK [emit-job-header : Print job information] 2026-04-16 09:09:17.920549 | # Job Information 2026-04-16 09:09:17.920789 | Ansible Version: 2.16.16 2026-04-16 09:09:17.920830 | Job: atmosphere-molecule-aio-openvswitch 2026-04-16 09:09:17.920859 | Pipeline: check 2026-04-16 09:09:17.920886 | Executor: 0a8996d2b663 2026-04-16 09:09:17.920911 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3834 2026-04-16 09:09:17.920938 | Event ID: 3a5c3620-3973-11f1-9e18-7305271de95d 2026-04-16 09:09:17.924716 | 2026-04-16 09:09:17.924813 | LOOP [emit-job-header : Print node information] 2026-04-16 09:09:18.022618 | localhost | ok: 2026-04-16 09:09:18.022808 | localhost | # Node Information 2026-04-16 09:09:18.022850 | localhost | Inventory Hostname: instance 2026-04-16 09:09:18.022882 | localhost | Hostname: np0000166635 2026-04-16 09:09:18.022911 | localhost | Username: zuul 2026-04-16 09:09:18.022945 | localhost | Distro: Ubuntu 22.04 2026-04-16 09:09:18.022974 | localhost | Provider: yul1 2026-04-16 09:09:18.023002 | localhost | Region: ca-ymq-1 2026-04-16 09:09:18.023029 | localhost | Label: ubuntu-jammy-16 2026-04-16 09:09:18.023056 | localhost | Product Name: OpenStack Nova 2026-04-16 09:09:18.023083 | localhost | Interface IP: 199.204.45.25 2026-04-16 09:09:18.034243 | 2026-04-16 09:09:18.034436 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-16 09:09:18.437716 | localhost -> localhost | changed 2026-04-16 09:09:18.445528 | 2026-04-16 09:09:18.445700 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-16 09:09:19.319202 | localhost -> localhost | changed 2026-04-16 09:09:19.325959 | 2026-04-16 09:09:19.326040 | PLAY [all] 2026-04-16 09:09:19.335230 | 2026-04-16 09:09:19.335365 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-16 09:09:19.573986 | instance -> localhost | ok 2026-04-16 09:09:19.583616 | 2026-04-16 09:09:19.583702 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-16 09:09:19.617611 | instance | ok 2026-04-16 09:09:19.633553 | instance | included: /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-16 09:09:19.639169 | 2026-04-16 09:09:19.639362 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-16 09:09:20.437402 | instance -> localhost | Generating public/private rsa key pair. 2026-04-16 09:09:20.437677 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/4a872e9e453443f8ac9d3c5083a99ade_id_rsa 2026-04-16 09:09:20.437756 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/4a872e9e453443f8ac9d3c5083a99ade_id_rsa.pub 2026-04-16 09:09:20.437865 | instance -> localhost | The key fingerprint is: 2026-04-16 09:09:20.437924 | instance -> localhost | SHA256:n53oAxrN4ohe4yc/T2dFQJENsUcuQWNV5XI8SY/isdc zuul-build-sshkey 2026-04-16 09:09:20.437998 | instance -> localhost | The key's randomart image is: 2026-04-16 09:09:20.438047 | instance -> localhost | +---[RSA 3072]----+ 2026-04-16 09:09:20.438106 | instance -> localhost | | o@Bo..o.| 2026-04-16 09:09:20.438156 | instance -> localhost | | ..B. .+o| 2026-04-16 09:09:20.438201 | instance -> localhost | | o * oo=| 2026-04-16 09:09:20.438245 | instance -> localhost | | = + +.| 2026-04-16 09:09:20.438289 | instance -> localhost | | oS + . E| 2026-04-16 09:09:20.438333 | instance -> localhost | | o +. = o | 2026-04-16 09:09:20.438376 | instance -> localhost | | .oo +..* o | 2026-04-16 09:09:20.438419 | instance -> localhost | | .oo.=. +. | 2026-04-16 09:09:20.438469 | instance -> localhost | | .. .+.o. .. | 2026-04-16 09:09:20.438513 | instance -> localhost | +----[SHA256]-----+ 2026-04-16 09:09:20.438616 | instance -> localhost | ok: Runtime: 0:00:00.356117 2026-04-16 09:09:20.444225 | 2026-04-16 09:09:20.444289 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-16 09:09:20.477000 | instance | ok 2026-04-16 09:09:20.486367 | instance | included: /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-16 09:09:20.494593 | 2026-04-16 09:09:20.494716 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-16 09:09:20.519050 | instance | skipping: Conditional result was False 2026-04-16 09:09:20.528971 | 2026-04-16 09:09:20.529132 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-16 09:09:20.998868 | instance | changed 2026-04-16 09:09:21.006435 | 2026-04-16 09:09:21.006537 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-16 09:09:21.191786 | instance | ok 2026-04-16 09:09:21.199219 | 2026-04-16 09:09:21.199312 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-16 09:09:21.707935 | instance | changed 2026-04-16 09:09:21.896300 | 2026-04-16 09:09:21.896411 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-16 09:09:22.371014 | instance | changed 2026-04-16 09:09:22.378139 | 2026-04-16 09:09:22.378207 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-16 09:09:22.402333 | instance | skipping: Conditional result was False 2026-04-16 09:09:22.442247 | 2026-04-16 09:09:22.442373 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-16 09:09:22.780209 | instance -> localhost | changed 2026-04-16 09:09:22.798305 | 2026-04-16 09:09:22.798400 | TASK [add-build-sshkey : Add back temp key] 2026-04-16 09:09:23.072686 | instance -> localhost | Identity added: /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/4a872e9e453443f8ac9d3c5083a99ade_id_rsa (zuul-build-sshkey) 2026-04-16 09:09:23.072905 | instance -> localhost | ok: Runtime: 0:00:00.011724 2026-04-16 09:09:23.079343 | 2026-04-16 09:09:23.079418 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-16 09:09:23.368474 | instance | ok 2026-04-16 09:09:23.376884 | 2026-04-16 09:09:23.377006 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-16 09:09:23.400789 | instance | skipping: Conditional result was False 2026-04-16 09:09:23.416154 | 2026-04-16 09:09:23.416243 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-16 09:09:23.691216 | instance | ok 2026-04-16 09:09:23.697768 | 2026-04-16 09:09:23.697891 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-16 09:09:25.266981 | instance | Output suppressed because no_log was given 2026-04-16 09:09:25.278985 | 2026-04-16 09:09:25.279059 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-16 09:09:25.458800 | instance | ok: "logs" 2026-04-16 09:09:25.459554 | instance | ok: All items complete 2026-04-16 09:09:25.459592 | 2026-04-16 09:09:25.635279 | instance | ok: "artifacts" 2026-04-16 09:09:25.775010 | instance | ok: "docs" 2026-04-16 09:09:25.793400 | 2026-04-16 09:09:25.793884 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-16 09:09:25.984649 | instance | changed: "logs" 2026-04-16 09:09:26.120587 | instance | changed: "artifacts" 2026-04-16 09:09:26.282221 | instance | changed: "docs" 2026-04-16 09:09:26.302040 | 2026-04-16 09:09:26.302099 | PLAY RECAP 2026-04-16 09:09:26.302140 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-16 09:09:26.302168 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 09:09:26.302190 | 2026-04-16 09:09:26.435036 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-16 09:09:26.445111 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-16 09:09:27.076956 | 2026-04-16 09:09:27.624643 | PLAY [all] 2026-04-16 09:09:27.645115 | 2026-04-16 09:09:27.645295 | TASK [setup-uv : Extract archive] 2026-04-16 09:09:29.926932 | instance | changed 2026-04-16 09:09:29.936649 | 2026-04-16 09:09:29.936838 | TASK [setup-uv : Print version] 2026-04-16 09:09:30.264272 | instance | uv 0.8.13 2026-04-16 09:09:30.482600 | instance | ok: Runtime: 0:00:00.012655 2026-04-16 09:09:30.491046 | 2026-04-16 09:09:30.491120 | PLAY RECAP 2026-04-16 09:09:30.491169 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 09:09:30.491216 | 2026-04-16 09:09:30.609281 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-16 09:09:30.620792 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-16 09:09:31.219254 | 2026-04-16 09:09:31.219434 | PLAY [all] 2026-04-16 09:09:31.234301 | 2026-04-16 09:09:31.234413 | TASK [Install "jq" for log collection] 2026-04-16 09:10:37.191371 | instance | changed 2026-04-16 09:10:37.200520 | 2026-04-16 09:10:37.200709 | TASK [Install pip3 for Python package management] 2026-04-16 09:10:41.658417 | instance | changed 2026-04-16 09:10:41.663417 | 2026-04-16 09:10:41.663483 | TASK [Install Python "kubernetes" library for kubernetes.core modules] 2026-04-16 09:10:44.635521 | instance | changed 2026-04-16 09:10:44.638069 | 2026-04-16 09:10:44.638125 | PLAY [all] 2026-04-16 09:10:44.645314 | 2026-04-16 09:10:44.645379 | TASK [ensure-go : Check installed go version] 2026-04-16 09:10:45.180312 | instance | ok: ERROR (ignored) 2026-04-16 09:10:45.180568 | instance | { 2026-04-16 09:10:45.180599 | instance | "failed_when_result": false, 2026-04-16 09:10:45.180621 | instance | "msg": "[Errno 2] No such file or directory: b'go'", 2026-04-16 09:10:45.180643 | instance | "rc": 2 2026-04-16 09:10:45.180665 | instance | } 2026-04-16 09:10:45.185482 | 2026-04-16 09:10:45.185545 | TASK [ensure-go : Skip if correct version of go is installed] 2026-04-16 09:10:45.241104 | instance | ok 2026-04-16 09:10:45.249745 | instance | included: /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/untrusted/project_2/opendev.org/zuul/zuul-jobs/roles/ensure-go/tasks/install-go.yaml 2026-04-16 09:10:45.255081 | 2026-04-16 09:10:45.255181 | TASK [ensure-go : Create temp directory] 2026-04-16 09:10:45.564321 | instance | changed 2026-04-16 09:10:45.574134 | 2026-04-16 09:10:45.574362 | TASK [ensure-go : Get archive checksum] 2026-04-16 09:10:46.203177 | instance | ok: OK (64 bytes) 2026-04-16 09:10:46.210168 | 2026-04-16 09:10:46.210243 | TASK [ensure-go : Download go archive] 2026-04-16 09:10:47.402329 | instance | changed: OK (78559214 bytes) 2026-04-16 09:10:47.409001 | 2026-04-16 09:10:47.409083 | TASK [ensure-go : Install go] 2026-04-16 09:10:53.153271 | instance | changed 2026-04-16 09:10:53.162428 | 2026-04-16 09:10:53.162506 | PLAY [all] 2026-04-16 09:10:53.168389 | 2026-04-16 09:10:53.168453 | TASK [Copy inventory file for Zuul] 2026-04-16 09:10:53.912982 | instance | changed 2026-04-16 09:10:53.917862 | 2026-04-16 09:10:53.917944 | TASK [Switch "ansible_host" to private IP] 2026-04-16 09:10:54.232338 | instance | changed: 1 replacements made 2026-04-16 09:10:54.269793 | 2026-04-16 09:10:54.269923 | TASK [Run molecule prepare] 2026-04-16 09:10:54.533514 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3 2026-04-16 09:10:54.533733 | instance | Creating virtual environment at: .venv 2026-04-16 09:10:54.558674 | instance | Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-16 09:10:54.570439 | instance | Downloading cryptography (4.2MiB) 2026-04-16 09:10:54.579612 | instance | Downloading kubernetes (1.9MiB) 2026-04-16 09:10:54.579760 | instance | Downloading ansible-core (2.1MiB) 2026-04-16 09:10:54.579941 | instance | Downloading pydantic-core (2.0MiB) 2026-04-16 09:10:54.580169 | instance | Downloading openstacksdk (1.7MiB) 2026-04-16 09:10:54.580329 | instance | Downloading rjsonnet (1.2MiB) 2026-04-16 09:10:54.580640 | instance | Downloading setuptools (1.1MiB) 2026-04-16 09:10:54.599382 | instance | Downloading netaddr (2.2MiB) 2026-04-16 09:10:54.600357 | instance | Downloading pygments (1.2MiB) 2026-04-16 09:10:54.927708 | instance | Building pyperclip==1.9.0 2026-04-16 09:10:54.941748 | instance | Downloading rjsonnet 2026-04-16 09:10:55.040718 | instance | Downloading pydantic-core 2026-04-16 09:10:55.082159 | instance | Downloading netaddr 2026-04-16 09:10:55.098740 | instance | Downloading pygments 2026-04-16 09:10:55.116332 | instance | Downloading cryptography 2026-04-16 09:10:55.150939 | instance | Downloading setuptools 2026-04-16 09:10:55.211756 | instance | Downloading kubernetes 2026-04-16 09:10:55.247677 | instance | Downloading ansible-core 2026-04-16 09:10:55.298758 | instance | Downloading openstacksdk 2026-04-16 09:10:55.664881 | instance | Built pyperclip==1.9.0 2026-04-16 09:10:55.854503 | instance | Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere 2026-04-16 09:10:55.898160 | instance | Installed 83 packages in 41ms 2026-04-16 09:10:56.500997 | instance | WARNING Molecule scenarios should migrate to 'extensions/molecule' 2026-04-16 09:10:57.088732 | instance | INFO [aio > discovery] scenario test matrix: prepare 2026-04-16 09:10:57.088784 | instance | INFO [aio > prerun] Performing prerun with role_name_check=0... 2026-04-16 09:11:36.342218 | instance | WARNING Retrying execution failure 250 of: ansible-galaxy collection install -vvv atmosphere.common:>=0.6.0 2026-04-16 09:11:36.342325 | instance | ERROR Command ansible-galaxy collection install -vvv atmosphere.common:>=0.6.0, returned 250 code: 2026-04-16 09:11:36.342333 | instance | Starting galaxy collection install process 2026-04-16 09:11:36.342340 | instance | Process install dependency map 2026-04-16 09:11:36.342346 | instance | the full traceback was: 2026-04-16 09:11:36.342352 | instance | 2026-04-16 09:11:36.342358 | instance | Traceback (most recent call last): 2026-04-16 09:11:36.342364 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor 2026-04-16 09:11:36.342370 | instance | exit_code = cli.run() 2026-04-16 09:11:36.342375 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-04-16 09:11:36.342381 | instance | return context.CLIARGS() 2026-04-16 09:11:36.342387 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper 2026-04-16 09:11:36.342393 | instance | return wrapped_method(*args, **kwargs) 2026-04-16 09:11:36.342398 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install 2026-04-16 09:11:36.342404 | instance | self._execute_install_collection( 2026-04-16 09:11:36.342410 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection 2026-04-16 09:11:36.342416 | instance | install_collections( 2026-04-16 09:11:36.342422 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections 2026-04-16 09:11:36.342428 | instance | dependency_map = _resolve_depenency_map( 2026-04-16 09:11:36.342433 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map 2026-04-16 09:11:36.342439 | instance | return collection_dep_resolver.resolve( 2026-04-16 09:11:36.342445 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-04-16 09:11:36.342464 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-04-16 09:11:36.342470 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-04-16 09:11:36.342475 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-04-16 09:11:36.342481 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria 2026-04-16 09:11:36.342486 | instance | matches = self._p.find_matches( 2026-04-16 09:11:36.342492 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches 2026-04-16 09:11:36.342497 | instance | match for match in self._find_matches(list(requirements)) 2026-04-16 09:11:36.342503 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches 2026-04-16 09:11:36.342509 | instance | coll_versions = if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable] 2026-04-16 09:11:36.342514 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions 2026-04-16 09:11:36.342520 | instance | return set( 2026-04-16 09:11:36.342526 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in 2026-04-16 09:11:36.342531 | instance | return set( 2026-04-16 09:11:36.342536 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions 2026-04-16 09:11:36.342542 | instance | raise last_error 2026-04-16 09:11:36.342547 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions 2026-04-16 09:11:36.342553 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-04-16 09:11:36.342558 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-04-16 09:11:36.342564 | instance | return method(self, *args, **kwargs) 2026-04-16 09:11:36.342569 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions 2026-04-16 09:11:36.342575 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-04-16 09:11:36.342588 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function 2026-04-16 09:11:36.342594 | instance | return call_retryable_function() 2026-04-16 09:11:36.342603 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy 2026-04-16 09:11:36.342609 | instance | res = path_cache 2026-04-16 09:11:36.342614 | instance | KeyError: 'results' 2026-04-16 09:11:36.342619 | instance | 2026-04-16 09:11:36.342625 | instance | ansible-galaxy 2026-04-16 09:11:36.342630 | instance | config file = None 2026-04-16 09:11:36.342636 | instance | configured module search path = 2026-04-16 09:11:36.342641 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-04-16 09:11:36.342647 | instance | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages 2026-04-16 09:11:36.342665 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-04-16 09:11:36.342670 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-04-16 09:11:36.342676 | instance | jinja version = 3.1.6 2026-04-16 09:11:36.342681 | instance | libyaml = True 2026-04-16 09:11:36.342687 | instance | No config file found; using defaults 2026-04-16 09:11:36.342692 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-04-16 09:11:36.342698 | instance | Found installed collection ansible.utils:6.0.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-04-16 09:11:36.342703 | instance | Found installed collection vexxhost.containers:1.6.5 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-04-16 09:11:36.342709 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-04-16 09:11:36.342714 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-04-16 09:11:36.342720 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-04-16 09:11:36.342725 | instance | Found installed collection openstack.cloud:2.5.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-04-16 09:11:36.342731 | instance | : Collection ansible.netcommon does not support Ansible version 2026-04-16 09:11:36.342736 | instance | 2.17.12 2026-04-16 09:11:36.342742 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-04-16 09:11:36.342747 | instance | Found installed collection vexxhost.ceph:3.2.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-04-16 09:11:36.342753 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-04-16 09:11:36.342758 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-04-16 09:11:36.342764 | instance | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-04-16 09:11:36.342769 | instance | unexpected error when getting available versions of collection 2026-04-16 09:11:36.342775 | instance | atmosphere.common: 'results' 2026-04-16 09:11:36.342780 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-04-16 09:11:36.342793 | instance | 2026-04-16 09:11:36.343013 | instance | Traceback (most recent call last): 2026-04-16 09:11:36.343334 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in 2026-04-16 09:11:36.343783 | instance | sys.exit(main()) 2026-04-16 09:11:36.344122 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__ 2026-04-16 09:11:36.344836 | instance | return self.main(*args, **kwargs) 2026-04-16 09:11:36.345114 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main 2026-04-16 09:11:36.345799 | instance | rv = self.invoke(ctx) 2026-04-16 09:11:36.346064 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke 2026-04-16 09:11:36.346861 | instance | return _process_result(sub_ctx.command.invoke(sub_ctx)) 2026-04-16 09:11:36.347145 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke 2026-04-16 09:11:36.347782 | instance | return ctx.invoke(self.callback, **ctx.params) 2026-04-16 09:11:36.348096 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke 2026-04-16 09:11:36.348623 | instance | return callback(*args, **kwargs) 2026-04-16 09:11:36.348914 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper 2026-04-16 09:11:36.349419 | instance | return func(*args, **kwargs) 2026-04-16 09:11:36.349696 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func 2026-04-16 09:11:36.350054 | instance | return f(get_current_context(), *args, **kwargs) 2026-04-16 09:11:36.350396 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper 2026-04-16 09:11:36.350843 | instance | return func(ctx) 2026-04-16 09:11:36.351127 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/prepare.py", line 139, in prepare 2026-04-16 09:11:36.351379 | instance | base.execute_cmdline_scenarios(scenario_name, args, command_args, excludes=exclude) 2026-04-16 09:11:36.351498 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios 2026-04-16 09:11:36.351670 | instance | _run_scenarios(scenarios, command_args, default_config) 2026-04-16 09:11:36.351789 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 240, in _run_scenarios 2026-04-16 09:11:36.352145 | instance | scenario.config.runtime.prepare_environment( 2026-04-16 09:11:36.352278 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment 2026-04-16 09:11:36.352514 | instance | self.install_collection( 2026-04-16 09:11:36.352637 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 552, in install_collection 2026-04-16 09:11:36.352851 | instance | raise InvalidPrerequisiteError(msg) 2026-04-16 09:11:36.354987 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv atmosphere.common:>=0.6.0, returned 250 code: 2026-04-16 09:11:36.355017 | instance | Starting galaxy collection install process 2026-04-16 09:11:36.355027 | instance | Process install dependency map 2026-04-16 09:11:36.355036 | instance | the full traceback was: 2026-04-16 09:11:36.355045 | instance | 2026-04-16 09:11:36.355054 | instance | Traceback (most recent call last): 2026-04-16 09:11:36.355062 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/__init__.py", line 658, in cli_executor 2026-04-16 09:11:36.355071 | instance | exit_code = cli.run() 2026-04-16 09:11:36.355080 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 773, in run 2026-04-16 09:11:36.355089 | instance | return context.CLIARGS['func']() 2026-04-16 09:11:36.355098 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 120, in method_wrapper 2026-04-16 09:11:36.355107 | instance | return wrapped_method(*args, **kwargs) 2026-04-16 09:11:36.355116 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1438, in execute_install 2026-04-16 09:11:36.355124 | instance | self._execute_install_collection( 2026-04-16 09:11:36.355133 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/cli/galaxy.py", line 1487, in _execute_install_collection 2026-04-16 09:11:36.355142 | instance | install_collections( 2026-04-16 09:11:36.355151 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 735, in install_collections 2026-04-16 09:11:36.355160 | instance | dependency_map = _resolve_depenency_map( 2026-04-16 09:11:36.355168 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/__init__.py", line 1852, in _resolve_depenency_map 2026-04-16 09:11:36.355177 | instance | return collection_dep_resolver.resolve( 2026-04-16 09:11:36.355196 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 546, in resolve 2026-04-16 09:11:36.355205 | instance | state = resolution.resolve(requirements, max_rounds=max_rounds) 2026-04-16 09:11:36.355214 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 397, in resolve 2026-04-16 09:11:36.355223 | instance | self._add_to_criteria(self.state.criteria, r, parent=None) 2026-04-16 09:11:36.355231 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/resolvelib/resolvers.py", line 148, in _add_to_criteria 2026-04-16 09:11:36.355245 | instance | matches = self._p.find_matches( 2026-04-16 09:11:36.355254 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 477, in find_matches 2026-04-16 09:11:36.355263 | instance | match for match in self._find_matches(list(requirements[identifier])) 2026-04-16 09:11:36.355272 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/dependency_resolution/providers.py", line 253, in _find_matches 2026-04-16 09:11:36.355280 | instance | coll_versions = [] if preinstalled_candidates else self._api_proxy.get_collection_versions(first_req) # type: t.Iterable[t.Tuple[str, GalaxyAPI]] 2026-04-16 09:11:36.355289 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in get_collection_versions 2026-04-16 09:11:36.355298 | instance | return set( 2026-04-16 09:11:36.355307 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 106, in 2026-04-16 09:11:36.355316 | instance | return set( 2026-04-16 09:11:36.355324 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 87, in _get_collection_versions 2026-04-16 09:11:36.355333 | instance | raise last_error 2026-04-16 09:11:36.355342 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/collection/galaxy_api_proxy.py", line 66, in _get_collection_versions 2026-04-16 09:11:36.355351 | instance | versions = api.get_collection_versions(requirement.namespace, requirement.name) 2026-04-16 09:11:36.355360 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 145, in wrapped 2026-04-16 09:11:36.355369 | instance | return method(self, *args, **kwargs) 2026-04-16 09:11:36.355378 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 874, in get_collection_versions 2026-04-16 09:11:36.355387 | instance | data = self._call_galaxy(versions_url, error_context_msg=error_context_msg, cache=True, cache_key=cache_key) 2026-04-16 09:11:36.355402 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/module_utils/api.py", line 169, in run_function 2026-04-16 09:11:36.355417 | instance | return call_retryable_function() 2026-04-16 09:11:36.355426 | instance | File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible/galaxy/api.py", line 390, in _call_galaxy 2026-04-16 09:11:36.355435 | instance | res = path_cache['results'] 2026-04-16 09:11:36.355444 | instance | KeyError: 'results' 2026-04-16 09:11:36.355452 | instance | 2026-04-16 09:11:36.355461 | instance | ansible-galaxy [core 2.17.12] 2026-04-16 09:11:36.355470 | instance | config file = None 2026-04-16 09:11:36.355479 | instance | configured module search path = ['/home/zuul/src/github.com/vexxhost/atmosphere/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules'] 2026-04-16 09:11:36.355488 | instance | ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible 2026-04-16 09:11:36.355498 | instance | ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages 2026-04-16 09:11:36.355514 | instance | executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy 2026-04-16 09:11:36.355523 | instance | python version = 3.10.12 (main, Mar 3 2026, 11:56:32) [GCC 11.4.0] (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python) 2026-04-16 09:11:36.355532 | instance | jinja version = 3.1.6 2026-04-16 09:11:36.355540 | instance | libyaml = True 2026-04-16 09:11:36.355549 | instance | No config file found; using defaults 2026-04-16 09:11:36.355558 | instance | Found installed collection kubernetes.core:2.4.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core' 2026-04-16 09:11:36.355570 | instance | Found installed collection ansible.utils:6.0.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-04-16 09:11:36.355579 | instance | Found installed collection vexxhost.containers:1.6.5 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers' 2026-04-16 09:11:36.355588 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general' 2026-04-16 09:11:36.355597 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix' 2026-04-16 09:11:36.355606 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql' 2026-04-16 09:11:36.355615 | instance | Found installed collection openstack.cloud:2.5.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud' 2026-04-16 09:11:36.355624 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version 2026-04-16 09:11:36.355633 | instance | 2.17.12 2026-04-16 09:11:36.355649 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-04-16 09:11:36.355658 | instance | Found installed collection vexxhost.ceph:3.2.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/ceph' 2026-04-16 09:11:36.355667 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto' 2026-04-16 09:11:36.355676 | instance | Opened /home/zuul/.ansible/galaxy_token 2026-04-16 09:11:36.355685 | instance | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-04-16 09:11:36.355694 | instance | unexpected error when getting available versions of collection 2026-04-16 09:11:36.355703 | instance | atmosphere.common: 'results' 2026-04-16 09:11:36.355712 | instance | ERROR! Unexpected Exception, this is probably a bug: 'results' 2026-04-16 09:11:36.355721 | instance | 2026-04-16 09:11:36.867047 | instance | ERROR 2026-04-16 09:11:36.867236 | instance | { 2026-04-16 09:11:36.867266 | instance | "delta": "0:00:41.956403", 2026-04-16 09:11:36.867288 | instance | "end": "2026-04-16 09:11:36.429878", 2026-04-16 09:11:36.867307 | instance | "msg": "non-zero return code", 2026-04-16 09:11:36.867325 | instance | "rc": 1, 2026-04-16 09:11:36.867343 | instance | "start": "2026-04-16 09:10:54.473475" 2026-04-16 09:11:36.867361 | instance | } failure 2026-04-16 09:11:36.871226 | 2026-04-16 09:11:36.871381 | PLAY RECAP 2026-04-16 09:11:36.871454 | instance | ok: 11 changed: 8 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-04-16 09:11:36.871488 | 2026-04-16 09:11:37.005991 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@main] 2026-04-16 09:11:37.018363 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-16 09:11:37.617208 | 2026-04-16 09:11:37.617359 | PLAY [all] 2026-04-16 09:11:37.630939 | 2026-04-16 09:11:37.631022 | TASK [gather-host-logs : creating directory for system status] 2026-04-16 09:11:37.971892 | instance | changed 2026-04-16 09:11:37.977411 | 2026-04-16 09:11:37.977489 | TASK [gather-host-logs : Get logs for each host] 2026-04-16 09:11:38.306115 | instance | + systemd-cgls --full --all --no-pager 2026-04-16 09:11:38.319671 | instance | + ip addr 2026-04-16 09:11:38.321538 | instance | + ip route 2026-04-16 09:11:38.322838 | instance | + lsblk 2026-04-16 09:11:38.325442 | instance | + mount 2026-04-16 09:11:38.326846 | instance | + docker images 2026-04-16 09:11:38.327253 | instance | /bin/bash: line 7: docker: command not found 2026-04-16 09:11:38.327464 | instance | + brctl show 2026-04-16 09:11:38.327877 | instance | /bin/bash: line 8: brctl: command not found 2026-04-16 09:11:38.328180 | instance | + ps aux --sort=-%mem 2026-04-16 09:11:38.345168 | instance | + dpkg -l 2026-04-16 09:11:38.356633 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul)) 2026-04-16 09:11:38.357207 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul 2026-04-16 09:11:38.357317 | instance | /bin/bash: line 11: docker: command not found 2026-04-16 09:11:38.357688 | instance | + '[' '!' -z '' ']' 2026-04-16 09:11:38.516461 | instance | ok: Runtime: 0:00:00.054722 2026-04-16 09:11:38.524978 | 2026-04-16 09:11:38.525111 | TASK [gather-host-logs : Downloads logs to executor] 2026-04-16 09:11:39.196713 | instance | changed: 2026-04-16 09:11:39.196942 | instance | created directory /var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/logs/instance 2026-04-16 09:11:39.197825 | instance | cd+++++++++ system/ 2026-04-16 09:11:39.197869 | instance | >f+++++++++ system/brctl-show.txt 2026-04-16 09:11:39.197894 | instance | >f+++++++++ system/docker-images.txt 2026-04-16 09:11:39.197915 | instance | >f+++++++++ system/ip-addr.txt 2026-04-16 09:11:39.197942 | instance | >f+++++++++ system/ip-route.txt 2026-04-16 09:11:39.197963 | instance | >f+++++++++ system/lsblk.txt 2026-04-16 09:11:39.197984 | instance | >f+++++++++ system/mount.txt 2026-04-16 09:11:39.198006 | instance | >f+++++++++ system/packages.txt 2026-04-16 09:11:39.198026 | instance | >f+++++++++ system/ps.txt 2026-04-16 09:11:39.198045 | instance | >f+++++++++ system/systemd-cgls.txt 2026-04-16 09:11:39.209050 | 2026-04-16 09:11:39.209123 | LOOP [helm-release-status : creating directory for helm release status] 2026-04-16 09:11:39.398375 | instance | changed: "values" 2026-04-16 09:11:39.576424 | instance | changed: "releases" 2026-04-16 09:11:39.592432 | 2026-04-16 09:11:39.592578 | TASK [helm-release-status : Gather get release status for helm charts] 2026-04-16 09:11:39.791758 | instance | /bin/bash: line 3: kubectl: command not found 2026-04-16 09:11:40.131651 | instance | ok: Runtime: 0:00:00.005895 2026-04-16 09:11:40.139501 | 2026-04-16 09:11:40.139602 | TASK [helm-release-status : Downloads logs to executor] 2026-04-16 09:11:40.615526 | instance | changed: 2026-04-16 09:11:40.615697 | instance | cd+++++++++ helm/ 2026-04-16 09:11:40.615725 | instance | cd+++++++++ helm/releases/ 2026-04-16 09:11:40.615747 | instance | cd+++++++++ helm/values/ 2026-04-16 09:11:40.627092 | 2026-04-16 09:11:40.627203 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects] 2026-04-16 09:11:40.868289 | instance | changed 2026-04-16 09:11:40.873593 | 2026-04-16 09:11:40.873684 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects] 2026-04-16 09:11:41.083064 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-16 09:11:41.083300 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-16 09:11:41.089434 | instance | environment: line 1: kubectl: command not found 2026-04-16 09:11:41.090591 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-16 09:11:41.092314 | instance | environment: line 1: kubectl: command not found 2026-04-16 09:11:41.093681 | instance | environment: line 1: kubectl: command not found 2026-04-16 09:11:41.094127 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-16 09:11:41.094963 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-16 09:11:41.097777 | instance | environment: line 1: kubectl: command not found 2026-04-16 09:11:41.098441 | instance | environment: line 1: kubectl: command not found 2026-04-16 09:11:41.099030 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-16 09:11:41.099877 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value 2026-04-16 09:11:41.411560 | instance | ok: Runtime: 0:00:00.032260 2026-04-16 09:11:41.418747 | 2026-04-16 09:11:41.418883 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects] 2026-04-16 09:11:41.603926 | instance | changed 2026-04-16 09:11:41.610392 | 2026-04-16 09:11:41.610515 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects] 2026-04-16 09:11:41.831030 | instance | environment: line 5: kubectl: command not found 2026-04-16 09:11:41.831953 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-16 09:11:41.832294 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-16 09:11:41.832474 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value 2026-04-16 09:11:42.155458 | instance | ok: Runtime: 0:00:00.010466 2026-04-16 09:11:42.161645 | 2026-04-16 09:11:42.161725 | TASK [describe-kubernetes-objects : Downloads logs to executor] 2026-04-16 09:11:42.663548 | instance | changed: 2026-04-16 09:11:42.664202 | instance | cd+++++++++ objects/ 2026-04-16 09:11:42.664268 | instance | cd+++++++++ objects/cluster/ 2026-04-16 09:11:42.664314 | instance | cd+++++++++ objects/namespaced/ 2026-04-16 09:11:42.676434 | 2026-04-16 09:11:42.676577 | TASK [gather-pod-logs : creating directory for pod logs] 2026-04-16 09:11:42.874184 | instance | changed 2026-04-16 09:11:42.881812 | 2026-04-16 09:11:42.881954 | TASK [gather-pod-logs : creating directory for failed pod logs] 2026-04-16 09:11:43.087329 | instance | changed 2026-04-16 09:11:43.094929 | 2026-04-16 09:11:43.095055 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)] 2026-04-16 09:11:43.326561 | instance | environment: line 3: kubectl: command not found 2026-04-16 09:11:43.649590 | instance | ok: Runtime: 0:00:00.008703 2026-04-16 09:11:43.655509 | 2026-04-16 09:11:43.655576 | TASK [gather-pod-logs : Downloads pod logs to executor] 2026-04-16 09:11:44.166224 | instance | changed: 2026-04-16 09:11:44.166932 | instance | cd+++++++++ pod-logs/ 2026-04-16 09:11:44.166984 | instance | cd+++++++++ pod-logs/failed-pods/ 2026-04-16 09:11:44.183831 | 2026-04-16 09:11:44.183917 | TASK [gather-prom-metrics : creating directory for helm release descriptions] 2026-04-16 09:11:44.381496 | instance | changed 2026-04-16 09:11:44.400498 | 2026-04-16 09:11:44.400684 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces] 2026-04-16 09:11:44.633030 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-16 09:11:45.071366 | instance | ok: Runtime: 0:00:00.034845 2026-04-16 09:11:45.078307 | 2026-04-16 09:11:45.078402 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr] 2026-04-16 09:11:45.284954 | instance | /bin/bash: line 2: kubectl: command not found 2026-04-16 09:11:45.311712 | instance | ceph-mgr endpoints: 2026-04-16 09:11:45.670650 | instance | ok: Runtime: 0:00:00.036620 2026-04-16 09:11:45.676650 | 2026-04-16 09:11:45.676791 | TASK [gather-prom-metrics : Get metrics from fluentd pods] 2026-04-16 09:11:45.890929 | instance | /bin/bash: line 4: kubectl: command not found 2026-04-16 09:11:46.217211 | instance | ok: Runtime: 0:00:00.037960 2026-04-16 09:11:46.224134 | 2026-04-16 09:11:46.224223 | TASK [gather-prom-metrics : Downloads logs to executor] 2026-04-16 09:11:46.927946 | instance | changed: cd+++++++++ prometheus/ 2026-04-16 09:11:46.942855 | 2026-04-16 09:11:46.943018 | TASK [gather-selenium-data : creating directory for helm release descriptions] 2026-04-16 09:11:47.202402 | instance | changed 2026-04-16 09:11:47.207302 | 2026-04-16 09:11:47.207369 | TASK [gather-selenium-data : Get selenium data] 2026-04-16 09:11:47.411121 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/. 2026-04-16 09:11:47.412632 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory 2026-04-16 09:11:47.917189 | instance | ERROR 2026-04-16 09:11:47.918374 | instance | { 2026-04-16 09:11:47.918454 | instance | "delta": "0:00:00.006496", 2026-04-16 09:11:47.918491 | instance | "end": "2026-04-16 09:11:47.412983", 2026-04-16 09:11:47.918522 | instance | "msg": "non-zero return code", 2026-04-16 09:11:47.918550 | instance | "rc": 1, 2026-04-16 09:11:47.918578 | instance | "start": "2026-04-16 09:11:47.406487" 2026-04-16 09:11:47.918613 | instance | } 2026-04-16 09:11:47.918664 | instance | ERROR: Ignoring Errors 2026-04-16 09:11:47.930702 | 2026-04-16 09:11:47.930854 | TASK [gather-selenium-data : Downloads logs to executor] 2026-04-16 09:11:48.776286 | instance | changed: cd+++++++++ selenium/ 2026-04-16 09:11:48.784251 | 2026-04-16 09:11:48.784301 | PLAY RECAP 2026-04-16 09:11:48.784345 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-04-16 09:11:48.784367 | 2026-04-16 09:11:48.890835 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@main] 2026-04-16 09:11:48.903326 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 09:11:49.591347 | 2026-04-16 09:11:49.591528 | PLAY [all] 2026-04-16 09:11:49.603374 | 2026-04-16 09:11:49.603493 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-16 09:11:49.649668 | instance | skipping: Conditional result was False 2026-04-16 09:11:49.662810 | 2026-04-16 09:11:49.663018 | TASK [fetch-output : Set log path for single node] 2026-04-16 09:11:49.707554 | instance | ok 2026-04-16 09:11:49.904102 | 2026-04-16 09:11:49.904296 | LOOP [fetch-output : Ensure local output dirs] 2026-04-16 09:11:50.637925 | instance -> localhost | ok: "/var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/logs" 2026-04-16 09:11:50.853842 | instance -> localhost | changed: "/var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/artifacts" 2026-04-16 09:11:51.077183 | instance -> localhost | changed: "/var/lib/zuul/builds/4a872e9e453443f8ac9d3c5083a99ade/work/docs" 2026-04-16 09:11:51.100480 | 2026-04-16 09:11:51.101539 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-16 09:11:52.078955 | instance | changed: .d..t...... ./ 2026-04-16 09:11:52.079213 | instance | changed: All items complete 2026-04-16 09:11:52.079252 | 2026-04-16 09:11:52.574399 | instance | changed: .d..t...... ./ 2026-04-16 09:11:53.018162 | instance | changed: .d..t...... ./ 2026-04-16 09:11:53.042412 | 2026-04-16 09:11:53.042563 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-16 09:11:53.472759 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008944 2026-04-16 09:11:53.696743 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007525 2026-04-16 09:11:53.714970 | 2026-04-16 09:11:53.715125 | PLAY [all] 2026-04-16 09:11:53.722171 | 2026-04-16 09:11:53.722270 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-16 09:11:54.148006 | instance | changed 2026-04-16 09:11:54.161287 | 2026-04-16 09:11:54.161463 | PLAY RECAP 2026-04-16 09:11:54.161539 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-16 09:11:54.161573 | 2026-04-16 09:11:54.303075 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 09:11:54.311702 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-16 09:11:54.970374 | 2026-04-16 09:11:54.970755 | PLAY [localhost] 2026-04-16 09:11:54.981063 | 2026-04-16 09:11:54.981166 | TASK [Generate Zuul manifest] 2026-04-16 09:11:55.002550 | localhost | ok 2026-04-16 09:11:55.018900 | 2026-04-16 09:11:55.019009 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-16 09:11:55.427071 | localhost | changed 2026-04-16 09:11:55.437178 | 2026-04-16 09:11:55.437252 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-16 09:11:55.468914 | localhost | ok 2026-04-16 09:11:55.478439 | 2026-04-16 09:11:55.478503 | TASK [Upload logs] 2026-04-16 09:11:55.507257 | localhost | ok 2026-04-16 09:11:55.610546 | 2026-04-16 09:11:55.610670 | TASK [Set zuul-log-path fact] 2026-04-16 09:11:55.629752 | localhost | ok 2026-04-16 09:11:55.643355 | 2026-04-16 09:11:55.643438 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 09:11:55.677291 | localhost | ok 2026-04-16 09:11:55.688439 | 2026-04-16 09:11:55.688672 | TASK [upload-logs : Create log directories] 2026-04-16 09:11:58.082981 | localhost | changed 2026-04-16 09:11:58.090477 | 2026-04-16 09:11:58.090592 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-16 09:11:58.466676 | localhost -> localhost | ok: Runtime: 0:00:00.004071 2026-04-16 09:11:58.474327 | 2026-04-16 09:11:58.474413 | TASK [upload-logs : Upload logs to log server] 2026-04-16 09:11:58.941231 | localhost | Output suppressed because no_log was given 2026-04-16 09:11:58.945572 | 2026-04-16 09:11:58.945651 | LOOP [upload-logs : Compress console log and json output] 2026-04-16 09:11:59.007677 | localhost | skipping: Conditional result was False 2026-04-16 09:11:59.017315 | localhost | skipping: Conditional result was False 2026-04-16 09:11:59.029123 | 2026-04-16 09:11:59.029302 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-16 09:11:59.073401 | localhost | skipping: Conditional result was False 2026-04-16 09:11:59.073755 | 2026-04-16 09:11:59.077864 | localhost | skipping: Conditional result was False 2026-04-16 09:11:59.098506 | 2026-04-16 09:11:59.098740 | LOOP [upload-logs : Upload console log and json output]