2025-09-25 15:59:48.944070 | Job console starting 2025-09-25 15:59:48.972654 | Updating git repos 2025-09-25 15:59:49.017138 | Cloning repos into workspace 2025-09-25 15:59:49.069189 | Restoring repo states 2025-09-25 15:59:49.087098 | Merging changes 2025-09-25 15:59:49.644844 | Checking out repos 2025-09-25 15:59:49.703224 | Preparing playbooks 2025-09-25 15:59:50.331394 | Running Ansible setup 2025-09-25 15:59:56.844981 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-25 15:59:57.703190 | 2025-09-25 15:59:57.703350 | PLAY [Base pre] 2025-09-25 15:59:57.747241 | 2025-09-25 15:59:57.747396 | TASK [Setup log path fact] 2025-09-25 15:59:57.767235 | noble | ok 2025-09-25 15:59:57.792206 | 2025-09-25 15:59:57.792360 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 15:59:57.825911 | noble | ok 2025-09-25 15:59:57.838377 | 2025-09-25 15:59:57.838504 | TASK [emit-job-header : Print job information] 2025-09-25 15:59:57.878234 | # Job Information 2025-09-25 15:59:57.878413 | Ansible Version: 2.16.14 2025-09-25 15:59:57.878448 | Job: openstack-ironic-images-build-osism-esp 2025-09-25 15:59:57.878480 | Pipeline: check 2025-09-25 15:59:57.878503 | Executor: 521e9411259a 2025-09-25 15:59:57.878524 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/150 2025-09-25 15:59:57.878546 | Event ID: 3be83b50-9a28-11f0-993b-5eb0aa7874f2 2025-09-25 15:59:57.887759 | 2025-09-25 15:59:57.887892 | LOOP [emit-job-header : Print node information] 2025-09-25 15:59:58.001921 | noble | ok: 2025-09-25 15:59:58.002227 | noble | # Node Information 2025-09-25 15:59:58.002267 | noble | Inventory Hostname: noble 2025-09-25 15:59:58.002292 | noble | Hostname: ubuntu 2025-09-25 15:59:58.002314 | noble | Username: zuul 2025-09-25 15:59:58.002335 | noble | Distro: Ubuntu 24.04 2025-09-25 15:59:58.002358 | noble | Provider: regiocloud-a 2025-09-25 15:59:58.002378 | noble | Region: 2025-09-25 15:59:58.002400 | noble | Label: ubuntu-noble-uefi 2025-09-25 15:59:58.002419 | noble | Product Name: OpenStack Nova 2025-09-25 15:59:58.002440 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe12:a478 2025-09-25 15:59:58.025688 | 2025-09-25 15:59:58.025834 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-25 15:59:58.488819 | noble -> localhost | changed 2025-09-25 15:59:58.499633 | 2025-09-25 15:59:58.499768 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-25 15:59:59.883017 | noble -> localhost | changed 2025-09-25 15:59:59.895235 | 2025-09-25 15:59:59.895340 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-25 16:00:00.335694 | noble -> localhost | ok 2025-09-25 16:00:00.342318 | 2025-09-25 16:00:00.342424 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-25 16:00:00.371732 | noble | ok 2025-09-25 16:00:00.387179 | noble | included: /var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-25 16:00:00.405632 | 2025-09-25 16:00:00.405733 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-25 16:00:01.730855 | noble -> localhost | Generating public/private rsa key pair. 2025-09-25 16:00:01.731077 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/work/ba0570bdc92f4664820be63fa1fd70b4_id_rsa 2025-09-25 16:00:01.731117 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/work/ba0570bdc92f4664820be63fa1fd70b4_id_rsa.pub 2025-09-25 16:00:01.731145 | noble -> localhost | The key fingerprint is: 2025-09-25 16:00:01.731172 | noble -> localhost | SHA256:VZoFZTEihzhmEI95/v3gRQUdsHQmi2KGou7uBDEuz5Q zuul-build-sshkey 2025-09-25 16:00:01.731195 | noble -> localhost | The key's randomart image is: 2025-09-25 16:00:01.731217 | noble -> localhost | +---[RSA 3072]----+ 2025-09-25 16:00:01.731238 | noble -> localhost | | oo ...+o%++. | 2025-09-25 16:00:01.731270 | noble -> localhost | | +=..o X O. | 2025-09-25 16:00:01.731293 | noble -> localhost | |o +o+.+ = o . | 2025-09-25 16:00:01.731313 | noble -> localhost | |.o o + o o . | 2025-09-25 16:00:01.731333 | noble -> localhost | |o.E . S . | 2025-09-25 16:00:01.731357 | noble -> localhost | |.* . . . | 2025-09-25 16:00:01.731377 | noble -> localhost | | = . o . | 2025-09-25 16:00:01.731397 | noble -> localhost | | o . + | 2025-09-25 16:00:01.731418 | noble -> localhost | | o+ . . | 2025-09-25 16:00:01.731438 | noble -> localhost | +----[SHA256]-----+ 2025-09-25 16:00:01.731488 | noble -> localhost | ok: Runtime: 0:00:00.880988 2025-09-25 16:00:01.738565 | 2025-09-25 16:00:01.738662 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-25 16:00:01.758479 | noble | ok 2025-09-25 16:00:01.769692 | noble | included: /var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-25 16:00:01.778578 | 2025-09-25 16:00:01.778668 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-25 16:00:01.811217 | noble | skipping: Conditional result was False 2025-09-25 16:00:01.818565 | 2025-09-25 16:00:01.818661 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-25 16:00:02.537111 | noble | changed 2025-09-25 16:00:02.543207 | 2025-09-25 16:00:02.543292 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-25 16:00:02.891039 | noble | ok 2025-09-25 16:00:02.899615 | 2025-09-25 16:00:02.899709 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-25 16:00:03.694820 | noble | changed 2025-09-25 16:00:03.702328 | 2025-09-25 16:00:03.702443 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-25 16:00:04.541438 | noble | changed 2025-09-25 16:00:04.561078 | 2025-09-25 16:00:04.561263 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-25 16:00:04.586215 | noble | skipping: Conditional result was False 2025-09-25 16:00:04.596770 | 2025-09-25 16:00:04.596914 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-25 16:00:05.060637 | noble -> localhost | changed 2025-09-25 16:00:05.098435 | 2025-09-25 16:00:05.098571 | TASK [add-build-sshkey : Add back temp key] 2025-09-25 16:00:05.440165 | noble -> localhost | Identity added: /var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/work/ba0570bdc92f4664820be63fa1fd70b4_id_rsa (zuul-build-sshkey) 2025-09-25 16:00:05.440404 | noble -> localhost | ok: Runtime: 0:00:00.016627 2025-09-25 16:00:05.447855 | 2025-09-25 16:00:05.447967 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-25 16:00:05.907707 | noble | ok 2025-09-25 16:00:05.914773 | 2025-09-25 16:00:05.914929 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-25 16:00:05.960318 | noble | skipping: Conditional result was False 2025-09-25 16:00:06.013675 | 2025-09-25 16:00:06.013812 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-25 16:00:06.454288 | noble | ok 2025-09-25 16:00:06.467329 | 2025-09-25 16:00:06.467457 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-25 16:00:06.507969 | noble | ok 2025-09-25 16:00:06.516240 | 2025-09-25 16:00:06.516374 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-25 16:00:06.803547 | noble -> localhost | ok 2025-09-25 16:00:06.812141 | 2025-09-25 16:00:06.812257 | TASK [validate-host : Collect information about the host] 2025-09-25 16:00:08.272529 | noble | ok 2025-09-25 16:00:08.286123 | 2025-09-25 16:00:08.286250 | TASK [validate-host : Sanitize hostname] 2025-09-25 16:00:08.362151 | noble | ok 2025-09-25 16:00:08.370476 | 2025-09-25 16:00:08.370609 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-25 16:00:09.035643 | noble -> localhost | changed 2025-09-25 16:00:09.042264 | 2025-09-25 16:00:09.042388 | TASK [validate-host : Collect information about zuul worker] 2025-09-25 16:00:09.537365 | noble | ok 2025-09-25 16:00:09.544463 | 2025-09-25 16:00:09.544586 | TASK [validate-host : Write out all zuul information for each host] 2025-09-25 16:00:10.112213 | noble -> localhost | changed 2025-09-25 16:00:10.133670 | 2025-09-25 16:00:10.133795 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-25 16:00:10.465030 | noble | ok 2025-09-25 16:00:10.472324 | 2025-09-25 16:00:10.472448 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-25 16:00:11.489990 | noble | changed: 2025-09-25 16:00:11.490221 | noble | cd+++++++++ src/ 2025-09-25 16:00:11.490257 | noble | cd+++++++++ src/github.com/ 2025-09-25 16:00:11.490281 | noble | cd+++++++++ src/github.com/osism/ 2025-09-25 16:00:11.490303 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-09-25 16:00:11.490324 | noble | localhost | ok: "/var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/work/logs" 2025-09-25 16:03:44.814508 | noble -> localhost | changed: "/var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/work/artifacts" 2025-09-25 16:03:45.065934 | noble -> localhost | changed: "/var/lib/zuul/builds/ba0570bdc92f4664820be63fa1fd70b4/work/docs" 2025-09-25 16:03:45.088601 | 2025-09-25 16:03:45.088752 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-09-25 16:03:46.429537 | noble | changed: 2025-09-25 16:03:46.429883 | noble | .d..t...... ./ 2025-09-25 16:03:46.429945 | noble | >f+++++++++ osism-esp.log 2025-09-25 16:03:46.430020 | noble | changed: All items complete 2025-09-25 16:03:46.430093 | 2025-09-25 16:03:47.094088 | noble | changed: .d..t...... ./ 2025-09-25 16:03:47.754466 | noble | changed: .d..t...... ./ 2025-09-25 16:03:47.786957 | 2025-09-25 16:03:47.787165 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-09-25 16:03:48.305481 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.009964 2025-09-25 16:03:48.602689 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009853 2025-09-25 16:03:48.621532 | 2025-09-25 16:03:48.621627 | PLAY RECAP 2025-09-25 16:03:48.621681 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-09-25 16:03:48.621706 | 2025-09-25 16:03:48.740289 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-09-25 16:03:48.741254 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-25 16:03:49.476447 | 2025-09-25 16:03:49.476595 | PLAY [Base post] 2025-09-25 16:03:49.491238 | 2025-09-25 16:03:49.491366 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-09-25 16:03:50.080013 | noble | changed 2025-09-25 16:03:50.087627 | 2025-09-25 16:03:50.087904 | PLAY RECAP 2025-09-25 16:03:50.088106 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-09-25 16:03:50.088278 | 2025-09-25 16:03:50.211018 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-09-25 16:03:50.213464 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-09-25 16:03:50.992799 | 2025-09-25 16:03:50.992969 | PLAY [Base post-logs] 2025-09-25 16:03:51.003485 | 2025-09-25 16:03:51.003620 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-09-25 16:03:51.465563 | localhost | changed 2025-09-25 16:03:51.476090 | 2025-09-25 16:03:51.476230 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-09-25 16:03:51.512502 | localhost | ok 2025-09-25 16:03:51.516943 | 2025-09-25 16:03:51.517082 | TASK [Set zuul-log-path fact] 2025-09-25 16:03:51.543873 | localhost | ok 2025-09-25 16:03:51.555948 | 2025-09-25 16:03:51.556125 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 16:03:51.593714 | localhost | ok 2025-09-25 16:03:51.600770 | 2025-09-25 16:03:51.600943 | TASK [upload-logs : Create log directories] 2025-09-25 16:03:52.111126 | localhost | changed 2025-09-25 16:03:52.115503 | 2025-09-25 16:03:52.115658 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-09-25 16:03:52.627453 | localhost -> localhost | ok: Runtime: 0:00:00.008216 2025-09-25 16:03:52.637271 | 2025-09-25 16:03:52.637487 | TASK [upload-logs : Upload logs to log server] 2025-09-25 16:03:53.235799 | localhost | Output suppressed because no_log was given 2025-09-25 16:03:53.239449 | 2025-09-25 16:03:53.239633 | LOOP [upload-logs : Compress console log and json output] 2025-09-25 16:03:53.293892 | localhost | skipping: Conditional result was False 2025-09-25 16:03:53.310188 | localhost | skipping: Conditional result was False 2025-09-25 16:03:53.321440 | 2025-09-25 16:03:53.321676 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-25 16:03:53.385634 | localhost | skipping: Conditional result was False 2025-09-25 16:03:53.386228 | 2025-09-25 16:03:53.390293 | localhost | skipping: Conditional result was False 2025-09-25 16:03:53.404912 | 2025-09-25 16:03:53.405228 | LOOP [upload-logs : Upload console log and json output]