2025-10-14 17:38:46.784455 | Job console starting 2025-10-14 17:38:46.792785 | Updating git repos 2025-10-14 17:38:46.815605 | Cloning repos into workspace 2025-10-14 17:38:46.854078 | Restoring repo states 2025-10-14 17:38:46.861819 | Merging changes 2025-10-14 17:38:46.861835 | Checking out repos 2025-10-14 17:38:46.897120 | Preparing playbooks 2025-10-14 17:38:47.513074 | Running Ansible setup 2025-10-14 17:38:53.857684 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-14 17:38:54.698159 | 2025-10-14 17:38:54.698386 | PLAY [Base pre] 2025-10-14 17:38:54.722761 | 2025-10-14 17:38:54.722924 | TASK [Setup log path fact] 2025-10-14 17:38:54.742756 | noble | ok 2025-10-14 17:38:54.760322 | 2025-10-14 17:38:54.760463 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 17:38:54.800078 | noble | ok 2025-10-14 17:38:54.811860 | 2025-10-14 17:38:54.812004 | TASK [emit-job-header : Print job information] 2025-10-14 17:38:54.858802 | # Job Information 2025-10-14 17:38:54.859021 | Ansible Version: 2.16.14 2025-10-14 17:38:54.859060 | Job: openstack-ironic-images-publish-osism-esp 2025-10-14 17:38:54.859094 | Pipeline: post 2025-10-14 17:38:54.859118 | Executor: 521e9411259a 2025-10-14 17:38:54.859139 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/8c3745cddb5697fe1bcedf92c3cc5a9e5e151e5d 2025-10-14 17:38:54.859203 | Event ID: 39f6d0bc-a924-11f0-8af4-67719c070d0b 2025-10-14 17:38:54.868391 | 2025-10-14 17:38:54.873051 | LOOP [emit-job-header : Print node information] 2025-10-14 17:38:55.041311 | noble | ok: 2025-10-14 17:38:55.041517 | noble | # Node Information 2025-10-14 17:38:55.041550 | noble | Inventory Hostname: noble 2025-10-14 17:38:55.041575 | noble | Hostname: ubuntu 2025-10-14 17:38:55.041596 | noble | Username: zuul 2025-10-14 17:38:55.041616 | noble | Distro: Ubuntu 24.04 2025-10-14 17:38:55.041639 | noble | Provider: regiocloud-a 2025-10-14 17:38:55.041660 | noble | Region: 2025-10-14 17:38:55.041681 | noble | Label: ubuntu-noble-uefi 2025-10-14 17:38:55.041701 | noble | Product Name: OpenStack Nova 2025-10-14 17:38:55.041720 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb6:4ba5 2025-10-14 17:38:55.073088 | 2025-10-14 17:38:55.073233 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-14 17:38:55.767310 | noble -> localhost | changed 2025-10-14 17:38:55.775830 | 2025-10-14 17:38:55.776165 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-14 17:38:57.005092 | noble -> localhost | changed 2025-10-14 17:38:57.018925 | 2025-10-14 17:38:57.019037 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-14 17:38:57.264940 | noble -> localhost | ok 2025-10-14 17:38:57.271849 | 2025-10-14 17:38:57.272008 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-14 17:38:57.289903 | noble | ok 2025-10-14 17:38:57.305340 | noble | included: /var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-14 17:38:57.317408 | 2025-10-14 17:38:57.317490 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-14 17:38:58.691850 | noble -> localhost | Generating public/private rsa key pair. 2025-10-14 17:38:58.692134 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/work/dff0d38b53984a7288c7071d5eaaf3a5_id_rsa 2025-10-14 17:38:58.692173 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/work/dff0d38b53984a7288c7071d5eaaf3a5_id_rsa.pub 2025-10-14 17:38:58.692199 | noble -> localhost | The key fingerprint is: 2025-10-14 17:38:58.692222 | noble -> localhost | SHA256:2le09CRHBkkseZ8bUmzzNFbHfNLPceRjOkuA9qOajBs zuul-build-sshkey 2025-10-14 17:38:58.692254 | noble -> localhost | The key's randomart image is: 2025-10-14 17:38:58.692276 | noble -> localhost | +---[RSA 3072]----+ 2025-10-14 17:38:58.692304 | noble -> localhost | | +o+o==| 2025-10-14 17:38:58.692337 | noble -> localhost | | + +o*=O| 2025-10-14 17:38:58.692358 | noble -> localhost | | o ++++XB| 2025-10-14 17:38:58.692378 | noble -> localhost | | . .oo** =| 2025-10-14 17:38:58.692398 | noble -> localhost | | S oo=.o | 2025-10-14 17:38:58.692425 | noble -> localhost | | o ..o + | 2025-10-14 17:38:58.692447 | noble -> localhost | | E. ... . | 2025-10-14 17:38:58.692468 | noble -> localhost | | + o. | 2025-10-14 17:38:58.692490 | noble -> localhost | | o.+ | 2025-10-14 17:38:58.692511 | noble -> localhost | +----[SHA256]-----+ 2025-10-14 17:38:58.692562 | noble -> localhost | ok: Runtime: 0:00:00.913137 2025-10-14 17:38:58.700018 | 2025-10-14 17:38:58.700109 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-14 17:38:58.728816 | noble | ok 2025-10-14 17:38:58.738959 | noble | included: /var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-14 17:38:58.748382 | 2025-10-14 17:38:58.748480 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-14 17:38:58.772856 | noble | skipping: Conditional result was False 2025-10-14 17:38:58.782516 | 2025-10-14 17:38:58.782633 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-14 17:38:59.425636 | noble | changed 2025-10-14 17:38:59.432135 | 2025-10-14 17:38:59.432226 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-14 17:38:59.738263 | noble | ok 2025-10-14 17:38:59.744434 | 2025-10-14 17:38:59.744519 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-14 17:39:00.485121 | noble | changed 2025-10-14 17:39:00.498698 | 2025-10-14 17:39:00.498811 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-14 17:39:01.296558 | noble | changed 2025-10-14 17:39:01.302788 | 2025-10-14 17:39:01.302898 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-14 17:39:01.316819 | noble | skipping: Conditional result was False 2025-10-14 17:39:01.323668 | 2025-10-14 17:39:01.323755 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-14 17:39:01.791998 | noble -> localhost | changed 2025-10-14 17:39:01.815279 | 2025-10-14 17:39:01.815402 | TASK [add-build-sshkey : Add back temp key] 2025-10-14 17:39:02.131703 | noble -> localhost | Identity added: /var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/work/dff0d38b53984a7288c7071d5eaaf3a5_id_rsa (zuul-build-sshkey) 2025-10-14 17:39:02.131943 | noble -> localhost | ok: Runtime: 0:00:00.018763 2025-10-14 17:39:02.139411 | 2025-10-14 17:39:02.139508 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-14 17:39:02.599808 | noble | ok 2025-10-14 17:39:02.608044 | 2025-10-14 17:39:02.608152 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-14 17:39:02.631711 | noble | skipping: Conditional result was False 2025-10-14 17:39:02.672741 | 2025-10-14 17:39:02.672840 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-14 17:39:03.199572 | noble | ok 2025-10-14 17:39:03.213981 | 2025-10-14 17:39:03.214084 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-14 17:39:03.253586 | noble | ok 2025-10-14 17:39:03.260547 | 2025-10-14 17:39:03.260634 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-14 17:39:03.526145 | noble -> localhost | ok 2025-10-14 17:39:03.533577 | 2025-10-14 17:39:03.533675 | TASK [validate-host : Collect information about the host] 2025-10-14 17:39:04.971910 | noble | ok 2025-10-14 17:39:04.986230 | 2025-10-14 17:39:04.986329 | TASK [validate-host : Sanitize hostname] 2025-10-14 17:39:05.034742 | noble | ok 2025-10-14 17:39:05.040032 | 2025-10-14 17:39:05.040124 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-14 17:39:05.600400 | noble -> localhost | changed 2025-10-14 17:39:05.613123 | 2025-10-14 17:39:05.613275 | TASK [validate-host : Collect information about zuul worker] 2025-10-14 17:39:06.088309 | noble | ok 2025-10-14 17:39:06.096822 | 2025-10-14 17:39:06.096953 | TASK [validate-host : Write out all zuul information for each host] 2025-10-14 17:39:06.647385 | noble -> localhost | changed 2025-10-14 17:39:06.664585 | 2025-10-14 17:39:06.664784 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-14 17:39:06.997186 | noble | ok 2025-10-14 17:39:07.008496 | 2025-10-14 17:39:07.008615 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-14 17:39:07.938405 | noble | changed: 2025-10-14 17:39:07.938602 | noble | cd+++++++++ src/ 2025-10-14 17:39:07.938637 | noble | cd+++++++++ src/github.com/ 2025-10-14 17:39:07.938662 | noble | cd+++++++++ src/github.com/osism/ 2025-10-14 17:39:07.938683 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-14 17:39:07.938704 | noble | localhost | ok: "/var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/work/logs" 2025-10-14 17:46:55.943694 | noble -> localhost | changed: "/var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/work/artifacts" 2025-10-14 17:46:56.225922 | noble -> localhost | changed: "/var/lib/zuul/builds/dff0d38b53984a7288c7071d5eaaf3a5/work/docs" 2025-10-14 17:46:56.253170 | 2025-10-14 17:46:56.253427 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-14 17:46:57.554470 | noble | changed: 2025-10-14 17:46:57.554793 | noble | .d..t...... ./ 2025-10-14 17:46:57.554919 | noble | >f+++++++++ osism-esp.log 2025-10-14 17:46:57.555007 | noble | changed: All items complete 2025-10-14 17:46:57.555053 | 2025-10-14 17:46:58.232665 | noble | changed: .d..t...... ./ 2025-10-14 17:46:58.920601 | noble | changed: .d..t...... ./ 2025-10-14 17:46:58.950347 | 2025-10-14 17:46:58.950490 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-14 17:46:58.988225 | noble | skipping: Conditional result was False 2025-10-14 17:46:58.991064 | noble | skipping: Conditional result was False 2025-10-14 17:46:59.011426 | 2025-10-14 17:46:59.011547 | PLAY RECAP 2025-10-14 17:46:59.011632 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-14 17:46:59.011673 | 2025-10-14 17:46:59.143466 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-14 17:46:59.144449 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 17:46:59.841406 | 2025-10-14 17:46:59.841560 | PLAY [Base post] 2025-10-14 17:46:59.856170 | 2025-10-14 17:46:59.856292 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-14 17:47:00.524691 | noble | changed 2025-10-14 17:47:00.534797 | 2025-10-14 17:47:00.535026 | PLAY RECAP 2025-10-14 17:47:00.535113 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-14 17:47:00.535195 | 2025-10-14 17:47:00.673962 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 17:47:00.676132 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-14 17:47:01.444260 | 2025-10-14 17:47:01.444420 | PLAY [Base post-logs] 2025-10-14 17:47:01.454983 | 2025-10-14 17:47:01.455121 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-14 17:47:01.905610 | localhost | changed 2025-10-14 17:47:01.915856 | 2025-10-14 17:47:01.916017 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-14 17:47:01.952033 | localhost | ok 2025-10-14 17:47:01.956633 | 2025-10-14 17:47:01.956759 | TASK [Set zuul-log-path fact] 2025-10-14 17:47:01.984303 | localhost | ok 2025-10-14 17:47:01.998990 | 2025-10-14 17:47:01.999138 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 17:47:02.037855 | localhost | ok 2025-10-14 17:47:02.044803 | 2025-10-14 17:47:02.044992 | TASK [upload-logs : Create log directories] 2025-10-14 17:47:02.550171 | localhost | changed 2025-10-14 17:47:02.553270 | 2025-10-14 17:47:02.553377 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-14 17:47:03.055955 | localhost -> localhost | ok: Runtime: 0:00:00.006831 2025-10-14 17:47:03.065633 | 2025-10-14 17:47:03.065812 | TASK [upload-logs : Upload logs to log server] 2025-10-14 17:47:03.624180 | localhost | Output suppressed because no_log was given 2025-10-14 17:47:03.627148 | 2025-10-14 17:47:03.627304 | LOOP [upload-logs : Compress console log and json output] 2025-10-14 17:47:03.683449 | localhost | skipping: Conditional result was False 2025-10-14 17:47:03.688578 | localhost | skipping: Conditional result was False 2025-10-14 17:47:03.697304 | 2025-10-14 17:47:03.697419 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-14 17:47:03.749136 | localhost | skipping: Conditional result was False 2025-10-14 17:47:03.749657 | 2025-10-14 17:47:03.753249 | localhost | skipping: Conditional result was False 2025-10-14 17:47:03.766512 | 2025-10-14 17:47:03.766741 | LOOP [upload-logs : Upload console log and json output]