2026-02-11 10:01:54.635976 | Job console starting 2026-02-11 10:01:54.652802 | Updating git repos 2026-02-11 10:01:54.683071 | Cloning repos into workspace 2026-02-11 10:01:54.721483 | Restoring repo states 2026-02-11 10:01:54.726415 | Merging changes 2026-02-11 10:01:55.249983 | Checking out repos 2026-02-11 10:01:55.294588 | Preparing playbooks 2026-02-11 10:01:56.103326 | Running Ansible setup 2026-02-11 10:02:02.514960 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-02-11 10:02:03.350442 | 2026-02-11 10:02:03.350627 | PLAY [Base pre] 2026-02-11 10:02:03.368188 | 2026-02-11 10:02:03.368340 | TASK [Setup log path fact] 2026-02-11 10:02:03.399341 | noble | ok 2026-02-11 10:02:03.416838 | 2026-02-11 10:02:03.417038 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-11 10:02:03.447482 | noble | ok 2026-02-11 10:02:03.468316 | 2026-02-11 10:02:03.468498 | TASK [emit-job-header : Print job information] 2026-02-11 10:02:03.553228 | # Job Information 2026-02-11 10:02:03.553444 | Ansible Version: 2.16.14 2026-02-11 10:02:03.553482 | Job: openstack-ironic-images-build-osism-node 2026-02-11 10:02:03.553517 | Pipeline: check 2026-02-11 10:02:03.553541 | Executor: 521e9411259a 2026-02-11 10:02:03.553562 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/169 2026-02-11 10:02:03.553584 | Event ID: 44846cf0-0730-11f1-8264-84ed2a206310 2026-02-11 10:02:03.564863 | 2026-02-11 10:02:03.565008 | LOOP [emit-job-header : Print node information] 2026-02-11 10:02:03.725817 | noble | ok: 2026-02-11 10:02:03.726073 | noble | # Node Information 2026-02-11 10:02:03.726112 | noble | Inventory Hostname: noble 2026-02-11 10:02:03.726139 | noble | Hostname: ubuntu 2026-02-11 10:02:03.726163 | noble | Username: zuul 2026-02-11 10:02:03.726186 | noble | Distro: Ubuntu 24.04 2026-02-11 10:02:03.726212 | noble | Provider: regiocloud-a 2026-02-11 10:02:03.726235 | noble | Region: 2026-02-11 10:02:03.726259 | noble | Label: ubuntu-noble-uefi 2026-02-11 10:02:03.726281 | noble | Product Name: OpenStack Nova 2026-02-11 10:02:03.726303 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4f:3035 2026-02-11 10:02:03.745297 | 2026-02-11 10:02:03.745484 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-11 10:02:04.682697 | noble -> localhost | changed 2026-02-11 10:02:04.692104 | 2026-02-11 10:02:04.692334 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-11 10:02:06.017634 | noble -> localhost | changed 2026-02-11 10:02:06.032758 | 2026-02-11 10:02:06.032947 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-11 10:02:06.407541 | noble -> localhost | ok 2026-02-11 10:02:06.418387 | 2026-02-11 10:02:06.418549 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-11 10:02:06.449478 | noble | ok 2026-02-11 10:02:06.468199 | noble | included: /var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-11 10:02:06.477076 | 2026-02-11 10:02:06.477207 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-11 10:02:08.998652 | noble -> localhost | Generating public/private rsa key pair. 2026-02-11 10:02:08.998861 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/work/d06ce5ee99cc47f396b5fcab649c1e5e_id_rsa 2026-02-11 10:02:08.998951 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/work/d06ce5ee99cc47f396b5fcab649c1e5e_id_rsa.pub 2026-02-11 10:02:08.998980 | noble -> localhost | The key fingerprint is: 2026-02-11 10:02:08.999007 | noble -> localhost | SHA256:6/YrH/EA6igC9lKCSjtQe4ntc5X1cDa2IXwZS0xR4GU zuul-build-sshkey 2026-02-11 10:02:08.999031 | noble -> localhost | The key's randomart image is: 2026-02-11 10:02:08.999053 | noble -> localhost | +---[RSA 3072]----+ 2026-02-11 10:02:08.999074 | noble -> localhost | | o*+E | 2026-02-11 10:02:08.999108 | noble -> localhost | | . o.* | 2026-02-11 10:02:08.999130 | noble -> localhost | | . . = @ | 2026-02-11 10:02:08.999150 | noble -> localhost | |.. + . . + O + | 2026-02-11 10:02:08.999170 | noble -> localhost | |++o.+ . S o o | 2026-02-11 10:02:08.999196 | noble -> localhost | |* =o o . . + | 2026-02-11 10:02:08.999216 | noble -> localhost | |o+..+ o . . . | 2026-02-11 10:02:08.999237 | noble -> localhost | | .o. o .o . | 2026-02-11 10:02:08.999257 | noble -> localhost | | ..++. | 2026-02-11 10:02:08.999277 | noble -> localhost | +----[SHA256]-----+ 2026-02-11 10:02:08.999329 | noble -> localhost | ok: Runtime: 0:00:01.757726 2026-02-11 10:02:09.006782 | 2026-02-11 10:02:09.006915 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-11 10:02:09.025744 | noble | ok 2026-02-11 10:02:09.035295 | noble | included: /var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-11 10:02:09.044247 | 2026-02-11 10:02:09.044323 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-11 10:02:09.057063 | noble | skipping: Conditional result was False 2026-02-11 10:02:09.064390 | 2026-02-11 10:02:09.064480 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-11 10:02:09.710932 | noble | changed 2026-02-11 10:02:09.718763 | 2026-02-11 10:02:09.718914 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-11 10:02:10.053428 | noble | ok 2026-02-11 10:02:10.062443 | 2026-02-11 10:02:10.062563 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-11 10:02:10.884066 | noble | changed 2026-02-11 10:02:10.893349 | 2026-02-11 10:02:10.893455 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-11 10:02:11.702670 | noble | changed 2026-02-11 10:02:11.713911 | 2026-02-11 10:02:11.714017 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-11 10:02:11.727618 | noble | skipping: Conditional result was False 2026-02-11 10:02:11.735384 | 2026-02-11 10:02:11.735492 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-11 10:02:12.133947 | noble -> localhost | changed 2026-02-11 10:02:12.148190 | 2026-02-11 10:02:12.148366 | TASK [add-build-sshkey : Add back temp key] 2026-02-11 10:02:12.445100 | noble -> localhost | Identity added: /var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/work/d06ce5ee99cc47f396b5fcab649c1e5e_id_rsa (zuul-build-sshkey) 2026-02-11 10:02:12.445369 | noble -> localhost | ok: Runtime: 0:00:00.010073 2026-02-11 10:02:12.453278 | 2026-02-11 10:02:12.453379 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-11 10:02:12.886431 | noble | ok 2026-02-11 10:02:12.896343 | 2026-02-11 10:02:12.896446 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-11 10:02:12.943428 | noble | skipping: Conditional result was False 2026-02-11 10:02:12.993452 | 2026-02-11 10:02:12.993562 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-11 10:02:13.421424 | noble | ok 2026-02-11 10:02:13.442465 | 2026-02-11 10:02:13.442585 | TASK [validate-host : Define zuul_info_dir fact] 2026-02-11 10:02:13.492912 | noble | ok 2026-02-11 10:02:13.504955 | 2026-02-11 10:02:13.505076 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-02-11 10:02:13.783738 | noble -> localhost | ok 2026-02-11 10:02:13.791037 | 2026-02-11 10:02:13.791144 | TASK [validate-host : Collect information about the host] 2026-02-11 10:02:15.264212 | noble | ok 2026-02-11 10:02:15.281992 | 2026-02-11 10:02:15.282127 | TASK [validate-host : Sanitize hostname] 2026-02-11 10:02:15.342742 | noble | ok 2026-02-11 10:02:15.348848 | 2026-02-11 10:02:15.348995 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-02-11 10:02:15.945417 | noble -> localhost | changed 2026-02-11 10:02:15.952829 | 2026-02-11 10:02:15.952996 | TASK [validate-host : Collect information about zuul worker] 2026-02-11 10:02:16.444492 | noble | ok 2026-02-11 10:02:16.450572 | 2026-02-11 10:02:16.450708 | TASK [validate-host : Write out all zuul information for each host] 2026-02-11 10:02:17.044749 | noble -> localhost | changed 2026-02-11 10:02:17.057153 | 2026-02-11 10:02:17.057322 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-02-11 10:02:17.418752 | noble | ok 2026-02-11 10:02:17.427966 | 2026-02-11 10:02:17.428096 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-02-11 10:02:18.596179 | noble | changed: 2026-02-11 10:02:18.596404 | noble | cd+++++++++ src/ 2026-02-11 10:02:18.596439 | noble | cd+++++++++ src/github.com/ 2026-02-11 10:02:18.596465 | noble | cd+++++++++ src/github.com/osism/ 2026-02-11 10:02:18.596487 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-02-11 10:02:18.596508 | noble | localhost | ok: "/var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/work/logs" 2026-02-11 10:14:55.246630 | noble -> localhost | changed: "/var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/work/artifacts" 2026-02-11 10:14:55.528561 | noble -> localhost | changed: "/var/lib/zuul/builds/d06ce5ee99cc47f396b5fcab649c1e5e/work/docs" 2026-02-11 10:14:55.552977 | 2026-02-11 10:14:55.553153 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-11 10:14:56.884986 | noble | changed: 2026-02-11 10:14:56.885343 | noble | .d..t...... ./ 2026-02-11 10:14:56.885407 | noble | >f+++++++++ osism-node.log 2026-02-11 10:14:56.885487 | noble | changed: All items complete 2026-02-11 10:14:56.885534 | 2026-02-11 10:14:57.557442 | noble | changed: .d..t...... ./ 2026-02-11 10:14:58.230487 | noble | changed: .d..t...... ./ 2026-02-11 10:14:58.261558 | 2026-02-11 10:14:58.261747 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-11 10:14:58.794677 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.012300 2026-02-11 10:14:59.112761 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.011575 2026-02-11 10:14:59.143190 | 2026-02-11 10:14:59.143344 | PLAY RECAP 2026-02-11 10:14:59.143427 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-02-11 10:14:59.143468 | 2026-02-11 10:14:59.275094 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-02-11 10:14:59.277266 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-11 10:15:00.053219 | 2026-02-11 10:15:00.053437 | PLAY [Base post] 2026-02-11 10:15:00.070549 | 2026-02-11 10:15:00.070761 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-02-11 10:15:00.733474 | noble | changed 2026-02-11 10:15:00.743648 | 2026-02-11 10:15:00.743810 | PLAY RECAP 2026-02-11 10:15:00.743884 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-11 10:15:00.743974 | 2026-02-11 10:15:00.886718 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-02-11 10:15:00.887772 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-02-11 10:15:01.712260 | 2026-02-11 10:15:01.712456 | PLAY [Base post-logs] 2026-02-11 10:15:01.723436 | 2026-02-11 10:15:01.723570 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-11 10:15:02.205578 | localhost | changed 2026-02-11 10:15:02.216153 | 2026-02-11 10:15:02.216298 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-11 10:15:02.252312 | localhost | ok 2026-02-11 10:15:02.257985 | 2026-02-11 10:15:02.258119 | TASK [Set zuul-log-path fact] 2026-02-11 10:15:02.274809 | localhost | ok 2026-02-11 10:15:02.285471 | 2026-02-11 10:15:02.285587 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-11 10:15:02.322339 | localhost | ok 2026-02-11 10:15:02.328471 | 2026-02-11 10:15:02.328639 | TASK [upload-logs : Create log directories] 2026-02-11 10:15:02.854129 | localhost | changed 2026-02-11 10:15:02.857344 | 2026-02-11 10:15:02.857459 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-02-11 10:15:03.401770 | localhost -> localhost | ok: Runtime: 0:00:00.009248 2026-02-11 10:15:03.410976 | 2026-02-11 10:15:03.411211 | TASK [upload-logs : Upload logs to log server] 2026-02-11 10:15:04.017969 | localhost | Output suppressed because no_log was given 2026-02-11 10:15:04.020433 | 2026-02-11 10:15:04.020565 | LOOP [upload-logs : Compress console log and json output] 2026-02-11 10:15:04.072878 | localhost | skipping: Conditional result was False 2026-02-11 10:15:04.077679 | localhost | skipping: Conditional result was False 2026-02-11 10:15:04.091586 | 2026-02-11 10:15:04.091854 | LOOP [upload-logs : Upload compressed console log and json output] 2026-02-11 10:15:04.142825 | localhost | skipping: Conditional result was False 2026-02-11 10:15:04.143564 | 2026-02-11 10:15:04.147216 | localhost | skipping: Conditional result was False 2026-02-11 10:15:04.155484 | 2026-02-11 10:15:04.155786 | LOOP [upload-logs : Upload console log and json output]