2025-07-31 09:46:56.104326 | Job console starting 2025-07-31 09:46:56.125864 | Updating git repos 2025-07-31 09:46:56.161616 | Cloning repos into workspace 2025-07-31 09:46:56.199391 | Restoring repo states 2025-07-31 09:46:56.207097 | Merging changes 2025-07-31 09:46:56.674322 | Checking out repos 2025-07-31 09:46:56.704430 | Preparing playbooks 2025-07-31 09:46:57.291202 | Running Ansible setup 2025-07-31 09:47:03.173488 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-31 09:47:03.915391 | 2025-07-31 09:47:03.915556 | PLAY [Base pre] 2025-07-31 09:47:03.932621 | 2025-07-31 09:47:03.932759 | TASK [Setup log path fact] 2025-07-31 09:47:03.963383 | noble | ok 2025-07-31 09:47:03.980924 | 2025-07-31 09:47:03.981080 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 09:47:04.020486 | noble | ok 2025-07-31 09:47:04.032394 | 2025-07-31 09:47:04.032512 | TASK [emit-job-header : Print job information] 2025-07-31 09:47:04.075257 | # Job Information 2025-07-31 09:47:04.075494 | Ansible Version: 2.16.14 2025-07-31 09:47:04.075545 | Job: openstack-ironic-images-build-osism-ipa 2025-07-31 09:47:04.075592 | Pipeline: check 2025-07-31 09:47:04.075627 | Executor: 521e9411259a 2025-07-31 09:47:04.075656 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/117 2025-07-31 09:47:04.075687 | Event ID: e992f180-6df2-11f0-82bf-eceeb5a95a94 2025-07-31 09:47:04.084097 | 2025-07-31 09:47:04.084271 | LOOP [emit-job-header : Print node information] 2025-07-31 09:47:04.205573 | noble | ok: 2025-07-31 09:47:04.205853 | noble | # Node Information 2025-07-31 09:47:04.205909 | noble | Inventory Hostname: noble 2025-07-31 09:47:04.205951 | noble | Hostname: ubuntu 2025-07-31 09:47:04.205985 | noble | Username: zuul 2025-07-31 09:47:04.206018 | noble | Distro: Ubuntu 24.04 2025-07-31 09:47:04.206055 | noble | Provider: regiocloud-a 2025-07-31 09:47:04.206090 | noble | Region: 2025-07-31 09:47:04.206125 | noble | Label: ubuntu-noble-uefi 2025-07-31 09:47:04.206157 | noble | Product Name: OpenStack Nova 2025-07-31 09:47:04.206207 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe2f:5982 2025-07-31 09:47:04.230280 | 2025-07-31 09:47:04.230438 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-31 09:47:04.685155 | noble -> localhost | changed 2025-07-31 09:47:04.693985 | 2025-07-31 09:47:04.694112 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-31 09:47:05.802361 | noble -> localhost | changed 2025-07-31 09:47:05.817572 | 2025-07-31 09:47:05.817691 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-31 09:47:06.093467 | noble -> localhost | ok 2025-07-31 09:47:06.101073 | 2025-07-31 09:47:06.101215 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-31 09:47:06.130077 | noble | ok 2025-07-31 09:47:06.146912 | noble | included: /var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-31 09:47:06.155270 | 2025-07-31 09:47:06.155367 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-31 09:47:06.911238 | noble -> localhost | Generating public/private rsa key pair. 2025-07-31 09:47:06.911694 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/work/e752513b02d44af6afa070a26c7445b1_id_rsa 2025-07-31 09:47:06.911790 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/work/e752513b02d44af6afa070a26c7445b1_id_rsa.pub 2025-07-31 09:47:06.911860 | noble -> localhost | The key fingerprint is: 2025-07-31 09:47:06.911923 | noble -> localhost | SHA256:vpgPRQ4iHbtzf54qoddEwUl0IBdmGuGXj9LmGV0NAlw zuul-build-sshkey 2025-07-31 09:47:06.911981 | noble -> localhost | The key's randomart image is: 2025-07-31 09:47:06.912035 | noble -> localhost | +---[RSA 3072]----+ 2025-07-31 09:47:06.912088 | noble -> localhost | | . +*X*E . | 2025-07-31 09:47:06.912182 | noble -> localhost | | . + B=o . o | 2025-07-31 09:47:06.912244 | noble -> localhost | | . + + +. . . | 2025-07-31 09:47:06.912298 | noble -> localhost | | . o *.+ . | 2025-07-31 09:47:06.912351 | noble -> localhost | | o o.S o | 2025-07-31 09:47:06.912416 | noble -> localhost | | o.B.o | 2025-07-31 09:47:06.912470 | noble -> localhost | | ..+= . | 2025-07-31 09:47:06.912521 | noble -> localhost | | . o+.+ . | 2025-07-31 09:47:06.912575 | noble -> localhost | | .oo+.o | 2025-07-31 09:47:06.912626 | noble -> localhost | +----[SHA256]-----+ 2025-07-31 09:47:06.912739 | noble -> localhost | ok: Runtime: 0:00:00.267469 2025-07-31 09:47:06.926234 | 2025-07-31 09:47:06.926483 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-31 09:47:06.961200 | noble | ok 2025-07-31 09:47:06.972658 | noble | included: /var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-31 09:47:06.981661 | 2025-07-31 09:47:06.981756 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-31 09:47:07.005036 | noble | skipping: Conditional result was False 2025-07-31 09:47:07.013376 | 2025-07-31 09:47:07.013482 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-31 09:47:07.662038 | noble | changed 2025-07-31 09:47:07.672883 | 2025-07-31 09:47:07.673015 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-31 09:47:07.994977 | noble | ok 2025-07-31 09:47:08.004027 | 2025-07-31 09:47:08.004209 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-31 09:47:08.832652 | noble | changed 2025-07-31 09:47:08.842227 | 2025-07-31 09:47:08.842356 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-31 09:47:09.641256 | noble | changed 2025-07-31 09:47:09.649303 | 2025-07-31 09:47:09.649434 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-31 09:47:09.673755 | noble | skipping: Conditional result was False 2025-07-31 09:47:09.685159 | 2025-07-31 09:47:09.685328 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-31 09:47:10.144444 | noble -> localhost | changed 2025-07-31 09:47:10.162406 | 2025-07-31 09:47:10.162534 | TASK [add-build-sshkey : Add back temp key] 2025-07-31 09:47:10.492385 | noble -> localhost | Identity added: /var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/work/e752513b02d44af6afa070a26c7445b1_id_rsa (zuul-build-sshkey) 2025-07-31 09:47:10.492676 | noble -> localhost | ok: Runtime: 0:00:00.018188 2025-07-31 09:47:10.500081 | 2025-07-31 09:47:10.500205 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-31 09:47:10.941639 | noble | ok 2025-07-31 09:47:10.950316 | 2025-07-31 09:47:10.950442 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-31 09:47:10.984453 | noble | skipping: Conditional result was False 2025-07-31 09:47:11.038004 | 2025-07-31 09:47:11.038138 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-31 09:47:11.483196 | noble | ok 2025-07-31 09:47:11.499016 | 2025-07-31 09:47:11.499147 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-31 09:47:11.528754 | noble | ok 2025-07-31 09:47:11.535868 | 2025-07-31 09:47:11.535969 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-31 09:47:11.822437 | noble -> localhost | ok 2025-07-31 09:47:11.830234 | 2025-07-31 09:47:11.830346 | TASK [validate-host : Collect information about the host] 2025-07-31 09:47:13.255163 | noble | ok 2025-07-31 09:47:13.268726 | 2025-07-31 09:47:13.268845 | TASK [validate-host : Sanitize hostname] 2025-07-31 09:47:13.331446 | noble | ok 2025-07-31 09:47:13.341093 | 2025-07-31 09:47:13.341285 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-31 09:47:13.955666 | noble -> localhost | changed 2025-07-31 09:47:13.968988 | 2025-07-31 09:47:13.969142 | TASK [validate-host : Collect information about zuul worker] 2025-07-31 09:47:14.466147 | noble | ok 2025-07-31 09:47:14.472062 | 2025-07-31 09:47:14.472204 | TASK [validate-host : Write out all zuul information for each host] 2025-07-31 09:47:15.052896 | noble -> localhost | changed 2025-07-31 09:47:15.064024 | 2025-07-31 09:47:15.064146 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-31 09:47:15.389116 | noble | ok 2025-07-31 09:47:15.395395 | 2025-07-31 09:47:15.395506 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-31 09:47:16.337727 | noble | changed: 2025-07-31 09:47:16.338241 | noble | cd+++++++++ src/ 2025-07-31 09:47:16.338332 | noble | cd+++++++++ src/github.com/ 2025-07-31 09:47:16.338380 | noble | cd+++++++++ src/github.com/osism/ 2025-07-31 09:47:16.338419 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-31 09:47:16.338454 | noble | localhost | ok: "/var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/work/logs" 2025-07-31 09:52:50.578523 | noble -> localhost | changed: "/var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/work/artifacts" 2025-07-31 09:52:50.862636 | noble -> localhost | changed: "/var/lib/zuul/builds/e752513b02d44af6afa070a26c7445b1/work/docs" 2025-07-31 09:52:50.884202 | 2025-07-31 09:52:50.884370 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-31 09:52:52.291126 | noble | changed: 2025-07-31 09:52:52.291454 | noble | .d..t...... ./ 2025-07-31 09:52:52.291524 | noble | >f+++++++++ osism-ipa.log 2025-07-31 09:52:52.291700 | noble | changed: All items complete 2025-07-31 09:52:52.291745 | 2025-07-31 09:52:52.957628 | noble | changed: .d..t...... ./ 2025-07-31 09:52:53.631115 | noble | changed: .d..t...... ./ 2025-07-31 09:52:53.664148 | 2025-07-31 09:52:53.664296 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-31 09:52:54.171546 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.005717 2025-07-31 09:52:54.495106 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010456 2025-07-31 09:52:54.520730 | 2025-07-31 09:52:54.520944 | PLAY RECAP 2025-07-31 09:52:54.521028 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-31 09:52:54.521063 | 2025-07-31 09:52:54.655067 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-31 09:52:54.657270 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 09:52:55.414572 | 2025-07-31 09:52:55.414746 | PLAY [Base post] 2025-07-31 09:52:55.430757 | 2025-07-31 09:52:55.430948 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-31 09:52:56.110086 | noble | changed 2025-07-31 09:52:56.121008 | 2025-07-31 09:52:56.121132 | PLAY RECAP 2025-07-31 09:52:56.121207 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-31 09:52:56.121283 | 2025-07-31 09:52:56.243244 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 09:52:56.245498 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-31 09:52:57.035437 | 2025-07-31 09:52:57.035620 | PLAY [Base post-logs] 2025-07-31 09:52:57.046781 | 2025-07-31 09:52:57.046959 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-31 09:52:57.511553 | localhost | changed 2025-07-31 09:52:57.527152 | 2025-07-31 09:52:57.527313 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-31 09:52:57.565266 | localhost | ok 2025-07-31 09:52:57.570954 | 2025-07-31 09:52:57.571097 | TASK [Set zuul-log-path fact] 2025-07-31 09:52:57.598738 | localhost | ok 2025-07-31 09:52:57.611768 | 2025-07-31 09:52:57.611909 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 09:52:57.649398 | localhost | ok 2025-07-31 09:52:57.656666 | 2025-07-31 09:52:57.656837 | TASK [upload-logs : Create log directories] 2025-07-31 09:52:58.167386 | localhost | changed 2025-07-31 09:52:58.172213 | 2025-07-31 09:52:58.172373 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-31 09:52:58.683999 | localhost -> localhost | ok: Runtime: 0:00:00.006964 2025-07-31 09:52:58.688183 | 2025-07-31 09:52:58.688299 | TASK [upload-logs : Upload logs to log server] 2025-07-31 09:52:59.284212 | localhost | Output suppressed because no_log was given 2025-07-31 09:52:59.286863 | 2025-07-31 09:52:59.287014 | LOOP [upload-logs : Compress console log and json output] 2025-07-31 09:52:59.345169 | localhost | skipping: Conditional result was False 2025-07-31 09:52:59.351341 | localhost | skipping: Conditional result was False 2025-07-31 09:52:59.358820 | 2025-07-31 09:52:59.359113 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-31 09:52:59.406096 | localhost | skipping: Conditional result was False 2025-07-31 09:52:59.406699 | 2025-07-31 09:52:59.410061 | localhost | skipping: Conditional result was False 2025-07-31 09:52:59.424045 | 2025-07-31 09:52:59.424281 | LOOP [upload-logs : Upload console log and json output]