2025-08-27 11:46:09.436029 | Job console starting 2025-08-27 11:46:09.453889 | Updating git repos 2025-08-27 11:46:09.475836 | Cloning repos into workspace 2025-08-27 11:46:09.521740 | Restoring repo states 2025-08-27 11:46:09.526402 | Merging changes 2025-08-27 11:46:09.526420 | Checking out repos 2025-08-27 11:46:09.567009 | Preparing playbooks 2025-08-27 11:46:10.187608 | Running Ansible setup 2025-08-27 11:46:16.088445 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-27 11:46:16.844279 | 2025-08-27 11:46:16.844444 | PLAY [Base pre] 2025-08-27 11:46:16.862164 | 2025-08-27 11:46:16.862299 | TASK [Setup log path fact] 2025-08-27 11:46:16.881930 | noble | ok 2025-08-27 11:46:16.899741 | 2025-08-27 11:46:16.899893 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 11:46:16.939743 | noble | ok 2025-08-27 11:46:16.951780 | 2025-08-27 11:46:16.951909 | TASK [emit-job-header : Print job information] 2025-08-27 11:46:16.991513 | # Job Information 2025-08-27 11:46:16.991696 | Ansible Version: 2.16.14 2025-08-27 11:46:16.991731 | Job: openstack-ironic-images-publish-osism-ipa 2025-08-27 11:46:16.991763 | Pipeline: post 2025-08-27 11:46:16.991785 | Executor: 521e9411259a 2025-08-27 11:46:16.991805 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-08-27 11:46:16.991826 | Event ID: 7ff1bedda35342b5957e7ccb7e392c3a 2025-08-27 11:46:16.998707 | 2025-08-27 11:46:16.998860 | LOOP [emit-job-header : Print node information] 2025-08-27 11:46:17.152349 | noble | ok: 2025-08-27 11:46:17.152557 | noble | # Node Information 2025-08-27 11:46:17.152593 | noble | Inventory Hostname: noble 2025-08-27 11:46:17.152618 | noble | Hostname: ubuntu 2025-08-27 11:46:17.152640 | noble | Username: zuul 2025-08-27 11:46:17.152661 | noble | Distro: Ubuntu 24.04 2025-08-27 11:46:17.152684 | noble | Provider: regiocloud-a 2025-08-27 11:46:17.152704 | noble | Region: 2025-08-27 11:46:17.152726 | noble | Label: ubuntu-noble-uefi 2025-08-27 11:46:17.152745 | noble | Product Name: OpenStack Nova 2025-08-27 11:46:17.152765 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee4:9784 2025-08-27 11:46:17.177599 | 2025-08-27 11:46:17.177757 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-27 11:46:17.683351 | noble -> localhost | changed 2025-08-27 11:46:17.697090 | 2025-08-27 11:46:17.697241 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-27 11:46:18.764504 | noble -> localhost | changed 2025-08-27 11:46:18.779635 | 2025-08-27 11:46:18.779779 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-27 11:46:19.092578 | noble -> localhost | ok 2025-08-27 11:46:19.099847 | 2025-08-27 11:46:19.100011 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-27 11:46:19.129954 | noble | ok 2025-08-27 11:46:19.159940 | noble | included: /var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-27 11:46:19.169719 | 2025-08-27 11:46:19.169858 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-27 11:46:20.646102 | noble -> localhost | Generating public/private rsa key pair. 2025-08-27 11:46:20.646350 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/work/191478bc705048e68cb1cba1b06372e7_id_rsa 2025-08-27 11:46:20.646391 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/work/191478bc705048e68cb1cba1b06372e7_id_rsa.pub 2025-08-27 11:46:20.646417 | noble -> localhost | The key fingerprint is: 2025-08-27 11:46:20.646440 | noble -> localhost | SHA256:lpQRxtN+Bhkk53+K3vIxm3AzC7yEFPOFC/7xYRVbJOA zuul-build-sshkey 2025-08-27 11:46:20.646462 | noble -> localhost | The key's randomart image is: 2025-08-27 11:46:20.646482 | noble -> localhost | +---[RSA 3072]----+ 2025-08-27 11:46:20.646502 | noble -> localhost | | .=++o..o.o| 2025-08-27 11:46:20.646523 | noble -> localhost | | .o*+o = | 2025-08-27 11:46:20.646555 | noble -> localhost | | *oo.E o | 2025-08-27 11:46:20.646576 | noble -> localhost | | o *.+o. | 2025-08-27 11:46:20.646595 | noble -> localhost | | S +o+ . | 2025-08-27 11:46:20.646624 | noble -> localhost | | o + = + | 2025-08-27 11:46:20.646645 | noble -> localhost | | . B O | 2025-08-27 11:46:20.646664 | noble -> localhost | | o.* O | 2025-08-27 11:46:20.646685 | noble -> localhost | | oo* | 2025-08-27 11:46:20.646706 | noble -> localhost | +----[SHA256]-----+ 2025-08-27 11:46:20.646762 | noble -> localhost | ok: Runtime: 0:00:00.711821 2025-08-27 11:46:20.654547 | 2025-08-27 11:46:20.654681 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-27 11:46:20.684143 | noble | ok 2025-08-27 11:46:20.694343 | noble | included: /var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-27 11:46:20.703812 | 2025-08-27 11:46:20.703920 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-27 11:46:20.727818 | noble | skipping: Conditional result was False 2025-08-27 11:46:20.736192 | 2025-08-27 11:46:20.736309 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-27 11:46:21.380383 | noble | changed 2025-08-27 11:46:21.389924 | 2025-08-27 11:46:21.390076 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-27 11:46:21.724123 | noble | ok 2025-08-27 11:46:21.730562 | 2025-08-27 11:46:21.730674 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-27 11:46:22.610438 | noble | changed 2025-08-27 11:46:22.617304 | 2025-08-27 11:46:22.617488 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-27 11:46:23.384906 | noble | changed 2025-08-27 11:46:23.391596 | 2025-08-27 11:46:23.391711 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-27 11:46:23.415358 | noble | skipping: Conditional result was False 2025-08-27 11:46:23.422306 | 2025-08-27 11:46:23.422415 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-27 11:46:23.937771 | noble -> localhost | changed 2025-08-27 11:46:23.952068 | 2025-08-27 11:46:23.952203 | TASK [add-build-sshkey : Add back temp key] 2025-08-27 11:46:24.291817 | noble -> localhost | Identity added: /var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/work/191478bc705048e68cb1cba1b06372e7_id_rsa (zuul-build-sshkey) 2025-08-27 11:46:24.292207 | noble -> localhost | ok: Runtime: 0:00:00.010778 2025-08-27 11:46:24.303343 | 2025-08-27 11:46:24.303468 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-27 11:46:24.769669 | noble | ok 2025-08-27 11:46:24.779946 | 2025-08-27 11:46:24.780155 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-27 11:46:24.805076 | noble | skipping: Conditional result was False 2025-08-27 11:46:24.861038 | 2025-08-27 11:46:24.861172 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-27 11:46:25.310855 | noble | ok 2025-08-27 11:46:25.337826 | 2025-08-27 11:46:25.337989 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-27 11:46:25.402453 | noble | ok 2025-08-27 11:46:25.414451 | 2025-08-27 11:46:25.414583 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-27 11:46:25.802963 | noble -> localhost | ok 2025-08-27 11:46:25.810922 | 2025-08-27 11:46:25.811058 | TASK [validate-host : Collect information about the host] 2025-08-27 11:46:27.357714 | noble | ok 2025-08-27 11:46:27.373613 | 2025-08-27 11:46:27.373756 | TASK [validate-host : Sanitize hostname] 2025-08-27 11:46:27.443891 | noble | ok 2025-08-27 11:46:27.450214 | 2025-08-27 11:46:27.450373 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-27 11:46:28.035212 | noble -> localhost | changed 2025-08-27 11:46:28.041902 | 2025-08-27 11:46:28.042035 | TASK [validate-host : Collect information about zuul worker] 2025-08-27 11:46:28.540449 | noble | ok 2025-08-27 11:46:28.548080 | 2025-08-27 11:46:28.548229 | TASK [validate-host : Write out all zuul information for each host] 2025-08-27 11:46:29.058295 | noble -> localhost | changed 2025-08-27 11:46:29.071235 | 2025-08-27 11:46:29.071379 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-27 11:46:29.419265 | noble | ok 2025-08-27 11:46:29.427197 | 2025-08-27 11:46:29.427327 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-27 11:46:30.394432 | noble | changed: 2025-08-27 11:46:30.394629 | noble | cd+++++++++ src/ 2025-08-27 11:46:30.394664 | noble | cd+++++++++ src/github.com/ 2025-08-27 11:46:30.394689 | noble | cd+++++++++ src/github.com/osism/ 2025-08-27 11:46:30.394710 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-08-27 11:46:30.394731 | noble | localhost | ok: "/var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/work/logs" 2025-08-27 11:54:33.283444 | noble -> localhost | changed: "/var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/work/artifacts" 2025-08-27 11:54:33.560332 | noble -> localhost | changed: "/var/lib/zuul/builds/191478bc705048e68cb1cba1b06372e7/work/docs" 2025-08-27 11:54:33.579667 | 2025-08-27 11:54:33.579787 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-08-27 11:54:34.904896 | noble | changed: 2025-08-27 11:54:34.905395 | noble | .d..t...... ./ 2025-08-27 11:54:34.905463 | noble | >f+++++++++ osism-ipa.log 2025-08-27 11:54:34.905531 | noble | changed: All items complete 2025-08-27 11:54:34.905571 | 2025-08-27 11:54:35.583081 | noble | changed: .d..t...... ./ 2025-08-27 11:54:36.267890 | noble | changed: .d..t...... ./ 2025-08-27 11:54:36.287564 | 2025-08-27 11:54:36.287683 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-08-27 11:54:36.321319 | noble | skipping: Conditional result was False 2025-08-27 11:54:36.323884 | noble | skipping: Conditional result was False 2025-08-27 11:54:36.349573 | 2025-08-27 11:54:36.349690 | PLAY RECAP 2025-08-27 11:54:36.349767 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-08-27 11:54:36.349809 | 2025-08-27 11:54:36.475710 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-08-27 11:54:36.478060 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-27 11:54:37.221012 | 2025-08-27 11:54:37.221191 | PLAY [Base post] 2025-08-27 11:54:37.236293 | 2025-08-27 11:54:37.236427 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-27 11:54:37.878933 | noble | changed 2025-08-27 11:54:37.888234 | 2025-08-27 11:54:37.888419 | PLAY RECAP 2025-08-27 11:54:37.888496 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-27 11:54:37.888592 | 2025-08-27 11:54:38.012291 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-27 11:54:38.013267 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-27 11:54:38.819476 | 2025-08-27 11:54:38.819647 | PLAY [Base post-logs] 2025-08-27 11:54:38.830592 | 2025-08-27 11:54:38.830724 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-27 11:54:39.304501 | localhost | changed 2025-08-27 11:54:39.314701 | 2025-08-27 11:54:39.314873 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-27 11:54:39.353139 | localhost | ok 2025-08-27 11:54:39.359251 | 2025-08-27 11:54:39.359406 | TASK [Set zuul-log-path fact] 2025-08-27 11:54:39.378380 | localhost | ok 2025-08-27 11:54:39.393659 | 2025-08-27 11:54:39.393803 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 11:54:39.432169 | localhost | ok 2025-08-27 11:54:39.440262 | 2025-08-27 11:54:39.440468 | TASK [upload-logs : Create log directories] 2025-08-27 11:54:39.960765 | localhost | changed 2025-08-27 11:54:39.966211 | 2025-08-27 11:54:39.966380 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-27 11:54:40.490313 | localhost -> localhost | ok: Runtime: 0:00:00.006674 2025-08-27 11:54:40.499036 | 2025-08-27 11:54:40.499290 | TASK [upload-logs : Upload logs to log server] 2025-08-27 11:54:41.064456 | localhost | Output suppressed because no_log was given 2025-08-27 11:54:41.067056 | 2025-08-27 11:54:41.067288 | LOOP [upload-logs : Compress console log and json output] 2025-08-27 11:54:41.121225 | localhost | skipping: Conditional result was False 2025-08-27 11:54:41.126781 | localhost | skipping: Conditional result was False 2025-08-27 11:54:41.139382 | 2025-08-27 11:54:41.139607 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-27 11:54:41.187778 | localhost | skipping: Conditional result was False 2025-08-27 11:54:41.188426 | 2025-08-27 11:54:41.191781 | localhost | skipping: Conditional result was False 2025-08-27 11:54:41.205057 | 2025-08-27 11:54:41.205287 | LOOP [upload-logs : Upload console log and json output]