2026-04-19 05:30:40.715521 | Job console starting 2026-04-19 05:30:40.748640 | Updating git repos 2026-04-19 05:30:40.777782 | Cloning repos into workspace 2026-04-19 05:30:40.827367 | Restoring repo states 2026-04-19 05:30:40.832540 | Merging changes 2026-04-19 05:30:41.398498 | Checking out repos 2026-04-19 05:30:41.425978 | Preparing playbooks 2026-04-19 05:30:41.984561 | Running Ansible setup 2026-04-19 05:30:47.981188 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-04-19 05:30:48.731571 | 2026-04-19 05:30:48.731745 | PLAY [Base pre] 2026-04-19 05:30:48.748908 | 2026-04-19 05:30:48.749050 | TASK [Setup log path fact] 2026-04-19 05:30:48.779648 | noble | ok 2026-04-19 05:30:48.797390 | 2026-04-19 05:30:48.797546 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 05:30:48.842190 | noble | ok 2026-04-19 05:30:48.857388 | 2026-04-19 05:30:48.857528 | TASK [emit-job-header : Print job information] 2026-04-19 05:30:48.899872 | # Job Information 2026-04-19 05:30:48.900120 | Ansible Version: 2.16.14 2026-04-19 05:30:48.900176 | Job: openstack-ironic-images-build-osism-esp 2026-04-19 05:30:48.900225 | Pipeline: check 2026-04-19 05:30:48.900260 | Executor: 521e9411259a 2026-04-19 05:30:48.900294 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/200 2026-04-19 05:30:48.900328 | Event ID: 7b304580-3bb0-11f1-8f1d-489bfd1ca7d3 2026-04-19 05:30:48.907760 | 2026-04-19 05:30:48.907912 | LOOP [emit-job-header : Print node information] 2026-04-19 05:30:49.033679 | noble | ok: 2026-04-19 05:30:49.034075 | noble | # Node Information 2026-04-19 05:30:49.034142 | noble | Inventory Hostname: noble 2026-04-19 05:30:49.034188 | noble | Hostname: ubuntu 2026-04-19 05:30:49.034228 | noble | Username: zuul 2026-04-19 05:30:49.034264 | noble | Distro: Ubuntu 24.04 2026-04-19 05:30:49.034308 | noble | Provider: regiocloud-a 2026-04-19 05:30:49.034342 | noble | Region: 2026-04-19 05:30:49.034377 | noble | Label: ubuntu-noble-uefi 2026-04-19 05:30:49.034410 | noble | Product Name: OpenStack Nova 2026-04-19 05:30:49.034446 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe00:1a5e 2026-04-19 05:30:49.062237 | 2026-04-19 05:30:49.062397 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-19 05:30:49.592880 | noble -> localhost | changed 2026-04-19 05:30:49.602069 | 2026-04-19 05:30:49.602225 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-19 05:30:50.652847 | noble -> localhost | changed 2026-04-19 05:30:50.677210 | 2026-04-19 05:30:50.677368 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-19 05:30:50.987065 | noble -> localhost | ok 2026-04-19 05:30:50.999312 | 2026-04-19 05:30:50.999485 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-19 05:30:51.035290 | noble | ok 2026-04-19 05:30:51.060713 | noble | included: /var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-19 05:30:51.070388 | 2026-04-19 05:30:51.070525 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-19 05:30:53.412267 | noble -> localhost | Generating public/private rsa key pair. 2026-04-19 05:30:53.412710 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/work/6dca73b4db534c57a6a30f11e0622c33_id_rsa 2026-04-19 05:30:53.412804 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/work/6dca73b4db534c57a6a30f11e0622c33_id_rsa.pub 2026-04-19 05:30:53.412894 | noble -> localhost | The key fingerprint is: 2026-04-19 05:30:53.412962 | noble -> localhost | SHA256:43zw9f4qSY8Vd/x+dRPxYphAd/rjwQiNMGVL+oZ3q6c zuul-build-sshkey 2026-04-19 05:30:53.413021 | noble -> localhost | The key's randomart image is: 2026-04-19 05:30:53.413075 | noble -> localhost | +---[RSA 3072]----+ 2026-04-19 05:30:53.413127 | noble -> localhost | | oo= . . | 2026-04-19 05:30:53.413198 | noble -> localhost | | *.= o . | 2026-04-19 05:30:53.413253 | noble -> localhost | | . +.oo .o| 2026-04-19 05:30:53.413305 | noble -> localhost | | o .o++.=| 2026-04-19 05:30:53.413355 | noble -> localhost | | S + +.=++| 2026-04-19 05:30:53.413416 | noble -> localhost | | o * o.+.++| 2026-04-19 05:30:53.413482 | noble -> localhost | | o o..=o.+| 2026-04-19 05:30:53.413539 | noble -> localhost | | . .=.. o| 2026-04-19 05:30:53.413594 | noble -> localhost | | Eo .ooo| 2026-04-19 05:30:53.413646 | noble -> localhost | +----[SHA256]-----+ 2026-04-19 05:30:53.413779 | noble -> localhost | ok: Runtime: 0:00:01.785813 2026-04-19 05:30:53.427344 | 2026-04-19 05:30:53.427501 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-19 05:30:53.455277 | noble | ok 2026-04-19 05:30:53.471891 | noble | included: /var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-19 05:30:53.483116 | 2026-04-19 05:30:53.483229 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-19 05:30:53.509079 | noble | skipping: Conditional result was False 2026-04-19 05:30:53.520967 | 2026-04-19 05:30:53.521099 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-19 05:30:54.225139 | noble | changed 2026-04-19 05:30:54.241376 | 2026-04-19 05:30:54.241511 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-19 05:30:54.573870 | noble | ok 2026-04-19 05:30:54.585656 | 2026-04-19 05:30:54.585800 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-19 05:30:55.536992 | noble | changed 2026-04-19 05:30:55.552002 | 2026-04-19 05:30:55.552513 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-19 05:30:56.364020 | noble | changed 2026-04-19 05:30:56.373792 | 2026-04-19 05:30:56.373966 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-19 05:30:56.422329 | noble | skipping: Conditional result was False 2026-04-19 05:30:56.434381 | 2026-04-19 05:30:56.434565 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-19 05:30:56.994024 | noble -> localhost | changed 2026-04-19 05:30:57.008901 | 2026-04-19 05:30:57.009042 | TASK [add-build-sshkey : Add back temp key] 2026-04-19 05:30:57.360597 | noble -> localhost | Identity added: /var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/work/6dca73b4db534c57a6a30f11e0622c33_id_rsa (zuul-build-sshkey) 2026-04-19 05:30:57.360875 | noble -> localhost | ok: Runtime: 0:00:00.011375 2026-04-19 05:30:57.371468 | 2026-04-19 05:30:57.371595 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-19 05:30:57.836273 | noble | ok 2026-04-19 05:30:57.842377 | 2026-04-19 05:30:57.842490 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-19 05:30:57.866780 | noble | skipping: Conditional result was False 2026-04-19 05:30:57.970572 | 2026-04-19 05:30:57.970724 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-04-19 05:30:58.497301 | noble | ok 2026-04-19 05:30:58.512996 | 2026-04-19 05:30:58.513140 | TASK [validate-host : Define zuul_info_dir fact] 2026-04-19 05:30:58.543670 | noble | ok 2026-04-19 05:30:58.551484 | 2026-04-19 05:30:58.551602 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-04-19 05:30:58.895111 | noble -> localhost | ok 2026-04-19 05:30:58.903954 | 2026-04-19 05:30:58.904087 | TASK [validate-host : Collect information about the host] 2026-04-19 05:31:00.412549 | noble | ok 2026-04-19 05:31:00.426894 | 2026-04-19 05:31:00.427033 | TASK [validate-host : Sanitize hostname] 2026-04-19 05:31:00.486289 | noble | ok 2026-04-19 05:31:00.491933 | 2026-04-19 05:31:00.492051 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-04-19 05:31:01.119554 | noble -> localhost | changed 2026-04-19 05:31:01.128159 | 2026-04-19 05:31:01.128284 | TASK [validate-host : Collect information about zuul worker] 2026-04-19 05:31:01.672737 | noble | ok 2026-04-19 05:31:01.678417 | 2026-04-19 05:31:01.678566 | TASK [validate-host : Write out all zuul information for each host] 2026-04-19 05:31:02.339634 | noble -> localhost | changed 2026-04-19 05:31:02.351467 | 2026-04-19 05:31:02.351605 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-04-19 05:31:02.680251 | noble | ok 2026-04-19 05:31:02.687002 | 2026-04-19 05:31:02.687134 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-04-19 05:31:03.679870 | noble | changed: 2026-04-19 05:31:03.680096 | noble | cd+++++++++ src/ 2026-04-19 05:31:03.680133 | noble | cd+++++++++ src/github.com/ 2026-04-19 05:31:03.680158 | noble | cd+++++++++ src/github.com/osism/ 2026-04-19 05:31:03.680180 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-04-19 05:31:03.680200 | noble | localhost | ok: "/var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/work/logs" 2026-04-19 05:34:19.927079 | noble -> localhost | changed: "/var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/work/artifacts" 2026-04-19 05:34:20.201225 | noble -> localhost | changed: "/var/lib/zuul/builds/6dca73b4db534c57a6a30f11e0622c33/work/docs" 2026-04-19 05:34:20.227808 | 2026-04-19 05:34:20.228022 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-19 05:34:21.517973 | noble | changed: 2026-04-19 05:34:21.518313 | noble | .d..t...... ./ 2026-04-19 05:34:21.518374 | noble | >f+++++++++ osism-esp.log 2026-04-19 05:34:21.518445 | noble | changed: All items complete 2026-04-19 05:34:21.518489 | 2026-04-19 05:34:22.167217 | noble | changed: .d..t...... ./ 2026-04-19 05:34:22.834358 | noble | changed: .d..t...... ./ 2026-04-19 05:34:22.865920 | 2026-04-19 05:34:22.866216 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-19 05:34:23.393104 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.010467 2026-04-19 05:34:23.685596 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009973 2026-04-19 05:34:23.708650 | 2026-04-19 05:34:23.708784 | PLAY RECAP 2026-04-19 05:34:23.708876 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-19 05:34:23.708912 | 2026-04-19 05:34:23.831688 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-04-19 05:34:23.832721 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 05:34:24.563883 | 2026-04-19 05:34:24.564043 | PLAY [Base post] 2026-04-19 05:34:24.578156 | 2026-04-19 05:34:24.578289 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-19 05:34:25.225693 | noble | changed 2026-04-19 05:34:25.236579 | 2026-04-19 05:34:25.236731 | PLAY RECAP 2026-04-19 05:34:25.236821 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 05:34:25.236959 | 2026-04-19 05:34:25.356941 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 05:34:25.359471 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-19 05:34:26.222123 | 2026-04-19 05:34:26.222295 | PLAY [Base post-logs] 2026-04-19 05:34:26.232847 | 2026-04-19 05:34:26.233040 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-19 05:34:26.708276 | localhost | changed 2026-04-19 05:34:26.726018 | 2026-04-19 05:34:26.726196 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-19 05:34:26.753565 | localhost | ok 2026-04-19 05:34:26.759419 | 2026-04-19 05:34:26.759573 | TASK [Set zuul-log-path fact] 2026-04-19 05:34:26.776452 | localhost | ok 2026-04-19 05:34:26.787054 | 2026-04-19 05:34:26.787176 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 05:34:26.824448 | localhost | ok 2026-04-19 05:34:26.831829 | 2026-04-19 05:34:26.832036 | TASK [upload-logs : Create log directories] 2026-04-19 05:34:27.340453 | localhost | changed 2026-04-19 05:34:27.343428 | 2026-04-19 05:34:27.343537 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-19 05:34:27.874603 | localhost -> localhost | ok: Runtime: 0:00:00.007423 2026-04-19 05:34:27.878812 | 2026-04-19 05:34:27.878988 | TASK [upload-logs : Upload logs to log server] 2026-04-19 05:34:28.493883 | localhost | Output suppressed because no_log was given 2026-04-19 05:34:28.495782 | 2026-04-19 05:34:28.495951 | LOOP [upload-logs : Compress console log and json output] 2026-04-19 05:34:28.546538 | localhost | skipping: Conditional result was False 2026-04-19 05:34:28.552457 | localhost | skipping: Conditional result was False 2026-04-19 05:34:28.563764 | 2026-04-19 05:34:28.563937 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-19 05:34:28.610811 | localhost | skipping: Conditional result was False 2026-04-19 05:34:28.611510 | 2026-04-19 05:34:28.615002 | localhost | skipping: Conditional result was False 2026-04-19 05:34:28.627515 | 2026-04-19 05:34:28.627681 | LOOP [upload-logs : Upload console log and json output]