2026-04-16 17:30:34.650606 | Job console starting 2026-04-16 17:30:34.666982 | Updating git repos 2026-04-16 17:30:35.129135 | Cloning repos into workspace 2026-04-16 17:30:35.172784 | Restoring repo states 2026-04-16 17:30:35.180047 | Merging changes 2026-04-16 17:30:35.811528 | Checking out repos 2026-04-16 17:30:35.858807 | Preparing playbooks 2026-04-16 17:30:36.528863 | Running Ansible setup 2026-04-16 17:31:16.384341 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-16 17:31:17.138144 | 2026-04-16 17:31:17.138299 | PLAY [Base pre] 2026-04-16 17:31:17.154944 | 2026-04-16 17:31:17.155066 | TASK [Setup log path fact] 2026-04-16 17:31:17.185156 | noble | ok 2026-04-16 17:31:17.202330 | 2026-04-16 17:31:17.202461 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 17:31:17.246524 | noble | ok 2026-04-16 17:31:17.261504 | 2026-04-16 17:31:17.261622 | TASK [emit-job-header : Print job information] 2026-04-16 17:31:17.324106 | # Job Information 2026-04-16 17:31:17.324376 | Ansible Version: 2.16.14 2026-04-16 17:31:17.324441 | Job: openstack-ironic-images-build-osism-ipa-stable 2026-04-16 17:31:17.324502 | Pipeline: check 2026-04-16 17:31:17.324543 | Executor: 521e9411259a 2026-04-16 17:31:17.324581 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/200 2026-04-16 17:31:17.324619 | Event ID: 2da71cf0-39b0-11f1-8d07-0a1a185d00d3 2026-04-16 17:31:17.339563 | 2026-04-16 17:31:17.339884 | LOOP [emit-job-header : Print node information] 2026-04-16 17:31:17.475200 | noble | ok: 2026-04-16 17:31:17.475467 | noble | # Node Information 2026-04-16 17:31:17.475521 | noble | Inventory Hostname: noble 2026-04-16 17:31:17.475561 | noble | Hostname: ubuntu 2026-04-16 17:31:17.475596 | noble | Username: zuul 2026-04-16 17:31:17.475630 | noble | Distro: Ubuntu 24.04 2026-04-16 17:31:17.475668 | noble | Provider: regiocloud-a 2026-04-16 17:31:17.475702 | noble | Region: 2026-04-16 17:31:17.475736 | noble | Label: ubuntu-noble-uefi 2026-04-16 17:31:17.475822 | noble | Product Name: OpenStack Nova 2026-04-16 17:31:17.475857 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee0:4712 2026-04-16 17:31:17.502515 | 2026-04-16 17:31:17.502712 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-16 17:31:17.976965 | noble -> localhost | changed 2026-04-16 17:31:17.996995 | 2026-04-16 17:31:17.997241 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-16 17:31:19.059459 | noble -> localhost | changed 2026-04-16 17:31:19.089083 | 2026-04-16 17:31:19.089287 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-16 17:31:19.380427 | noble -> localhost | ok 2026-04-16 17:31:19.395853 | 2026-04-16 17:31:19.396040 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-16 17:31:19.432495 | noble | ok 2026-04-16 17:31:19.451817 | noble | included: /var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-16 17:31:19.460003 | 2026-04-16 17:31:19.460107 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-16 17:31:20.574731 | noble -> localhost | Generating public/private rsa key pair. 2026-04-16 17:31:20.575161 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/e9a942f8561f4bdfae0dffbf0bc0fe84_id_rsa 2026-04-16 17:31:20.575236 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/e9a942f8561f4bdfae0dffbf0bc0fe84_id_rsa.pub 2026-04-16 17:31:20.575289 | noble -> localhost | The key fingerprint is: 2026-04-16 17:31:20.575337 | noble -> localhost | SHA256:Ccpi7e5XhZRGV+ninSp+S5GFNLU8m6kwJ6VJR4v8HmY zuul-build-sshkey 2026-04-16 17:31:20.575382 | noble -> localhost | The key's randomart image is: 2026-04-16 17:31:20.575426 | noble -> localhost | +---[RSA 3072]----+ 2026-04-16 17:31:20.575468 | noble -> localhost | | ...=oo. | 2026-04-16 17:31:20.575539 | noble -> localhost | | .+= =.. | 2026-04-16 17:31:20.575584 | noble -> localhost | | .o+.=.= | 2026-04-16 17:31:20.575626 | noble -> localhost | | o . o.Boo.= | 2026-04-16 17:31:20.575667 | noble -> localhost | | o + SoEo+. | 2026-04-16 17:31:20.575720 | noble -> localhost | | . o .O.+o | 2026-04-16 17:31:20.575783 | noble -> localhost | | . . +. | 2026-04-16 17:31:20.575828 | noble -> localhost | | . . ..o | 2026-04-16 17:31:20.575872 | noble -> localhost | | .o. ..o.. | 2026-04-16 17:31:20.575914 | noble -> localhost | +----[SHA256]-----+ 2026-04-16 17:31:20.576012 | noble -> localhost | ok: Runtime: 0:00:00.549462 2026-04-16 17:31:20.587090 | 2026-04-16 17:31:20.587227 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-16 17:31:20.620740 | noble | ok 2026-04-16 17:31:20.632280 | noble | included: /var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-16 17:31:20.641625 | 2026-04-16 17:31:20.641725 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-16 17:31:20.665588 | noble | skipping: Conditional result was False 2026-04-16 17:31:20.674414 | 2026-04-16 17:31:20.674519 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-16 17:31:21.366468 | noble | changed 2026-04-16 17:31:21.376044 | 2026-04-16 17:31:21.376184 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-16 17:31:21.709713 | noble | ok 2026-04-16 17:31:21.719131 | 2026-04-16 17:31:21.719270 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-16 17:31:22.539718 | noble | changed 2026-04-16 17:31:22.549952 | 2026-04-16 17:31:22.550088 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-16 17:31:23.354950 | noble | changed 2026-04-16 17:31:23.365041 | 2026-04-16 17:31:23.365185 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-16 17:31:23.389799 | noble | skipping: Conditional result was False 2026-04-16 17:31:23.403540 | 2026-04-16 17:31:23.403707 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-16 17:31:23.868179 | noble -> localhost | changed 2026-04-16 17:31:23.898287 | 2026-04-16 17:31:23.898489 | TASK [add-build-sshkey : Add back temp key] 2026-04-16 17:31:24.251543 | noble -> localhost | Identity added: /var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/e9a942f8561f4bdfae0dffbf0bc0fe84_id_rsa (zuul-build-sshkey) 2026-04-16 17:31:24.252105 | noble -> localhost | ok: Runtime: 0:00:00.017766 2026-04-16 17:31:24.266125 | 2026-04-16 17:31:24.266261 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-16 17:31:24.726321 | noble | ok 2026-04-16 17:31:24.736471 | 2026-04-16 17:31:24.736617 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-16 17:31:24.771132 | noble | skipping: Conditional result was False 2026-04-16 17:31:24.829523 | 2026-04-16 17:31:24.829649 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-16 17:31:25.284387 | noble | ok 2026-04-16 17:31:25.299364 | 2026-04-16 17:31:25.299484 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-16 17:31:25.348225 | noble | ok 2026-04-16 17:31:25.361001 | 2026-04-16 17:31:25.361136 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-16 17:31:25.689246 | noble -> localhost | ok 2026-04-16 17:31:25.697595 | 2026-04-16 17:31:25.697720 | TASK [validate-host : Collect information about the host] 2026-04-16 17:31:41.194109 | noble | ok 2026-04-16 17:31:41.210924 | 2026-04-16 17:31:41.211043 | TASK [validate-host : Sanitize hostname] 2026-04-16 17:31:41.286003 | noble | ok 2026-04-16 17:31:41.294230 | 2026-04-16 17:31:41.294361 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-16 17:31:41.876835 | noble -> localhost | changed 2026-04-16 17:31:41.883358 | 2026-04-16 17:31:41.883477 | TASK [validate-host : Collect information about zuul worker] 2026-04-16 17:31:42.357849 | noble | ok 2026-04-16 17:31:42.366209 | 2026-04-16 17:31:42.366366 | TASK [validate-host : Write out all zuul information for each host] 2026-04-16 17:31:42.938680 | noble -> localhost | changed 2026-04-16 17:31:42.957246 | 2026-04-16 17:31:42.957379 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-16 17:31:43.264837 | noble | ok 2026-04-16 17:31:43.274076 | 2026-04-16 17:31:43.274210 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-16 17:31:44.656908 | noble | changed: 2026-04-16 17:31:44.657202 | noble | cd+++++++++ src/ 2026-04-16 17:31:44.657261 | noble | cd+++++++++ src/github.com/ 2026-04-16 17:31:44.657306 | noble | cd+++++++++ src/github.com/osism/ 2026-04-16 17:31:44.657344 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-04-16 17:31:44.657378 | noble | localhost | ok: "/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/logs" 2026-04-16 18:01:26.596858 | noble -> localhost | changed: "/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/artifacts" 2026-04-16 18:01:26.862355 | noble -> localhost | changed: "/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/docs" 2026-04-16 18:01:26.883817 | 2026-04-16 18:01:26.883984 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-16 18:01:28.225754 | noble | ERROR: Item: {'dest': '/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/logs', 'src': 'logs'} 2026-04-16 18:01:28.226103 | noble | { 2026-04-16 18:01:28.226146 | noble | "ansible_loop_var": "zj_output", 2026-04-16 18:01:28.226175 | noble | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/logs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2026-04-16 18:01:28.226201 | noble | "rc": 23, 2026-04-16 18:01:28.226221 | noble | "zj_output": { 2026-04-16 18:01:28.226240 | noble | "dest": "/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/logs", 2026-04-16 18:01:28.226258 | noble | "src": "logs" 2026-04-16 18:01:28.226276 | noble | } 2026-04-16 18:01:28.226293 | noble | } 2026-04-16 18:01:28.226332 | noble | changed: All items complete 2026-04-16 18:01:28.226355 | 2026-04-16 18:01:29.009850 | noble | ERROR: Item: {'dest': '/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/artifacts', 'src': 'artifacts'} 2026-04-16 18:01:29.010111 | noble | { 2026-04-16 18:01:29.010173 | noble | "ansible_loop_var": "zj_output", 2026-04-16 18:01:29.010217 | noble | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/artifacts\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2026-04-16 18:01:29.010262 | noble | "rc": 23, 2026-04-16 18:01:29.010297 | noble | "zj_output": { 2026-04-16 18:01:29.010331 | noble | "dest": "/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/artifacts", 2026-04-16 18:01:29.010371 | noble | "src": "artifacts" 2026-04-16 18:01:29.010404 | noble | } 2026-04-16 18:01:29.010436 | noble | } 2026-04-16 18:01:29.766128 | noble | ERROR: Item: {'dest': '/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/docs', 'src': 'docs'} 2026-04-16 18:01:29.766358 | noble | { 2026-04-16 18:01:29.766419 | noble | "ansible_loop_var": "zj_output", 2026-04-16 18:01:29.766460 | noble | "msg": "rsync: [sender] change_dir \"/home/zuul/zuul-output/docs\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1865) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n", 2026-04-16 18:01:29.766497 | noble | "rc": 23, 2026-04-16 18:01:29.766531 | noble | "zj_output": { 2026-04-16 18:01:29.766566 | noble | "dest": "/var/lib/zuul/builds/e9a942f8561f4bdfae0dffbf0bc0fe84/work/docs", 2026-04-16 18:01:29.766599 | noble | "src": "docs" 2026-04-16 18:01:29.766630 | noble | } 2026-04-16 18:01:29.766660 | noble | } failure 2026-04-16 18:01:29.783397 | 2026-04-16 18:01:29.783484 | PLAY RECAP 2026-04-16 18:01:29.783545 | noble | ok: 2 changed: 1 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2026-04-16 18:01:29.783573 | 2026-04-16 18:01:29.914393 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-16 18:01:29.917496 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 18:01:30.678131 | 2026-04-16 18:01:30.678282 | PLAY [Base post] 2026-04-16 18:01:30.692688 | 2026-04-16 18:01:30.692828 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-16 18:01:31.331731 | noble | changed 2026-04-16 18:01:31.342365 | 2026-04-16 18:01:31.342502 | PLAY RECAP 2026-04-16 18:01:31.342579 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 18:01:31.342654 | 2026-04-16 18:01:31.463262 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 18:01:31.465396 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-16 18:01:32.256177 | 2026-04-16 18:01:32.256350 | PLAY [Base post-logs] 2026-04-16 18:01:32.266773 | 2026-04-16 18:01:32.266955 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-16 18:01:32.772428 | localhost | changed 2026-04-16 18:01:32.790029 | 2026-04-16 18:01:32.790208 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-16 18:01:32.817809 | localhost | ok 2026-04-16 18:01:32.823939 | 2026-04-16 18:01:32.824110 | TASK [Set zuul-log-path fact] 2026-04-16 18:01:32.841923 | localhost | ok 2026-04-16 18:01:32.855184 | 2026-04-16 18:01:32.855312 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 18:01:32.880972 | localhost | ok 2026-04-16 18:01:32.885172 | 2026-04-16 18:01:32.885307 | TASK [upload-logs : Create log directories] 2026-04-16 18:01:33.402074 | localhost | changed 2026-04-16 18:01:33.405124 | 2026-04-16 18:01:33.405235 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-16 18:01:33.916263 | localhost -> localhost | ok: Runtime: 0:00:00.005852 2026-04-16 18:01:33.921144 | 2026-04-16 18:01:33.921285 | TASK [upload-logs : Upload logs to log server] 2026-04-16 18:01:34.494726 | localhost | Output suppressed because no_log was given 2026-04-16 18:01:34.498614 | 2026-04-16 18:01:34.500649 | LOOP [upload-logs : Compress console log and json output] 2026-04-16 18:01:34.560103 | localhost | skipping: Conditional result was False 2026-04-16 18:01:34.565508 | localhost | skipping: Conditional result was False 2026-04-16 18:01:34.572080 | 2026-04-16 18:01:34.572233 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-16 18:01:34.620984 | localhost | skipping: Conditional result was False 2026-04-16 18:01:34.621582 | 2026-04-16 18:01:34.626095 | localhost | skipping: Conditional result was False 2026-04-16 18:01:34.633649 | 2026-04-16 18:01:34.633936 | LOOP [upload-logs : Upload console log and json output]