2025-11-11 12:05:52.549391 | Job console starting 2025-11-11 12:05:52.561634 | Updating git repos 2025-11-11 12:05:52.588310 | Cloning repos into workspace 2025-11-11 12:05:52.644993 | Restoring repo states 2025-11-11 12:05:52.653513 | Merging changes 2025-11-11 12:05:53.071125 | Checking out repos 2025-11-11 12:05:53.103656 | Preparing playbooks 2025-11-11 12:05:53.808874 | Running Ansible setup 2025-11-11 12:05:59.781195 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-11 12:06:00.531855 | 2025-11-11 12:06:00.532055 | PLAY [Base pre] 2025-11-11 12:06:00.549239 | 2025-11-11 12:06:00.549381 | TASK [Setup log path fact] 2025-11-11 12:06:00.579556 | noble | ok 2025-11-11 12:06:00.597598 | 2025-11-11 12:06:00.597764 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-11 12:06:00.637396 | noble | ok 2025-11-11 12:06:00.649150 | 2025-11-11 12:06:00.649267 | TASK [emit-job-header : Print job information] 2025-11-11 12:06:00.689568 | # Job Information 2025-11-11 12:06:00.689763 | Ansible Version: 2.16.14 2025-11-11 12:06:00.689797 | Job: openstack-ironic-images-build-osism-node 2025-11-11 12:06:00.689829 | Pipeline: check 2025-11-11 12:06:00.689851 | Executor: 521e9411259a 2025-11-11 12:06:00.689873 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/161 2025-11-11 12:06:00.689896 | Event ID: 624b0000-bef6-11f0-8cb6-ca8599fc9c3e 2025-11-11 12:06:00.698552 | 2025-11-11 12:06:00.698722 | LOOP [emit-job-header : Print node information] 2025-11-11 12:06:00.831076 | noble | ok: 2025-11-11 12:06:00.831371 | noble | # Node Information 2025-11-11 12:06:00.831428 | noble | Inventory Hostname: noble 2025-11-11 12:06:00.831470 | noble | Hostname: ubuntu 2025-11-11 12:06:00.831506 | noble | Username: zuul 2025-11-11 12:06:00.831539 | noble | Distro: Ubuntu 24.04 2025-11-11 12:06:00.831577 | noble | Provider: regiocloud-a 2025-11-11 12:06:00.831613 | noble | Region: 2025-11-11 12:06:00.831647 | noble | Label: ubuntu-noble-uefi 2025-11-11 12:06:00.831680 | noble | Product Name: OpenStack Nova 2025-11-11 12:06:00.831711 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fec8:269c 2025-11-11 12:06:00.858313 | 2025-11-11 12:06:00.858480 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-11 12:06:01.383104 | noble -> localhost | changed 2025-11-11 12:06:01.398353 | 2025-11-11 12:06:01.398525 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-11 12:06:02.492338 | noble -> localhost | changed 2025-11-11 12:06:02.506376 | 2025-11-11 12:06:02.506504 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-11 12:06:02.794668 | noble -> localhost | ok 2025-11-11 12:06:02.802762 | 2025-11-11 12:06:02.803044 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-11 12:06:02.837478 | noble | ok 2025-11-11 12:06:02.860242 | noble | included: /var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-11 12:06:02.871184 | 2025-11-11 12:06:02.871311 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-11 12:06:04.144404 | noble -> localhost | Generating public/private rsa key pair. 2025-11-11 12:06:04.144665 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/work/75b2cc938c864d0d8f0fab2d669ba9ed_id_rsa 2025-11-11 12:06:04.144704 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/work/75b2cc938c864d0d8f0fab2d669ba9ed_id_rsa.pub 2025-11-11 12:06:04.144732 | noble -> localhost | The key fingerprint is: 2025-11-11 12:06:04.144760 | noble -> localhost | SHA256:mN6c45STfUCMRKXJoVgj29+CpjjpCTDdGtfoHTKbG/k zuul-build-sshkey 2025-11-11 12:06:04.144786 | noble -> localhost | The key's randomart image is: 2025-11-11 12:06:04.144809 | noble -> localhost | +---[RSA 3072]----+ 2025-11-11 12:06:04.144832 | noble -> localhost | | . o.+.. | 2025-11-11 12:06:04.144870 | noble -> localhost | | * = * | 2025-11-11 12:06:04.144894 | noble -> localhost | | o o = o | 2025-11-11 12:06:04.144915 | noble -> localhost | | . . o = o | 2025-11-11 12:06:04.144956 | noble -> localhost | |o o * B S o | 2025-11-11 12:06:04.144988 | noble -> localhost | |.. B @ + * . | 2025-11-11 12:06:04.145010 | noble -> localhost | |. = B o X . . | 2025-11-11 12:06:04.145032 | noble -> localhost | | o o + o o . | 2025-11-11 12:06:04.145054 | noble -> localhost | | o . E . | 2025-11-11 12:06:04.145076 | noble -> localhost | +----[SHA256]-----+ 2025-11-11 12:06:04.145134 | noble -> localhost | ok: Runtime: 0:00:00.762598 2025-11-11 12:06:04.153454 | 2025-11-11 12:06:04.153573 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-11 12:06:04.190453 | noble | ok 2025-11-11 12:06:04.204261 | noble | included: /var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-11 12:06:04.213620 | 2025-11-11 12:06:04.213721 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-11 12:06:04.237913 | noble | skipping: Conditional result was False 2025-11-11 12:06:04.247096 | 2025-11-11 12:06:04.247207 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-11 12:06:04.890201 | noble | changed 2025-11-11 12:06:04.896738 | 2025-11-11 12:06:04.896859 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-11 12:06:05.220346 | noble | ok 2025-11-11 12:06:05.230879 | 2025-11-11 12:06:05.231081 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-11 12:06:06.011003 | noble | changed 2025-11-11 12:06:06.020156 | 2025-11-11 12:06:06.020299 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-11 12:06:06.788014 | noble | changed 2025-11-11 12:06:06.798345 | 2025-11-11 12:06:06.798520 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-11 12:06:06.824294 | noble | skipping: Conditional result was False 2025-11-11 12:06:06.842473 | 2025-11-11 12:06:06.842675 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-11 12:06:07.312541 | noble -> localhost | changed 2025-11-11 12:06:07.327037 | 2025-11-11 12:06:07.327177 | TASK [add-build-sshkey : Add back temp key] 2025-11-11 12:06:07.656080 | noble -> localhost | Identity added: /var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/work/75b2cc938c864d0d8f0fab2d669ba9ed_id_rsa (zuul-build-sshkey) 2025-11-11 12:06:07.656334 | noble -> localhost | ok: Runtime: 0:00:00.020565 2025-11-11 12:06:07.663846 | 2025-11-11 12:06:07.663995 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-11 12:06:08.111224 | noble | ok 2025-11-11 12:06:08.117211 | 2025-11-11 12:06:08.117321 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-11 12:06:08.143371 | noble | skipping: Conditional result was False 2025-11-11 12:06:08.208620 | 2025-11-11 12:06:08.208767 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-11 12:06:08.637799 | noble | ok 2025-11-11 12:06:08.657332 | 2025-11-11 12:06:08.657468 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-11 12:06:08.700574 | noble | ok 2025-11-11 12:06:08.712693 | 2025-11-11 12:06:08.712835 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-11 12:06:09.001648 | noble -> localhost | ok 2025-11-11 12:06:09.010019 | 2025-11-11 12:06:09.010137 | TASK [validate-host : Collect information about the host] 2025-11-11 12:06:10.462727 | noble | ok 2025-11-11 12:06:10.481402 | 2025-11-11 12:06:10.481529 | TASK [validate-host : Sanitize hostname] 2025-11-11 12:06:10.554984 | noble | ok 2025-11-11 12:06:10.564090 | 2025-11-11 12:06:10.564252 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-11 12:06:11.135451 | noble -> localhost | changed 2025-11-11 12:06:11.142258 | 2025-11-11 12:06:11.142379 | TASK [validate-host : Collect information about zuul worker] 2025-11-11 12:06:11.630100 | noble | ok 2025-11-11 12:06:11.635671 | 2025-11-11 12:06:11.635800 | TASK [validate-host : Write out all zuul information for each host] 2025-11-11 12:06:12.207992 | noble -> localhost | changed 2025-11-11 12:06:12.219956 | 2025-11-11 12:06:12.220092 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-11 12:06:12.572398 | noble | ok 2025-11-11 12:06:12.581529 | 2025-11-11 12:06:12.581661 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-11 12:06:13.518192 | noble | changed: 2025-11-11 12:06:13.518526 | noble | cd+++++++++ src/ 2025-11-11 12:06:13.518593 | noble | cd+++++++++ src/github.com/ 2025-11-11 12:06:13.518634 | noble | cd+++++++++ src/github.com/osism/ 2025-11-11 12:06:13.518670 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-11-11 12:06:13.518703 | noble | localhost | ok: "/var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/work/logs" 2025-11-11 12:17:23.788245 | noble -> localhost | changed: "/var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/work/artifacts" 2025-11-11 12:17:24.056828 | noble -> localhost | changed: "/var/lib/zuul/builds/75b2cc938c864d0d8f0fab2d669ba9ed/work/docs" 2025-11-11 12:17:24.082028 | 2025-11-11 12:17:24.082232 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-11 12:17:25.379700 | noble | changed: 2025-11-11 12:17:25.380034 | noble | .d..t...... ./ 2025-11-11 12:17:25.380094 | noble | >f+++++++++ osism-node.log 2025-11-11 12:17:25.380172 | noble | changed: All items complete 2025-11-11 12:17:25.380216 | 2025-11-11 12:17:26.037086 | noble | changed: .d..t...... ./ 2025-11-11 12:17:26.730912 | noble | changed: .d..t...... ./ 2025-11-11 12:17:26.745939 | 2025-11-11 12:17:26.746085 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-11 12:17:27.283853 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.012655 2025-11-11 12:17:27.578945 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.010346 2025-11-11 12:17:27.592212 | 2025-11-11 12:17:27.592308 | PLAY RECAP 2025-11-11 12:17:27.592361 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-11-11 12:17:27.592386 | 2025-11-11 12:17:27.720018 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-11-11 12:17:27.722206 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-11 12:17:28.461499 | 2025-11-11 12:17:28.461654 | PLAY [Base post] 2025-11-11 12:17:28.475839 | 2025-11-11 12:17:28.475970 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-11 12:17:29.090558 | noble | changed 2025-11-11 12:17:29.102660 | 2025-11-11 12:17:29.102831 | PLAY RECAP 2025-11-11 12:17:29.102961 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-11 12:17:29.103082 | 2025-11-11 12:17:29.228375 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-11 12:17:29.230206 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-11 12:17:30.008294 | 2025-11-11 12:17:30.008478 | PLAY [Base post-logs] 2025-11-11 12:17:30.019642 | 2025-11-11 12:17:30.019785 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-11 12:17:30.492344 | localhost | changed 2025-11-11 12:17:30.502526 | 2025-11-11 12:17:30.502675 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-11 12:17:30.538409 | localhost | ok 2025-11-11 12:17:30.542109 | 2025-11-11 12:17:30.542225 | TASK [Set zuul-log-path fact] 2025-11-11 12:17:30.567647 | localhost | ok 2025-11-11 12:17:30.576874 | 2025-11-11 12:17:30.577019 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-11 12:17:30.612072 | localhost | ok 2025-11-11 12:17:30.615534 | 2025-11-11 12:17:30.615639 | TASK [upload-logs : Create log directories] 2025-11-11 12:17:31.154351 | localhost | changed 2025-11-11 12:17:31.159943 | 2025-11-11 12:17:31.160124 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-11 12:17:31.661254 | localhost -> localhost | ok: Runtime: 0:00:00.007379 2025-11-11 12:17:31.665388 | 2025-11-11 12:17:31.665504 | TASK [upload-logs : Upload logs to log server] 2025-11-11 12:17:32.227694 | localhost | Output suppressed because no_log was given 2025-11-11 12:17:32.229695 | 2025-11-11 12:17:32.229800 | LOOP [upload-logs : Compress console log and json output] 2025-11-11 12:17:32.284699 | localhost | skipping: Conditional result was False 2025-11-11 12:17:32.289502 | localhost | skipping: Conditional result was False 2025-11-11 12:17:32.303368 | 2025-11-11 12:17:32.303594 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-11 12:17:32.350221 | localhost | skipping: Conditional result was False 2025-11-11 12:17:32.350798 | 2025-11-11 12:17:32.354417 | localhost | skipping: Conditional result was False 2025-11-11 12:17:32.368109 | 2025-11-11 12:17:32.368338 | LOOP [upload-logs : Upload console log and json output]