2025-10-19 08:05:53.428978 | Job console starting 2025-10-19 08:05:53.441248 | Updating git repos 2025-10-19 08:05:53.461187 | Cloning repos into workspace 2025-10-19 08:05:53.495741 | Restoring repo states 2025-10-19 08:05:53.503457 | Merging changes 2025-10-19 08:05:53.969329 | Checking out repos 2025-10-19 08:05:54.015390 | Preparing playbooks 2025-10-19 08:05:54.527809 | Running Ansible setup 2025-10-19 08:06:00.385256 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-19 08:06:01.238803 | 2025-10-19 08:06:01.238997 | PLAY [Base pre] 2025-10-19 08:06:01.256683 | 2025-10-19 08:06:01.256833 | TASK [Setup log path fact] 2025-10-19 08:06:01.290760 | noble | ok 2025-10-19 08:06:01.317531 | 2025-10-19 08:06:01.317775 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 08:06:01.365549 | noble | ok 2025-10-19 08:06:01.382440 | 2025-10-19 08:06:01.382653 | TASK [emit-job-header : Print job information] 2025-10-19 08:06:01.441871 | # Job Information 2025-10-19 08:06:01.442155 | Ansible Version: 2.16.14 2025-10-19 08:06:01.442218 | Job: openstack-ironic-images-build-osism-node 2025-10-19 08:06:01.442277 | Pipeline: check 2025-10-19 08:06:01.442317 | Executor: 521e9411259a 2025-10-19 08:06:01.442355 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/152 2025-10-19 08:06:01.442395 | Event ID: 08b95744-acc2-11f0-9238-5c0379b0909e 2025-10-19 08:06:01.453344 | 2025-10-19 08:06:01.453498 | LOOP [emit-job-header : Print node information] 2025-10-19 08:06:01.610624 | noble | ok: 2025-10-19 08:06:01.610874 | noble | # Node Information 2025-10-19 08:06:01.610917 | noble | Inventory Hostname: noble 2025-10-19 08:06:01.610943 | noble | Hostname: ubuntu 2025-10-19 08:06:01.610970 | noble | Username: zuul 2025-10-19 08:06:01.611001 | noble | Distro: Ubuntu 24.04 2025-10-19 08:06:01.611035 | noble | Provider: regiocloud-a 2025-10-19 08:06:01.611065 | noble | Region: 2025-10-19 08:06:01.611087 | noble | Label: ubuntu-noble-uefi 2025-10-19 08:06:01.611108 | noble | Product Name: OpenStack Nova 2025-10-19 08:06:01.611131 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe83:711b 2025-10-19 08:06:01.627556 | 2025-10-19 08:06:01.627722 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-19 08:06:02.186543 | noble -> localhost | changed 2025-10-19 08:06:02.198557 | 2025-10-19 08:06:02.198718 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-19 08:06:03.602881 | noble -> localhost | changed 2025-10-19 08:06:03.617476 | 2025-10-19 08:06:03.617648 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-19 08:06:03.888819 | noble -> localhost | ok 2025-10-19 08:06:03.896358 | 2025-10-19 08:06:03.896491 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-19 08:06:03.916516 | noble | ok 2025-10-19 08:06:03.933190 | noble | included: /var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-19 08:06:03.941442 | 2025-10-19 08:06:03.941554 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-19 08:06:05.507842 | noble -> localhost | Generating public/private rsa key pair. 2025-10-19 08:06:05.508167 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/work/e5cbf1f89c5b45ba92332fa6cb59808d_id_rsa 2025-10-19 08:06:05.508211 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/work/e5cbf1f89c5b45ba92332fa6cb59808d_id_rsa.pub 2025-10-19 08:06:05.508239 | noble -> localhost | The key fingerprint is: 2025-10-19 08:06:05.508268 | noble -> localhost | SHA256:BZLaojbmp930bRchUOMKFG7SXvGcbt3rNW2AX9WjAS8 zuul-build-sshkey 2025-10-19 08:06:05.508291 | noble -> localhost | The key's randomart image is: 2025-10-19 08:06:05.508312 | noble -> localhost | +---[RSA 3072]----+ 2025-10-19 08:06:05.508333 | noble -> localhost | | +oo.o. | 2025-10-19 08:06:05.508370 | noble -> localhost | | +...* oo .| 2025-10-19 08:06:05.508392 | noble -> localhost | | .o= ..*E o .o| 2025-10-19 08:06:05.508412 | noble -> localhost | | o+.o +..ooo o| 2025-10-19 08:06:05.508432 | noble -> localhost | | . .. S o.ooo .| 2025-10-19 08:06:05.508458 | noble -> localhost | | = . .. +.| 2025-10-19 08:06:05.508478 | noble -> localhost | | + . . .o.+| 2025-10-19 08:06:05.508498 | noble -> localhost | | ...o . .. .. o.| 2025-10-19 08:06:05.508519 | noble -> localhost | | .o. . .... . | 2025-10-19 08:06:05.508540 | noble -> localhost | +----[SHA256]-----+ 2025-10-19 08:06:05.508620 | noble -> localhost | ok: Runtime: 0:00:01.052437 2025-10-19 08:06:05.516617 | 2025-10-19 08:06:05.516750 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-19 08:06:05.547530 | noble | ok 2025-10-19 08:06:05.558312 | noble | included: /var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-19 08:06:05.568052 | 2025-10-19 08:06:05.568173 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-19 08:06:05.592882 | noble | skipping: Conditional result was False 2025-10-19 08:06:05.609484 | 2025-10-19 08:06:05.609699 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-19 08:06:06.271993 | noble | changed 2025-10-19 08:06:06.279472 | 2025-10-19 08:06:06.279611 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-19 08:06:06.587035 | noble | ok 2025-10-19 08:06:06.601116 | 2025-10-19 08:06:06.601317 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-19 08:06:07.363888 | noble | changed 2025-10-19 08:06:07.370284 | 2025-10-19 08:06:07.370396 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-19 08:06:08.112918 | noble | changed 2025-10-19 08:06:08.120328 | 2025-10-19 08:06:08.120459 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-19 08:06:08.144543 | noble | skipping: Conditional result was False 2025-10-19 08:06:08.156609 | 2025-10-19 08:06:08.156774 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-19 08:06:08.599723 | noble -> localhost | changed 2025-10-19 08:06:08.634157 | 2025-10-19 08:06:08.634278 | TASK [add-build-sshkey : Add back temp key] 2025-10-19 08:06:09.027483 | noble -> localhost | Identity added: /var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/work/e5cbf1f89c5b45ba92332fa6cb59808d_id_rsa (zuul-build-sshkey) 2025-10-19 08:06:09.027756 | noble -> localhost | ok: Runtime: 0:00:00.017512 2025-10-19 08:06:09.049321 | 2025-10-19 08:06:09.049442 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-19 08:06:09.504940 | noble | ok 2025-10-19 08:06:09.511088 | 2025-10-19 08:06:09.511204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-19 08:06:09.534882 | noble | skipping: Conditional result was False 2025-10-19 08:06:09.582745 | 2025-10-19 08:06:09.582889 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-19 08:06:10.035518 | noble | ok 2025-10-19 08:06:10.048293 | 2025-10-19 08:06:10.048417 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-19 08:06:10.091223 | noble | ok 2025-10-19 08:06:10.100599 | 2025-10-19 08:06:10.100741 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-19 08:06:10.377478 | noble -> localhost | ok 2025-10-19 08:06:10.385995 | 2025-10-19 08:06:10.386108 | TASK [validate-host : Collect information about the host] 2025-10-19 08:06:11.839895 | noble | ok 2025-10-19 08:06:11.855583 | 2025-10-19 08:06:11.855705 | TASK [validate-host : Sanitize hostname] 2025-10-19 08:06:11.914095 | noble | ok 2025-10-19 08:06:11.919741 | 2025-10-19 08:06:11.919846 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-19 08:06:12.471441 | noble -> localhost | changed 2025-10-19 08:06:12.485204 | 2025-10-19 08:06:12.485306 | TASK [validate-host : Collect information about zuul worker] 2025-10-19 08:06:12.935314 | noble | ok 2025-10-19 08:06:12.940353 | 2025-10-19 08:06:12.940441 | TASK [validate-host : Write out all zuul information for each host] 2025-10-19 08:06:13.393537 | noble -> localhost | changed 2025-10-19 08:06:13.408554 | 2025-10-19 08:06:13.408660 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-19 08:06:13.728624 | noble | ok 2025-10-19 08:06:13.738173 | 2025-10-19 08:06:13.738291 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-19 08:06:14.580255 | noble | changed: 2025-10-19 08:06:14.580420 | noble | cd+++++++++ src/ 2025-10-19 08:06:14.580453 | noble | cd+++++++++ src/github.com/ 2025-10-19 08:06:14.580477 | noble | cd+++++++++ src/github.com/osism/ 2025-10-19 08:06:14.580499 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-19 08:06:14.580520 | noble | localhost | ok: "/var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/work/logs" 2025-10-19 08:17:56.245436 | noble -> localhost | changed: "/var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/work/artifacts" 2025-10-19 08:17:56.551326 | noble -> localhost | changed: "/var/lib/zuul/builds/e5cbf1f89c5b45ba92332fa6cb59808d/work/docs" 2025-10-19 08:17:56.567782 | 2025-10-19 08:17:56.568006 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-19 08:17:57.875290 | noble | changed: 2025-10-19 08:17:57.875631 | noble | .d..t...... ./ 2025-10-19 08:17:57.875695 | noble | >f+++++++++ osism-node.log 2025-10-19 08:17:57.875772 | noble | changed: All items complete 2025-10-19 08:17:57.875820 | 2025-10-19 08:17:58.529449 | noble | changed: .d..t...... ./ 2025-10-19 08:17:59.167694 | noble | changed: .d..t...... ./ 2025-10-19 08:17:59.202008 | 2025-10-19 08:17:59.202169 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-19 08:17:59.696924 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.009642 2025-10-19 08:17:59.982815 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010706 2025-10-19 08:18:00.004098 | 2025-10-19 08:18:00.004228 | PLAY RECAP 2025-10-19 08:18:00.004312 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-19 08:18:00.004353 | 2025-10-19 08:18:00.136691 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-19 08:18:00.137625 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 08:18:00.839452 | 2025-10-19 08:18:00.839601 | PLAY [Base post] 2025-10-19 08:18:00.854316 | 2025-10-19 08:18:00.854443 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-19 08:18:01.520275 | noble | changed 2025-10-19 08:18:01.530647 | 2025-10-19 08:18:01.530769 | PLAY RECAP 2025-10-19 08:18:01.530863 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-19 08:18:01.530951 | 2025-10-19 08:18:01.645239 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-19 08:18:01.646182 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-19 08:18:02.429390 | 2025-10-19 08:18:02.429554 | PLAY [Base post-logs] 2025-10-19 08:18:02.439958 | 2025-10-19 08:18:02.440110 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-19 08:18:02.873810 | localhost | changed 2025-10-19 08:18:02.890385 | 2025-10-19 08:18:02.890554 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-19 08:18:02.926896 | localhost | ok 2025-10-19 08:18:02.931404 | 2025-10-19 08:18:02.931542 | TASK [Set zuul-log-path fact] 2025-10-19 08:18:02.947742 | localhost | ok 2025-10-19 08:18:02.957870 | 2025-10-19 08:18:02.957990 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-19 08:18:02.986160 | localhost | ok 2025-10-19 08:18:02.993021 | 2025-10-19 08:18:02.993217 | TASK [upload-logs : Create log directories] 2025-10-19 08:18:03.475725 | localhost | changed 2025-10-19 08:18:03.480777 | 2025-10-19 08:18:03.480940 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-19 08:18:03.952679 | localhost -> localhost | ok: Runtime: 0:00:00.004298 2025-10-19 08:18:03.962887 | 2025-10-19 08:18:03.963139 | TASK [upload-logs : Upload logs to log server] 2025-10-19 08:18:04.531110 | localhost | Output suppressed because no_log was given 2025-10-19 08:18:04.535181 | 2025-10-19 08:18:04.535364 | LOOP [upload-logs : Compress console log and json output] 2025-10-19 08:18:04.595562 | localhost | skipping: Conditional result was False 2025-10-19 08:18:04.600302 | localhost | skipping: Conditional result was False 2025-10-19 08:18:04.607311 | 2025-10-19 08:18:04.607536 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-19 08:18:04.652473 | localhost | skipping: Conditional result was False 2025-10-19 08:18:04.652989 | 2025-10-19 08:18:04.656390 | localhost | skipping: Conditional result was False 2025-10-19 08:18:04.670114 | 2025-10-19 08:18:04.670372 | LOOP [upload-logs : Upload console log and json output]