2025-11-10 19:24:31.523075 | Job console starting 2025-11-10 19:24:31.540122 | Updating git repos 2025-11-10 19:24:31.565830 | Cloning repos into workspace 2025-11-10 19:24:31.605380 | Restoring repo states 2025-11-10 19:24:31.611557 | Merging changes 2025-11-10 19:24:32.061252 | Checking out repos 2025-11-10 19:24:32.096077 | Preparing playbooks 2025-11-10 19:24:32.724246 | Running Ansible setup 2025-11-10 19:24:38.436332 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-10 19:24:39.168520 | 2025-11-10 19:24:39.168676 | PLAY [Base pre] 2025-11-10 19:24:39.185525 | 2025-11-10 19:24:39.185649 | TASK [Setup log path fact] 2025-11-10 19:24:39.215510 | noble | ok 2025-11-10 19:24:39.233404 | 2025-11-10 19:24:39.233683 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 19:24:39.263447 | noble | ok 2025-11-10 19:24:39.275069 | 2025-11-10 19:24:39.275217 | TASK [emit-job-header : Print job information] 2025-11-10 19:24:39.315372 | # Job Information 2025-11-10 19:24:39.315551 | Ansible Version: 2.16.14 2025-11-10 19:24:39.315590 | Job: openstack-ironic-images-build-osism-ipa 2025-11-10 19:24:39.315627 | Pipeline: check 2025-11-10 19:24:39.315654 | Executor: 521e9411259a 2025-11-10 19:24:39.315678 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/160 2025-11-10 19:24:39.315703 | Event ID: 8ae06290-be69-11f0-9615-640c422daa11 2025-11-10 19:24:39.323187 | 2025-11-10 19:24:39.323298 | LOOP [emit-job-header : Print node information] 2025-11-10 19:24:39.446449 | noble | ok: 2025-11-10 19:24:39.446706 | noble | # Node Information 2025-11-10 19:24:39.446781 | noble | Inventory Hostname: noble 2025-11-10 19:24:39.446809 | noble | Hostname: ubuntu 2025-11-10 19:24:39.446862 | noble | Username: zuul 2025-11-10 19:24:39.446887 | noble | Distro: Ubuntu 24.04 2025-11-10 19:24:39.446911 | noble | Provider: regiocloud-a 2025-11-10 19:24:39.446932 | noble | Region: 2025-11-10 19:24:39.446954 | noble | Label: ubuntu-noble-uefi 2025-11-10 19:24:39.446973 | noble | Product Name: OpenStack Nova 2025-11-10 19:24:39.446993 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3c:7c42 2025-11-10 19:24:39.470427 | 2025-11-10 19:24:39.470556 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-10 19:24:39.927483 | noble -> localhost | changed 2025-11-10 19:24:39.942515 | 2025-11-10 19:24:39.942911 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-10 19:24:40.949673 | noble -> localhost | changed 2025-11-10 19:24:40.964194 | 2025-11-10 19:24:40.964326 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-10 19:24:41.240773 | noble -> localhost | ok 2025-11-10 19:24:41.249774 | 2025-11-10 19:24:41.249910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-10 19:24:41.280899 | noble | ok 2025-11-10 19:24:41.298687 | noble | included: /var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/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.306782 | 2025-11-10 19:24:41.306917 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-10 19:24:42.353878 | noble -> localhost | Generating public/private rsa key pair. 2025-11-10 19:24:42.354195 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/work/bc85623d0d1a43b2ba9442c9e618a0ff_id_rsa 2025-11-10 19:24:42.354249 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/work/bc85623d0d1a43b2ba9442c9e618a0ff_id_rsa.pub 2025-11-10 19:24:42.354287 | noble -> localhost | The key fingerprint is: 2025-11-10 19:24:42.354324 | noble -> localhost | SHA256:ZOMtspggepdpfDMZN817/sI1pOqk0IKjqPeJJmTI6H0 zuul-build-sshkey 2025-11-10 19:24:42.354357 | noble -> localhost | The key's randomart image is: 2025-11-10 19:24:42.354388 | noble -> localhost | +---[RSA 3072]----+ 2025-11-10 19:24:42.354418 | noble -> localhost | | | 2025-11-10 19:24:42.354460 | noble -> localhost | | | 2025-11-10 19:24:42.354492 | noble -> localhost | | + | 2025-11-10 19:24:42.354521 | noble -> localhost | | + = . | 2025-11-10 19:24:42.354550 | noble -> localhost | |= . o S + o | 2025-11-10 19:24:42.354587 | noble -> localhost | |+= o * B o .. o | 2025-11-10 19:24:42.354617 | noble -> localhost | |= o @ O . oo.. . | 2025-11-10 19:24:42.354645 | noble -> localhost | |.+o* E = o.oo | 2025-11-10 19:24:42.354676 | noble -> localhost | |++o.+ ... .o. | 2025-11-10 19:24:42.354705 | noble -> localhost | +----[SHA256]-----+ 2025-11-10 19:24:42.354808 | noble -> localhost | ok: Runtime: 0:00:00.573360 2025-11-10 19:24:42.365313 | 2025-11-10 19:24:42.365451 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-10 19:24:42.397600 | noble | ok 2025-11-10 19:24:42.411194 | noble | included: /var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-10 19:24:42.421625 | 2025-11-10 19:24:42.421722 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-10 19:24:42.435214 | noble | skipping: Conditional result was False 2025-11-10 19:24:42.442941 | 2025-11-10 19:24:42.443041 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-10 19:24:43.039132 | noble | changed 2025-11-10 19:24:43.045540 | 2025-11-10 19:24:43.045647 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-10 19:24:43.359890 | noble | ok 2025-11-10 19:24:43.371344 | 2025-11-10 19:24:43.371471 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-10 19:24:44.153274 | noble | changed 2025-11-10 19:24:44.161543 | 2025-11-10 19:24:44.161667 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-10 19:24:44.931457 | noble | changed 2025-11-10 19:24:44.940714 | 2025-11-10 19:24:44.940888 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-10 19:24:44.964859 | noble | skipping: Conditional result was False 2025-11-10 19:24:44.978250 | 2025-11-10 19:24:44.978383 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-10 19:24:45.447487 | noble -> localhost | changed 2025-11-10 19:24:45.464893 | 2025-11-10 19:24:45.465018 | TASK [add-build-sshkey : Add back temp key] 2025-11-10 19:24:45.781728 | noble -> localhost | Identity added: /var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/work/bc85623d0d1a43b2ba9442c9e618a0ff_id_rsa (zuul-build-sshkey) 2025-11-10 19:24:45.782014 | noble -> localhost | ok: Runtime: 0:00:00.012130 2025-11-10 19:24:45.789289 | 2025-11-10 19:24:45.789404 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-10 19:24:46.240835 | noble | ok 2025-11-10 19:24:46.250239 | 2025-11-10 19:24:46.250357 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-10 19:24:46.284357 | noble | skipping: Conditional result was False 2025-11-10 19:24:46.344029 | 2025-11-10 19:24:46.344162 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-10 19:24:46.778563 | noble | ok 2025-11-10 19:24:46.792584 | 2025-11-10 19:24:46.792715 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-10 19:24:46.834003 | noble | ok 2025-11-10 19:24:46.842486 | 2025-11-10 19:24:46.842605 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-10 19:24:47.119295 | noble -> localhost | ok 2025-11-10 19:24:47.134193 | 2025-11-10 19:24:47.134346 | TASK [validate-host : Collect information about the host] 2025-11-10 19:24:48.549955 | noble | ok 2025-11-10 19:24:48.566761 | 2025-11-10 19:24:48.566893 | TASK [validate-host : Sanitize hostname] 2025-11-10 19:24:48.632226 | noble | ok 2025-11-10 19:24:48.640943 | 2025-11-10 19:24:48.641090 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-10 19:24:49.202155 | noble -> localhost | changed 2025-11-10 19:24:49.208917 | 2025-11-10 19:24:49.209033 | TASK [validate-host : Collect information about zuul worker] 2025-11-10 19:24:49.652301 | noble | ok 2025-11-10 19:24:49.658084 | 2025-11-10 19:24:49.658203 | TASK [validate-host : Write out all zuul information for each host] 2025-11-10 19:24:50.226288 | noble -> localhost | changed 2025-11-10 19:24:50.245288 | 2025-11-10 19:24:50.245415 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-10 19:24:50.585227 | noble | ok 2025-11-10 19:24:50.595204 | 2025-11-10 19:24:50.595319 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-10 19:24:51.493868 | noble | changed: 2025-11-10 19:24:51.494557 | noble | cd+++++++++ src/ 2025-11-10 19:24:51.494636 | noble | cd+++++++++ src/github.com/ 2025-11-10 19:24:51.494683 | noble | cd+++++++++ src/github.com/osism/ 2025-11-10 19:24:51.494722 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-11-10 19:24:51.494912 | noble | localhost | ok: "/var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/work/logs" 2025-11-10 19:30:23.114103 | noble -> localhost | changed: "/var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/work/artifacts" 2025-11-10 19:30:23.394929 | noble -> localhost | changed: "/var/lib/zuul/builds/bc85623d0d1a43b2ba9442c9e618a0ff/work/docs" 2025-11-10 19:30:23.421903 | 2025-11-10 19:30:23.422075 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-10 19:30:24.654450 | noble | changed: 2025-11-10 19:30:24.654824 | noble | .d..t...... ./ 2025-11-10 19:30:24.654921 | noble | >f+++++++++ osism-ipa.log 2025-11-10 19:30:24.655007 | noble | changed: All items complete 2025-11-10 19:30:24.655060 | 2025-11-10 19:30:25.286655 | noble | changed: .d..t...... ./ 2025-11-10 19:30:25.943401 | noble | changed: .d..t...... ./ 2025-11-10 19:30:25.969190 | 2025-11-10 19:30:25.969325 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-10 19:30:26.496973 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.024282 2025-11-10 19:30:26.774659 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.008491 2025-11-10 19:30:26.797314 | 2025-11-10 19:30:26.797438 | PLAY RECAP 2025-11-10 19:30:26.797508 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-11-10 19:30:26.797543 | 2025-11-10 19:30:26.938664 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-11-10 19:30:26.939691 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-10 19:30:27.663743 | 2025-11-10 19:30:27.663901 | PLAY [Base post] 2025-11-10 19:30:27.678228 | 2025-11-10 19:30:27.678355 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-10 19:30:28.236120 | noble | changed 2025-11-10 19:30:28.248775 | 2025-11-10 19:30:28.248913 | PLAY RECAP 2025-11-10 19:30:28.249002 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-10 19:30:28.249091 | 2025-11-10 19:30:28.361641 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-10 19:30:28.362549 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-10 19:30:29.144734 | 2025-11-10 19:30:29.144908 | PLAY [Base post-logs] 2025-11-10 19:30:29.155814 | 2025-11-10 19:30:29.155958 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-10 19:30:29.587147 | localhost | changed 2025-11-10 19:30:29.597273 | 2025-11-10 19:30:29.597430 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-10 19:30:29.624213 | localhost | ok 2025-11-10 19:30:29.628636 | 2025-11-10 19:30:29.628774 | TASK [Set zuul-log-path fact] 2025-11-10 19:30:29.654343 | localhost | ok 2025-11-10 19:30:29.662376 | 2025-11-10 19:30:29.662490 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-10 19:30:29.700306 | localhost | ok 2025-11-10 19:30:29.707478 | 2025-11-10 19:30:29.707659 | TASK [upload-logs : Create log directories] 2025-11-10 19:30:30.226338 | localhost | changed 2025-11-10 19:30:30.231800 | 2025-11-10 19:30:30.231962 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-10 19:30:30.731050 | localhost -> localhost | ok: Runtime: 0:00:00.006670 2025-11-10 19:30:30.738000 | 2025-11-10 19:30:30.738149 | TASK [upload-logs : Upload logs to log server] 2025-11-10 19:30:31.293487 | localhost | Output suppressed because no_log was given 2025-11-10 19:30:31.296996 | 2025-11-10 19:30:31.297173 | LOOP [upload-logs : Compress console log and json output] 2025-11-10 19:30:31.345464 | localhost | skipping: Conditional result was False 2025-11-10 19:30:31.351237 | localhost | skipping: Conditional result was False 2025-11-10 19:30:31.358158 | 2025-11-10 19:30:31.358332 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-10 19:30:31.404110 | localhost | skipping: Conditional result was False 2025-11-10 19:30:31.404698 | 2025-11-10 19:30:31.408062 | localhost | skipping: Conditional result was False 2025-11-10 19:30:31.421477 | 2025-11-10 19:30:31.421770 | LOOP [upload-logs : Upload console log and json output]