2026-04-19 04:48:00.897095 | Job console starting 2026-04-19 04:48:00.921568 | Updating git repos 2026-04-19 04:48:00.938582 | Cloning repos into workspace 2026-04-19 04:48:00.990450 | Restoring repo states 2026-04-19 04:48:00.996937 | Merging changes 2026-04-19 04:48:01.507198 | Checking out repos 2026-04-19 04:48:01.576751 | Preparing playbooks 2026-04-19 04:48:02.274638 | Running Ansible setup 2026-04-19 04:48:08.284056 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-19 04:48:09.118133 | 2026-04-19 04:48:09.118306 | PLAY [Base pre] 2026-04-19 04:48:09.135621 | 2026-04-19 04:48:09.135771 | TASK [Setup log path fact] 2026-04-19 04:48:09.156078 | noble | ok 2026-04-19 04:48:09.173896 | 2026-04-19 04:48:09.174074 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 04:48:09.214922 | noble | ok 2026-04-19 04:48:09.226857 | 2026-04-19 04:48:09.226996 | TASK [emit-job-header : Print job information] 2026-04-19 04:48:09.276814 | # Job Information 2026-04-19 04:48:09.277086 | Ansible Version: 2.16.14 2026-04-19 04:48:09.277130 | Job: openstack-ironic-images-build-osism-esp 2026-04-19 04:48:09.277166 | Pipeline: check 2026-04-19 04:48:09.277192 | Executor: 521e9411259a 2026-04-19 04:48:09.277214 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/200 2026-04-19 04:48:09.277237 | Event ID: 8c8570d6-3baa-11f1-9d4c-c6732948ad1f 2026-04-19 04:48:09.290909 | 2026-04-19 04:48:09.291449 | LOOP [emit-job-header : Print node information] 2026-04-19 04:48:09.427241 | noble | ok: 2026-04-19 04:48:09.427553 | noble | # Node Information 2026-04-19 04:48:09.427599 | noble | Inventory Hostname: noble 2026-04-19 04:48:09.427625 | noble | Hostname: ubuntu 2026-04-19 04:48:09.427648 | noble | Username: zuul 2026-04-19 04:48:09.427669 | noble | Distro: Ubuntu 24.04 2026-04-19 04:48:09.427700 | noble | Provider: regiocloud-a 2026-04-19 04:48:09.427725 | noble | Region: 2026-04-19 04:48:09.427747 | noble | Label: ubuntu-noble-uefi 2026-04-19 04:48:09.427767 | noble | Product Name: OpenStack Nova 2026-04-19 04:48:09.427789 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee3:5eae 2026-04-19 04:48:09.451697 | 2026-04-19 04:48:09.451841 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-19 04:48:10.058440 | noble -> localhost | changed 2026-04-19 04:48:10.068176 | 2026-04-19 04:48:10.068311 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-19 04:48:11.141776 | noble -> localhost | changed 2026-04-19 04:48:11.157102 | 2026-04-19 04:48:11.157231 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-19 04:48:11.453817 | noble -> localhost | ok 2026-04-19 04:48:11.461176 | 2026-04-19 04:48:11.461302 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-19 04:48:11.494961 | noble | ok 2026-04-19 04:48:11.525565 | noble | included: /var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-19 04:48:11.541560 | 2026-04-19 04:48:11.541725 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-19 04:48:15.040438 | noble -> localhost | Generating public/private rsa key pair. 2026-04-19 04:48:15.040829 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/work/18d8448015994b13b163ac4f37c4273d_id_rsa 2026-04-19 04:48:15.040899 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/work/18d8448015994b13b163ac4f37c4273d_id_rsa.pub 2026-04-19 04:48:15.040952 | noble -> localhost | The key fingerprint is: 2026-04-19 04:48:15.041004 | noble -> localhost | SHA256:MjFzLnFDEzdu63PIxgYqsNX3fP3jzPUEdRhyaGaiG30 zuul-build-sshkey 2026-04-19 04:48:15.041047 | noble -> localhost | The key's randomart image is: 2026-04-19 04:48:15.041088 | noble -> localhost | +---[RSA 3072]----+ 2026-04-19 04:48:15.041129 | noble -> localhost | | +.o ..o | 2026-04-19 04:48:15.041195 | noble -> localhost | | . +..=o o | 2026-04-19 04:48:15.041237 | noble -> localhost | | = +oo= . o| 2026-04-19 04:48:15.041277 | noble -> localhost | | . Ooo..E ..| 2026-04-19 04:48:15.041316 | noble -> localhost | | . . = So.. . | 2026-04-19 04:48:15.041369 | noble -> localhost | | + *.B . . . | 2026-04-19 04:48:15.041409 | noble -> localhost | | . . . @ o . o| 2026-04-19 04:48:15.041450 | noble -> localhost | | . o + o+o| 2026-04-19 04:48:15.041509 | noble -> localhost | | .++| 2026-04-19 04:48:15.041552 | noble -> localhost | +----[SHA256]-----+ 2026-04-19 04:48:15.041655 | noble -> localhost | ok: Runtime: 0:00:02.959279 2026-04-19 04:48:15.053139 | 2026-04-19 04:48:15.053286 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-19 04:48:15.083345 | noble | ok 2026-04-19 04:48:15.093590 | noble | included: /var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-19 04:48:15.102960 | 2026-04-19 04:48:15.103086 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-19 04:48:15.127350 | noble | skipping: Conditional result was False 2026-04-19 04:48:15.136072 | 2026-04-19 04:48:15.136205 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-19 04:48:15.800567 | noble | changed 2026-04-19 04:48:15.810701 | 2026-04-19 04:48:15.810866 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-19 04:48:16.148079 | noble | ok 2026-04-19 04:48:16.154909 | 2026-04-19 04:48:16.155024 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-19 04:48:16.994717 | noble | changed 2026-04-19 04:48:17.001471 | 2026-04-19 04:48:17.001615 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-19 04:48:17.810254 | noble | changed 2026-04-19 04:48:17.817504 | 2026-04-19 04:48:17.817632 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-19 04:48:17.841361 | noble | skipping: Conditional result was False 2026-04-19 04:48:17.848407 | 2026-04-19 04:48:17.848576 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-19 04:48:18.305072 | noble -> localhost | changed 2026-04-19 04:48:18.319652 | 2026-04-19 04:48:18.319792 | TASK [add-build-sshkey : Add back temp key] 2026-04-19 04:48:18.672967 | noble -> localhost | Identity added: /var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/work/18d8448015994b13b163ac4f37c4273d_id_rsa (zuul-build-sshkey) 2026-04-19 04:48:18.673224 | noble -> localhost | ok: Runtime: 0:00:00.011890 2026-04-19 04:48:18.680891 | 2026-04-19 04:48:18.681026 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-19 04:48:19.210052 | noble | ok 2026-04-19 04:48:19.216559 | 2026-04-19 04:48:19.216678 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-19 04:48:19.240244 | noble | skipping: Conditional result was False 2026-04-19 04:48:19.308890 | 2026-04-19 04:48:19.309025 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-19 04:48:19.729055 | noble | ok 2026-04-19 04:48:19.740755 | 2026-04-19 04:48:19.740885 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-19 04:48:19.770124 | noble | ok 2026-04-19 04:48:19.777740 | 2026-04-19 04:48:19.777855 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-19 04:48:20.110958 | noble -> localhost | ok 2026-04-19 04:48:20.126877 | 2026-04-19 04:48:20.127133 | TASK [validate-host : Collect information about the host] 2026-04-19 04:48:21.614162 | noble | ok 2026-04-19 04:48:21.635077 | 2026-04-19 04:48:21.635224 | TASK [validate-host : Sanitize hostname] 2026-04-19 04:48:21.711666 | noble | ok 2026-04-19 04:48:21.728030 | 2026-04-19 04:48:21.728181 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-19 04:48:22.294709 | noble -> localhost | changed 2026-04-19 04:48:22.301415 | 2026-04-19 04:48:22.301572 | TASK [validate-host : Collect information about zuul worker] 2026-04-19 04:48:22.770280 | noble | ok 2026-04-19 04:48:22.778245 | 2026-04-19 04:48:22.778388 | TASK [validate-host : Write out all zuul information for each host] 2026-04-19 04:48:23.478739 | noble -> localhost | changed 2026-04-19 04:48:23.489259 | 2026-04-19 04:48:23.489371 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-19 04:48:23.798689 | noble | ok 2026-04-19 04:48:23.810052 | 2026-04-19 04:48:23.810309 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-19 04:48:24.884322 | noble | changed: 2026-04-19 04:48:24.884603 | noble | cd+++++++++ src/ 2026-04-19 04:48:24.884645 | noble | cd+++++++++ src/github.com/ 2026-04-19 04:48:24.884670 | noble | cd+++++++++ src/github.com/osism/ 2026-04-19 04:48:24.884691 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-04-19 04:48:24.884711 | noble | localhost | ok: "/var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/work/logs" 2026-04-19 04:51:38.556346 | noble -> localhost | changed: "/var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/work/artifacts" 2026-04-19 04:51:38.810603 | noble -> localhost | changed: "/var/lib/zuul/builds/18d8448015994b13b163ac4f37c4273d/work/docs" 2026-04-19 04:51:38.836065 | 2026-04-19 04:51:38.836228 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-19 04:51:40.133220 | noble | changed: 2026-04-19 04:51:40.133496 | noble | .d..t...... ./ 2026-04-19 04:51:40.133588 | noble | >f+++++++++ osism-esp.log 2026-04-19 04:51:40.133668 | noble | changed: All items complete 2026-04-19 04:51:40.133716 | 2026-04-19 04:51:40.799326 | noble | changed: .d..t...... ./ 2026-04-19 04:51:41.479800 | noble | changed: .d..t...... ./ 2026-04-19 04:51:41.514323 | 2026-04-19 04:51:41.514483 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-19 04:51:42.049334 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.011900 2026-04-19 04:51:42.342667 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010042 2026-04-19 04:51:42.358822 | 2026-04-19 04:51:42.358974 | PLAY RECAP 2026-04-19 04:51:42.359060 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-19 04:51:42.359108 | 2026-04-19 04:51:42.483309 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-19 04:51:42.484291 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 04:51:43.220646 | 2026-04-19 04:51:43.220815 | PLAY [Base post] 2026-04-19 04:51:43.235515 | 2026-04-19 04:51:43.235685 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-19 04:51:43.904101 | noble | changed 2026-04-19 04:51:43.914983 | 2026-04-19 04:51:43.915124 | PLAY RECAP 2026-04-19 04:51:43.915204 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 04:51:43.915279 | 2026-04-19 04:51:44.038199 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 04:51:44.039213 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-19 04:51:44.819278 | 2026-04-19 04:51:44.819453 | PLAY [Base post-logs] 2026-04-19 04:51:44.831299 | 2026-04-19 04:51:44.831448 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-19 04:51:45.337234 | localhost | changed 2026-04-19 04:51:45.353429 | 2026-04-19 04:51:45.353652 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-19 04:51:45.391358 | localhost | ok 2026-04-19 04:51:45.396781 | 2026-04-19 04:51:45.396928 | TASK [Set zuul-log-path fact] 2026-04-19 04:51:45.418429 | localhost | ok 2026-04-19 04:51:45.433328 | 2026-04-19 04:51:45.433477 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 04:51:45.470638 | localhost | ok 2026-04-19 04:51:45.475981 | 2026-04-19 04:51:45.476136 | TASK [upload-logs : Create log directories] 2026-04-19 04:51:45.975378 | localhost | changed 2026-04-19 04:51:45.981289 | 2026-04-19 04:51:45.981476 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-19 04:51:46.485486 | localhost -> localhost | ok: Runtime: 0:00:00.006807 2026-04-19 04:51:46.493393 | 2026-04-19 04:51:46.493651 | TASK [upload-logs : Upload logs to log server] 2026-04-19 04:51:47.083579 | localhost | Output suppressed because no_log was given 2026-04-19 04:51:47.087258 | 2026-04-19 04:51:47.087459 | LOOP [upload-logs : Compress console log and json output] 2026-04-19 04:51:47.146709 | localhost | skipping: Conditional result was False 2026-04-19 04:51:47.153240 | localhost | skipping: Conditional result was False 2026-04-19 04:51:47.169499 | 2026-04-19 04:51:47.169757 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-19 04:51:47.217957 | localhost | skipping: Conditional result was False 2026-04-19 04:51:47.218973 | 2026-04-19 04:51:47.222258 | localhost | skipping: Conditional result was False 2026-04-19 04:51:47.234597 | 2026-04-19 04:51:47.234821 | LOOP [upload-logs : Upload console log and json output]