2026-07-09 03:22:48.578219 | Job console starting
2026-07-09 03:22:48.591664 | Updating git repos
2026-07-09 03:22:49.020730 | Cloning repos into workspace
2026-07-09 03:23:00.245439 | Restoring repo states
2026-07-09 03:23:11.124847 | Merging changes
2026-07-09 03:23:12.044995 | Checking out repos
2026-07-09 03:23:13.515800 | Preparing playbooks
2026-07-09 03:23:16.527913 | Running Ansible setup
2026-07-09 03:23:19.983410 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-09 03:23:20.586837 | 
2026-07-09 03:23:20.586974 | PLAY [localhost]
2026-07-09 03:23:20.594706 | 
2026-07-09 03:23:20.594778 | TASK [Gathering Facts]
2026-07-09 03:23:21.476922 | localhost | ok
2026-07-09 03:23:21.487876 | 
2026-07-09 03:23:21.487950 | TASK [Setup log path fact]
2026-07-09 03:23:21.505676 | localhost | ok
2026-07-09 03:23:21.520744 | 
2026-07-09 03:23:21.520808 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-09 03:23:21.550813 | localhost | ok
2026-07-09 03:23:21.559311 | 
2026-07-09 03:23:21.559378 | TASK [emit-job-header : Print job information]
2026-07-09 03:23:21.601921 | # Job Information
2026-07-09 03:23:21.602168 | Ansible Version: 2.16.18
2026-07-09 03:23:21.602237 | Job: atmosphere-molecule-keycloak
2026-07-09 03:23:21.602285 | Pipeline: check
2026-07-09 03:23:21.602330 | Executor: 2d72f0692154
2026-07-09 03:23:21.602410 | Triggered by: https://github.com/vexxhost/atmosphere/pull/4128
2026-07-09 03:23:21.602514 | Event ID: 4b221240-7b45-11f1-8014-1b6e2f2c5d9c
2026-07-09 03:23:21.606060 | 
2026-07-09 03:23:21.606126 | LOOP [emit-job-header : Print node information]
2026-07-09 03:23:21.699252 | localhost | ok:
2026-07-09 03:23:21.910237 | localhost | # Node Information
2026-07-09 03:23:21.910380 | localhost | Inventory Hostname: instance
2026-07-09 03:23:21.910416 | localhost | Hostname: np0000184552
2026-07-09 03:23:21.910461 | localhost | Username: zuul
2026-07-09 03:23:21.910559 | localhost | Distro: Ubuntu 22.04
2026-07-09 03:23:21.910599 | localhost | Provider: yul1
2026-07-09 03:23:21.910627 | localhost | Region: ca-ymq-1
2026-07-09 03:23:21.910654 | localhost | Label: ubuntu-jammy
2026-07-09 03:23:21.910681 | localhost | Product Name: OpenStack Nova
2026-07-09 03:23:21.910707 | localhost | Interface IP: 199.19.213.250
2026-07-09 03:23:21.924126 | 
2026-07-09 03:23:21.924295 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-09 03:23:22.324231 | localhost -> localhost | changed
2026-07-09 03:23:22.334112 | 
2026-07-09 03:23:22.334229 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-09 03:23:23.121633 | localhost -> localhost | changed
2026-07-09 03:23:23.132126 | 
2026-07-09 03:23:23.132181 | PLAY [all]
2026-07-09 03:23:23.139469 | 
2026-07-09 03:23:23.139533 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-09 03:23:23.356365 | instance -> localhost | ok
2026-07-09 03:23:23.368927 | 
2026-07-09 03:23:23.369126 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-09 03:23:23.408609 | instance | ok
2026-07-09 03:23:23.426749 | instance | included: /var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-09 03:23:23.435246 | 
2026-07-09 03:23:23.435369 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-09 03:23:24.497237 | instance -> localhost | Generating public/private rsa key pair.
2026-07-09 03:23:24.497396 | instance -> localhost | Your identification has been saved in /var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/c6f8cc54526847cfb0dba763fd45c561_id_rsa
2026-07-09 03:23:24.497425 | instance -> localhost | Your public key has been saved in /var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/c6f8cc54526847cfb0dba763fd45c561_id_rsa.pub
2026-07-09 03:23:24.497448 | instance -> localhost | The key fingerprint is:
2026-07-09 03:23:24.497470 | instance -> localhost | SHA256:FypO+jpt2NGfx2hu1Wg9S1S/k7nGJCwdEUXp99Z4jd0 zuul-build-sshkey
2026-07-09 03:23:24.497501 | instance -> localhost | The key's randomart image is:
2026-07-09 03:23:24.497523 | instance -> localhost | +---[RSA 3072]----+
2026-07-09 03:23:24.497549 | instance -> localhost | |             .+o.|
2026-07-09 03:23:24.497571 | instance -> localhost | |             . ..|
2026-07-09 03:23:24.497592 | instance -> localhost | |          .   o..|
2026-07-09 03:23:24.497612 | instance -> localhost | |         . . ...o|
2026-07-09 03:23:24.497631 | instance -> localhost | |      o.S . o=.+X|
2026-07-09 03:23:24.497651 | instance -> localhost | |     +.... .++B*E|
2026-07-09 03:23:24.497671 | instance -> localhost | |    .+.. . *..+=o|
2026-07-09 03:23:24.497691 | instance -> localhost | |    o.+   * o .+ |
2026-07-09 03:23:24.497713 | instance -> localhost | |    .+.  +..  .  |
2026-07-09 03:23:24.497734 | instance -> localhost | +----[SHA256]-----+
2026-07-09 03:23:24.497781 | instance -> localhost | ok: Runtime: 0:00:00.650304
2026-07-09 03:23:24.502765 | 
2026-07-09 03:23:24.502825 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-09 03:23:24.535699 | instance | ok
2026-07-09 03:23:24.546931 | instance | included: /var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-09 03:23:24.555359 | 
2026-07-09 03:23:24.555418 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-09 03:23:24.579357 | instance | skipping: Conditional result was False
2026-07-09 03:23:24.589918 | 
2026-07-09 03:23:24.590005 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-09 03:23:25.019497 | instance | changed
2026-07-09 03:23:25.026455 | 
2026-07-09 03:23:25.026544 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-09 03:23:25.205304 | instance | ok
2026-07-09 03:23:25.210467 | 
2026-07-09 03:23:25.210535 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-09 03:23:25.683232 | instance | changed
2026-07-09 03:23:25.689929 | 
2026-07-09 03:23:25.690025 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-09 03:23:26.131577 | instance | changed
2026-07-09 03:23:26.138354 | 
2026-07-09 03:23:26.138440 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-09 03:23:26.162898 | instance | skipping: Conditional result was False
2026-07-09 03:23:26.172136 | 
2026-07-09 03:23:26.172231 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-09 03:23:26.521226 | instance -> localhost | changed
2026-07-09 03:23:26.540038 | 
2026-07-09 03:23:26.540113 | TASK [add-build-sshkey : Add back temp key]
2026-07-09 03:23:26.806091 | instance -> localhost | Identity added: /var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/c6f8cc54526847cfb0dba763fd45c561_id_rsa (zuul-build-sshkey)
2026-07-09 03:23:26.806320 | instance -> localhost | ok: Runtime: 0:00:00.014292
2026-07-09 03:23:26.812896 | 
2026-07-09 03:23:26.813009 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-09 03:23:27.111730 | instance | ok
2026-07-09 03:23:27.118615 | 
2026-07-09 03:23:27.118710 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-09 03:23:27.143843 | instance | skipping: Conditional result was False
2026-07-09 03:23:27.191049 | 
2026-07-09 03:23:27.191198 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-09 03:23:27.468922 | instance | ok
2026-07-09 03:23:27.475580 | 
2026-07-09 03:23:27.475643 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-09 03:23:30.452219 | instance | Output suppressed because no_log was given
2026-07-09 03:23:30.463345 | 
2026-07-09 03:23:30.463412 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-09 03:23:30.661515 | instance | ok: "logs"
2026-07-09 03:23:30.661842 | instance | ok: All items complete
2026-07-09 03:23:30.661881 | 
2026-07-09 03:23:30.821807 | instance | ok: "artifacts"
2026-07-09 03:23:30.968003 | instance | ok: "docs"
2026-07-09 03:23:30.986753 | 
2026-07-09 03:23:30.986905 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-09 03:23:31.161177 | instance | changed: "logs"
2026-07-09 03:23:31.309750 | instance | changed: "artifacts"
2026-07-09 03:23:31.465974 | instance | changed: "docs"
2026-07-09 03:23:31.481508 | 
2026-07-09 03:23:31.481674 | PLAY RECAP
2026-07-09 03:23:31.481744 | instance | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-09 03:23:31.481801 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-09 03:23:31.481825 | 
2026-07-09 03:23:31.616311 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-09 03:23:31.618855 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-09 03:23:32.210976 | 
2026-07-09 03:23:32.211204 | PLAY [all]
2026-07-09 03:23:32.222981 | 
2026-07-09 03:23:32.223098 | TASK [setup-uv : Extract archive]
2026-07-09 03:23:34.362921 | instance | changed
2026-07-09 03:23:34.371535 | 
2026-07-09 03:23:34.371615 | TASK [setup-uv : Print version]
2026-07-09 03:23:34.702480 | instance | uv 0.8.13
2026-07-09 03:23:34.907564 | instance | ok: Runtime: 0:00:00.011828
2026-07-09 03:23:34.919032 | 
2026-07-09 03:23:34.919100 | TASK [Install Ansible collection siblings]
2026-07-09 03:27:47.409927 | instance | ERROR
2026-07-09 03:27:47.410204 | instance | {
2026-07-09 03:27:47.410248 | instance |   "msg": "Data could not be sent to remote host \"199.19.213.250\". Make sure this host can be reached over ssh: ssh: connect to host 199.19.213.250 port 22: No route to host\r\n",
2026-07-09 03:27:47.410285 | instance |   "unreachable": true
2026-07-09 03:27:47.410315 | instance | }
unreachable
2026-07-09 03:27:47.411786 | 
2026-07-09 03:27:47.411844 | PLAY RECAP
2026-07-09 03:27:47.411903 | instance | ok: 2 changed: 2 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-09 03:27:47.411936 | 
2026-07-09 03:27:47.536701 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-07-09 03:27:47.543827 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-09 03:27:48.170413 | 
2026-07-09 03:27:48.170535 | PLAY [all]
2026-07-09 03:27:48.181995 | 
2026-07-09 03:27:48.182074 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-09 03:27:48.217793 | instance | skipping: Conditional result was False
2026-07-09 03:27:48.227733 | 
2026-07-09 03:27:48.227810 | TASK [fetch-output : Set log path for single node]
2026-07-09 03:27:48.270326 | instance | ok
2026-07-09 03:27:48.277473 | 
2026-07-09 03:27:48.277543 | LOOP [fetch-output : Ensure local output dirs]
2026-07-09 03:27:48.654140 | instance -> localhost | ok: "/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/logs"
2026-07-09 03:27:48.851558 | instance -> localhost | changed: "/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/artifacts"
2026-07-09 03:27:49.052929 | instance -> localhost | changed: "/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/docs"
2026-07-09 03:27:49.076566 | 
2026-07-09 03:27:49.076743 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-09 03:27:52.525700 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/logs', 'src': 'logs'}
2026-07-09 03:27:52.525943 | instance | {
2026-07-09 03:27:52.525975 | instance |   "ansible_loop_var": "zj_output",
2026-07-09 03:27:52.526002 | instance |   "msg": "ssh: connect to host 199.19.213.250 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n",
2026-07-09 03:27:52.526027 | instance |   "rc": 255,
2026-07-09 03:27:52.526047 | instance |   "zj_output": {
2026-07-09 03:27:52.526065 | instance |     "dest": "/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/logs",
2026-07-09 03:27:52.526098 | instance |     "src": "logs"
2026-07-09 03:27:52.526117 | instance |   }
2026-07-09 03:27:52.526134 | instance | }
2026-07-09 03:27:52.526165 | instance | changed: All items complete
2026-07-09 03:27:52.526186 | 
2026-07-09 03:27:55.822388 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/artifacts', 'src': 'artifacts'}
2026-07-09 03:27:55.822581 | instance | {
2026-07-09 03:27:55.822624 | instance |   "ansible_loop_var": "zj_output",
2026-07-09 03:27:55.822653 | instance |   "msg": "ssh: connect to host 199.19.213.250 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n",
2026-07-09 03:27:55.822682 | instance |   "rc": 255,
2026-07-09 03:27:55.822710 | instance |   "zj_output": {
2026-07-09 03:27:55.822735 | instance |     "dest": "/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/artifacts",
2026-07-09 03:27:55.822764 | instance |     "src": "artifacts"
2026-07-09 03:27:55.822790 | instance |   }
2026-07-09 03:27:55.822814 | instance | }
2026-07-09 03:27:59.120669 | instance | ERROR: Item: {'dest': '/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/docs', 'src': 'docs'}
2026-07-09 03:27:59.120909 | instance | {
2026-07-09 03:27:59.121015 | instance |   "ansible_loop_var": "zj_output",
2026-07-09 03:27:59.121071 | instance |   "msg": "ssh: connect to host 199.19.213.250 port 22: No route to host\r\nrsync: connection unexpectedly closed (0 bytes received so far) [Receiver]\nrsync error: unexplained error (code 255) at io.c(232) [Receiver=3.2.7]\n",
2026-07-09 03:27:59.121119 | instance |   "rc": 255,
2026-07-09 03:27:59.121162 | instance |   "zj_output": {
2026-07-09 03:27:59.121207 | instance |     "dest": "/var/lib/zuul/builds/c6f8cc54526847cfb0dba763fd45c561/work/docs",
2026-07-09 03:27:59.121251 | instance |     "src": "docs"
2026-07-09 03:27:59.121292 | instance |   }
2026-07-09 03:27:59.121334 | instance | }
failure
2026-07-09 03:27:59.137188 | 
2026-07-09 03:27:59.137257 | PLAY RECAP
2026-07-09 03:27:59.137313 | instance | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-07-09 03:27:59.137351 | 
2026-07-09 03:27:59.252087 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-09 03:27:59.257445 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-09 03:27:59.822735 | 
2026-07-09 03:28:00.112112 | PLAY [localhost]
2026-07-09 03:28:00.128748 | 
2026-07-09 03:28:00.128868 | TASK [Generate Zuul manifest]
2026-07-09 03:28:00.149549 | localhost | ok
2026-07-09 03:28:00.165523 | 
2026-07-09 03:28:00.165599 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-09 03:28:00.513883 | localhost | changed
2026-07-09 03:28:00.527829 | 
2026-07-09 03:28:00.527992 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-09 03:28:00.591717 | localhost | ok
2026-07-09 03:28:00.601718 | 
2026-07-09 03:28:00.601851 | TASK [Upload logs]
2026-07-09 03:28:00.623892 | localhost | ok
2026-07-09 03:28:00.699572 | 
2026-07-09 03:28:00.699747 | TASK [Set zuul-log-path fact]
2026-07-09 03:28:00.724043 | localhost | ok
2026-07-09 03:28:00.736757 | 
2026-07-09 03:28:00.736841 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-09 03:28:00.770356 | localhost | ok
2026-07-09 03:28:00.780329 | 
2026-07-09 03:28:00.780437 | TASK [upload-logs : Create log directories]
2026-07-09 03:28:01.177321 | localhost | changed
2026-07-09 03:28:01.226628 | 
2026-07-09 03:28:01.226790 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-09 03:28:01.598051 | localhost -> localhost | ok: Runtime: 0:00:00.005119
2026-07-09 03:28:01.605175 | 
2026-07-09 03:28:01.605253 | TASK [upload-logs : Upload logs to log server]
2026-07-09 03:28:02.006867 | localhost | Output suppressed because no_log was given
2026-07-09 03:28:02.012625 | 
2026-07-09 03:28:02.012729 | LOOP [upload-logs : Compress console log and json output]
2026-07-09 03:28:02.062388 | localhost | skipping: Conditional result was False
2026-07-09 03:28:02.070995 | localhost | skipping: Conditional result was False
2026-07-09 03:28:02.088789 | 
2026-07-09 03:28:02.089053 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-09 03:28:02.131336 | localhost | skipping: Conditional result was False
2026-07-09 03:28:02.131825 | 
2026-07-09 03:28:02.135353 | localhost | skipping: Conditional result was False
2026-07-09 03:28:02.145422 | 
2026-07-09 03:28:02.145661 | LOOP [upload-logs : Upload console log and json output]