2026-09-25 02:18:26.350457 | Job console starting
2026-09-25 02:18:26.361013 | Updating git repos
2026-09-25 02:18:34.827819 | Cloning repos into workspace
2026-09-25 02:18:47.494027 | Restoring repo states
2026-09-25 02:18:58.170936 | Merging changes
2026-09-25 02:18:58.612756 | Checking out repos
2026-09-25 02:18:59.744817 | Preparing playbooks
2026-09-25 02:19:01.365538 | Running Ansible setup
2026-09-25 02:19:06.365678 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-25 02:19:06.925423 | 
2026-09-25 02:19:06.925532 | PLAY [localhost]
2026-09-25 02:19:06.932992 | 
2026-09-25 02:19:06.933076 | TASK [Gathering Facts]
2026-09-25 02:19:07.824113 | localhost | ok
2026-09-25 02:19:07.836740 | 
2026-09-25 02:19:07.836936 | TASK [Setup log path fact]
2026-09-25 02:19:07.859713 | localhost | ok
2026-09-25 02:19:07.874662 | 
2026-09-25 02:19:07.874987 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-25 02:19:07.904211 | localhost | ok
2026-09-25 02:19:07.917659 | 
2026-09-25 02:19:07.917814 | TASK [emit-job-header : Print job information]
2026-09-25 02:19:07.958378 | # Job Information
2026-09-25 02:19:07.958627 | Ansible Version: 2.16.18
2026-09-25 02:19:07.958692 | Job: atmosphere-common-molecule-secretgen-controller
2026-09-25 02:19:07.958738 | Pipeline: check
2026-09-25 02:19:07.958779 | Executor: 2d72f0692154
2026-09-25 02:19:07.958817 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/97
2026-09-25 02:19:07.958862 | Event ID: 95c4d7f0-b886-11f1-9aee-a13f5325556f
2026-09-25 02:19:07.964161 | 
2026-09-25 02:19:07.964307 | LOOP [emit-job-header : Print node information]
2026-09-25 02:19:08.063530 | localhost | ok:
2026-09-25 02:19:08.063698 | localhost | # Node Information
2026-09-25 02:19:08.063738 | localhost | Inventory Hostname: controller
2026-09-25 02:19:08.063769 | localhost | Hostname: np0000193947
2026-09-25 02:19:08.063799 | localhost | Username: zuul
2026-09-25 02:19:08.063832 | localhost | Distro: Ubuntu 24.04
2026-09-25 02:19:08.063863 | localhost | Provider: yul1
2026-09-25 02:19:08.063893 | localhost | Region: ca-ymq-1
2026-09-25 02:19:08.063922 | localhost | Label: ubuntu-noble
2026-09-25 02:19:08.063951 | localhost | Product Name: OpenStack Nova
2026-09-25 02:19:08.063978 | localhost | Interface IP: 162.253.55.92
2026-09-25 02:19:08.081765 | 
2026-09-25 02:19:08.081938 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-25 02:19:08.676126 | localhost -> localhost | changed
2026-09-25 02:19:08.682007 | 
2026-09-25 02:19:08.682097 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-25 02:19:09.579191 | localhost -> localhost | changed
2026-09-25 02:19:09.586943 | 
2026-09-25 02:19:09.587066 | PLAY [all]
2026-09-25 02:19:09.598454 | 
2026-09-25 02:19:09.598587 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-25 02:19:09.896927 | controller -> localhost | ok
2026-09-25 02:19:09.914267 | 
2026-09-25 02:19:09.914522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-25 02:19:09.943116 | controller | ok
2026-09-25 02:19:09.965546 | controller | included: /var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-25 02:19:09.973206 | 
2026-09-25 02:19:09.973302 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-25 02:19:11.361030 | controller -> localhost | Generating public/private rsa key pair.
2026-09-25 02:19:11.361278 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/5f99e913bfcb4571b290df9240cdd17c_id_rsa
2026-09-25 02:19:11.361341 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/5f99e913bfcb4571b290df9240cdd17c_id_rsa.pub
2026-09-25 02:19:11.361410 | controller -> localhost | The key fingerprint is:
2026-09-25 02:19:11.361460 | controller -> localhost | SHA256:AUVO1WQJrdot+hjAcfP1Hr6CIT2VjgoKumtEMTJtEDg zuul-build-sshkey
2026-09-25 02:19:11.361522 | controller -> localhost | The key's randomart image is:
2026-09-25 02:19:11.361568 | controller -> localhost | +---[RSA 3072]----+
2026-09-25 02:19:11.361621 | controller -> localhost | |++    .o+.o=o.   |
2026-09-25 02:19:11.361667 | controller -> localhost | |Eoo    +   .+    |
2026-09-25 02:19:11.361710 | controller -> localhost | |.+o   . =  ...   |
2026-09-25 02:19:11.361751 | controller -> localhost | | .   . o +..o.   |
2026-09-25 02:19:11.361792 | controller -> localhost | |.     o S+.=  o  |
2026-09-25 02:19:11.361833 | controller -> localhost | | ..   ..o B oo . |
2026-09-25 02:19:11.361873 | controller -> localhost | |.. . . ..+ =  o  |
2026-09-25 02:19:11.361913 | controller -> localhost | |..  .   oo. .  . |
2026-09-25 02:19:11.361961 | controller -> localhost | |+o      ...  ..  |
2026-09-25 02:19:11.362003 | controller -> localhost | +----[SHA256]-----+
2026-09-25 02:19:11.362101 | controller -> localhost | ok: Runtime: 0:00:00.923209
2026-09-25 02:19:11.374280 | 
2026-09-25 02:19:11.374437 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-25 02:19:11.410724 | controller | ok
2026-09-25 02:19:11.426459 | controller | included: /var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-25 02:19:11.436305 | 
2026-09-25 02:19:11.436399 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-25 02:19:11.460328 | controller | skipping: Conditional result was False
2026-09-25 02:19:11.472487 | 
2026-09-25 02:19:11.472832 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-25 02:19:12.029057 | controller | changed
2026-09-25 02:19:12.036579 | 
2026-09-25 02:19:12.036699 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-25 02:19:12.259248 | controller | ok
2026-09-25 02:19:12.267105 | 
2026-09-25 02:19:12.267236 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-25 02:19:12.906015 | controller | changed
2026-09-25 02:19:12.916757 | 
2026-09-25 02:19:12.916884 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-25 02:19:13.518263 | controller | changed
2026-09-25 02:19:13.528971 | 
2026-09-25 02:19:13.529127 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-25 02:19:13.574659 | controller | skipping: Conditional result was False
2026-09-25 02:19:13.586108 | 
2026-09-25 02:19:13.586254 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-25 02:19:13.977905 | controller -> localhost | changed
2026-09-25 02:19:13.993325 | 
2026-09-25 02:19:13.993432 | TASK [add-build-sshkey : Add back temp key]
2026-09-25 02:19:14.299720 | controller -> localhost | Identity added: /var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/5f99e913bfcb4571b290df9240cdd17c_id_rsa (zuul-build-sshkey)
2026-09-25 02:19:14.299995 | controller -> localhost | ok: Runtime: 0:00:00.018269
2026-09-25 02:19:14.311144 | 
2026-09-25 02:19:14.311264 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-25 02:19:14.688175 | controller | ok
2026-09-25 02:19:14.692954 | 
2026-09-25 02:19:14.693029 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-25 02:19:14.726522 | controller | skipping: Conditional result was False
2026-09-25 02:19:14.774371 | 
2026-09-25 02:19:14.774502 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-25 02:19:15.117711 | controller | ok
2026-09-25 02:19:15.124965 | 
2026-09-25 02:19:15.126047 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-25 02:19:18.151784 | controller | Output suppressed because no_log was given
2026-09-25 02:19:18.169107 | 
2026-09-25 02:19:18.169197 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-25 02:19:18.423500 | controller | ok: "logs"
2026-09-25 02:19:18.423791 | controller | ok: All items complete
2026-09-25 02:19:18.423821 | 
2026-09-25 02:19:18.611312 | controller | ok: "artifacts"
2026-09-25 02:19:18.796290 | controller | ok: "docs"
2026-09-25 02:19:18.808931 | 
2026-09-25 02:19:18.809096 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-25 02:19:19.022153 | controller | changed: "logs"
2026-09-25 02:19:19.246426 | controller | changed: "artifacts"
2026-09-25 02:19:19.455492 | controller | changed: "docs"
2026-09-25 02:19:19.470629 | 
2026-09-25 02:19:19.470762 | PLAY RECAP
2026-09-25 02:19:19.470842 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-25 02:19:19.470898 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:19:19.470936 | 
2026-09-25 02:19:19.606725 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-25 02:19:19.607963 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-25 02:19:20.125465 | 
2026-09-25 02:19:20.125572 | PLAY [all]
2026-09-25 02:19:20.135361 | 
2026-09-25 02:19:20.135445 | TASK [setup-uv : Extract archive]
2026-09-25 02:19:22.690750 | controller | changed
2026-09-25 02:19:22.705699 | 
2026-09-25 02:19:22.705945 | TASK [setup-uv : Print version]
2026-09-25 02:19:23.102016 | controller | uv 0.8.13
2026-09-25 02:19:23.261165 | controller | ok: Runtime: 0:00:00.020658
2026-09-25 02:19:23.282281 | 
2026-09-25 02:19:23.282485 | TASK [Install Ansible collection siblings]
2026-09-25 02:19:32.521388 | controller | changed:
2026-09-25 02:19:32.521596 | controller | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-25 02:19:32.521633 | controller | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-25 02:19:32.521660 | controller | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-25 02:19:32.521687 | controller | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-25 02:19:32.521712 | controller | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-25 02:19:32.521743 | controller | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-25 02:19:32.521771 | controller | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-25 02:19:32.521800 | controller | Resolved kubernetes.core to 6.6.0 for >=2.4.0
2026-09-25 02:19:32.521828 | controller | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-25 02:19:32.521853 | controller | Resolved vexxhost.kubernetes to v3.4.0 for >=2.5.0
2026-09-25 02:19:32.521879 | controller | Resolved ansible.posix to 2.2.2 for >=1.6.0
2026-09-25 02:19:32.521905 | controller | Resolved community.crypto to 3.4.0 for >=2.2.3
2026-09-25 02:19:32.521930 | controller | Resolved community.general to 13.4.0 for >=4.5.0
2026-09-25 02:19:32.521956 | controller | Skipping community.library_inventory_filtering_v1: no checked-out sibling
2026-09-25 02:19:32.521980 | controller | Resolved kubernetes.core to 6.6.0 for >=2.4.0,>=2.3.2
2026-09-25 02:19:32.522007 | controller | Resolved vexxhost.containers to v1.6.8 for >=1.6.0
2026-09-25 02:19:32.522032 | controller | Checked out ansible.posix to 2.2.2
2026-09-25 02:19:32.522059 | 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-25 02:19:32.522090 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.522114 | controller | Process install dependency map
2026-09-25 02:19:32.522140 | controller | Starting collection install process
2026-09-25 02:19:32.522165 | controller | Installing 'ansible.posix:2.2.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 02:19:32.522207 | controller | Created collection for ansible.posix:2.2.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-25 02:19:32.522234 | controller | ansible.posix:2.2.2 was installed successfully
2026-09-25 02:19:32.522260 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-25 02:19:32.522285 | controller | Creating virtual environment at: .venv
2026-09-25 02:19:32.522311 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.522335 | controller | Downloading aiohttp (1.7MiB)
2026-09-25 02:19:32.522361 | controller | Downloading ansible-core (2.1MiB)
2026-09-25 02:19:32.522384 | controller | Downloading kubernetes (4.4MiB)
2026-09-25 02:19:32.522409 | controller | Downloading cryptography (4.5MiB)
2026-09-25 02:19:32.522454 | controller | Downloading pydantic-core (2.0MiB)
2026-09-25 02:19:32.522480 | controller | Downloading pygments (1.2MiB)
2026-09-25 02:19:32.522504 | controller |  Downloading aiohttp
2026-09-25 02:19:32.522528 | controller |  Downloading pydantic-core
2026-09-25 02:19:32.522551 | controller |  Downloading pygments
2026-09-25 02:19:32.522855 | controller |  Downloading cryptography
2026-09-25 02:19:32.522893 | controller |  Downloading ansible-core
2026-09-25 02:19:32.522920 | controller |  Downloading kubernetes
2026-09-25 02:19:32.522945 | controller | Installed 53 packages in 83ms
2026-09-25 02:19:32.522970 | controller | Checked out community.crypto to 3.4.0
2026-09-25 02:19:32.522995 | 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-25 02:19:32.523020 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.523045 | controller | Process install dependency map
2026-09-25 02:19:32.523069 | controller | Starting collection install process
2026-09-25 02:19:32.523101 | controller | Installing 'community.crypto:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 02:19:32.523129 | controller | Created collection for community.crypto:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-25 02:19:32.523154 | controller | community.crypto:3.4.0 was installed successfully
2026-09-25 02:19:32.523179 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.523205 | controller | Checked out community.general to 13.4.0
2026-09-25 02:19:32.523230 | 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-25 02:19:32.523255 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.523280 | controller | Process install dependency map
2026-09-25 02:19:32.523307 | controller | Starting collection install process
2026-09-25 02:19:32.523333 | controller | Installing 'community.general:13.4.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 02:19:32.523358 | controller | Created collection for community.general:13.4.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-25 02:19:32.523383 | controller | community.general:13.4.0 was installed successfully
2026-09-25 02:19:32.523407 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.523453 | controller | Checked out kubernetes.core to 6.6.0
2026-09-25 02:19:32.523479 | 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-25 02:19:32.523505 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.523530 | controller | Process install dependency map
2026-09-25 02:19:32.523562 | controller | Starting collection install process
2026-09-25 02:19:32.523587 | controller | Installing 'kubernetes.core:6.6.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 02:19:32.523613 | controller | Created collection for kubernetes.core:6.6.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-25 02:19:32.523636 | controller | kubernetes.core:6.6.0 was installed successfully
2026-09-25 02:19:32.523661 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.523685 | controller | Checked out openstack.cloud to 2.6.0
2026-09-25 02:19:32.523710 | 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-25 02:19:32.523734 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.523759 | controller | Process install dependency map
2026-09-25 02:19:32.523783 | controller | Starting collection install process
2026-09-25 02:19:32.523807 | controller | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 02:19:32.523832 | controller | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-25 02:19:32.523857 | controller | openstack.cloud:2.6.0 was installed successfully
2026-09-25 02:19:32.523890 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.523938 | controller | Checked out vexxhost.containers to v1.6.8
2026-09-25 02:19:32.523978 | 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-25 02:19:32.524007 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.524032 | controller | Process install dependency map
2026-09-25 02:19:32.524058 | controller | Starting collection install process
2026-09-25 02:19:32.524082 | controller | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 02:19:32.524107 | controller | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-25 02:19:32.524132 | controller | vexxhost.containers:1.6.8 was installed successfully
2026-09-25 02:19:32.524157 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.524181 | controller | Checked out vexxhost.kubernetes to v3.4.0
2026-09-25 02:19:32.524205 | 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-25 02:19:32.524230 | controller | Starting galaxy collection install process
2026-09-25 02:19:32.524254 | controller | Process install dependency map
2026-09-25 02:19:32.524279 | controller | Starting collection install process
2026-09-25 02:19:32.524303 | controller | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 02:19:32.524330 | controller | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-25 02:19:32.524355 | controller | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-25 02:19:32.524380 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:32.529925 | 
2026-09-25 02:19:32.529990 | PLAY RECAP
2026-09-25 02:19:32.530040 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:19:32.530067 | 
2026-09-25 02:19:32.670178 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-25 02:19:32.671452 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-25 02:19:33.293612 | 
2026-09-25 02:19:33.294035 | PLAY [Prepare Molecule log collection]
2026-09-25 02:19:33.304686 | 
2026-09-25 02:19:33.304778 | TASK [Install jq for log collection]
2026-09-25 02:19:41.674815 | controller | changed
2026-09-25 02:19:41.678463 | 
2026-09-25 02:19:41.678590 | PLAY RECAP
2026-09-25 02:19:41.678694 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:19:41.678800 | 
2026-09-25 02:19:41.791059 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-25 02:19:41.794236 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-25 02:19:42.348729 | 
2026-09-25 02:19:42.348850 | PLAY [all]
2026-09-25 02:19:42.359709 | 
2026-09-25 02:19:42.359822 | LOOP [Check inventory directories]
2026-09-25 02:19:42.754213 | controller | ok: "src/github.com/vexxhost/atmosphere.common"
2026-09-25 02:19:42.961493 | controller | ok: "src/github.com/vexxhost/atmosphere.common/molecule/secretgen-controller/inventory"
2026-09-25 02:19:43.190244 | controller | ok: "src/github.com/vexxhost/atmosphere.common/extensions/molecule/secretgen-controller/inventory"
2026-09-25 02:19:43.203646 | 
2026-09-25 02:19:43.203893 | LOOP [Copy inventory file for Zuul]
2026-09-25 02:19:44.156739 | controller | changed:
2026-09-25 02:19:44.157035 | controller | {
2026-09-25 02:19:44.157100 | controller |   "ansible_loop_var": "item",
2026-09-25 02:19:44.157135 | controller |   "changed": false,
2026-09-25 02:19:44.157161 | controller |   "failed": false,
2026-09-25 02:19:44.157188 | controller |   "invocation": {
2026-09-25 02:19:44.157210 | controller |     "module_args": {
2026-09-25 02:19:44.157250 | controller |       "checksum_algorithm": "sha1",
2026-09-25 02:19:44.157304 | controller |       "follow": true,
2026-09-25 02:19:44.157342 | controller |       "get_attributes": true,
2026-09-25 02:19:44.157375 | controller |       "get_checksum": true,
2026-09-25 02:19:44.157403 | controller |       "get_mime": true,
2026-09-25 02:19:44.157426 | controller |       "path": "src/github.com/vexxhost/atmosphere.common"
2026-09-25 02:19:44.157482 | controller |     }
2026-09-25 02:19:44.157512 | controller |   },
2026-09-25 02:19:44.157541 | controller |   "item": "src/github.com/vexxhost/atmosphere.common",
2026-09-25 02:19:44.157569 | controller |   "stat": {
2026-09-25 02:19:44.157596 | controller |     "atime": 1790302757.8396924,
2026-09-25 02:19:44.157624 | controller |     "attr_flags": "e",
2026-09-25 02:19:44.157645 | controller |     "attributes": [
2026-09-25 02:19:44.157667 | controller |       "extents"
2026-09-25 02:19:44.157688 | controller |     ],
2026-09-25 02:19:44.157708 | controller |     "block_size": 4096,
2026-09-25 02:19:44.157732 | controller |     "blocks": 8,
2026-09-25 02:19:44.157763 | controller |     "charset": "unknown",
2026-09-25 02:19:44.157794 | controller |     "ctime": 1790302764.0620804,
2026-09-25 02:19:44.157820 | controller |     "dev": 64769,
2026-09-25 02:19:44.157842 | controller |     "device_type": 0,
2026-09-25 02:19:44.157862 | controller |     "executable": true,
2026-09-25 02:19:44.157883 | controller |     "exists": true,
2026-09-25 02:19:44.157903 | controller |     "gid": 1000,
2026-09-25 02:19:44.157924 | controller |     "gr_name": "zuul",
2026-09-25 02:19:44.157944 | controller |     "inode": 1039126,
2026-09-25 02:19:44.157968 | controller |     "isblk": false,
2026-09-25 02:19:44.158028 | controller |     "ischr": false,
2026-09-25 02:19:44.158068 | controller |     "isdir": true,
2026-09-25 02:19:44.158099 | controller |     "isfifo": false,
2026-09-25 02:19:44.158128 | controller |     "isgid": false,
2026-09-25 02:19:44.158149 | controller |     "islnk": false,
2026-09-25 02:19:44.158170 | controller |     "isreg": false,
2026-09-25 02:19:44.158211 | controller |     "issock": false,
2026-09-25 02:19:44.158241 | controller |     "isuid": false,
2026-09-25 02:19:44.158272 | controller |     "mimetype": "unknown",
2026-09-25 02:19:44.158318 | controller |     "mode": "0755",
2026-09-25 02:19:44.158368 | controller |     "mtime": 1790302764.0620804,
2026-09-25 02:19:44.158399 | controller |     "nlink": 11,
2026-09-25 02:19:44.158421 | controller |     "path": "src/github.com/vexxhost/atmosphere.common",
2026-09-25 02:19:44.158467 | controller |     "pw_name": "zuul",
2026-09-25 02:19:44.158491 | controller |     "readable": true,
2026-09-25 02:19:44.158513 | controller |     "rgrp": true,
2026-09-25 02:19:44.158533 | controller |     "roth": true,
2026-09-25 02:19:44.158554 | controller |     "rusr": true,
2026-09-25 02:19:44.158574 | controller |     "size": 4096,
2026-09-25 02:19:44.158594 | controller |     "uid": 1000,
2026-09-25 02:19:44.158614 | controller |     "version": "1413443293",
2026-09-25 02:19:44.158635 | controller |     "wgrp": false,
2026-09-25 02:19:44.158659 | controller |     "woth": false,
2026-09-25 02:19:44.158680 | controller |     "writeable": true,
2026-09-25 02:19:44.158701 | controller |     "wusr": true,
2026-09-25 02:19:44.158722 | controller |     "xgrp": true,
2026-09-25 02:19:44.158742 | controller |     "xoth": true,
2026-09-25 02:19:44.158762 | controller |     "xusr": true
2026-09-25 02:19:44.158782 | controller |   }
2026-09-25 02:19:44.158802 | controller | }
2026-09-25 02:19:44.158852 | controller | ok: All items complete
2026-09-25 02:19:44.158880 | 
2026-09-25 02:19:44.160654 | controller | skipping: Conditional result was False
2026-09-25 02:19:44.170474 | controller | skipping: Conditional result was False
2026-09-25 02:19:44.196101 | 
2026-09-25 02:19:44.196334 | LOOP [Switch "ansible_host" to private IP]
2026-09-25 02:19:44.608719 | controller | changed: 1 replacements made
2026-09-25 02:19:44.610259 | controller | skipping: Conditional result was False
2026-09-25 02:19:44.614334 | controller | skipping: Conditional result was False
2026-09-25 02:19:44.636342 | 
2026-09-25 02:19:44.636617 | TASK [Run Molecule scenario]
2026-09-25 02:19:45.094812 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:19:45.766532 | controller | INFO     Collection 'atmosphere.common' detected.
2026-09-25 02:19:45.766584 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-25 02:19:46.182511 | controller | INFO     [secretgen-controller > discovery] scenario test matrix: dependency, cleanup, destroy, syntax, create, prepare, converge, idempotence, side_effect, verify, cleanup, destroy
2026-09-25 02:19:46.182589 | controller | INFO     [secretgen-controller > prerun] Performing prerun with role_name_check=0...
2026-09-25 02:19:48.927614 | controller | WARNING  Retrying execution failure 1 of: ansible-galaxy collection install -vvv --force /home/zuul/src/github.com/vexxhost/atmosphere.common
2026-09-25 02:19:48.927716 | controller | ERROR    Command ansible-galaxy collection install -vvv --force /home/zuul/src/github.com/vexxhost/atmosphere.common, returned 1 code:
2026-09-25 02:19:48.927727 | controller | Starting galaxy collection install process
2026-09-25 02:19:48.927736 | controller | Process install dependency map
2026-09-25 02:19:48.927745 | controller |
2026-09-25 02:19:48.927754 | controller | ansible-galaxy [core 2.18.19]
2026-09-25 02:19:48.927765 | controller |   config file = None
2026-09-25 02:19:48.927790 | controller |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-25 02:19:48.927798 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-25 02:19:48.927807 | 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-25 02:19:48.927821 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-25 02:19:48.927829 | 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-25 02:19:48.927837 | controller |   jinja version = 3.1.6
2026-09-25 02:19:48.927845 | controller |   libyaml = True
2026-09-25 02:19:48.927854 | controller | No config file found; using defaults
2026-09-25 02:19:48.927862 | controller | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 02:19:48.927871 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 02:19:48.927879 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 02:19:48.927887 | controller | Found installed collection kubernetes.core:6.6.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 02:19:48.927895 | controller | Found installed collection vexxhost.containers:1.6.8 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 02:19:48.927903 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 02:19:48.927948 | controller | Found installed collection vexxhost.kubernetes:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 02:19:48.927957 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-25 02:19:48.927965 | controller | : Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected
2026-09-25 02:19:48.927973 | controller | error when getting available versions of collection
2026-09-25 02:19:48.927982 | controller | community.library_inventory_filtering_v1: Unknown error when attempting to call
2026-09-25 02:19:48.927990 | controller | Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error [Errno 104]
2026-09-25 02:19:48.927998 | controller | Connection reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-25 02:19:48.928029 | controller | ERROR! Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error [Errno 104] Connection reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-25 02:19:48.928038 | controller |
2026-09-25 02:19:48.928299 | controller | Traceback (most recent call last):
2026-09-25 02:19:48.928583 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/molecule", line 10, in <module>
2026-09-25 02:19:48.928995 | controller |     sys.exit(main())
2026-09-25 02:19:48.929241 | controller |              ^^^^^^
2026-09-25 02:19:48.929465 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1631, in __call__
2026-09-25 02:19:48.930326 | controller |     return self.main(*args, **kwargs)
2026-09-25 02:19:48.930778 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.931015 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1552, in main
2026-09-25 02:19:48.931780 | controller |     rv = self.invoke(ctx)
2026-09-25 02:19:48.931989 | controller |          ^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.932137 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 2032, in invoke
2026-09-25 02:19:48.932872 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-25 02:19:48.933125 | controller |                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.933256 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 1415, in invoke
2026-09-25 02:19:48.933744 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-25 02:19:48.933939 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.934052 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/click/core.py", line 910, in invoke
2026-09-25 02:19:48.934420 | controller |     return callback(*args, **kwargs)
2026-09-25 02:19:48.934662 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.934783 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 660, in wrapper
2026-09-25 02:19:48.935207 | controller |     return func(*args, **kwargs)
2026-09-25 02:19:48.935431 | controller |            ^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.935579 | 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-25 02:19:48.935826 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-25 02:19:48.936057 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:19:48.936202 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/click_cfg.py", line 479, in wrapper
2026-09-25 02:19:48.936556 | controller |     return func(ctx)
2026-09-25 02:19:48.936691 | controller |            ^^^^^^^^^
2026-09-25 02:19:48.936853 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/test.py", line 95, in test
2026-09-25 02:19:48.937075 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-25 02:19:48.937244 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 243, in execute_cmdline_scenarios
2026-09-25 02:19:48.937537 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-25 02:19:48.937695 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/molecule/command/base.py", line 361, in _run_scenarios
2026-09-25 02:19:48.937947 | controller |     scenario.config.runtime.prepare_environment(
2026-09-25 02:19:48.938083 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 797, in prepare_environment
2026-09-25 02:19:48.938429 | controller |     self._handle_local_install(destination, role_name_check)
2026-09-25 02:19:48.938586 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 850, in _handle_local_install
2026-09-25 02:19:48.938929 | controller |     self.install_collection_from_disk(
2026-09-25 02:19:48.939057 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 630, in install_collection_from_disk
2026-09-25 02:19:48.939382 | controller |     self.install_collection(path, destination=destination, force=True)
2026-09-25 02:19:48.939607 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible_compat/runtime.py", line 622, in install_collection
2026-09-25 02:19:48.940004 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-25 02:19:48.941218 | 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-25 02:19:48.941234 | controller | Starting galaxy collection install process
2026-09-25 02:19:48.941243 | controller | Process install dependency map
2026-09-25 02:19:48.941251 | controller |
2026-09-25 02:19:48.941260 | controller | ansible-galaxy [core 2.18.19]
2026-09-25 02:19:48.941268 | controller |   config file = None
2026-09-25 02:19:48.941276 | controller |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-25 02:19:48.941284 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-25 02:19:48.941293 | 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-25 02:19:48.941302 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-25 02:19:48.941310 | 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-25 02:19:48.941318 | controller |   jinja version = 3.1.6
2026-09-25 02:19:48.941326 | controller |   libyaml = True
2026-09-25 02:19:48.941334 | controller | No config file found; using defaults
2026-09-25 02:19:48.941342 | controller | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 02:19:48.941351 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 02:19:48.941359 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 02:19:48.941370 | controller | Found installed collection kubernetes.core:6.6.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 02:19:48.941380 | controller | Found installed collection vexxhost.containers:1.6.8 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 02:19:48.941422 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 02:19:48.941434 | controller | Found installed collection vexxhost.kubernetes:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 02:19:48.941442 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-25 02:19:48.941450 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com. Got an unexpected
2026-09-25 02:19:48.941458 | controller | error when getting available versions of collection
2026-09-25 02:19:48.941466 | controller | community.library_inventory_filtering_v1: Unknown error when attempting to call
2026-09-25 02:19:48.941474 | controller | Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error [Errno 104]
2026-09-25 02:19:48.941485 | controller | Connection reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-25 02:19:48.941514 | controller | ERROR! Unknown error when attempting to call Galaxy at 'https://galaxy.ansible.com/api/': <urlopen error [Errno 104] Connection reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-25 02:19:48.941531 | controller |
2026-09-25 02:19:49.213406 | controller | ERROR
2026-09-25 02:19:49.213686 | controller | {
2026-09-25 02:19:49.213745 | controller |   "delta": "0:00:03.965238",
2026-09-25 02:19:49.213804 | controller |   "end": "2026-09-25 02:19:49.017439",
2026-09-25 02:19:49.213843 | controller |   "msg": "non-zero return code",
2026-09-25 02:19:49.213879 | controller |   "rc": 1,
2026-09-25 02:19:49.213914 | controller |   "start": "2026-09-25 02:19:45.052201"
2026-09-25 02:19:49.213949 | controller | }
failure
2026-09-25 02:19:49.218353 | 
2026-09-25 02:19:49.218481 | PLAY RECAP
2026-09-25 02:19:49.218567 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:19:49.218609 | 
2026-09-25 02:19:49.363641 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-25 02:19:49.366247 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-25 02:19:50.007189 | 
2026-09-25 02:19:50.007317 | PLAY [Collect Molecule logs]
2026-09-25 02:19:50.019868 | 
2026-09-25 02:19:50.019942 | TASK [gather-host-logs : creating directory for system status]
2026-09-25 02:19:50.450882 | controller | changed
2026-09-25 02:19:50.462402 | 
2026-09-25 02:19:50.462858 | TASK [gather-host-logs : Get logs for each host]
2026-09-25 02:19:50.862363 | controller | + systemd-cgls --full --all --no-pager
2026-09-25 02:19:50.887367 | controller | + ip addr
2026-09-25 02:19:50.891892 | controller | + ip route
2026-09-25 02:19:50.896312 | controller | + lsblk
2026-09-25 02:19:50.904268 | controller | + mount
2026-09-25 02:19:50.910990 | controller | + docker images
2026-09-25 02:19:50.911963 | controller | /bin/bash: line 7: docker: command not found
2026-09-25 02:19:50.912535 | controller | + brctl show
2026-09-25 02:19:50.913444 | controller | /bin/bash: line 8: brctl: command not found
2026-09-25 02:19:50.914063 | controller | + ip neigh
2026-09-25 02:19:50.918541 | controller | + bridge fdb show
2026-09-25 02:19:50.922802 | controller | + ps aux --sort=-%mem
2026-09-25 02:19:50.959534 | controller | + dpkg -l
2026-09-25 02:19:50.980320 | controller | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-25 02:19:50.981367 | controller | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-25 02:19:50.981485 | controller | /bin/bash: line 13: docker: command not found
2026-09-25 02:19:50.982173 | controller | + '[' '!' -z '' ']'
2026-09-25 02:19:51.049534 | controller | ok: Runtime: 0:00:00.128119
2026-09-25 02:19:51.061055 | 
2026-09-25 02:19:51.061166 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-25 02:19:51.735253 | controller | changed:
2026-09-25 02:19:51.735502 | controller | created directory /var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/logs/controller
2026-09-25 02:19:51.735606 | controller | cd+++++++++ system/
2026-09-25 02:19:51.735667 | controller | >f+++++++++ system/brctl-show.txt
2026-09-25 02:19:51.735710 | controller | >f+++++++++ system/bridge-fdb.txt
2026-09-25 02:19:51.735747 | controller | >f+++++++++ system/docker-images.txt
2026-09-25 02:19:51.735791 | controller | >f+++++++++ system/ip-addr.txt
2026-09-25 02:19:51.735954 | controller | >f+++++++++ system/ip-neigh.txt
2026-09-25 02:19:51.736017 | controller | >f+++++++++ system/ip-route.txt
2026-09-25 02:19:51.736056 | controller | >f+++++++++ system/lsblk.txt
2026-09-25 02:19:51.736095 | controller | >f+++++++++ system/mount.txt
2026-09-25 02:19:51.736132 | controller | >f+++++++++ system/packages.txt
2026-09-25 02:19:51.736169 | controller | >f+++++++++ system/ps.txt
2026-09-25 02:19:51.736206 | controller | >f+++++++++ system/systemd-cgls.txt
2026-09-25 02:19:51.756704 | 
2026-09-25 02:19:51.756861 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-25 02:19:52.027368 | controller | changed: "values"
2026-09-25 02:19:52.261437 | controller | changed: "releases"
2026-09-25 02:19:52.283757 | 
2026-09-25 02:19:52.283994 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-25 02:19:52.557553 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-25 02:19:52.821532 | controller | ok: Runtime: 0:00:00.006466
2026-09-25 02:19:52.826349 | 
2026-09-25 02:19:52.826426 | TASK [helm-release-status : Downloads logs to executor]
2026-09-25 02:19:53.376460 | controller | changed:
2026-09-25 02:19:53.376706 | controller | cd+++++++++ helm/
2026-09-25 02:19:53.376761 | controller | cd+++++++++ helm/releases/
2026-09-25 02:19:53.376802 | controller | cd+++++++++ helm/values/
2026-09-25 02:19:53.397992 | 
2026-09-25 02:19:53.398180 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-25 02:19:53.684441 | controller | changed
2026-09-25 02:19:53.692489 | 
2026-09-25 02:19:53.692641 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-25 02:19:54.009997 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:19:54.010242 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:19:54.017266 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:19:54.018799 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:19:54.018836 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:19:54.020377 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:19:54.022808 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:19:54.024984 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:19:54.026834 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:19:54.027923 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:19:54.028551 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:19:54.029701 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:19:54.271414 | controller | ok: Runtime: 0:00:00.034458
2026-09-25 02:19:54.282523 | 
2026-09-25 02:19:54.282654 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-25 02:19:54.543331 | controller | changed
2026-09-25 02:19:54.554356 | 
2026-09-25 02:19:54.554525 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-25 02:19:54.862719 | controller | environment: line 5: kubectl: command not found
2026-09-25 02:19:54.865072 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:19:54.865105 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:19:54.865116 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:19:55.103931 | controller | ok: Runtime: 0:00:00.015806
2026-09-25 02:19:55.115595 | 
2026-09-25 02:19:55.115779 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-25 02:19:55.707853 | controller | changed:
2026-09-25 02:19:55.708080 | controller | cd+++++++++ objects/
2026-09-25 02:19:55.708126 | controller | cd+++++++++ objects/cluster/
2026-09-25 02:19:55.708160 | controller | cd+++++++++ objects/namespaced/
2026-09-25 02:19:55.728010 | 
2026-09-25 02:19:55.728173 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-25 02:19:55.995175 | controller | changed
2026-09-25 02:19:56.007229 | 
2026-09-25 02:19:56.007368 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-25 02:19:56.255806 | controller | changed
2026-09-25 02:19:56.269369 | 
2026-09-25 02:19:56.269588 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-25 02:19:56.572047 | controller | environment: line 3: kubectl: command not found
2026-09-25 02:19:56.820112 | controller | ok: Runtime: 0:00:00.014752
2026-09-25 02:19:56.832477 | 
2026-09-25 02:19:56.832639 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-25 02:19:57.448413 | controller | changed:
2026-09-25 02:19:57.448788 | controller | cd+++++++++ pod-logs/
2026-09-25 02:19:57.448845 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-25 02:19:57.471009 | 
2026-09-25 02:19:57.471145 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-25 02:19:57.760262 | controller | changed
2026-09-25 02:19:57.770152 | 
2026-09-25 02:19:57.770383 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-25 02:19:58.087325 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-25 02:19:58.319077 | controller | ok: Runtime: 0:00:00.014049
2026-09-25 02:19:58.331053 | 
2026-09-25 02:19:58.331198 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-25 02:19:58.637133 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-25 02:19:58.645141 | controller | ceph-mgr endpoints:
2026-09-25 02:19:58.882097 | controller | ok: Runtime: 0:00:00.018498
2026-09-25 02:19:58.894717 | 
2026-09-25 02:19:58.894875 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-25 02:19:59.185153 | controller | /bin/bash: line 4: kubectl: command not found
2026-09-25 02:19:59.450604 | controller | ok: Runtime: 0:00:00.015473
2026-09-25 02:19:59.462513 | 
2026-09-25 02:19:59.462651 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-25 02:20:00.047232 | controller | changed: cd+++++++++ prometheus/
2026-09-25 02:20:00.133928 | 
2026-09-25 02:20:00.134110 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-25 02:20:00.422059 | controller | changed
2026-09-25 02:20:00.434749 | 
2026-09-25 02:20:00.435000 | TASK [gather-selenium-data : Get selenium data]
2026-09-25 02:20:00.747607 | controller | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-25 02:20:00.752286 | controller | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-25 02:20:00.990698 | controller | ERROR
2026-09-25 02:20:00.990933 | controller | {
2026-09-25 02:20:00.990995 | controller |   "delta": "0:00:00.012954",
2026-09-25 02:20:00.991036 | controller |   "end": "2026-09-25 02:20:00.752906",
2026-09-25 02:20:00.991072 | controller |   "msg": "non-zero return code",
2026-09-25 02:20:00.991107 | controller |   "rc": 1,
2026-09-25 02:20:00.991141 | controller |   "start": "2026-09-25 02:20:00.739952"
2026-09-25 02:20:00.991174 | controller | }
2026-09-25 02:20:00.991218 | controller | ERROR: Ignoring Errors
2026-09-25 02:20:01.001973 | 
2026-09-25 02:20:01.002097 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-25 02:20:01.583818 | controller | changed: cd+++++++++ selenium/
2026-09-25 02:20:01.595710 | 
2026-09-25 02:20:01.595828 | PLAY RECAP
2026-09-25 02:20:01.595910 | controller | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-25 02:20:01.595952 | 
2026-09-25 02:20:01.735628 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-25 02:20:01.738283 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-25 02:20:02.311642 | 
2026-09-25 02:20:02.311754 | PLAY [all]
2026-09-25 02:20:02.322208 | 
2026-09-25 02:20:02.322289 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-25 02:20:02.385690 | controller | skipping: Conditional result was False
2026-09-25 02:20:02.394254 | 
2026-09-25 02:20:02.394351 | TASK [fetch-output : Set log path for single node]
2026-09-25 02:20:02.430810 | controller | ok
2026-09-25 02:20:02.439178 | 
2026-09-25 02:20:02.439333 | LOOP [fetch-output : Ensure local output dirs]
2026-09-25 02:20:02.791629 | controller -> localhost | ok: "/var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/logs"
2026-09-25 02:20:03.032056 | controller -> localhost | changed: "/var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/artifacts"
2026-09-25 02:20:03.258989 | controller -> localhost | changed: "/var/lib/zuul/builds/5f99e913bfcb4571b290df9240cdd17c/work/docs"
2026-09-25 02:20:03.285534 | 
2026-09-25 02:20:03.285767 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-25 02:20:03.985174 | controller | changed: .d..t...... ./
2026-09-25 02:20:03.985393 | controller | changed: All items complete
2026-09-25 02:20:03.985429 | 
2026-09-25 02:20:04.497364 | controller | changed: .d..t...... ./
2026-09-25 02:20:05.027734 | controller | changed: .d..t...... ./
2026-09-25 02:20:05.065887 | 
2026-09-25 02:20:05.066177 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-25 02:20:05.584139 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007108
2026-09-25 02:20:05.822433 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006950
2026-09-25 02:20:05.847497 | 
2026-09-25 02:20:05.847694 | PLAY [all]
2026-09-25 02:20:05.857012 | 
2026-09-25 02:20:05.857177 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-25 02:20:06.428454 | controller | changed
2026-09-25 02:20:06.437356 | 
2026-09-25 02:20:06.437542 | PLAY RECAP
2026-09-25 02:20:06.437614 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-25 02:20:06.437643 | 
2026-09-25 02:20:06.639350 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-25 02:20:06.641029 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-25 02:20:07.457470 | 
2026-09-25 02:20:07.457592 | PLAY [localhost]
2026-09-25 02:20:07.467162 | 
2026-09-25 02:20:07.467257 | TASK [Generate Zuul manifest]
2026-09-25 02:20:07.484107 | localhost | ok
2026-09-25 02:20:07.498322 | 
2026-09-25 02:20:07.498478 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-25 02:20:07.876225 | localhost | changed
2026-09-25 02:20:07.894822 | 
2026-09-25 02:20:07.894993 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-25 02:20:07.926547 | localhost | ok
2026-09-25 02:20:07.940299 | 
2026-09-25 02:20:07.940485 | TASK [Upload logs]
2026-09-25 02:20:07.960744 | localhost | ok
2026-09-25 02:20:08.029167 | 
2026-09-25 02:20:08.029287 | TASK [Set zuul-log-path fact]
2026-09-25 02:20:08.044872 | localhost | ok
2026-09-25 02:20:08.054649 | 
2026-09-25 02:20:08.054738 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-25 02:20:08.083280 | localhost | ok
2026-09-25 02:20:08.092615 | 
2026-09-25 02:20:08.092723 | TASK [upload-logs : Create log directories]
2026-09-25 02:20:08.491551 | localhost | changed
2026-09-25 02:20:08.500049 | 
2026-09-25 02:20:08.500169 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-25 02:20:08.947250 | localhost -> localhost | ok: Runtime: 0:00:00.005310
2026-09-25 02:20:08.956732 | 
2026-09-25 02:20:08.956867 | TASK [upload-logs : Upload logs to log server]
2026-09-25 02:20:09.431643 | localhost | Output suppressed because no_log was given
2026-09-25 02:20:09.438440 | 
2026-09-25 02:20:09.438591 | LOOP [upload-logs : Compress console log and json output]
2026-09-25 02:20:09.530749 | localhost | skipping: Conditional result was False
2026-09-25 02:20:09.537587 | localhost | skipping: Conditional result was False
2026-09-25 02:20:09.547885 | 
2026-09-25 02:20:09.548003 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-25 02:20:09.645966 | localhost | skipping: Conditional result was False
2026-09-25 02:20:09.646376 | 
2026-09-25 02:20:09.650157 | localhost | skipping: Conditional result was False
2026-09-25 02:20:09.663751 | 
2026-09-25 02:20:09.663948 | LOOP [upload-logs : Upload console log and json output]