2025-04-28 17:22:12.429763 | Job console starting... 2025-04-28 17:22:12.442383 | Updating repositories 2025-04-28 17:22:12.469645 | Preparing job workspace 2025-04-28 17:22:13.872800 | Running Ansible setup... 2025-04-28 17:22:19.192779 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-04-28 17:22:19.905375 | 2025-04-28 17:22:19.905541 | PLAY [Base pre] 2025-04-28 17:22:19.935331 | 2025-04-28 17:22:19.935463 | TASK [Setup log path fact] 2025-04-28 17:22:19.967360 | ubuntu-noble-large | ok 2025-04-28 17:22:19.987354 | 2025-04-28 17:22:19.987484 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-28 17:22:20.018652 | ubuntu-noble-large | ok 2025-04-28 17:22:20.031947 | 2025-04-28 17:22:20.032063 | TASK [emit-job-header : Print job information] 2025-04-28 17:22:20.090393 | # Job Information 2025-04-28 17:22:20.090640 | Ansible Version: 2.15.3 2025-04-28 17:22:20.090737 | Job: openstack-ironic-images-build-metalbox 2025-04-28 17:22:20.090790 | Pipeline: check 2025-04-28 17:22:20.090824 | Executor: 7d211f194f6a 2025-04-28 17:22:20.090854 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/37 2025-04-28 17:22:20.090883 | Event ID: 398cbc30-2455-11f0-91ce-9015a306e257 2025-04-28 17:22:20.098977 | 2025-04-28 17:22:20.099101 | LOOP [emit-job-header : Print node information] 2025-04-28 17:22:20.259579 | ubuntu-noble-large | ok: 2025-04-28 17:22:20.259877 | ubuntu-noble-large | # Node Information 2025-04-28 17:22:20.259927 | ubuntu-noble-large | Inventory Hostname: ubuntu-noble-large 2025-04-28 17:22:20.259962 | ubuntu-noble-large | Hostname: ubuntu 2025-04-28 17:22:20.259993 | ubuntu-noble-large | Username: zuul 2025-04-28 17:22:20.260022 | ubuntu-noble-large | Distro: Ubuntu 24.04 2025-04-28 17:22:20.260055 | ubuntu-noble-large | Provider: regiocloud-a 2025-04-28 17:22:20.260083 | ubuntu-noble-large | Label: ubuntu-noble-large 2025-04-28 17:22:20.260113 | ubuntu-noble-large | Product Name: OpenStack Nova 2025-04-28 17:22:20.260141 | ubuntu-noble-large | Interface IP: 81.163.192.49 2025-04-28 17:22:20.276947 | 2025-04-28 17:22:20.277072 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-04-28 17:22:20.762553 | ubuntu-noble-large -> localhost | changed 2025-04-28 17:22:20.771624 | 2025-04-28 17:22:20.771784 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-04-28 17:22:21.828353 | ubuntu-noble-large -> localhost | changed 2025-04-28 17:22:21.853209 | 2025-04-28 17:22:21.853344 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-04-28 17:22:22.165264 | ubuntu-noble-large -> localhost | ok 2025-04-28 17:22:22.181067 | 2025-04-28 17:22:22.181223 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-04-28 17:22:22.230389 | ubuntu-noble-large | ok 2025-04-28 17:22:22.249817 | ubuntu-noble-large | included: /var/lib/zuul/builds/df97270f65d94c7b8479759051339f09/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-04-28 17:22:22.257495 | 2025-04-28 17:22:22.257593 | TASK [add-build-sshkey : Create Temp SSH key] 2025-04-28 17:22:23.374143 | ubuntu-noble-large -> localhost | Generating public/private rsa key pair. 2025-04-28 17:22:23.374489 | ubuntu-noble-large -> localhost | Your identification has been saved in /var/lib/zuul/builds/df97270f65d94c7b8479759051339f09/work/df97270f65d94c7b8479759051339f09_id_rsa 2025-04-28 17:22:23.374551 | ubuntu-noble-large -> localhost | Your public key has been saved in /var/lib/zuul/builds/df97270f65d94c7b8479759051339f09/work/df97270f65d94c7b8479759051339f09_id_rsa.pub 2025-04-28 17:22:23.374593 | ubuntu-noble-large -> localhost | The key fingerprint is: 2025-04-28 17:22:23.374632 | ubuntu-noble-large -> localhost | SHA256:a+/iPV4/SXw1YQIBHYHnBpbwfbzIuYSVyIBmNVbxCig zuul-build-sshkey 2025-04-28 17:22:23.374668 | ubuntu-noble-large -> localhost | The key's randomart image is: 2025-04-28 17:22:23.374718 | ubuntu-noble-large -> localhost | +---[RSA 3072]----+ 2025-04-28 17:22:23.374753 | ubuntu-noble-large -> localhost | | o*o+*== | 2025-04-28 17:22:23.374799 | ubuntu-noble-large -> localhost | | +o ==+oo. o | 2025-04-28 17:22:23.374832 | ubuntu-noble-large -> localhost | | Eo. ..++= oo .| 2025-04-28 17:22:23.374864 | ubuntu-noble-large -> localhost | | . . =o+ ...| 2025-04-28 17:22:23.374895 | ubuntu-noble-large -> localhost | | So.= o o| 2025-04-28 17:22:23.374927 | ubuntu-noble-large -> localhost | | .. . o .| 2025-04-28 17:22:23.374969 | ubuntu-noble-large -> localhost | | o ... o | 2025-04-28 17:22:23.375003 | ubuntu-noble-large -> localhost | | ..o.. .o | 2025-04-28 17:22:23.375035 | ubuntu-noble-large -> localhost | | ..+=. .. | 2025-04-28 17:22:23.375068 | ubuntu-noble-large -> localhost | +----[SHA256]-----+ 2025-04-28 17:22:23.375143 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.615385 2025-04-28 17:22:23.385974 | 2025-04-28 17:22:23.386096 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-04-28 17:22:23.421460 | ubuntu-noble-large | ok 2025-04-28 17:22:23.436661 | ubuntu-noble-large | included: /var/lib/zuul/builds/df97270f65d94c7b8479759051339f09/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-04-28 17:22:23.446284 | 2025-04-28 17:22:23.446380 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-04-28 17:22:23.480804 | ubuntu-noble-large | skipping: Conditional result was False 2025-04-28 17:22:23.490889 | 2025-04-28 17:22:23.491015 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-04-28 17:22:24.175501 | ubuntu-noble-large | changed 2025-04-28 17:22:24.185061 | 2025-04-28 17:22:24.185188 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-04-28 17:22:24.502613 | ubuntu-noble-large | ok 2025-04-28 17:22:24.509991 | 2025-04-28 17:22:24.510105 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-04-28 17:22:25.255445 | ubuntu-noble-large | changed 2025-04-28 17:22:25.264024 | 2025-04-28 17:22:25.264151 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-04-28 17:22:25.997009 | ubuntu-noble-large | changed 2025-04-28 17:22:26.006790 | 2025-04-28 17:22:26.006920 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-04-28 17:22:26.032174 | ubuntu-noble-large | skipping: Conditional result was False 2025-04-28 17:22:26.049359 | 2025-04-28 17:22:26.049531 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-04-28 17:22:26.503905 | ubuntu-noble-large -> localhost | changed 2025-04-28 17:22:26.519054 | 2025-04-28 17:22:26.519185 | TASK [add-build-sshkey : Add back temp key] 2025-04-28 17:22:26.851826 | ubuntu-noble-large -> localhost | Identity added: /var/lib/zuul/builds/df97270f65d94c7b8479759051339f09/work/df97270f65d94c7b8479759051339f09_id_rsa (zuul-build-sshkey) 2025-04-28 17:22:26.852067 | ubuntu-noble-large -> localhost | ok: Runtime: 0:00:00.012599 2025-04-28 17:22:26.868873 | 2025-04-28 17:22:26.869015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-04-28 17:22:27.327290 | ubuntu-noble-large | ok 2025-04-28 17:22:27.336050 | 2025-04-28 17:22:27.336206 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-04-28 17:22:27.372569 | ubuntu-noble-large | skipping: Conditional result was False 2025-04-28 17:22:27.425840 | 2025-04-28 17:22:27.425975 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-04-28 17:22:27.842770 | ubuntu-noble-large | ok 2025-04-28 17:22:27.858109 | 2025-04-28 17:22:27.858234 | TASK [validate-host : Define zuul_info_dir fact] 2025-04-28 17:22:27.899463 | ubuntu-noble-large | ok 2025-04-28 17:22:27.906221 | 2025-04-28 17:22:27.906342 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-04-28 17:22:28.185685 | ubuntu-noble-large -> localhost | ok 2025-04-28 17:22:28.195053 | 2025-04-28 17:22:28.195268 | TASK [validate-host : Collect information about the host] 2025-04-28 17:22:29.457245 | ubuntu-noble-large | ok 2025-04-28 17:22:29.480773 | 2025-04-28 17:22:29.480982 | TASK [validate-host : Sanitize hostname] 2025-04-28 17:22:29.564785 | ubuntu-noble-large | ok 2025-04-28 17:22:29.575092 | 2025-04-28 17:22:29.575256 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-04-28 17:22:30.166254 | ubuntu-noble-large -> localhost | changed 2025-04-28 17:22:30.181380 | 2025-04-28 17:22:30.181546 | TASK [validate-host : Collect information about zuul worker] 2025-04-28 17:22:30.690435 | ubuntu-noble-large | ok 2025-04-28 17:22:30.705272 | 2025-04-28 17:22:30.705454 | TASK [validate-host : Write out all zuul information for each host] 2025-04-28 17:22:31.285493 | ubuntu-noble-large -> localhost | changed 2025-04-28 17:22:31.309808 | 2025-04-28 17:22:31.309958 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-04-28 17:22:31.614783 | ubuntu-noble-large | ok 2025-04-28 17:22:31.625273 | 2025-04-28 17:22:31.625403 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-04-28 17:22:32.488797 | ubuntu-noble-large | changed: 2025-04-28 17:22:32.489091 | ubuntu-noble-large | cd+++++++++ src/ 2025-04-28 17:22:32.489155 | ubuntu-noble-large | cd+++++++++ src/github.com/ 2025-04-28 17:22:32.489199 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/ 2025-04-28 17:22:32.489237 | ubuntu-noble-large | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-04-28 17:22:32.489271 | ubuntu-noble-large | 2025-04-28 17:23:08.022884 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | sys.exit(main()) 2025-04-28 17:23:08.022898 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | ^^^^^^ 2025-04-28 17:23:08.022917 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/element_dependencies.py", line 343, in main 2025-04-28 17:23:08.046356 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | elements = _get_elements(args.elements) 2025-04-28 17:23:08.046449 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2025-04-28 17:23:08.046465 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/element_dependencies.py", line 254, in _get_elements 2025-04-28 17:23:08.046479 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | return _expand_element_dependencies(elements, all_elements) 2025-04-28 17:23:08.046522 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 2025-04-28 17:23:08.046536 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | File "/tmp/venv/lib/python3.12/site-packages/diskimage_builder/element_dependencies.py", line 149, in _expand_element_dependencies 2025-04-28 17:23:08.046550 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | raise MissingElementException("Element '%s' not found" % element) 2025-04-28 17:23:08.046567 | ubuntu-noble-large | 2025-04-28 17:23:08.022 | diskimage_builder.element_dependencies.MissingElementException: Element 'metalbox' not found 2025-04-28 17:23:08.427891 | ubuntu-noble-large | ERROR 2025-04-28 17:23:08.428570 | ubuntu-noble-large | { 2025-04-28 17:23:08.428745 | ubuntu-noble-large | "delta": "0:00:00.633283", 2025-04-28 17:23:08.428825 | ubuntu-noble-large | "end": "2025-04-28 17:23:08.047270", 2025-04-28 17:23:08.428887 | ubuntu-noble-large | "msg": "non-zero return code", 2025-04-28 17:23:08.428944 | ubuntu-noble-large | "rc": 1, 2025-04-28 17:23:08.428998 | ubuntu-noble-large | "start": "2025-04-28 17:23:07.413987" 2025-04-28 17:23:08.429053 | ubuntu-noble-large | } failure 2025-04-28 17:23:08.435985 | 2025-04-28 17:23:08.436328 | PLAY RECAP 2025-04-28 17:23:08.436538 | ubuntu-noble-large | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2025-04-28 17:23:08.436616 | 2025-04-28 17:23:08.596992 | RUN END RESULT_NORMAL: [untrusted : github.com/osism/openstack-ironic-images/playbooks/build.yml@main] 2025-04-28 17:23:08.604907 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-04-28 17:23:09.315829 | 2025-04-28 17:23:09.315993 | PLAY [Base post] 2025-04-28 17:23:09.342289 | 2025-04-28 17:23:09.342440 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-04-28 17:23:09.974766 | ubuntu-noble-large | changed 2025-04-28 17:23:10.008908 | 2025-04-28 17:23:10.009004 | PLAY RECAP 2025-04-28 17:23:10.009065 | ubuntu-noble-large | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-04-28 17:23:10.009127 | 2025-04-28 17:23:10.090291 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-04-28 17:23:10.097154 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-04-28 17:23:10.767835 | 2025-04-28 17:23:10.767960 | PLAY [Base post-logs] 2025-04-28 17:23:10.782359 | 2025-04-28 17:23:10.782466 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-04-28 17:23:11.201300 | localhost | changed 2025-04-28 17:23:11.207941 | 2025-04-28 17:23:11.208119 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-04-28 17:23:11.239150 | localhost | ok 2025-04-28 17:23:11.245406 | 2025-04-28 17:23:11.245512 | TASK [Set zuul-log-path fact] 2025-04-28 17:23:11.272946 | localhost | ok 2025-04-28 17:23:11.283607 | 2025-04-28 17:23:11.283773 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-28 17:23:11.324317 | localhost | ok 2025-04-28 17:23:11.335281 | 2025-04-28 17:23:11.335431 | TASK [upload-logs : Create log directories] 2025-04-28 17:23:11.842244 | localhost | changed 2025-04-28 17:23:11.850327 | 2025-04-28 17:23:11.850482 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-04-28 17:23:12.347416 | localhost -> localhost | ok: Runtime: 0:00:00.007264 2025-04-28 17:23:12.353142 | 2025-04-28 17:23:12.353258 | TASK [upload-logs : Upload logs to log server] 2025-04-28 17:23:12.914849 | localhost | Output suppressed because no_log was given 2025-04-28 17:23:12.921662 | 2025-04-28 17:23:12.921853 | LOOP [upload-logs : Compress console log and json output] 2025-04-28 17:23:12.996996 | localhost | skipping: Conditional result was False 2025-04-28 17:23:13.025978 | localhost | skipping: Conditional result was False 2025-04-28 17:23:13.039091 | 2025-04-28 17:23:13.039280 | LOOP [upload-logs : Upload compressed console log and json output] 2025-04-28 17:23:13.105580 | localhost | skipping: Conditional result was False 2025-04-28 17:23:13.106268 | 2025-04-28 17:23:13.118344 | localhost | skipping: Conditional result was False 2025-04-28 17:23:13.129256 | 2025-04-28 17:23:13.129478 | LOOP [upload-logs : Upload console log and json output]