2026-08-12 17:22:51.323833 | Job console starting
2026-08-12 17:22:51.334668 | Updating git repos
2026-08-12 17:22:57.447271 | Cloning repos into workspace
2026-08-12 17:23:16.165569 | Restoring repo states
2026-08-12 17:23:29.991941 | Merging changes
2026-08-12 17:23:31.204779 | Checking out repos
2026-08-12 17:23:32.917220 | Preparing playbooks
2026-08-12 17:23:35.273382 | Running Ansible setup
2026-08-12 17:23:38.647194 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 17:23:39.338094 | 
2026-08-12 17:23:39.338236 | PLAY [localhost]
2026-08-12 17:23:39.346279 | 
2026-08-12 17:23:39.346361 | TASK [Gathering Facts]
2026-08-12 17:23:40.239338 | localhost | ok
2026-08-12 17:23:40.248020 | 
2026-08-12 17:23:40.248106 | TASK [Setup log path fact]
2026-08-12 17:23:40.267884 | localhost | ok
2026-08-12 17:23:40.283068 | 
2026-08-12 17:23:40.283135 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 17:23:40.312387 | localhost | ok
2026-08-12 17:23:40.323107 | 
2026-08-12 17:23:40.323182 | TASK [emit-job-header : Print job information]
2026-08-12 17:23:40.368747 | # Job Information
2026-08-12 17:23:40.368898 | Ansible Version: 2.16.18
2026-08-12 17:23:40.368943 | Job: atmosphere-molecule-keycloak
2026-08-12 17:23:40.368999 | Pipeline: check
2026-08-12 17:23:40.369030 | Executor: 2d72f0692154
2026-08-12 17:23:40.369059 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4089
2026-08-12 17:23:40.369090 | Event ID: ef8297a0-9671-11f1-80d3-25d23e1f0c08
2026-08-12 17:23:40.372394 | 
2026-08-12 17:23:40.372457 | LOOP [emit-job-header : Print node information]
2026-08-12 17:23:40.463393 | localhost | ok:
2026-08-12 17:23:40.463650 | localhost | # Node Information
2026-08-12 17:23:40.463691 | localhost | Inventory Hostname: instance
2026-08-12 17:23:40.463722 | localhost | Hostname: np0000189043
2026-08-12 17:23:40.463751 | localhost | Username: zuul
2026-08-12 17:23:40.463783 | localhost | Distro: Ubuntu 22.04
2026-08-12 17:23:40.463811 | localhost | Provider: yul1
2026-08-12 17:23:40.463838 | localhost | Region: ca-ymq-1
2026-08-12 17:23:40.463864 | localhost | Label: ubuntu-jammy
2026-08-12 17:23:40.463890 | localhost | Product Name: OpenStack Nova
2026-08-12 17:23:40.463916 | localhost | Interface IP: 199.19.213.179
2026-08-12 17:23:40.476786 | 
2026-08-12 17:23:40.476905 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-12 17:23:40.854817 | localhost -> localhost | changed
2026-08-12 17:23:40.861867 | 
2026-08-12 17:23:40.861963 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-12 17:23:41.692288 | localhost -> localhost | changed
2026-08-12 17:23:41.702718 | 
2026-08-12 17:23:41.702826 | PLAY [all]
2026-08-12 17:23:41.711633 | 
2026-08-12 17:23:41.711723 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-12 17:23:41.940868 | instance -> localhost | ok
2026-08-12 17:23:41.950319 | 
2026-08-12 17:23:41.950424 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-12 17:23:41.984880 | instance | ok
2026-08-12 17:23:42.000553 | instance | included: /var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-12 17:23:42.006066 | 
2026-08-12 17:23:42.006124 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-12 17:23:42.980655 | instance -> localhost | Generating public/private rsa key pair.
2026-08-12 17:23:42.980878 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/work/4b6d1089192042f287e2608f407053f9_id_rsa
2026-08-12 17:23:42.980918 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/work/4b6d1089192042f287e2608f407053f9_id_rsa.pub
2026-08-12 17:23:42.980950 | instance -> localhost | The key fingerprint is:
2026-08-12 17:23:42.981018 | instance -> localhost | SHA256:D9eWIzvH6EPVy66wOiukLSq47CgUhmgB7pO+UYtcqsw zuul-build-sshkey
2026-08-12 17:23:42.981066 | instance -> localhost | The key's randomart image is:
2026-08-12 17:23:42.981097 | instance -> localhost | +---[RSA 3072]----+
2026-08-12 17:23:42.981137 | instance -> localhost | |o                |
2026-08-12 17:23:42.981168 | instance -> localhost | |..               |
2026-08-12 17:23:42.981212 | instance -> localhost | |o..          .   |
2026-08-12 17:23:42.981240 | instance -> localhost | |++.        ....  |
2026-08-12 17:23:42.981268 | instance -> localhost | |o+.o    S o.=. . |
2026-08-12 17:23:42.981295 | instance -> localhost | |o.* . .  +.* .o  |
2026-08-12 17:23:42.981324 | instance -> localhost | |o* . +   .* o.   |
2026-08-12 17:23:42.981352 | instance -> localhost | |X o o o ...=  .  |
2026-08-12 17:23:42.981383 | instance -> localhost | |*E.. . .o+o...   |
2026-08-12 17:23:42.981411 | instance -> localhost | +----[SHA256]-----+
2026-08-12 17:23:42.981482 | instance -> localhost | ok: Runtime: 0:00:00.547315
2026-08-12 17:23:42.987920 | 
2026-08-12 17:23:42.987997 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-12 17:23:43.021265 | instance | ok
2026-08-12 17:23:43.031085 | instance | included: /var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-12 17:23:43.039267 | 
2026-08-12 17:23:43.039335 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-12 17:23:43.064715 | instance | skipping: Conditional result was False
2026-08-12 17:23:43.075063 | 
2026-08-12 17:23:43.075128 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-12 17:23:43.529752 | instance | changed
2026-08-12 17:23:43.534661 | 
2026-08-12 17:23:43.534723 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-12 17:23:43.721317 | instance | ok
2026-08-12 17:23:43.727807 | 
2026-08-12 17:23:43.727871 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-12 17:23:44.185721 | instance | changed
2026-08-12 17:23:44.190615 | 
2026-08-12 17:23:44.190681 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-12 17:23:44.651147 | instance | changed
2026-08-12 17:23:44.658241 | 
2026-08-12 17:23:44.658310 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-12 17:23:44.683246 | instance | skipping: Conditional result was False
2026-08-12 17:23:44.692834 | 
2026-08-12 17:23:44.692926 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-12 17:23:45.093753 | instance -> localhost | changed
2026-08-12 17:23:45.107427 | 
2026-08-12 17:23:45.107503 | TASK [add-build-sshkey : Add back temp key]
2026-08-12 17:23:45.405038 | instance -> localhost | Identity added: /var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/work/4b6d1089192042f287e2608f407053f9_id_rsa (zuul-build-sshkey)
2026-08-12 17:23:45.405273 | instance -> localhost | ok: Runtime: 0:00:00.015556
2026-08-12 17:23:45.411273 | 
2026-08-12 17:23:45.411334 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-12 17:23:45.731132 | instance | ok
2026-08-12 17:23:45.735940 | 
2026-08-12 17:23:45.736004 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-12 17:23:45.761315 | instance | skipping: Conditional result was False
2026-08-12 17:23:45.778862 | 
2026-08-12 17:23:45.778930 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-12 17:23:46.144178 | instance | ok
2026-08-12 17:23:46.149635 | 
2026-08-12 17:23:46.149700 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-12 17:23:49.139975 | instance | Output suppressed because no_log was given
2026-08-12 17:23:49.148834 | 
2026-08-12 17:23:49.148901 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-12 17:23:49.347382 | instance | ok: "logs"
2026-08-12 17:23:49.351603 | instance | ok: All items complete
2026-08-12 17:23:49.351645 | 
2026-08-12 17:23:49.494870 | instance | ok: "artifacts"
2026-08-12 17:23:49.660496 | instance | ok: "docs"
2026-08-12 17:23:49.678770 | 
2026-08-12 17:23:49.678946 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-12 17:23:49.867068 | instance | changed: "logs"
2026-08-12 17:23:50.022184 | instance | changed: "artifacts"
2026-08-12 17:23:50.176150 | instance | changed: "docs"
2026-08-12 17:23:50.184997 | 
2026-08-12 17:23:50.185129 | PLAY RECAP
2026-08-12 17:23:50.185182 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-12 17:23:50.185213 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-12 17:23:50.185236 | 
2026-08-12 17:23:50.323653 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-12 17:23:50.326171 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-12 17:23:50.935657 | 
2026-08-12 17:23:50.935801 | PLAY [all]
2026-08-12 17:23:50.947024 | 
2026-08-12 17:23:50.947133 | TASK [setup-uv : Extract archive]
2026-08-12 17:23:51.791145 | instance | ERROR
2026-08-12 17:23:51.791421 | instance | {
2026-08-12 17:23:51.791460 | 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: Remote end closed connection without response"
2026-08-12 17:23:51.791490 | instance | }
failure
2026-08-12 17:23:51.792865 | 
2026-08-12 17:23:51.792912 | PLAY RECAP
2026-08-12 17:23:51.792960 | instance | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-12 17:23:51.792998 | 
2026-08-12 17:23:51.928089 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-08-12 17:23:51.934193 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 17:23:52.530120 | 
2026-08-12 17:23:52.530293 | PLAY [all]
2026-08-12 17:23:52.542782 | 
2026-08-12 17:23:52.542877 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-12 17:23:52.589377 | instance | skipping: Conditional result was False
2026-08-12 17:23:52.599206 | 
2026-08-12 17:23:52.599384 | TASK [fetch-output : Set log path for single node]
2026-08-12 17:23:52.643646 | instance | ok
2026-08-12 17:23:52.651293 | 
2026-08-12 17:23:52.651381 | LOOP [fetch-output : Ensure local output dirs]
2026-08-12 17:23:53.049991 | instance -> localhost | ok: "/var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/work/logs"
2026-08-12 17:23:53.329248 | instance -> localhost | changed: "/var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/work/artifacts"
2026-08-12 17:23:53.535344 | instance -> localhost | changed: "/var/lib/zuul/builds/4b6d1089192042f287e2608f407053f9/work/docs"
2026-08-12 17:23:53.552703 | 
2026-08-12 17:23:53.552888 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-12 17:23:54.196106 | instance | changed: .d..t...... ./
2026-08-12 17:23:54.196385 | instance | changed: All items complete
2026-08-12 17:23:54.196425 | 
2026-08-12 17:23:54.645294 | instance | changed: .d..t...... ./
2026-08-12 17:23:55.110854 | instance | changed: .d..t...... ./
2026-08-12 17:23:55.131649 | 
2026-08-12 17:23:55.131787 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-12 17:23:55.571873 | instance -> localhost | ok: Item: artifacts Runtime: 0:00:00.010127
2026-08-12 17:23:55.865016 | instance -> localhost | ok: Item: docs Runtime: 0:00:00.010873
2026-08-12 17:23:55.884397 | 
2026-08-12 17:23:55.884473 | PLAY [all]
2026-08-12 17:23:55.890863 | 
2026-08-12 17:23:55.891092 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-12 17:23:56.313310 | instance | changed
2026-08-12 17:23:56.323157 | 
2026-08-12 17:23:56.323235 | PLAY RECAP
2026-08-12 17:23:56.323305 | instance | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-12 17:23:56.323353 | 
2026-08-12 17:23:56.505672 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-12 17:23:56.508549 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-12 17:23:57.115807 | 
2026-08-12 17:23:57.116226 | PLAY [localhost]
2026-08-12 17:23:57.126973 | 
2026-08-12 17:23:57.127050 | TASK [Generate Zuul manifest]
2026-08-12 17:23:57.160157 | localhost | ok
2026-08-12 17:23:57.176171 | 
2026-08-12 17:23:57.176263 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-12 17:23:57.558011 | localhost | changed
2026-08-12 17:23:57.582793 | 
2026-08-12 17:23:57.582986 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-12 17:23:57.617060 | localhost | ok
2026-08-12 17:23:57.626388 | 
2026-08-12 17:23:57.626459 | TASK [Upload logs]
2026-08-12 17:23:57.648364 | localhost | ok
2026-08-12 17:23:57.714794 | 
2026-08-12 17:23:57.714938 | TASK [Set zuul-log-path fact]
2026-08-12 17:23:57.735247 | localhost | ok
2026-08-12 17:23:57.748348 | 
2026-08-12 17:23:57.748416 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-12 17:23:57.777577 | localhost | ok
2026-08-12 17:23:57.786817 | 
2026-08-12 17:23:57.786887 | TASK [upload-logs : Create log directories]
2026-08-12 17:23:58.182187 | localhost | changed
2026-08-12 17:23:58.224693 | 
2026-08-12 17:23:58.224845 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-12 17:23:58.590135 | localhost -> localhost | ok: Runtime: 0:00:00.005624
2026-08-12 17:23:58.596394 | 
2026-08-12 17:23:58.596464 | TASK [upload-logs : Upload logs to log server]
2026-08-12 17:23:59.049563 | localhost | Output suppressed because no_log was given
2026-08-12 17:23:59.053280 | 
2026-08-12 17:23:59.053347 | LOOP [upload-logs : Compress console log and json output]
2026-08-12 17:23:59.100515 | localhost | skipping: Conditional result was False
2026-08-12 17:23:59.109551 | localhost | skipping: Conditional result was False
2026-08-12 17:23:59.125161 | 
2026-08-12 17:23:59.125277 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-12 17:23:59.170229 | localhost | skipping: Conditional result was False
2026-08-12 17:23:59.170678 | 
2026-08-12 17:23:59.173785 | localhost | skipping: Conditional result was False
2026-08-12 17:23:59.191530 | 
2026-08-12 17:23:59.191716 | LOOP [upload-logs : Upload console log and json output]