2026-02-20 21:28:34.963179 | Job console starting
2026-02-20 21:28:34.974348 | Updating git repos
2026-02-20 21:28:35.028039 | Cloning repos into workspace
2026-02-20 21:28:35.084214 | Restoring repo states
2026-02-20 21:28:35.125488 | Merging changes
2026-02-20 21:28:36.218789 | Checking out repos
2026-02-20 21:28:36.339977 | Preparing playbooks
2026-02-20 21:28:39.001142 | Running Ansible setup
2026-02-20 21:28:42.218122 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-20 21:28:42.834261 | 
2026-02-20 21:28:42.834407 | PLAY [localhost]
2026-02-20 21:28:42.842467 | 
2026-02-20 21:28:42.842537 | TASK [Gathering Facts]
2026-02-20 21:28:43.719785 | localhost | ok
2026-02-20 21:28:43.729879 | 
2026-02-20 21:28:43.729955 | TASK [Setup log path fact]
2026-02-20 21:28:43.750055 | localhost | ok
2026-02-20 21:28:43.762286 | 
2026-02-20 21:28:43.762349 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-20 21:28:43.792257 | localhost | ok
2026-02-20 21:28:43.800736 | 
2026-02-20 21:28:43.800832 | TASK [emit-job-header : Print job information]
2026-02-20 21:28:43.843382 | # Job Information
2026-02-20 21:28:43.843664 | Ansible Version: 2.16.16
2026-02-20 21:28:43.843741 | Job: atmosphere-molecule-csi-local-path-provisioner
2026-02-20 21:28:43.843795 | Pipeline: check
2026-02-20 21:28:43.843843 | Executor: 0a8996d2b663
2026-02-20 21:28:43.843891 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3589
2026-02-20 21:28:43.843944 | Event ID: 0733f620-0ea3-11f1-9d33-023a454519c2
2026-02-20 21:28:43.847614 | 
2026-02-20 21:28:43.847679 | LOOP [emit-job-header : Print node information]
2026-02-20 21:28:43.938415 | localhost | ok:
2026-02-20 21:28:43.938594 | localhost | # Node Information
2026-02-20 21:28:43.938623 | localhost | Inventory Hostname: instance
2026-02-20 21:28:43.938644 | localhost | Hostname: np0000156606
2026-02-20 21:28:43.938664 | localhost | Username: zuul
2026-02-20 21:28:43.938688 | localhost | Distro: Ubuntu 22.04
2026-02-20 21:28:43.938707 | localhost | Provider: yul1
2026-02-20 21:28:43.938726 | localhost | Region: ca-ymq-1
2026-02-20 21:28:43.938745 | localhost | Label: ubuntu-jammy
2026-02-20 21:28:43.938763 | localhost | Product Name: OpenStack Nova
2026-02-20 21:28:43.938782 | localhost | Interface IP: 199.204.45.78
2026-02-20 21:28:43.950382 | 
2026-02-20 21:28:43.950538 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-20 21:28:44.350823 | localhost -> localhost | changed
2026-02-20 21:28:44.359715 | 
2026-02-20 21:28:44.359786 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-20 21:28:45.215588 | localhost -> localhost | changed
2026-02-20 21:28:45.224856 | 
2026-02-20 21:28:45.224918 | PLAY [all]
2026-02-20 21:28:45.236129 | 
2026-02-20 21:28:45.236215 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-20 21:28:45.466463 | instance -> localhost | ok
2026-02-20 21:28:45.478122 | 
2026-02-20 21:28:45.478213 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-20 21:28:45.511654 | instance | ok
2026-02-20 21:28:45.528409 | instance | included: /var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-20 21:28:45.535051 | 
2026-02-20 21:28:45.535224 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-20 21:28:46.522632 | instance -> localhost | Generating public/private rsa key pair.
2026-02-20 21:28:46.522853 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/6a2928f585ca48eeb3b7595ba08b83e2_id_rsa
2026-02-20 21:28:46.522894 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/6a2928f585ca48eeb3b7595ba08b83e2_id_rsa.pub
2026-02-20 21:28:46.522926 | instance -> localhost | The key fingerprint is:
2026-02-20 21:28:46.522955 | instance -> localhost | SHA256:bDQtMahzHz5KUH6Fve38/7ZJFtdEXDLLmXYcx436fkM zuul-build-sshkey
2026-02-20 21:28:46.523002 | instance -> localhost | The key's randomart image is:
2026-02-20 21:28:46.523038 | instance -> localhost | +---[RSA 3072]----+
2026-02-20 21:28:46.523088 | instance -> localhost | |       .oo    o=*|
2026-02-20 21:28:46.523137 | instance -> localhost | |      o .+o  ..O*|
2026-02-20 21:28:46.523183 | instance -> localhost | |     +  +..o .* +|
2026-02-20 21:28:46.523214 | instance -> localhost | |    + oooo. o. o.|
2026-02-20 21:28:46.523244 | instance -> localhost | |     + +S. o . .o|
2026-02-20 21:28:46.523273 | instance -> localhost | |      ..+   o .Eo|
2026-02-20 21:28:46.523302 | instance -> localhost | |     . . .   o.o |
2026-02-20 21:28:46.523330 | instance -> localhost | |      .       =.+|
2026-02-20 21:28:46.523361 | instance -> localhost | |               **|
2026-02-20 21:28:46.523390 | instance -> localhost | +----[SHA256]-----+
2026-02-20 21:28:46.523456 | instance -> localhost | ok: Runtime: 0:00:00.568938
2026-02-20 21:28:46.529259 | 
2026-02-20 21:28:46.529324 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-20 21:28:46.564624 | instance | ok
2026-02-20 21:28:46.576231 | instance | included: /var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-20 21:28:46.584713 | 
2026-02-20 21:28:46.584796 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-20 21:28:46.609337 | instance | skipping: Conditional result was False
2026-02-20 21:28:46.618828 | 
2026-02-20 21:28:46.618891 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-20 21:28:47.099354 | instance | changed
2026-02-20 21:28:47.104139 | 
2026-02-20 21:28:47.104203 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-20 21:28:47.299524 | instance | ok
2026-02-20 21:28:47.306546 | 
2026-02-20 21:28:47.306612 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-20 21:28:47.766838 | instance | changed
2026-02-20 21:28:47.773715 | 
2026-02-20 21:28:47.773849 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-20 21:28:48.247744 | instance | changed
2026-02-20 21:28:48.259846 | 
2026-02-20 21:28:48.260042 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-20 21:28:48.286363 | instance | skipping: Conditional result was False
2026-02-20 21:28:48.297770 | 
2026-02-20 21:28:48.297879 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-20 21:28:48.658677 | instance -> localhost | changed
2026-02-20 21:28:48.674137 | 
2026-02-20 21:28:48.674209 | TASK [add-build-sshkey : Add back temp key]
2026-02-20 21:28:48.940912 | instance -> localhost | Identity added: /var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/6a2928f585ca48eeb3b7595ba08b83e2_id_rsa (zuul-build-sshkey)
2026-02-20 21:28:48.941175 | instance -> localhost | ok: Runtime: 0:00:00.013928
2026-02-20 21:28:48.947815 | 
2026-02-20 21:28:48.947884 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-20 21:28:49.254801 | instance | ok
2026-02-20 21:28:49.260939 | 
2026-02-20 21:28:49.261020 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-20 21:28:49.294547 | instance | skipping: Conditional result was False
2026-02-20 21:28:49.310805 | 
2026-02-20 21:28:49.310902 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-20 21:28:49.688287 | instance | ok
2026-02-20 21:28:49.697516 | 
2026-02-20 21:28:49.697590 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-20 21:28:51.257097 | instance | Output suppressed because no_log was given
2026-02-20 21:28:51.267703 | 
2026-02-20 21:28:51.267815 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-20 21:28:51.453253 | instance | ok: "logs"
2026-02-20 21:28:51.453623 | instance | ok: All items complete
2026-02-20 21:28:51.453663 | 
2026-02-20 21:28:51.608265 | instance | ok: "artifacts"
2026-02-20 21:28:51.764651 | instance | ok: "docs"
2026-02-20 21:28:51.783020 | 
2026-02-20 21:28:51.783179 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-20 21:28:51.967811 | instance | changed: "logs"
2026-02-20 21:28:52.114185 | instance | changed: "artifacts"
2026-02-20 21:28:52.267804 | instance | changed: "docs"
2026-02-20 21:28:52.289636 | 
2026-02-20 21:28:52.289838 | PLAY RECAP
2026-02-20 21:28:52.289915 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-20 21:28:52.289957 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-20 21:28:52.289988 | 
2026-02-20 21:28:52.455456 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-20 21:28:52.458402 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-20 21:28:53.047224 | 
2026-02-20 21:28:53.047418 | PLAY [all]
2026-02-20 21:28:53.065024 | 
2026-02-20 21:28:53.065231 | TASK [setup-uv : Extract archive]
2026-02-20 21:28:55.243354 | instance | changed
2026-02-20 21:28:55.249493 | 
2026-02-20 21:28:55.249577 | TASK [setup-uv : Print version]
2026-02-20 21:28:54.845639 | instance | uv 0.8.13
2026-02-20 21:28:55.786209 | instance | ok: Runtime: 0:00:00.011599
2026-02-20 21:28:55.793818 | 
2026-02-20 21:28:55.793873 | PLAY RECAP
2026-02-20 21:28:55.793926 | instance | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-20 21:28:55.793953 | 
2026-02-20 21:28:55.923953 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-20 21:28:55.927260 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.2]
2026-02-20 21:28:56.507319 | 
2026-02-20 21:28:56.507450 | PLAY [all]
2026-02-20 21:28:56.518465 | 
2026-02-20 21:28:56.518539 | TASK [Install "jq" for log collection]
2026-02-20 21:29:06.159524 | instance | changed
2026-02-20 21:29:06.161605 | 
2026-02-20 21:29:06.161671 | PLAY RECAP
2026-02-20 21:29:06.161822 | instance | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-20 21:29:06.161896 | 
2026-02-20 21:29:06.288318 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/pre.yml@stable/2023.2]
2026-02-20 21:29:06.293196 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-20 21:29:06.922781 | 
2026-02-20 21:29:06.922941 | PLAY [all]
2026-02-20 21:29:06.935079 | 
2026-02-20 21:29:06.935151 | TASK [Copy inventory file for Zuul]
2026-02-20 21:29:07.871276 | instance | changed
2026-02-20 21:29:07.876421 | 
2026-02-20 21:29:07.876497 | TASK [Switch "ansible_host" to private IP]
2026-02-20 21:29:08.153613 | instance | changed: 1 replacements made
2026-02-20 21:29:08.160725 | 
2026-02-20 21:29:08.160794 | TASK [Run Molecule scenario]
2026-02-20 21:29:08.571774 | instance | Using CPython 3.10.12 interpreter at: /usr/bin/python3
2026-02-20 21:29:08.571906 | instance | Creating virtual environment at: .venv
2026-02-20 21:29:08.595684 | instance |    Building atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-20 21:29:08.624433 | instance | Downloading pygments (1.2MiB)
2026-02-20 21:29:08.624714 | instance | Downloading ansible-core (2.1MiB)
2026-02-20 21:29:08.625029 | instance | Downloading setuptools (1.1MiB)
2026-02-20 21:29:08.625284 | instance | Downloading kubernetes (1.9MiB)
2026-02-20 21:29:08.625556 | instance | Downloading rjsonnet (1.2MiB)
2026-02-20 21:29:08.626915 | instance | Downloading openstacksdk (1.7MiB)
2026-02-20 21:29:08.628395 | instance | Downloading netaddr (2.2MiB)
2026-02-20 21:29:08.628945 | instance | Downloading cryptography (4.2MiB)
2026-02-20 21:29:08.909325 | instance |    Building pyperclip==1.9.0
2026-02-20 21:29:08.931662 | instance |  Downloading rjsonnet
2026-02-20 21:29:09.061407 | instance |  Downloading netaddr
2026-02-20 21:29:09.068471 | instance |  Downloading pygments
2026-02-20 21:29:09.123703 | instance |  Downloading setuptools
2026-02-20 21:29:09.131404 | instance |  Downloading cryptography
2026-02-20 21:29:09.177457 | instance |  Downloading kubernetes
2026-02-20 21:29:09.212965 | instance |  Downloading ansible-core
2026-02-20 21:29:09.242066 | instance |  Downloading openstacksdk
2026-02-20 21:29:09.596065 | instance |       Built pyperclip==1.9.0
2026-02-20 21:29:09.851131 | instance |       Built atmosphere @ file:///home/zuul/src/github.com/vexxhost/atmosphere
2026-02-20 21:29:09.893928 | instance | Installed 79 packages in 39ms
2026-02-20 21:29:10.545712 | instance | WARNING  Molecule scenarios should migrate to 'extensions/molecule'
2026-02-20 21:29:11.170283 | instance | INFO     [csi > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-02-20 21:29:11.170591 | instance | INFO     [csi > prerun] Performing prerun with role_name_check=0...
2026-02-20 21:29:30.943461 | instance | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv kubernetes.core:2.4.0
2026-02-20 21:29:30.943572 | instance | ERROR    Command ansible-galaxy collection install -vvv kubernetes.core:2.4.0, returned 1 code:
2026-02-20 21:29:30.943586 | instance | Starting galaxy collection install process
2026-02-20 21:29:30.943596 | instance | Process install dependency map
2026-02-20 21:29:30.943606 | instance | Starting collection install process
2026-02-20 21:29:30.943617 | instance | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/kubernetes-core-2.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-130186uw3clm/tmpjkittm9e/kubernetes-core-2.4.0-tay7lkgi
2026-02-20 21:29:30.943639 | instance |
2026-02-20 21:29:30.943649 | instance | ansible-galaxy
2026-02-20 21:29:30.943659 | instance |   config file = None
2026-02-20 21:29:30.943668 | instance |   configured module search path =
2026-02-20 21:29:30.943678 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-20 21:29:30.943689 | instance |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages
2026-02-20 21:29:30.943720 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-20 21:29:30.943736 | instance |   python version = 3.10.12 (main, Jan 26 2026, 14:55:28)  (/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/python)
2026-02-20 21:29:30.943750 | instance |   jinja version = 3.1.6
2026-02-20 21:29:30.943764 | instance |   libyaml = True
2026-02-20 21:29:30.943774 | instance | No config file found; using defaults
2026-02-20 21:29:30.943784 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-02-20 21:29:30.943794 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-02-20 21:29:30.943804 | instance | : Collection ansible.netcommon does not support Ansible version
2026-02-20 21:29:30.943837 | instance | 2.17.12
2026-02-20 21:29:30.943847 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-20 21:29:30.943858 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-02-20 21:29:30.943867 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-20 21:29:30.943877 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-20 21:29:30.943887 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-20 21:29:30.943897 | instance | ERROR! Failed to download collection tar from 'default' due to the following unforeseen error: HTTP Error 502: Bad Gateway. HTTP Error 502: Bad Gateway
2026-02-20 21:29:30.943907 | instance |
2026-02-20 21:29:30.944217 | instance | Traceback (most recent call last):
2026-02-20 21:29:30.944529 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/molecule", line 10, in <module>
2026-02-20 21:29:30.945039 | instance |     sys.exit(main())
2026-02-20 21:29:30.945386 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1442, in __call__
2026-02-20 21:29:30.946064 | instance |     return self.main(*args, **kwargs)
2026-02-20 21:29:30.946353 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1363, in main
2026-02-20 21:29:30.946991 | instance |     rv = self.invoke(ctx)
2026-02-20 21:29:30.947274 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1830, in invoke
2026-02-20 21:29:30.948017 | instance |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-02-20 21:29:30.948251 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 1226, in invoke
2026-02-20 21:29:30.948954 | instance |     return ctx.invoke(self.callback, **ctx.params)
2026-02-20 21:29:30.949226 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/core.py", line 794, in invoke
2026-02-20 21:29:30.949697 | instance |     return callback(*args, **kwargs)
2026-02-20 21:29:30.949979 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-02-20 21:29:30.950448 | instance |     return func(*args, **kwargs)
2026-02-20 21:29:30.950733 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/click/decorators.py", line 34, in new_func
2026-02-20 21:29:30.951088 | instance |     return f(get_current_context(), *args, **kwargs)
2026-02-20 21:29:30.951372 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-02-20 21:29:30.951815 | instance |     return func(ctx)
2026-02-20 21:29:30.952096 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/test.py", line 81, in test
2026-02-20 21:29:30.952475 | instance |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-02-20 21:29:30.952847 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-02-20 21:29:30.953300 | instance |     _run_scenarios(scenarios, command_args, default_config)
2026-02-20 21:29:30.953584 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-02-20 21:29:30.953986 | instance |     scenario.config.runtime.prepare_environment(
2026-02-20 21:29:30.954068 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 694, in prepare_environment
2026-02-20 21:29:30.954349 | instance |     self.install_collection(
2026-02-20 21:29:30.954398 | instance |   File "/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible_compat/runtime.py", line 552, in install_collection
2026-02-20 21:29:30.954686 | instance |     raise InvalidPrerequisiteError(msg)
2026-02-20 21:29:30.955559 | instance | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv kubernetes.core:2.4.0, returned 1 code:
2026-02-20 21:29:30.955592 | instance | Starting galaxy collection install process
2026-02-20 21:29:30.955603 | instance | Process install dependency map
2026-02-20 21:29:30.955613 | instance | Starting collection install process
2026-02-20 21:29:30.955622 | instance | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/kubernetes-core-2.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-130186uw3clm/tmpjkittm9e/kubernetes-core-2.4.0-tay7lkgi
2026-02-20 21:29:30.955631 | instance |
2026-02-20 21:29:30.955640 | instance | ansible-galaxy [core 2.17.12]
2026-02-20 21:29:30.955649 | instance |   config file = None
2026-02-20 21:29:30.955659 | instance |   configured module search path = ['/home/zuul/src/github.com/vexxhost/atmosphere/plugins/modules', '/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-02-20 21:29:30.955669 | instance |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages/ansible
2026-02-20 21:29:30.955679 | instance |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/local/lib/python3.10/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere/.venv/lib/python3.10/site-packages
2026-02-20 21:29:30.955688 | instance |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere/.venv/bin/ansible-galaxy
2026-02-20 21:29:30.955698 | 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-02-20 21:29:30.955707 | instance |   jinja version = 3.1.6
2026-02-20 21:29:30.955716 | instance |   libyaml = True
2026-02-20 21:29:30.955725 | instance | No config file found; using defaults
2026-02-20 21:29:30.955734 | instance | Found installed collection community.general:7.3.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-02-20 21:29:30.955743 | instance | Found installed collection community.mysql:3.6.0 at '/home/zuul/.ansible/collections/ansible_collections/community/mysql'
2026-02-20 21:29:30.955752 | instance | [WARNING]: Collection ansible.netcommon does not support Ansible version
2026-02-20 21:29:30.955761 | instance | 2.17.12
2026-02-20 21:29:30.955770 | instance | Found installed collection ansible.netcommon:1.2.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-20 21:29:30.955779 | instance | Found installed collection community.crypto:2.2.3 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-02-20 21:29:30.955788 | instance | Found installed collection ansible.posix:1.6.0 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-02-20 21:29:30.955796 | instance | Found installed collection ansible.utils:6.0.1 at '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-20 21:29:30.955805 | instance | Opened /home/zuul/.ansible/galaxy_token
2026-02-20 21:29:30.955821 | instance | ERROR! Failed to download collection tar from 'default' due to the following unforeseen error: HTTP Error 502: Bad Gateway. HTTP Error 502: Bad Gateway
2026-02-20 21:29:30.955835 | instance |
2026-02-20 21:29:31.235336 | instance | ERROR
2026-02-20 21:29:31.235757 | instance | {
2026-02-20 21:29:31.235832 | instance |   "delta": "0:00:22.525199",
2026-02-20 21:29:31.235878 | instance |   "end": "2026-02-20 21:29:31.039866",
2026-02-20 21:29:31.235921 | instance |   "msg": "non-zero return code",
2026-02-20 21:29:31.235962 | instance |   "rc": 1,
2026-02-20 21:29:31.236010 | instance |   "start": "2026-02-20 21:29:08.514667"
2026-02-20 21:29:31.236073 | instance | }
failure
2026-02-20 21:29:31.240488 | 
2026-02-20 21:29:31.240537 | PLAY RECAP
2026-02-20 21:29:31.240582 | instance | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-20 21:29:31.240603 | 
2026-02-20 21:29:31.376949 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-02-20 21:29:31.380911 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.2]
2026-02-20 21:29:32.025657 | 
2026-02-20 21:29:32.025784 | PLAY [all]
2026-02-20 21:29:32.040018 | 
2026-02-20 21:29:32.040096 | TASK [gather-host-logs : creating directory for system status]
2026-02-20 21:29:32.378974 | instance | changed
2026-02-20 21:29:32.384724 | 
2026-02-20 21:29:32.384804 | TASK [gather-host-logs : Get logs for each host]
2026-02-20 21:29:32.742160 | instance | + systemd-cgls --full --all --no-pager
2026-02-20 21:29:32.756378 | instance | + ip addr
2026-02-20 21:29:32.759525 | instance | + ip route
2026-02-20 21:29:32.762132 | instance | + lsblk
2026-02-20 21:29:32.766540 | instance | + mount
2026-02-20 21:29:32.769183 | instance | + docker images
2026-02-20 21:29:32.769741 | instance | /bin/bash: line 7: docker: command not found
2026-02-20 21:29:32.770040 | instance | + brctl show
2026-02-20 21:29:32.770449 | instance | /bin/bash: line 8: brctl: command not found
2026-02-20 21:29:32.770678 | instance | + ps aux --sort=-%mem
2026-02-20 21:29:32.782293 | instance | + dpkg -l
2026-02-20 21:29:32.789954 | instance | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-02-20 21:29:32.790406 | instance | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-02-20 21:29:32.790454 | instance | /bin/bash: line 11: docker: command not found
2026-02-20 21:29:32.790758 | instance | + '[' '!' -z '' ']'
2026-02-20 21:29:32.919063 | instance | ok: Runtime: 0:00:00.054761
2026-02-20 21:29:32.925327 | 
2026-02-20 21:29:32.925393 | TASK [gather-host-logs : Downloads logs to executor]
2026-02-20 21:29:33.568756 | instance | changed:
2026-02-20 21:29:33.569015 | instance | created directory /var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/logs/instance
2026-02-20 21:29:33.569058 | instance | cd+++++++++ system/
2026-02-20 21:29:33.569090 | instance | >f+++++++++ system/brctl-show.txt
2026-02-20 21:29:33.569118 | instance | >f+++++++++ system/docker-images.txt
2026-02-20 21:29:33.569145 | instance | >f+++++++++ system/ip-addr.txt
2026-02-20 21:29:33.569175 | instance | >f+++++++++ system/ip-route.txt
2026-02-20 21:29:33.569203 | instance | >f+++++++++ system/lsblk.txt
2026-02-20 21:29:33.569230 | instance | >f+++++++++ system/mount.txt
2026-02-20 21:29:33.569259 | instance | >f+++++++++ system/packages.txt
2026-02-20 21:29:33.569287 | instance | >f+++++++++ system/ps.txt
2026-02-20 21:29:33.569313 | instance | >f+++++++++ system/systemd-cgls.txt
2026-02-20 21:29:33.580777 | 
2026-02-20 21:29:33.580841 | LOOP [helm-release-status : creating directory for helm release status]
2026-02-20 21:29:33.779665 | instance | changed: "values"
2026-02-20 21:29:33.947547 | instance | changed: "releases"
2026-02-20 21:29:33.967077 | 
2026-02-20 21:29:33.967249 | TASK [helm-release-status : Gather get release status for helm charts]
2026-02-20 21:29:34.178880 | instance | /bin/bash: line 3: kubectl: command not found
2026-02-20 21:29:34.508318 | instance | ok: Runtime: 0:00:00.006363
2026-02-20 21:29:34.513331 | 
2026-02-20 21:29:34.513394 | TASK [helm-release-status : Downloads logs to executor]
2026-02-20 21:29:35.004101 | instance | changed:
2026-02-20 21:29:35.004314 | instance | cd+++++++++ helm/
2026-02-20 21:29:35.004343 | instance | cd+++++++++ helm/releases/
2026-02-20 21:29:35.004364 | instance | cd+++++++++ helm/values/
2026-02-20 21:29:35.013198 | 
2026-02-20 21:29:35.013271 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-02-20 21:29:35.210425 | instance | changed
2026-02-20 21:29:35.217904 | 
2026-02-20 21:29:35.218015 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-02-20 21:29:35.424939 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-20 21:29:35.425090 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-20 21:29:35.430796 | instance | environment: line 1: kubectl: command not found
2026-02-20 21:29:35.432545 | instance | environment: line 1: kubectl: command not found
2026-02-20 21:29:35.432702 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-20 21:29:35.434236 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-20 21:29:35.437318 | instance | environment: line 1: kubectl: command not found
2026-02-20 21:29:35.437562 | instance | environment: line 1: kubectl: command not found
2026-02-20 21:29:35.439046 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-20 21:29:35.439428 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-20 21:29:35.442545 | instance | environment: line 1: kubectl: command not found
2026-02-20 21:29:35.443539 | instance | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-02-20 21:29:35.754069 | instance | ok: Runtime: 0:00:00.029674
2026-02-20 21:29:35.762320 | 
2026-02-20 21:29:35.762384 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-02-20 21:29:35.955414 | instance | changed
2026-02-20 21:29:35.962927 | 
2026-02-20 21:29:35.963005 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-02-20 21:29:36.201838 | instance | environment: line 5: kubectl: command not found
2026-02-20 21:29:36.202430 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-20 21:29:36.203045 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-20 21:29:36.203230 | instance | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-02-20 21:29:36.535276 | instance | ok: Runtime: 0:00:00.009536
2026-02-20 21:29:36.543370 | 
2026-02-20 21:29:36.543491 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-02-20 21:29:37.036162 | instance | changed:
2026-02-20 21:29:37.036458 | instance | cd+++++++++ objects/
2026-02-20 21:29:37.036520 | instance | cd+++++++++ objects/cluster/
2026-02-20 21:29:37.036568 | instance | cd+++++++++ objects/namespaced/
2026-02-20 21:29:37.050693 | 
2026-02-20 21:29:37.050796 | TASK [gather-pod-logs : creating directory for pod logs]
2026-02-20 21:29:37.246035 | instance | changed
2026-02-20 21:29:37.253757 | 
2026-02-20 21:29:37.253927 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-02-20 21:29:37.462583 | instance | changed
2026-02-20 21:29:37.469656 | 
2026-02-20 21:29:37.469756 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-02-20 21:29:37.688991 | instance | environment: line 3: kubectl: command not found
2026-02-20 21:29:38.006708 | instance | ok: Runtime: 0:00:00.009816
2026-02-20 21:29:38.012599 | 
2026-02-20 21:29:38.012662 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-02-20 21:29:38.495630 | instance | changed:
2026-02-20 21:29:38.495958 | instance | cd+++++++++ pod-logs/
2026-02-20 21:29:38.496067 | instance | cd+++++++++ pod-logs/failed-pods/
2026-02-20 21:29:38.506233 | 
2026-02-20 21:29:38.506297 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-02-20 21:29:38.721326 | instance | changed
2026-02-20 21:29:38.727115 | 
2026-02-20 21:29:38.727185 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-02-20 21:29:38.943841 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-20 21:29:39.269203 | instance | ok: Runtime: 0:00:00.038987
2026-02-20 21:29:39.276742 | 
2026-02-20 21:29:39.276835 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-02-20 21:29:39.489056 | instance | /bin/bash: line 2: kubectl: command not found
2026-02-20 21:29:39.516424 | instance | ceph-mgr endpoints:
2026-02-20 21:29:39.817692 | instance | ok: Runtime: 0:00:00.035010
2026-02-20 21:29:39.824811 | 
2026-02-20 21:29:39.824879 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-02-20 21:29:40.041453 | instance | /bin/bash: line 4: kubectl: command not found
2026-02-20 21:29:40.358474 | instance | ok: Runtime: 0:00:00.038924
2026-02-20 21:29:40.365183 | 
2026-02-20 21:29:40.365251 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-02-20 21:29:40.855674 | instance | changed: cd+++++++++ prometheus/
2026-02-20 21:29:40.868339 | 
2026-02-20 21:29:40.868408 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-02-20 21:29:41.066379 | instance | changed
2026-02-20 21:29:41.073881 | 
2026-02-20 21:29:41.073976 | TASK [gather-selenium-data : Get selenium data]
2026-02-20 21:29:41.285148 | instance | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-02-20 21:29:41.286610 | instance | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-02-20 21:29:41.611312 | instance | ERROR
2026-02-20 21:29:41.611561 | instance | {
2026-02-20 21:29:41.611628 | instance |   "delta": "0:00:00.006817",
2026-02-20 21:29:41.611676 | instance |   "end": "2026-02-20 21:29:41.287016",
2026-02-20 21:29:41.611721 | instance |   "msg": "non-zero return code",
2026-02-20 21:29:41.611764 | instance |   "rc": 1,
2026-02-20 21:29:41.611806 | instance |   "start": "2026-02-20 21:29:41.280199"
2026-02-20 21:29:41.611847 | instance | }
2026-02-20 21:29:41.611899 | instance | ERROR: Ignoring Errors
2026-02-20 21:29:41.619289 | 
2026-02-20 21:29:41.619379 | TASK [gather-selenium-data : Downloads logs to executor]
2026-02-20 21:29:42.106886 | instance | changed: cd+++++++++ selenium/
2026-02-20 21:29:42.113031 | 
2026-02-20 21:29:42.113130 | PLAY RECAP
2026-02-20 21:29:42.113193 | instance | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-02-20 21:29:42.113221 | 
2026-02-20 21:29:42.252212 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere/test-playbooks/molecule/post.yml@stable/2023.2]
2026-02-20 21:29:42.254477 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-20 21:29:42.861265 | 
2026-02-20 21:29:42.861417 | PLAY [all]
2026-02-20 21:29:42.873505 | 
2026-02-20 21:29:42.873591 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-20 21:29:42.920612 | instance | skipping: Conditional result was False
2026-02-20 21:29:42.931089 | 
2026-02-20 21:29:42.931196 | TASK [fetch-output : Set log path for single node]
2026-02-20 21:29:42.974931 | instance | ok
2026-02-20 21:29:42.981064 | 
2026-02-20 21:29:42.981154 | LOOP [fetch-output : Ensure local output dirs]
2026-02-20 21:29:43.384622 | instance -> localhost | ok: "/var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/logs"
2026-02-20 21:29:43.608401 | instance -> localhost | changed: "/var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/artifacts"
2026-02-20 21:29:43.823240 | instance -> localhost | changed: "/var/lib/zuul/builds/6a2928f585ca48eeb3b7595ba08b83e2/work/docs"
2026-02-20 21:29:43.837802 | 
2026-02-20 21:29:43.838238 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-20 21:29:44.489060 | instance | changed: .d..t...... ./
2026-02-20 21:29:44.489321 | instance | changed: All items complete
2026-02-20 21:29:44.489363 | 
2026-02-20 21:29:45.043357 | instance | changed: .d..t...... ./
2026-02-20 21:29:45.547009 | instance | changed: .d..t...... ./
2026-02-20 21:29:45.565623 | 
2026-02-20 21:29:45.565765 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-20 21:29:45.994691 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.015635
2026-02-20 21:29:46.213979 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.008272
2026-02-20 21:29:46.234734 | 
2026-02-20 21:29:46.243564 | PLAY [all]
2026-02-20 21:29:46.255133 | 
2026-02-20 21:29:46.255208 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-20 21:29:46.683705 | instance | changed
2026-02-20 21:29:46.689362 | 
2026-02-20 21:29:46.689416 | PLAY RECAP
2026-02-20 21:29:46.689467 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-20 21:29:46.689489 | 
2026-02-20 21:29:46.835332 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-20 21:29:46.839591 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-20 21:29:47.469446 | 
2026-02-20 21:29:47.469777 | PLAY [localhost]
2026-02-20 21:29:47.485247 | 
2026-02-20 21:29:47.485321 | TASK [Generate Zuul manifest]
2026-02-20 21:29:47.517531 | localhost | ok
2026-02-20 21:29:47.533175 | 
2026-02-20 21:29:47.533251 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-20 21:29:47.844312 | localhost | changed
2026-02-20 21:29:47.858128 | 
2026-02-20 21:29:47.858216 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-20 21:29:47.888027 | localhost | ok
2026-02-20 21:29:47.894525 | 
2026-02-20 21:29:47.894600 | TASK [Upload logs]
2026-02-20 21:29:47.924553 | localhost | ok
2026-02-20 21:29:47.990068 | 
2026-02-20 21:29:47.990239 | TASK [Set zuul-log-path fact]
2026-02-20 21:29:48.014867 | localhost | ok
2026-02-20 21:29:48.033048 | 
2026-02-20 21:29:48.033144 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-20 21:29:48.065006 | localhost | ok
2026-02-20 21:29:48.073412 | 
2026-02-20 21:29:48.073476 | TASK [upload-logs : Create log directories]
2026-02-20 21:29:48.509207 | localhost | changed
2026-02-20 21:29:48.516344 | 
2026-02-20 21:29:48.516438 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-20 21:29:48.964652 | localhost -> localhost | ok: Runtime: 0:00:00.005057
2026-02-20 21:29:48.981896 | 
2026-02-20 21:29:48.981985 | TASK [upload-logs : Upload logs to log server]
2026-02-20 21:29:49.480308 | localhost | Output suppressed because no_log was given
2026-02-20 21:29:49.484916 | 
2026-02-20 21:29:49.485034 | LOOP [upload-logs : Compress console log and json output]
2026-02-20 21:29:49.541100 | localhost | skipping: Conditional result was False
2026-02-20 21:29:49.548682 | localhost | skipping: Conditional result was False
2026-02-20 21:29:49.560853 | 
2026-02-20 21:29:49.561079 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-20 21:29:49.605107 | localhost | skipping: Conditional result was False
2026-02-20 21:29:49.606761 | 
2026-02-20 21:29:49.608918 | localhost | skipping: Conditional result was False
2026-02-20 21:29:49.627392 | 
2026-02-20 21:29:49.627609 | LOOP [upload-logs : Upload console log and json output]