2026-07-22 11:33:23.268235 | Job console starting
2026-07-22 11:33:23.278573 | Updating git repos
2026-07-22 11:33:25.167917 | Cloning repos into workspace
2026-07-22 11:33:30.199579 | Restoring repo states
2026-07-22 11:33:31.733818 | Merging changes
2026-07-22 11:33:32.197910 | Checking out repos
2026-07-22 11:34:20.240424 | Preparing playbooks
2026-07-22 11:35:38.156358 | Running Ansible setup
2026-07-22 11:35:48.566958 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-22 11:35:49.259224 | 
2026-07-22 11:35:49.259370 | PLAY [localhost]
2026-07-22 11:35:49.267784 | 
2026-07-22 11:35:49.267917 | TASK [Gathering Facts]
2026-07-22 11:35:58.584926 | localhost | ok
2026-07-22 11:35:58.595265 | 
2026-07-22 11:35:58.595412 | TASK [Setup log path fact]
2026-07-22 11:35:58.634454 | localhost | ok
2026-07-22 11:35:58.644808 | 
2026-07-22 11:35:58.644901 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-22 11:35:58.675914 | localhost | ok
2026-07-22 11:35:58.703063 | 
2026-07-22 11:35:58.703296 | TASK [emit-job-header : Print job information]
2026-07-22 11:35:58.744167 | # Job Information
2026-07-22 11:35:58.744332 | Ansible Version: 2.16.18
2026-07-22 11:35:58.744364 | Job: magnum-cluster-api-hydrophone-v1.33.12-calico
2026-07-22 11:35:58.744387 | Pipeline: check
2026-07-22 11:35:58.744409 | Executor: 2d72f0692154
2026-07-22 11:35:58.744429 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1119
2026-07-22 11:35:58.744452 | Event ID: ddbabd7a-85c0-11f1-9c80-daaba797fac7
2026-07-22 11:35:58.748914 | 
2026-07-22 11:35:58.748998 | LOOP [emit-job-header : Print node information]
2026-07-22 11:35:58.913083 | localhost | ok:
2026-07-22 11:35:58.913288 | localhost | # Node Information
2026-07-22 11:35:58.913326 | localhost | Inventory Hostname: controller
2026-07-22 11:35:58.913351 | localhost | Hostname: np0000186172
2026-07-22 11:35:58.913371 | localhost | Username: zuul
2026-07-22 11:35:58.913398 | localhost | Distro: Ubuntu 24.04
2026-07-22 11:35:58.913419 | localhost | Provider: yul1
2026-07-22 11:35:58.913438 | localhost | Region: ca-ymq-1
2026-07-22 11:35:58.913457 | localhost | Label: ubuntu-noble-16
2026-07-22 11:35:58.913477 | localhost | Product Name: OpenStack Nova
2026-07-22 11:35:58.913502 | localhost | Interface IP: 199.204.45.222
2026-07-22 11:35:58.926307 | 
2026-07-22 11:35:58.926440 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-22 11:35:59.319034 | localhost -> localhost | changed
2026-07-22 11:35:59.327562 | 
2026-07-22 11:35:59.327708 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-22 11:36:01.209958 | localhost -> localhost | changed
2026-07-22 11:36:01.220843 | 
2026-07-22 11:36:01.221122 | PLAY [all]
2026-07-22 11:36:01.234753 | 
2026-07-22 11:36:01.235010 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-22 11:36:01.477431 | controller -> localhost | ok
2026-07-22 11:36:01.485884 | 
2026-07-22 11:36:01.485997 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-22 11:36:01.520379 | controller | ok
2026-07-22 11:36:01.538187 | controller | included: /var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-22 11:36:01.546385 | 
2026-07-22 11:36:01.546524 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-22 11:36:03.642883 | controller -> localhost | Generating public/private rsa key pair.
2026-07-22 11:36:03.643109 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/3b5391d4fac4447591a4ecd276f93064_id_rsa
2026-07-22 11:36:03.643160 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/3b5391d4fac4447591a4ecd276f93064_id_rsa.pub
2026-07-22 11:36:03.643200 | controller -> localhost | The key fingerprint is:
2026-07-22 11:36:03.643238 | controller -> localhost | SHA256:nvKn/BlRy2/ovgXDG6tqD7TRKZyLLeq0XsQK6U/5eco zuul-build-sshkey
2026-07-22 11:36:03.643292 | controller -> localhost | The key's randomart image is:
2026-07-22 11:36:03.643329 | controller -> localhost | +---[RSA 3072]----+
2026-07-22 11:36:03.643373 | controller -> localhost | |                 |
2026-07-22 11:36:03.643412 | controller -> localhost | |                 |
2026-07-22 11:36:03.643449 | controller -> localhost | |            .    |
2026-07-22 11:36:03.643510 | controller -> localhost | |   . . . o = .   |
2026-07-22 11:36:03.643553 | controller -> localhost | |  o   o S + B    |
2026-07-22 11:36:03.643591 | controller -> localhost | | . . + = * . O   |
2026-07-22 11:36:03.643627 | controller -> localhost | |  . = = B . + +  |
2026-07-22 11:36:03.643669 | controller -> localhost | |   + * *o..= o   |
2026-07-22 11:36:03.643710 | controller -> localhost | |   o* E+=*=.+.   |
2026-07-22 11:36:03.643746 | controller -> localhost | +----[SHA256]-----+
2026-07-22 11:36:03.643838 | controller -> localhost | ok: Runtime: 0:00:01.631780
2026-07-22 11:36:03.652078 | 
2026-07-22 11:36:03.652146 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-22 11:36:03.683982 | controller | ok
2026-07-22 11:36:03.693429 | controller | included: /var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-22 11:36:03.702114 | 
2026-07-22 11:36:03.702186 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-22 11:36:03.726072 | controller | skipping: Conditional result was False
2026-07-22 11:36:03.736351 | 
2026-07-22 11:36:03.736430 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-22 11:36:04.536213 | controller | changed
2026-07-22 11:36:04.544348 | 
2026-07-22 11:36:04.544444 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-22 11:36:04.767440 | controller | ok
2026-07-22 11:36:04.775830 | 
2026-07-22 11:36:04.775952 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-22 11:36:09.260857 | controller | changed
2026-07-22 11:36:09.268785 | 
2026-07-22 11:36:09.268909 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-22 11:36:09.884237 | controller | changed
2026-07-22 11:36:10.246962 | 
2026-07-22 11:36:10.247094 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-22 11:36:10.271671 | controller | skipping: Conditional result was False
2026-07-22 11:36:10.301918 | 
2026-07-22 11:36:10.302007 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-22 11:36:10.671213 | controller -> localhost | changed
2026-07-22 11:36:10.684325 | 
2026-07-22 11:36:10.684456 | TASK [add-build-sshkey : Add back temp key]
2026-07-22 11:36:10.974518 | controller -> localhost | Identity added: /var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/3b5391d4fac4447591a4ecd276f93064_id_rsa (zuul-build-sshkey)
2026-07-22 11:36:10.974755 | controller -> localhost | ok: Runtime: 0:00:00.013723
2026-07-22 11:36:10.980901 | 
2026-07-22 11:36:10.981010 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-22 11:36:11.301004 | controller | ok
2026-07-22 11:36:11.308342 | 
2026-07-22 11:36:11.308450 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-22 11:36:11.333681 | controller | skipping: Conditional result was False
2026-07-22 11:36:11.352207 | 
2026-07-22 11:36:11.352317 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-22 11:36:11.686704 | controller | ok
2026-07-22 11:36:11.693292 | 
2026-07-22 11:36:11.693363 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-22 11:52:52.537024 | controller | Output suppressed because no_log was given
failure
2026-07-22 11:52:52.540797 | 
2026-07-22 11:52:52.540865 | PLAY RECAP
2026-07-22 11:52:52.540932 | controller | ok: 12 changed: 6 unreachable: 0 failed: 1 skipped: 3 rescued: 0 ignored: 0
2026-07-22 11:52:52.541018 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-22 11:52:52.541078 | 
2026-07-22 11:52:52.694249 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-22 11:52:52.703564 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-22 11:52:53.462637 | 
2026-07-22 11:52:53.462832 | PLAY [all]
2026-07-22 11:52:53.475516 | 
2026-07-22 11:52:53.475600 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-22 11:52:53.530419 | controller | skipping: Conditional result was False
2026-07-22 11:52:53.541872 | 
2026-07-22 11:52:53.541962 | TASK [fetch-output : Set log path for single node]
2026-07-22 11:52:53.585525 | controller | ok
2026-07-22 11:52:53.590885 | 
2026-07-22 11:52:53.591001 | LOOP [fetch-output : Ensure local output dirs]
2026-07-22 11:52:54.028019 | controller -> localhost | ok: "/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/logs"
2026-07-22 11:52:54.232568 | controller -> localhost | changed: "/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/artifacts"
2026-07-22 11:52:54.486857 | controller -> localhost | changed: "/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/docs"
2026-07-22 11:52:54.507425 | 
2026-07-22 11:52:54.507570 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-22 11:52:57.972592 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/logs', 'src': 'logs'}
2026-07-22 11:52:57.973007 | controller | {
2026-07-22 11:52:57.973056 | controller |   "ansible_loop_var": "zj_output",
2026-07-22 11:52:57.973096 | controller |   "msg": "ssh: connect to host 199.204.45.222 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 11:52:57.973131 | controller |   "rc": 255,
2026-07-22 11:52:57.973159 | controller |   "zj_output": {
2026-07-22 11:52:57.973185 | controller |     "dest": "/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/logs",
2026-07-22 11:52:57.973210 | controller |     "src": "logs"
2026-07-22 11:52:57.973235 | controller |   }
2026-07-22 11:52:57.973260 | controller | }
2026-07-22 11:52:57.973305 | controller | changed: All items complete
2026-07-22 11:52:57.973334 | 
2026-07-22 11:53:01.297298 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/artifacts', 'src': 'artifacts'}
2026-07-22 11:53:01.297488 | controller | {
2026-07-22 11:53:01.297524 | controller |   "ansible_loop_var": "zj_output",
2026-07-22 11:53:01.297550 | controller |   "msg": "ssh: connect to host 199.204.45.222 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 11:53:01.297576 | controller |   "rc": 255,
2026-07-22 11:53:01.297602 | controller |   "zj_output": {
2026-07-22 11:53:01.297626 | controller |     "dest": "/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/artifacts",
2026-07-22 11:53:01.297653 | controller |     "src": "artifacts"
2026-07-22 11:53:01.297677 | controller |   }
2026-07-22 11:53:01.297700 | controller | }
2026-07-22 11:53:04.589732 | controller | ERROR: Item: {'dest': '/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/docs', 'src': 'docs'}
2026-07-22 11:53:04.589942 | controller | {
2026-07-22 11:53:04.589989 | controller |   "ansible_loop_var": "zj_output",
2026-07-22 11:53:04.590026 | controller |   "msg": "ssh: connect to host 199.204.45.222 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 11:53:04.590062 | controller |   "rc": 255,
2026-07-22 11:53:04.590097 | controller |   "zj_output": {
2026-07-22 11:53:04.590131 | controller |     "dest": "/var/lib/zuul/builds/3b5391d4fac4447591a4ecd276f93064/work/docs",
2026-07-22 11:53:04.590164 | controller |     "src": "docs"
2026-07-22 11:53:04.590212 | controller |   }
2026-07-22 11:53:04.590247 | controller | }
failure
2026-07-22 11:53:04.610762 | 
2026-07-22 11:53:04.610860 | PLAY RECAP
2026-07-22 11:53:04.610940 | controller | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0
2026-07-22 11:53:04.610984 | 
2026-07-22 11:53:04.757636 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-22 11:53:04.799320 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-22 11:53:06.151268 | 
2026-07-22 11:53:06.151387 | PLAY [localhost]
2026-07-22 11:53:06.161343 | 
2026-07-22 11:53:06.161469 | TASK [Generate Zuul manifest]
2026-07-22 11:53:06.182014 | localhost | ok
2026-07-22 11:53:07.131418 | 
2026-07-22 11:53:07.131596 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-22 11:53:08.089714 | localhost | changed
2026-07-22 11:53:08.982128 | 
2026-07-22 11:53:09.093916 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-22 11:53:09.327183 | localhost | ok
2026-07-22 11:53:09.334446 | 
2026-07-22 11:53:09.334538 | TASK [Upload logs]
2026-07-22 11:53:09.354518 | localhost | ok
2026-07-22 11:53:10.600096 | 
2026-07-22 11:53:10.600237 | TASK [Set zuul-log-path fact]
2026-07-22 11:53:10.619725 | localhost | ok
2026-07-22 11:53:10.932652 | 
2026-07-22 11:53:10.932853 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-22 11:53:10.963918 | localhost | ok
2026-07-22 11:53:10.972404 | 
2026-07-22 11:53:10.972484 | TASK [upload-logs : Create log directories]
2026-07-22 11:53:11.912597 | localhost | changed
2026-07-22 11:53:11.918070 | 
2026-07-22 11:53:11.918152 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-22 11:53:12.280900 | localhost -> localhost | ok: Runtime: 0:00:00.004076
2026-07-22 11:53:12.287520 | 
2026-07-22 11:53:12.287611 | TASK [upload-logs : Upload logs to log server]
2026-07-22 11:53:16.303554 | localhost | Output suppressed because no_log was given
2026-07-22 11:53:16.307971 | 
2026-07-22 11:53:16.308052 | LOOP [upload-logs : Compress console log and json output]
2026-07-22 11:53:16.356211 | localhost | skipping: Conditional result was False
2026-07-22 11:53:16.363418 | localhost | skipping: Conditional result was False
2026-07-22 11:53:16.382105 | 
2026-07-22 11:53:16.382257 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-22 11:53:16.427310 | localhost | skipping: Conditional result was False
2026-07-22 11:53:16.427781 | 
2026-07-22 11:53:16.431368 | localhost | skipping: Conditional result was False
2026-07-22 11:53:16.455783 | 
2026-07-22 11:53:16.455959 | LOOP [upload-logs : Upload console log and json output]