2026-02-27 16:42:04.356040 | Job console starting
2026-02-27 16:42:04.366767 | Updating git repos
2026-02-27 16:42:04.408648 | Cloning repos into workspace
2026-02-27 16:42:04.481698 | Restoring repo states
2026-02-27 16:42:04.498285 | Merging changes
2026-02-27 16:42:05.410579 | Checking out repos
2026-02-27 16:42:05.508725 | Preparing playbooks
2026-02-27 16:42:09.378094 | Running Ansible setup
2026-02-27 16:42:14.822287 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-27 16:42:15.466581 | 
2026-02-27 16:42:15.466704 | PLAY [localhost]
2026-02-27 16:42:15.473834 | 
2026-02-27 16:42:15.473900 | TASK [Gathering Facts]
2026-02-27 16:42:18.207739 | localhost | ok
2026-02-27 16:42:18.222166 | 
2026-02-27 16:42:18.222279 | TASK [Setup log path fact]
2026-02-27 16:42:18.241323 | localhost | ok
2026-02-27 16:42:18.253285 | 
2026-02-27 16:42:18.253402 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 16:42:18.283292 | localhost | ok
2026-02-27 16:42:18.291515 | 
2026-02-27 16:42:18.291585 | TASK [emit-job-header : Print job information]
2026-02-27 16:42:18.331977 | # Job Information
2026-02-27 16:42:18.332133 | Ansible Version: 2.16.16
2026-02-27 16:42:18.332179 | Job: atmosphere-molecule-aio-ovn
2026-02-27 16:42:18.332209 | Pipeline: check
2026-02-27 16:42:18.332237 | Executor: 0a8996d2b663
2026-02-27 16:42:18.332264 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3594
2026-02-27 16:42:18.332295 | Event ID: 27f8c590-13fb-11f1-8565-3bdcc6b09bc1
2026-02-27 16:42:18.336019 | 
2026-02-27 16:42:18.336105 | LOOP [emit-job-header : Print node information]
2026-02-27 16:42:18.429153 | localhost | ok:
2026-02-27 16:42:18.429471 | localhost | # Node Information
2026-02-27 16:42:18.429533 | localhost | Inventory Hostname: instance
2026-02-27 16:42:18.429580 | localhost | Hostname: np0000157333
2026-02-27 16:42:18.429622 | localhost | Username: zuul
2026-02-27 16:42:18.429670 | localhost | Distro: Ubuntu 22.04
2026-02-27 16:42:18.429712 | localhost | Provider: yul1
2026-02-27 16:42:18.429753 | localhost | Region: ca-ymq-1
2026-02-27 16:42:18.429794 | localhost | Label: ubuntu-jammy-16
2026-02-27 16:42:18.429834 | localhost | Product Name: OpenStack Nova
2026-02-27 16:42:18.429874 | localhost | Interface IP: 199.204.45.229
2026-02-27 16:42:18.440304 | 
2026-02-27 16:42:18.440426 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-27 16:42:18.829635 | localhost -> localhost | changed
2026-02-27 16:42:18.836938 | 
2026-02-27 16:42:18.837037 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-27 16:42:19.671221 | localhost -> localhost | changed
2026-02-27 16:42:19.682408 | 
2026-02-27 16:42:19.682500 | PLAY [all]
2026-02-27 16:42:19.690976 | 
2026-02-27 16:42:19.691056 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 16:42:19.949008 | instance -> localhost | ok
2026-02-27 16:42:19.959913 | 
2026-02-27 16:42:19.960008 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 16:42:19.995663 | instance | ok
2026-02-27 16:42:20.010185 | instance | included: /var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 16:42:20.015966 | 
2026-02-27 16:42:20.016027 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 16:42:20.750554 | instance -> localhost | Generating public/private rsa key pair.
2026-02-27 16:42:20.750771 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/work/a9406a04c1454077a0dfd72b0bca0a02_id_rsa
2026-02-27 16:42:20.760925 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/work/a9406a04c1454077a0dfd72b0bca0a02_id_rsa.pub
2026-02-27 16:42:20.761122 | instance -> localhost | The key fingerprint is:
2026-02-27 16:42:20.761154 | instance -> localhost | SHA256:ScSd5aT9itiVerI3dPOaB7SST9o1ingCTtkgy+LxFkM zuul-build-sshkey
2026-02-27 16:42:20.761210 | instance -> localhost | The key's randomart image is:
2026-02-27 16:42:20.761233 | instance -> localhost | +---[RSA 3072]----+
2026-02-27 16:42:20.761380 | instance -> localhost | |       ... oo    |
2026-02-27 16:42:20.761415 | instance -> localhost | |       .. o=     |
2026-02-27 16:42:20.761437 | instance -> localhost | |        . . o    |
2026-02-27 16:42:20.761457 | instance -> localhost | |     E o .   o.  |
2026-02-27 16:42:20.761477 | instance -> localhost | |    o o S   oo.. |
2026-02-27 16:42:20.761518 | instance -> localhost | |   o = + + ++.*..|
2026-02-27 16:42:20.761541 | instance -> localhost | |  . + = o *.+B.=.|
2026-02-27 16:42:20.761560 | instance -> localhost | |   . o . o *+.o.o|
2026-02-27 16:42:20.761603 | instance -> localhost | |    .     +. .oo |
2026-02-27 16:42:20.761678 | instance -> localhost | +----[SHA256]-----+
2026-02-27 16:42:20.761794 | instance -> localhost | ok: Runtime: 0:00:00.288350
2026-02-27 16:42:20.767320 | 
2026-02-27 16:42:20.767382 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 16:42:20.800693 | instance | ok
2026-02-27 16:42:20.813308 | instance | included: /var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 16:42:20.820759 | 
2026-02-27 16:42:20.820838 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 16:42:20.845151 | instance | skipping: Conditional result was False
2026-02-27 16:42:20.857460 | 
2026-02-27 16:42:20.857577 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 16:42:21.355178 | instance | changed
2026-02-27 16:42:21.361898 | 
2026-02-27 16:42:21.361985 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 16:42:21.538603 | instance | ok
2026-02-27 16:42:21.543733 | 
2026-02-27 16:42:21.543803 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 16:42:22.013887 | instance | changed
2026-02-27 16:42:22.019351 | 
2026-02-27 16:42:22.019417 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 16:42:22.510827 | instance | changed
2026-02-27 16:42:22.516428 | 
2026-02-27 16:42:22.516503 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 16:42:22.541615 | instance | skipping: Conditional result was False
2026-02-27 16:42:22.554121 | 
2026-02-27 16:42:22.554214 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 16:42:22.881382 | instance -> localhost | changed
2026-02-27 16:42:22.926298 | 
2026-02-27 16:42:22.926423 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 16:42:23.385130 | instance -> localhost | Identity added: /var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/work/a9406a04c1454077a0dfd72b0bca0a02_id_rsa (zuul-build-sshkey)
2026-02-27 16:42:23.385382 | instance -> localhost | ok: Runtime: 0:00:00.014558
2026-02-27 16:42:23.392443 | 
2026-02-27 16:42:23.392528 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 16:42:23.674282 | instance | ok
2026-02-27 16:42:23.679018 | 
2026-02-27 16:42:23.679082 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 16:42:23.702987 | instance | skipping: Conditional result was False
2026-02-27 16:42:23.720949 | 
2026-02-27 16:42:23.721044 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-27 16:42:24.019084 | instance | ok
2026-02-27 16:42:24.157914 | 
2026-02-27 16:42:24.158026 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-27 16:42:25.670037 | instance | Output suppressed because no_log was given
2026-02-27 16:42:25.680203 | 
2026-02-27 16:42:25.680299 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-27 16:42:25.869713 | instance | ok: "logs"
2026-02-27 16:42:25.869949 | instance | ok: All items complete
2026-02-27 16:42:25.869977 | 
2026-02-27 16:42:26.019288 | instance | ok: "artifacts"
2026-02-27 16:42:26.175956 | instance | ok: "docs"
2026-02-27 16:42:26.194300 | 
2026-02-27 16:42:26.194503 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-27 16:42:26.380143 | instance | changed: "logs"
2026-02-27 16:42:26.527384 | instance | changed: "artifacts"
2026-02-27 16:42:26.674716 | instance | changed: "docs"
2026-02-27 16:42:27.072174 | 
2026-02-27 16:42:27.072287 | PLAY RECAP
2026-02-27 16:42:27.072347 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-27 16:42:27.072388 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 16:42:27.072417 | 
2026-02-27 16:42:27.185234 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-27 16:42:27.195462 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-27 16:42:27.805631 | 
2026-02-27 16:42:27.805754 | PLAY [all]
2026-02-27 16:42:27.817199 | 
2026-02-27 16:42:27.817272 | TASK [setup-uv : Extract archive]
2026-02-27 16:42:38.802887 | instance | ERROR
2026-02-27 16:42:38.803314 | instance | {
2026-02-27 16:42:38.803366 | instance |   "msg": "Failure downloading https://github.com/astral-sh/uv/releases/download/0.8.13/uv-x86_64-unknown-linux-gnu.tar.gz, Connection failure: The read operation timed out"
2026-02-27 16:42:38.803408 | instance | }
failure
2026-02-27 16:42:38.805012 | 
2026-02-27 16:42:38.805058 | PLAY RECAP
2026-02-27 16:42:38.805106 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-27 16:42:38.805130 | 
2026-02-27 16:42:38.966142 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-02-27 16:42:38.979484 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-27 16:42:39.624819 | 
2026-02-27 16:42:39.625886 | PLAY [all]
2026-02-27 16:42:39.639489 | 
2026-02-27 16:42:39.639573 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-27 16:42:39.687884 | instance | skipping: Conditional result was False
2026-02-27 16:42:39.698604 | 
2026-02-27 16:42:39.698699 | TASK [fetch-output : Set log path for single node]
2026-02-27 16:42:39.742122 | instance | ok
2026-02-27 16:42:39.768673 | 
2026-02-27 16:42:39.768772 | LOOP [fetch-output : Ensure local output dirs]
2026-02-27 16:42:40.211126 | instance -> localhost | ok: "/var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/work/logs"
2026-02-27 16:42:40.444561 | instance -> localhost | changed: "/var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/work/artifacts"
2026-02-27 16:42:40.706823 | instance -> localhost | changed: "/var/lib/zuul/builds/a9406a04c1454077a0dfd72b0bca0a02/work/docs"
2026-02-27 16:42:40.726144 | 
2026-02-27 16:42:40.726285 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-27 16:42:41.427412 | instance | changed: .d..t...... ./
2026-02-27 16:42:41.427639 | instance | changed: All items complete
2026-02-27 16:42:41.427681 | 
2026-02-27 16:42:41.909081 | instance | changed: .d..t...... ./
2026-02-27 16:42:42.391625 | instance | changed: .d..t...... ./
2026-02-27 16:42:42.415157 | 
2026-02-27 16:42:42.415289 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-27 16:42:43.099844 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.009626
2026-02-27 16:42:43.377931 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010116
2026-02-27 16:42:43.400877 | 
2026-02-27 16:42:43.401801 | PLAY [all]
2026-02-27 16:42:43.413354 | 
2026-02-27 16:42:43.413931 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-27 16:42:43.860897 | instance | changed
2026-02-27 16:42:43.866735 | 
2026-02-27 16:42:43.866787 | PLAY RECAP
2026-02-27 16:42:43.866838 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-27 16:42:43.866864 | 
2026-02-27 16:42:44.050005 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-27 16:42:44.084912 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-27 16:42:44.661305 | 
2026-02-27 16:42:44.746274 | PLAY [localhost]
2026-02-27 16:42:44.760260 | 
2026-02-27 16:42:44.760335 | TASK [Generate Zuul manifest]
2026-02-27 16:42:44.780741 | localhost | ok
2026-02-27 16:42:44.796272 | 
2026-02-27 16:42:44.898006 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-27 16:42:45.975753 | localhost | changed
2026-02-27 16:42:45.987254 | 
2026-02-27 16:42:45.987342 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-27 16:42:46.478279 | localhost | ok
2026-02-27 16:42:46.486756 | 
2026-02-27 16:42:46.486822 | TASK [Upload logs]
2026-02-27 16:42:46.505110 | localhost | ok
2026-02-27 16:42:46.601184 | 
2026-02-27 16:42:46.601300 | TASK [Set zuul-log-path fact]
2026-02-27 16:42:46.621400 | localhost | ok
2026-02-27 16:42:46.634638 | 
2026-02-27 16:42:46.634715 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 16:42:46.665570 | localhost | ok
2026-02-27 16:42:46.675147 | 
2026-02-27 16:42:46.675214 | TASK [upload-logs : Create log directories]
2026-02-27 16:42:47.284618 | localhost | changed
2026-02-27 16:42:47.290959 | 
2026-02-27 16:42:47.291029 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-27 16:42:47.790861 | localhost -> localhost | ok: Runtime: 0:00:00.016152
2026-02-27 16:42:47.797168 | 
2026-02-27 16:42:47.797255 | TASK [upload-logs : Upload logs to log server]
2026-02-27 16:42:48.509025 | localhost | Output suppressed because no_log was given
2026-02-27 16:42:48.611530 | 
2026-02-27 16:42:48.611660 | LOOP [upload-logs : Compress console log and json output]
2026-02-27 16:42:48.660353 | localhost | skipping: Conditional result was False
2026-02-27 16:42:48.668157 | localhost | skipping: Conditional result was False
2026-02-27 16:42:48.685604 | 
2026-02-27 16:42:48.824906 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-27 16:42:48.882034 | localhost | skipping: Conditional result was False
2026-02-27 16:42:48.882605 | 
2026-02-27 16:42:48.895319 | localhost | skipping: Conditional result was False
2026-02-27 16:42:48.917609 | 
2026-02-27 16:42:48.917746 | LOOP [upload-logs : Upload console log and json output]