2025-08-14 08:15:55.936554 | Job console starting 2025-08-14 08:15:55.951372 | Updating git repos 2025-08-14 08:15:55.976949 | Cloning repos into workspace 2025-08-14 08:15:56.022290 | Restoring repo states 2025-08-14 08:15:56.028248 | Merging changes 2025-08-14 08:15:56.475340 | Checking out repos 2025-08-14 08:15:56.526134 | Preparing playbooks 2025-08-14 08:15:57.152923 | Running Ansible setup 2025-08-14 08:16:03.015629 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-14 08:16:03.763942 | 2025-08-14 08:16:03.764107 | PLAY [Base pre] 2025-08-14 08:16:03.780737 | 2025-08-14 08:16:03.780879 | TASK [Setup log path fact] 2025-08-14 08:16:03.810599 | noble | ok 2025-08-14 08:16:03.827766 | 2025-08-14 08:16:03.827938 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-14 08:16:03.871428 | noble | ok 2025-08-14 08:16:03.885093 | 2025-08-14 08:16:03.885198 | TASK [emit-job-header : Print job information] 2025-08-14 08:16:03.942462 | # Job Information 2025-08-14 08:16:03.942715 | Ansible Version: 2.16.14 2025-08-14 08:16:03.942774 | Job: openstack-ironic-images-build-osism-ipa-stable 2025-08-14 08:16:03.942830 | Pipeline: check 2025-08-14 08:16:03.942912 | Executor: 521e9411259a 2025-08-14 08:16:03.942950 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/138 2025-08-14 08:16:03.942988 | Event ID: 7b313900-78e6-11f0-9e41-39b1d0ef9516 2025-08-14 08:16:03.952957 | 2025-08-14 08:16:03.953100 | LOOP [emit-job-header : Print node information] 2025-08-14 08:16:04.077129 | noble | ok: 2025-08-14 08:16:04.077419 | noble | # Node Information 2025-08-14 08:16:04.077480 | noble | Inventory Hostname: noble 2025-08-14 08:16:04.077526 | noble | Hostname: ubuntu 2025-08-14 08:16:04.077565 | noble | Username: zuul 2025-08-14 08:16:04.077602 | noble | Distro: Ubuntu 24.04 2025-08-14 08:16:04.077644 | noble | Provider: regiocloud-a 2025-08-14 08:16:04.077680 | noble | Region: 2025-08-14 08:16:04.078462 | noble | Label: ubuntu-noble-uefi 2025-08-14 08:16:04.078545 | noble | Product Name: OpenStack Nova 2025-08-14 08:16:04.078586 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed8:7493 2025-08-14 08:16:04.105004 | 2025-08-14 08:16:04.105148 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-14 08:16:04.585786 | noble -> localhost | changed 2025-08-14 08:16:04.603059 | 2025-08-14 08:16:04.603228 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-14 08:16:05.671657 | noble -> localhost | changed 2025-08-14 08:16:05.685745 | 2025-08-14 08:16:05.685871 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-14 08:16:05.952307 | noble -> localhost | ok 2025-08-14 08:16:05.960151 | 2025-08-14 08:16:05.960274 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-14 08:16:05.989251 | noble | ok 2025-08-14 08:16:06.009869 | noble | included: /var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-14 08:16:06.017751 | 2025-08-14 08:16:06.017847 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-14 08:16:07.207846 | noble -> localhost | Generating public/private rsa key pair. 2025-08-14 08:16:07.208135 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/work/6b97221f1de64f24abc1d62c793a917c_id_rsa 2025-08-14 08:16:07.208178 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/work/6b97221f1de64f24abc1d62c793a917c_id_rsa.pub 2025-08-14 08:16:07.208206 | noble -> localhost | The key fingerprint is: 2025-08-14 08:16:07.208231 | noble -> localhost | SHA256:YosFOvzgRH+eZ+VunuzU/cQGOLTTg4d8tb+lPYPEvWA zuul-build-sshkey 2025-08-14 08:16:07.208253 | noble -> localhost | The key's randomart image is: 2025-08-14 08:16:07.208274 | noble -> localhost | +---[RSA 3072]----+ 2025-08-14 08:16:07.208295 | noble -> localhost | | | 2025-08-14 08:16:07.208328 | noble -> localhost | | | 2025-08-14 08:16:07.208349 | noble -> localhost | | . . . .| 2025-08-14 08:16:07.208369 | noble -> localhost | | o o . o * ..| 2025-08-14 08:16:07.208389 | noble -> localhost | | * . = S . X B. | 2025-08-14 08:16:07.208412 | noble -> localhost | | o + * + o . E =.| 2025-08-14 08:16:07.208433 | noble -> localhost | | . o + o o + + B| 2025-08-14 08:16:07.208453 | noble -> localhost | | o +.. . Xo| 2025-08-14 08:16:07.208474 | noble -> localhost | | +* ..=| 2025-08-14 08:16:07.208494 | noble -> localhost | +----[SHA256]-----+ 2025-08-14 08:16:07.208553 | noble -> localhost | ok: Runtime: 0:00:00.715891 2025-08-14 08:16:07.216380 | 2025-08-14 08:16:07.216491 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-14 08:16:07.245366 | noble | ok 2025-08-14 08:16:07.255509 | noble | included: /var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-14 08:16:07.267987 | 2025-08-14 08:16:07.268097 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-14 08:16:07.291688 | noble | skipping: Conditional result was False 2025-08-14 08:16:07.300107 | 2025-08-14 08:16:07.300213 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-14 08:16:07.926738 | noble | changed 2025-08-14 08:16:07.936421 | 2025-08-14 08:16:07.936551 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-14 08:16:08.241589 | noble | ok 2025-08-14 08:16:08.248603 | 2025-08-14 08:16:08.248724 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-14 08:16:09.049514 | noble | changed 2025-08-14 08:16:09.055768 | 2025-08-14 08:16:09.055877 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-14 08:16:09.824164 | noble | changed 2025-08-14 08:16:09.830588 | 2025-08-14 08:16:09.830700 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-14 08:16:09.854175 | noble | skipping: Conditional result was False 2025-08-14 08:16:09.860808 | 2025-08-14 08:16:09.860925 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-14 08:16:10.309554 | noble -> localhost | changed 2025-08-14 08:16:10.323570 | 2025-08-14 08:16:10.323678 | TASK [add-build-sshkey : Add back temp key] 2025-08-14 08:16:10.661789 | noble -> localhost | Identity added: /var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/work/6b97221f1de64f24abc1d62c793a917c_id_rsa (zuul-build-sshkey) 2025-08-14 08:16:10.662137 | noble -> localhost | ok: Runtime: 0:00:00.017687 2025-08-14 08:16:10.669585 | 2025-08-14 08:16:10.669698 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-14 08:16:11.123256 | noble | ok 2025-08-14 08:16:11.132603 | 2025-08-14 08:16:11.132739 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-14 08:16:11.159137 | noble | skipping: Conditional result was False 2025-08-14 08:16:11.216299 | 2025-08-14 08:16:11.216428 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-14 08:16:11.671877 | noble | ok 2025-08-14 08:16:11.687649 | 2025-08-14 08:16:11.687783 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-14 08:16:11.735042 | noble | ok 2025-08-14 08:16:11.750030 | 2025-08-14 08:16:11.750274 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-14 08:16:12.051919 | noble -> localhost | ok 2025-08-14 08:16:12.061887 | 2025-08-14 08:16:12.062039 | TASK [validate-host : Collect information about the host] 2025-08-14 08:16:13.476192 | noble | ok 2025-08-14 08:16:13.492377 | 2025-08-14 08:16:13.492510 | TASK [validate-host : Sanitize hostname] 2025-08-14 08:16:13.555441 | noble | ok 2025-08-14 08:16:13.563591 | 2025-08-14 08:16:13.563729 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-14 08:16:14.094248 | noble -> localhost | changed 2025-08-14 08:16:14.100772 | 2025-08-14 08:16:14.100879 | TASK [validate-host : Collect information about zuul worker] 2025-08-14 08:16:14.567729 | noble | ok 2025-08-14 08:16:14.575128 | 2025-08-14 08:16:14.575258 | TASK [validate-host : Write out all zuul information for each host] 2025-08-14 08:16:15.096044 | noble -> localhost | changed 2025-08-14 08:16:15.113890 | 2025-08-14 08:16:15.114066 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-14 08:16:15.448000 | noble | ok 2025-08-14 08:16:15.458786 | 2025-08-14 08:16:15.459042 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-14 08:16:16.427166 | noble | changed: 2025-08-14 08:16:16.427411 | noble | cd+++++++++ src/ 2025-08-14 08:16:16.427455 | noble | cd+++++++++ src/github.com/ 2025-08-14 08:16:16.427486 | noble | cd+++++++++ src/github.com/osism/ 2025-08-14 08:16:16.427515 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-08-14 08:16:16.427540 | noble | localhost | ok: "/var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/work/logs" 2025-08-14 08:21:59.057027 | noble -> localhost | changed: "/var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/work/artifacts" 2025-08-14 08:21:59.312303 | noble -> localhost | changed: "/var/lib/zuul/builds/6b97221f1de64f24abc1d62c793a917c/work/docs" 2025-08-14 08:21:59.335910 | 2025-08-14 08:21:59.336076 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-08-14 08:22:00.751715 | noble | changed: 2025-08-14 08:22:00.752006 | noble | .d..t...... ./ 2025-08-14 08:22:00.752059 | noble | >f+++++++++ osism-ipa-stable.log 2025-08-14 08:22:00.752147 | noble | changed: All items complete 2025-08-14 08:22:00.752189 | 2025-08-14 08:22:01.426539 | noble | changed: .d..t...... ./ 2025-08-14 08:22:02.123222 | noble | changed: .d..t...... ./ 2025-08-14 08:22:02.158067 | 2025-08-14 08:22:02.158264 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-08-14 08:22:02.672474 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.010059 2025-08-14 08:22:02.950713 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.008317 2025-08-14 08:22:02.970450 | 2025-08-14 08:22:02.970615 | PLAY RECAP 2025-08-14 08:22:02.970695 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-08-14 08:22:02.970732 | 2025-08-14 08:22:03.123400 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-08-14 08:22:03.125663 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-14 08:22:03.864609 | 2025-08-14 08:22:03.864839 | PLAY [Base post] 2025-08-14 08:22:03.879477 | 2025-08-14 08:22:03.879610 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-14 08:22:04.577873 | noble | changed 2025-08-14 08:22:04.588364 | 2025-08-14 08:22:04.588497 | PLAY RECAP 2025-08-14 08:22:04.588573 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-14 08:22:04.588650 | 2025-08-14 08:22:04.703996 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-14 08:22:04.706363 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-14 08:22:05.477435 | 2025-08-14 08:22:05.477612 | PLAY [Base post-logs] 2025-08-14 08:22:05.488662 | 2025-08-14 08:22:05.488806 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-14 08:22:05.962610 | localhost | changed 2025-08-14 08:22:05.975680 | 2025-08-14 08:22:05.975838 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-14 08:22:06.013596 | localhost | ok 2025-08-14 08:22:06.019609 | 2025-08-14 08:22:06.019764 | TASK [Set zuul-log-path fact] 2025-08-14 08:22:06.039639 | localhost | ok 2025-08-14 08:22:06.054502 | 2025-08-14 08:22:06.054651 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-14 08:22:06.091998 | localhost | ok 2025-08-14 08:22:06.097841 | 2025-08-14 08:22:06.097999 | TASK [upload-logs : Create log directories] 2025-08-14 08:22:06.581524 | localhost | changed 2025-08-14 08:22:06.584428 | 2025-08-14 08:22:06.584536 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-14 08:22:07.087000 | localhost -> localhost | ok: Runtime: 0:00:00.007662 2025-08-14 08:22:07.095664 | 2025-08-14 08:22:07.095897 | TASK [upload-logs : Upload logs to log server] 2025-08-14 08:22:07.633167 | localhost | Output suppressed because no_log was given 2025-08-14 08:22:07.637536 | 2025-08-14 08:22:07.637784 | LOOP [upload-logs : Compress console log and json output] 2025-08-14 08:22:07.684911 | localhost | skipping: Conditional result was False 2025-08-14 08:22:07.689826 | localhost | skipping: Conditional result was False 2025-08-14 08:22:07.702367 | 2025-08-14 08:22:07.702600 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-14 08:22:07.761263 | localhost | skipping: Conditional result was False 2025-08-14 08:22:07.761887 | 2025-08-14 08:22:07.765440 | localhost | skipping: Conditional result was False 2025-08-14 08:22:07.777744 | 2025-08-14 08:22:07.777940 | LOOP [upload-logs : Upload console log and json output]