2025-10-24 08:24:04.104958 | Job console starting 2025-10-24 08:24:04.131474 | Updating git repos 2025-10-24 08:24:04.161641 | Cloning repos into workspace 2025-10-24 08:24:04.213744 | Restoring repo states 2025-10-24 08:24:04.223427 | Merging changes 2025-10-24 08:24:04.741713 | Checking out repos 2025-10-24 08:24:04.787095 | Preparing playbooks 2025-10-24 08:24:05.495578 | Running Ansible setup 2025-10-24 08:24:11.955234 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-24 08:24:12.975441 | 2025-10-24 08:24:12.975605 | PLAY [Base pre] 2025-10-24 08:24:13.014587 | 2025-10-24 08:24:13.014751 | TASK [Setup log path fact] 2025-10-24 08:24:13.034566 | noble | ok 2025-10-24 08:24:13.052031 | 2025-10-24 08:24:13.052221 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 08:24:13.081095 | noble | ok 2025-10-24 08:24:13.093851 | 2025-10-24 08:24:13.093985 | TASK [emit-job-header : Print job information] 2025-10-24 08:24:13.133786 | # Job Information 2025-10-24 08:24:13.133976 | Ansible Version: 2.16.14 2025-10-24 08:24:13.134011 | Job: openstack-ironic-images-build-osism-node 2025-10-24 08:24:13.134044 | Pipeline: check 2025-10-24 08:24:13.134067 | Executor: 521e9411259a 2025-10-24 08:24:13.134088 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/155 2025-10-24 08:24:13.134127 | Event ID: 60569680-b0b2-11f0-8a87-a5c0bb06cef0 2025-10-24 08:24:13.144953 | 2025-10-24 08:24:13.145071 | LOOP [emit-job-header : Print node information] 2025-10-24 08:24:13.356910 | noble | ok: 2025-10-24 08:24:13.357150 | noble | # Node Information 2025-10-24 08:24:13.357190 | noble | Inventory Hostname: noble 2025-10-24 08:24:13.357216 | noble | Hostname: ubuntu 2025-10-24 08:24:13.357237 | noble | Username: zuul 2025-10-24 08:24:13.357257 | noble | Distro: Ubuntu 24.04 2025-10-24 08:24:13.357280 | noble | Provider: regiocloud-a 2025-10-24 08:24:13.357300 | noble | Region: 2025-10-24 08:24:13.357321 | noble | Label: ubuntu-noble-uefi 2025-10-24 08:24:13.357341 | noble | Product Name: OpenStack Nova 2025-10-24 08:24:13.357361 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe63:62e0 2025-10-24 08:24:13.375607 | 2025-10-24 08:24:13.375744 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-24 08:24:13.950365 | noble -> localhost | changed 2025-10-24 08:24:13.958708 | 2025-10-24 08:24:13.958877 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-24 08:24:15.320255 | noble -> localhost | changed 2025-10-24 08:24:15.334467 | 2025-10-24 08:24:15.334607 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-24 08:24:15.643445 | noble -> localhost | ok 2025-10-24 08:24:15.651705 | 2025-10-24 08:24:15.651838 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-24 08:24:15.681126 | noble | ok 2025-10-24 08:24:15.712733 | noble | included: /var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-24 08:24:15.727604 | 2025-10-24 08:24:15.727723 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-24 08:24:17.411878 | noble -> localhost | Generating public/private rsa key pair. 2025-10-24 08:24:17.412528 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/work/789136ee111f454ea558100ec630e2f6_id_rsa 2025-10-24 08:24:17.412648 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/work/789136ee111f454ea558100ec630e2f6_id_rsa.pub 2025-10-24 08:24:17.412717 | noble -> localhost | The key fingerprint is: 2025-10-24 08:24:17.412785 | noble -> localhost | SHA256:m/iBepN+QnYGzWN4dn72HaZiXiTBKNhQ8ZidUyY7izo zuul-build-sshkey 2025-10-24 08:24:17.412843 | noble -> localhost | The key's randomart image is: 2025-10-24 08:24:17.412896 | noble -> localhost | +---[RSA 3072]----+ 2025-10-24 08:24:17.412948 | noble -> localhost | | ..o.. o | 2025-10-24 08:24:17.413023 | noble -> localhost | | + = O | 2025-10-24 08:24:17.413076 | noble -> localhost | | . O O o | 2025-10-24 08:24:17.413146 | noble -> localhost | | o X = . | 2025-10-24 08:24:17.413197 | noble -> localhost | | *S= . . | 2025-10-24 08:24:17.413262 | noble -> localhost | | +ooo. = o | 2025-10-24 08:24:17.413312 | noble -> localhost | | Eo++ o oo..| 2025-10-24 08:24:17.413361 | noble -> localhost | | .*... o... .| 2025-10-24 08:24:17.413411 | noble -> localhost | | .o.+. o.. | 2025-10-24 08:24:17.413460 | noble -> localhost | +----[SHA256]-----+ 2025-10-24 08:24:17.413593 | noble -> localhost | ok: Runtime: 0:00:01.167743 2025-10-24 08:24:17.428506 | 2025-10-24 08:24:17.428699 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-24 08:24:17.461041 | noble | ok 2025-10-24 08:24:17.475501 | noble | included: /var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-24 08:24:17.484460 | 2025-10-24 08:24:17.484558 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-24 08:24:17.507700 | noble | skipping: Conditional result was False 2025-10-24 08:24:17.515704 | 2025-10-24 08:24:17.515806 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-24 08:24:18.141190 | noble | changed 2025-10-24 08:24:18.147628 | 2025-10-24 08:24:18.147734 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-24 08:24:18.459720 | noble | ok 2025-10-24 08:24:18.466205 | 2025-10-24 08:24:18.466316 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-24 08:24:19.243061 | noble | changed 2025-10-24 08:24:19.251844 | 2025-10-24 08:24:19.251974 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-24 08:24:20.075763 | noble | changed 2025-10-24 08:24:20.082134 | 2025-10-24 08:24:20.082243 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-24 08:24:20.106671 | noble | skipping: Conditional result was False 2025-10-24 08:24:20.115529 | 2025-10-24 08:24:20.115652 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-24 08:24:20.575123 | noble -> localhost | changed 2025-10-24 08:24:20.589520 | 2025-10-24 08:24:20.589654 | TASK [add-build-sshkey : Add back temp key] 2025-10-24 08:24:20.926849 | noble -> localhost | Identity added: /var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/work/789136ee111f454ea558100ec630e2f6_id_rsa (zuul-build-sshkey) 2025-10-24 08:24:20.927103 | noble -> localhost | ok: Runtime: 0:00:00.015105 2025-10-24 08:24:20.934419 | 2025-10-24 08:24:20.934527 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-24 08:24:21.409224 | noble | ok 2025-10-24 08:24:21.415332 | 2025-10-24 08:24:21.415442 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-24 08:24:21.449082 | noble | skipping: Conditional result was False 2025-10-24 08:24:21.514728 | 2025-10-24 08:24:21.514892 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-24 08:24:21.979125 | noble | ok 2025-10-24 08:24:21.990446 | 2025-10-24 08:24:21.990569 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-24 08:24:22.019770 | noble | ok 2025-10-24 08:24:22.027248 | 2025-10-24 08:24:22.027350 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-24 08:24:22.313993 | noble -> localhost | ok 2025-10-24 08:24:22.321930 | 2025-10-24 08:24:22.322041 | TASK [validate-host : Collect information about the host] 2025-10-24 08:24:23.791751 | noble | ok 2025-10-24 08:24:23.826221 | 2025-10-24 08:24:23.826366 | TASK [validate-host : Sanitize hostname] 2025-10-24 08:24:23.895197 | noble | ok 2025-10-24 08:24:23.900781 | 2025-10-24 08:24:23.900891 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-24 08:24:24.507734 | noble -> localhost | changed 2025-10-24 08:24:24.514403 | 2025-10-24 08:24:24.514532 | TASK [validate-host : Collect information about zuul worker] 2025-10-24 08:24:24.991429 | noble | ok 2025-10-24 08:24:25.006908 | 2025-10-24 08:24:25.007043 | TASK [validate-host : Write out all zuul information for each host] 2025-10-24 08:24:25.600591 | noble -> localhost | changed 2025-10-24 08:24:25.623570 | 2025-10-24 08:24:25.623704 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-24 08:24:25.956552 | noble | ok 2025-10-24 08:24:25.963384 | 2025-10-24 08:24:25.963490 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-24 08:24:26.869577 | noble | changed: 2025-10-24 08:24:26.869793 | noble | cd+++++++++ src/ 2025-10-24 08:24:26.869828 | noble | cd+++++++++ src/github.com/ 2025-10-24 08:24:26.869852 | noble | cd+++++++++ src/github.com/osism/ 2025-10-24 08:24:26.869872 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-24 08:24:26.869891 | noble | localhost | ok: "/var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/work/logs" 2025-10-24 08:35:50.652827 | noble -> localhost | changed: "/var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/work/artifacts" 2025-10-24 08:35:50.903715 | noble -> localhost | changed: "/var/lib/zuul/builds/789136ee111f454ea558100ec630e2f6/work/docs" 2025-10-24 08:35:50.927205 | 2025-10-24 08:35:50.927363 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-24 08:35:52.111658 | noble | changed: 2025-10-24 08:35:52.111918 | noble | .d..t...... ./ 2025-10-24 08:35:52.111961 | noble | >f+++++++++ osism-node.log 2025-10-24 08:35:52.112026 | noble | changed: All items complete 2025-10-24 08:35:52.112063 | 2025-10-24 08:35:52.755529 | noble | changed: .d..t...... ./ 2025-10-24 08:35:53.396644 | noble | changed: .d..t...... ./ 2025-10-24 08:35:53.424805 | 2025-10-24 08:35:53.424945 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-24 08:35:53.912210 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007110 2025-10-24 08:35:54.186111 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009512 2025-10-24 08:35:54.206794 | 2025-10-24 08:35:54.206973 | PLAY RECAP 2025-10-24 08:35:54.207057 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-24 08:35:54.207100 | 2025-10-24 08:35:54.326302 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-24 08:35:54.328596 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-24 08:35:55.081110 | 2025-10-24 08:35:55.081267 | PLAY [Base post] 2025-10-24 08:35:55.096096 | 2025-10-24 08:35:55.096223 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-24 08:35:55.754656 | noble | changed 2025-10-24 08:35:55.766081 | 2025-10-24 08:35:55.766216 | PLAY RECAP 2025-10-24 08:35:55.766309 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-24 08:35:55.766397 | 2025-10-24 08:35:55.875421 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-24 08:35:55.877659 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-24 08:35:56.658694 | 2025-10-24 08:35:56.658884 | PLAY [Base post-logs] 2025-10-24 08:35:56.669049 | 2025-10-24 08:35:56.669181 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-24 08:35:57.120678 | localhost | changed 2025-10-24 08:35:57.131776 | 2025-10-24 08:35:57.131923 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-24 08:35:57.168710 | localhost | ok 2025-10-24 08:35:57.172252 | 2025-10-24 08:35:57.172355 | TASK [Set zuul-log-path fact] 2025-10-24 08:35:57.187858 | localhost | ok 2025-10-24 08:35:57.196361 | 2025-10-24 08:35:57.196514 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 08:35:57.221727 | localhost | ok 2025-10-24 08:35:57.225572 | 2025-10-24 08:35:57.225732 | TASK [upload-logs : Create log directories] 2025-10-24 08:35:57.694813 | localhost | changed 2025-10-24 08:35:57.700040 | 2025-10-24 08:35:57.700198 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-24 08:35:58.174333 | localhost -> localhost | ok: Runtime: 0:00:00.006536 2025-10-24 08:35:58.183314 | 2025-10-24 08:35:58.183526 | TASK [upload-logs : Upload logs to log server] 2025-10-24 08:35:58.711262 | localhost | Output suppressed because no_log was given 2025-10-24 08:35:58.715522 | 2025-10-24 08:35:58.715701 | LOOP [upload-logs : Compress console log and json output] 2025-10-24 08:35:58.770034 | localhost | skipping: Conditional result was False 2025-10-24 08:35:58.774821 | localhost | skipping: Conditional result was False 2025-10-24 08:35:58.787161 | 2025-10-24 08:35:58.787397 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-24 08:35:58.836389 | localhost | skipping: Conditional result was False 2025-10-24 08:35:58.836959 | 2025-10-24 08:35:58.839739 | localhost | skipping: Conditional result was False 2025-10-24 08:35:58.848209 | 2025-10-24 08:35:58.848510 | LOOP [upload-logs : Upload console log and json output]