2025-10-14 17:38:34.480707 | Job console starting 2025-10-14 17:38:34.494953 | Updating git repos 2025-10-14 17:38:34.517639 | Cloning repos into workspace 2025-10-14 17:38:34.546165 | Restoring repo states 2025-10-14 17:38:34.553543 | Merging changes 2025-10-14 17:38:34.553576 | Checking out repos 2025-10-14 17:38:34.607159 | Preparing playbooks 2025-10-14 17:38:35.211609 | Running Ansible setup 2025-10-14 17:38:41.261064 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-14 17:38:42.025717 | 2025-10-14 17:38:42.025878 | PLAY [Base pre] 2025-10-14 17:38:42.042818 | 2025-10-14 17:38:42.042972 | TASK [Setup log path fact] 2025-10-14 17:38:42.073926 | noble | ok 2025-10-14 17:38:42.091573 | 2025-10-14 17:38:42.091704 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 17:38:42.131924 | noble | ok 2025-10-14 17:38:42.143520 | 2025-10-14 17:38:42.143628 | TASK [emit-job-header : Print job information] 2025-10-14 17:38:42.182704 | # Job Information 2025-10-14 17:38:42.182883 | Ansible Version: 2.16.14 2025-10-14 17:38:42.182919 | Job: openstack-ironic-images-publish-osism-node 2025-10-14 17:38:42.182951 | Pipeline: post 2025-10-14 17:38:42.182974 | Executor: 521e9411259a 2025-10-14 17:38:42.183037 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/8c3745cddb5697fe1bcedf92c3cc5a9e5e151e5d 2025-10-14 17:38:42.183061 | Event ID: 39f6d0bc-a924-11f0-8af4-67719c070d0b 2025-10-14 17:38:42.189672 | 2025-10-14 17:38:42.189785 | LOOP [emit-job-header : Print node information] 2025-10-14 17:38:42.300201 | noble | ok: 2025-10-14 17:38:42.300378 | noble | # Node Information 2025-10-14 17:38:42.300413 | noble | Inventory Hostname: noble 2025-10-14 17:38:42.300438 | noble | Hostname: ubuntu 2025-10-14 17:38:42.300460 | noble | Username: zuul 2025-10-14 17:38:42.300480 | noble | Distro: Ubuntu 24.04 2025-10-14 17:38:42.300504 | noble | Provider: regiocloud-a 2025-10-14 17:38:42.300525 | noble | Region: 2025-10-14 17:38:42.300547 | noble | Label: ubuntu-noble-uefi 2025-10-14 17:38:42.300568 | noble | Product Name: OpenStack Nova 2025-10-14 17:38:42.300587 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4b:1929 2025-10-14 17:38:42.331203 | 2025-10-14 17:38:42.331373 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-14 17:38:42.843460 | noble -> localhost | changed 2025-10-14 17:38:42.859587 | 2025-10-14 17:38:42.859741 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-14 17:38:43.900944 | noble -> localhost | changed 2025-10-14 17:38:43.923682 | 2025-10-14 17:38:43.923811 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-14 17:38:44.211726 | noble -> localhost | ok 2025-10-14 17:38:44.222293 | 2025-10-14 17:38:44.222457 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-14 17:38:44.252684 | noble | ok 2025-10-14 17:38:44.269265 | noble | included: /var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-14 17:38:44.277418 | 2025-10-14 17:38:44.277521 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-14 17:38:45.618840 | noble -> localhost | Generating public/private rsa key pair. 2025-10-14 17:38:45.619109 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/work/562729ba48084bb980346edef40eaf7e_id_rsa 2025-10-14 17:38:45.619150 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/work/562729ba48084bb980346edef40eaf7e_id_rsa.pub 2025-10-14 17:38:45.619177 | noble -> localhost | The key fingerprint is: 2025-10-14 17:38:45.619201 | noble -> localhost | SHA256:ticuKKPx00bQc8wCY/HfWMQI9YJIkLacmmbzGlXr8ks zuul-build-sshkey 2025-10-14 17:38:45.619224 | noble -> localhost | The key's randomart image is: 2025-10-14 17:38:45.619245 | noble -> localhost | +---[RSA 3072]----+ 2025-10-14 17:38:45.619265 | noble -> localhost | |.oo..o.o. | 2025-10-14 17:38:45.619296 | noble -> localhost | |.o+o ..o. | 2025-10-14 17:38:45.619318 | noble -> localhost | |o.++o+. o | 2025-10-14 17:38:45.619337 | noble -> localhost | | +. =.== | 2025-10-14 17:38:45.619357 | noble -> localhost | |.. o =o S | 2025-10-14 17:38:45.619386 | noble -> localhost | |o+. o . . | 2025-10-14 17:38:45.619407 | noble -> localhost | |+.o+ E o . | 2025-10-14 17:38:45.619428 | noble -> localhost | | o=.B .. o | 2025-10-14 17:38:45.619450 | noble -> localhost | |.oo= o... | 2025-10-14 17:38:45.619470 | noble -> localhost | +----[SHA256]-----+ 2025-10-14 17:38:45.619530 | noble -> localhost | ok: Runtime: 0:00:00.801810 2025-10-14 17:38:45.629465 | 2025-10-14 17:38:45.629733 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-14 17:38:45.662229 | noble | ok 2025-10-14 17:38:45.675564 | noble | included: /var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-14 17:38:45.686951 | 2025-10-14 17:38:45.687190 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-14 17:38:45.722911 | noble | skipping: Conditional result was False 2025-10-14 17:38:45.742195 | 2025-10-14 17:38:45.742463 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-14 17:38:46.481972 | noble | changed 2025-10-14 17:38:46.489808 | 2025-10-14 17:38:46.489931 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-14 17:38:46.835525 | noble | ok 2025-10-14 17:38:46.846515 | 2025-10-14 17:38:46.846642 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-14 17:38:47.684760 | noble | changed 2025-10-14 17:38:47.704146 | 2025-10-14 17:38:47.704601 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-14 17:38:48.617232 | noble | changed 2025-10-14 17:38:48.623751 | 2025-10-14 17:38:48.623855 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-14 17:38:48.655316 | noble | skipping: Conditional result was False 2025-10-14 17:38:48.678448 | 2025-10-14 17:38:48.678563 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-14 17:38:49.553416 | noble -> localhost | changed 2025-10-14 17:38:49.570051 | 2025-10-14 17:38:49.570189 | TASK [add-build-sshkey : Add back temp key] 2025-10-14 17:38:50.069833 | noble -> localhost | Identity added: /var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/work/562729ba48084bb980346edef40eaf7e_id_rsa (zuul-build-sshkey) 2025-10-14 17:38:50.070139 | noble -> localhost | ok: Runtime: 0:00:00.014505 2025-10-14 17:38:50.077457 | 2025-10-14 17:38:50.077564 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-14 17:38:50.575725 | noble | ok 2025-10-14 17:38:50.581853 | 2025-10-14 17:38:50.581966 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-14 17:38:50.605505 | noble | skipping: Conditional result was False 2025-10-14 17:38:50.654817 | 2025-10-14 17:38:50.654967 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-14 17:38:51.109040 | noble | ok 2025-10-14 17:38:51.120966 | 2025-10-14 17:38:51.121143 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-14 17:38:51.159917 | noble | ok 2025-10-14 17:38:51.171261 | 2025-10-14 17:38:51.171395 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-14 17:38:51.448178 | noble -> localhost | ok 2025-10-14 17:38:51.456351 | 2025-10-14 17:38:51.456464 | TASK [validate-host : Collect information about the host] 2025-10-14 17:38:53.072507 | noble | ok 2025-10-14 17:38:53.094089 | 2025-10-14 17:38:53.094225 | TASK [validate-host : Sanitize hostname] 2025-10-14 17:38:53.165901 | noble | ok 2025-10-14 17:38:53.176834 | 2025-10-14 17:38:53.177030 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-14 17:38:53.867497 | noble -> localhost | changed 2025-10-14 17:38:53.882095 | 2025-10-14 17:38:53.882234 | TASK [validate-host : Collect information about zuul worker] 2025-10-14 17:38:54.456219 | noble | ok 2025-10-14 17:38:54.466030 | 2025-10-14 17:38:54.466202 | TASK [validate-host : Write out all zuul information for each host] 2025-10-14 17:38:55.220285 | noble -> localhost | changed 2025-10-14 17:38:55.239587 | 2025-10-14 17:38:55.239725 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-14 17:38:55.592071 | noble | ok 2025-10-14 17:38:55.598599 | 2025-10-14 17:38:55.598714 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-14 17:38:56.605651 | noble | changed: 2025-10-14 17:38:56.605815 | noble | cd+++++++++ src/ 2025-10-14 17:38:56.605849 | noble | cd+++++++++ src/github.com/ 2025-10-14 17:38:56.605872 | noble | cd+++++++++ src/github.com/osism/ 2025-10-14 17:38:56.605894 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-14 17:38:56.605915 | noble | localhost | ok: "/var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/work/logs" 2025-10-14 17:56:22.328044 | noble -> localhost | changed: "/var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/work/artifacts" 2025-10-14 17:56:22.591456 | noble -> localhost | changed: "/var/lib/zuul/builds/562729ba48084bb980346edef40eaf7e/work/docs" 2025-10-14 17:56:22.612786 | 2025-10-14 17:56:22.612947 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-14 17:56:23.890347 | noble | changed: 2025-10-14 17:56:23.890566 | noble | .d..t...... ./ 2025-10-14 17:56:23.890600 | noble | >f+++++++++ osism-node.log 2025-10-14 17:56:23.890646 | noble | changed: All items complete 2025-10-14 17:56:23.890677 | 2025-10-14 17:56:24.552243 | noble | changed: .d..t...... ./ 2025-10-14 17:56:25.236657 | noble | changed: .d..t...... ./ 2025-10-14 17:56:25.257841 | 2025-10-14 17:56:25.257976 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-14 17:56:25.294609 | noble | skipping: Conditional result was False 2025-10-14 17:56:25.297666 | noble | skipping: Conditional result was False 2025-10-14 17:56:25.313105 | 2025-10-14 17:56:25.313201 | PLAY RECAP 2025-10-14 17:56:25.313269 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-14 17:56:25.313302 | 2025-10-14 17:56:25.439382 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-14 17:56:25.440311 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 17:56:26.192941 | 2025-10-14 17:56:26.193119 | PLAY [Base post] 2025-10-14 17:56:26.208514 | 2025-10-14 17:56:26.208735 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-14 17:56:26.846490 | noble | changed 2025-10-14 17:56:26.856142 | 2025-10-14 17:56:26.856255 | PLAY RECAP 2025-10-14 17:56:26.856327 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-14 17:56:26.856402 | 2025-10-14 17:56:26.973599 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 17:56:26.975641 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-14 17:56:27.783678 | 2025-10-14 17:56:27.783871 | PLAY [Base post-logs] 2025-10-14 17:56:27.794715 | 2025-10-14 17:56:27.794885 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-14 17:56:28.251158 | localhost | changed 2025-10-14 17:56:28.274469 | 2025-10-14 17:56:28.274628 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-14 17:56:28.304008 | localhost | ok 2025-10-14 17:56:28.307101 | 2025-10-14 17:56:28.307208 | TASK [Set zuul-log-path fact] 2025-10-14 17:56:28.321637 | localhost | ok 2025-10-14 17:56:28.329428 | 2025-10-14 17:56:28.329536 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 17:56:28.365147 | localhost | ok 2025-10-14 17:56:28.370787 | 2025-10-14 17:56:28.370942 | TASK [upload-logs : Create log directories] 2025-10-14 17:56:28.889074 | localhost | changed 2025-10-14 17:56:28.896720 | 2025-10-14 17:56:28.896913 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-14 17:56:29.398022 | localhost -> localhost | ok: Runtime: 0:00:00.005108 2025-10-14 17:56:29.403990 | 2025-10-14 17:56:29.404224 | TASK [upload-logs : Upload logs to log server] 2025-10-14 17:56:29.976677 | localhost | Output suppressed because no_log was given 2025-10-14 17:56:29.978560 | 2025-10-14 17:56:29.978736 | LOOP [upload-logs : Compress console log and json output] 2025-10-14 17:56:30.031486 | localhost | skipping: Conditional result was False 2025-10-14 17:56:30.036544 | localhost | skipping: Conditional result was False 2025-10-14 17:56:30.045568 | 2025-10-14 17:56:30.045690 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-14 17:56:30.092105 | localhost | skipping: Conditional result was False 2025-10-14 17:56:30.092642 | 2025-10-14 17:56:30.096170 | localhost | skipping: Conditional result was False 2025-10-14 17:56:30.103574 | 2025-10-14 17:56:30.103796 | LOOP [upload-logs : Upload console log and json output]