2025-07-09 07:39:47.498016 | Job console starting 2025-07-09 07:39:47.513714 | Updating git repos 2025-07-09 07:39:47.534209 | Cloning repos into workspace 2025-07-09 07:39:47.571559 | Restoring repo states 2025-07-09 07:39:47.580341 | Merging changes 2025-07-09 07:39:49.662331 | Checking out repos 2025-07-09 07:39:49.698510 | Preparing playbooks 2025-07-09 07:39:50.378000 | Running Ansible setup 2025-07-09 07:39:56.205229 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-09 07:39:56.997783 | 2025-07-09 07:39:56.997968 | PLAY [Base pre] 2025-07-09 07:39:57.015161 | 2025-07-09 07:39:57.015309 | TASK [Setup log path fact] 2025-07-09 07:39:57.046265 | noble | ok 2025-07-09 07:39:57.068543 | 2025-07-09 07:39:57.068701 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-09 07:39:57.111349 | noble | ok 2025-07-09 07:39:57.129363 | 2025-07-09 07:39:57.129645 | TASK [emit-job-header : Print job information] 2025-07-09 07:39:57.177961 | # Job Information 2025-07-09 07:39:57.178181 | Ansible Version: 2.16.14 2025-07-09 07:39:57.178230 | Job: openstack-ironic-images-build-osism-node 2025-07-09 07:39:57.178272 | Pipeline: check 2025-07-09 07:39:57.178302 | Executor: 521e9411259a 2025-07-09 07:39:57.178329 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/98 2025-07-09 07:39:57.178357 | Event ID: 7b850e60-5c97-11f0-9d70-abb6b9430b98 2025-07-09 07:39:57.186274 | 2025-07-09 07:39:57.186391 | LOOP [emit-job-header : Print node information] 2025-07-09 07:39:57.306679 | noble | ok: 2025-07-09 07:39:57.307006 | noble | # Node Information 2025-07-09 07:39:57.307070 | noble | Inventory Hostname: noble 2025-07-09 07:39:57.307116 | noble | Hostname: ubuntu 2025-07-09 07:39:57.307156 | noble | Username: zuul 2025-07-09 07:39:57.307192 | noble | Distro: Ubuntu 24.04 2025-07-09 07:39:57.307234 | noble | Provider: regiocloud-a 2025-07-09 07:39:57.307270 | noble | Region: 2025-07-09 07:39:57.307308 | noble | Label: ubuntu-noble-uefi 2025-07-09 07:39:57.307344 | noble | Product Name: OpenStack Nova 2025-07-09 07:39:57.307380 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe01:c023 2025-07-09 07:39:57.327324 | 2025-07-09 07:39:57.327469 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-09 07:39:57.869976 | noble -> localhost | changed 2025-07-09 07:39:57.887439 | 2025-07-09 07:39:57.887609 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-09 07:39:58.947572 | noble -> localhost | changed 2025-07-09 07:39:58.967988 | 2025-07-09 07:39:58.968243 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-09 07:39:59.240496 | noble -> localhost | ok 2025-07-09 07:39:59.250489 | 2025-07-09 07:39:59.250633 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-09 07:39:59.283955 | noble | ok 2025-07-09 07:39:59.308241 | noble | included: /var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-09 07:39:59.318758 | 2025-07-09 07:39:59.318925 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-09 07:40:00.980462 | noble -> localhost | Generating public/private rsa key pair. 2025-07-09 07:40:00.980891 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/work/dcd2ece6a7e54a4e93327b6d8c0afad2_id_rsa 2025-07-09 07:40:00.981011 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/work/dcd2ece6a7e54a4e93327b6d8c0afad2_id_rsa.pub 2025-07-09 07:40:00.981073 | noble -> localhost | The key fingerprint is: 2025-07-09 07:40:00.981121 | noble -> localhost | SHA256:dk/aVb4VDv+DfpLZvSyfHa6AIZBNoCsc9oNwVpLkOSY zuul-build-sshkey 2025-07-09 07:40:00.981222 | noble -> localhost | The key's randomart image is: 2025-07-09 07:40:00.981264 | noble -> localhost | +---[RSA 3072]----+ 2025-07-09 07:40:00.981303 | noble -> localhost | | .o..... | 2025-07-09 07:40:00.981366 | noble -> localhost | | ..+. + | 2025-07-09 07:40:00.981440 | noble -> localhost | |EoB. o . . ..| 2025-07-09 07:40:00.981507 | noble -> localhost | |+=+.. . +o.| 2025-07-09 07:40:00.981549 | noble -> localhost | | + + .S.. . .oo| 2025-07-09 07:40:00.981598 | noble -> localhost | | . . ...o= .. +| 2025-07-09 07:40:00.981634 | noble -> localhost | | ...o.++o| 2025-07-09 07:40:00.981670 | noble -> localhost | | o=oo*| 2025-07-09 07:40:00.981707 | noble -> localhost | | o**o| 2025-07-09 07:40:00.981743 | noble -> localhost | +----[SHA256]-----+ 2025-07-09 07:40:00.981891 | noble -> localhost | ok: Runtime: 0:00:01.133433 2025-07-09 07:40:00.993568 | 2025-07-09 07:40:00.993745 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-09 07:40:01.027652 | noble | ok 2025-07-09 07:40:01.042112 | noble | included: /var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-09 07:40:01.052489 | 2025-07-09 07:40:01.052651 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-09 07:40:01.077434 | noble | skipping: Conditional result was False 2025-07-09 07:40:01.093615 | 2025-07-09 07:40:01.093880 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-09 07:40:01.705413 | noble | changed 2025-07-09 07:40:01.712021 | 2025-07-09 07:40:01.712140 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-09 07:40:02.043129 | noble | ok 2025-07-09 07:40:02.062703 | 2025-07-09 07:40:02.063088 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-09 07:40:02.862351 | noble | changed 2025-07-09 07:40:02.869269 | 2025-07-09 07:40:02.869392 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-09 07:40:03.676449 | noble | changed 2025-07-09 07:40:03.687881 | 2025-07-09 07:40:03.688057 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-09 07:40:03.712488 | noble | skipping: Conditional result was False 2025-07-09 07:40:03.719662 | 2025-07-09 07:40:03.719777 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-09 07:40:04.160850 | noble -> localhost | changed 2025-07-09 07:40:04.183129 | 2025-07-09 07:40:04.183281 | TASK [add-build-sshkey : Add back temp key] 2025-07-09 07:40:04.573734 | noble -> localhost | Identity added: /var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/work/dcd2ece6a7e54a4e93327b6d8c0afad2_id_rsa (zuul-build-sshkey) 2025-07-09 07:40:04.574003 | noble -> localhost | ok: Runtime: 0:00:00.017041 2025-07-09 07:40:04.581735 | 2025-07-09 07:40:04.581871 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-09 07:40:05.028129 | noble | ok 2025-07-09 07:40:05.034967 | 2025-07-09 07:40:05.035087 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-09 07:40:05.069073 | noble | skipping: Conditional result was False 2025-07-09 07:40:05.121436 | 2025-07-09 07:40:05.121576 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-09 07:40:05.557984 | noble | ok 2025-07-09 07:40:05.570038 | 2025-07-09 07:40:05.570161 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-09 07:40:05.611629 | noble | ok 2025-07-09 07:40:05.619280 | 2025-07-09 07:40:05.619388 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-09 07:40:05.917102 | noble -> localhost | ok 2025-07-09 07:40:05.924993 | 2025-07-09 07:40:05.925109 | TASK [validate-host : Collect information about the host] 2025-07-09 07:40:07.401875 | noble | ok 2025-07-09 07:40:07.417737 | 2025-07-09 07:40:07.417931 | TASK [validate-host : Sanitize hostname] 2025-07-09 07:40:07.494990 | noble | ok 2025-07-09 07:40:07.504611 | 2025-07-09 07:40:07.504778 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-09 07:40:08.068322 | noble -> localhost | changed 2025-07-09 07:40:08.082306 | 2025-07-09 07:40:08.082463 | TASK [validate-host : Collect information about zuul worker] 2025-07-09 07:40:08.543078 | noble | ok 2025-07-09 07:40:08.552863 | 2025-07-09 07:40:08.553004 | TASK [validate-host : Write out all zuul information for each host] 2025-07-09 07:40:09.135993 | noble -> localhost | changed 2025-07-09 07:40:09.154348 | 2025-07-09 07:40:09.154482 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-09 07:40:09.494630 | noble | ok 2025-07-09 07:40:09.504383 | 2025-07-09 07:40:09.504514 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-09 07:40:10.437441 | noble | changed: 2025-07-09 07:40:10.437886 | noble | cd+++++++++ src/ 2025-07-09 07:40:10.437974 | noble | cd+++++++++ src/github.com/ 2025-07-09 07:40:10.438025 | noble | cd+++++++++ src/github.com/osism/ 2025-07-09 07:40:10.438069 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-09 07:40:10.438110 | noble | localhost | ok: "/var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/work/logs" 2025-07-09 07:49:19.814025 | noble -> localhost | changed: "/var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/work/artifacts" 2025-07-09 07:49:20.094205 | noble -> localhost | changed: "/var/lib/zuul/builds/dcd2ece6a7e54a4e93327b6d8c0afad2/work/docs" 2025-07-09 07:49:20.126223 | 2025-07-09 07:49:20.126414 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-09 07:49:21.436528 | noble | changed: 2025-07-09 07:49:21.436884 | noble | .d..t...... ./ 2025-07-09 07:49:21.437010 | noble | >f+++++++++ osism-node.log 2025-07-09 07:49:21.437102 | noble | changed: All items complete 2025-07-09 07:49:21.437154 | 2025-07-09 07:49:22.091447 | noble | changed: .d..t...... ./ 2025-07-09 07:49:22.746635 | noble | changed: .d..t...... ./ 2025-07-09 07:49:22.773339 | 2025-07-09 07:49:22.773490 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-09 07:49:23.326388 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.023151 2025-07-09 07:49:23.616401 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010092 2025-07-09 07:49:23.642408 | 2025-07-09 07:49:23.642534 | PLAY RECAP 2025-07-09 07:49:23.642613 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-09 07:49:23.642646 | 2025-07-09 07:49:23.764083 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-09 07:49:23.765017 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-09 07:49:24.476966 | 2025-07-09 07:49:24.477131 | PLAY [Base post] 2025-07-09 07:49:24.491988 | 2025-07-09 07:49:24.492124 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-09 07:49:25.132298 | noble | changed 2025-07-09 07:49:25.145862 | 2025-07-09 07:49:25.146059 | PLAY RECAP 2025-07-09 07:49:25.146169 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-09 07:49:25.146270 | 2025-07-09 07:49:25.263102 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-09 07:49:25.264007 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-09 07:49:26.028728 | 2025-07-09 07:49:26.028904 | PLAY [Base post-logs] 2025-07-09 07:49:26.039858 | 2025-07-09 07:49:26.040052 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-09 07:49:26.536927 | localhost | changed 2025-07-09 07:49:26.555341 | 2025-07-09 07:49:26.555536 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-09 07:49:26.591907 | localhost | ok 2025-07-09 07:49:26.596048 | 2025-07-09 07:49:26.596174 | TASK [Set zuul-log-path fact] 2025-07-09 07:49:26.611534 | localhost | ok 2025-07-09 07:49:26.620709 | 2025-07-09 07:49:26.620828 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-09 07:49:26.646395 | localhost | ok 2025-07-09 07:49:26.651153 | 2025-07-09 07:49:26.651308 | TASK [upload-logs : Create log directories] 2025-07-09 07:49:27.147523 | localhost | changed 2025-07-09 07:49:27.150379 | 2025-07-09 07:49:27.150487 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-09 07:49:27.662423 | localhost -> localhost | ok: Runtime: 0:00:00.007143 2025-07-09 07:49:27.671731 | 2025-07-09 07:49:27.671970 | TASK [upload-logs : Upload logs to log server] 2025-07-09 07:49:28.247830 | localhost | Output suppressed because no_log was given 2025-07-09 07:49:28.250191 | 2025-07-09 07:49:28.250316 | LOOP [upload-logs : Compress console log and json output] 2025-07-09 07:49:28.300987 | localhost | skipping: Conditional result was False 2025-07-09 07:49:28.306412 | localhost | skipping: Conditional result was False 2025-07-09 07:49:28.319032 | 2025-07-09 07:49:28.319198 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-09 07:49:28.368146 | localhost | skipping: Conditional result was False 2025-07-09 07:49:28.368719 | 2025-07-09 07:49:28.372271 | localhost | skipping: Conditional result was False 2025-07-09 07:49:28.377902 | 2025-07-09 07:49:28.378118 | LOOP [upload-logs : Upload console log and json output]