2026-09-03 13:17:53.834554 | Job console starting 2026-09-03 13:17:53.846384 | Updating git repos 2026-09-03 13:17:53.865825 | Cloning repos into workspace 2026-09-03 13:17:53.907717 | Restoring repo states 2026-09-03 13:17:53.913098 | Merging changes 2026-09-03 13:17:54.460072 | Checking out repos 2026-09-03 13:17:54.495184 | Preparing playbooks 2026-09-03 13:17:55.337839 | Running Ansible setup 2026-09-03 13:18:01.524558 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-03 13:18:02.328197 | 2026-09-03 13:18:02.328616 | PLAY [Base pre] 2026-09-03 13:18:02.362371 | 2026-09-03 13:18:02.362535 | TASK [Setup log path fact] 2026-09-03 13:18:02.384091 | noble | ok 2026-09-03 13:18:02.403182 | 2026-09-03 13:18:02.403322 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-03 13:18:02.447028 | noble | ok 2026-09-03 13:18:02.459230 | 2026-09-03 13:18:02.459374 | TASK [emit-job-header : Print job information] 2026-09-03 13:18:02.502607 | # Job Information 2026-09-03 13:18:02.502794 | Ansible Version: 2.16.14 2026-09-03 13:18:02.502867 | Job: openstack-ironic-images-build-osism-node 2026-09-03 13:18:02.502915 | Pipeline: check 2026-09-03 13:18:02.502948 | Executor: 521e9411259a 2026-09-03 13:18:02.502977 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/221 2026-09-03 13:18:02.503009 | Event ID: 587a4900-a799-11f1-95af-2cb065bad89e 2026-09-03 13:18:02.510681 | 2026-09-03 13:18:02.510808 | LOOP [emit-job-header : Print node information] 2026-09-03 13:18:02.640593 | noble | ok: 2026-09-03 13:18:02.640798 | noble | # Node Information 2026-09-03 13:18:02.640844 | noble | Inventory Hostname: noble 2026-09-03 13:18:02.640876 | noble | Hostname: ubuntu 2026-09-03 13:18:02.640904 | noble | Username: zuul 2026-09-03 13:18:02.640931 | noble | Distro: Ubuntu 24.04 2026-09-03 13:18:02.640962 | noble | Provider: regiocloud-a 2026-09-03 13:18:02.640990 | noble | Region: 2026-09-03 13:18:02.641017 | noble | Label: ubuntu-noble-uefi 2026-09-03 13:18:02.641044 | noble | Product Name: OpenStack Nova 2026-09-03 13:18:02.641070 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe13:9075 2026-09-03 13:18:02.663368 | 2026-09-03 13:18:02.663528 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-03 13:18:03.166814 | noble -> localhost | changed 2026-09-03 13:18:03.176491 | 2026-09-03 13:18:03.176616 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-03 13:18:04.297052 | noble -> localhost | changed 2026-09-03 13:18:04.317436 | 2026-09-03 13:18:04.317576 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-03 13:18:04.606914 | noble -> localhost | ok 2026-09-03 13:18:04.622103 | 2026-09-03 13:18:04.622258 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-03 13:18:04.671071 | noble | ok 2026-09-03 13:18:04.695125 | noble | included: /var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-03 13:18:04.702712 | 2026-09-03 13:18:04.702810 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-03 13:18:05.748385 | noble -> localhost | Generating public/private rsa key pair. 2026-09-03 13:18:05.748653 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/work/b2f76fa8fcca4dba96f5b279390593ef_id_rsa 2026-09-03 13:18:05.748696 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/work/b2f76fa8fcca4dba96f5b279390593ef_id_rsa.pub 2026-09-03 13:18:05.748723 | noble -> localhost | The key fingerprint is: 2026-09-03 13:18:05.748749 | noble -> localhost | SHA256:K7ObgZcAdl30s8ugzfkCFgQ+KvVdaehxoUSSQv90rpc zuul-build-sshkey 2026-09-03 13:18:05.748772 | noble -> localhost | The key's randomart image is: 2026-09-03 13:18:05.748793 | noble -> localhost | +---[RSA 3072]----+ 2026-09-03 13:18:05.748815 | noble -> localhost | | .. o+o.+ | 2026-09-03 13:18:05.748849 | noble -> localhost | | .o.+.+ + | 2026-09-03 13:18:05.748871 | noble -> localhost | | +.=.* * o | 2026-09-03 13:18:05.748890 | noble -> localhost | | o = *.B o | 2026-09-03 13:18:05.748909 | noble -> localhost | |. . o +.S . | 2026-09-03 13:18:05.748935 | noble -> localhost | | . oo* * . | 2026-09-03 13:18:05.748955 | noble -> localhost | | ..O.E o | 2026-09-03 13:18:05.748976 | noble -> localhost | | . B.. | 2026-09-03 13:18:05.748997 | noble -> localhost | | +. .. | 2026-09-03 13:18:05.749017 | noble -> localhost | +----[SHA256]-----+ 2026-09-03 13:18:05.749075 | noble -> localhost | ok: Runtime: 0:00:00.551049 2026-09-03 13:18:05.760873 | 2026-09-03 13:18:05.760996 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-03 13:18:05.801971 | noble | ok 2026-09-03 13:18:05.813722 | noble | included: /var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-03 13:18:05.824041 | 2026-09-03 13:18:05.824140 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-03 13:18:05.857610 | noble | skipping: Conditional result was False 2026-09-03 13:18:05.866051 | 2026-09-03 13:18:05.866164 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-03 13:18:06.536651 | noble | changed 2026-09-03 13:18:06.543311 | 2026-09-03 13:18:06.543424 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-03 13:18:06.867036 | noble | ok 2026-09-03 13:18:06.878985 | 2026-09-03 13:18:06.879133 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-03 13:18:07.719248 | noble | changed 2026-09-03 13:18:07.728254 | 2026-09-03 13:18:07.728425 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-03 13:18:08.666148 | noble | changed 2026-09-03 13:18:08.676005 | 2026-09-03 13:18:08.676143 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-03 13:18:08.700486 | noble | skipping: Conditional result was False 2026-09-03 13:18:08.712812 | 2026-09-03 13:18:08.712938 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-03 13:18:09.160714 | noble -> localhost | changed 2026-09-03 13:18:09.174086 | 2026-09-03 13:18:09.174208 | TASK [add-build-sshkey : Add back temp key] 2026-09-03 13:18:09.500961 | noble -> localhost | Identity added: /var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/work/b2f76fa8fcca4dba96f5b279390593ef_id_rsa (zuul-build-sshkey) 2026-09-03 13:18:09.501221 | noble -> localhost | ok: Runtime: 0:00:00.019760 2026-09-03 13:18:09.509065 | 2026-09-03 13:18:09.509176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-03 13:18:09.982880 | noble | ok 2026-09-03 13:18:09.992211 | 2026-09-03 13:18:09.992343 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-03 13:18:10.016359 | noble | skipping: Conditional result was False 2026-09-03 13:18:10.064073 | 2026-09-03 13:18:10.064193 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-03 13:18:10.545460 | noble | ok 2026-09-03 13:18:10.560826 | 2026-09-03 13:18:10.560952 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-03 13:18:10.609312 | noble | ok 2026-09-03 13:18:10.618148 | 2026-09-03 13:18:10.618315 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-03 13:18:10.925315 | noble -> localhost | ok 2026-09-03 13:18:10.933385 | 2026-09-03 13:18:10.933628 | TASK [validate-host : Collect information about the host] 2026-09-03 13:18:12.495870 | noble | ok 2026-09-03 13:18:12.511099 | 2026-09-03 13:18:12.511220 | TASK [validate-host : Sanitize hostname] 2026-09-03 13:18:12.575899 | noble | ok 2026-09-03 13:18:12.584182 | 2026-09-03 13:18:12.584373 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-03 13:18:13.185331 | noble -> localhost | changed 2026-09-03 13:18:13.191930 | 2026-09-03 13:18:13.192040 | TASK [validate-host : Collect information about zuul worker] 2026-09-03 13:18:13.722116 | noble | ok 2026-09-03 13:18:13.730328 | 2026-09-03 13:18:13.730467 | TASK [validate-host : Write out all zuul information for each host] 2026-09-03 13:18:14.303562 | noble -> localhost | changed 2026-09-03 13:18:14.314759 | 2026-09-03 13:18:14.314917 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-03 13:18:14.709235 | noble | ok 2026-09-03 13:18:14.719737 | 2026-09-03 13:18:14.719867 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-03 13:18:15.667894 | noble | changed: 2026-09-03 13:18:15.668086 | noble | cd+++++++++ src/ 2026-09-03 13:18:15.668122 | noble | cd+++++++++ src/github.com/ 2026-09-03 13:18:15.668147 | noble | cd+++++++++ src/github.com/osism/ 2026-09-03 13:18:15.668169 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-09-03 13:18:15.668189 | noble | localhost | ok: "/var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/work/logs" 2026-09-03 13:36:23.371819 | noble -> localhost | changed: "/var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/work/artifacts" 2026-09-03 13:36:23.637961 | noble -> localhost | changed: "/var/lib/zuul/builds/b2f76fa8fcca4dba96f5b279390593ef/work/docs" 2026-09-03 13:36:23.651252 | 2026-09-03 13:36:23.651376 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-03 13:36:24.901150 | noble | changed: 2026-09-03 13:36:24.901497 | noble | .d..t...... ./ 2026-09-03 13:36:24.901561 | noble | >f+++++++++ net-debug.post.txt 2026-09-03 13:36:24.901623 | noble | >f+++++++++ net-debug.pre.txt 2026-09-03 13:36:24.901718 | noble | >f+++++++++ osism-node.log 2026-09-03 13:36:24.901758 | noble | >f+++++++++ sudo-debug.txt 2026-09-03 13:36:24.901793 | noble | >f+++++++++ sudo-strace.log 2026-09-03 13:36:24.901835 | noble | >f+++++++++ sudo-strace.log.time 2026-09-03 13:36:24.901900 | noble | changed: All items complete 2026-09-03 13:36:24.901943 | 2026-09-03 13:36:25.534265 | noble | changed: .d..t...... ./ 2026-09-03 13:36:26.158996 | noble | changed: .d..t...... ./ 2026-09-03 13:36:26.186561 | 2026-09-03 13:36:26.186756 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-03 13:36:26.516567 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.009662 2026-09-03 13:36:26.847452 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.008234 2026-09-03 13:36:26.869478 | 2026-09-03 13:36:26.869596 | PLAY RECAP 2026-09-03 13:36:26.869751 | noble | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-03 13:36:26.869792 | 2026-09-03 13:36:26.991021 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-03 13:36:26.992423 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-03 13:36:27.710114 | 2026-09-03 13:36:27.710257 | PLAY [Base post] 2026-09-03 13:36:27.724269 | 2026-09-03 13:36:27.724392 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-03 13:36:28.436794 | noble | changed 2026-09-03 13:36:28.445452 | 2026-09-03 13:36:28.445560 | PLAY RECAP 2026-09-03 13:36:28.445624 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-03 13:36:28.445708 | 2026-09-03 13:36:28.568745 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-03 13:36:28.569804 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-03 13:36:29.350273 | 2026-09-03 13:36:29.350424 | PLAY [Base post-logs] 2026-09-03 13:36:29.360700 | 2026-09-03 13:36:29.360833 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-03 13:36:29.812139 | localhost | changed 2026-09-03 13:36:29.822050 | 2026-09-03 13:36:29.822192 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-03 13:36:29.858385 | localhost | ok 2026-09-03 13:36:29.862402 | 2026-09-03 13:36:29.862519 | TASK [Set zuul-log-path fact] 2026-09-03 13:36:29.878604 | localhost | ok 2026-09-03 13:36:29.890142 | 2026-09-03 13:36:29.890281 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-03 13:36:29.916495 | localhost | ok 2026-09-03 13:36:29.920686 | 2026-09-03 13:36:29.920817 | TASK [upload-logs : Create log directories] 2026-09-03 13:36:30.415214 | localhost | changed 2026-09-03 13:36:30.418687 | 2026-09-03 13:36:30.418816 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-03 13:36:30.931704 | localhost -> localhost | ok: Runtime: 0:00:00.005388 2026-09-03 13:36:30.937898 | 2026-09-03 13:36:30.938050 | TASK [upload-logs : Upload logs to log server] 2026-09-03 13:36:31.524142 | localhost | Output suppressed because no_log was given 2026-09-03 13:36:31.528879 | 2026-09-03 13:36:31.529086 | LOOP [upload-logs : Compress console log and json output] 2026-09-03 13:36:31.588633 | localhost | skipping: Conditional result was False 2026-09-03 13:36:31.595024 | localhost | skipping: Conditional result was False 2026-09-03 13:36:31.601794 | 2026-09-03 13:36:31.601970 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-03 13:36:31.648037 | localhost | skipping: Conditional result was False 2026-09-03 13:36:31.648571 | 2026-09-03 13:36:31.651844 | localhost | skipping: Conditional result was False 2026-09-03 13:36:31.665225 | 2026-09-03 13:36:31.665444 | LOOP [upload-logs : Upload console log and json output]