2025-10-18 20:49:54.670572 | Job console starting 2025-10-18 20:49:54.692728 | Updating git repos 2025-10-18 20:49:54.727219 | Cloning repos into workspace 2025-10-18 20:49:54.765251 | Restoring repo states 2025-10-18 20:49:54.771499 | Merging changes 2025-10-18 20:49:55.276773 | Checking out repos 2025-10-18 20:49:55.316822 | Preparing playbooks 2025-10-18 20:49:55.964225 | Running Ansible setup 2025-10-18 20:50:01.718167 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-18 20:50:02.517898 | 2025-10-18 20:50:02.518064 | PLAY [Base pre] 2025-10-18 20:50:02.535056 | 2025-10-18 20:50:02.535208 | TASK [Setup log path fact] 2025-10-18 20:50:02.565198 | noble | ok 2025-10-18 20:50:02.583877 | 2025-10-18 20:50:02.584050 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-18 20:50:02.614140 | noble | ok 2025-10-18 20:50:02.626925 | 2025-10-18 20:50:02.627047 | TASK [emit-job-header : Print job information] 2025-10-18 20:50:02.674203 | # Job Information 2025-10-18 20:50:02.674497 | Ansible Version: 2.16.14 2025-10-18 20:50:02.674558 | Job: openstack-ironic-images-build-osism-ipa-stable 2025-10-18 20:50:02.674618 | Pipeline: check 2025-10-18 20:50:02.674680 | Executor: 521e9411259a 2025-10-18 20:50:02.674735 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/152 2025-10-18 20:50:02.674778 | Event ID: 8b661bf0-ac63-11f0-920c-734c9e07f328 2025-10-18 20:50:02.684169 | 2025-10-18 20:50:02.684315 | LOOP [emit-job-header : Print node information] 2025-10-18 20:50:02.798216 | noble | ok: 2025-10-18 20:50:02.798465 | noble | # Node Information 2025-10-18 20:50:02.798565 | noble | Inventory Hostname: noble 2025-10-18 20:50:02.798603 | noble | Hostname: ubuntu 2025-10-18 20:50:02.798627 | noble | Username: zuul 2025-10-18 20:50:02.798648 | noble | Distro: Ubuntu 24.04 2025-10-18 20:50:02.798672 | noble | Provider: regiocloud-a 2025-10-18 20:50:02.798694 | noble | Region: 2025-10-18 20:50:02.798715 | noble | Label: ubuntu-noble-uefi 2025-10-18 20:50:02.798735 | noble | Product Name: OpenStack Nova 2025-10-18 20:50:02.798756 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea3:1016 2025-10-18 20:50:02.821302 | 2025-10-18 20:50:02.821429 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-18 20:50:03.375683 | noble -> localhost | changed 2025-10-18 20:50:03.384042 | 2025-10-18 20:50:03.384185 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-18 20:50:04.566582 | noble -> localhost | changed 2025-10-18 20:50:04.605901 | 2025-10-18 20:50:04.606142 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-18 20:50:04.863931 | noble -> localhost | ok 2025-10-18 20:50:04.871506 | 2025-10-18 20:50:04.871624 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-18 20:50:04.902280 | noble | ok 2025-10-18 20:50:04.922516 | noble | included: /var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-18 20:50:04.931772 | 2025-10-18 20:50:04.931885 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-18 20:50:05.793696 | noble -> localhost | Generating public/private rsa key pair. 2025-10-18 20:50:05.793928 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/work/f25b7c5586044872b168864a75c64df3_id_rsa 2025-10-18 20:50:05.793967 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/work/f25b7c5586044872b168864a75c64df3_id_rsa.pub 2025-10-18 20:50:05.793994 | noble -> localhost | The key fingerprint is: 2025-10-18 20:50:05.794022 | noble -> localhost | SHA256:J2QgbKAS9LPFKHg+kxdcwIQsZyhkW28pTjHHFBmnOyM zuul-build-sshkey 2025-10-18 20:50:05.794046 | noble -> localhost | The key's randomart image is: 2025-10-18 20:50:05.794068 | noble -> localhost | +---[RSA 3072]----+ 2025-10-18 20:50:05.794129 | noble -> localhost | |+*.X***. | 2025-10-18 20:50:05.794167 | noble -> localhost | |*oO.@=+. | 2025-10-18 20:50:05.794188 | noble -> localhost | |=*.BoB o | 2025-10-18 20:50:05.794209 | noble -> localhost | |.o+.*..o | 2025-10-18 20:50:05.794230 | noble -> localhost | | =E.+ S . | 2025-10-18 20:50:05.794255 | noble -> localhost | | +. o o | 2025-10-18 20:50:05.794276 | noble -> localhost | | | 2025-10-18 20:50:05.794296 | noble -> localhost | | | 2025-10-18 20:50:05.794317 | noble -> localhost | | | 2025-10-18 20:50:05.794337 | noble -> localhost | +----[SHA256]-----+ 2025-10-18 20:50:05.794395 | noble -> localhost | ok: Runtime: 0:00:00.332844 2025-10-18 20:50:05.802112 | 2025-10-18 20:50:05.802221 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-18 20:50:05.831757 | noble | ok 2025-10-18 20:50:05.841730 | noble | included: /var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-18 20:50:05.851051 | 2025-10-18 20:50:05.851161 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-18 20:50:05.865294 | noble | skipping: Conditional result was False 2025-10-18 20:50:05.873406 | 2025-10-18 20:50:05.873512 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-18 20:50:06.511652 | noble | changed 2025-10-18 20:50:06.534005 | 2025-10-18 20:50:06.534142 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-18 20:50:06.837477 | noble | ok 2025-10-18 20:50:06.844109 | 2025-10-18 20:50:06.844231 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-18 20:50:07.654018 | noble | changed 2025-10-18 20:50:07.663573 | 2025-10-18 20:50:07.663714 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-18 20:50:08.470716 | noble | changed 2025-10-18 20:50:08.478368 | 2025-10-18 20:50:08.478486 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-18 20:50:08.502989 | noble | skipping: Conditional result was False 2025-10-18 20:50:08.510491 | 2025-10-18 20:50:08.510605 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-18 20:50:09.058006 | noble -> localhost | changed 2025-10-18 20:50:09.090077 | 2025-10-18 20:50:09.090351 | TASK [add-build-sshkey : Add back temp key] 2025-10-18 20:50:09.685746 | noble -> localhost | Identity added: /var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/work/f25b7c5586044872b168864a75c64df3_id_rsa (zuul-build-sshkey) 2025-10-18 20:50:09.687242 | noble -> localhost | ok: Runtime: 0:00:00.022120 2025-10-18 20:50:09.698027 | 2025-10-18 20:50:09.698154 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-18 20:50:10.180886 | noble | ok 2025-10-18 20:50:10.196118 | 2025-10-18 20:50:10.196254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-18 20:50:10.250716 | noble | skipping: Conditional result was False 2025-10-18 20:50:10.362795 | 2025-10-18 20:50:10.363538 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-18 20:50:10.998663 | noble | ok 2025-10-18 20:50:11.023741 | 2025-10-18 20:50:11.023904 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-18 20:50:11.106739 | noble | ok 2025-10-18 20:50:11.114563 | 2025-10-18 20:50:11.114693 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-18 20:50:11.575570 | noble -> localhost | ok 2025-10-18 20:50:11.591223 | 2025-10-18 20:50:11.591376 | TASK [validate-host : Collect information about the host] 2025-10-18 20:50:14.108411 | noble | ok 2025-10-18 20:50:14.123240 | 2025-10-18 20:50:14.123373 | TASK [validate-host : Sanitize hostname] 2025-10-18 20:50:14.182692 | noble | ok 2025-10-18 20:50:14.188727 | 2025-10-18 20:50:14.188845 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-18 20:50:14.752206 | noble -> localhost | changed 2025-10-18 20:50:14.758739 | 2025-10-18 20:50:14.759193 | TASK [validate-host : Collect information about zuul worker] 2025-10-18 20:50:15.233202 | noble | ok 2025-10-18 20:50:15.239175 | 2025-10-18 20:50:15.239307 | TASK [validate-host : Write out all zuul information for each host] 2025-10-18 20:50:15.867919 | noble -> localhost | changed 2025-10-18 20:50:15.889137 | 2025-10-18 20:50:15.889277 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-18 20:50:16.215656 | noble | ok 2025-10-18 20:50:16.221835 | 2025-10-18 20:50:16.221941 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-18 20:50:17.312197 | noble | changed: 2025-10-18 20:50:17.312485 | noble | cd+++++++++ src/ 2025-10-18 20:50:17.312546 | noble | cd+++++++++ src/github.com/ 2025-10-18 20:50:17.312589 | noble | cd+++++++++ src/github.com/osism/ 2025-10-18 20:50:17.312627 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-18 20:50:17.312662 | noble | localhost | ok: "/var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/work/logs" 2025-10-18 20:56:24.134252 | noble -> localhost | changed: "/var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/work/artifacts" 2025-10-18 20:56:24.387428 | noble -> localhost | changed: "/var/lib/zuul/builds/f25b7c5586044872b168864a75c64df3/work/docs" 2025-10-18 20:56:24.403668 | 2025-10-18 20:56:24.403865 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-18 20:56:25.720820 | noble | changed: 2025-10-18 20:56:25.721187 | noble | .d..t...... ./ 2025-10-18 20:56:25.721252 | noble | >f+++++++++ osism-ipa-stable.log 2025-10-18 20:56:25.721331 | noble | changed: All items complete 2025-10-18 20:56:25.721378 | 2025-10-18 20:56:26.375254 | noble | changed: .d..t...... ./ 2025-10-18 20:56:27.029594 | noble | changed: .d..t...... ./ 2025-10-18 20:56:27.055660 | 2025-10-18 20:56:27.055866 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-18 20:56:27.560029 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.010018 2025-10-18 20:56:27.828528 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009920 2025-10-18 20:56:27.854496 | 2025-10-18 20:56:27.854612 | PLAY RECAP 2025-10-18 20:56:27.854684 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-18 20:56:27.854720 | 2025-10-18 20:56:27.985443 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-18 20:56:27.987931 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-18 20:56:28.707585 | 2025-10-18 20:56:28.707738 | PLAY [Base post] 2025-10-18 20:56:28.721883 | 2025-10-18 20:56:28.722005 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-18 20:56:29.396859 | noble | changed 2025-10-18 20:56:29.406727 | 2025-10-18 20:56:29.406893 | PLAY RECAP 2025-10-18 20:56:29.406988 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-18 20:56:29.407062 | 2025-10-18 20:56:29.519879 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-18 20:56:29.520851 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-18 20:56:30.295078 | 2025-10-18 20:56:30.295270 | PLAY [Base post-logs] 2025-10-18 20:56:30.305481 | 2025-10-18 20:56:30.305596 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-18 20:56:30.776671 | localhost | changed 2025-10-18 20:56:30.786705 | 2025-10-18 20:56:30.786876 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-18 20:56:30.812507 | localhost | ok 2025-10-18 20:56:30.815624 | 2025-10-18 20:56:30.815721 | TASK [Set zuul-log-path fact] 2025-10-18 20:56:30.830587 | localhost | ok 2025-10-18 20:56:30.838441 | 2025-10-18 20:56:30.838546 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-18 20:56:30.863274 | localhost | ok 2025-10-18 20:56:30.866163 | 2025-10-18 20:56:30.866876 | TASK [upload-logs : Create log directories] 2025-10-18 20:56:31.373789 | localhost | changed 2025-10-18 20:56:31.382904 | 2025-10-18 20:56:31.383053 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-18 20:56:31.863953 | localhost -> localhost | ok: Runtime: 0:00:00.006758 2025-10-18 20:56:31.872362 | 2025-10-18 20:56:31.872557 | TASK [upload-logs : Upload logs to log server] 2025-10-18 20:56:32.481866 | localhost | Output suppressed because no_log was given 2025-10-18 20:56:32.483820 | 2025-10-18 20:56:32.483928 | LOOP [upload-logs : Compress console log and json output] 2025-10-18 20:56:32.536322 | localhost | skipping: Conditional result was False 2025-10-18 20:56:32.541460 | localhost | skipping: Conditional result was False 2025-10-18 20:56:32.551716 | 2025-10-18 20:56:32.551868 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-18 20:56:32.597755 | localhost | skipping: Conditional result was False 2025-10-18 20:56:32.598405 | 2025-10-18 20:56:32.601826 | localhost | skipping: Conditional result was False 2025-10-18 20:56:32.613159 | 2025-10-18 20:56:32.613318 | LOOP [upload-logs : Upload console log and json output]