2026-03-11 06:59:57.174249 | Job console starting 2026-03-11 06:59:57.186173 | Updating git repos 2026-03-11 06:59:57.209457 | Cloning repos into workspace 2026-03-11 06:59:57.257317 | Restoring repo states 2026-03-11 06:59:57.265291 | Merging changes 2026-03-11 06:59:57.776326 | Checking out repos 2026-03-11 06:59:57.821112 | Preparing playbooks 2026-03-11 06:59:58.442775 | Running Ansible setup 2026-03-11 07:00:04.212232 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-03-11 07:00:04.989610 | 2026-03-11 07:00:04.989775 | PLAY [Base pre] 2026-03-11 07:00:05.007151 | 2026-03-11 07:00:05.007302 | TASK [Setup log path fact] 2026-03-11 07:00:05.037523 | noble | ok 2026-03-11 07:00:05.055379 | 2026-03-11 07:00:05.055594 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 07:00:05.103275 | noble | ok 2026-03-11 07:00:05.120185 | 2026-03-11 07:00:05.120338 | TASK [emit-job-header : Print job information] 2026-03-11 07:00:05.179506 | # Job Information 2026-03-11 07:00:05.179793 | Ansible Version: 2.16.14 2026-03-11 07:00:05.179857 | Job: openstack-ironic-images-build-osism-ipa-stable 2026-03-11 07:00:05.179919 | Pipeline: check 2026-03-11 07:00:05.179961 | Executor: 521e9411259a 2026-03-11 07:00:05.179999 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/196 2026-03-11 07:00:05.180038 | Event ID: 7f7bf740-1d17-11f1-9ece-bbfd27cbd62b 2026-03-11 07:00:05.190121 | 2026-03-11 07:00:05.190265 | LOOP [emit-job-header : Print node information] 2026-03-11 07:00:05.333731 | noble | ok: 2026-03-11 07:00:05.334016 | noble | # Node Information 2026-03-11 07:00:05.334070 | noble | Inventory Hostname: noble 2026-03-11 07:00:05.334112 | noble | Hostname: ubuntu 2026-03-11 07:00:05.334148 | noble | Username: zuul 2026-03-11 07:00:05.334182 | noble | Distro: Ubuntu 24.04 2026-03-11 07:00:05.334220 | noble | Provider: regiocloud-a 2026-03-11 07:00:05.334254 | noble | Region: 2026-03-11 07:00:05.334289 | noble | Label: ubuntu-noble-uefi 2026-03-11 07:00:05.334322 | noble | Product Name: OpenStack Nova 2026-03-11 07:00:05.334354 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef6:1d89 2026-03-11 07:00:05.362993 | 2026-03-11 07:00:05.363142 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-11 07:00:05.839645 | noble -> localhost | changed 2026-03-11 07:00:05.856195 | 2026-03-11 07:00:05.856353 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-11 07:00:06.927250 | noble -> localhost | changed 2026-03-11 07:00:06.950016 | 2026-03-11 07:00:06.950148 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-11 07:00:07.257870 | noble -> localhost | ok 2026-03-11 07:00:07.277020 | 2026-03-11 07:00:07.277285 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-11 07:00:07.319166 | noble | ok 2026-03-11 07:00:07.343981 | noble | included: /var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-11 07:00:07.352201 | 2026-03-11 07:00:07.352311 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-11 07:00:08.508690 | noble -> localhost | Generating public/private rsa key pair. 2026-03-11 07:00:08.508925 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/work/732d4cdeaa354e0ab6f5376ee18ebf3e_id_rsa 2026-03-11 07:00:08.508964 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/work/732d4cdeaa354e0ab6f5376ee18ebf3e_id_rsa.pub 2026-03-11 07:00:08.508993 | noble -> localhost | The key fingerprint is: 2026-03-11 07:00:08.509021 | noble -> localhost | SHA256:jqgvONELlPuF7mL6wEfkyDXq838/e0RXmaZq9brlnBc zuul-build-sshkey 2026-03-11 07:00:08.509045 | noble -> localhost | The key's randomart image is: 2026-03-11 07:00:08.509067 | noble -> localhost | +---[RSA 3072]----+ 2026-03-11 07:00:08.509088 | noble -> localhost | | o| 2026-03-11 07:00:08.509123 | noble -> localhost | | = | 2026-03-11 07:00:08.509146 | noble -> localhost | | .+ + | 2026-03-11 07:00:08.509167 | noble -> localhost | |.o* . . + | 2026-03-11 07:00:08.509189 | noble -> localhost | |.=.o. S . + . | 2026-03-11 07:00:08.509213 | noble -> localhost | |=.o. o o + E | 2026-03-11 07:00:08.509233 | noble -> localhost | |.Booo . . o ...| 2026-03-11 07:00:08.509254 | noble -> localhost | |o+*+ . . . .+ o| 2026-03-11 07:00:08.509276 | noble -> localhost | |+o==o.. .o+ ..+.| 2026-03-11 07:00:08.509297 | noble -> localhost | +----[SHA256]-----+ 2026-03-11 07:00:08.509348 | noble -> localhost | ok: Runtime: 0:00:00.643107 2026-03-11 07:00:08.516994 | 2026-03-11 07:00:08.517126 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-11 07:00:08.548566 | noble | ok 2026-03-11 07:00:08.558530 | noble | included: /var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-11 07:00:08.567893 | 2026-03-11 07:00:08.567990 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-11 07:00:08.591852 | noble | skipping: Conditional result was False 2026-03-11 07:00:08.599550 | 2026-03-11 07:00:08.599653 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-11 07:00:09.224765 | noble | changed 2026-03-11 07:00:09.234763 | 2026-03-11 07:00:09.234936 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-11 07:00:09.555587 | noble | ok 2026-03-11 07:00:09.564147 | 2026-03-11 07:00:09.564277 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-11 07:00:10.353187 | noble | changed 2026-03-11 07:00:10.362696 | 2026-03-11 07:00:10.362830 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-11 07:00:11.183512 | noble | changed 2026-03-11 07:00:11.194136 | 2026-03-11 07:00:11.194276 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-11 07:00:11.220833 | noble | skipping: Conditional result was False 2026-03-11 07:00:11.231227 | 2026-03-11 07:00:11.231368 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-11 07:00:11.683144 | noble -> localhost | changed 2026-03-11 07:00:11.708874 | 2026-03-11 07:00:11.709033 | TASK [add-build-sshkey : Add back temp key] 2026-03-11 07:00:12.080916 | noble -> localhost | Identity added: /var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/work/732d4cdeaa354e0ab6f5376ee18ebf3e_id_rsa (zuul-build-sshkey) 2026-03-11 07:00:12.083082 | noble -> localhost | ok: Runtime: 0:00:00.019571 2026-03-11 07:00:12.101246 | 2026-03-11 07:00:12.101423 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-11 07:00:12.578947 | noble | ok 2026-03-11 07:00:12.588287 | 2026-03-11 07:00:12.588432 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-11 07:00:12.614472 | noble | skipping: Conditional result was False 2026-03-11 07:00:12.674091 | 2026-03-11 07:00:12.674230 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-11 07:00:13.099874 | noble | ok 2026-03-11 07:00:13.114919 | 2026-03-11 07:00:13.115100 | TASK [validate-host : Define zuul_info_dir fact] 2026-03-11 07:00:13.160868 | noble | ok 2026-03-11 07:00:13.173718 | 2026-03-11 07:00:13.173871 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-03-11 07:00:13.513390 | noble -> localhost | ok 2026-03-11 07:00:13.522238 | 2026-03-11 07:00:13.522367 | TASK [validate-host : Collect information about the host] 2026-03-11 07:00:14.968237 | noble | ok 2026-03-11 07:00:14.985361 | 2026-03-11 07:00:14.985540 | TASK [validate-host : Sanitize hostname] 2026-03-11 07:00:15.050517 | noble | ok 2026-03-11 07:00:15.059706 | 2026-03-11 07:00:15.059890 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-03-11 07:00:15.602476 | noble -> localhost | changed 2026-03-11 07:00:15.615217 | 2026-03-11 07:00:15.615380 | TASK [validate-host : Collect information about zuul worker] 2026-03-11 07:00:16.100851 | noble | ok 2026-03-11 07:00:16.109859 | 2026-03-11 07:00:16.110028 | TASK [validate-host : Write out all zuul information for each host] 2026-03-11 07:00:16.642233 | noble -> localhost | changed 2026-03-11 07:00:16.657870 | 2026-03-11 07:00:16.658027 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-03-11 07:00:16.982935 | noble | ok 2026-03-11 07:00:16.996238 | 2026-03-11 07:00:16.996423 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-03-11 07:00:17.964525 | noble | changed: 2026-03-11 07:00:17.964792 | noble | cd+++++++++ src/ 2026-03-11 07:00:17.964847 | noble | cd+++++++++ src/github.com/ 2026-03-11 07:00:17.964901 | noble | cd+++++++++ src/github.com/osism/ 2026-03-11 07:00:17.964935 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-03-11 07:00:17.964966 | noble | 2026-03-11 07:01:39.876630 | noble | sys.exit(main()) 2026-03-11 07:01:39.876733 | noble | ^^^^^^ 2026-03-11 07:01:39.876753 | noble | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/diskimage_builder.py", line 572, in main 2026-03-11 07:01:39.877190 | noble | raise subprocess.CalledProcessError( 2026-03-11 07:01:39.877268 | noble | subprocess.CalledProcessError: Command '['bash', '/tmp/venv/lib/python3.12/site-packages/diskimage_builder/lib/disk-image-create', '-o', 'osism-ipa-stable', '-a', 'amd64', '--logfile', '/home/zuul/zuul-output/logs/osism-ipa-stable.log', '--checksum', '--image-size', '5', 'ubuntu', 'ironic-python-agent-ramdisk', 'burn-in', 'openssh-server', 'devuser', 'osism-ipa']' returned non-zero exit status 1. 2026-03-11 07:01:40.175442 | noble | ERROR 2026-03-11 07:01:40.175920 | noble | { 2026-03-11 07:01:40.176029 | noble | "delta": "0:00:28.982953", 2026-03-11 07:01:40.176097 | noble | "end": "2026-03-11 07:01:39.913285", 2026-03-11 07:01:40.176156 | noble | "msg": "non-zero return code", 2026-03-11 07:01:40.176210 | noble | "rc": 1, 2026-03-11 07:01:40.176263 | noble | "start": "2026-03-11 07:01:10.930332" 2026-03-11 07:01:40.176323 | noble | } failure 2026-03-11 07:01:40.180437 | 2026-03-11 07:01:40.180615 | PLAY RECAP 2026-03-11 07:01:40.180742 | noble | ok: 1 changed: 1 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-11 07:01:40.180801 | 2026-03-11 07:01:40.309654 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openstack-ironic-images/playbooks/builder.yml@main] 2026-03-11 07:01:40.311145 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-03-11 07:01:41.050252 | 2026-03-11 07:01:41.050451 | PLAY [Base post-fetch] 2026-03-11 07:01:41.069605 | 2026-03-11 07:01:41.069778 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-11 07:01:41.126550 | noble | skipping: Conditional result was False 2026-03-11 07:01:41.140079 | 2026-03-11 07:01:41.140294 | TASK [fetch-output : Set log path for single node] 2026-03-11 07:01:41.189597 | noble | ok 2026-03-11 07:01:41.197698 | 2026-03-11 07:01:41.197829 | LOOP [fetch-output : Ensure local output dirs] 2026-03-11 07:01:41.689515 | noble -> localhost | ok: "/var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/work/logs" 2026-03-11 07:01:41.993361 | noble -> localhost | changed: "/var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/work/artifacts" 2026-03-11 07:01:42.273619 | noble -> localhost | changed: "/var/lib/zuul/builds/732d4cdeaa354e0ab6f5376ee18ebf3e/work/docs" 2026-03-11 07:01:42.299254 | 2026-03-11 07:01:42.299442 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-11 07:01:43.603216 | noble | changed: 2026-03-11 07:01:43.603586 | noble | .d..t...... ./ 2026-03-11 07:01:43.603657 | noble | >f+++++++++ osism-ipa-stable.log 2026-03-11 07:01:43.603739 | noble | changed: All items complete 2026-03-11 07:01:43.603811 | 2026-03-11 07:01:44.270942 | noble | changed: .d..t...... ./ 2026-03-11 07:01:44.942514 | noble | changed: .d..t...... ./ 2026-03-11 07:01:44.971276 | 2026-03-11 07:01:44.971445 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-11 07:01:45.507893 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.012485 2026-03-11 07:01:45.800511 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010175 2026-03-11 07:01:45.811084 | 2026-03-11 07:01:45.811179 | PLAY RECAP 2026-03-11 07:01:45.811234 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-11 07:01:45.811260 | 2026-03-11 07:01:45.937999 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-03-11 07:01:45.941084 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 07:01:46.681749 | 2026-03-11 07:01:46.681911 | PLAY [Base post] 2026-03-11 07:01:46.696217 | 2026-03-11 07:01:46.696359 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-11 07:01:47.343061 | noble | changed 2026-03-11 07:01:47.352937 | 2026-03-11 07:01:47.353071 | PLAY RECAP 2026-03-11 07:01:47.353138 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-11 07:01:47.353208 | 2026-03-11 07:01:47.477114 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-03-11 07:01:47.479366 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-11 07:01:48.251185 | 2026-03-11 07:01:48.251356 | PLAY [Base post-logs] 2026-03-11 07:01:48.261893 | 2026-03-11 07:01:48.262027 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-11 07:01:48.714217 | localhost | changed 2026-03-11 07:01:48.724274 | 2026-03-11 07:01:48.724432 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-11 07:01:48.760208 | localhost | ok 2026-03-11 07:01:48.764607 | 2026-03-11 07:01:48.764744 | TASK [Set zuul-log-path fact] 2026-03-11 07:01:48.783665 | localhost | ok 2026-03-11 07:01:48.797552 | 2026-03-11 07:01:48.797704 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-11 07:01:48.826399 | localhost | ok 2026-03-11 07:01:48.832147 | 2026-03-11 07:01:48.832317 | TASK [upload-logs : Create log directories] 2026-03-11 07:01:49.342717 | localhost | changed 2026-03-11 07:01:49.347507 | 2026-03-11 07:01:49.347678 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-11 07:01:49.848657 | localhost -> localhost | ok: Runtime: 0:00:00.007089 2026-03-11 07:01:49.852734 | 2026-03-11 07:01:49.852847 | TASK [upload-logs : Upload logs to log server] 2026-03-11 07:01:50.447564 | localhost | Output suppressed because no_log was given 2026-03-11 07:01:50.450211 | 2026-03-11 07:01:50.450354 | LOOP [upload-logs : Compress console log and json output] 2026-03-11 07:01:50.508412 | localhost | skipping: Conditional result was False 2026-03-11 07:01:50.513351 | localhost | skipping: Conditional result was False 2026-03-11 07:01:50.519484 | 2026-03-11 07:01:50.519695 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-11 07:01:50.565814 | localhost | skipping: Conditional result was False 2026-03-11 07:01:50.566572 | 2026-03-11 07:01:50.569829 | localhost | skipping: Conditional result was False 2026-03-11 07:01:50.583982 | 2026-03-11 07:01:50.584257 | LOOP [upload-logs : Upload console log and json output]