2025-10-24 08:24:04.203361 | Job console starting 2025-10-24 08:24:04.223887 | Updating git repos 2025-10-24 08:24:04.244893 | Cloning repos into workspace 2025-10-24 08:24:04.282181 | Restoring repo states 2025-10-24 08:24:04.288223 | Merging changes 2025-10-24 08:24:04.784641 | Checking out repos 2025-10-24 08:24:04.828935 | Preparing playbooks 2025-10-24 08:24:05.469038 | Running Ansible setup 2025-10-24 08:24:11.593224 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-24 08:24:12.352922 | 2025-10-24 08:24:12.353081 | PLAY [Base pre] 2025-10-24 08:24:12.370607 | 2025-10-24 08:24:12.370750 | TASK [Setup log path fact] 2025-10-24 08:24:12.391584 | noble | ok 2025-10-24 08:24:12.411007 | 2025-10-24 08:24:12.411181 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 08:24:12.450798 | noble | ok 2025-10-24 08:24:12.464092 | 2025-10-24 08:24:12.464238 | TASK [emit-job-header : Print job information] 2025-10-24 08:24:12.504297 | # Job Information 2025-10-24 08:24:12.504492 | Ansible Version: 2.16.14 2025-10-24 08:24:12.504527 | Job: openstack-ironic-images-build-osism-esp 2025-10-24 08:24:12.504560 | Pipeline: check 2025-10-24 08:24:12.504585 | Executor: 521e9411259a 2025-10-24 08:24:12.504606 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/155 2025-10-24 08:24:12.504629 | Event ID: 60569680-b0b2-11f0-8a87-a5c0bb06cef0 2025-10-24 08:24:12.515928 | 2025-10-24 08:24:12.516071 | LOOP [emit-job-header : Print node information] 2025-10-24 08:24:12.668177 | noble | ok: 2025-10-24 08:24:12.668440 | noble | # Node Information 2025-10-24 08:24:12.668476 | noble | Inventory Hostname: noble 2025-10-24 08:24:12.668501 | noble | Hostname: ubuntu 2025-10-24 08:24:12.668524 | noble | Username: zuul 2025-10-24 08:24:12.668545 | noble | Distro: Ubuntu 24.04 2025-10-24 08:24:12.668572 | noble | Provider: regiocloud-a 2025-10-24 08:24:12.668594 | noble | Region: 2025-10-24 08:24:12.668616 | noble | Label: ubuntu-noble-uefi 2025-10-24 08:24:12.668635 | noble | Product Name: OpenStack Nova 2025-10-24 08:24:12.668657 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe36:946a 2025-10-24 08:24:12.687394 | 2025-10-24 08:24:12.687530 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-24 08:24:13.277630 | noble -> localhost | changed 2025-10-24 08:24:13.286349 | 2025-10-24 08:24:13.286477 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-24 08:24:14.655674 | noble -> localhost | changed 2025-10-24 08:24:14.678216 | 2025-10-24 08:24:14.678350 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-24 08:24:15.107847 | noble -> localhost | ok 2025-10-24 08:24:15.115686 | 2025-10-24 08:24:15.115811 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-24 08:24:15.155515 | noble | ok 2025-10-24 08:24:15.172290 | noble | included: /var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-24 08:24:15.188909 | 2025-10-24 08:24:15.189050 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-24 08:24:16.788533 | noble -> localhost | Generating public/private rsa key pair. 2025-10-24 08:24:16.788787 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/work/02ed7434b5f5483d976300a2ab80bed6_id_rsa 2025-10-24 08:24:16.788825 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/work/02ed7434b5f5483d976300a2ab80bed6_id_rsa.pub 2025-10-24 08:24:16.788851 | noble -> localhost | The key fingerprint is: 2025-10-24 08:24:16.788875 | noble -> localhost | SHA256:4KD0+DcdaSV86Pj9F5at04v4qfDh7qQeApyYPpuiM0g zuul-build-sshkey 2025-10-24 08:24:16.788898 | noble -> localhost | The key's randomart image is: 2025-10-24 08:24:16.788919 | noble -> localhost | +---[RSA 3072]----+ 2025-10-24 08:24:16.788940 | noble -> localhost | | | 2025-10-24 08:24:16.788971 | noble -> localhost | | . . | 2025-10-24 08:24:16.788991 | noble -> localhost | | . . . + o | 2025-10-24 08:24:16.789011 | noble -> localhost | | . ++o.+ = | 2025-10-24 08:24:16.789031 | noble -> localhost | | oo.+o S o | 2025-10-24 08:24:16.789058 | noble -> localhost | | E.. .+ o + .| 2025-10-24 08:24:16.789079 | noble -> localhost | |o o. o.oo.o . + | 2025-10-24 08:24:16.789098 | noble -> localhost | |+. +. .. B.o * .| 2025-10-24 08:24:16.789137 | noble -> localhost | |oo.o .ooB+= o.| 2025-10-24 08:24:16.789158 | noble -> localhost | +----[SHA256]-----+ 2025-10-24 08:24:16.789210 | noble -> localhost | ok: Runtime: 0:00:00.997460 2025-10-24 08:24:16.798336 | 2025-10-24 08:24:16.798538 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-24 08:24:16.837755 | noble | ok 2025-10-24 08:24:16.856897 | noble | included: /var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-24 08:24:16.874607 | 2025-10-24 08:24:16.874714 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-24 08:24:16.918389 | noble | skipping: Conditional result was False 2025-10-24 08:24:16.927584 | 2025-10-24 08:24:16.927698 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-24 08:24:17.661205 | noble | changed 2025-10-24 08:24:17.674577 | 2025-10-24 08:24:17.674698 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-24 08:24:17.979723 | noble | ok 2025-10-24 08:24:17.989518 | 2025-10-24 08:24:17.989673 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-24 08:24:18.763245 | noble | changed 2025-10-24 08:24:18.774295 | 2025-10-24 08:24:18.774434 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-24 08:24:19.610247 | noble | changed 2025-10-24 08:24:19.620290 | 2025-10-24 08:24:19.620450 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-24 08:24:19.645595 | noble | skipping: Conditional result was False 2025-10-24 08:24:19.652738 | 2025-10-24 08:24:19.652845 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-24 08:24:20.133553 | noble -> localhost | changed 2025-10-24 08:24:20.147921 | 2025-10-24 08:24:20.148051 | TASK [add-build-sshkey : Add back temp key] 2025-10-24 08:24:20.519242 | noble -> localhost | Identity added: /var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/work/02ed7434b5f5483d976300a2ab80bed6_id_rsa (zuul-build-sshkey) 2025-10-24 08:24:20.519482 | noble -> localhost | ok: Runtime: 0:00:00.019182 2025-10-24 08:24:20.526705 | 2025-10-24 08:24:20.526805 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-24 08:24:21.014884 | noble | ok 2025-10-24 08:24:21.025170 | 2025-10-24 08:24:21.025294 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-24 08:24:21.052924 | noble | skipping: Conditional result was False 2025-10-24 08:24:21.105835 | 2025-10-24 08:24:21.105962 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-24 08:24:21.551526 | noble | ok 2025-10-24 08:24:21.572178 | 2025-10-24 08:24:21.572313 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-24 08:24:21.622530 | noble | ok 2025-10-24 08:24:21.630058 | 2025-10-24 08:24:21.630190 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-24 08:24:21.932165 | noble -> localhost | ok 2025-10-24 08:24:21.939774 | 2025-10-24 08:24:21.939885 | TASK [validate-host : Collect information about the host] 2025-10-24 08:24:23.420769 | noble | ok 2025-10-24 08:24:23.448033 | 2025-10-24 08:24:23.448255 | TASK [validate-host : Sanitize hostname] 2025-10-24 08:24:23.526613 | noble | ok 2025-10-24 08:24:23.535613 | 2025-10-24 08:24:23.535748 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-24 08:24:24.130947 | noble -> localhost | changed 2025-10-24 08:24:24.137423 | 2025-10-24 08:24:24.137528 | TASK [validate-host : Collect information about zuul worker] 2025-10-24 08:24:24.636126 | noble | ok 2025-10-24 08:24:24.641825 | 2025-10-24 08:24:24.641948 | TASK [validate-host : Write out all zuul information for each host] 2025-10-24 08:24:25.220401 | noble -> localhost | changed 2025-10-24 08:24:25.231328 | 2025-10-24 08:24:25.231439 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-24 08:24:25.580925 | noble | ok 2025-10-24 08:24:25.587288 | 2025-10-24 08:24:25.587404 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-24 08:24:26.575222 | noble | changed: 2025-10-24 08:24:26.575433 | noble | cd+++++++++ src/ 2025-10-24 08:24:26.575470 | noble | cd+++++++++ src/github.com/ 2025-10-24 08:24:26.575495 | noble | cd+++++++++ src/github.com/osism/ 2025-10-24 08:24:26.575517 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-24 08:24:26.575538 | noble | localhost | ok: "/var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/work/logs" 2025-10-24 08:27:35.739833 | noble -> localhost | changed: "/var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/work/artifacts" 2025-10-24 08:27:35.992147 | noble -> localhost | changed: "/var/lib/zuul/builds/02ed7434b5f5483d976300a2ab80bed6/work/docs" 2025-10-24 08:27:36.004123 | 2025-10-24 08:27:36.004280 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-24 08:27:37.306520 | noble | changed: 2025-10-24 08:27:37.306788 | noble | .d..t...... ./ 2025-10-24 08:27:37.306922 | noble | >f+++++++++ osism-esp.log 2025-10-24 08:27:37.306988 | noble | changed: All items complete 2025-10-24 08:27:37.307026 | 2025-10-24 08:27:37.945343 | noble | changed: .d..t...... ./ 2025-10-24 08:27:38.600741 | noble | changed: .d..t...... ./ 2025-10-24 08:27:38.630302 | 2025-10-24 08:27:38.630447 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-24 08:27:39.131664 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.009911 2025-10-24 08:27:39.437268 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.008934 2025-10-24 08:27:39.456082 | 2025-10-24 08:27:39.456208 | PLAY RECAP 2025-10-24 08:27:39.456264 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-24 08:27:39.456289 | 2025-10-24 08:27:39.573621 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-24 08:27:39.576017 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-24 08:27:40.279729 | 2025-10-24 08:27:40.279878 | PLAY [Base post] 2025-10-24 08:27:40.294003 | 2025-10-24 08:27:40.294130 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-24 08:27:40.948024 | noble | changed 2025-10-24 08:27:40.957239 | 2025-10-24 08:27:40.957353 | PLAY RECAP 2025-10-24 08:27:40.957424 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-24 08:27:40.957548 | 2025-10-24 08:27:41.074648 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-24 08:27:41.075802 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-24 08:27:41.862425 | 2025-10-24 08:27:41.862589 | PLAY [Base post-logs] 2025-10-24 08:27:41.873152 | 2025-10-24 08:27:41.873318 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-24 08:27:42.328673 | localhost | changed 2025-10-24 08:27:42.344413 | 2025-10-24 08:27:42.344585 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-24 08:27:42.371286 | localhost | ok 2025-10-24 08:27:42.375136 | 2025-10-24 08:27:42.375277 | TASK [Set zuul-log-path fact] 2025-10-24 08:27:42.390942 | localhost | ok 2025-10-24 08:27:42.401305 | 2025-10-24 08:27:42.401449 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 08:27:42.427029 | localhost | ok 2025-10-24 08:27:42.430680 | 2025-10-24 08:27:42.430801 | TASK [upload-logs : Create log directories] 2025-10-24 08:27:42.916380 | localhost | changed 2025-10-24 08:27:42.919282 | 2025-10-24 08:27:42.919392 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-24 08:27:43.405857 | localhost -> localhost | ok: Runtime: 0:00:00.006958 2025-10-24 08:27:43.415285 | 2025-10-24 08:27:43.415477 | TASK [upload-logs : Upload logs to log server] 2025-10-24 08:27:43.971375 | localhost | Output suppressed because no_log was given 2025-10-24 08:27:43.973925 | 2025-10-24 08:27:43.974061 | LOOP [upload-logs : Compress console log and json output] 2025-10-24 08:27:44.025987 | localhost | skipping: Conditional result was False 2025-10-24 08:27:44.031240 | localhost | skipping: Conditional result was False 2025-10-24 08:27:44.046385 | 2025-10-24 08:27:44.046630 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-24 08:27:44.105152 | localhost | skipping: Conditional result was False 2025-10-24 08:27:44.105839 | 2025-10-24 08:27:44.109504 | localhost | skipping: Conditional result was False 2025-10-24 08:27:44.121931 | 2025-10-24 08:27:44.122120 | LOOP [upload-logs : Upload console log and json output]