2026-07-22 08:34:02.334710 | Job console starting
2026-07-22 08:34:02.352497 | Updating git repos
2026-07-22 08:34:04.615926 | Cloning repos into workspace
2026-07-22 08:34:08.840239 | Restoring repo states
2026-07-22 08:34:11.049047 | Merging changes
2026-07-22 08:34:11.675914 | Checking out repos
2026-07-22 08:34:21.091938 | Preparing playbooks
2026-07-22 08:34:27.148666 | Running Ansible setup
2026-07-22 08:34:32.574757 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-22 08:34:33.298462 | 
2026-07-22 08:34:33.298627 | PLAY [localhost]
2026-07-22 08:34:33.306742 | 
2026-07-22 08:34:33.306821 | TASK [Gathering Facts]
2026-07-22 08:34:34.217579 | localhost | ok
2026-07-22 08:34:34.230539 | 
2026-07-22 08:34:34.230753 | TASK [Setup log path fact]
2026-07-22 08:34:34.254933 | localhost | ok
2026-07-22 08:34:34.271292 | 
2026-07-22 08:34:34.271464 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-22 08:34:34.302963 | localhost | ok
2026-07-22 08:34:34.312149 | 
2026-07-22 08:34:34.312261 | TASK [emit-job-header : Print job information]
2026-07-22 08:34:34.353311 | # Job Information
2026-07-22 08:34:34.353497 | Ansible Version: 2.16.18
2026-07-22 08:34:34.353542 | Job: magnum-cluster-api-hydrophone-v1.36.1-cilium
2026-07-22 08:34:34.353572 | Pipeline: check
2026-07-22 08:34:34.353600 | Executor: 2d72f0692154
2026-07-22 08:34:34.353627 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1119
2026-07-22 08:34:34.353657 | Event ID: 609cadb2-85a7-11f1-8da0-79c2fbfb41bb
2026-07-22 08:34:34.357486 | 
2026-07-22 08:34:34.357574 | LOOP [emit-job-header : Print node information]
2026-07-22 08:34:34.480583 | localhost | ok:
2026-07-22 08:34:34.480899 | localhost | # Node Information
2026-07-22 08:34:34.492999 | localhost | Inventory Hostname: controller
2026-07-22 08:34:34.493066 | localhost | Hostname: np0000186170
2026-07-22 08:34:34.493099 | localhost | Username: zuul
2026-07-22 08:34:34.493133 | localhost | Distro: Ubuntu 24.04
2026-07-22 08:34:34.493161 | localhost | Provider: yul1
2026-07-22 08:34:34.493187 | localhost | Region: ca-ymq-1
2026-07-22 08:34:34.493212 | localhost | Label: ubuntu-noble-16
2026-07-22 08:34:34.493237 | localhost | Product Name: OpenStack Nova
2026-07-22 08:34:34.493262 | localhost | Interface IP: 199.19.213.163
2026-07-22 08:34:34.513580 | 
2026-07-22 08:34:34.513756 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-22 08:34:34.924860 | localhost -> localhost | changed
2026-07-22 08:34:34.932713 | 
2026-07-22 08:34:34.932805 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-22 08:34:35.824982 | localhost -> localhost | changed
2026-07-22 08:34:35.834095 | 
2026-07-22 08:34:35.834167 | PLAY [all]
2026-07-22 08:34:35.842790 | 
2026-07-22 08:34:35.842856 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-22 08:34:36.078991 | controller -> localhost | ok
2026-07-22 08:34:36.092414 | 
2026-07-22 08:34:36.092486 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-22 08:34:36.129558 | controller | ok
2026-07-22 08:34:36.145710 | controller | included: /var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-22 08:34:36.152199 | 
2026-07-22 08:34:36.152448 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-22 08:34:37.275002 | controller -> localhost | Generating public/private rsa key pair.
2026-07-22 08:34:37.275197 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/9b71d6c595244d3584c0e1385b559a25_id_rsa
2026-07-22 08:34:37.275227 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/9b71d6c595244d3584c0e1385b559a25_id_rsa.pub
2026-07-22 08:34:37.275251 | controller -> localhost | The key fingerprint is:
2026-07-22 08:34:37.275273 | controller -> localhost | SHA256:1wMkpkDqwGofxlMhdNZsq72DFRoLcxXovWBYidbQHM8 zuul-build-sshkey
2026-07-22 08:34:37.275304 | controller -> localhost | The key's randomart image is:
2026-07-22 08:34:37.275326 | controller -> localhost | +---[RSA 3072]----+
2026-07-22 08:34:37.275362 | controller -> localhost | |  .=O=*.+ .      |
2026-07-22 08:34:37.275385 | controller -> localhost | |.  ++O+B o       |
2026-07-22 08:34:37.275407 | controller -> localhost | |..o +.=E. .      |
2026-07-22 08:34:37.275427 | controller -> localhost | |.o.+.* +   o     |
2026-07-22 08:34:37.275447 | controller -> localhost | |...== B S . o    |
2026-07-22 08:34:37.275467 | controller -> localhost | |. o o+ + .   .   |
2026-07-22 08:34:37.275487 | controller -> localhost | |   .  o .        |
2026-07-22 08:34:37.275507 | controller -> localhost | |     . o         |
2026-07-22 08:34:37.275530 | controller -> localhost | |        .        |
2026-07-22 08:34:37.275550 | controller -> localhost | +----[SHA256]-----+
2026-07-22 08:34:37.275604 | controller -> localhost | ok: Runtime: 0:00:00.696973
2026-07-22 08:34:37.282149 | 
2026-07-22 08:34:37.282220 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-22 08:34:37.316138 | controller | ok
2026-07-22 08:34:37.334289 | controller | included: /var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-22 08:34:37.344193 | 
2026-07-22 08:34:37.344292 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-22 08:34:37.371198 | controller | skipping: Conditional result was False
2026-07-22 08:34:37.385476 | 
2026-07-22 08:34:37.385555 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-22 08:34:38.013561 | controller | changed
2026-07-22 08:34:38.021348 | 
2026-07-22 08:34:38.021431 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-22 08:34:38.241517 | controller | ok
2026-07-22 08:34:38.252836 | 
2026-07-22 08:34:38.253187 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-22 08:34:38.967240 | controller | changed
2026-07-22 08:34:38.975649 | 
2026-07-22 08:34:38.975727 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-22 08:34:39.668437 | controller | changed
2026-07-22 08:34:39.676689 | 
2026-07-22 08:34:39.676766 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-22 08:34:39.701373 | controller | skipping: Conditional result was False
2026-07-22 08:34:39.713072 | 
2026-07-22 08:34:39.713193 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-22 08:34:40.034771 | controller -> localhost | changed
2026-07-22 08:34:40.050597 | 
2026-07-22 08:34:40.050730 | TASK [add-build-sshkey : Add back temp key]
2026-07-22 08:34:40.362861 | controller -> localhost | Identity added: /var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/9b71d6c595244d3584c0e1385b559a25_id_rsa (zuul-build-sshkey)
2026-07-22 08:34:40.363166 | controller -> localhost | ok: Runtime: 0:00:00.015232
2026-07-22 08:34:40.370853 | 
2026-07-22 08:34:40.370919 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-22 08:34:40.681430 | controller | ok
2026-07-22 08:34:40.687368 | 
2026-07-22 08:34:40.687500 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-22 08:34:40.712642 | controller | skipping: Conditional result was False
2026-07-22 08:34:40.729929 | 
2026-07-22 08:34:40.730104 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-22 08:34:41.059028 | controller | ok
2026-07-22 08:34:41.065340 | 
2026-07-22 08:34:41.065425 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-22 08:52:04.289744 | controller | Output suppressed because no_log was given
failure
2026-07-22 08:52:04.291305 | 
2026-07-22 08:52:04.291351 | PLAY RECAP
2026-07-22 08:52:04.291397 | controller | ok: 12 changed: 6 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 0
2026-07-22 08:52:04.291425 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-22 08:52:04.291451 | 
2026-07-22 08:52:04.427922 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-22 08:52:04.437017 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-22 08:52:05.387223 | 
2026-07-22 08:52:05.387375 | PLAY [all]
2026-07-22 08:52:05.400003 | 
2026-07-22 08:52:05.400133 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-22 08:52:05.445874 | controller | skipping: Conditional result was False
2026-07-22 08:52:05.459557 | 
2026-07-22 08:52:05.459753 | TASK [fetch-output : Set log path for single node]
2026-07-22 08:52:05.505732 | controller | ok
2026-07-22 08:52:05.511096 | 
2026-07-22 08:52:05.511169 | LOOP [fetch-output : Ensure local output dirs]
2026-07-22 08:52:05.910030 | controller -> localhost | ok: "/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/logs"
2026-07-22 08:52:06.114266 | controller -> localhost | changed: "/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/artifacts"
2026-07-22 08:52:06.320076 | controller -> localhost | changed: "/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/docs"
2026-07-22 08:52:06.343124 | 
2026-07-22 08:52:06.343314 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-22 08:52:09.812744 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/logs', 'src': 'logs'}
2026-07-22 08:52:09.813286 | controller | {
2026-07-22 08:52:09.813363 | controller |   "ansible_loop_var": "zj_output",
2026-07-22 08:52:09.813417 | controller |   "msg": "ssh: connect to host 199.19.213.163 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-22 08:52:09.813470 | controller |   "rc": 255,
2026-07-22 08:52:09.813513 | controller |   "zj_output": {
2026-07-22 08:52:09.813552 | controller |     "dest": "/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/logs",
2026-07-22 08:52:09.813592 | controller |     "src": "logs"
2026-07-22 08:52:09.813631 | controller |   }
2026-07-22 08:52:09.813668 | controller | }
2026-07-22 08:52:09.813737 | controller | changed: All items complete
2026-07-22 08:52:09.813785 | 
2026-07-22 08:52:13.102510 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/artifacts', 'src': 'artifacts'}
2026-07-22 08:52:13.102701 | controller | {
2026-07-22 08:52:13.102743 | controller |   "ansible_loop_var": "zj_output",
2026-07-22 08:52:13.102773 | controller |   "msg": "ssh: connect to host 199.19.213.163 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-22 08:52:13.102800 | controller |   "rc": 255,
2026-07-22 08:52:13.102825 | controller |   "zj_output": {
2026-07-22 08:52:13.102850 | controller |     "dest": "/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/artifacts",
2026-07-22 08:52:13.102878 | controller |     "src": "artifacts"
2026-07-22 08:52:13.102904 | controller |   }
2026-07-22 08:52:13.102928 | controller | }
2026-07-22 08:52:16.409117 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/docs', 'src': 'docs'}
2026-07-22 08:52:16.409471 | controller | {
2026-07-22 08:52:16.409557 | controller |   "ansible_loop_var": "zj_output",
2026-07-22 08:52:16.409614 | controller |   "msg": "ssh: connect to host 199.19.213.163 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-22 08:52:16.409678 | controller |   "rc": 255,
2026-07-22 08:52:16.409724 | controller |   "zj_output": {
2026-07-22 08:52:16.409753 | controller |     "dest": "/var/lib/zuul/builds/9b71d6c595244d3584c0e1385b559a25/work/docs",
2026-07-22 08:52:16.409783 | controller |     "src": "docs"
2026-07-22 08:52:16.409875 | controller |   }
2026-07-22 08:52:16.409912 | controller | }
failure
2026-07-22 08:52:16.433867 | 
2026-07-22 08:52:16.433926 | PLAY RECAP
2026-07-22 08:52:16.433970 | controller | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-07-22 08:52:16.433992 | 
2026-07-22 08:52:16.582723 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-22 08:52:16.593236 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-22 08:52:17.296130 | 
2026-07-22 08:52:17.296258 | PLAY [localhost]
2026-07-22 08:52:17.306047 | 
2026-07-22 08:52:17.306117 | TASK [Generate Zuul manifest]
2026-07-22 08:52:17.328323 | localhost | ok
2026-07-22 08:52:17.347306 | 
2026-07-22 08:52:17.347396 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-22 08:52:17.713919 | localhost | changed
2026-07-22 08:52:17.732032 | 
2026-07-22 08:52:17.732157 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-22 08:52:17.764471 | localhost | ok
2026-07-22 08:52:17.772382 | 
2026-07-22 08:52:17.772444 | TASK [Upload logs]
2026-07-22 08:52:17.794375 | localhost | ok
2026-07-22 08:52:17.906895 | 
2026-07-22 08:52:17.907041 | TASK [Set zuul-log-path fact]
2026-07-22 08:52:17.929159 | localhost | ok
2026-07-22 08:52:17.941632 | 
2026-07-22 08:52:17.941713 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-22 08:52:17.982314 | localhost | ok
2026-07-22 08:52:17.992225 | 
2026-07-22 08:52:17.992300 | TASK [upload-logs : Create log directories]
2026-07-22 08:52:18.359297 | localhost | changed
2026-07-22 08:52:18.365946 | 
2026-07-22 08:52:18.366072 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-22 08:52:18.725747 | localhost -> localhost | ok: Runtime: 0:00:00.004912
2026-07-22 08:52:18.733952 | 
2026-07-22 08:52:18.734061 | TASK [upload-logs : Upload logs to log server]
2026-07-22 08:52:19.171761 | localhost | Output suppressed because no_log was given
2026-07-22 08:52:19.176870 | 
2026-07-22 08:52:19.176980 | LOOP [upload-logs : Compress console log and json output]
2026-07-22 08:52:19.220792 | localhost | skipping: Conditional result was False
2026-07-22 08:52:19.227074 | localhost | skipping: Conditional result was False
2026-07-22 08:52:19.240850 | 
2026-07-22 08:52:19.241014 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-22 08:52:19.284199 | localhost | skipping: Conditional result was False
2026-07-22 08:52:19.284680 | 
2026-07-22 08:52:19.288052 | localhost | skipping: Conditional result was False
2026-07-22 08:52:19.307332 | 
2026-07-22 08:52:19.307504 | LOOP [upload-logs : Upload console log and json output]