2025-10-13 14:13:41.166363 | Job console starting 2025-10-13 14:13:41.188737 | Updating git repos 2025-10-13 14:13:41.217354 | Cloning repos into workspace 2025-10-13 14:13:41.273069 | Restoring repo states 2025-10-13 14:13:41.282017 | Merging changes 2025-10-13 14:13:41.796689 | Checking out repos 2025-10-13 14:13:41.830138 | Preparing playbooks 2025-10-13 14:13:42.459949 | Running Ansible setup 2025-10-13 14:13:48.380474 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-13 14:13:49.124358 | 2025-10-13 14:13:49.124548 | PLAY [Base pre] 2025-10-13 14:13:49.142102 | 2025-10-13 14:13:49.142249 | TASK [Setup log path fact] 2025-10-13 14:13:49.164294 | noble | ok 2025-10-13 14:13:49.182109 | 2025-10-13 14:13:49.182244 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 14:13:49.223093 | noble | ok 2025-10-13 14:13:49.235305 | 2025-10-13 14:13:49.235425 | TASK [emit-job-header : Print job information] 2025-10-13 14:13:49.283810 | # Job Information 2025-10-13 14:13:49.284092 | Ansible Version: 2.16.14 2025-10-13 14:13:49.284156 | Job: openstack-ironic-images-build-osism-ipa 2025-10-13 14:13:49.284212 | Pipeline: check 2025-10-13 14:13:49.284254 | Executor: 521e9411259a 2025-10-13 14:13:49.284291 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/138 2025-10-13 14:13:49.284331 | Event ID: 64c5d1d0-a83e-11f0-8a7b-85ac310903d9 2025-10-13 14:13:49.295620 | 2025-10-13 14:13:49.295752 | LOOP [emit-job-header : Print node information] 2025-10-13 14:13:49.413080 | noble | ok: 2025-10-13 14:13:49.413331 | noble | # Node Information 2025-10-13 14:13:49.413374 | noble | Inventory Hostname: noble 2025-10-13 14:13:49.413406 | noble | Hostname: ubuntu 2025-10-13 14:13:49.413434 | noble | Username: zuul 2025-10-13 14:13:49.413461 | noble | Distro: Ubuntu 24.04 2025-10-13 14:13:49.413491 | noble | Provider: regiocloud-a 2025-10-13 14:13:49.413517 | noble | Region: 2025-10-13 14:13:49.413543 | noble | Label: ubuntu-noble-uefi 2025-10-13 14:13:49.413585 | noble | Product Name: OpenStack Nova 2025-10-13 14:13:49.413613 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feae:87ec 2025-10-13 14:13:49.434700 | 2025-10-13 14:13:49.434899 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 14:13:49.885806 | noble -> localhost | changed 2025-10-13 14:13:49.894323 | 2025-10-13 14:13:49.894448 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 14:13:50.920368 | noble -> localhost | changed 2025-10-13 14:13:50.934786 | 2025-10-13 14:13:50.934922 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 14:13:51.221600 | noble -> localhost | ok 2025-10-13 14:13:51.229200 | 2025-10-13 14:13:51.229323 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 14:13:51.258559 | noble | ok 2025-10-13 14:13:51.274801 | noble | included: /var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 14:13:51.282778 | 2025-10-13 14:13:51.282906 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 14:13:52.367228 | noble -> localhost | Generating public/private rsa key pair. 2025-10-13 14:13:52.367479 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/work/0f0fd783e7bf428bb993eec68803fffa_id_rsa 2025-10-13 14:13:52.367518 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/work/0f0fd783e7bf428bb993eec68803fffa_id_rsa.pub 2025-10-13 14:13:52.367545 | noble -> localhost | The key fingerprint is: 2025-10-13 14:13:52.367587 | noble -> localhost | SHA256:2eSHqANpHLYmgOOQDX2qYlChcyNL3JTkQMYTEe2CK0g zuul-build-sshkey 2025-10-13 14:13:52.367612 | noble -> localhost | The key's randomart image is: 2025-10-13 14:13:52.367633 | noble -> localhost | +---[RSA 3072]----+ 2025-10-13 14:13:52.367654 | noble -> localhost | |+OB+. | 2025-10-13 14:13:52.367691 | noble -> localhost | |+BBo. | 2025-10-13 14:13:52.367712 | noble -> localhost | |@+*=o . | 2025-10-13 14:13:52.367732 | noble -> localhost | |*E.= + * . | 2025-10-13 14:13:52.367752 | noble -> localhost | |=+o B S + . | 2025-10-13 14:13:52.367774 | noble -> localhost | |*. + . . . | 2025-10-13 14:13:52.367794 | noble -> localhost | |+ o | 2025-10-13 14:13:52.367814 | noble -> localhost | | . | 2025-10-13 14:13:52.367834 | noble -> localhost | | | 2025-10-13 14:13:52.367854 | noble -> localhost | +----[SHA256]-----+ 2025-10-13 14:13:52.367907 | noble -> localhost | ok: Runtime: 0:00:00.568853 2025-10-13 14:13:52.375784 | 2025-10-13 14:13:52.375894 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 14:13:52.416028 | noble | ok 2025-10-13 14:13:52.426623 | noble | included: /var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 14:13:52.436636 | 2025-10-13 14:13:52.436738 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 14:13:52.471400 | noble | skipping: Conditional result was False 2025-10-13 14:13:52.480300 | 2025-10-13 14:13:52.480406 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 14:13:53.132492 | noble | changed 2025-10-13 14:13:53.142878 | 2025-10-13 14:13:53.143006 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 14:13:53.477430 | noble | ok 2025-10-13 14:13:53.486987 | 2025-10-13 14:13:53.487143 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 14:13:54.282988 | noble | changed 2025-10-13 14:13:54.289417 | 2025-10-13 14:13:54.289525 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 14:13:55.116539 | noble | changed 2025-10-13 14:13:55.125790 | 2025-10-13 14:13:55.125922 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 14:13:55.150083 | noble | skipping: Conditional result was False 2025-10-13 14:13:55.159643 | 2025-10-13 14:13:55.159765 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 14:13:55.600345 | noble -> localhost | changed 2025-10-13 14:13:55.617828 | 2025-10-13 14:13:55.617967 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 14:13:55.968241 | noble -> localhost | Identity added: /var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/work/0f0fd783e7bf428bb993eec68803fffa_id_rsa (zuul-build-sshkey) 2025-10-13 14:13:55.968849 | noble -> localhost | ok: Runtime: 0:00:00.022150 2025-10-13 14:13:55.984118 | 2025-10-13 14:13:55.984259 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 14:13:56.452931 | noble | ok 2025-10-13 14:13:56.459060 | 2025-10-13 14:13:56.459165 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 14:13:56.493123 | noble | skipping: Conditional result was False 2025-10-13 14:13:56.540968 | 2025-10-13 14:13:56.541089 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 14:13:57.004361 | noble | ok 2025-10-13 14:13:57.018233 | 2025-10-13 14:13:57.018356 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 14:13:57.051735 | noble | ok 2025-10-13 14:13:57.060530 | 2025-10-13 14:13:57.060657 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 14:13:57.388218 | noble -> localhost | ok 2025-10-13 14:13:57.396064 | 2025-10-13 14:13:57.396175 | TASK [validate-host : Collect information about the host] 2025-10-13 14:13:58.887724 | noble | ok 2025-10-13 14:13:58.905921 | 2025-10-13 14:13:58.906057 | TASK [validate-host : Sanitize hostname] 2025-10-13 14:13:58.970790 | noble | ok 2025-10-13 14:13:58.977992 | 2025-10-13 14:13:58.978115 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 14:13:59.569717 | noble -> localhost | changed 2025-10-13 14:13:59.580402 | 2025-10-13 14:13:59.580559 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 14:14:00.083941 | noble | ok 2025-10-13 14:14:00.093717 | 2025-10-13 14:14:00.093870 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 14:14:00.613739 | noble -> localhost | changed 2025-10-13 14:14:00.624464 | 2025-10-13 14:14:00.624587 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-13 14:14:00.947816 | noble | ok 2025-10-13 14:14:00.954340 | 2025-10-13 14:14:00.954446 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-13 14:14:01.982458 | noble | changed: 2025-10-13 14:14:01.982805 | noble | cd+++++++++ src/ 2025-10-13 14:14:01.982907 | noble | cd+++++++++ src/github.com/ 2025-10-13 14:14:01.982951 | noble | cd+++++++++ src/github.com/osism/ 2025-10-13 14:14:01.982986 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-13 14:14:01.983018 | noble | localhost | ok: "/var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/work/logs" 2025-10-13 14:19:41.222670 | noble -> localhost | changed: "/var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/work/artifacts" 2025-10-13 14:19:41.479113 | noble -> localhost | changed: "/var/lib/zuul/builds/0f0fd783e7bf428bb993eec68803fffa/work/docs" 2025-10-13 14:19:41.500473 | 2025-10-13 14:19:41.500691 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-13 14:19:42.767501 | noble | changed: 2025-10-13 14:19:42.767875 | noble | .d..t...... ./ 2025-10-13 14:19:42.767942 | noble | >f+++++++++ osism-ipa.log 2025-10-13 14:19:42.768035 | noble | changed: All items complete 2025-10-13 14:19:42.768082 | 2025-10-13 14:19:43.428508 | noble | changed: .d..t...... ./ 2025-10-13 14:19:44.070732 | noble | changed: .d..t...... ./ 2025-10-13 14:19:44.101537 | 2025-10-13 14:19:44.101721 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-13 14:19:44.592748 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.029141 2025-10-13 14:19:44.877853 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.013449 2025-10-13 14:19:44.901096 | 2025-10-13 14:19:44.901300 | PLAY RECAP 2025-10-13 14:19:44.901369 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-13 14:19:44.901399 | 2025-10-13 14:19:45.019698 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-13 14:19:45.021870 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-13 14:19:45.715763 | 2025-10-13 14:19:45.715880 | PLAY [Base post] 2025-10-13 14:19:45.730126 | 2025-10-13 14:19:45.730255 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-13 14:19:46.286413 | noble | changed 2025-10-13 14:19:46.293196 | 2025-10-13 14:19:46.293283 | PLAY RECAP 2025-10-13 14:19:46.293341 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-13 14:19:46.293402 | 2025-10-13 14:19:46.375981 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-13 14:19:46.377885 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-13 14:19:47.173275 | 2025-10-13 14:19:47.173439 | PLAY [Base post-logs] 2025-10-13 14:19:47.183737 | 2025-10-13 14:19:47.183862 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-13 14:19:47.624618 | localhost | changed 2025-10-13 14:19:47.635066 | 2025-10-13 14:19:47.635219 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-13 14:19:47.671266 | localhost | ok 2025-10-13 14:19:47.675055 | 2025-10-13 14:19:47.675169 | TASK [Set zuul-log-path fact] 2025-10-13 14:19:47.692930 | localhost | ok 2025-10-13 14:19:47.707482 | 2025-10-13 14:19:47.707643 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 14:19:47.736386 | localhost | ok 2025-10-13 14:19:47.743117 | 2025-10-13 14:19:47.743289 | TASK [upload-logs : Create log directories] 2025-10-13 14:19:48.257930 | localhost | changed 2025-10-13 14:19:48.260822 | 2025-10-13 14:19:48.260930 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-13 14:19:48.750541 | localhost -> localhost | ok: Runtime: 0:00:00.006922 2025-10-13 14:19:48.754667 | 2025-10-13 14:19:48.754788 | TASK [upload-logs : Upload logs to log server] 2025-10-13 14:19:49.301897 | localhost | Output suppressed because no_log was given 2025-10-13 14:19:49.306134 | 2025-10-13 14:19:49.306315 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 14:19:49.362992 | localhost | skipping: Conditional result was False 2025-10-13 14:19:49.367858 | localhost | skipping: Conditional result was False 2025-10-13 14:19:49.380353 | 2025-10-13 14:19:49.380677 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 14:19:49.428934 | localhost | skipping: Conditional result was False 2025-10-13 14:19:49.429448 | 2025-10-13 14:19:49.432921 | localhost | skipping: Conditional result was False 2025-10-13 14:19:49.442049 | 2025-10-13 14:19:49.442236 | LOOP [upload-logs : Upload console log and json output]