2025-11-10 19:24:31.606780 | Job console starting 2025-11-10 19:24:31.626357 | Updating git repos 2025-11-10 19:24:31.642069 | Cloning repos into workspace 2025-11-10 19:24:31.668100 | Restoring repo states 2025-11-10 19:24:31.673353 | Merging changes 2025-11-10 19:24:32.083421 | Checking out repos 2025-11-10 19:24:32.132129 | Preparing playbooks 2025-11-10 19:24:32.671790 | Running Ansible setup 2025-11-10 19:24:38.339432 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-10 19:24:39.080717 | 2025-11-10 19:24:39.080920 | PLAY [Base pre] 2025-11-10 19:24:39.097756 | 2025-11-10 19:24:39.097890 | TASK [Setup log path fact] 2025-11-10 19:24:39.128147 | noble | ok 2025-11-10 19:24:39.145199 | 2025-11-10 19:24:39.145336 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 19:24:39.185549 | noble | ok 2025-11-10 19:24:39.198026 | 2025-11-10 19:24:39.198132 | TASK [emit-job-header : Print job information] 2025-11-10 19:24:39.240420 | # Job Information 2025-11-10 19:24:39.240610 | Ansible Version: 2.16.14 2025-11-10 19:24:39.240653 | Job: openstack-ironic-images-build-osism-ipa-stable 2025-11-10 19:24:39.240694 | Pipeline: check 2025-11-10 19:24:39.240722 | Executor: 521e9411259a 2025-11-10 19:24:39.240787 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/160 2025-11-10 19:24:39.240816 | Event ID: 8ae06290-be69-11f0-9615-640c422daa11 2025-11-10 19:24:39.247800 | 2025-11-10 19:24:39.247913 | LOOP [emit-job-header : Print node information] 2025-11-10 19:24:39.373984 | noble | ok: 2025-11-10 19:24:39.375013 | noble | # Node Information 2025-11-10 19:24:39.375219 | noble | Inventory Hostname: noble 2025-11-10 19:24:39.375306 | noble | Hostname: ubuntu 2025-11-10 19:24:39.375373 | noble | Username: zuul 2025-11-10 19:24:39.375435 | noble | Distro: Ubuntu 24.04 2025-11-10 19:24:39.375504 | noble | Provider: regiocloud-a 2025-11-10 19:24:39.375564 | noble | Region: 2025-11-10 19:24:39.375623 | noble | Label: ubuntu-noble-uefi 2025-11-10 19:24:39.375681 | noble | Product Name: OpenStack Nova 2025-11-10 19:24:39.375772 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef4:cb6b 2025-11-10 19:24:39.395636 | 2025-11-10 19:24:39.395771 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-10 19:24:39.865417 | noble -> localhost | changed 2025-11-10 19:24:39.880911 | 2025-11-10 19:24:39.881081 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-10 19:24:40.922096 | noble -> localhost | changed 2025-11-10 19:24:40.948475 | 2025-11-10 19:24:40.948635 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-10 19:24:41.206390 | noble -> localhost | ok 2025-11-10 19:24:41.218955 | 2025-11-10 19:24:41.219113 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-10 19:24:41.251644 | noble | ok 2025-11-10 19:24:41.268681 | noble | included: /var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-10 19:24:41.276688 | 2025-11-10 19:24:41.276808 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-10 19:24:42.737664 | noble -> localhost | Generating public/private rsa key pair. 2025-11-10 19:24:42.738084 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/work/c93c93c7b2ca4bbea31e98dabeeecc3b_id_rsa 2025-11-10 19:24:42.738169 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/work/c93c93c7b2ca4bbea31e98dabeeecc3b_id_rsa.pub 2025-11-10 19:24:42.738232 | noble -> localhost | The key fingerprint is: 2025-11-10 19:24:42.738295 | noble -> localhost | SHA256:gxF6r+eNSmavNhJOA+oQxzSRDeTjYOalf1f6cFnN+R0 zuul-build-sshkey 2025-11-10 19:24:42.738348 | noble -> localhost | The key's randomart image is: 2025-11-10 19:24:42.738398 | noble -> localhost | +---[RSA 3072]----+ 2025-11-10 19:24:42.738446 | noble -> localhost | | .+= . | 2025-11-10 19:24:42.738513 | noble -> localhost | | .+ .. . | 2025-11-10 19:24:42.738563 | noble -> localhost | |.=oo. o | 2025-11-10 19:24:42.738608 | noble -> localhost | |=o*. . + o . | 2025-11-10 19:24:42.738654 | noble -> localhost | | *.. . S. . + E | 2025-11-10 19:24:42.738712 | noble -> localhost | |o . + .o.o . o| 2025-11-10 19:24:42.738781 | noble -> localhost | |o + +=+.o ..| 2025-11-10 19:24:42.738829 | noble -> localhost | | . +++++o | 2025-11-10 19:24:42.738909 | noble -> localhost | | oo++.. | 2025-11-10 19:24:42.738957 | noble -> localhost | +----[SHA256]-----+ 2025-11-10 19:24:42.739073 | noble -> localhost | ok: Runtime: 0:00:00.988289 2025-11-10 19:24:42.752818 | 2025-11-10 19:24:42.752972 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-10 19:24:42.793479 | noble | ok 2025-11-10 19:24:42.807435 | noble | included: /var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-10 19:24:42.817128 | 2025-11-10 19:24:42.817224 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-10 19:24:42.840510 | noble | skipping: Conditional result was False 2025-11-10 19:24:42.849945 | 2025-11-10 19:24:42.850056 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-10 19:24:43.473463 | noble | changed 2025-11-10 19:24:43.482504 | 2025-11-10 19:24:43.482632 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-10 19:24:43.794275 | noble | ok 2025-11-10 19:24:43.804888 | 2025-11-10 19:24:43.805018 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-10 19:24:44.583656 | noble | changed 2025-11-10 19:24:44.592099 | 2025-11-10 19:24:44.592249 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-10 19:24:45.350305 | noble | changed 2025-11-10 19:24:45.360488 | 2025-11-10 19:24:45.360611 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-10 19:24:45.384904 | noble | skipping: Conditional result was False 2025-11-10 19:24:45.396021 | 2025-11-10 19:24:45.396170 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-10 19:24:45.887181 | noble -> localhost | changed 2025-11-10 19:24:45.902336 | 2025-11-10 19:24:45.902461 | TASK [add-build-sshkey : Add back temp key] 2025-11-10 19:24:46.232107 | noble -> localhost | Identity added: /var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/work/c93c93c7b2ca4bbea31e98dabeeecc3b_id_rsa (zuul-build-sshkey) 2025-11-10 19:24:46.232664 | noble -> localhost | ok: Runtime: 0:00:00.015941 2025-11-10 19:24:46.247835 | 2025-11-10 19:24:46.247974 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-10 19:24:46.692306 | noble | ok 2025-11-10 19:24:46.700964 | 2025-11-10 19:24:46.701097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-10 19:24:46.735435 | noble | skipping: Conditional result was False 2025-11-10 19:24:46.797300 | 2025-11-10 19:24:46.797412 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-10 19:24:47.240954 | noble | ok 2025-11-10 19:24:47.257711 | 2025-11-10 19:24:47.257882 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-10 19:24:47.290368 | noble | ok 2025-11-10 19:24:47.298618 | 2025-11-10 19:24:47.298730 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-10 19:24:47.579899 | noble -> localhost | ok 2025-11-10 19:24:47.598245 | 2025-11-10 19:24:47.598397 | TASK [validate-host : Collect information about the host] 2025-11-10 19:24:48.917180 | noble | ok 2025-11-10 19:24:48.930790 | 2025-11-10 19:24:48.930935 | TASK [validate-host : Sanitize hostname] 2025-11-10 19:24:48.996112 | noble | ok 2025-11-10 19:24:49.004269 | 2025-11-10 19:24:49.004392 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-10 19:24:49.574722 | noble -> localhost | changed 2025-11-10 19:24:49.588459 | 2025-11-10 19:24:49.588617 | TASK [validate-host : Collect information about zuul worker] 2025-11-10 19:24:50.049833 | noble | ok 2025-11-10 19:24:50.055776 | 2025-11-10 19:24:50.055900 | TASK [validate-host : Write out all zuul information for each host] 2025-11-10 19:24:50.607306 | noble -> localhost | changed 2025-11-10 19:24:50.629110 | 2025-11-10 19:24:50.629251 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-10 19:24:50.941244 | noble | ok 2025-11-10 19:24:50.950666 | 2025-11-10 19:24:50.950813 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-10 19:24:51.808567 | noble | changed: 2025-11-10 19:24:51.808944 | noble | cd+++++++++ src/ 2025-11-10 19:24:51.809868 | noble | cd+++++++++ src/github.com/ 2025-11-10 19:24:51.809937 | noble | cd+++++++++ src/github.com/osism/ 2025-11-10 19:24:51.809978 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-11-10 19:24:51.810015 | noble | localhost | ok: "/var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/work/logs" 2025-11-10 19:30:29.066960 | noble -> localhost | changed: "/var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/work/artifacts" 2025-11-10 19:30:29.336601 | noble -> localhost | changed: "/var/lib/zuul/builds/c93c93c7b2ca4bbea31e98dabeeecc3b/work/docs" 2025-11-10 19:30:29.350184 | 2025-11-10 19:30:29.350322 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-10 19:30:30.615303 | noble | changed: 2025-11-10 19:30:30.615697 | noble | .d..t...... ./ 2025-11-10 19:30:30.615811 | noble | >f+++++++++ osism-ipa-stable.log 2025-11-10 19:30:30.615903 | noble | changed: All items complete 2025-11-10 19:30:30.615960 | 2025-11-10 19:30:31.238017 | noble | changed: .d..t...... ./ 2025-11-10 19:30:31.886220 | noble | changed: .d..t...... ./ 2025-11-10 19:30:31.905266 | 2025-11-10 19:30:31.905389 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-10 19:30:32.430986 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007397 2025-11-10 19:30:32.711318 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009243 2025-11-10 19:30:32.733014 | 2025-11-10 19:30:32.733137 | PLAY RECAP 2025-11-10 19:30:32.733211 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-11-10 19:30:32.733249 | 2025-11-10 19:30:32.857009 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-11-10 19:30:32.857954 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-10 19:30:33.590108 | 2025-11-10 19:30:33.590261 | PLAY [Base post] 2025-11-10 19:30:33.604450 | 2025-11-10 19:30:33.604580 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-10 19:30:34.230305 | noble | changed 2025-11-10 19:30:34.239900 | 2025-11-10 19:30:34.240025 | PLAY RECAP 2025-11-10 19:30:34.240104 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-10 19:30:34.240180 | 2025-11-10 19:30:34.348235 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-10 19:30:34.350401 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-10 19:30:35.133843 | 2025-11-10 19:30:35.134011 | PLAY [Base post-logs] 2025-11-10 19:30:35.144641 | 2025-11-10 19:30:35.144844 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-10 19:30:35.610023 | localhost | changed 2025-11-10 19:30:35.626439 | 2025-11-10 19:30:35.626612 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-10 19:30:35.653066 | localhost | ok 2025-11-10 19:30:35.657695 | 2025-11-10 19:30:35.657948 | TASK [Set zuul-log-path fact] 2025-11-10 19:30:35.684558 | localhost | ok 2025-11-10 19:30:35.696373 | 2025-11-10 19:30:35.696500 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 19:30:35.723600 | localhost | ok 2025-11-10 19:30:35.729958 | 2025-11-10 19:30:35.730112 | TASK [upload-logs : Create log directories] 2025-11-10 19:30:36.244621 | localhost | changed 2025-11-10 19:30:36.250335 | 2025-11-10 19:30:36.250651 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-10 19:30:36.744918 | localhost -> localhost | ok: Runtime: 0:00:00.006226 2025-11-10 19:30:36.755063 | 2025-11-10 19:30:36.755253 | TASK [upload-logs : Upload logs to log server] 2025-11-10 19:30:37.302678 | localhost | Output suppressed because no_log was given 2025-11-10 19:30:37.305275 | 2025-11-10 19:30:37.305402 | LOOP [upload-logs : Compress console log and json output] 2025-11-10 19:30:37.388126 | localhost | skipping: Conditional result was False 2025-11-10 19:30:37.394619 | localhost | skipping: Conditional result was False 2025-11-10 19:30:37.405596 | 2025-11-10 19:30:37.405873 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-10 19:30:37.463103 | localhost | skipping: Conditional result was False 2025-11-10 19:30:37.463738 | 2025-11-10 19:30:37.467472 | localhost | skipping: Conditional result was False 2025-11-10 19:30:37.479151 | 2025-11-10 19:30:37.479367 | LOOP [upload-logs : Upload console log and json output]