2025-07-31 09:54:02.057306 | Job console starting 2025-07-31 09:54:02.067276 | Updating git repos 2025-07-31 09:54:02.080438 | Cloning repos into workspace 2025-07-31 09:54:02.137992 | Restoring repo states 2025-07-31 09:54:02.144152 | Merging changes 2025-07-31 09:54:02.607923 | Checking out repos 2025-07-31 09:54:02.639126 | Preparing playbooks 2025-07-31 09:54:03.184354 | Running Ansible setup 2025-07-31 09:54:08.780334 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-31 09:54:09.532881 | 2025-07-31 09:54:09.533061 | PLAY [Base pre] 2025-07-31 09:54:09.550525 | 2025-07-31 09:54:09.550686 | TASK [Setup log path fact] 2025-07-31 09:54:09.570512 | noble | ok 2025-07-31 09:54:09.590300 | 2025-07-31 09:54:09.590515 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 09:54:09.620125 | noble | ok 2025-07-31 09:54:09.631998 | 2025-07-31 09:54:09.632129 | TASK [emit-job-header : Print job information] 2025-07-31 09:54:09.675039 | # Job Information 2025-07-31 09:54:09.675262 | Ansible Version: 2.16.14 2025-07-31 09:54:09.675309 | Job: openstack-ironic-images-build-osism-ipa 2025-07-31 09:54:09.675353 | Pipeline: check 2025-07-31 09:54:09.675384 | Executor: 521e9411259a 2025-07-31 09:54:09.675412 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/118 2025-07-31 09:54:09.675442 | Event ID: dda2a630-6df3-11f0-906c-144e92250a86 2025-07-31 09:54:09.683617 | 2025-07-31 09:54:09.683756 | LOOP [emit-job-header : Print node information] 2025-07-31 09:54:09.796437 | noble | ok: 2025-07-31 09:54:09.796747 | noble | # Node Information 2025-07-31 09:54:09.796792 | noble | Inventory Hostname: noble 2025-07-31 09:54:09.796818 | noble | Hostname: ubuntu 2025-07-31 09:54:09.796840 | noble | Username: zuul 2025-07-31 09:54:09.796861 | noble | Distro: Ubuntu 24.04 2025-07-31 09:54:09.796885 | noble | Provider: regiocloud-a 2025-07-31 09:54:09.796906 | noble | Region: 2025-07-31 09:54:09.796928 | noble | Label: ubuntu-noble-uefi 2025-07-31 09:54:09.796949 | noble | Product Name: OpenStack Nova 2025-07-31 09:54:09.796970 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe35:a323 2025-07-31 09:54:09.818323 | 2025-07-31 09:54:09.818486 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-31 09:54:10.278514 | noble -> localhost | changed 2025-07-31 09:54:10.288587 | 2025-07-31 09:54:10.288727 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-31 09:54:11.298207 | noble -> localhost | changed 2025-07-31 09:54:11.312603 | 2025-07-31 09:54:11.312731 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-31 09:54:11.580685 | noble -> localhost | ok 2025-07-31 09:54:11.587990 | 2025-07-31 09:54:11.588107 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-31 09:54:11.619619 | noble | ok 2025-07-31 09:54:11.636018 | noble | included: /var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-31 09:54:11.644109 | 2025-07-31 09:54:11.644209 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-31 09:54:13.092898 | noble -> localhost | Generating public/private rsa key pair. 2025-07-31 09:54:13.093179 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/work/c28639fe7f08453c997b24885e0c6444_id_rsa 2025-07-31 09:54:13.093230 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/work/c28639fe7f08453c997b24885e0c6444_id_rsa.pub 2025-07-31 09:54:13.093265 | noble -> localhost | The key fingerprint is: 2025-07-31 09:54:13.093298 | noble -> localhost | SHA256:teSjYWen1CjM2PIhgQKxiGNjNxRcgI/szI83U+AI1vw zuul-build-sshkey 2025-07-31 09:54:13.093327 | noble -> localhost | The key's randomart image is: 2025-07-31 09:54:13.093356 | noble -> localhost | +---[RSA 3072]----+ 2025-07-31 09:54:13.093384 | noble -> localhost | |o.o++. | 2025-07-31 09:54:13.093427 | noble -> localhost | |o+.. . | 2025-07-31 09:54:13.093506 | noble -> localhost | |*+*o. . o | 2025-07-31 09:54:13.093537 | noble -> localhost | |+=o*. * + + | 2025-07-31 09:54:13.093565 | noble -> localhost | |* o o + S O o | 2025-07-31 09:54:13.093598 | noble -> localhost | | = . E = O + | 2025-07-31 09:54:13.093626 | noble -> localhost | | o . o . | 2025-07-31 09:54:13.093652 | noble -> localhost | | . = | 2025-07-31 09:54:13.093680 | noble -> localhost | | . o | 2025-07-31 09:54:13.093707 | noble -> localhost | +----[SHA256]-----+ 2025-07-31 09:54:13.093777 | noble -> localhost | ok: Runtime: 0:00:00.944910 2025-07-31 09:54:13.102747 | 2025-07-31 09:54:13.102889 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-31 09:54:13.139095 | noble | ok 2025-07-31 09:54:13.152557 | noble | included: /var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-31 09:54:13.162047 | 2025-07-31 09:54:13.162143 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-31 09:54:13.185456 | noble | skipping: Conditional result was False 2025-07-31 09:54:13.196123 | 2025-07-31 09:54:13.196238 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-31 09:54:13.817909 | noble | changed 2025-07-31 09:54:13.825868 | 2025-07-31 09:54:13.825984 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-31 09:54:14.150589 | noble | ok 2025-07-31 09:54:14.161126 | 2025-07-31 09:54:14.161492 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-31 09:54:14.948007 | noble | changed 2025-07-31 09:54:14.956000 | 2025-07-31 09:54:14.956134 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-31 09:54:15.709732 | noble | changed 2025-07-31 09:54:15.720817 | 2025-07-31 09:54:15.720964 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-31 09:54:15.746036 | noble | skipping: Conditional result was False 2025-07-31 09:54:15.758345 | 2025-07-31 09:54:15.758556 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-31 09:54:16.199261 | noble -> localhost | changed 2025-07-31 09:54:16.223669 | 2025-07-31 09:54:16.223813 | TASK [add-build-sshkey : Add back temp key] 2025-07-31 09:54:16.592741 | noble -> localhost | Identity added: /var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/work/c28639fe7f08453c997b24885e0c6444_id_rsa (zuul-build-sshkey) 2025-07-31 09:54:16.592992 | noble -> localhost | ok: Runtime: 0:00:00.019723 2025-07-31 09:54:16.600277 | 2025-07-31 09:54:16.600396 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-31 09:54:17.047426 | noble | ok 2025-07-31 09:54:17.055865 | 2025-07-31 09:54:17.055994 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-31 09:54:17.090498 | noble | skipping: Conditional result was False 2025-07-31 09:54:17.148426 | 2025-07-31 09:54:17.148580 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-31 09:54:17.592581 | noble | ok 2025-07-31 09:54:17.610677 | 2025-07-31 09:54:17.611377 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-31 09:54:17.661411 | noble | ok 2025-07-31 09:54:17.675919 | 2025-07-31 09:54:17.676086 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-31 09:54:17.994324 | noble -> localhost | ok 2025-07-31 09:54:18.010749 | 2025-07-31 09:54:18.010945 | TASK [validate-host : Collect information about the host] 2025-07-31 09:54:19.384607 | noble | ok 2025-07-31 09:54:19.403646 | 2025-07-31 09:54:19.403775 | TASK [validate-host : Sanitize hostname] 2025-07-31 09:54:19.457825 | noble | ok 2025-07-31 09:54:19.466066 | 2025-07-31 09:54:19.466203 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-31 09:54:20.058509 | noble -> localhost | changed 2025-07-31 09:54:20.065006 | 2025-07-31 09:54:20.065116 | TASK [validate-host : Collect information about zuul worker] 2025-07-31 09:54:20.528566 | noble | ok 2025-07-31 09:54:20.534004 | 2025-07-31 09:54:20.534118 | TASK [validate-host : Write out all zuul information for each host] 2025-07-31 09:54:21.076163 | noble -> localhost | changed 2025-07-31 09:54:21.096507 | 2025-07-31 09:54:21.096658 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-31 09:54:21.439007 | noble | ok 2025-07-31 09:54:21.449199 | 2025-07-31 09:54:21.449334 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-31 09:54:22.396783 | noble | changed: 2025-07-31 09:54:22.397014 | noble | cd+++++++++ src/ 2025-07-31 09:54:22.397059 | noble | cd+++++++++ src/github.com/ 2025-07-31 09:54:22.397091 | noble | cd+++++++++ src/github.com/osism/ 2025-07-31 09:54:22.397118 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-31 09:54:22.397144 | noble | localhost | ok: "/var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/work/logs" 2025-07-31 09:59:47.765873 | noble -> localhost | changed: "/var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/work/artifacts" 2025-07-31 09:59:48.041145 | noble -> localhost | changed: "/var/lib/zuul/builds/c28639fe7f08453c997b24885e0c6444/work/docs" 2025-07-31 09:59:48.066603 | 2025-07-31 09:59:48.066798 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-31 09:59:49.369716 | noble | changed: 2025-07-31 09:59:49.370066 | noble | .d..t...... ./ 2025-07-31 09:59:49.370121 | noble | >f+++++++++ osism-ipa.log 2025-07-31 09:59:49.370192 | noble | changed: All items complete 2025-07-31 09:59:49.370237 | 2025-07-31 09:59:50.028390 | noble | changed: .d..t...... ./ 2025-07-31 09:59:50.659121 | noble | changed: .d..t...... ./ 2025-07-31 09:59:50.674735 | 2025-07-31 09:59:50.674879 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-31 09:59:51.188133 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.006647 2025-07-31 09:59:51.455537 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.008196 2025-07-31 09:59:51.466937 | 2025-07-31 09:59:51.467030 | PLAY RECAP 2025-07-31 09:59:51.467082 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-31 09:59:51.467107 | 2025-07-31 09:59:51.590662 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-31 09:59:51.591599 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 09:59:52.367691 | 2025-07-31 09:59:52.367861 | PLAY [Base post] 2025-07-31 09:59:52.382268 | 2025-07-31 09:59:52.382407 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-31 09:59:53.018926 | noble | changed 2025-07-31 09:59:53.031920 | 2025-07-31 09:59:53.032124 | PLAY RECAP 2025-07-31 09:59:53.032239 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-31 09:59:53.032334 | 2025-07-31 09:59:53.149822 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 09:59:53.151028 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-31 09:59:53.937061 | 2025-07-31 09:59:53.937329 | PLAY [Base post-logs] 2025-07-31 09:59:53.951863 | 2025-07-31 09:59:53.952012 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-31 09:59:54.411144 | localhost | changed 2025-07-31 09:59:54.421739 | 2025-07-31 09:59:54.421898 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-31 09:59:54.457347 | localhost | ok 2025-07-31 09:59:54.461103 | 2025-07-31 09:59:54.461216 | TASK [Set zuul-log-path fact] 2025-07-31 09:59:54.476919 | localhost | ok 2025-07-31 09:59:54.485298 | 2025-07-31 09:59:54.485405 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 09:59:54.522527 | localhost | ok 2025-07-31 09:59:54.531214 | 2025-07-31 09:59:54.531399 | TASK [upload-logs : Create log directories] 2025-07-31 09:59:55.026864 | localhost | changed 2025-07-31 09:59:55.029672 | 2025-07-31 09:59:55.029775 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-31 09:59:55.548715 | localhost -> localhost | ok: Runtime: 0:00:00.007042 2025-07-31 09:59:55.556633 | 2025-07-31 09:59:55.556804 | TASK [upload-logs : Upload logs to log server] 2025-07-31 09:59:56.092990 | localhost | Output suppressed because no_log was given 2025-07-31 09:59:56.096258 | 2025-07-31 09:59:56.096437 | LOOP [upload-logs : Compress console log and json output] 2025-07-31 09:59:56.146694 | localhost | skipping: Conditional result was False 2025-07-31 09:59:56.153062 | localhost | skipping: Conditional result was False 2025-07-31 09:59:56.163639 | 2025-07-31 09:59:56.163869 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-31 09:59:56.217106 | localhost | skipping: Conditional result was False 2025-07-31 09:59:56.217374 | 2025-07-31 09:59:56.222059 | localhost | skipping: Conditional result was False 2025-07-31 09:59:56.235947 | 2025-07-31 09:59:56.236199 | LOOP [upload-logs : Upload console log and json output]