2026-02-23 16:34:11.693262 | Job console starting 2026-02-23 16:34:11.705687 | Updating git repos 2026-02-23 16:34:11.720113 | Cloning repos into workspace 2026-02-23 16:34:11.748497 | Restoring repo states 2026-02-23 16:34:11.759922 | Merging changes 2026-02-23 16:34:11.759938 | Checking out repos 2026-02-23 16:34:11.807888 | Preparing playbooks 2026-02-23 16:34:12.472232 | Running Ansible setup 2026-02-23 16:34:18.445743 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-02-23 16:34:19.246552 | 2026-02-23 16:34:19.246728 | PLAY [Base pre] 2026-02-23 16:34:19.266166 | 2026-02-23 16:34:19.266323 | TASK [Setup log path fact] 2026-02-23 16:34:19.286672 | noble | ok 2026-02-23 16:34:19.304685 | 2026-02-23 16:34:19.304851 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-23 16:34:19.346998 | noble | ok 2026-02-23 16:34:19.360631 | 2026-02-23 16:34:19.360757 | TASK [emit-job-header : Print job information] 2026-02-23 16:34:19.407396 | # Job Information 2026-02-23 16:34:19.407683 | Ansible Version: 2.16.14 2026-02-23 16:34:19.407745 | Job: openstack-ironic-images-publish-osism-node 2026-02-23 16:34:19.407802 | Pipeline: post 2026-02-23 16:34:19.407843 | Executor: 521e9411259a 2026-02-23 16:34:19.407879 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/cc36e15b4d477b6cc4ddbdbd7332c7a19976d4dd 2026-02-23 16:34:19.407917 | Event ID: 045804ee-10d5-11f1-8945-282e65460216 2026-02-23 16:34:19.417616 | 2026-02-23 16:34:19.417757 | LOOP [emit-job-header : Print node information] 2026-02-23 16:34:19.558452 | noble | ok: 2026-02-23 16:34:19.558821 | noble | # Node Information 2026-02-23 16:34:19.558989 | noble | Inventory Hostname: noble 2026-02-23 16:34:19.559022 | noble | Hostname: ubuntu 2026-02-23 16:34:19.559050 | noble | Username: zuul 2026-02-23 16:34:19.559077 | noble | Distro: Ubuntu 24.04 2026-02-23 16:34:19.559106 | noble | Provider: regiocloud-a 2026-02-23 16:34:19.559132 | noble | Region: 2026-02-23 16:34:19.559154 | noble | Label: ubuntu-noble-uefi 2026-02-23 16:34:19.559174 | noble | Product Name: OpenStack Nova 2026-02-23 16:34:19.559194 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febe:efd0 2026-02-23 16:34:19.572155 | 2026-02-23 16:34:19.572373 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-23 16:34:20.146382 | noble -> localhost | changed 2026-02-23 16:34:20.161326 | 2026-02-23 16:34:20.162085 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-23 16:34:21.510218 | noble -> localhost | changed 2026-02-23 16:34:21.536016 | 2026-02-23 16:34:21.536166 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-23 16:34:21.821610 | noble -> localhost | ok 2026-02-23 16:34:21.829132 | 2026-02-23 16:34:21.829283 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-23 16:34:21.883666 | noble | ok 2026-02-23 16:34:21.917995 | noble | included: /var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-23 16:34:21.941751 | 2026-02-23 16:34:21.941904 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-23 16:34:22.951809 | noble -> localhost | Generating public/private rsa key pair. 2026-02-23 16:34:22.952044 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/work/7260bad10d8e459cb04b85472131fa66_id_rsa 2026-02-23 16:34:22.952082 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/work/7260bad10d8e459cb04b85472131fa66_id_rsa.pub 2026-02-23 16:34:22.952108 | noble -> localhost | The key fingerprint is: 2026-02-23 16:34:22.952131 | noble -> localhost | SHA256:wLH0NoSI1JT0yXryQhT5pMxDRULvpKA/9peCv6uVUXc zuul-build-sshkey 2026-02-23 16:34:22.952153 | noble -> localhost | The key's randomart image is: 2026-02-23 16:34:22.952174 | noble -> localhost | +---[RSA 3072]----+ 2026-02-23 16:34:22.952195 | noble -> localhost | | ..***=.. | 2026-02-23 16:34:22.952230 | noble -> localhost | | . *Oo* | 2026-02-23 16:34:22.952252 | noble -> localhost | | .+.+@ = E | 2026-02-23 16:34:22.952272 | noble -> localhost | | . o=*.+ o | 2026-02-23 16:34:22.952292 | noble -> localhost | |. *.o S | 2026-02-23 16:34:22.952320 | noble -> localhost | | . . * | 2026-02-23 16:34:22.952342 | noble -> localhost | | +.+ .. | 2026-02-23 16:34:22.952361 | noble -> localhost | | ..+..o | 2026-02-23 16:34:22.952381 | noble -> localhost | | .o== | 2026-02-23 16:34:22.952401 | noble -> localhost | +----[SHA256]-----+ 2026-02-23 16:34:22.952452 | noble -> localhost | ok: Runtime: 0:00:00.372901 2026-02-23 16:34:22.960543 | 2026-02-23 16:34:22.960668 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-23 16:34:22.991485 | noble | ok 2026-02-23 16:34:23.007852 | noble | included: /var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-23 16:34:23.017906 | 2026-02-23 16:34:23.018021 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-23 16:34:23.034825 | noble | skipping: Conditional result was False 2026-02-23 16:34:23.049721 | 2026-02-23 16:34:23.049883 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-23 16:34:23.704529 | noble | changed 2026-02-23 16:34:23.711138 | 2026-02-23 16:34:23.711261 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-23 16:34:24.016282 | noble | ok 2026-02-23 16:34:24.022953 | 2026-02-23 16:34:24.023074 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-23 16:34:24.831254 | noble | changed 2026-02-23 16:34:24.837712 | 2026-02-23 16:34:24.837832 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-23 16:34:25.651702 | noble | changed 2026-02-23 16:34:25.661378 | 2026-02-23 16:34:25.661549 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-23 16:34:25.685667 | noble | skipping: Conditional result was False 2026-02-23 16:34:25.693196 | 2026-02-23 16:34:25.693333 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-23 16:34:26.184279 | noble -> localhost | changed 2026-02-23 16:34:26.214586 | 2026-02-23 16:34:26.214725 | TASK [add-build-sshkey : Add back temp key] 2026-02-23 16:34:26.638801 | noble -> localhost | Identity added: /var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/work/7260bad10d8e459cb04b85472131fa66_id_rsa (zuul-build-sshkey) 2026-02-23 16:34:26.639260 | noble -> localhost | ok: Runtime: 0:00:00.018851 2026-02-23 16:34:26.651047 | 2026-02-23 16:34:26.651194 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-23 16:34:27.191190 | noble | ok 2026-02-23 16:34:27.203294 | 2026-02-23 16:34:27.203485 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-23 16:34:27.229960 | noble | skipping: Conditional result was False 2026-02-23 16:34:27.311163 | 2026-02-23 16:34:27.311302 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-23 16:34:27.812195 | noble | ok 2026-02-23 16:34:27.823926 | 2026-02-23 16:34:27.824058 | TASK [validate-host : Define zuul_info_dir fact] 2026-02-23 16:34:27.853970 | noble | ok 2026-02-23 16:34:27.861745 | 2026-02-23 16:34:27.861864 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-02-23 16:34:28.144762 | noble -> localhost | ok 2026-02-23 16:34:28.152948 | 2026-02-23 16:34:28.153071 | TASK [validate-host : Collect information about the host] 2026-02-23 16:34:29.704256 | noble | ok 2026-02-23 16:34:29.736991 | 2026-02-23 16:34:29.737202 | TASK [validate-host : Sanitize hostname] 2026-02-23 16:34:29.804906 | noble | ok 2026-02-23 16:34:29.813621 | 2026-02-23 16:34:29.813764 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-02-23 16:34:30.410718 | noble -> localhost | changed 2026-02-23 16:34:30.417565 | 2026-02-23 16:34:30.417690 | TASK [validate-host : Collect information about zuul worker] 2026-02-23 16:34:30.970883 | noble | ok 2026-02-23 16:34:30.981020 | 2026-02-23 16:34:30.981203 | TASK [validate-host : Write out all zuul information for each host] 2026-02-23 16:34:31.585231 | noble -> localhost | changed 2026-02-23 16:34:31.596399 | 2026-02-23 16:34:31.596552 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-02-23 16:34:31.956158 | noble | ok 2026-02-23 16:34:31.964836 | 2026-02-23 16:34:31.964958 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-02-23 16:34:32.971181 | noble | changed: 2026-02-23 16:34:32.971396 | noble | cd+++++++++ src/ 2026-02-23 16:34:32.971433 | noble | cd+++++++++ src/github.com/ 2026-02-23 16:34:32.971458 | noble | cd+++++++++ src/github.com/osism/ 2026-02-23 16:34:32.971479 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-02-23 16:34:32.971515 | noble | localhost | ok: "/var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/work/logs" 2026-02-23 16:50:30.869292 | noble -> localhost | changed: "/var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/work/artifacts" 2026-02-23 16:50:31.140903 | noble -> localhost | changed: "/var/lib/zuul/builds/7260bad10d8e459cb04b85472131fa66/work/docs" 2026-02-23 16:50:31.161448 | 2026-02-23 16:50:31.161607 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-23 16:50:32.539367 | noble | changed: 2026-02-23 16:50:32.539708 | noble | .d..t...... ./ 2026-02-23 16:50:32.539767 | noble | >f+++++++++ osism-node.log 2026-02-23 16:50:32.539845 | noble | changed: All items complete 2026-02-23 16:50:32.539895 | 2026-02-23 16:50:33.223874 | noble | changed: .d..t...... ./ 2026-02-23 16:50:33.888446 | noble | changed: .d..t...... ./ 2026-02-23 16:50:33.914359 | 2026-02-23 16:50:33.914527 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-23 16:50:33.952224 | noble | skipping: Conditional result was False 2026-02-23 16:50:33.956268 | noble | skipping: Conditional result was False 2026-02-23 16:50:33.988654 | 2026-02-23 16:50:33.988843 | PLAY RECAP 2026-02-23 16:50:33.988955 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-02-23 16:50:33.989019 | 2026-02-23 16:50:34.122948 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-02-23 16:50:34.124930 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-23 16:50:34.858356 | 2026-02-23 16:50:34.858516 | PLAY [Base post] 2026-02-23 16:50:34.873379 | 2026-02-23 16:50:34.873509 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-23 16:50:35.557605 | noble | changed 2026-02-23 16:50:35.569203 | 2026-02-23 16:50:35.569341 | PLAY RECAP 2026-02-23 16:50:35.569419 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-23 16:50:35.569496 | 2026-02-23 16:50:35.700752 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-23 16:50:35.701670 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-23 16:50:36.513619 | 2026-02-23 16:50:36.513787 | PLAY [Base post-logs] 2026-02-23 16:50:36.525694 | 2026-02-23 16:50:36.525853 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-23 16:50:36.984540 | localhost | changed 2026-02-23 16:50:36.998937 | 2026-02-23 16:50:36.999087 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-23 16:50:37.038334 | localhost | ok 2026-02-23 16:50:37.046991 | 2026-02-23 16:50:37.047165 | TASK [Set zuul-log-path fact] 2026-02-23 16:50:37.078853 | localhost | ok 2026-02-23 16:50:37.087812 | 2026-02-23 16:50:37.087939 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-23 16:50:37.124537 | localhost | ok 2026-02-23 16:50:37.128406 | 2026-02-23 16:50:37.128529 | TASK [upload-logs : Create log directories] 2026-02-23 16:50:37.687181 | localhost | changed 2026-02-23 16:50:37.692372 | 2026-02-23 16:50:37.692539 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-23 16:50:38.235911 | localhost -> localhost | ok: Runtime: 0:00:00.005090 2026-02-23 16:50:38.246616 | 2026-02-23 16:50:38.247171 | TASK [upload-logs : Upload logs to log server] 2026-02-23 16:50:38.869947 | localhost | Output suppressed because no_log was given 2026-02-23 16:50:38.874250 | 2026-02-23 16:50:38.874467 | LOOP [upload-logs : Compress console log and json output] 2026-02-23 16:50:38.931847 | localhost | skipping: Conditional result was False 2026-02-23 16:50:38.937257 | localhost | skipping: Conditional result was False 2026-02-23 16:50:38.949443 | 2026-02-23 16:50:38.949660 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-23 16:50:38.997947 | localhost | skipping: Conditional result was False 2026-02-23 16:50:38.998669 | 2026-02-23 16:50:39.001778 | localhost | skipping: Conditional result was False 2026-02-23 16:50:39.016118 | 2026-02-23 16:50:39.016345 | LOOP [upload-logs : Upload console log and json output]