2026-02-27 16:41:28.338350 | Job console starting 2026-02-27 16:41:28.395033 | Updating git repos 2026-02-27 16:41:28.474966 | Cloning repos into workspace 2026-02-27 16:41:28.812256 | Restoring repo states 2026-02-27 16:41:28.843434 | Merging changes 2026-02-27 16:41:30.614811 | Checking out repos 2026-02-27 16:41:31.730087 | Preparing playbooks 2026-02-27 16:41:35.795367 | Running Ansible setup 2026-02-27 16:41:39.231349 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-27 16:41:39.881569 | 2026-02-27 16:41:39.881723 | PLAY [localhost] 2026-02-27 16:41:39.899031 | 2026-02-27 16:41:39.899251 | TASK [Gathering Facts] 2026-02-27 16:41:40.844141 | localhost | ok 2026-02-27 16:41:40.852401 | 2026-02-27 16:41:40.852478 | TASK [Setup log path fact] 2026-02-27 16:41:40.882370 | localhost | ok 2026-02-27 16:41:40.962449 | 2026-02-27 16:41:40.962536 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-27 16:41:40.993530 | localhost | ok 2026-02-27 16:41:41.001555 | 2026-02-27 16:41:41.001663 | TASK [emit-job-header : Print job information] 2026-02-27 16:41:41.043265 | # Job Information 2026-02-27 16:41:41.043493 | Ansible Version: 2.16.16 2026-02-27 16:41:41.043541 | Job: atmosphere-molecule-aio-openvswitch 2026-02-27 16:41:41.043575 | Pipeline: check 2026-02-27 16:41:41.043606 | Executor: 0a8996d2b663 2026-02-27 16:41:41.043636 | Triggered by: https://github.com/vexxhost/atmosphere/pull/3590 2026-02-27 16:41:41.043671 | Event ID: 12d62860-13fb-11f1-9a3b-86ec55b09b8c 2026-02-27 16:41:41.047858 | 2026-02-27 16:41:41.047956 | LOOP [emit-job-header : Print node information] 2026-02-27 16:41:41.147940 | localhost | ok: 2026-02-27 16:41:41.237278 | localhost | # Node Information 2026-02-27 16:41:41.237543 | localhost | Inventory Hostname: instance 2026-02-27 16:41:41.237604 | localhost | Hostname: np0000157328 2026-02-27 16:41:41.237660 | localhost | Username: zuul 2026-02-27 16:41:41.237730 | localhost | Distro: Ubuntu 22.04 2026-02-27 16:41:41.237780 | localhost | Provider: yul1 2026-02-27 16:41:41.237825 | localhost | Region: ca-ymq-1 2026-02-27 16:41:41.237869 | localhost | Label: ubuntu-jammy-16 2026-02-27 16:41:41.237909 | localhost | Product Name: OpenStack Nova 2026-02-27 16:41:41.237951 | localhost | Interface IP: 199.204.45.156 2026-02-27 16:41:41.251214 | 2026-02-27 16:41:41.251681 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-27 16:41:41.694682 | localhost -> localhost | changed 2026-02-27 16:41:41.702961 | 2026-02-27 16:41:41.703053 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-27 16:41:42.591586 | localhost -> localhost | changed 2026-02-27 16:41:42.599678 | 2026-02-27 16:41:42.599734 | PLAY [all] 2026-02-27 16:41:42.607578 | 2026-02-27 16:41:42.607642 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-27 16:41:42.956796 | instance -> localhost | ok 2026-02-27 16:41:42.962931 | 2026-02-27 16:41:42.962997 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-27 16:41:42.992995 | instance | ok 2026-02-27 16:41:43.007304 | instance | included: /var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-27 16:41:43.013294 | 2026-02-27 16:41:43.013354 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-27 16:41:43.976932 | instance -> localhost | Generating public/private rsa key pair. 2026-02-27 16:41:43.977108 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/work/67defb22cf1346a786405cee503dae02_id_rsa 2026-02-27 16:41:43.977138 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/work/67defb22cf1346a786405cee503dae02_id_rsa.pub 2026-02-27 16:41:43.977160 | instance -> localhost | The key fingerprint is: 2026-02-27 16:41:43.977181 | instance -> localhost | SHA256:Lwto/D/b91hPivKto2lNXvHjqrrDbCPlw4bOodYZSa4 zuul-build-sshkey 2026-02-27 16:41:43.977213 | instance -> localhost | The key's randomart image is: 2026-02-27 16:41:43.977235 | instance -> localhost | +---[RSA 3072]----+ 2026-02-27 16:41:43.977262 | instance -> localhost | | | 2026-02-27 16:41:43.977283 | instance -> localhost | | | 2026-02-27 16:41:43.977303 | instance -> localhost | | | 2026-02-27 16:41:43.977322 | instance -> localhost | | . . | 2026-02-27 16:41:43.977342 | instance -> localhost | | o S o | 2026-02-27 16:41:43.977362 | instance -> localhost | | . . + o . ...| 2026-02-27 16:41:43.977381 | instance -> localhost | | + +.@ .+ .o o| 2026-02-27 16:41:43.977400 | instance -> localhost | | . Eo*o#o.== = | 2026-02-27 16:41:43.977422 | instance -> localhost | | ..o+B*X**== .| 2026-02-27 16:41:43.977442 | instance -> localhost | +----[SHA256]-----+ 2026-02-27 16:41:43.977487 | instance -> localhost | ok: Runtime: 0:00:00.571287 2026-02-27 16:41:43.983308 | 2026-02-27 16:41:43.983372 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-27 16:41:44.016025 | instance | ok 2026-02-27 16:41:44.024430 | instance | included: /var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-27 16:41:44.031924 | 2026-02-27 16:41:44.031983 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-27 16:41:44.056219 | instance | skipping: Conditional result was False 2026-02-27 16:41:44.066092 | 2026-02-27 16:41:44.066156 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-27 16:41:44.554471 | instance | changed 2026-02-27 16:41:44.561284 | 2026-02-27 16:41:44.561377 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-27 16:41:44.758269 | instance | ok 2026-02-27 16:41:44.763891 | 2026-02-27 16:41:44.763954 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-27 16:41:45.234430 | instance | changed 2026-02-27 16:41:45.239975 | 2026-02-27 16:41:45.240041 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-27 16:41:45.709439 | instance | changed 2026-02-27 16:41:45.715893 | 2026-02-27 16:41:45.715956 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-27 16:41:45.740307 | instance | skipping: Conditional result was False 2026-02-27 16:41:45.751346 | 2026-02-27 16:41:45.751448 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-27 16:41:46.090128 | instance -> localhost | changed 2026-02-27 16:41:46.135484 | 2026-02-27 16:41:46.135613 | TASK [add-build-sshkey : Add back temp key] 2026-02-27 16:41:46.450381 | instance -> localhost | Identity added: /var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/work/67defb22cf1346a786405cee503dae02_id_rsa (zuul-build-sshkey) 2026-02-27 16:41:46.450622 | instance -> localhost | ok: Runtime: 0:00:00.014394 2026-02-27 16:41:46.456743 | 2026-02-27 16:41:46.456808 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-27 16:41:46.757364 | instance | ok 2026-02-27 16:41:46.763810 | 2026-02-27 16:41:46.763884 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-27 16:41:46.789219 | instance | skipping: Conditional result was False 2026-02-27 16:41:46.804754 | 2026-02-27 16:41:46.804818 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-27 16:41:47.098990 | instance | ok 2026-02-27 16:41:47.104944 | 2026-02-27 16:41:47.105037 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-27 16:41:48.652981 | instance | Output suppressed because no_log was given 2026-02-27 16:41:48.662171 | 2026-02-27 16:41:48.662240 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-27 16:41:48.852588 | instance | ok: "logs" 2026-02-27 16:41:48.852888 | instance | ok: All items complete 2026-02-27 16:41:48.852924 | 2026-02-27 16:41:49.015561 | instance | ok: "artifacts" 2026-02-27 16:41:49.168084 | instance | ok: "docs" 2026-02-27 16:41:49.186267 | 2026-02-27 16:41:49.186447 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-27 16:41:49.388666 | instance | changed: "logs" 2026-02-27 16:41:49.559651 | instance | changed: "artifacts" 2026-02-27 16:41:49.724898 | instance | changed: "docs" 2026-02-27 16:41:49.738982 | 2026-02-27 16:41:49.739138 | PLAY RECAP 2026-02-27 16:41:49.739203 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-02-27 16:41:49.739243 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-27 16:41:49.739270 | 2026-02-27 16:41:49.877510 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-02-27 16:41:49.888544 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-27 16:41:50.485654 | 2026-02-27 16:41:50.485814 | PLAY [all] 2026-02-27 16:41:50.498949 | 2026-02-27 16:41:50.499073 | TASK [setup-uv : Extract archive] 2026-02-27 16:42:01.215243 | instance | ERROR 2026-02-27 16:42:01.215539 | instance | { 2026-02-27 16:42:01.215596 | 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:01.215642 | instance | } failure 2026-02-27 16:42:01.217283 | 2026-02-27 16:42:01.217343 | PLAY RECAP 2026-02-27 16:42:01.217486 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-02-27 16:42:01.217531 | 2026-02-27 16:42:01.335448 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-02-27 16:42:01.343479 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-27 16:42:01.997720 | 2026-02-27 16:42:01.997911 | PLAY [all] 2026-02-27 16:42:02.009988 | 2026-02-27 16:42:02.010159 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-27 16:42:02.054730 | instance | skipping: Conditional result was False 2026-02-27 16:42:02.061918 | 2026-02-27 16:42:02.062042 | TASK [fetch-output : Set log path for single node] 2026-02-27 16:42:02.101246 | instance | ok 2026-02-27 16:42:02.106043 | 2026-02-27 16:42:02.106126 | LOOP [fetch-output : Ensure local output dirs] 2026-02-27 16:42:02.491499 | instance -> localhost | ok: "/var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/work/logs" 2026-02-27 16:42:02.691973 | instance -> localhost | changed: "/var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/work/artifacts" 2026-02-27 16:42:02.882125 | instance -> localhost | changed: "/var/lib/zuul/builds/67defb22cf1346a786405cee503dae02/work/docs" 2026-02-27 16:42:02.896352 | 2026-02-27 16:42:02.896545 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-27 16:42:03.512907 | instance | ok 2026-02-27 16:42:03.513177 | instance | changed: All items complete 2026-02-27 16:42:03.513216 | 2026-02-27 16:42:03.964036 | instance | changed: .d..t...... ./ 2026-02-27 16:42:04.420823 | instance | changed: .d..t...... ./ 2026-02-27 16:42:04.448999 | 2026-02-27 16:42:04.467470 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-27 16:42:04.871755 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.008178 2026-02-27 16:42:05.092536 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.006809 2026-02-27 16:42:05.106936 | 2026-02-27 16:42:05.107052 | PLAY [all] 2026-02-27 16:42:05.113515 | 2026-02-27 16:42:05.113580 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-27 16:42:05.545017 | instance | changed 2026-02-27 16:42:05.550324 | 2026-02-27 16:42:05.579873 | PLAY RECAP 2026-02-27 16:42:05.580040 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-27 16:42:05.580099 | 2026-02-27 16:42:05.716114 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-02-27 16:42:05.731415 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-27 16:42:06.394244 | 2026-02-27 16:42:06.394374 | PLAY [localhost] 2026-02-27 16:42:06.404218 | 2026-02-27 16:42:06.404298 | TASK [Generate Zuul manifest] 2026-02-27 16:42:06.426903 | localhost | ok 2026-02-27 16:42:06.444788 | 2026-02-27 16:42:06.444984 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-27 16:42:06.917187 | localhost | changed 2026-02-27 16:42:06.929778 | 2026-02-27 16:42:06.929861 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-27 16:42:06.957924 | localhost | ok 2026-02-27 16:42:06.965887 | 2026-02-27 16:42:06.965987 | TASK [Upload logs] 2026-02-27 16:42:06.986850 | localhost | ok 2026-02-27 16:42:07.107777 | 2026-02-27 16:42:07.107951 | TASK [Set zuul-log-path fact] 2026-02-27 16:42:07.130922 | localhost | ok 2026-02-27 16:42:07.146278 | 2026-02-27 16:42:07.146366 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-27 16:42:07.187358 | localhost | ok 2026-02-27 16:42:07.199839 | 2026-02-27 16:42:07.199954 | TASK [upload-logs : Create log directories] 2026-02-27 16:42:07.577151 | localhost | changed 2026-02-27 16:42:07.581905 | 2026-02-27 16:42:07.581978 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-27 16:42:08.069734 | localhost -> localhost | ok: Runtime: 0:00:00.067541 2026-02-27 16:42:08.079757 | 2026-02-27 16:42:08.079867 | TASK [upload-logs : Upload logs to log server] 2026-02-27 16:42:08.504331 | localhost | Output suppressed because no_log was given 2026-02-27 16:42:08.535586 | 2026-02-27 16:42:08.535686 | LOOP [upload-logs : Compress console log and json output] 2026-02-27 16:42:08.585660 | localhost | skipping: Conditional result was False 2026-02-27 16:42:08.592432 | localhost | skipping: Conditional result was False 2026-02-27 16:42:08.609264 | 2026-02-27 16:42:08.609452 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-27 16:42:08.658528 | localhost | skipping: Conditional result was False 2026-02-27 16:42:08.659118 | 2026-02-27 16:42:08.663576 | localhost | skipping: Conditional result was False 2026-02-27 16:42:08.678529 | 2026-02-27 16:42:08.678708 | LOOP [upload-logs : Upload console log and json output]