2025-12-20 06:03:01.851916 | Job console starting 2025-12-20 06:03:01.878080 | Updating git repos 2025-12-20 06:03:01.921306 | Cloning repos into workspace 2025-12-20 06:03:02.017445 | Restoring repo states 2025-12-20 06:03:02.032746 | Merging changes 2025-12-20 06:03:02.032768 | Checking out repos 2025-12-20 06:03:02.078625 | Preparing playbooks 2025-12-20 06:03:03.027295 | Running Ansible setup 2025-12-20 06:03:09.158188 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-20 06:03:10.001286 | 2025-12-20 06:03:10.001471 | PLAY [Base pre] 2025-12-20 06:03:10.021363 | 2025-12-20 06:03:10.021538 | TASK [Setup log path fact] 2025-12-20 06:03:10.043793 | noble | ok 2025-12-20 06:03:10.067160 | 2025-12-20 06:03:10.067336 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-20 06:03:10.097718 | noble | ok 2025-12-20 06:03:10.114027 | 2025-12-20 06:03:10.114243 | TASK [emit-job-header : Print job information] 2025-12-20 06:03:10.155783 | # Job Information 2025-12-20 06:03:10.156093 | Ansible Version: 2.16.14 2025-12-20 06:03:10.156133 | Job: openstack-ironic-images-publish-osism-esp 2025-12-20 06:03:10.156170 | Pipeline: periodic-weekly 2025-12-20 06:03:10.156199 | Executor: 521e9411259a 2025-12-20 06:03:10.156219 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-12-20 06:03:10.156241 | Event ID: cdf97428b8d348b1a033dad9eb8def74 2025-12-20 06:03:10.166083 | 2025-12-20 06:03:10.166239 | LOOP [emit-job-header : Print node information] 2025-12-20 06:03:10.316146 | noble | ok: 2025-12-20 06:03:10.316346 | noble | # Node Information 2025-12-20 06:03:10.316379 | noble | Inventory Hostname: noble 2025-12-20 06:03:10.316403 | noble | Hostname: ubuntu 2025-12-20 06:03:10.316425 | noble | Username: zuul 2025-12-20 06:03:10.316446 | noble | Distro: Ubuntu 24.04 2025-12-20 06:03:10.316469 | noble | Provider: regiocloud-a 2025-12-20 06:03:10.316489 | noble | Region: 2025-12-20 06:03:10.316509 | noble | Label: ubuntu-noble-uefi 2025-12-20 06:03:10.316529 | noble | Product Name: OpenStack Nova 2025-12-20 06:03:10.316548 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febf:aac8 2025-12-20 06:03:10.357321 | 2025-12-20 06:03:10.357503 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-20 06:03:11.064128 | noble -> localhost | changed 2025-12-20 06:03:11.075636 | 2025-12-20 06:03:11.075795 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-20 06:03:12.441692 | noble -> localhost | changed 2025-12-20 06:03:12.460397 | 2025-12-20 06:03:12.460549 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-20 06:03:12.946026 | noble -> localhost | ok 2025-12-20 06:03:12.957459 | 2025-12-20 06:03:12.957650 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-20 06:03:12.992287 | noble | ok 2025-12-20 06:03:13.025335 | noble | included: /var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-20 06:03:13.040403 | 2025-12-20 06:03:13.040536 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-20 06:03:14.380788 | noble -> localhost | Generating public/private rsa key pair. 2025-12-20 06:03:14.381215 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/work/3bf3adef66214fb0a64fb95e742b3f53_id_rsa 2025-12-20 06:03:14.381295 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/work/3bf3adef66214fb0a64fb95e742b3f53_id_rsa.pub 2025-12-20 06:03:14.381351 | noble -> localhost | The key fingerprint is: 2025-12-20 06:03:14.381408 | noble -> localhost | SHA256:ElfoHLKiF91b92KRAK3/3bzrZ/jyzkS1icVoLj3mbng zuul-build-sshkey 2025-12-20 06:03:14.381456 | noble -> localhost | The key's randomart image is: 2025-12-20 06:03:14.381501 | noble -> localhost | +---[RSA 3072]----+ 2025-12-20 06:03:14.381544 | noble -> localhost | | .+. | 2025-12-20 06:03:14.381605 | noble -> localhost | | . o.o o | 2025-12-20 06:03:14.381651 | noble -> localhost | | ..*.o . + o.| 2025-12-20 06:03:14.381694 | noble -> localhost | | o oo= . B o +| 2025-12-20 06:03:14.381736 | noble -> localhost | | . o. S+ o O o.| 2025-12-20 06:03:14.381786 | noble -> localhost | | . . .. . * o. | 2025-12-20 06:03:14.381889 | noble -> localhost | | . o.+ +.| 2025-12-20 06:03:14.381936 | noble -> localhost | | .oE+o=| 2025-12-20 06:03:14.381980 | noble -> localhost | | o..XO| 2025-12-20 06:03:14.382022 | noble -> localhost | +----[SHA256]-----+ 2025-12-20 06:03:14.382127 | noble -> localhost | ok: Runtime: 0:00:00.497129 2025-12-20 06:03:14.394937 | 2025-12-20 06:03:14.395119 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-20 06:03:14.427300 | noble | ok 2025-12-20 06:03:14.438475 | noble | included: /var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-20 06:03:14.454558 | 2025-12-20 06:03:14.454684 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-20 06:03:14.489007 | noble | skipping: Conditional result was False 2025-12-20 06:03:14.504879 | 2025-12-20 06:03:14.505029 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-20 06:03:15.236966 | noble | changed 2025-12-20 06:03:15.247487 | 2025-12-20 06:03:15.247638 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-20 06:03:15.579447 | noble | ok 2025-12-20 06:03:15.588335 | 2025-12-20 06:03:15.588469 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-20 06:03:16.420642 | noble | changed 2025-12-20 06:03:16.427384 | 2025-12-20 06:03:16.427521 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-20 06:03:17.165446 | noble | changed 2025-12-20 06:03:17.172367 | 2025-12-20 06:03:17.172498 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-20 06:03:17.196705 | noble | skipping: Conditional result was False 2025-12-20 06:03:17.204041 | 2025-12-20 06:03:17.204158 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-20 06:03:17.705050 | noble -> localhost | changed 2025-12-20 06:03:17.722674 | 2025-12-20 06:03:17.722932 | TASK [add-build-sshkey : Add back temp key] 2025-12-20 06:03:18.116732 | noble -> localhost | Identity added: /var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/work/3bf3adef66214fb0a64fb95e742b3f53_id_rsa (zuul-build-sshkey) 2025-12-20 06:03:18.117111 | noble -> localhost | ok: Runtime: 0:00:00.010658 2025-12-20 06:03:18.124781 | 2025-12-20 06:03:18.124950 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-20 06:03:18.621363 | noble | ok 2025-12-20 06:03:18.639972 | 2025-12-20 06:03:18.640135 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-20 06:03:18.674606 | noble | skipping: Conditional result was False 2025-12-20 06:03:18.751552 | 2025-12-20 06:03:18.751700 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-20 06:03:19.294081 | noble | ok 2025-12-20 06:03:19.307590 | 2025-12-20 06:03:19.307770 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-20 06:03:19.345714 | noble | ok 2025-12-20 06:03:19.357407 | 2025-12-20 06:03:19.357621 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-20 06:03:19.677410 | noble -> localhost | ok 2025-12-20 06:03:19.696094 | 2025-12-20 06:03:19.696340 | TASK [validate-host : Collect information about the host] 2025-12-20 06:03:21.180359 | noble | ok 2025-12-20 06:03:21.196084 | 2025-12-20 06:03:21.196224 | TASK [validate-host : Sanitize hostname] 2025-12-20 06:03:21.256434 | noble | ok 2025-12-20 06:03:21.262514 | 2025-12-20 06:03:21.262650 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-20 06:03:21.881252 | noble -> localhost | changed 2025-12-20 06:03:21.888134 | 2025-12-20 06:03:21.888265 | TASK [validate-host : Collect information about zuul worker] 2025-12-20 06:03:22.374010 | noble | ok 2025-12-20 06:03:22.380275 | 2025-12-20 06:03:22.380406 | TASK [validate-host : Write out all zuul information for each host] 2025-12-20 06:03:23.016293 | noble -> localhost | changed 2025-12-20 06:03:23.031737 | 2025-12-20 06:03:23.031901 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-20 06:03:23.349794 | noble | ok 2025-12-20 06:03:23.362437 | 2025-12-20 06:03:23.362596 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-20 06:03:24.411157 | noble | changed: 2025-12-20 06:03:24.411371 | noble | cd+++++++++ src/ 2025-12-20 06:03:24.411407 | noble | cd+++++++++ src/github.com/ 2025-12-20 06:03:24.411432 | noble | cd+++++++++ src/github.com/osism/ 2025-12-20 06:03:24.411454 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-12-20 06:03:24.411474 | noble | localhost | ok: "/var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/work/logs" 2025-12-20 06:11:02.939719 | noble -> localhost | changed: "/var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/work/artifacts" 2025-12-20 06:11:03.210608 | noble -> localhost | changed: "/var/lib/zuul/builds/3bf3adef66214fb0a64fb95e742b3f53/work/docs" 2025-12-20 06:11:03.232698 | 2025-12-20 06:11:03.232860 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-20 06:11:04.586551 | noble | changed: 2025-12-20 06:11:04.587275 | noble | .d..t...... ./ 2025-12-20 06:11:04.587382 | noble | >f+++++++++ osism-esp.log 2025-12-20 06:11:04.587500 | noble | changed: All items complete 2025-12-20 06:11:04.587566 | 2025-12-20 06:11:05.262986 | noble | changed: .d..t...... ./ 2025-12-20 06:11:05.964210 | noble | changed: .d..t...... ./ 2025-12-20 06:11:05.993832 | 2025-12-20 06:11:05.994040 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-20 06:11:06.036541 | noble | skipping: Conditional result was False 2025-12-20 06:11:06.037812 | noble | skipping: Conditional result was False 2025-12-20 06:11:06.052470 | 2025-12-20 06:11:06.052608 | PLAY RECAP 2025-12-20 06:11:06.052676 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-12-20 06:11:06.052708 | 2025-12-20 06:11:06.207324 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-12-20 06:11:06.208391 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-20 06:11:06.990162 | 2025-12-20 06:11:06.990337 | PLAY [Base post] 2025-12-20 06:11:07.006652 | 2025-12-20 06:11:07.006807 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-12-20 06:11:07.693066 | noble | changed 2025-12-20 06:11:07.706459 | 2025-12-20 06:11:07.706638 | PLAY RECAP 2025-12-20 06:11:07.706760 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-20 06:11:07.706911 | 2025-12-20 06:11:07.877408 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-12-20 06:11:07.879072 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-12-20 06:11:08.730351 | 2025-12-20 06:11:08.730527 | PLAY [Base post-logs] 2025-12-20 06:11:08.742090 | 2025-12-20 06:11:08.742260 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-20 06:11:09.261358 | localhost | changed 2025-12-20 06:11:09.276252 | 2025-12-20 06:11:09.276446 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-20 06:11:09.315048 | localhost | ok 2025-12-20 06:11:09.321186 | 2025-12-20 06:11:09.321354 | TASK [Set zuul-log-path fact] 2025-12-20 06:11:09.350758 | localhost | ok 2025-12-20 06:11:09.373981 | 2025-12-20 06:11:09.374239 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-20 06:11:09.416764 | localhost | ok 2025-12-20 06:11:09.422252 | 2025-12-20 06:11:09.422407 | TASK [upload-logs : Create log directories] 2025-12-20 06:11:09.976577 | localhost | changed 2025-12-20 06:11:09.981343 | 2025-12-20 06:11:09.981504 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-12-20 06:11:10.586207 | localhost -> localhost | ok: Runtime: 0:00:00.004990 2025-12-20 06:11:10.594542 | 2025-12-20 06:11:10.594782 | TASK [upload-logs : Upload logs to log server] 2025-12-20 06:11:11.208546 | localhost | Output suppressed because no_log was given 2025-12-20 06:11:11.211378 | 2025-12-20 06:11:11.211532 | LOOP [upload-logs : Compress console log and json output] 2025-12-20 06:11:11.268556 | localhost | skipping: Conditional result was False 2025-12-20 06:11:11.277643 | localhost | skipping: Conditional result was False 2025-12-20 06:11:11.292914 | 2025-12-20 06:11:11.293096 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-20 06:11:11.372078 | localhost | skipping: Conditional result was False 2025-12-20 06:11:11.372366 | 2025-12-20 06:11:11.385287 | localhost | skipping: Conditional result was False 2025-12-20 06:11:11.391860 | 2025-12-20 06:11:11.392001 | LOOP [upload-logs : Upload console log and json output]