2026-07-09 16:32:39.728478 | Job console starting
2026-07-09 16:32:39.746446 | Updating git repos
2026-07-09 16:32:40.963057 | Cloning repos into workspace
2026-07-09 16:33:28.999050 | Restoring repo states
2026-07-09 16:33:37.658526 | Merging changes
2026-07-09 16:33:44.504530 | Checking out repos
2026-07-09 16:33:53.469159 | Preparing playbooks
2026-07-09 16:34:01.464840 | Running Ansible setup
2026-07-09 16:34:05.892393 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-09 16:34:06.680362 | 
2026-07-09 16:34:06.680578 | PLAY [localhost]
2026-07-09 16:34:06.691167 | 
2026-07-09 16:34:06.691493 | TASK [Gathering Facts]
2026-07-09 16:34:08.322947 | localhost | ok
2026-07-09 16:34:08.333827 | 
2026-07-09 16:34:09.021181 | TASK [Setup log path fact]
2026-07-09 16:34:09.053647 | localhost | ok
2026-07-09 16:34:09.068126 | 
2026-07-09 16:34:09.068270 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-09 16:34:09.099947 | localhost | ok
2026-07-09 16:34:09.109295 | 
2026-07-09 16:34:09.109422 | TASK [emit-job-header : Print job information]
2026-07-09 16:34:09.171410 | # Job Information
2026-07-09 16:34:09.171656 | Ansible Version: 2.16.18
2026-07-09 16:34:09.171729 | Job: atmosphere-molecule-aio-ovn
2026-07-09 16:34:09.171778 | Pipeline: check
2026-07-09 16:34:09.171945 | Executor: 2d72f0692154
2026-07-09 16:34:09.172003 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4093
2026-07-09 16:34:09.172057 | Event ID: 42520530-7bb3-11f1-9707-0f66710dac21
2026-07-09 16:34:09.178411 | 
2026-07-09 16:34:09.178559 | LOOP [emit-job-header : Print node information]
2026-07-09 16:34:09.293037 | localhost | ok:
2026-07-09 16:34:09.293246 | localhost | # Node Information
2026-07-09 16:34:09.293277 | localhost | Inventory Hostname: instance
2026-07-09 16:34:09.293300 | localhost | Hostname: np0000184750
2026-07-09 16:34:09.293323 | localhost | Username: zuul
2026-07-09 16:34:09.293348 | localhost | Distro: Ubuntu 22.04
2026-07-09 16:34:09.293370 | localhost | Provider: yul1
2026-07-09 16:34:09.293391 | localhost | Region: ca-ymq-1
2026-07-09 16:34:09.293412 | localhost | Label: ubuntu-jammy-16
2026-07-09 16:34:09.293432 | localhost | Product Name: OpenStack Nova
2026-07-09 16:34:09.293453 | localhost | Interface IP: 199.19.213.59
2026-07-09 16:34:09.303675 | 
2026-07-09 16:34:09.303857 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-09 16:34:10.283124 | localhost -> localhost | changed
2026-07-09 16:34:10.291943 | 
2026-07-09 16:34:10.292136 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-09 16:34:11.751830 | localhost -> localhost | changed
2026-07-09 16:34:11.758983 | 
2026-07-09 16:34:11.759066 | PLAY [all]
2026-07-09 16:34:11.767284 | 
2026-07-09 16:34:11.767522 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-09 16:34:12.088199 | instance -> localhost | ok
2026-07-09 16:34:12.099752 | 
2026-07-09 16:34:12.099903 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-09 16:34:12.131289 | instance | ok
2026-07-09 16:34:12.151323 | instance | included: /var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-09 16:34:12.159528 | 
2026-07-09 16:34:12.159658 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-09 16:34:14.819668 | instance -> localhost | Generating public/private rsa key pair.
2026-07-09 16:34:14.819889 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/work/13f04d6bc6bf4dffbcd7c7d5911b89cc_id_rsa
2026-07-09 16:34:14.819979 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/work/13f04d6bc6bf4dffbcd7c7d5911b89cc_id_rsa.pub
2026-07-09 16:34:14.820020 | instance -> localhost | The key fingerprint is:
2026-07-09 16:34:14.820051 | instance -> localhost | SHA256:tRXXQtteVJmBUyf2O/7rTRPj18LwDLywQp0zQsNgPz0 zuul-build-sshkey
2026-07-09 16:34:14.820094 | instance -> localhost | The key's randomart image is:
2026-07-09 16:34:14.820124 | instance -> localhost | +---[RSA 3072]----+
2026-07-09 16:34:14.820162 | instance -> localhost | |      o     ..B+O|
2026-07-09 16:34:14.820192 | instance -> localhost | |     . + .   *.Oo|
2026-07-09 16:34:14.820220 | instance -> localhost | |        * E . o.+|
2026-07-09 16:34:14.820249 | instance -> localhost | |       . = *   .o|
2026-07-09 16:34:14.820276 | instance -> localhost | |        S B +  =.|
2026-07-09 16:34:14.820303 | instance -> localhost | |       . . = Bo =|
2026-07-09 16:34:14.820330 | instance -> localhost | |        . . . =++|
2026-07-09 16:34:14.820357 | instance -> localhost | |         .     o=|
2026-07-09 16:34:14.820388 | instance -> localhost | |              .o+|
2026-07-09 16:34:14.820416 | instance -> localhost | +----[SHA256]-----+
2026-07-09 16:34:14.820479 | instance -> localhost | ok: Runtime: 0:00:01.109948
2026-07-09 16:34:14.829427 | 
2026-07-09 16:34:14.829516 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-09 16:34:14.862686 | instance | ok
2026-07-09 16:34:14.878650 | instance | included: /var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-09 16:34:14.886237 | 
2026-07-09 16:34:14.886298 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-09 16:34:14.910764 | instance | skipping: Conditional result was False
2026-07-09 16:34:14.920779 | 
2026-07-09 16:34:14.920869 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-09 16:34:15.430701 | instance | changed
2026-07-09 16:34:15.437283 | 
2026-07-09 16:34:15.437350 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-09 16:34:15.690561 | instance | ok
2026-07-09 16:34:15.695721 | 
2026-07-09 16:34:15.695815 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-09 16:34:16.305507 | instance | changed
2026-07-09 16:34:16.431520 | 
2026-07-09 16:34:16.431712 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-09 16:34:16.901288 | instance | changed
2026-07-09 16:34:17.020747 | 
2026-07-09 16:34:17.020859 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-09 16:34:17.055480 | instance | skipping: Conditional result was False
2026-07-09 16:34:17.069440 | 
2026-07-09 16:34:17.069643 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-09 16:34:17.420495 | instance -> localhost | changed
2026-07-09 16:34:17.432751 | 
2026-07-09 16:34:17.432862 | TASK [add-build-sshkey : Add back temp key]
2026-07-09 16:34:18.941771 | instance -> localhost | Identity added: /var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/work/13f04d6bc6bf4dffbcd7c7d5911b89cc_id_rsa (zuul-build-sshkey)
2026-07-09 16:34:18.941974 | instance -> localhost | ok: Runtime: 0:00:00.016373
2026-07-09 16:34:18.947093 | 
2026-07-09 16:34:18.947165 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-09 16:34:19.223829 | instance | ok
2026-07-09 16:34:19.448266 | 
2026-07-09 16:34:19.448406 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-09 16:34:19.473508 | instance | skipping: Conditional result was False
2026-07-09 16:34:19.489193 | 
2026-07-09 16:34:19.489303 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-09 16:34:19.799494 | instance | ok
2026-07-09 16:34:19.805583 | 
2026-07-09 16:34:19.805658 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-09 16:34:23.989746 | instance | Output suppressed because no_log was given
2026-07-09 16:34:24.075771 | 
2026-07-09 16:34:24.075907 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-09 16:34:24.295372 | instance | ok: "logs"
2026-07-09 16:34:24.295586 | instance | ok: All items complete
2026-07-09 16:34:24.295616 | 
2026-07-09 16:34:24.454118 | instance | ok: "artifacts"
2026-07-09 16:34:24.630384 | instance | ok: "docs"
2026-07-09 16:34:24.646907 | 
2026-07-09 16:34:24.647078 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-09 16:34:24.845178 | instance | changed: "logs"
2026-07-09 16:34:25.020147 | instance | changed: "artifacts"
2026-07-09 16:34:25.185504 | instance | changed: "docs"
2026-07-09 16:34:25.200971 | 
2026-07-09 16:34:25.201372 | PLAY RECAP
2026-07-09 16:34:25.201464 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-09 16:34:25.201513 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-09 16:34:25.201553 | 
2026-07-09 16:34:25.403258 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-09 16:34:25.423252 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-09 16:34:26.052069 | 
2026-07-09 16:34:26.052204 | PLAY [all]
2026-07-09 16:34:26.070123 | 
2026-07-09 16:34:26.070321 | TASK [setup-uv : Extract archive]
2026-07-09 16:34:37.146021 | instance | ERROR
2026-07-09 16:34:37.146484 | instance | {
2026-07-09 16:34:37.146569 | 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-07-09 16:34:37.146637 | instance | }
failure
2026-07-09 16:34:37.151624 | 
2026-07-09 16:34:37.151756 | PLAY RECAP
2026-07-09 16:34:37.151825 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-07-09 16:34:37.151862 | 
2026-07-09 16:34:37.462797 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-09 16:34:37.479281 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-09 16:34:38.175135 | 
2026-07-09 16:34:39.796638 | PLAY [all]
2026-07-09 16:34:39.825093 | 
2026-07-09 16:34:39.825421 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-09 16:34:39.874743 | instance | skipping: Conditional result was False
2026-07-09 16:34:39.887054 | 
2026-07-09 16:34:39.887205 | TASK [fetch-output : Set log path for single node]
2026-07-09 16:34:39.922932 | instance | ok
2026-07-09 16:34:39.930096 | 
2026-07-09 16:34:39.930217 | LOOP [fetch-output : Ensure local output dirs]
2026-07-09 16:34:40.346921 | instance -> localhost | ok: "/var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/work/logs"
2026-07-09 16:34:40.611520 | instance -> localhost | changed: "/var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/work/artifacts"
2026-07-09 16:34:40.827462 | instance -> localhost | changed: "/var/lib/zuul/builds/13f04d6bc6bf4dffbcd7c7d5911b89cc/work/docs"
2026-07-09 16:34:40.852114 | 
2026-07-09 16:34:40.852292 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-09 16:34:41.560794 | instance | changed: .d..t...... ./
2026-07-09 16:34:41.646513 | instance | changed: All items complete
2026-07-09 16:34:41.646717 | 
2026-07-09 16:34:42.082823 | instance | changed: .d..t...... ./
2026-07-09 16:34:42.572130 | instance | changed: .d..t...... ./
2026-07-09 16:34:42.593114 | 
2026-07-09 16:34:42.593762 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-09 16:34:43.188428 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.017328
2026-07-09 16:34:44.394083 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.007996
2026-07-09 16:34:44.422173 | 
2026-07-09 16:34:44.422376 | PLAY [all]
2026-07-09 16:34:44.433435 | 
2026-07-09 16:34:44.433591 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-09 16:34:44.884667 | instance | changed
2026-07-09 16:34:45.179719 | 
2026-07-09 16:34:45.179835 | PLAY RECAP
2026-07-09 16:34:45.179897 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-09 16:34:45.179928 | 
2026-07-09 16:34:45.418677 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-09 16:34:45.432269 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-09 16:34:46.130629 | 
2026-07-09 16:34:46.276107 | PLAY [localhost]
2026-07-09 16:34:46.291341 | 
2026-07-09 16:34:46.291446 | TASK [Generate Zuul manifest]
2026-07-09 16:34:46.311396 | localhost | ok
2026-07-09 16:34:46.331762 | 
2026-07-09 16:34:46.331845 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-09 16:34:46.991781 | localhost | changed
2026-07-09 16:34:47.005290 | 
2026-07-09 16:34:47.005440 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-09 16:34:47.038948 | localhost | ok
2026-07-09 16:34:47.046050 | 
2026-07-09 16:34:47.046169 | TASK [Upload logs]
2026-07-09 16:34:47.066413 | localhost | ok
2026-07-09 16:34:47.172091 | 
2026-07-09 16:34:47.172201 | TASK [Set zuul-log-path fact]
2026-07-09 16:34:47.191141 | localhost | ok
2026-07-09 16:34:47.205110 | 
2026-07-09 16:34:47.205183 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-09 16:34:47.235831 | localhost | ok
2026-07-09 16:34:47.245078 | 
2026-07-09 16:34:47.245170 | TASK [upload-logs : Create log directories]
2026-07-09 16:34:47.964615 | localhost | changed
2026-07-09 16:34:47.970535 | 
2026-07-09 16:34:47.970837 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-09 16:34:48.474149 | localhost -> localhost | ok: Runtime: 0:00:00.106698
2026-07-09 16:34:48.481429 | 
2026-07-09 16:34:48.481536 | TASK [upload-logs : Upload logs to log server]
2026-07-09 16:34:49.435694 | localhost | Output suppressed because no_log was given
2026-07-09 16:34:49.442837 | 
2026-07-09 16:34:49.442942 | LOOP [upload-logs : Compress console log and json output]
2026-07-09 16:34:49.492696 | localhost | skipping: Conditional result was False
2026-07-09 16:34:49.500103 | localhost | skipping: Conditional result was False
2026-07-09 16:34:49.514654 | 
2026-07-09 16:34:49.514839 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-09 16:34:49.559308 | localhost | skipping: Conditional result was False
2026-07-09 16:34:49.559665 | 
2026-07-09 16:34:49.563463 | localhost | skipping: Conditional result was False
2026-07-09 16:34:49.577176 | 
2026-07-09 16:34:49.577679 | LOOP [upload-logs : Upload console log and json output]