2026-04-16 09:00:23.062995 | Job console starting 2026-04-16 09:00:23.075902 | Updating git repos 2026-04-16 09:00:23.094005 | Cloning repos into workspace 2026-04-16 09:00:23.169839 | Restoring repo states 2026-04-16 09:00:23.176446 | Merging changes 2026-04-16 09:00:23.790751 | Checking out repos 2026-04-16 09:00:23.850696 | Preparing playbooks 2026-04-16 09:00:24.483545 | Running Ansible setup 2026-04-16 09:00:30.497522 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-16 09:00:31.390756 | 2026-04-16 09:00:31.390977 | PLAY [Base pre] 2026-04-16 09:00:31.408511 | 2026-04-16 09:00:31.408673 | TASK [Setup log path fact] 2026-04-16 09:00:31.428809 | noble | ok 2026-04-16 09:00:31.447455 | 2026-04-16 09:00:31.447636 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 09:00:31.477611 | noble | ok 2026-04-16 09:00:31.489955 | 2026-04-16 09:00:31.490092 | TASK [emit-job-header : Print job information] 2026-04-16 09:00:31.530417 | # Job Information 2026-04-16 09:00:31.530625 | Ansible Version: 2.16.14 2026-04-16 09:00:31.530661 | Job: openstack-ironic-images-build-osism-esp 2026-04-16 09:00:31.530693 | Pipeline: check 2026-04-16 09:00:31.530716 | Executor: 521e9411259a 2026-04-16 09:00:31.530736 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/200 2026-04-16 09:00:31.530759 | Event ID: 46f6f240-3972-11f1-8ff7-62f27c5eeda0 2026-04-16 09:00:31.537911 | 2026-04-16 09:00:31.538069 | LOOP [emit-job-header : Print node information] 2026-04-16 09:00:31.670821 | noble | ok: 2026-04-16 09:00:31.671137 | noble | # Node Information 2026-04-16 09:00:31.671175 | noble | Inventory Hostname: noble 2026-04-16 09:00:31.671200 | noble | Hostname: ubuntu 2026-04-16 09:00:31.671222 | noble | Username: zuul 2026-04-16 09:00:31.671243 | noble | Distro: Ubuntu 24.04 2026-04-16 09:00:31.671287 | noble | Provider: regiocloud-a 2026-04-16 09:00:31.671310 | noble | Region: 2026-04-16 09:00:31.671332 | noble | Label: ubuntu-noble-uefi 2026-04-16 09:00:31.671353 | noble | Product Name: OpenStack Nova 2026-04-16 09:00:31.671374 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5e:7bcb 2026-04-16 09:00:31.690747 | 2026-04-16 09:00:31.690992 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-16 09:00:32.354537 | noble -> localhost | changed 2026-04-16 09:00:32.362722 | 2026-04-16 09:00:32.362881 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-16 09:00:33.769009 | noble -> localhost | changed 2026-04-16 09:00:33.796719 | 2026-04-16 09:00:33.796874 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-16 09:00:34.246388 | noble -> localhost | ok 2026-04-16 09:00:34.254981 | 2026-04-16 09:00:34.255116 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-16 09:00:34.285967 | noble | ok 2026-04-16 09:00:34.306043 | noble | included: /var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-16 09:00:34.315277 | 2026-04-16 09:00:34.315402 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-16 09:00:36.188246 | noble -> localhost | Generating public/private rsa key pair. 2026-04-16 09:00:36.188501 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/work/4aba7d3f220a4dddad89af948cfcc855_id_rsa 2026-04-16 09:00:36.188538 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/work/4aba7d3f220a4dddad89af948cfcc855_id_rsa.pub 2026-04-16 09:00:36.188566 | noble -> localhost | The key fingerprint is: 2026-04-16 09:00:36.188593 | noble -> localhost | SHA256:Lj5DI/q3jnjUJu3BfEz3KOwkjEM9vbOJXOHle58C7i4 zuul-build-sshkey 2026-04-16 09:00:36.188617 | noble -> localhost | The key's randomart image is: 2026-04-16 09:00:36.188638 | noble -> localhost | +---[RSA 3072]----+ 2026-04-16 09:00:36.188659 | noble -> localhost | | | 2026-04-16 09:00:36.188694 | noble -> localhost | | | 2026-04-16 09:00:36.188717 | noble -> localhost | | . . | 2026-04-16 09:00:36.188738 | noble -> localhost | | . o + o | 2026-04-16 09:00:36.188758 | noble -> localhost | | . B *S* o | 2026-04-16 09:00:36.188783 | noble -> localhost | | * %.@ + . | 2026-04-16 09:00:36.188804 | noble -> localhost | | o O.X.* o | 2026-04-16 09:00:36.188824 | noble -> localhost | | ...oB.E o o . | 2026-04-16 09:00:36.188845 | noble -> localhost | | .ooo++ +o. oo | 2026-04-16 09:00:36.188866 | noble -> localhost | +----[SHA256]-----+ 2026-04-16 09:00:36.188917 | noble -> localhost | ok: Runtime: 0:00:01.314170 2026-04-16 09:00:36.197117 | 2026-04-16 09:00:36.197237 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-16 09:00:36.227009 | noble | ok 2026-04-16 09:00:36.247552 | noble | included: /var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-16 09:00:36.267176 | 2026-04-16 09:00:36.267339 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-16 09:00:36.305569 | noble | skipping: Conditional result was False 2026-04-16 09:00:36.314884 | 2026-04-16 09:00:36.315015 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-16 09:00:37.004366 | noble | changed 2026-04-16 09:00:37.012119 | 2026-04-16 09:00:37.012239 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-16 09:00:37.306022 | noble | ok 2026-04-16 09:00:37.314788 | 2026-04-16 09:00:37.314955 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-16 09:00:38.044519 | noble | changed 2026-04-16 09:00:38.050967 | 2026-04-16 09:00:38.051083 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-16 09:00:38.804037 | noble | changed 2026-04-16 09:00:38.813889 | 2026-04-16 09:00:38.814031 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-16 09:00:38.848389 | noble | skipping: Conditional result was False 2026-04-16 09:00:38.855560 | 2026-04-16 09:00:38.855689 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-16 09:00:39.284371 | noble -> localhost | changed 2026-04-16 09:00:39.299547 | 2026-04-16 09:00:39.299685 | TASK [add-build-sshkey : Add back temp key] 2026-04-16 09:00:39.639342 | noble -> localhost | Identity added: /var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/work/4aba7d3f220a4dddad89af948cfcc855_id_rsa (zuul-build-sshkey) 2026-04-16 09:00:39.639633 | noble -> localhost | ok: Runtime: 0:00:00.009769 2026-04-16 09:00:39.655761 | 2026-04-16 09:00:39.655908 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-16 09:00:40.122766 | noble | ok 2026-04-16 09:00:40.140092 | 2026-04-16 09:00:40.140232 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-16 09:00:40.165232 | noble | skipping: Conditional result was False 2026-04-16 09:00:40.248348 | 2026-04-16 09:00:40.248493 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-16 09:00:40.690956 | noble | ok 2026-04-16 09:00:40.704136 | 2026-04-16 09:00:40.704288 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-16 09:00:40.759720 | noble | ok 2026-04-16 09:00:40.768340 | 2026-04-16 09:00:40.768456 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-16 09:00:41.157036 | noble -> localhost | ok 2026-04-16 09:00:41.165678 | 2026-04-16 09:00:41.165807 | TASK [validate-host : Collect information about the host] 2026-04-16 09:00:42.795331 | noble | ok 2026-04-16 09:00:42.824647 | 2026-04-16 09:00:42.824792 | TASK [validate-host : Sanitize hostname] 2026-04-16 09:00:42.896900 | noble | ok 2026-04-16 09:00:42.903023 | 2026-04-16 09:00:42.903163 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-16 09:00:43.570901 | noble -> localhost | changed 2026-04-16 09:00:43.577489 | 2026-04-16 09:00:43.577609 | TASK [validate-host : Collect information about zuul worker] 2026-04-16 09:00:44.174163 | noble | ok 2026-04-16 09:00:44.180911 | 2026-04-16 09:00:44.181044 | TASK [validate-host : Write out all zuul information for each host] 2026-04-16 09:00:44.755292 | noble -> localhost | changed 2026-04-16 09:00:44.776167 | 2026-04-16 09:00:44.776350 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-16 09:00:45.123569 | noble | ok 2026-04-16 09:00:45.133955 | 2026-04-16 09:00:45.134103 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-16 09:00:46.194190 | noble | changed: 2026-04-16 09:00:46.194657 | noble | cd+++++++++ src/ 2026-04-16 09:00:46.194735 | noble | cd+++++++++ src/github.com/ 2026-04-16 09:00:46.194788 | noble | cd+++++++++ src/github.com/osism/ 2026-04-16 09:00:46.194872 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-04-16 09:00:46.194923 | noble | 2026-04-16 09:22:30.186849 | noble | sys.exit(main()) 2026-04-16 09:22:30.186947 | noble | ^^^^^^ 2026-04-16 09:22:30.186969 | noble | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/diskimage_builder.py", line 572, in main 2026-04-16 09:22:30.187377 | noble | raise subprocess.CalledProcessError( 2026-04-16 09:22:30.187468 | noble | subprocess.CalledProcessError: Command '['bash', '/tmp/venv/lib/python3.12/site-packages/diskimage_builder/lib/disk-image-create', '-o', 'osism-esp', '-a', 'amd64', '-t', 'raw', '--logfile', '/home/zuul/zuul-output/logs/osism-esp.log', '--checksum', 'ubuntu', 'vm', 'osism-esp']' returned non-zero exit status 1. 2026-04-16 09:22:30.645641 | noble | ERROR 2026-04-16 09:22:30.645959 | noble | { 2026-04-16 09:22:30.646019 | noble | "delta": "0:16:02.725308", 2026-04-16 09:22:30.646057 | noble | "end": "2026-04-16 09:22:30.221326", 2026-04-16 09:22:30.646089 | noble | "msg": "non-zero return code", 2026-04-16 09:22:30.646119 | noble | "rc": 1, 2026-04-16 09:22:30.646149 | noble | "start": "2026-04-16 09:06:27.496018" 2026-04-16 09:22:30.646186 | noble | } failure 2026-04-16 09:22:30.652992 | 2026-04-16 09:22:30.653241 | PLAY RECAP 2026-04-16 09:22:30.653339 | noble | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 1 rescued: 0 ignored: 0 2026-04-16 09:22:30.653381 | 2026-04-16 09:22:30.815155 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openstack-ironic-images/playbooks/builder.yml@main] 2026-04-16 09:22:30.819924 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-16 09:22:31.643959 | 2026-04-16 09:22:31.644142 | PLAY [Base post-fetch] 2026-04-16 09:22:31.661959 | 2026-04-16 09:22:31.662122 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-16 09:22:31.718628 | noble | skipping: Conditional result was False 2026-04-16 09:22:31.727740 | 2026-04-16 09:22:31.727914 | TASK [fetch-output : Set log path for single node] 2026-04-16 09:22:31.775771 | noble | ok 2026-04-16 09:22:31.784660 | 2026-04-16 09:22:31.784824 | LOOP [fetch-output : Ensure local output dirs] 2026-04-16 09:22:32.277394 | noble -> localhost | ok: "/var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/work/logs" 2026-04-16 09:22:32.573587 | noble -> localhost | changed: "/var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/work/artifacts" 2026-04-16 09:22:32.860653 | noble -> localhost | changed: "/var/lib/zuul/builds/4aba7d3f220a4dddad89af948cfcc855/work/docs" 2026-04-16 09:22:32.882120 | 2026-04-16 09:22:32.882395 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-16 09:22:34.210274 | noble | changed: 2026-04-16 09:22:34.210598 | noble | .d..t...... ./ 2026-04-16 09:22:34.210650 | noble | >f+++++++++ osism-esp.log 2026-04-16 09:22:34.210727 | noble | changed: All items complete 2026-04-16 09:22:34.210772 | 2026-04-16 09:22:34.881582 | noble | changed: .d..t...... ./ 2026-04-16 09:22:35.563149 | noble | changed: .d..t...... ./ 2026-04-16 09:22:35.595950 | 2026-04-16 09:22:35.596177 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-16 09:22:36.166335 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.011021 2026-04-16 09:22:36.459520 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010545 2026-04-16 09:22:36.489781 | 2026-04-16 09:22:36.490063 | PLAY RECAP 2026-04-16 09:22:36.490219 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-16 09:22:36.490326 | 2026-04-16 09:22:36.650901 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-16 09:22:36.653200 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 09:22:37.442455 | 2026-04-16 09:22:37.442634 | PLAY [Base post] 2026-04-16 09:22:37.458082 | 2026-04-16 09:22:37.458256 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-16 09:22:38.096563 | noble | changed 2026-04-16 09:22:38.106546 | 2026-04-16 09:22:38.106666 | PLAY RECAP 2026-04-16 09:22:38.106735 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 09:22:38.106803 | 2026-04-16 09:22:38.228847 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 09:22:38.229845 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-16 09:22:39.051335 | 2026-04-16 09:22:39.051541 | PLAY [Base post-logs] 2026-04-16 09:22:39.067688 | 2026-04-16 09:22:39.067961 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-16 09:22:39.524429 | localhost | changed 2026-04-16 09:22:39.534525 | 2026-04-16 09:22:39.534677 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-16 09:22:39.571220 | localhost | ok 2026-04-16 09:22:39.575556 | 2026-04-16 09:22:39.575689 | TASK [Set zuul-log-path fact] 2026-04-16 09:22:39.592673 | localhost | ok 2026-04-16 09:22:39.603837 | 2026-04-16 09:22:39.603968 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 09:22:39.632214 | localhost | ok 2026-04-16 09:22:39.638910 | 2026-04-16 09:22:39.639086 | TASK [upload-logs : Create log directories] 2026-04-16 09:22:40.202449 | localhost | changed 2026-04-16 09:22:40.208391 | 2026-04-16 09:22:40.208555 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-16 09:22:40.751790 | localhost -> localhost | ok: Runtime: 0:00:00.007058 2026-04-16 09:22:40.760600 | 2026-04-16 09:22:40.760808 | TASK [upload-logs : Upload logs to log server] 2026-04-16 09:22:41.383737 | localhost | Output suppressed because no_log was given 2026-04-16 09:22:41.390012 | 2026-04-16 09:22:41.390332 | LOOP [upload-logs : Compress console log and json output] 2026-04-16 09:22:41.444033 | localhost | skipping: Conditional result was False 2026-04-16 09:22:41.448830 | localhost | skipping: Conditional result was False 2026-04-16 09:22:41.468489 | 2026-04-16 09:22:41.468732 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-16 09:22:41.515154 | localhost | skipping: Conditional result was False 2026-04-16 09:22:41.515777 | 2026-04-16 09:22:41.519063 | localhost | skipping: Conditional result was False 2026-04-16 09:22:41.533487 | 2026-04-16 09:22:41.533886 | LOOP [upload-logs : Upload console log and json output]