2025-08-08 10:11:02.205248 | Job console starting 2025-08-08 10:11:02.216900 | Updating git repos 2025-08-08 10:11:02.242755 | Cloning repos into workspace 2025-08-08 10:11:02.346506 | Restoring repo states 2025-08-08 10:11:02.356219 | Merging changes 2025-08-08 10:11:02.356254 | Checking out repos 2025-08-08 10:11:02.422755 | Preparing playbooks 2025-08-08 10:11:03.038504 | Running Ansible setup 2025-08-08 10:11:08.772284 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-08 10:11:09.525424 | 2025-08-08 10:11:09.525592 | PLAY [Base pre] 2025-08-08 10:11:09.542792 | 2025-08-08 10:11:09.542959 | TASK [Setup log path fact] 2025-08-08 10:11:09.574045 | noble | ok 2025-08-08 10:11:09.591479 | 2025-08-08 10:11:09.591608 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-08 10:11:09.640830 | noble | ok 2025-08-08 10:11:09.657256 | 2025-08-08 10:11:09.657377 | TASK [emit-job-header : Print job information] 2025-08-08 10:11:09.711509 | # Job Information 2025-08-08 10:11:09.711762 | Ansible Version: 2.16.14 2025-08-08 10:11:09.711821 | Job: openstack-ironic-images-publish-osism-esp 2025-08-08 10:11:09.711877 | Pipeline: post 2025-08-08 10:11:09.711916 | Executor: 521e9411259a 2025-08-08 10:11:09.711949 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-08-08 10:11:09.711985 | Event ID: f6aa6553920f443bbb45f0ec0345ece7 2025-08-08 10:11:09.721041 | 2025-08-08 10:11:09.721214 | LOOP [emit-job-header : Print node information] 2025-08-08 10:11:09.863408 | noble | ok: 2025-08-08 10:11:09.863593 | noble | # Node Information 2025-08-08 10:11:09.863633 | noble | Inventory Hostname: noble 2025-08-08 10:11:09.863669 | noble | Hostname: ubuntu 2025-08-08 10:11:09.863697 | noble | Username: zuul 2025-08-08 10:11:09.863718 | noble | Distro: Ubuntu 24.04 2025-08-08 10:11:09.863742 | noble | Provider: regiocloud-a 2025-08-08 10:11:09.863763 | noble | Region: 2025-08-08 10:11:09.863785 | noble | Label: ubuntu-noble-uefi 2025-08-08 10:11:09.863804 | noble | Product Name: OpenStack Nova 2025-08-08 10:11:09.863824 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7e:e29 2025-08-08 10:11:09.882030 | 2025-08-08 10:11:09.882195 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-08 10:11:10.362520 | noble -> localhost | changed 2025-08-08 10:11:10.372805 | 2025-08-08 10:11:10.372930 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-08 10:11:11.476335 | noble -> localhost | changed 2025-08-08 10:11:11.516899 | 2025-08-08 10:11:11.517033 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-08 10:11:11.794072 | noble -> localhost | ok 2025-08-08 10:11:11.801569 | 2025-08-08 10:11:11.801691 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-08 10:11:11.836251 | noble | ok 2025-08-08 10:11:11.853876 | noble | included: /var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-08 10:11:11.863246 | 2025-08-08 10:11:11.863357 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-08 10:11:12.869325 | noble -> localhost | Generating public/private rsa key pair. 2025-08-08 10:11:12.869549 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/work/8113688b46b840f099647f1b0636f523_id_rsa 2025-08-08 10:11:12.869587 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/work/8113688b46b840f099647f1b0636f523_id_rsa.pub 2025-08-08 10:11:12.869615 | noble -> localhost | The key fingerprint is: 2025-08-08 10:11:12.869640 | noble -> localhost | SHA256:k9N2hpi1QjUaP0D5E6RqmwjHQHUsKjRwzyzF6IrOtfM zuul-build-sshkey 2025-08-08 10:11:12.869665 | noble -> localhost | The key's randomart image is: 2025-08-08 10:11:12.869688 | noble -> localhost | +---[RSA 3072]----+ 2025-08-08 10:11:12.869708 | noble -> localhost | |o o+o...+o+ | 2025-08-08 10:11:12.869730 | noble -> localhost | | =.=o.. .B.. | 2025-08-08 10:11:12.869771 | noble -> localhost | |..+.+. +.+. | 2025-08-08 10:11:12.869793 | noble -> localhost | |. o+ o *o+ | 2025-08-08 10:11:12.869814 | noble -> localhost | |.o. o o S =.o | 2025-08-08 10:11:12.869841 | noble -> localhost | |o + o o = o | 2025-08-08 10:11:12.869862 | noble -> localhost | |o . o o | 2025-08-08 10:11:12.869882 | noble -> localhost | | o o | 2025-08-08 10:11:12.869903 | noble -> localhost | | oE | 2025-08-08 10:11:12.869923 | noble -> localhost | +----[SHA256]-----+ 2025-08-08 10:11:12.869976 | noble -> localhost | ok: Runtime: 0:00:00.495052 2025-08-08 10:11:12.877607 | 2025-08-08 10:11:12.877715 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-08 10:11:12.897371 | noble | ok 2025-08-08 10:11:12.907228 | noble | included: /var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-08 10:11:12.916557 | 2025-08-08 10:11:12.916664 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-08 10:11:12.937616 | noble | skipping: Conditional result was False 2025-08-08 10:11:12.945595 | 2025-08-08 10:11:12.945700 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-08 10:11:13.577346 | noble | changed 2025-08-08 10:11:13.586173 | 2025-08-08 10:11:13.586305 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-08 10:11:13.898798 | noble | ok 2025-08-08 10:11:13.905407 | 2025-08-08 10:11:13.905525 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-08 10:11:14.693729 | noble | changed 2025-08-08 10:11:14.702933 | 2025-08-08 10:11:14.703059 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-08 10:11:15.485998 | noble | changed 2025-08-08 10:11:15.492902 | 2025-08-08 10:11:15.493024 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-08 10:11:15.517615 | noble | skipping: Conditional result was False 2025-08-08 10:11:15.535122 | 2025-08-08 10:11:15.535309 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-08 10:11:15.975860 | noble -> localhost | changed 2025-08-08 10:11:15.990216 | 2025-08-08 10:11:15.990342 | TASK [add-build-sshkey : Add back temp key] 2025-08-08 10:11:16.317640 | noble -> localhost | Identity added: /var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/work/8113688b46b840f099647f1b0636f523_id_rsa (zuul-build-sshkey) 2025-08-08 10:11:16.317896 | noble -> localhost | ok: Runtime: 0:00:00.011004 2025-08-08 10:11:16.325447 | 2025-08-08 10:11:16.325566 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-08 10:11:16.786294 | noble | ok 2025-08-08 10:11:16.795233 | 2025-08-08 10:11:16.795375 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-08 10:11:16.829237 | noble | skipping: Conditional result was False 2025-08-08 10:11:16.880061 | 2025-08-08 10:11:16.880200 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-08 10:11:17.306665 | noble | ok 2025-08-08 10:11:17.318114 | 2025-08-08 10:11:17.318246 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-08 10:11:17.358015 | noble | ok 2025-08-08 10:11:17.365512 | 2025-08-08 10:11:17.365623 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-08 10:11:17.676892 | noble -> localhost | ok 2025-08-08 10:11:17.684528 | 2025-08-08 10:11:17.684636 | TASK [validate-host : Collect information about the host] 2025-08-08 10:11:19.187757 | noble | ok 2025-08-08 10:11:19.204938 | 2025-08-08 10:11:19.205064 | TASK [validate-host : Sanitize hostname] 2025-08-08 10:11:19.280512 | noble | ok 2025-08-08 10:11:19.288849 | 2025-08-08 10:11:19.288986 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-08 10:11:19.890277 | noble -> localhost | changed 2025-08-08 10:11:19.896828 | 2025-08-08 10:11:19.896940 | TASK [validate-host : Collect information about zuul worker] 2025-08-08 10:11:20.376332 | noble | ok 2025-08-08 10:11:20.384926 | 2025-08-08 10:11:20.385071 | TASK [validate-host : Write out all zuul information for each host] 2025-08-08 10:11:20.937023 | noble -> localhost | changed 2025-08-08 10:11:20.947885 | 2025-08-08 10:11:20.948007 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-08 10:11:21.278990 | noble | ok 2025-08-08 10:11:21.288609 | 2025-08-08 10:11:21.288745 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-08 10:11:22.229948 | noble | changed: 2025-08-08 10:11:22.230295 | noble | cd+++++++++ src/ 2025-08-08 10:11:22.230358 | noble | cd+++++++++ src/github.com/ 2025-08-08 10:11:22.230399 | noble | cd+++++++++ src/github.com/osism/ 2025-08-08 10:11:22.230432 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-08-08 10:11:22.230460 | noble | localhost | ok: "/var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/work/logs" 2025-08-08 10:15:45.824201 | noble -> localhost | changed: "/var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/work/artifacts" 2025-08-08 10:15:46.094447 | noble -> localhost | changed: "/var/lib/zuul/builds/8113688b46b840f099647f1b0636f523/work/docs" 2025-08-08 10:15:46.131350 | 2025-08-08 10:15:46.131485 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-08-08 10:15:47.363724 | noble | changed: 2025-08-08 10:15:47.364004 | noble | .d..t...... ./ 2025-08-08 10:15:47.364053 | noble | >f+++++++++ osism-esp.log 2025-08-08 10:15:47.364142 | noble | changed: All items complete 2025-08-08 10:15:47.364187 | 2025-08-08 10:15:47.983486 | noble | changed: .d..t...... ./ 2025-08-08 10:15:48.624968 | noble | changed: .d..t...... ./ 2025-08-08 10:15:48.655684 | 2025-08-08 10:15:48.655825 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-08-08 10:15:48.689020 | noble | skipping: Conditional result was False 2025-08-08 10:15:48.691295 | noble | skipping: Conditional result was False 2025-08-08 10:15:48.716580 | 2025-08-08 10:15:48.716694 | PLAY RECAP 2025-08-08 10:15:48.716775 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-08-08 10:15:48.716814 | 2025-08-08 10:15:48.841054 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-08-08 10:15:48.843402 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-08 10:15:49.584887 | 2025-08-08 10:15:49.585045 | PLAY [Base post] 2025-08-08 10:15:49.600010 | 2025-08-08 10:15:49.600181 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-08 10:15:50.241365 | noble | changed 2025-08-08 10:15:50.252152 | 2025-08-08 10:15:50.252280 | PLAY RECAP 2025-08-08 10:15:50.252353 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-08 10:15:50.252426 | 2025-08-08 10:15:50.369414 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-08 10:15:50.371538 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-08 10:15:51.136381 | 2025-08-08 10:15:51.136553 | PLAY [Base post-logs] 2025-08-08 10:15:51.148000 | 2025-08-08 10:15:51.148163 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-08 10:15:51.613028 | localhost | changed 2025-08-08 10:15:51.629283 | 2025-08-08 10:15:51.629453 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-08 10:15:51.668226 | localhost | ok 2025-08-08 10:15:51.675284 | 2025-08-08 10:15:51.675454 | TASK [Set zuul-log-path fact] 2025-08-08 10:15:51.693978 | localhost | ok 2025-08-08 10:15:51.708833 | 2025-08-08 10:15:51.708976 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-08 10:15:51.746697 | localhost | ok 2025-08-08 10:15:51.754398 | 2025-08-08 10:15:51.754601 | TASK [upload-logs : Create log directories] 2025-08-08 10:15:52.248899 | localhost | changed 2025-08-08 10:15:52.251851 | 2025-08-08 10:15:52.251960 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-08 10:15:52.733367 | localhost -> localhost | ok: Runtime: 0:00:00.007176 2025-08-08 10:15:52.743403 | 2025-08-08 10:15:52.743624 | TASK [upload-logs : Upload logs to log server] 2025-08-08 10:15:53.299869 | localhost | Output suppressed because no_log was given 2025-08-08 10:15:53.301760 | 2025-08-08 10:15:53.301862 | LOOP [upload-logs : Compress console log and json output] 2025-08-08 10:15:53.360147 | localhost | skipping: Conditional result was False 2025-08-08 10:15:53.368252 | localhost | skipping: Conditional result was False 2025-08-08 10:15:53.382082 | 2025-08-08 10:15:53.382344 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-08 10:15:53.430974 | localhost | skipping: Conditional result was False 2025-08-08 10:15:53.431553 | 2025-08-08 10:15:53.434389 | localhost | skipping: Conditional result was False 2025-08-08 10:15:53.448497 | 2025-08-08 10:15:53.448727 | LOOP [upload-logs : Upload console log and json output]