2026-09-25 02:17:17.499724 | Job console starting
2026-09-25 02:17:17.508615 | Updating git repos
2026-09-25 02:17:25.253272 | Cloning repos into workspace
2026-09-25 02:17:34.271614 | Restoring repo states
2026-09-25 02:17:42.129196 | Merging changes
2026-09-25 02:17:42.431847 | Checking out repos
2026-09-25 02:17:43.627152 | Preparing playbooks
2026-09-25 02:17:46.124100 | Running Ansible setup
2026-09-25 02:17:49.977480 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-25 02:17:50.628474 | 
2026-09-25 02:17:50.628629 | PLAY [localhost]
2026-09-25 02:17:50.635782 | 
2026-09-25 02:17:50.635868 | TASK [Gathering Facts]
2026-09-25 02:17:51.719257 | localhost | ok
2026-09-25 02:17:51.735476 | 
2026-09-25 02:17:51.735695 | TASK [Setup log path fact]
2026-09-25 02:17:51.768772 | localhost | ok
2026-09-25 02:17:51.789216 | 
2026-09-25 02:17:51.789425 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-25 02:17:51.821035 | localhost | ok
2026-09-25 02:17:51.833011 | 
2026-09-25 02:17:51.833152 | TASK [emit-job-header : Print job information]
2026-09-25 02:17:51.873318 | # Job Information
2026-09-25 02:17:51.901619 | Ansible Version: 2.16.18
2026-09-25 02:17:51.901774 | Job: atmosphere-common-molecule-secretgen-controller
2026-09-25 02:17:51.901849 | Pipeline: check
2026-09-25 02:17:51.901912 | Executor: 2d72f0692154
2026-09-25 02:17:51.901966 | Triggered by: https://github.com/vexxhost/atmosphere.common/pull/100
2026-09-25 02:17:51.902028 | Event ID: 7e69d6f0-b886-11f1-8d2b-6626a24f1cb2
2026-09-25 02:17:51.909032 | 
2026-09-25 02:17:51.909167 | LOOP [emit-job-header : Print node information]
2026-09-25 02:17:52.018348 | localhost | ok:
2026-09-25 02:17:52.018489 | localhost | # Node Information
2026-09-25 02:17:52.018519 | localhost | Inventory Hostname: controller
2026-09-25 02:17:52.018540 | localhost | Hostname: np0000193941
2026-09-25 02:17:52.018557 | localhost | Username: zuul
2026-09-25 02:17:52.018578 | localhost | Distro: Ubuntu 24.04
2026-09-25 02:17:52.018597 | localhost | Provider: yul1
2026-09-25 02:17:52.018613 | localhost | Region: ca-ymq-1
2026-09-25 02:17:52.018629 | localhost | Label: ubuntu-noble
2026-09-25 02:17:52.018646 | localhost | Product Name: OpenStack Nova
2026-09-25 02:17:52.018663 | localhost | Interface IP: 162.253.55.140
2026-09-25 02:17:52.039755 | 
2026-09-25 02:17:52.039931 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-25 02:17:52.432219 | localhost -> localhost | changed
2026-09-25 02:17:52.441533 | 
2026-09-25 02:17:52.441704 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-25 02:17:53.255505 | localhost -> localhost | changed
2026-09-25 02:17:53.260859 | 
2026-09-25 02:17:53.260925 | PLAY [all]
2026-09-25 02:17:53.270168 | 
2026-09-25 02:17:53.270275 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-25 02:17:53.486405 | controller -> localhost | ok
2026-09-25 02:17:53.500591 | 
2026-09-25 02:17:53.500823 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-25 02:17:53.523880 | controller | ok
2026-09-25 02:17:53.539950 | controller | included: /var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-25 02:17:53.548179 | 
2026-09-25 02:17:53.548322 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-25 02:17:54.194133 | controller -> localhost | Generating public/private rsa key pair.
2026-09-25 02:17:54.194281 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/c26e7ee281c748a1b38a1f25b10564d3_id_rsa
2026-09-25 02:17:54.194310 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/c26e7ee281c748a1b38a1f25b10564d3_id_rsa.pub
2026-09-25 02:17:54.194330 | controller -> localhost | The key fingerprint is:
2026-09-25 02:17:54.194349 | controller -> localhost | SHA256:ct+aMEE3On2lPDB8jFpEKoJlBh6bVRFpSZKskGDWQHk zuul-build-sshkey
2026-09-25 02:17:54.194376 | controller -> localhost | The key's randomart image is:
2026-09-25 02:17:54.194395 | controller -> localhost | +---[RSA 3072]----+
2026-09-25 02:17:54.194418 | controller -> localhost | |+**o*+== .o      |
2026-09-25 02:17:54.194438 | controller -> localhost | |=o Eo.+  + o     |
2026-09-25 02:17:54.194456 | controller -> localhost | | .*.... o O o .  |
2026-09-25 02:17:54.194474 | controller -> localhost | |  .  . o * * o   |
2026-09-25 02:17:54.194491 | controller -> localhost | |      . S . =    |
2026-09-25 02:17:54.194508 | controller -> localhost | |       o + o .   |
2026-09-25 02:17:54.194526 | controller -> localhost | |        o . .    |
2026-09-25 02:17:54.194543 | controller -> localhost | |         o o     |
2026-09-25 02:17:54.194563 | controller -> localhost | |          o      |
2026-09-25 02:17:54.194580 | controller -> localhost | +----[SHA256]-----+
2026-09-25 02:17:54.194622 | controller -> localhost | ok: Runtime: 0:00:00.207644
2026-09-25 02:17:54.199924 | 
2026-09-25 02:17:54.199981 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-25 02:17:54.227163 | controller | ok
2026-09-25 02:17:54.235026 | controller | included: /var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-25 02:17:54.241834 | 
2026-09-25 02:17:54.241888 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-25 02:17:54.264156 | controller | skipping: Conditional result was False
2026-09-25 02:17:54.269185 | 
2026-09-25 02:17:54.269244 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-25 02:17:54.832710 | controller | changed
2026-09-25 02:17:54.848662 | 
2026-09-25 02:17:54.848862 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-25 02:17:55.105794 | controller | ok
2026-09-25 02:17:55.114657 | 
2026-09-25 02:17:55.114807 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-25 02:17:55.739329 | controller | changed
2026-09-25 02:17:55.747865 | 
2026-09-25 02:17:55.747989 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-25 02:17:56.310356 | controller | changed
2026-09-25 02:17:56.317950 | 
2026-09-25 02:17:56.318057 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-25 02:17:56.340639 | controller | skipping: Conditional result was False
2026-09-25 02:17:56.346126 | 
2026-09-25 02:17:56.346213 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-25 02:17:56.753396 | controller -> localhost | changed
2026-09-25 02:17:56.780544 | 
2026-09-25 02:17:56.780762 | TASK [add-build-sshkey : Add back temp key]
2026-09-25 02:17:57.074147 | controller -> localhost | Identity added: /var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/c26e7ee281c748a1b38a1f25b10564d3_id_rsa (zuul-build-sshkey)
2026-09-25 02:17:57.074324 | controller -> localhost | ok: Runtime: 0:00:00.010896
2026-09-25 02:17:57.078953 | 
2026-09-25 02:17:57.079033 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-25 02:17:57.426893 | controller | ok
2026-09-25 02:17:57.434469 | 
2026-09-25 02:17:57.434617 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-25 02:17:57.459030 | controller | skipping: Conditional result was False
2026-09-25 02:17:57.520947 | 
2026-09-25 02:17:57.521069 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-25 02:17:57.916382 | controller | ok
2026-09-25 02:17:57.923023 | 
2026-09-25 02:17:57.923100 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-25 02:18:01.208526 | controller | Output suppressed because no_log was given
2026-09-25 02:18:01.225228 | 
2026-09-25 02:18:01.225447 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-25 02:18:01.505143 | controller | ok: "logs"
2026-09-25 02:18:01.505499 | controller | ok: All items complete
2026-09-25 02:18:01.505556 | 
2026-09-25 02:18:01.728231 | controller | ok: "artifacts"
2026-09-25 02:18:01.954574 | controller | ok: "docs"
2026-09-25 02:18:01.980141 | 
2026-09-25 02:18:01.980274 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-25 02:18:02.219184 | controller | changed: "logs"
2026-09-25 02:18:02.412859 | controller | changed: "artifacts"
2026-09-25 02:18:02.613243 | controller | changed: "docs"
2026-09-25 02:18:02.633244 | 
2026-09-25 02:18:02.633427 | PLAY RECAP
2026-09-25 02:18:02.633503 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-25 02:18:02.633559 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:18:02.633582 | 
2026-09-25 02:18:02.796854 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-25 02:18:02.798408 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-25 02:18:03.387100 | 
2026-09-25 02:18:03.387217 | PLAY [all]
2026-09-25 02:18:03.397823 | 
2026-09-25 02:18:03.397921 | TASK [setup-uv : Extract archive]
2026-09-25 02:18:06.066051 | controller | changed
2026-09-25 02:18:06.077347 | 
2026-09-25 02:18:06.077517 | TASK [setup-uv : Print version]
2026-09-25 02:18:06.480625 | controller | uv 0.8.13
2026-09-25 02:18:06.617741 | controller | ok: Runtime: 0:00:00.019691
2026-09-25 02:18:06.637544 | 
2026-09-25 02:18:06.637704 | TASK [Install Ansible collection siblings]
2026-09-25 02:18:14.589124 | controller | changed:
2026-09-25 02:18:14.589354 | controller | Sibling collection ansible.posix at /home/zuul/src/github.com/ansible-collections/ansible.posix
2026-09-25 02:18:14.589412 | controller | Sibling collection community.crypto at /home/zuul/src/github.com/ansible-collections/community.crypto
2026-09-25 02:18:14.589455 | controller | Sibling collection community.general at /home/zuul/src/github.com/ansible-collections/community.general
2026-09-25 02:18:14.589494 | controller | Sibling collection kubernetes.core at /home/zuul/src/github.com/ansible-collections/kubernetes.core
2026-09-25 02:18:14.589533 | controller | Sibling collection vexxhost.containers at /home/zuul/src/github.com/vexxhost/ansible-collection-containers
2026-09-25 02:18:14.589577 | controller | Sibling collection vexxhost.kubernetes at /home/zuul/src/github.com/vexxhost/ansible-collection-kubernetes
2026-09-25 02:18:14.589617 | controller | Sibling collection openstack.cloud at /home/zuul/src/opendev.org/openstack/ansible-collections-openstack
2026-09-25 02:18:14.589659 | controller | Resolved kubernetes.core to 6.6.0 for >=2.4.0
2026-09-25 02:18:14.589697 | controller | Resolved openstack.cloud to 2.6.0 for >=2.0.0
2026-09-25 02:18:14.589734 | controller | Resolved vexxhost.kubernetes to v3.4.0 for >=2.5.0
2026-09-25 02:18:14.589770 | controller | Resolved ansible.posix to 2.2.2 for >=1.6.0
2026-09-25 02:18:14.589806 | controller | Resolved community.crypto to 3.4.0 for >=2.2.3
2026-09-25 02:18:14.589840 | controller | Resolved community.general to 13.4.0 for >=4.5.0
2026-09-25 02:18:14.589874 | controller | Skipping community.library_inventory_filtering_v1: no checked-out sibling
2026-09-25 02:18:14.589909 | controller | Resolved kubernetes.core to 6.6.0 for >=2.4.0,>=2.3.2
2026-09-25 02:18:14.589946 | controller | Resolved vexxhost.containers to v1.6.8 for >=1.6.0
2026-09-25 02:18:14.589981 | controller | Checked out ansible.posix to 2.2.2
2026-09-25 02:18:14.590019 | 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:18:14.590062 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.590097 | controller | Process install dependency map
2026-09-25 02:18:14.590133 | controller | Starting collection install process
2026-09-25 02:18:14.590168 | controller | Installing 'ansible.posix:2.2.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 02:18:14.590221 | controller | Created collection for ansible.posix:2.2.2 at /home/zuul/.ansible/collections/ansible_collections/ansible/posix
2026-09-25 02:18:14.590259 | controller | ansible.posix:2.2.2 was installed successfully
2026-09-25 02:18:14.590295 | controller | Using CPython 3.12.3 interpreter at: /usr/bin/python3
2026-09-25 02:18:14.590352 | controller | Creating virtual environment at: .venv
2026-09-25 02:18:14.590387 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.590422 | controller | Downloading pydantic-core (2.0MiB)
2026-09-25 02:18:14.590457 | controller | Downloading pygments (1.2MiB)
2026-09-25 02:18:14.590491 | controller | Downloading ansible-core (2.1MiB)
2026-09-25 02:18:14.590526 | controller | Downloading cryptography (4.3MiB)
2026-09-25 02:18:14.590562 | controller | Downloading kubernetes (1.9MiB)
2026-09-25 02:18:14.590598 | controller |  Downloading pydantic-core
2026-09-25 02:18:14.590633 | controller |  Downloading cryptography
2026-09-25 02:18:14.590667 | controller |  Downloading pygments
2026-09-25 02:18:14.590701 | controller |  Downloading kubernetes
2026-09-25 02:18:14.590736 | controller |  Downloading ansible-core
2026-09-25 02:18:14.590771 | controller | Installed 52 packages in 62ms
2026-09-25 02:18:14.590805 | controller | Checked out community.crypto to 3.4.0
2026-09-25 02:18:14.590839 | 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:18:14.590874 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.590909 | controller | Process install dependency map
2026-09-25 02:18:14.590944 | controller | Starting collection install process
2026-09-25 02:18:14.590978 | controller | Installing 'community.crypto:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 02:18:14.591013 | controller | Created collection for community.crypto:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/community/crypto
2026-09-25 02:18:14.591055 | controller | community.crypto:3.4.0 was installed successfully
2026-09-25 02:18:14.591092 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.591127 | controller | Checked out community.general to 13.4.0
2026-09-25 02:18:14.591163 | 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:18:14.591199 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.591234 | controller | Process install dependency map
2026-09-25 02:18:14.591270 | controller | Starting collection install process
2026-09-25 02:18:14.591326 | controller | Installing 'community.general:13.4.0' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 02:18:14.591366 | controller | Created collection for community.general:13.4.0 at /home/zuul/.ansible/collections/ansible_collections/community/general
2026-09-25 02:18:14.591403 | controller | community.general:13.4.0 was installed successfully
2026-09-25 02:18:14.591439 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.591474 | controller | Checked out kubernetes.core to 6.6.0
2026-09-25 02:18:14.591510 | 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:18:14.591545 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.591581 | controller | Process install dependency map
2026-09-25 02:18:14.591616 | controller | Starting collection install process
2026-09-25 02:18:14.591653 | controller | Installing 'kubernetes.core:6.6.0' to '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 02:18:14.591697 | controller | Created collection for kubernetes.core:6.6.0 at /home/zuul/.ansible/collections/ansible_collections/kubernetes/core
2026-09-25 02:18:14.591735 | controller | kubernetes.core:6.6.0 was installed successfully
2026-09-25 02:18:14.591789 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.591890 | controller | Checked out openstack.cloud to 2.6.0
2026-09-25 02:18:14.591965 | 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:18:14.592032 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.592073 | controller | Process install dependency map
2026-09-25 02:18:14.592110 | controller | Starting collection install process
2026-09-25 02:18:14.592147 | controller | Installing 'openstack.cloud:2.6.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 02:18:14.592183 | controller | Created collection for openstack.cloud:2.6.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/cloud
2026-09-25 02:18:14.592218 | controller | openstack.cloud:2.6.0 was installed successfully
2026-09-25 02:18:14.592255 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.592290 | controller | Checked out vexxhost.containers to v1.6.8
2026-09-25 02:18:14.592356 | 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:18:14.592396 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.592434 | controller | Process install dependency map
2026-09-25 02:18:14.592469 | controller | Starting collection install process
2026-09-25 02:18:14.592543 | controller | Installing 'vexxhost.containers:1.6.8' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 02:18:14.592591 | controller | Created collection for vexxhost.containers:1.6.8 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/containers
2026-09-25 02:18:14.592628 | controller | vexxhost.containers:1.6.8 was installed successfully
2026-09-25 02:18:14.592664 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.592700 | controller | Checked out vexxhost.kubernetes to v3.4.0
2026-09-25 02:18:14.592735 | 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:18:14.592770 | controller | Starting galaxy collection install process
2026-09-25 02:18:14.592805 | controller | Process install dependency map
2026-09-25 02:18:14.592839 | controller | Starting collection install process
2026-09-25 02:18:14.592873 | controller | Installing 'vexxhost.kubernetes:3.4.0' to '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 02:18:14.592907 | controller | Created collection for vexxhost.kubernetes:3.4.0 at /home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes
2026-09-25 02:18:14.592942 | controller | vexxhost.kubernetes:3.4.0 was installed successfully
2026-09-25 02:18:14.592976 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:14.600233 | 
2026-09-25 02:18:14.600343 | PLAY RECAP
2026-09-25 02:18:14.600423 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:18:14.600464 | 
2026-09-25 02:18:14.718090 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-09-25 02:18:14.719291 | PRE-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-25 02:18:15.252716 | 
2026-09-25 02:18:15.252833 | PLAY [Prepare Molecule log collection]
2026-09-25 02:18:15.262871 | 
2026-09-25 02:18:15.262948 | TASK [Install jq for log collection]
2026-09-25 02:18:24.887338 | controller | changed
2026-09-25 02:18:24.891027 | 
2026-09-25 02:18:24.891296 | PLAY RECAP
2026-09-25 02:18:24.891469 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:18:24.891641 | 
2026-09-25 02:18:25.024793 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/pre.yml@main]
2026-09-25 02:18:25.027744 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-25 02:18:25.768966 | 
2026-09-25 02:18:25.769214 | PLAY [all]
2026-09-25 02:18:25.788773 | 
2026-09-25 02:18:25.788996 | LOOP [Check inventory directories]
2026-09-25 02:18:26.153409 | controller | ok: "src/github.com/vexxhost/atmosphere.common"
2026-09-25 02:18:26.345137 | controller | ok: "src/github.com/vexxhost/atmosphere.common/molecule/secretgen-controller/inventory"
2026-09-25 02:18:26.563964 | controller | ok: "src/github.com/vexxhost/atmosphere.common/extensions/molecule/secretgen-controller/inventory"
2026-09-25 02:18:26.577449 | 
2026-09-25 02:18:26.577660 | LOOP [Copy inventory file for Zuul]
2026-09-25 02:18:27.566932 | controller | changed:
2026-09-25 02:18:27.567264 | controller | {
2026-09-25 02:18:27.567379 | controller |   "ansible_loop_var": "item",
2026-09-25 02:18:27.567453 | controller |   "changed": false,
2026-09-25 02:18:27.567517 | controller |   "failed": false,
2026-09-25 02:18:27.567586 | controller |   "invocation": {
2026-09-25 02:18:27.567646 | controller |     "module_args": {
2026-09-25 02:18:27.567704 | controller |       "checksum_algorithm": "sha1",
2026-09-25 02:18:27.567761 | controller |       "follow": true,
2026-09-25 02:18:27.567817 | controller |       "get_attributes": true,
2026-09-25 02:18:27.567878 | controller |       "get_checksum": true,
2026-09-25 02:18:27.567934 | controller |       "get_mime": true,
2026-09-25 02:18:27.567990 | controller |       "path": "src/github.com/vexxhost/atmosphere.common"
2026-09-25 02:18:27.568046 | controller |     }
2026-09-25 02:18:27.568101 | controller |   },
2026-09-25 02:18:27.568156 | controller |   "item": "src/github.com/vexxhost/atmosphere.common",
2026-09-25 02:18:27.568215 | controller |   "stat": {
2026-09-25 02:18:27.568271 | controller |     "atime": 1790302680.933465,
2026-09-25 02:18:27.568351 | controller |     "attr_flags": "e",
2026-09-25 02:18:27.568414 | controller |     "attributes": [
2026-09-25 02:18:27.568472 | controller |       "extents"
2026-09-25 02:18:27.568566 | controller |     ],
2026-09-25 02:18:27.568628 | controller |     "block_size": 4096,
2026-09-25 02:18:27.568683 | controller |     "blocks": 8,
2026-09-25 02:18:27.568739 | controller |     "charset": "unknown",
2026-09-25 02:18:27.568799 | controller |     "ctime": 1790302687.433738,
2026-09-25 02:18:27.568856 | controller |     "dev": 64769,
2026-09-25 02:18:27.568912 | controller |     "device_type": 0,
2026-09-25 02:18:27.568968 | controller |     "executable": true,
2026-09-25 02:18:27.569022 | controller |     "exists": true,
2026-09-25 02:18:27.569076 | controller |     "gid": 1000,
2026-09-25 02:18:27.569130 | controller |     "gr_name": "zuul",
2026-09-25 02:18:27.569184 | controller |     "inode": 1038711,
2026-09-25 02:18:27.569240 | controller |     "isblk": false,
2026-09-25 02:18:27.569295 | controller |     "ischr": false,
2026-09-25 02:18:27.569744 | controller |     "isdir": true,
2026-09-25 02:18:27.569843 | controller |     "isfifo": false,
2026-09-25 02:18:27.569915 | controller |     "isgid": false,
2026-09-25 02:18:27.569979 | controller |     "islnk": false,
2026-09-25 02:18:27.570088 | controller |     "isreg": false,
2026-09-25 02:18:27.570154 | controller |     "issock": false,
2026-09-25 02:18:27.570210 | controller |     "isuid": false,
2026-09-25 02:18:27.570286 | controller |     "mimetype": "unknown",
2026-09-25 02:18:27.570372 | controller |     "mode": "0755",
2026-09-25 02:18:27.570431 | controller |     "mtime": 1790302687.433738,
2026-09-25 02:18:27.570486 | controller |     "nlink": 11,
2026-09-25 02:18:27.570542 | controller |     "path": "src/github.com/vexxhost/atmosphere.common",
2026-09-25 02:18:27.570596 | controller |     "pw_name": "zuul",
2026-09-25 02:18:27.570652 | controller |     "readable": true,
2026-09-25 02:18:27.570709 | controller |     "rgrp": true,
2026-09-25 02:18:27.570766 | controller |     "roth": true,
2026-09-25 02:18:27.570823 | controller |     "rusr": true,
2026-09-25 02:18:27.570880 | controller |     "size": 4096,
2026-09-25 02:18:27.570937 | controller |     "uid": 1000,
2026-09-25 02:18:27.570994 | controller |     "version": "3736689170",
2026-09-25 02:18:27.571052 | controller |     "wgrp": false,
2026-09-25 02:18:27.571116 | controller |     "woth": false,
2026-09-25 02:18:27.571174 | controller |     "writeable": true,
2026-09-25 02:18:27.571229 | controller |     "wusr": true,
2026-09-25 02:18:27.571285 | controller |     "xgrp": true,
2026-09-25 02:18:27.571371 | controller |     "xoth": true,
2026-09-25 02:18:27.571430 | controller |     "xusr": true
2026-09-25 02:18:27.571484 | controller |   }
2026-09-25 02:18:27.571539 | controller | }
2026-09-25 02:18:27.571642 | controller | ok: All items complete
2026-09-25 02:18:27.571712 | 
2026-09-25 02:18:27.575655 | controller | skipping: Conditional result was False
2026-09-25 02:18:27.577909 | controller | skipping: Conditional result was False
2026-09-25 02:18:27.589835 | 
2026-09-25 02:18:27.589955 | LOOP [Switch "ansible_host" to private IP]
2026-09-25 02:18:27.979409 | controller | changed: 1 replacements made
2026-09-25 02:18:27.983082 | controller | skipping: Conditional result was False
2026-09-25 02:18:27.990982 | controller | skipping: Conditional result was False
2026-09-25 02:18:28.011037 | 
2026-09-25 02:18:28.011178 | TASK [Run Molecule scenario]
2026-09-25 02:18:28.427964 | controller | warning: No `requires-python` value found in the workspace. Defaulting to `>=3.12`.
2026-09-25 02:18:29.322258 | controller | INFO     Collection 'atmosphere.common' detected.
2026-09-25 02:18:29.322379 | controller | INFO     Scenarios will be used from 'extensions/molecule'
2026-09-25 02:18:29.857551 | 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:18:29.857658 | controller | INFO     [secretgen-controller > prerun] Performing prerun with role_name_check=0...
2026-09-25 02:18:37.041524 | 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:18:37.041629 | controller | ERROR    Command ansible-galaxy collection install -vvv --force /home/zuul/src/github.com/vexxhost/atmosphere.common, returned 1 code:
2026-09-25 02:18:37.041639 | controller | Starting galaxy collection install process
2026-09-25 02:18:37.041647 | controller | Process install dependency map
2026-09-25 02:18:37.041655 | controller |
2026-09-25 02:18:37.041662 | controller | ansible-galaxy
2026-09-25 02:18:37.041671 | controller |   config file = None
2026-09-25 02:18:37.041679 | controller |   configured module search path =
2026-09-25 02:18:37.041705 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-25 02:18:37.041713 | 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:18:37.041727 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-25 02:18:37.041734 | 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-25 02:18:37.041741 | controller |   jinja version = 3.1.6
2026-09-25 02:18:37.041748 | controller |   libyaml = True
2026-09-25 02:18:37.041755 | controller | No config file found; using defaults
2026-09-25 02:18:37.041762 | controller | Found installed collection vexxhost.kubernetes:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 02:18:37.041769 | controller | Found installed collection kubernetes.core:6.6.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 02:18:37.041776 | controller | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 02:18:37.041783 | controller | Found installed collection vexxhost.containers:1.6.8 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 02:18:37.041790 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 02:18:37.041797 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 02:18:37.041804 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 02:18:37.041811 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-25 02:18:37.041818 | controller | : Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-25 02:18:37.041825 | controller | unexpected error when getting available versions of collection
2026-09-25 02:18:37.041832 | controller | community.library_inventory_filtering_v1: Unknown error when attempting to call
2026-09-25 02:18:37.041839 | controller | Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inve
2026-09-25 02:18:37.041846 | controller | ntory_filtering_v1/versions/1.1.5/': <urlopen error  Connection
2026-09-25 02:18:37.041853 | controller | reset by peer>. <urlopen error  Connection reset by peer>
2026-09-25 02:18:37.041866 | 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-25 02:18:37.041903 | controller |
2026-09-25 02:18:37.042281 | controller | Traceback (most recent call last):
2026-09-25 02:18:37.042583 | controller |   File "/home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/molecule", line 10, in <module>
2026-09-25 02:18:37.042966 | controller |     sys.exit(main())
2026-09-25 02:18:37.043253 | controller |              ^^^^^^
2026-09-25 02:18:37.043608 | 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-25 02:18:37.044329 | controller |     return self.main(*args, **kwargs)
2026-09-25 02:18:37.044625 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.044828 | 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-25 02:18:37.045501 | controller |     rv = self.invoke(ctx)
2026-09-25 02:18:37.045662 | controller |          ^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.045788 | 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-25 02:18:37.046379 | controller |     return _process_result(sub_ctx.command.invoke(sub_ctx))
2026-09-25 02:18:37.046540 | controller |                            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.046671 | 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-25 02:18:37.047086 | controller |     return ctx.invoke(self.callback, **ctx.params)
2026-09-25 02:18:37.047283 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.047499 | 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-25 02:18:37.047886 | controller |     return callback(*args, **kwargs)
2026-09-25 02:18:37.048098 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.048240 | 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-25 02:18:37.048824 | controller |     return func(*args, **kwargs)
2026-09-25 02:18:37.049086 | controller |            ^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.049288 | 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:18:37.049637 | controller |     return f(get_current_context(), *args, **kwargs)
2026-09-25 02:18:37.049902 | controller |            ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2026-09-25 02:18:37.050173 | 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-25 02:18:37.050617 | controller |     return func(ctx)
2026-09-25 02:18:37.050851 | controller |            ^^^^^^^^^
2026-09-25 02:18:37.051081 | 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-25 02:18:37.051600 | controller |     base.execute_cmdline_scenarios(scenario_name, args, command_args, ansible_args, exclude)
2026-09-25 02:18:37.051948 | 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-25 02:18:37.052503 | controller |     _run_scenarios(scenarios, command_args, default_config)
2026-09-25 02:18:37.052844 | 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-25 02:18:37.053340 | controller |     scenario.config.runtime.prepare_environment(
2026-09-25 02:18:37.053606 | 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-25 02:18:37.054210 | controller |     self.install_collection_from_disk(
2026-09-25 02:18:37.054438 | 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-25 02:18:37.054740 | controller |     self.install_collection(path, destination=destination, force=True)
2026-09-25 02:18:37.054902 | 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-25 02:18:37.055212 | controller |     raise InvalidPrerequisiteError(msg)
2026-09-25 02:18:37.056484 | 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:18:37.056507 | controller | Starting galaxy collection install process
2026-09-25 02:18:37.056517 | controller | Process install dependency map
2026-09-25 02:18:37.056526 | controller |
2026-09-25 02:18:37.056536 | controller | ansible-galaxy [core 2.18.17]
2026-09-25 02:18:37.056545 | controller |   config file = None
2026-09-25 02:18:37.056554 | controller |   configured module search path = ['/home/zuul/.ansible/plugins/modules', '/usr/share/ansible/plugins/modules']
2026-09-25 02:18:37.056563 | controller |   ansible python module location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/lib/python3.12/site-packages/ansible
2026-09-25 02:18:37.056573 | 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:18:37.056582 | controller |   executable location = /home/zuul/src/github.com/vexxhost/atmosphere.common/.venv/bin/ansible-galaxy
2026-09-25 02:18:37.056591 | 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:18:37.056600 | controller |   jinja version = 3.1.6
2026-09-25 02:18:37.056609 | controller |   libyaml = True
2026-09-25 02:18:37.056618 | controller | No config file found; using defaults
2026-09-25 02:18:37.056627 | controller | Found installed collection vexxhost.kubernetes:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/kubernetes'
2026-09-25 02:18:37.056635 | controller | Found installed collection kubernetes.core:6.6.0 at '/home/zuul/.ansible/collections/ansible_collections/kubernetes/core'
2026-09-25 02:18:37.056644 | controller | Found installed collection community.general:13.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-09-25 02:18:37.056653 | controller | Found installed collection vexxhost.containers:1.6.8 at '/home/zuul/.ansible/collections/ansible_collections/vexxhost/containers'
2026-09-25 02:18:37.056677 | controller | Found installed collection community.crypto:3.4.0 at '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-09-25 02:18:37.056689 | controller | Found installed collection ansible.posix:2.2.2 at '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-09-25 02:18:37.056701 | controller | Found installed collection openstack.cloud:2.6.0 at '/home/zuul/.ansible/collections/ansible_collections/openstack/cloud'
2026-09-25 02:18:37.056713 | controller | Created /home/zuul/.ansible/galaxy_token
2026-09-25 02:18:37.056724 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an
2026-09-25 02:18:37.056736 | controller | unexpected error when getting available versions of collection
2026-09-25 02:18:37.056747 | controller | community.library_inventory_filtering_v1: Unknown error when attempting to call
2026-09-25 02:18:37.056756 | controller | Galaxy at 'https://galaxy.ansible.com/api/v3/collections/community/library_inve
2026-09-25 02:18:37.056765 | controller | ntory_filtering_v1/versions/1.1.5/': <urlopen error [Errno 104] Connection
2026-09-25 02:18:37.056774 | controller | reset by peer>. <urlopen error [Errno 104] Connection reset by peer>
2026-09-25 02:18:37.056790 | 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-25 02:18:37.056823 | controller |
2026-09-25 02:18:37.559257 | controller | ERROR
2026-09-25 02:18:37.559458 | controller | {
2026-09-25 02:18:37.559499 | controller |   "delta": "0:00:08.775500",
2026-09-25 02:18:37.559526 | controller |   "end": "2026-09-25 02:18:37.176213",
2026-09-25 02:18:37.559552 | controller |   "msg": "non-zero return code",
2026-09-25 02:18:37.559577 | controller |   "rc": 1,
2026-09-25 02:18:37.559610 | controller |   "start": "2026-09-25 02:18:28.400713"
2026-09-25 02:18:37.559643 | controller | }
failure
2026-09-25 02:18:37.562596 | 
2026-09-25 02:18:37.563680 | PLAY RECAP
2026-09-25 02:18:37.563759 | controller | ok: 3 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-25 02:18:37.563801 | 
2026-09-25 02:18:37.732022 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-09-25 02:18:37.734916 | POST-RUN START: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-25 02:18:38.343468 | 
2026-09-25 02:18:38.343587 | PLAY [Collect Molecule logs]
2026-09-25 02:18:38.356650 | 
2026-09-25 02:18:38.356801 | TASK [gather-host-logs : creating directory for system status]
2026-09-25 02:18:38.808853 | controller | changed
2026-09-25 02:18:38.832050 | 
2026-09-25 02:18:38.832225 | TASK [gather-host-logs : Get logs for each host]
2026-09-25 02:18:39.281800 | controller | + systemd-cgls --full --all --no-pager
2026-09-25 02:18:39.309505 | controller | + ip addr
2026-09-25 02:18:39.313297 | controller | + ip route
2026-09-25 02:18:39.317779 | controller | + lsblk
2026-09-25 02:18:39.326543 | controller | + mount
2026-09-25 02:18:39.331290 | controller | + docker images
2026-09-25 02:18:39.331993 | controller | /bin/bash: line 7: docker: command not found
2026-09-25 02:18:39.332543 | controller | + brctl show
2026-09-25 02:18:39.333448 | controller | /bin/bash: line 8: brctl: command not found
2026-09-25 02:18:39.334030 | controller | + ip neigh
2026-09-25 02:18:39.338707 | controller | + bridge fdb show
2026-09-25 02:18:39.350760 | controller | + ps aux --sort=-%mem
2026-09-25 02:18:39.393969 | controller | + dpkg -l
2026-09-25 02:18:39.416944 | controller | + CONTAINERS=($(docker ps -a --format '{{ .Names }}' --filter label=zuul))
2026-09-25 02:18:39.417819 | controller | ++ docker ps -a --format '{{ .Names }}' --filter label=zuul
2026-09-25 02:18:39.417876 | controller | /bin/bash: line 13: docker: command not found
2026-09-25 02:18:39.418530 | controller | + '[' '!' -z '' ']'
2026-09-25 02:18:39.881246 | controller | ok: Runtime: 0:00:00.145955
2026-09-25 02:18:39.895001 | 
2026-09-25 02:18:40.084318 | TASK [gather-host-logs : Downloads logs to executor]
2026-09-25 02:18:40.802418 | controller | changed:
2026-09-25 02:18:40.940819 | controller | created directory /var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/logs/controller
2026-09-25 02:18:40.940987 | controller | cd+++++++++ system/
2026-09-25 02:18:40.941081 | controller | >f+++++++++ system/brctl-show.txt
2026-09-25 02:18:40.941161 | controller | >f+++++++++ system/bridge-fdb.txt
2026-09-25 02:18:40.941233 | controller | >f+++++++++ system/docker-images.txt
2026-09-25 02:18:40.941310 | controller | >f+++++++++ system/ip-addr.txt
2026-09-25 02:18:40.941430 | controller | >f+++++++++ system/ip-neigh.txt
2026-09-25 02:18:40.941509 | controller | >f+++++++++ system/ip-route.txt
2026-09-25 02:18:40.941582 | controller | >f+++++++++ system/lsblk.txt
2026-09-25 02:18:40.941654 | controller | >f+++++++++ system/mount.txt
2026-09-25 02:18:40.941725 | controller | >f+++++++++ system/packages.txt
2026-09-25 02:18:40.941786 | controller | >f+++++++++ system/ps.txt
2026-09-25 02:18:40.941840 | controller | >f+++++++++ system/systemd-cgls.txt
2026-09-25 02:18:40.965763 | 
2026-09-25 02:18:41.180470 | LOOP [helm-release-status : creating directory for helm release status]
2026-09-25 02:18:41.443299 | controller | changed: "values"
2026-09-25 02:18:41.665131 | controller | changed: "releases"
2026-09-25 02:18:41.696992 | 
2026-09-25 02:18:41.735871 | TASK [helm-release-status : Gather get release status for helm charts]
2026-09-25 02:18:42.021233 | controller | /bin/bash: line 3: kubectl: command not found
2026-09-25 02:18:42.305358 | controller | ok: Runtime: 0:00:00.009251
2026-09-25 02:18:42.316486 | 
2026-09-25 02:18:42.316678 | TASK [helm-release-status : Downloads logs to executor]
2026-09-25 02:18:43.049806 | controller | changed:
2026-09-25 02:18:43.246145 | controller | cd+++++++++ helm/
2026-09-25 02:18:43.246259 | controller | cd+++++++++ helm/releases/
2026-09-25 02:18:43.246309 | controller | cd+++++++++ helm/values/
2026-09-25 02:18:43.276267 | 
2026-09-25 02:18:43.381152 | TASK [describe-kubernetes-objects : creating directory for cluster scoped objects]
2026-09-25 02:18:43.664429 | controller | changed
2026-09-25 02:18:43.719451 | 
2026-09-25 02:18:43.783069 | TASK [describe-kubernetes-objects : Gathering descriptions for cluster scoped objects]
2026-09-25 02:18:44.124441 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:18:44.124539 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:18:44.131150 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:18:44.133471 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:18:44.138534 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:18:44.140788 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:18:44.142598 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:18:44.145122 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:18:44.148018 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:18:44.149365 | controller | environment: line 1: kubectl: command not found
2026-09-25 02:18:44.150545 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:18:44.151771 | controller | xargs: warning: options --max-lines and --replace/-I/-i are mutually exclusive, ignoring previous --max-lines value
2026-09-25 02:18:44.557066 | controller | ok: Runtime: 0:00:00.043576
2026-09-25 02:18:44.570308 | 
2026-09-25 02:18:44.612589 | TASK [describe-kubernetes-objects : creating directory for namespace scoped objects]
2026-09-25 02:18:44.901267 | controller | changed
2026-09-25 02:18:45.061399 | 
2026-09-25 02:18:45.061588 | TASK [describe-kubernetes-objects : Gathering descriptions for namespace scoped objects]
2026-09-25 02:18:45.389178 | controller | environment: line 5: kubectl: command not found
2026-09-25 02:18:45.390821 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:18:45.391421 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:18:45.391669 | controller | xargs: warning: options --max-args and --replace/-I/-i are mutually exclusive, ignoring previous --max-args value
2026-09-25 02:18:45.617804 | controller | ok: Runtime: 0:00:00.015622
2026-09-25 02:18:45.625086 | 
2026-09-25 02:18:45.625240 | TASK [describe-kubernetes-objects : Downloads logs to executor]
2026-09-25 02:18:46.260040 | controller | changed:
2026-09-25 02:18:46.260253 | controller | cd+++++++++ objects/
2026-09-25 02:18:46.260309 | controller | cd+++++++++ objects/cluster/
2026-09-25 02:18:46.260369 | controller | cd+++++++++ objects/namespaced/
2026-09-25 02:18:46.275531 | 
2026-09-25 02:18:46.275601 | TASK [gather-pod-logs : creating directory for pod logs]
2026-09-25 02:18:46.563535 | controller | changed
2026-09-25 02:18:46.647042 | 
2026-09-25 02:18:46.647308 | TASK [gather-pod-logs : creating directory for failed pod logs]
2026-09-25 02:18:46.939056 | controller | changed
2026-09-25 02:18:46.954971 | 
2026-09-25 02:18:46.955192 | TASK [gather-pod-logs : retrieve all kubernetes logs, current and previous (if they exist)]
2026-09-25 02:18:47.279557 | controller | environment: line 3: kubectl: command not found
2026-09-25 02:18:47.521526 | controller | ok: Runtime: 0:00:00.016064
2026-09-25 02:18:47.526744 | 
2026-09-25 02:18:47.530932 | TASK [gather-pod-logs : Downloads pod logs to executor]
2026-09-25 02:18:48.398013 | controller | changed:
2026-09-25 02:18:48.398185 | controller | cd+++++++++ pod-logs/
2026-09-25 02:18:48.398221 | controller | cd+++++++++ pod-logs/failed-pods/
2026-09-25 02:18:48.411443 | 
2026-09-25 02:18:48.411577 | TASK [gather-prom-metrics : creating directory for helm release descriptions]
2026-09-25 02:18:48.681324 | controller | changed
2026-09-25 02:18:48.691061 | 
2026-09-25 02:18:48.691153 | TASK [gather-prom-metrics : Get metrics from exporter services in all namespaces]
2026-09-25 02:18:48.968153 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-25 02:18:49.244900 | controller | ok: Runtime: 0:00:00.017146
2026-09-25 02:18:49.254625 | 
2026-09-25 02:18:49.254753 | TASK [gather-prom-metrics : Get ceph metrics from ceph-mgr]
2026-09-25 02:18:49.586690 | controller | /bin/bash: line 2: kubectl: command not found
2026-09-25 02:18:49.592451 | controller | ceph-mgr endpoints:
2026-09-25 02:18:49.805171 | controller | ok: Runtime: 0:00:00.013419
2026-09-25 02:18:49.820908 | 
2026-09-25 02:18:49.907750 | TASK [gather-prom-metrics : Get metrics from fluentd pods]
2026-09-25 02:18:50.289460 | controller | /bin/bash: line 4: kubectl: command not found
2026-09-25 02:18:50.465698 | controller | ok: Runtime: 0:00:00.020983
2026-09-25 02:18:50.472398 | 
2026-09-25 02:18:50.472509 | TASK [gather-prom-metrics : Downloads logs to executor]
2026-09-25 02:18:51.027575 | controller | changed: cd+++++++++ prometheus/
2026-09-25 02:18:51.085916 | 
2026-09-25 02:18:51.086034 | TASK [gather-selenium-data : creating directory for helm release descriptions]
2026-09-25 02:18:51.357609 | controller | changed
2026-09-25 02:18:51.364885 | 
2026-09-25 02:18:51.364990 | TASK [gather-selenium-data : Get selenium data]
2026-09-25 02:18:51.686078 | controller | + cp '/tmp/artifacts/*' /tmp/logs/selenium/.
2026-09-25 02:18:51.694975 | controller | cp: cannot stat '/tmp/artifacts/*': No such file or directory
2026-09-25 02:18:51.929301 | controller | ERROR
2026-09-25 02:18:51.929609 | controller | {
2026-09-25 02:18:51.929675 | controller |   "delta": "0:00:00.017516",
2026-09-25 02:18:51.929718 | controller |   "end": "2026-09-25 02:18:51.695678",
2026-09-25 02:18:51.929756 | controller |   "msg": "non-zero return code",
2026-09-25 02:18:51.929792 | controller |   "rc": 1,
2026-09-25 02:18:51.929827 | controller |   "start": "2026-09-25 02:18:51.678162"
2026-09-25 02:18:51.929860 | controller | }
2026-09-25 02:18:51.929909 | controller | ERROR: Ignoring Errors
2026-09-25 02:18:51.941433 | 
2026-09-25 02:18:51.941579 | TASK [gather-selenium-data : Downloads logs to executor]
2026-09-25 02:18:52.486899 | controller | changed: cd+++++++++ selenium/
2026-09-25 02:18:52.497188 | 
2026-09-25 02:18:52.497258 | PLAY RECAP
2026-09-25 02:18:52.497312 | controller | ok: 23 changed: 23 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-09-25 02:18:52.497346 | 
2026-09-25 02:18:52.640671 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/atmosphere.common/test-playbooks/molecule/post.yml@main]
2026-09-25 02:18:52.643073 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-25 02:18:53.193676 | 
2026-09-25 02:18:53.193785 | PLAY [all]
2026-09-25 02:18:53.204124 | 
2026-09-25 02:18:53.204199 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-25 02:18:53.267689 | controller | skipping: Conditional result was False
2026-09-25 02:18:53.275737 | 
2026-09-25 02:18:53.275843 | TASK [fetch-output : Set log path for single node]
2026-09-25 02:18:53.328275 | controller | ok
2026-09-25 02:18:53.337773 | 
2026-09-25 02:18:53.339375 | LOOP [fetch-output : Ensure local output dirs]
2026-09-25 02:18:53.738566 | controller -> localhost | ok: "/var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/logs"
2026-09-25 02:18:53.970416 | controller -> localhost | changed: "/var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/artifacts"
2026-09-25 02:18:54.250474 | controller -> localhost | changed: "/var/lib/zuul/builds/c26e7ee281c748a1b38a1f25b10564d3/work/docs"
2026-09-25 02:18:54.271674 | 
2026-09-25 02:18:54.271852 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-25 02:18:54.960333 | controller | changed: .d..t...... ./
2026-09-25 02:18:54.960525 | controller | changed: All items complete
2026-09-25 02:18:54.960555 | 
2026-09-25 02:18:55.495824 | controller | changed: .d..t...... ./
2026-09-25 02:18:56.047411 | controller | changed: .d..t...... ./
2026-09-25 02:18:56.079791 | 
2026-09-25 02:18:56.079924 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-25 02:18:56.542320 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.040562
2026-09-25 02:18:56.836522 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.090069
2026-09-25 02:18:56.853151 | 
2026-09-25 02:18:56.853300 | PLAY [all]
2026-09-25 02:18:56.865083 | 
2026-09-25 02:18:56.865211 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-25 02:18:57.397347 | controller | changed
2026-09-25 02:18:57.409668 | 
2026-09-25 02:18:57.409797 | PLAY RECAP
2026-09-25 02:18:57.409906 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-25 02:18:57.409955 | 
2026-09-25 02:18:57.527876 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-25 02:18:57.531859 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-25 02:18:58.127653 | 
2026-09-25 02:18:58.127848 | PLAY [localhost]
2026-09-25 02:18:58.138825 | 
2026-09-25 02:18:58.138915 | TASK [Generate Zuul manifest]
2026-09-25 02:18:58.156475 | localhost | ok
2026-09-25 02:18:58.170423 | 
2026-09-25 02:18:58.170518 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-25 02:18:58.564259 | localhost | changed
2026-09-25 02:18:58.611116 | 
2026-09-25 02:18:58.611279 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-25 02:18:58.647161 | localhost | ok
2026-09-25 02:18:58.660637 | 
2026-09-25 02:18:58.660746 | TASK [Upload logs]
2026-09-25 02:18:58.679755 | localhost | ok
2026-09-25 02:18:58.745659 | 
2026-09-25 02:18:58.745789 | TASK [Set zuul-log-path fact]
2026-09-25 02:18:58.763616 | localhost | ok
2026-09-25 02:18:58.774889 | 
2026-09-25 02:18:58.774955 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-25 02:18:58.814068 | localhost | ok
2026-09-25 02:18:58.825126 | 
2026-09-25 02:18:58.825217 | TASK [upload-logs : Create log directories]
2026-09-25 02:18:59.260816 | localhost | changed
2026-09-25 02:18:59.266866 | 
2026-09-25 02:18:59.267003 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-25 02:18:59.664249 | localhost -> localhost | ok: Runtime: 0:00:00.003877
2026-09-25 02:18:59.670605 | 
2026-09-25 02:18:59.670709 | TASK [upload-logs : Upload logs to log server]
2026-09-25 02:19:00.162856 | localhost | Output suppressed because no_log was given
2026-09-25 02:19:00.170245 | 
2026-09-25 02:19:00.170350 | LOOP [upload-logs : Compress console log and json output]
2026-09-25 02:19:00.252988 | localhost | skipping: Conditional result was False
2026-09-25 02:19:00.261139 | localhost | skipping: Conditional result was False
2026-09-25 02:19:00.270630 | 
2026-09-25 02:19:00.270735 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-25 02:19:00.346938 | localhost | skipping: Conditional result was False
2026-09-25 02:19:00.347281 | 
2026-09-25 02:19:00.351194 | localhost | skipping: Conditional result was False
2026-09-25 02:19:00.359461 | 
2026-09-25 02:19:00.359547 | LOOP [upload-logs : Upload console log and json output]