2025-07-16 18:17:55.975299 | Job console starting 2025-07-16 18:17:55.981603 | Updating git repos 2025-07-16 18:17:56.039122 | Cloning repos into workspace 2025-07-16 18:17:56.130302 | Restoring repo states 2025-07-16 18:17:56.146686 | Merging changes 2025-07-16 18:17:57.188288 | Checking out repos 2025-07-16 18:17:57.259250 | Preparing playbooks 2025-07-16 18:17:58.745175 | Running Ansible setup 2025-07-16 18:18:08.020723 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-16 18:18:11.424862 | 2025-07-16 18:18:11.424982 | PLAY [Base pre] 2025-07-16 18:18:11.486497 | 2025-07-16 18:18:11.487162 | TASK [Setup log path fact] 2025-07-16 18:18:11.554757 | noble | ok 2025-07-16 18:18:11.615450 | 2025-07-16 18:18:11.615603 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-16 18:18:11.717859 | noble | ok 2025-07-16 18:18:11.736443 | 2025-07-16 18:18:11.736978 | TASK [emit-job-header : Print job information] 2025-07-16 18:18:11.836756 | # Job Information 2025-07-16 18:18:11.836895 | Ansible Version: 2.16.14 2025-07-16 18:18:11.836923 | Job: openstack-ironic-images-build-osism-ipa 2025-07-16 18:18:11.836950 | Pipeline: check 2025-07-16 18:18:11.837130 | Executor: 521e9411259a 2025-07-16 18:18:11.837316 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/108 2025-07-16 18:18:11.837358 | Event ID: b6a6f370-626c-11f0-9793-3505b73f66b3 2025-07-16 18:18:11.863724 | 2025-07-16 18:18:11.863830 | LOOP [emit-job-header : Print node information] 2025-07-16 18:18:12.657238 | noble | ok: 2025-07-16 18:18:12.657438 | noble | # Node Information 2025-07-16 18:18:12.657470 | noble | Inventory Hostname: noble 2025-07-16 18:18:12.657490 | noble | Hostname: ubuntu 2025-07-16 18:18:12.657508 | noble | Username: zuul 2025-07-16 18:18:12.657601 | noble | Distro: Ubuntu 24.04 2025-07-16 18:18:12.657623 | noble | Provider: regiocloud-a 2025-07-16 18:18:12.657640 | noble | Region: 2025-07-16 18:18:12.657659 | noble | Label: ubuntu-noble-uefi 2025-07-16 18:18:12.657676 | noble | Product Name: OpenStack Nova 2025-07-16 18:18:12.657692 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb0:17ef 2025-07-16 18:18:12.675019 | 2025-07-16 18:18:12.675119 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-16 18:18:14.423520 | noble -> localhost | changed 2025-07-16 18:18:14.429893 | 2025-07-16 18:18:14.429987 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-16 18:18:18.473922 | noble -> localhost | changed 2025-07-16 18:18:18.488938 | 2025-07-16 18:18:18.489033 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-16 18:18:19.077586 | noble -> localhost | ok 2025-07-16 18:18:19.083845 | 2025-07-16 18:18:19.083936 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-16 18:18:19.117735 | noble | ok 2025-07-16 18:18:19.173258 | noble | included: /var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-16 18:18:19.190104 | 2025-07-16 18:18:19.190204 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-16 18:18:21.554841 | noble -> localhost | Generating public/private rsa key pair. 2025-07-16 18:18:21.555020 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/work/ecd9e84e8a0d4ac2af07b40c11b97371_id_rsa 2025-07-16 18:18:21.555052 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/work/ecd9e84e8a0d4ac2af07b40c11b97371_id_rsa.pub 2025-07-16 18:18:21.555073 | noble -> localhost | The key fingerprint is: 2025-07-16 18:18:21.555095 | noble -> localhost | SHA256:fKN192wTs8sMO/GuUtnQP0t8W56aWLLZa6y85OZlCww zuul-build-sshkey 2025-07-16 18:18:21.555114 | noble -> localhost | The key's randomart image is: 2025-07-16 18:18:21.555131 | noble -> localhost | +---[RSA 3072]----+ 2025-07-16 18:18:21.555148 | noble -> localhost | | | 2025-07-16 18:18:21.555173 | noble -> localhost | | | 2025-07-16 18:18:21.555190 | noble -> localhost | | . | 2025-07-16 18:18:21.555207 | noble -> localhost | | . . . | 2025-07-16 18:18:21.555224 | noble -> localhost | | SE+ . *o.| 2025-07-16 18:18:21.555245 | noble -> localhost | | +oo = BB| 2025-07-16 18:18:21.555261 | noble -> localhost | | . =o=++@| 2025-07-16 18:18:21.555277 | noble -> localhost | | +o@+B*+| 2025-07-16 18:18:21.555294 | noble -> localhost | | oX=O*= | 2025-07-16 18:18:21.555311 | noble -> localhost | +----[SHA256]-----+ 2025-07-16 18:18:21.555351 | noble -> localhost | ok: Runtime: 0:00:00.798296 2025-07-16 18:18:21.561299 | 2025-07-16 18:18:21.561380 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-16 18:18:21.611648 | noble | ok 2025-07-16 18:18:21.654060 | noble | included: /var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-16 18:18:21.689003 | 2025-07-16 18:18:21.689107 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-16 18:18:21.793605 | noble | skipping: Conditional result was False 2025-07-16 18:18:21.805605 | 2025-07-16 18:18:21.805888 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-16 18:18:22.756932 | noble | changed 2025-07-16 18:18:22.762122 | 2025-07-16 18:18:22.762203 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-16 18:18:23.169310 | noble | ok 2025-07-16 18:18:23.211936 | 2025-07-16 18:18:23.212043 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-16 18:18:24.218050 | noble | changed 2025-07-16 18:18:24.226164 | 2025-07-16 18:18:24.226261 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-16 18:18:25.260097 | noble | changed 2025-07-16 18:18:25.272244 | 2025-07-16 18:18:25.273571 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-16 18:18:25.333390 | noble | skipping: Conditional result was False 2025-07-16 18:18:25.342487 | 2025-07-16 18:18:25.342597 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-16 18:18:26.824898 | noble -> localhost | changed 2025-07-16 18:18:26.836923 | 2025-07-16 18:18:26.837019 | TASK [add-build-sshkey : Add back temp key] 2025-07-16 18:18:27.646851 | noble -> localhost | Identity added: /var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/work/ecd9e84e8a0d4ac2af07b40c11b97371_id_rsa (zuul-build-sshkey) 2025-07-16 18:18:27.647217 | noble -> localhost | ok: Runtime: 0:00:00.021473 2025-07-16 18:18:27.656019 | 2025-07-16 18:18:27.656170 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-16 18:18:28.266170 | noble | ok 2025-07-16 18:18:28.272582 | 2025-07-16 18:18:28.272683 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-16 18:18:28.327724 | noble | skipping: Conditional result was False 2025-07-16 18:18:28.431883 | 2025-07-16 18:18:28.431977 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-16 18:18:29.027467 | noble | ok 2025-07-16 18:18:29.051842 | 2025-07-16 18:18:29.051941 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-16 18:18:29.116263 | noble | ok 2025-07-16 18:18:29.123056 | 2025-07-16 18:18:29.123144 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-16 18:18:29.755576 | noble -> localhost | ok 2025-07-16 18:18:29.761430 | 2025-07-16 18:18:29.761522 | TASK [validate-host : Collect information about the host] 2025-07-16 18:18:31.128334 | noble | ok 2025-07-16 18:18:31.158461 | 2025-07-16 18:18:31.158579 | TASK [validate-host : Sanitize hostname] 2025-07-16 18:18:31.282731 | noble | ok 2025-07-16 18:18:31.287215 | 2025-07-16 18:18:31.287295 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-16 18:18:32.037585 | noble -> localhost | changed 2025-07-16 18:18:32.045566 | 2025-07-16 18:18:32.045660 | TASK [validate-host : Collect information about zuul worker] 2025-07-16 18:18:32.601232 | noble | ok 2025-07-16 18:18:32.605679 | 2025-07-16 18:18:32.605755 | TASK [validate-host : Write out all zuul information for each host] 2025-07-16 18:18:33.704665 | noble -> localhost | changed 2025-07-16 18:18:33.713071 | 2025-07-16 18:18:33.713152 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-16 18:18:34.042689 | noble | ok 2025-07-16 18:18:34.049445 | 2025-07-16 18:18:34.049556 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-16 18:18:35.300455 | noble | changed: 2025-07-16 18:18:35.300765 | noble | cd+++++++++ src/ 2025-07-16 18:18:35.300811 | noble | cd+++++++++ src/github.com/ 2025-07-16 18:18:35.300832 | noble | cd+++++++++ src/github.com/osism/ 2025-07-16 18:18:35.300851 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-16 18:18:35.300868 | noble | localhost | ok: "/var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/work/logs" 2025-07-16 18:24:02.473029 | noble -> localhost | changed: "/var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/work/artifacts" 2025-07-16 18:24:02.756727 | noble -> localhost | changed: "/var/lib/zuul/builds/ecd9e84e8a0d4ac2af07b40c11b97371/work/docs" 2025-07-16 18:24:02.780036 | 2025-07-16 18:24:02.780225 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-16 18:24:04.153124 | noble | changed: 2025-07-16 18:24:04.153568 | noble | .d..t...... ./ 2025-07-16 18:24:04.153654 | noble | >f+++++++++ osism-ipa.log 2025-07-16 18:24:04.153763 | noble | changed: All items complete 2025-07-16 18:24:04.153830 | 2025-07-16 18:24:04.847167 | noble | changed: .d..t...... ./ 2025-07-16 18:24:05.575952 | noble | changed: .d..t...... ./ 2025-07-16 18:24:05.610624 | 2025-07-16 18:24:05.610780 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-16 18:24:06.160881 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.013902 2025-07-16 18:24:06.468993 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010797 2025-07-16 18:24:06.495121 | 2025-07-16 18:24:06.495258 | PLAY RECAP 2025-07-16 18:24:06.495344 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-16 18:24:06.495387 | 2025-07-16 18:24:06.623876 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-16 18:24:06.624836 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-16 18:24:07.406651 | 2025-07-16 18:24:07.406819 | PLAY [Base post] 2025-07-16 18:24:07.421941 | 2025-07-16 18:24:07.422088 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-16 18:24:08.061413 | noble | changed 2025-07-16 18:24:08.072415 | 2025-07-16 18:24:08.072578 | PLAY RECAP 2025-07-16 18:24:08.072665 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-16 18:24:08.072744 | 2025-07-16 18:24:08.199207 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-16 18:24:08.200144 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-16 18:24:08.996961 | 2025-07-16 18:24:08.997142 | PLAY [Base post-logs] 2025-07-16 18:24:09.008183 | 2025-07-16 18:24:09.008347 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-16 18:24:09.495895 | localhost | changed 2025-07-16 18:24:09.511190 | 2025-07-16 18:24:09.511359 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-16 18:24:09.548192 | localhost | ok 2025-07-16 18:24:09.553568 | 2025-07-16 18:24:09.553693 | TASK [Set zuul-log-path fact] 2025-07-16 18:24:09.570364 | localhost | ok 2025-07-16 18:24:09.580173 | 2025-07-16 18:24:09.580282 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-16 18:24:09.609648 | localhost | ok 2025-07-16 18:24:09.617937 | 2025-07-16 18:24:09.618095 | TASK [upload-logs : Create log directories] 2025-07-16 18:24:10.149657 | localhost | changed 2025-07-16 18:24:10.155278 | 2025-07-16 18:24:10.155493 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-16 18:24:10.690185 | localhost -> localhost | ok: Runtime: 0:00:00.007566 2025-07-16 18:24:10.695642 | 2025-07-16 18:24:10.695779 | TASK [upload-logs : Upload logs to log server] 2025-07-16 18:24:11.334965 | localhost | Output suppressed because no_log was given 2025-07-16 18:24:11.339424 | 2025-07-16 18:24:11.339703 | LOOP [upload-logs : Compress console log and json output] 2025-07-16 18:24:11.411569 | localhost | skipping: Conditional result was False 2025-07-16 18:24:11.417082 | localhost | skipping: Conditional result was False 2025-07-16 18:24:11.425470 | 2025-07-16 18:24:11.425776 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-16 18:24:11.481638 | localhost | skipping: Conditional result was False 2025-07-16 18:24:11.482299 | 2025-07-16 18:24:11.485350 | localhost | skipping: Conditional result was False 2025-07-16 18:24:11.500771 | 2025-07-16 18:24:11.501217 | LOOP [upload-logs : Upload console log and json output]