2025-07-31 13:49:37.724798 | Job console starting 2025-07-31 13:49:37.734829 | Updating git repos 2025-07-31 13:49:37.760934 | Cloning repos into workspace 2025-07-31 13:49:37.792514 | Restoring repo states 2025-07-31 13:49:37.804926 | Merging changes 2025-07-31 13:49:38.265345 | Checking out repos 2025-07-31 13:49:38.297764 | Preparing playbooks 2025-07-31 13:49:38.943679 | Running Ansible setup 2025-07-31 13:49:44.919326 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-31 13:49:45.686441 | 2025-07-31 13:49:45.686600 | PLAY [Base pre] 2025-07-31 13:49:45.703456 | 2025-07-31 13:49:45.703590 | TASK [Setup log path fact] 2025-07-31 13:49:45.723101 | noble | ok 2025-07-31 13:49:45.740524 | 2025-07-31 13:49:45.740663 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 13:49:45.769689 | noble | ok 2025-07-31 13:49:45.781415 | 2025-07-31 13:49:45.781526 | TASK [emit-job-header : Print job information] 2025-07-31 13:49:45.821326 | # Job Information 2025-07-31 13:49:45.821501 | Ansible Version: 2.16.14 2025-07-31 13:49:45.821536 | Job: openstack-ironic-images-build-osism-ipa 2025-07-31 13:49:45.821568 | Pipeline: check 2025-07-31 13:49:45.821591 | Executor: 521e9411259a 2025-07-31 13:49:45.821611 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/119 2025-07-31 13:49:45.821633 | Event ID: c69462a0-6e14-11f0-9f78-0b780a29069d 2025-07-31 13:49:45.828450 | 2025-07-31 13:49:45.828566 | LOOP [emit-job-header : Print node information] 2025-07-31 13:49:45.942625 | noble | ok: 2025-07-31 13:49:45.942922 | noble | # Node Information 2025-07-31 13:49:45.942962 | noble | Inventory Hostname: noble 2025-07-31 13:49:45.942987 | noble | Hostname: ubuntu 2025-07-31 13:49:45.943009 | noble | Username: zuul 2025-07-31 13:49:45.943030 | noble | Distro: Ubuntu 24.04 2025-07-31 13:49:45.943052 | noble | Provider: regiocloud-a 2025-07-31 13:49:45.943074 | noble | Region: 2025-07-31 13:49:45.943096 | noble | Label: ubuntu-noble-uefi 2025-07-31 13:49:45.943115 | noble | Product Name: OpenStack Nova 2025-07-31 13:49:45.943134 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe8f:3338 2025-07-31 13:49:45.956945 | 2025-07-31 13:49:45.957073 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-31 13:49:46.426926 | noble -> localhost | changed 2025-07-31 13:49:46.443331 | 2025-07-31 13:49:46.443504 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-31 13:49:47.534608 | noble -> localhost | changed 2025-07-31 13:49:47.549066 | 2025-07-31 13:49:47.549184 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-31 13:49:47.815548 | noble -> localhost | ok 2025-07-31 13:49:47.822657 | 2025-07-31 13:49:47.822822 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-31 13:49:47.852752 | noble | ok 2025-07-31 13:49:47.869041 | noble | included: /var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-31 13:49:47.877055 | 2025-07-31 13:49:47.877161 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-31 13:49:48.716899 | noble -> localhost | Generating public/private rsa key pair. 2025-07-31 13:49:48.717135 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/work/727a03b99a2649e6b51fd676a9ab3323_id_rsa 2025-07-31 13:49:48.717174 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/work/727a03b99a2649e6b51fd676a9ab3323_id_rsa.pub 2025-07-31 13:49:48.717201 | noble -> localhost | The key fingerprint is: 2025-07-31 13:49:48.717230 | noble -> localhost | SHA256:OMqrxblj8Vtg2gIjYwHLJJSHjwuOO8qUdg+G6dkwOQM zuul-build-sshkey 2025-07-31 13:49:48.717253 | noble -> localhost | The key's randomart image is: 2025-07-31 13:49:48.717274 | noble -> localhost | +---[RSA 3072]----+ 2025-07-31 13:49:48.717294 | noble -> localhost | |+oo | 2025-07-31 13:49:48.717330 | noble -> localhost | |=+ . | 2025-07-31 13:49:48.717352 | noble -> localhost | |.o+ | 2025-07-31 13:49:48.717371 | noble -> localhost | |.... . | 2025-07-31 13:49:48.717391 | noble -> localhost | |E.+ = S | 2025-07-31 13:49:48.717414 | noble -> localhost | |+===.* o | 2025-07-31 13:49:48.717434 | noble -> localhost | | & +Oo. . | 2025-07-31 13:49:48.717455 | noble -> localhost | |B X.=+.. | 2025-07-31 13:49:48.717476 | noble -> localhost | |o=.+oo.. | 2025-07-31 13:49:48.717497 | noble -> localhost | +----[SHA256]-----+ 2025-07-31 13:49:48.717547 | noble -> localhost | ok: Runtime: 0:00:00.374639 2025-07-31 13:49:48.725109 | 2025-07-31 13:49:48.725222 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-31 13:49:48.765202 | noble | ok 2025-07-31 13:49:48.775172 | noble | included: /var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-31 13:49:48.784144 | 2025-07-31 13:49:48.784239 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-31 13:49:48.807452 | noble | skipping: Conditional result was False 2025-07-31 13:49:48.814910 | 2025-07-31 13:49:48.815014 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-31 13:49:49.453035 | noble | changed 2025-07-31 13:49:49.459979 | 2025-07-31 13:49:49.460087 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-31 13:49:49.778527 | noble | ok 2025-07-31 13:49:49.787763 | 2025-07-31 13:49:49.787935 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-31 13:49:50.598275 | noble | changed 2025-07-31 13:49:50.607356 | 2025-07-31 13:49:50.607494 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-31 13:49:51.427704 | noble | changed 2025-07-31 13:49:51.437516 | 2025-07-31 13:49:51.437675 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-31 13:49:51.464542 | noble | skipping: Conditional result was False 2025-07-31 13:49:51.479080 | 2025-07-31 13:49:51.479231 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-31 13:49:51.924915 | noble -> localhost | changed 2025-07-31 13:49:51.959538 | 2025-07-31 13:49:51.959754 | TASK [add-build-sshkey : Add back temp key] 2025-07-31 13:49:52.310264 | noble -> localhost | Identity added: /var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/work/727a03b99a2649e6b51fd676a9ab3323_id_rsa (zuul-build-sshkey) 2025-07-31 13:49:52.310517 | noble -> localhost | ok: Runtime: 0:00:00.014514 2025-07-31 13:49:52.317916 | 2025-07-31 13:49:52.318028 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-31 13:49:52.769828 | noble | ok 2025-07-31 13:49:52.778067 | 2025-07-31 13:49:52.778204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-31 13:49:52.813052 | noble | skipping: Conditional result was False 2025-07-31 13:49:52.870894 | 2025-07-31 13:49:52.871030 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-31 13:49:53.295812 | noble | ok 2025-07-31 13:49:53.312197 | 2025-07-31 13:49:53.312345 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-31 13:49:53.353093 | noble | ok 2025-07-31 13:49:53.361720 | 2025-07-31 13:49:53.361893 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-31 13:49:53.651116 | noble -> localhost | ok 2025-07-31 13:49:53.666881 | 2025-07-31 13:49:53.667057 | TASK [validate-host : Collect information about the host] 2025-07-31 13:49:55.136948 | noble | ok 2025-07-31 13:49:55.154206 | 2025-07-31 13:49:55.154338 | TASK [validate-host : Sanitize hostname] 2025-07-31 13:49:55.219937 | noble | ok 2025-07-31 13:49:55.228512 | 2025-07-31 13:49:55.228643 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-31 13:49:55.775169 | noble -> localhost | changed 2025-07-31 13:49:55.789953 | 2025-07-31 13:49:55.790110 | TASK [validate-host : Collect information about zuul worker] 2025-07-31 13:49:56.276317 | noble | ok 2025-07-31 13:49:56.284499 | 2025-07-31 13:49:56.284664 | TASK [validate-host : Write out all zuul information for each host] 2025-07-31 13:49:56.827922 | noble -> localhost | changed 2025-07-31 13:49:56.839087 | 2025-07-31 13:49:56.839204 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-31 13:49:57.188332 | noble | ok 2025-07-31 13:49:57.198502 | 2025-07-31 13:49:57.198633 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-31 13:49:58.157302 | noble | changed: 2025-07-31 13:49:58.157611 | noble | cd+++++++++ src/ 2025-07-31 13:49:58.157666 | noble | cd+++++++++ src/github.com/ 2025-07-31 13:49:58.157703 | noble | cd+++++++++ src/github.com/osism/ 2025-07-31 13:49:58.157734 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-07-31 13:49:58.157764 | noble | localhost | ok: "/var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/work/logs" 2025-07-31 13:55:23.180900 | noble -> localhost | changed: "/var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/work/artifacts" 2025-07-31 13:55:23.460518 | noble -> localhost | changed: "/var/lib/zuul/builds/727a03b99a2649e6b51fd676a9ab3323/work/docs" 2025-07-31 13:55:23.477572 | 2025-07-31 13:55:23.477708 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-07-31 13:55:24.754469 | noble | changed: 2025-07-31 13:55:24.754783 | noble | .d..t...... ./ 2025-07-31 13:55:24.754896 | noble | >f+++++++++ osism-ipa.log 2025-07-31 13:55:24.754984 | noble | changed: All items complete 2025-07-31 13:55:24.755034 | 2025-07-31 13:55:25.375072 | noble | changed: .d..t...... ./ 2025-07-31 13:55:26.013389 | noble | changed: .d..t...... ./ 2025-07-31 13:55:26.038039 | 2025-07-31 13:55:26.038185 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-07-31 13:55:26.548617 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.007536 2025-07-31 13:55:26.866708 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.008859 2025-07-31 13:55:26.897760 | 2025-07-31 13:55:26.897890 | PLAY RECAP 2025-07-31 13:55:26.897958 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-07-31 13:55:26.898137 | 2025-07-31 13:55:27.040281 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-07-31 13:55:27.042542 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 13:55:27.763127 | 2025-07-31 13:55:27.763286 | PLAY [Base post] 2025-07-31 13:55:27.777905 | 2025-07-31 13:55:27.778038 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-07-31 13:55:28.346290 | noble | changed 2025-07-31 13:55:28.353492 | 2025-07-31 13:55:28.353599 | PLAY RECAP 2025-07-31 13:55:28.353663 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-07-31 13:55:28.353723 | 2025-07-31 13:55:28.471836 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-07-31 13:55:28.472833 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-07-31 13:55:29.247676 | 2025-07-31 13:55:29.247839 | PLAY [Base post-logs] 2025-07-31 13:55:29.258231 | 2025-07-31 13:55:29.258363 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-07-31 13:55:29.722911 | localhost | changed 2025-07-31 13:55:29.740430 | 2025-07-31 13:55:29.740633 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-07-31 13:55:29.767104 | localhost | ok 2025-07-31 13:55:29.773351 | 2025-07-31 13:55:29.773572 | TASK [Set zuul-log-path fact] 2025-07-31 13:55:29.791641 | localhost | ok 2025-07-31 13:55:29.805005 | 2025-07-31 13:55:29.805149 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 13:55:29.832260 | localhost | ok 2025-07-31 13:55:29.839856 | 2025-07-31 13:55:29.840033 | TASK [upload-logs : Create log directories] 2025-07-31 13:55:30.339108 | localhost | changed 2025-07-31 13:55:30.343719 | 2025-07-31 13:55:30.343874 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-07-31 13:55:30.846446 | localhost -> localhost | ok: Runtime: 0:00:00.006905 2025-07-31 13:55:30.854808 | 2025-07-31 13:55:30.855035 | TASK [upload-logs : Upload logs to log server] 2025-07-31 13:55:31.432604 | localhost | Output suppressed because no_log was given 2025-07-31 13:55:31.436987 | 2025-07-31 13:55:31.437175 | LOOP [upload-logs : Compress console log and json output] 2025-07-31 13:55:31.493579 | localhost | skipping: Conditional result was False 2025-07-31 13:55:31.498882 | localhost | skipping: Conditional result was False 2025-07-31 13:55:31.510467 | 2025-07-31 13:55:31.510752 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-31 13:55:31.556920 | localhost | skipping: Conditional result was False 2025-07-31 13:55:31.557567 | 2025-07-31 13:55:31.560917 | localhost | skipping: Conditional result was False 2025-07-31 13:55:31.574729 | 2025-07-31 13:55:31.575058 | LOOP [upload-logs : Upload console log and json output]