2025-08-01 16:59:00.325983 | Job console starting 2025-08-01 16:59:00.337495 | Updating git repos 2025-08-01 16:59:00.365783 | Cloning repos into workspace 2025-08-01 16:59:00.428241 | Restoring repo states 2025-08-01 16:59:00.437218 | Merging changes 2025-08-01 16:59:00.437248 | Checking out repos 2025-08-01 16:59:00.489221 | Preparing playbooks 2025-08-01 16:59:01.135435 | Running Ansible setup 2025-08-01 16:59:07.078598 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-01 16:59:07.831300 | 2025-08-01 16:59:07.831462 | PLAY [Base pre] 2025-08-01 16:59:07.848554 | 2025-08-01 16:59:07.848682 | TASK [Setup log path fact] 2025-08-01 16:59:07.880297 | noble | ok 2025-08-01 16:59:07.897822 | 2025-08-01 16:59:07.897959 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-01 16:59:07.938409 | noble | ok 2025-08-01 16:59:07.950408 | 2025-08-01 16:59:07.950516 | TASK [emit-job-header : Print job information] 2025-08-01 16:59:07.999097 | # Job Information 2025-08-01 16:59:07.999366 | Ansible Version: 2.16.14 2025-08-01 16:59:07.999427 | Job: openstack-ironic-images-publish-osism-ipa 2025-08-01 16:59:07.999484 | Pipeline: post 2025-08-01 16:59:07.999524 | Executor: 521e9411259a 2025-08-01 16:59:07.999561 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/0c68eb108dc5a0de469cf93640d619e1435b1d2e 2025-08-01 16:59:07.999600 | Event ID: 694303ec-6ef8-11f0-9d8d-5208b21f7e2d 2025-08-01 16:59:08.009764 | 2025-08-01 16:59:08.009907 | LOOP [emit-job-header : Print node information] 2025-08-01 16:59:08.136897 | noble | ok: 2025-08-01 16:59:08.137144 | noble | # Node Information 2025-08-01 16:59:08.137190 | noble | Inventory Hostname: noble 2025-08-01 16:59:08.137217 | noble | Hostname: ubuntu 2025-08-01 16:59:08.137253 | noble | Username: zuul 2025-08-01 16:59:08.137275 | noble | Distro: Ubuntu 24.04 2025-08-01 16:59:08.137298 | noble | Provider: regiocloud-a 2025-08-01 16:59:08.137318 | noble | Region: 2025-08-01 16:59:08.137340 | noble | Label: ubuntu-noble-uefi 2025-08-01 16:59:08.137431 | noble | Product Name: OpenStack Nova 2025-08-01 16:59:08.137465 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7c:b33a 2025-08-01 16:59:08.160122 | 2025-08-01 16:59:08.160283 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-01 16:59:08.623213 | noble -> localhost | changed 2025-08-01 16:59:08.641249 | 2025-08-01 16:59:08.641431 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-01 16:59:09.694010 | noble -> localhost | changed 2025-08-01 16:59:09.708273 | 2025-08-01 16:59:09.708387 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-01 16:59:10.007266 | noble -> localhost | ok 2025-08-01 16:59:10.017935 | 2025-08-01 16:59:10.018111 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-01 16:59:10.040196 | noble | ok 2025-08-01 16:59:10.056903 | noble | included: /var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-01 16:59:10.065389 | 2025-08-01 16:59:10.065490 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-01 16:59:10.941463 | noble -> localhost | Generating public/private rsa key pair. 2025-08-01 16:59:10.941731 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/work/ea6017a12a5e4587b03f23594760b749_id_rsa 2025-08-01 16:59:10.941777 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/work/ea6017a12a5e4587b03f23594760b749_id_rsa.pub 2025-08-01 16:59:10.941810 | noble -> localhost | The key fingerprint is: 2025-08-01 16:59:10.941839 | noble -> localhost | SHA256:kRxKZerqvJFkjGfPsaxJt+N2wg5sUq8fYfypsy2pJi0 zuul-build-sshkey 2025-08-01 16:59:10.941867 | noble -> localhost | The key's randomart image is: 2025-08-01 16:59:10.941894 | noble -> localhost | +---[RSA 3072]----+ 2025-08-01 16:59:10.941920 | noble -> localhost | | ..+ | 2025-08-01 16:59:10.941980 | noble -> localhost | | . = o | 2025-08-01 16:59:10.942011 | noble -> localhost | | o + | 2025-08-01 16:59:10.942036 | noble -> localhost | | o o . | 2025-08-01 16:59:10.942061 | noble -> localhost | | . B * S | 2025-08-01 16:59:10.942091 | noble -> localhost | | B O = . | 2025-08-01 16:59:10.942117 | noble -> localhost | | ..O.O.o | 2025-08-01 16:59:10.942143 | noble -> localhost | | E*oBO=. | 2025-08-01 16:59:10.942169 | noble -> localhost | | +OBBB. | 2025-08-01 16:59:10.942195 | noble -> localhost | +----[SHA256]-----+ 2025-08-01 16:59:10.942259 | noble -> localhost | ok: Runtime: 0:00:00.385419 2025-08-01 16:59:10.950514 | 2025-08-01 16:59:10.950639 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-01 16:59:10.990254 | noble | ok 2025-08-01 16:59:11.007758 | noble | included: /var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-01 16:59:11.022058 | 2025-08-01 16:59:11.022180 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-01 16:59:11.045930 | noble | skipping: Conditional result was False 2025-08-01 16:59:11.055260 | 2025-08-01 16:59:11.055372 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-01 16:59:11.694767 | noble | changed 2025-08-01 16:59:11.705229 | 2025-08-01 16:59:11.705366 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-01 16:59:12.035077 | noble | ok 2025-08-01 16:59:12.044201 | 2025-08-01 16:59:12.044380 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-01 16:59:12.893279 | noble | changed 2025-08-01 16:59:12.901658 | 2025-08-01 16:59:12.901805 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-01 16:59:13.710486 | noble | changed 2025-08-01 16:59:13.726115 | 2025-08-01 16:59:13.726348 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-01 16:59:13.752548 | noble | skipping: Conditional result was False 2025-08-01 16:59:13.766349 | 2025-08-01 16:59:13.766502 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-01 16:59:14.197593 | noble -> localhost | changed 2025-08-01 16:59:14.211866 | 2025-08-01 16:59:14.212058 | TASK [add-build-sshkey : Add back temp key] 2025-08-01 16:59:14.571503 | noble -> localhost | Identity added: /var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/work/ea6017a12a5e4587b03f23594760b749_id_rsa (zuul-build-sshkey) 2025-08-01 16:59:14.571931 | noble -> localhost | ok: Runtime: 0:00:00.019806 2025-08-01 16:59:14.582374 | 2025-08-01 16:59:14.582508 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-01 16:59:15.057248 | noble | ok 2025-08-01 16:59:15.063600 | 2025-08-01 16:59:15.063719 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-01 16:59:15.087425 | noble | skipping: Conditional result was False 2025-08-01 16:59:15.135631 | 2025-08-01 16:59:15.135749 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-01 16:59:15.584400 | noble | ok 2025-08-01 16:59:15.599407 | 2025-08-01 16:59:15.599540 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-01 16:59:15.647021 | noble | ok 2025-08-01 16:59:15.657596 | 2025-08-01 16:59:15.657719 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-01 16:59:15.955926 | noble -> localhost | ok 2025-08-01 16:59:15.963579 | 2025-08-01 16:59:15.963695 | TASK [validate-host : Collect information about the host] 2025-08-01 16:59:17.422413 | noble | ok 2025-08-01 16:59:17.438657 | 2025-08-01 16:59:17.438776 | TASK [validate-host : Sanitize hostname] 2025-08-01 16:59:17.504311 | noble | ok 2025-08-01 16:59:17.513206 | 2025-08-01 16:59:17.513335 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-01 16:59:18.083485 | noble -> localhost | changed 2025-08-01 16:59:18.090148 | 2025-08-01 16:59:18.090275 | TASK [validate-host : Collect information about zuul worker] 2025-08-01 16:59:18.565630 | noble | ok 2025-08-01 16:59:18.572277 | 2025-08-01 16:59:18.572395 | TASK [validate-host : Write out all zuul information for each host] 2025-08-01 16:59:19.112929 | noble -> localhost | changed 2025-08-01 16:59:19.123860 | 2025-08-01 16:59:19.124019 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-01 16:59:19.452625 | noble | ok 2025-08-01 16:59:19.460989 | 2025-08-01 16:59:19.461107 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-01 16:59:20.468685 | noble | changed: 2025-08-01 16:59:20.469072 | noble | cd+++++++++ src/ 2025-08-01 16:59:20.469140 | noble | cd+++++++++ src/github.com/ 2025-08-01 16:59:20.469182 | noble | cd+++++++++ src/github.com/osism/ 2025-08-01 16:59:20.469218 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-08-01 16:59:20.469252 | noble | localhost | ok: "/var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/work/logs" 2025-08-01 17:05:16.909001 | noble -> localhost | changed: "/var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/work/artifacts" 2025-08-01 17:05:17.186800 | noble -> localhost | changed: "/var/lib/zuul/builds/ea6017a12a5e4587b03f23594760b749/work/docs" 2025-08-01 17:05:17.208707 | 2025-08-01 17:05:17.208855 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-08-01 17:05:18.530203 | noble | changed: 2025-08-01 17:05:18.530599 | noble | .d..t...... ./ 2025-08-01 17:05:18.530666 | noble | >f+++++++++ osism-ipa.log 2025-08-01 17:05:18.530742 | noble | changed: All items complete 2025-08-01 17:05:18.530795 | 2025-08-01 17:05:19.179344 | noble | changed: .d..t...... ./ 2025-08-01 17:05:19.830857 | noble | changed: .d..t...... ./ 2025-08-01 17:05:19.857068 | 2025-08-01 17:05:19.857203 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-08-01 17:05:19.894293 | noble | skipping: Conditional result was False 2025-08-01 17:05:19.897060 | noble | skipping: Conditional result was False 2025-08-01 17:05:19.924194 | 2025-08-01 17:05:19.924358 | PLAY RECAP 2025-08-01 17:05:19.924533 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-08-01 17:05:19.924604 | 2025-08-01 17:05:20.049208 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-08-01 17:05:20.050278 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-01 17:05:20.781042 | 2025-08-01 17:05:20.781202 | PLAY [Base post] 2025-08-01 17:05:20.796784 | 2025-08-01 17:05:20.796916 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-01 17:05:21.425455 | noble | changed 2025-08-01 17:05:21.435413 | 2025-08-01 17:05:21.435540 | PLAY RECAP 2025-08-01 17:05:21.435615 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-01 17:05:21.435688 | 2025-08-01 17:05:21.554722 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-01 17:05:21.557062 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-01 17:05:22.362512 | 2025-08-01 17:05:22.362681 | PLAY [Base post-logs] 2025-08-01 17:05:22.373931 | 2025-08-01 17:05:22.374076 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-01 17:05:22.829325 | localhost | changed 2025-08-01 17:05:22.847278 | 2025-08-01 17:05:22.847521 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-01 17:05:22.886746 | localhost | ok 2025-08-01 17:05:22.894683 | 2025-08-01 17:05:22.894883 | TASK [Set zuul-log-path fact] 2025-08-01 17:05:22.914005 | localhost | ok 2025-08-01 17:05:22.927105 | 2025-08-01 17:05:22.927255 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-01 17:05:22.953638 | localhost | ok 2025-08-01 17:05:22.958555 | 2025-08-01 17:05:22.958700 | TASK [upload-logs : Create log directories] 2025-08-01 17:05:23.453426 | localhost | changed 2025-08-01 17:05:23.456263 | 2025-08-01 17:05:23.456421 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-01 17:05:23.936600 | localhost -> localhost | ok: Runtime: 0:00:00.006926 2025-08-01 17:05:23.945544 | 2025-08-01 17:05:23.945733 | TASK [upload-logs : Upload logs to log server] 2025-08-01 17:05:24.512071 | localhost | Output suppressed because no_log was given 2025-08-01 17:05:24.515064 | 2025-08-01 17:05:24.515224 | LOOP [upload-logs : Compress console log and json output] 2025-08-01 17:05:24.568919 | localhost | skipping: Conditional result was False 2025-08-01 17:05:24.573763 | localhost | skipping: Conditional result was False 2025-08-01 17:05:24.587822 | 2025-08-01 17:05:24.588058 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-01 17:05:24.635848 | localhost | skipping: Conditional result was False 2025-08-01 17:05:24.636600 | 2025-08-01 17:05:24.639729 | localhost | skipping: Conditional result was False 2025-08-01 17:05:24.652845 | 2025-08-01 17:05:24.653193 | LOOP [upload-logs : Upload console log and json output]