2026-09-22 22:37:50.114827 | Job console starting
2026-09-22 22:37:50.126145 | Updating git repos
2026-09-22 22:37:52.403608 | Cloning repos into workspace
2026-09-22 22:38:01.779420 | Restoring repo states
2026-09-22 22:38:10.588082 | Merging changes
2026-09-22 22:38:11.307277 | Checking out repos
2026-09-22 22:38:12.545208 | Preparing playbooks
2026-09-22 22:38:15.118478 | Running Ansible setup
2026-09-22 22:38:19.361217 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-22 22:38:19.901879 | 
2026-09-22 22:38:19.902012 | PLAY [localhost]
2026-09-22 22:38:19.909190 | 
2026-09-22 22:38:19.909254 | TASK [Gathering Facts]
2026-09-22 22:38:20.867300 | localhost | ok
2026-09-22 22:38:20.877931 | 
2026-09-22 22:38:20.878052 | TASK [Setup log path fact]
2026-09-22 22:38:20.897220 | localhost | ok
2026-09-22 22:38:20.911962 | 
2026-09-22 22:38:20.912069 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-22 22:38:20.939870 | localhost | ok
2026-09-22 22:38:20.953328 | 
2026-09-22 22:38:20.953413 | TASK [emit-job-header : Print job information]
2026-09-22 22:38:21.001661 | # Job Information
2026-09-22 22:38:21.001857 | Ansible Version: 2.16.18
2026-09-22 22:38:21.001902 | Job: atmosphere-common-molecule-secretgen-controller
2026-09-22 22:38:21.001933 | Pipeline: check
2026-09-22 22:38:21.001961 | Executor: 2d72f0692154
2026-09-22 22:38:21.001989 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/99
2026-09-22 22:38:21.002018 | Event ID: d68362a0-b6d5-11f1-9650-f7f2d13973f9
2026-09-22 22:38:21.005804 | 
2026-09-22 22:38:21.005887 | LOOP [emit-job-header : Print node information]
2026-09-22 22:38:21.160912 | localhost | ok:
2026-09-22 22:38:21.161146 | localhost | # Node Information
2026-09-22 22:38:21.161276 | localhost | Inventory Hostname: controller
2026-09-22 22:38:21.161330 | localhost | Hostname: np0000193746
2026-09-22 22:38:21.161373 | localhost | Username: zuul
2026-09-22 22:38:21.161419 | localhost | Distro: Ubuntu 24.04
2026-09-22 22:38:21.161509 | localhost | Provider: yul1
2026-09-22 22:38:21.161615 | localhost | Region: ca-ymq-1
2026-09-22 22:38:21.161664 | localhost | Label: ubuntu-noble
2026-09-22 22:38:21.161730 | localhost | Product Name: OpenStack Nova
2026-09-22 22:38:21.161774 | localhost | Interface IP: 162.253.55.32
2026-09-22 22:38:21.178776 | 
2026-09-22 22:38:21.178923 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-22 22:38:21.537219 | localhost -> localhost | changed
2026-09-22 22:38:21.545341 | 
2026-09-22 22:38:21.545431 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-22 22:38:22.377332 | localhost -> localhost | changed
2026-09-22 22:38:22.382864 | 
2026-09-22 22:38:22.382938 | PLAY [all]
2026-09-22 22:38:22.389730 | 
2026-09-22 22:38:22.389795 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-22 22:38:22.624441 | controller -> localhost | ok
2026-09-22 22:38:22.637423 | 
2026-09-22 22:38:22.637557 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-22 22:38:22.665595 | controller | ok
2026-09-22 22:38:22.690623 | controller | included: /var/lib/zuul/builds/052c112603d0438a84bcde696af13812/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-22 22:38:22.699558 | 
2026-09-22 22:38:22.699640 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-22 22:38:24.044849 | controller -> localhost | Generating public/private rsa key pair.
2026-09-22 22:38:24.045078 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/052c112603d0438a84bcde696af13812_id_rsa
2026-09-22 22:38:24.045118 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/052c112603d0438a84bcde696af13812_id_rsa.pub
2026-09-22 22:38:24.045147 | controller -> localhost | The key fingerprint is:
2026-09-22 22:38:24.045175 | controller -> localhost | SHA256:4b3A5toDjWMIWreQVHWfOEzXPWamnmTD0Gwk0Wf2skQ zuul-build-sshkey
2026-09-22 22:38:24.045216 | controller -> localhost | The key's randomart image is:
2026-09-22 22:38:24.045243 | controller -> localhost | +---[RSA 3072]----+
2026-09-22 22:38:24.045276 | controller -> localhost | |   .... o +B..   |
2026-09-22 22:38:24.045305 | controller -> localhost | |  .    + +.o* E  |
2026-09-22 22:38:24.045331 | controller -> localhost | | . .    = o+ X o |
2026-09-22 22:38:24.045358 | controller -> localhost | |  = .  o +  * o .|
2026-09-22 22:38:24.045384 | controller -> localhost | | o + o oS .+ + o |
2026-09-22 22:38:24.045410 | controller -> localhost | |.   o =o.. .o .  |
2026-09-22 22:38:24.045435 | controller -> localhost | |     . o. .      |
2026-09-22 22:38:24.045460 | controller -> localhost | |       o.        |
2026-09-22 22:38:24.045489 | controller -> localhost | |      . ..       |
2026-09-22 22:38:24.045515 | controller -> localhost | +----[SHA256]-----+
2026-09-22 22:38:24.045576 | controller -> localhost | ok: Runtime: 0:00:01.000622
2026-09-22 22:38:24.053939 | 
2026-09-22 22:38:24.054044 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-22 22:38:24.074534 | controller | ok
2026-09-22 22:38:24.087101 | controller | included: /var/lib/zuul/builds/052c112603d0438a84bcde696af13812/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-22 22:38:24.099129 | 
2026-09-22 22:38:24.099221 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-22 22:38:24.113115 | controller | skipping: Conditional result was False
2026-09-22 22:38:24.121341 | 
2026-09-22 22:38:24.121438 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-22 22:38:24.643239 | controller | changed
2026-09-22 22:38:24.652879 | 
2026-09-22 22:38:24.652998 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-22 22:38:24.869716 | controller | ok
2026-09-22 22:38:24.879507 | 
2026-09-22 22:38:24.879623 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-22 22:38:25.480361 | controller | changed
2026-09-22 22:38:25.491438 | 
2026-09-22 22:38:25.491565 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-22 22:38:26.062508 | controller | changed
2026-09-22 22:38:26.073314 | 
2026-09-22 22:38:26.073437 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-22 22:38:26.097622 | controller | skipping: Conditional result was False
2026-09-22 22:38:26.108114 | 
2026-09-22 22:38:26.108235 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-22 22:38:26.465954 | controller -> localhost | changed
2026-09-22 22:38:26.487429 | 
2026-09-22 22:38:26.487580 | TASK [add-build-sshkey : Add back temp key]
2026-09-22 22:38:26.792002 | controller -> localhost | Identity added: /var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/052c112603d0438a84bcde696af13812_id_rsa (zuul-build-sshkey)
2026-09-22 22:38:26.792200 | controller -> localhost | ok: Runtime: 0:00:00.010591
2026-09-22 22:38:26.798810 | 
2026-09-22 22:38:26.798902 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-22 22:38:27.153559 | controller | ok
2026-09-22 22:38:27.160250 | 
2026-09-22 22:38:27.160351 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-22 22:38:27.184357 | controller | skipping: Conditional result was False
2026-09-22 22:38:27.231573 | 
2026-09-22 22:38:27.231704 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-22 22:38:27.569017 | controller | ok
2026-09-22 22:38:27.580439 | 
2026-09-22 22:38:27.580599 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-22 22:38:31.266630 | controller | Output suppressed because no_log was given
2026-09-22 22:38:31.285853 | 
2026-09-22 22:38:31.286096 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-22 22:38:31.544025 | controller | ok: "logs"
2026-09-22 22:38:31.544362 | controller | ok: All items complete
2026-09-22 22:38:31.544419 | 
2026-09-22 22:38:31.754843 | controller | ok: "artifacts"
2026-09-22 22:38:31.967493 | controller | ok: "docs"
2026-09-22 22:38:31.986645 | 
2026-09-22 22:38:31.986857 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-22 22:38:32.214086 | controller | changed: "logs"
2026-09-22 22:38:32.398268 | controller | changed: "artifacts"
2026-09-22 22:38:32.612421 | controller | changed: "docs"
2026-09-22 22:38:32.633341 | 
2026-09-22 22:38:32.633475 | PLAY RECAP
2026-09-22 22:38:32.633555 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-22 22:38:32.633610 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-22 22:38:32.633652 | 
2026-09-22 22:38:32.762247 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-22 22:38:32.764597 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-22 22:38:33.286182 | 
2026-09-22 22:38:33.286286 | PLAY [all]
2026-09-22 22:38:33.296968 | 
2026-09-22 22:38:33.297045 | TASK [setup-uv : Extract archive]
2026-09-22 22:38:35.799610 | controller | changed
2026-09-22 22:38:35.805306 | 
2026-09-22 22:38:35.805382 | TASK [setup-uv : Print version]
2026-09-22 22:38:36.199804 | controller | uv 0.8.13
2026-09-22 22:38:36.336860 | controller | ok: Runtime: 0:00:00.019841
2026-09-22 22:38:36.355654 | 
2026-09-22 22:38:36.355806 | TASK [Install Ansible collection siblings]
2026-09-22 22:38:50.783344 | controller | changed:
2026-09-22 22:38:50.783648 | controller | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-22 22:38:50.783707 | controller | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-22 22:38:50.783768 | controller | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-22 22:38:50.783809 | controller | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-22 22:38:50.783846 | controller | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-22 22:38:50.783891 | controller | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-22 22:38:50.783930 | controller | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-22 22:38:50.783971 | controller | Resolved kubernetes.core to 6.6.0 for >=2.4.0
2026-09-22 22:38:50.784009 | controller | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-22 22:38:50.784044 | controller | Resolved vexxhost.kubernetes to v3.4.0 for >=2.5.0
2026-09-22 22:38:50.784080 | controller | Resolved ansible.posix to 2.2.2 for >=1.6.0
2026-09-22 22:38:50.784115 | controller | Resolved community.crypto to 3.4.0 for >=2.2.3
2026-09-22 22:38:50.784149 | controller | Resolved community.general to 13.4.0 for >=4.5.0
2026-09-22 22:38:50.784183 | controller | Skipping community.library_inventory_filtering_v1: no checked-out sibling
2026-09-22 22:38:50.784218 | controller | Resolved kubernetes.core to 6.6.0 for >=2.4.0,>=2.3.2
2026-09-22 22:38:50.784253 | controller | Resolved vexxhost.containers to v1.6.8 for >=1.6.0
2026-09-22 22:38:50.784288 | controller | Checked out ansible.posix to 2.2.2
2026-09-22 22:38:50.784324 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-22 22:38:50.784366 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.784401 | controller | Process install dependency map
2026-09-22 22:38:50.784437 | controller | Starting collection install process
2026-09-22 22:38:50.784472 | controller | Installing 'ansible.posix:2.2.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-22 22:38:50.784641 | controller | Created collection for ansible.posix:2.2.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-22 22:38:50.784698 | controller | ansible.posix:2.2.2 was installed successfully
2026-09-22 22:38:50.784736 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-22 22:38:50.784805 | controller | Creating virtual environment at: .venv
2026-09-22 22:38:50.784845 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.784881 | controller | Downloading pydantic-core (2.0MiB)
2026-09-22 22:38:50.784916 | controller | Downloading cryptography (4.3MiB)
2026-09-22 22:38:50.784953 | controller | Downloading ansible-core (2.1MiB)
2026-09-22 22:38:50.784988 | controller | Downloading kubernetes (1.9MiB)
2026-09-22 22:38:50.785023 | controller | Downloading pygments (1.2MiB)
2026-09-22 22:38:50.785058 | controller |  Downloading pydantic-core
2026-09-22 22:38:50.785092 | controller |  Downloading cryptography
2026-09-22 22:38:50.785146 | controller |  Downloading pygments
2026-09-22 22:38:50.785182 | controller |  Downloading kubernetes
2026-09-22 22:38:50.785217 | controller |  Downloading ansible-core
2026-09-22 22:38:50.785251 | controller | Installed 52 packages in 64ms
2026-09-22 22:38:50.785285 | controller | Checked out community.crypto to 3.4.0
2026-09-22 22:38:50.785319 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-22 22:38:50.785354 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.785394 | controller | Process install dependency map
2026-09-22 22:38:50.785467 | controller | Starting collection install process
2026-09-22 22:38:50.785548 | controller | Installing 'community.crypto:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-22 22:38:50.785590 | controller | Created collection for community.crypto:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-22 22:38:50.785633 | controller | community.crypto:3.4.0 was installed successfully
2026-09-22 22:38:50.785669 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.785703 | controller | Checked out community.general to 13.4.0
2026-09-22 22:38:50.785737 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/community.general
2026-09-22 22:38:50.785798 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.785834 | controller | Process install dependency map
2026-09-22 22:38:50.785869 | controller | Starting collection install process
2026-09-22 22:38:50.785903 | controller | Installing 'community.general:13.4.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-22 22:38:50.785942 | controller | Created collection for community.general:13.4.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-22 22:38:50.785977 | controller | community.general:13.4.0 was installed successfully
2026-09-22 22:38:50.786013 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.786047 | controller | Checked out kubernetes.core to 6.6.0
2026-09-22 22:38:50.786082 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-22 22:38:50.786116 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.786150 | controller | Process install dependency map
2026-09-22 22:38:50.786183 | controller | Starting collection install process
2026-09-22 22:38:50.786217 | controller | Installing 'kubernetes.core:6.6.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-22 22:38:50.786264 | controller | Created collection for kubernetes.core:6.6.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-22 22:38:50.786301 | controller | kubernetes.core:6.6.0 was installed successfully
2026-09-22 22:38:50.786335 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.786369 | controller | Checked out openstack.cloud to 2.6.0
2026-09-22 22:38:50.786402 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-22 22:38:50.786435 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.786544 | controller | Process install dependency map
2026-09-22 22:38:50.786594 | controller | Starting collection install process
2026-09-22 22:38:50.786629 | controller | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-22 22:38:50.786663 | controller | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-22 22:38:50.786695 | controller | openstack.cloud:2.6.0 was installed successfully
2026-09-22 22:38:50.786728 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.786785 | controller | Checked out vexxhost.containers to v1.6.8
2026-09-22 22:38:50.786820 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-22 22:38:50.786854 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.786888 | controller | Process install dependency map
2026-09-22 22:38:50.786921 | controller | Starting collection install process
2026-09-22 22:38:50.786955 | controller | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-22 22:38:50.786989 | controller | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-22 22:38:50.787022 | controller | vexxhost.containers:1.6.8 was installed successfully
2026-09-22 22:38:50.787056 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.787090 | controller | Checked out vexxhost.kubernetes to v3.4.0
2026-09-22 22:38:50.787124 | controller | Running: uv run ansible-galaxy collection install --force --no-deps --collections-path /home/zuul/.ansible/collections /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-22 22:38:50.787157 | controller | Starting galaxy collection install process
2026-09-22 22:38:50.787190 | controller | Process install dependency map
2026-09-22 22:38:50.787223 | controller | Starting collection install process
2026-09-22 22:38:50.787255 | controller | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-22 22:38:50.787288 | controller | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-22 22:38:50.787320 | controller | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-22 22:38:50.787352 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:38:50.794446 | 
2026-09-22 22:38:50.794533 | PLAY RECAP
2026-09-22 22:38:50.794609 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-22 22:38:50.794650 | 
2026-09-22 22:38:50.898371 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-22 22:38:50.900734 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-22 22:38:51.459650 | 
2026-09-22 22:38:51.459780 | PLAY [Prepare Molecule log collection]
2026-09-22 22:38:51.469801 | 
2026-09-22 22:38:51.469868 | TASK [Install jq for log collection]
2026-09-22 22:38:59.829942 | controller | changed
2026-09-22 22:38:59.832888 | 
2026-09-22 22:38:59.833005 | PLAY RECAP
2026-09-22 22:38:59.833099 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-22 22:38:59.833192 | 
2026-09-22 22:38:59.945229 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-22 22:38:59.947725 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-22 22:39:00.458693 | 
2026-09-22 22:39:00.458803 | PLAY [all]
2026-09-22 22:39:00.468453 | 
2026-09-22 22:39:00.468543 | LOOP [Check inventory directories]
2026-09-22 22:39:00.843979 | controller | ok: "src/github.com/vexxhost/atmosphere.common"
2026-09-22 22:39:01.044828 | controller | ok: "src/github.com/vexxhost/atmosphere.common/molecule/secretgen-controller/inventory"
2026-09-22 22:39:01.220375 | controller | ok: "src/github.com/vexxhost/atmosphere.common/extensions/molecule/secretgen-controller/inventory"
2026-09-22 22:39:01.231827 | 
2026-09-22 22:39:01.232002 | LOOP [Copy inventory file for Zuul]
2026-09-22 22:39:02.197692 | controller | changed:
2026-09-22 22:39:02.197975 | controller | {
2026-09-22 22:39:02.198031 | controller |   "ansible_loop_var": "item",
2026-09-22 22:39:02.198075 | controller |   "changed": false,
2026-09-22 22:39:02.198113 | controller |   "failed": false,
2026-09-22 22:39:02.198156 | controller |   "invocation": {
2026-09-22 22:39:02.198191 | controller |     "module_args": {
2026-09-22 22:39:02.198226 | controller |       "checksum_algorithm": "sha1",
2026-09-22 22:39:02.198260 | controller |       "follow": true,
2026-09-22 22:39:02.198294 | controller |       "get_attributes": true,
2026-09-22 22:39:02.198329 | controller |       "get_checksum": true,
2026-09-22 22:39:02.198363 | controller |       "get_mime": true,
2026-09-22 22:39:02.198396 | controller |       "path": "src/github.com/vexxhost/atmosphere.common"
2026-09-22 22:39:02.198430 | controller |     }
2026-09-22 22:39:02.198463 | controller |   },
2026-09-22 22:39:02.198495 | controller |   "item": "src/github.com/vexxhost/atmosphere.common",
2026-09-22 22:39:02.198528 | controller |   "stat": {
2026-09-22 22:39:02.198561 | controller |     "atime": 1790116710.9612985,
2026-09-22 22:39:02.198594 | controller |     "attr_flags": "e",
2026-09-22 22:39:02.198628 | controller |     "attributes": [
2026-09-22 22:39:02.198661 | controller |       "extents"
2026-09-22 22:39:02.198693 | controller |     ],
2026-09-22 22:39:02.198725 | controller |     "block_size": 4096,
2026-09-22 22:39:02.198780 | controller |     "blocks": 8,
2026-09-22 22:39:02.198818 | controller |     "charset": "unknown",
2026-09-22 22:39:02.198853 | controller |     "ctime": 1790116717.1393154,
2026-09-22 22:39:02.198887 | controller |     "dev": 64769,
2026-09-22 22:39:02.198920 | controller |     "device_type": 0,
2026-09-22 22:39:02.198953 | controller |     "executable": true,
2026-09-22 22:39:02.198985 | controller |     "exists": true,
2026-09-22 22:39:02.199017 | controller |     "gid": 1000,
2026-09-22 22:39:02.199049 | controller |     "gr_name": "zuul",
2026-09-22 22:39:02.199081 | controller |     "inode": 1037943,
2026-09-22 22:39:02.199113 | controller |     "isblk": false,
2026-09-22 22:39:02.199147 | controller |     "ischr": false,
2026-09-22 22:39:02.199179 | controller |     "isdir": true,
2026-09-22 22:39:02.199211 | controller |     "isfifo": false,
2026-09-22 22:39:02.199243 | controller |     "isgid": false,
2026-09-22 22:39:02.199275 | controller |     "islnk": false,
2026-09-22 22:39:02.199307 | controller |     "isreg": false,
2026-09-22 22:39:02.199339 | controller |     "issock": false,
2026-09-22 22:39:02.199371 | controller |     "isuid": false,
2026-09-22 22:39:02.199417 | controller |     "mimetype": "unknown",
2026-09-22 22:39:02.199451 | controller |     "mode": "0755",
2026-09-22 22:39:02.199483 | controller |     "mtime": 1790116717.1393154,
2026-09-22 22:39:02.199515 | controller |     "nlink": 11,
2026-09-22 22:39:02.199546 | controller |     "path": "src/github.com/vexxhost/atmosphere.common",
2026-09-22 22:39:02.199578 | controller |     "pw_name": "zuul",
2026-09-22 22:39:02.199611 | controller |     "readable": true,
2026-09-22 22:39:02.199642 | controller |     "rgrp": true,
2026-09-22 22:39:02.199674 | controller |     "roth": true,
2026-09-22 22:39:02.199708 | controller |     "rusr": true,
2026-09-22 22:39:02.199740 | controller |     "size": 4096,
2026-09-22 22:39:02.199792 | controller |     "uid": 1000,
2026-09-22 22:39:02.199827 | controller |     "version": "3197628142",
2026-09-22 22:39:02.199860 | controller |     "wgrp": false,
2026-09-22 22:39:02.199894 | controller |     "woth": false,
2026-09-22 22:39:02.199927 | controller |     "writeable": true,
2026-09-22 22:39:02.199960 | controller |     "wusr": true,
2026-09-22 22:39:02.199992 | controller |     "xgrp": true,
2026-09-22 22:39:02.200024 | controller |     "xoth": true,
2026-09-22 22:39:02.200055 | controller |     "xusr": true
2026-09-22 22:39:02.200087 | controller |   }
2026-09-22 22:39:02.200120 | controller | }
2026-09-22 22:39:02.200181 | controller | ok: All items complete
2026-09-22 22:39:02.200222 | 
2026-09-22 22:39:02.202478 | controller | skipping: Conditional result was False
2026-09-22 22:39:02.203605 | controller | skipping: Conditional result was False
2026-09-22 22:39:02.219092 | 
2026-09-22 22:39:02.219272 | LOOP [Switch "ansible_host" to private IP]
2026-09-22 22:39:02.606824 | controller | changed: 1 replacements made
2026-09-22 22:39:02.609010 | controller | skipping: Conditional result was False
2026-09-22 22:39:02.610752 | controller | skipping: Conditional result was False
2026-09-22 22:39:02.627570 | 
2026-09-22 22:39:02.627735 | TASK [Run Molecule scenario]
2026-09-22 22:39:03.050787 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-22 22:39:03.747592 | controller | INFO     Collection 'atmosphere.common' detected.
2026-09-22 22:39:03.748940 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-22 22:39:04.175596 | controller | INFO     [secretgen-controller > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-22 22:39:04.175638 | controller | INFO     [secretgen-controller > prerun] Performing prerun with role_name_check=0...
2026-09-22 22:39:22.063725 | controller | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv --force /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-22 22:39:22.063846 | controller | ERROR    Command ansible-galaxy collection install -vvv --force /home/zuul/src/github.com/vexxhost/atmosphere.common, returned 1 code:
2026-09-22 22:39:22.063878 | controller | Starting galaxy collection install process
2026-09-22 22:39:22.063888 | controller | Process install dependency map
2026-09-22 22:39:22.063897 | controller |
2026-09-22 22:39:22.063906 | controller | ansible-galaxy
2026-09-22 22:39:22.063916 | controller |   config file = None
2026-09-22 22:39:22.063925 | controller |   configured module search path =
2026-09-22 22:39:22.063956 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-22 22:39:22.063965 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages
2026-09-22 22:39:22.063980 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-22 22:39:22.063988 | controller |   python version = 3.12.3 (main, Aug 31 2026, 10:18:26)  (/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/python)
2026-09-22 22:39:22.063997 | controller |   jinja version = 3.1.6
2026-09-22 22:39:22.064005 | controller |   libyaml = True
2026-09-22 22:39:22.064013 | controller | No config file found; using defaults
2026-09-22 22:39:22.064022 | controller | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-22 22:39:22.064030 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-22 22:39:22.064038 | controller | Found installed collection kubernetes.core:6.6.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-22 22:39:22.064046 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-22 22:39:22.064054 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-22 22:39:22.064062 | controller | Found installed collection vexxhost.containers:1.6.8 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-22 22:39:22.064071 | controller | Found installed collection vexxhost.kubernetes:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-22 22:39:22.064079 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-22 22:39:22.064087 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-22 22:39:22.064095 | controller | unexpected error when getting available versions of collection
2026-09-22 22:39:22.064104 | controller | community.library_inventory_filtering_v1: Unknown error when attempting to call
2026-09-22 22:39:22.064111 | controller | Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inve
2026-09-22 22:39:22.064119 | controller | ntory_filtering_v1/versions/1.1.5/': <urlopen error  Connection
2026-09-22 22:39:22.064127 | controller | reset by peer>. <urlopen error  Connection reset by peer>
2026-09-22 22:39:22.064141 | controller | ERROR! Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inventory_filtering_v1/versions/1.1.5/': <urlopen error  Connection reset by peer>. <urlopen error  Connection reset by peer>
2026-09-22 22:39:22.064179 | controller |
2026-09-22 22:39:22.064500 | controller | Traceback (most recent call last):
2026-09-22 22:39:22.064792 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/molecule", line 10, in <module>
2026-09-22 22:39:22.065296 | controller |     sys.exit(main())
2026-09-22 22:39:22.065552 | controller |              ^^^^^^
2026-09-22 22:39:22.065787 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1442, in __call__
2026-09-22 22:39:22.066497 | controller |     return self.main(*args, **kwargs)
2026-09-22 22:39:22.066780 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.067016 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1363, in main
2026-09-22 22:39:22.067666 | controller |     rv = self.invoke(ctx)
2026-09-22 22:39:22.067998 | controller |          ^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.068145 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1830, in invoke
2026-09-22 22:39:22.069019 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-22 22:39:22.069233 | controller |                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.069425 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1226, in invoke
2026-09-22 22:39:22.070128 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-22 22:39:22.070337 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.070528 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 794, in invoke
2026-09-22 22:39:22.071062 | controller |     return callback(*args, **kwargs)
2026-09-22 22:39:22.071303 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.071492 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 583, in wrapper
2026-09-22 22:39:22.071995 | controller |     return func(*args, **kwargs)
2026-09-22 22:39:22.072219 | controller |            ^^^^^^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.072414 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/decorators.py", line 34, in new_func
2026-09-22 22:39:22.072724 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-22 22:39:22.073034 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-22 22:39:22.073229 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 418, in wrapper
2026-09-22 22:39:22.073612 | controller |     return func(ctx)
2026-09-22 22:39:22.073874 | controller |            ^^^^^^^^^
2026-09-22 22:39:22.074095 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/test.py", line 81, in test
2026-09-22 22:39:22.074413 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-22 22:39:22.074677 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 169, in execute_cmdline_scenarios
2026-09-22 22:39:22.075071 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-22 22:39:22.075312 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 240, in _run_scenarios
2026-09-22 22:39:22.075662 | controller |     scenario.config.runtime.prepare_environment(
2026-09-22 22:39:22.075932 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 782, in prepare_environment
2026-09-22 22:39:22.076428 | controller |     self.install_collection_from_disk(
2026-09-22 22:39:22.076635 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 617, in install_collection_from_disk
2026-09-22 22:39:22.077159 | controller |     self.install_collection(path, destination=destination, force=True)
2026-09-22 22:39:22.077416 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 609, in install_collection
2026-09-22 22:39:22.077900 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-22 22:39:22.079660 | controller | ansible_compat.errors.InvalidPrerequisiteError: Command ansible-galaxy collection install -vvv --force /home/zuul/src/github.com/vexxhost/atmosphere.common, returned 1 code:
2026-09-22 22:39:22.079678 | controller | Starting galaxy collection install process
2026-09-22 22:39:22.079686 | controller | Process install dependency map
2026-09-22 22:39:22.079695 | controller |
2026-09-22 22:39:22.079704 | controller | ansible-galaxy [core 2.18.17]
2026-09-22 22:39:22.079712 | controller |   config file = None
2026-09-22 22:39:22.079720 | controller |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-22 22:39:22.079728 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-22 22:39:22.079765 | controller |   ansible collection location = /home/zuul/.ansible/collections:/usr/share/ansible/collections:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/local/lib/python3.12/dist-packages:/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages
2026-09-22 22:39:22.079774 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-22 22:39:22.079782 | controller |   python version = 3.12.3 (main, Aug 31 2026, 10:18:26) [GCC 13.3.0] (/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/python)
2026-09-22 22:39:22.079790 | controller |   jinja version = 3.1.6
2026-09-22 22:39:22.079799 | controller |   libyaml = True
2026-09-22 22:39:22.079807 | controller | No config file found; using defaults
2026-09-22 22:39:22.079815 | controller | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-22 22:39:22.079823 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-22 22:39:22.079831 | controller | Found installed collection kubernetes.core:6.6.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-22 22:39:22.079840 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-22 22:39:22.079848 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-22 22:39:22.079882 | controller | Found installed collection vexxhost.containers:1.6.8 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-22 22:39:22.079891 | controller | Found installed collection vexxhost.kubernetes:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-22 22:39:22.079899 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-22 22:39:22.079907 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-22 22:39:22.079915 | controller | unexpected error when getting available versions of collection
2026-09-22 22:39:22.079923 | controller | community.library_inventory_filtering_v1: Unknown error when attempting to call
2026-09-22 22:39:22.079932 | controller | Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inve
2026-09-22 22:39:22.079940 | controller | ntory_filtering_v1/versions/1.1.5/': <urlopen error [Errno 104] Connection
2026-09-22 22:39:22.079948 | controller | reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-22 22:39:22.079962 | controller | ERROR! Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inventory_filtering_v1/versions/1.1.5/': <urlopen error [Errno 104] Connection reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-22 22:39:22.079993 | controller |
2026-09-22 22:39:22.218037 | controller | ERROR
2026-09-22 22:39:22.218241 | controller | {
2026-09-22 22:39:22.218277 | controller |   "delta": "0:00:19.126590",
2026-09-22 22:39:22.218302 | controller |   "end": "2026-09-22 22:39:22.155716",
2026-09-22 22:39:22.218326 | controller |   "msg": "non-zero return code",
2026-09-22 22:39:22.218348 | controller |   "rc": 1,
2026-09-22 22:39:22.218383 | controller |   "start": "2026-09-22 22:39:03.029126"
2026-09-22 22:39:22.218405 | controller | }
failure
2026-09-22 22:39:22.222641 | 
2026-09-22 22:39:22.222941 | PLAY RECAP
2026-09-22 22:39:22.223203 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-22 22:39:22.223238 | 
2026-09-22 22:39:22.350447 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-22 22:39:22.353037 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-22 22:39:22.903709 | 
2026-09-22 22:39:22.903837 | PLAY [Collect Molecule logs]
2026-09-22 22:39:22.915879 | 
2026-09-22 22:39:22.915947 | TASK [gather-host-logs : creating directory for system status]
2026-09-22 22:39:23.314744 | controller | changed
2026-09-22 22:39:23.325143 | 
2026-09-22 22:39:23.325289 | TASK [gather-host-logs : Get logs for each host]
2026-09-22 22:39:23.765328 | controller | + systemd-cgls --full --all --no-pager
2026-09-22 22:39:23.790946 | controller | + ip addr
2026-09-22 22:39:23.795846 | controller | + ip route
2026-09-22 22:39:23.799704 | controller | + lsblk
2026-09-22 22:39:23.808286 | controller | + mount
2026-09-22 22:39:23.812201 | controller | + docker images
2026-09-22 22:39:23.813274 | controller | /bin/bash: line 7: docker: command not found
2026-09-22 22:39:23.813872 | controller | + brctl show
2026-09-22 22:39:23.814724 | controller | /bin/bash: line 8: brctl: command not found
2026-09-22 22:39:23.815352 | controller | + ip neigh
2026-09-22 22:39:23.819616 | controller | + bridge fdb show
2026-09-22 22:39:23.824855 | controller | + ps aux --sort=-%mem
2026-09-22 22:39:23.858541 | controller | + dpkg -l
2026-09-22 22:39:23.880117 | controller | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-22 22:39:23.881102 | controller | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-22 22:39:23.881171 | controller | /bin/bash: line 13: docker: command not found
2026-09-22 22:39:23.881803 | controller | + '[' '!' -z '' ']'
2026-09-22 22:39:24.374069 | controller | ok: Runtime: 0:00:00.125637
2026-09-22 22:39:24.385695 | 
2026-09-22 22:39:24.385836 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-22 22:39:25.068222 | controller | changed:
2026-09-22 22:39:25.068444 | controller | created directory /var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/logs/controller
2026-09-22 22:39:25.068523 | controller | cd+++++++++ system/
2026-09-22 22:39:25.068570 | controller | >f+++++++++ system/brctl-show.txt
2026-09-22 22:39:25.068609 | controller | >f+++++++++ system/bridge-fdb.txt
2026-09-22 22:39:25.068646 | controller | >f+++++++++ system/docker-images.txt
2026-09-22 22:39:25.068687 | controller | >f+++++++++ system/ip-addr.txt
2026-09-22 22:39:25.068729 | controller | >f+++++++++ system/ip-neigh.txt
2026-09-22 22:39:25.068767 | controller | >f+++++++++ system/ip-route.txt
2026-09-22 22:39:25.068825 | controller | >f+++++++++ system/lsblk.txt
2026-09-22 22:39:25.068862 | controller | >f+++++++++ system/mount.txt
2026-09-22 22:39:25.068898 | controller | >f+++++++++ system/packages.txt
2026-09-22 22:39:25.068933 | controller | >f+++++++++ system/ps.txt
2026-09-22 22:39:25.068967 | controller | >f+++++++++ system/systemd-cgls.txt
2026-09-22 22:39:25.087284 | 
2026-09-22 22:39:25.087403 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-22 22:39:25.353818 | controller | changed: "values"
2026-09-22 22:39:25.582346 | controller | changed: "releases"
2026-09-22 22:39:25.594198 | 
2026-09-22 22:39:25.594364 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-22 22:39:25.888566 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-22 22:39:26.135949 | controller | ok: Runtime: 0:00:00.009589
2026-09-22 22:39:26.140552 | 
2026-09-22 22:39:26.140643 | TASK [helm-release-status : Downloads logs to executor]
2026-09-22 22:39:26.690828 | controller | changed:
2026-09-22 22:39:26.691063 | controller | cd+++++++++ helm/
2026-09-22 22:39:26.691117 | controller | cd+++++++++ helm/releases/
2026-09-22 22:39:26.691159 | controller | cd+++++++++ helm/values/
2026-09-22 22:39:26.710345 | 
2026-09-22 22:39:26.710469 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-22 22:39:26.977207 | controller | changed
2026-09-22 22:39:26.986726 | 
2026-09-22 22:39:26.986872 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-22 22:39:27.299435 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-22 22:39:27.299622 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-22 22:39:27.308262 | controller | environment: line 1: kubectl: command not found
2026-09-22 22:39:27.310660 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-22 22:39:27.311979 | controller | environment: line 1: kubectl: command not found
2026-09-22 22:39:27.315674 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-22 22:39:27.316309 | controller | environment: line 1: kubectl: command not found
2026-09-22 22:39:27.318809 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-22 22:39:27.320884 | controller | environment: line 1: kubectl: command not found
2026-09-22 22:39:27.323526 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-22 22:39:27.325362 | controller | environment: line 1: kubectl: command not found
2026-09-22 22:39:27.327638 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-22 22:39:27.562985 | controller | ok: Runtime: 0:00:00.043510
2026-09-22 22:39:27.573737 | 
2026-09-22 22:39:27.573961 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-22 22:39:27.799747 | controller | changed
2026-09-22 22:39:27.810376 | 
2026-09-22 22:39:27.810504 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-22 22:39:28.076901 | controller | environment: line 5: kubectl: command not found
2026-09-22 22:39:28.077987 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-22 22:39:28.078172 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-22 22:39:28.078652 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-22 22:39:28.360651 | controller | ok: Runtime: 0:00:00.015161
2026-09-22 22:39:28.371046 | 
2026-09-22 22:39:28.371218 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-22 22:39:28.871428 | controller | changed:
2026-09-22 22:39:28.871653 | controller | cd+++++++++ objects/
2026-09-22 22:39:28.871705 | controller | cd+++++++++ objects/cluster/
2026-09-22 22:39:28.871744 | controller | cd+++++++++ objects/namespaced/
2026-09-22 22:39:28.890499 | 
2026-09-22 22:39:28.890661 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-22 22:39:29.146273 | controller | changed
2026-09-22 22:39:29.155846 | 
2026-09-22 22:39:29.155965 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-22 22:39:29.412199 | controller | changed
2026-09-22 22:39:29.422051 | 
2026-09-22 22:39:29.422171 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-22 22:39:29.704858 | controller | environment: line 3: kubectl: command not found
2026-09-22 22:39:29.973711 | controller | ok: Runtime: 0:00:00.014530
2026-09-22 22:39:29.984182 | 
2026-09-22 22:39:29.984359 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-22 22:39:30.515273 | controller | changed:
2026-09-22 22:39:30.515502 | controller | cd+++++++++ pod-logs/
2026-09-22 22:39:30.515555 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-22 22:39:30.533171 | 
2026-09-22 22:39:30.533290 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-22 22:39:30.808051 | controller | changed
2026-09-22 22:39:30.815035 | 
2026-09-22 22:39:30.815121 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-22 22:39:31.098222 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-22 22:39:31.354416 | controller | ok: Runtime: 0:00:00.018263
2026-09-22 22:39:31.364687 | 
2026-09-22 22:39:31.364835 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-22 22:39:31.665088 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-22 22:39:31.672305 | controller | ceph-mgr endpoints:
2026-09-22 22:39:31.920110 | controller | ok: Runtime: 0:00:00.017729
2026-09-22 22:39:31.933142 | 
2026-09-22 22:39:31.933324 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-22 22:39:32.225021 | controller | /bin/bash: line 4: kubectl: command not found
2026-09-22 22:39:32.473622 | controller | ok: Runtime: 0:00:00.018652
2026-09-22 22:39:32.480817 | 
2026-09-22 22:39:32.480970 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-22 22:39:33.000438 | controller | changed: cd+++++++++ prometheus/
2026-09-22 22:39:33.050396 | 
2026-09-22 22:39:33.050520 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-22 22:39:33.324617 | controller | changed
2026-09-22 22:39:33.334551 | 
2026-09-22 22:39:33.334646 | TASK [gather-selenium-data : Get selenium data]
2026-09-22 22:39:33.618138 | controller | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-22 22:39:33.622836 | controller | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-22 22:39:33.876526 | controller | ERROR
2026-09-22 22:39:33.876828 | controller | {
2026-09-22 22:39:33.876893 | controller |   "delta": "0:00:00.014550",
2026-09-22 22:39:33.876933 | controller |   "end": "2026-09-22 22:39:33.623483",
2026-09-22 22:39:33.876969 | controller |   "msg": "non-zero return code",
2026-09-22 22:39:33.877020 | controller |   "rc": 1,
2026-09-22 22:39:33.877070 | controller |   "start": "2026-09-22 22:39:33.608933"
2026-09-22 22:39:33.877106 | controller | }
2026-09-22 22:39:33.877149 | controller | ERROR: Ignoring Errors
2026-09-22 22:39:33.887307 | 
2026-09-22 22:39:33.887448 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-22 22:39:34.426991 | controller | changed: cd+++++++++ selenium/
2026-09-22 22:39:34.438003 | 
2026-09-22 22:39:34.438097 | PLAY RECAP
2026-09-22 22:39:34.438181 | controller | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-22 22:39:34.438223 | 
2026-09-22 22:39:34.563890 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-22 22:39:34.565078 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-22 22:39:35.094461 | 
2026-09-22 22:39:35.094566 | PLAY [all]
2026-09-22 22:39:35.104411 | 
2026-09-22 22:39:35.104480 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-22 22:39:35.158202 | controller | skipping: Conditional result was False
2026-09-22 22:39:35.165851 | 
2026-09-22 22:39:35.165974 | TASK [fetch-output : Set log path for single node]
2026-09-22 22:39:35.194670 | controller | ok
2026-09-22 22:39:35.200410 | 
2026-09-22 22:39:35.200538 | LOOP [fetch-output : Ensure local output dirs]
2026-09-22 22:39:35.593701 | controller -> localhost | ok: "/var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/logs"
2026-09-22 22:39:35.809600 | controller -> localhost | changed: "/var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/artifacts"
2026-09-22 22:39:36.009040 | controller -> localhost | changed: "/var/lib/zuul/builds/052c112603d0438a84bcde696af13812/work/docs"
2026-09-22 22:39:36.028984 | 
2026-09-22 22:39:36.029157 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-22 22:39:36.730225 | controller | changed: .d..t...... ./
2026-09-22 22:39:36.730471 | controller | changed: All items complete
2026-09-22 22:39:36.730507 | 
2026-09-22 22:39:37.263290 | controller | changed: .d..t...... ./
2026-09-22 22:39:37.763177 | controller | changed: .d..t...... ./
2026-09-22 22:39:37.786491 | 
2026-09-22 22:39:37.786673 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-22 22:39:38.249841 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010497
2026-09-22 22:39:38.497524 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007076
2026-09-22 22:39:38.519914 | 
2026-09-22 22:39:38.520071 | PLAY [all]
2026-09-22 22:39:38.531927 | 
2026-09-22 22:39:38.532078 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-22 22:39:39.023385 | controller | changed
2026-09-22 22:39:39.034059 | 
2026-09-22 22:39:39.034162 | PLAY RECAP
2026-09-22 22:39:39.034438 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-22 22:39:39.034478 | 
2026-09-22 22:39:39.154699 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-22 22:39:39.157079 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-22 22:39:39.660781 | 
2026-09-22 22:39:39.660904 | PLAY [localhost]
2026-09-22 22:39:39.669302 | 
2026-09-22 22:39:39.669366 | TASK [Generate Zuul manifest]
2026-09-22 22:39:39.684946 | localhost | ok
2026-09-22 22:39:39.697497 | 
2026-09-22 22:39:39.697567 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-22 22:39:40.054051 | localhost | changed
2026-09-22 22:39:40.081155 | 
2026-09-22 22:39:40.081306 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-22 22:39:40.114192 | localhost | ok
2026-09-22 22:39:40.130244 | 
2026-09-22 22:39:40.130391 | TASK [Upload logs]
2026-09-22 22:39:40.153400 | localhost | ok
2026-09-22 22:39:40.245439 | 
2026-09-22 22:39:40.245860 | TASK [Set zuul-log-path fact]
2026-09-22 22:39:40.269350 | localhost | ok
2026-09-22 22:39:40.292385 | 
2026-09-22 22:39:40.292582 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-22 22:39:40.324389 | localhost | ok
2026-09-22 22:39:40.340213 | 
2026-09-22 22:39:40.340436 | TASK [upload-logs : Create log directories]
2026-09-22 22:39:40.744357 | localhost | changed
2026-09-22 22:39:40.750081 | 
2026-09-22 22:39:40.750169 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-22 22:39:41.206039 | localhost -> localhost | ok: Runtime: 0:00:00.004207
2026-09-22 22:39:41.215208 | 
2026-09-22 22:39:41.215336 | TASK [upload-logs : Upload logs to log server]
2026-09-22 22:39:41.743063 | localhost | Output suppressed because no_log was given
2026-09-22 22:39:41.749550 | 
2026-09-22 22:39:41.749668 | LOOP [upload-logs : Compress console log and json output]
2026-09-22 22:39:41.823451 | localhost | skipping: Conditional result was False
2026-09-22 22:39:41.830132 | localhost | skipping: Conditional result was False
2026-09-22 22:39:41.843152 | 
2026-09-22 22:39:41.843335 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-22 22:39:41.913965 | localhost | skipping: Conditional result was False
2026-09-22 22:39:41.914300 | 
2026-09-22 22:39:41.917981 | localhost | skipping: Conditional result was False
2026-09-22 22:39:41.926388 | 
2026-09-22 22:39:41.926565 | LOOP [upload-logs : Upload console log and json output]