2025-08-27 11:46:04.325071 | Job console starting 2025-08-27 11:46:04.335350 | Updating git repos 2025-08-27 11:46:04.354762 | Cloning repos into workspace 2025-08-27 11:46:04.412211 | Restoring repo states 2025-08-27 11:46:04.419658 | Merging changes 2025-08-27 11:46:04.419674 | Checking out repos 2025-08-27 11:46:04.465547 | Preparing playbooks 2025-08-27 11:46:05.065569 | Running Ansible setup 2025-08-27 11:46:11.251890 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-27 11:46:12.159192 | 2025-08-27 11:46:12.159451 | PLAY [Base pre] 2025-08-27 11:46:12.177143 | 2025-08-27 11:46:12.177285 | TASK [Setup log path fact] 2025-08-27 11:46:12.207926 | noble | ok 2025-08-27 11:46:12.225378 | 2025-08-27 11:46:12.225517 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 11:46:12.267183 | noble | ok 2025-08-27 11:46:12.279190 | 2025-08-27 11:46:12.279318 | TASK [emit-job-header : Print job information] 2025-08-27 11:46:12.319530 | # Job Information 2025-08-27 11:46:12.319724 | Ansible Version: 2.16.14 2025-08-27 11:46:12.319760 | Job: openstack-ironic-images-publish-osism-esp 2025-08-27 11:46:12.319793 | Pipeline: post 2025-08-27 11:46:12.319817 | Executor: 521e9411259a 2025-08-27 11:46:12.319837 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-08-27 11:46:12.319860 | Event ID: 7ff1bedda35342b5957e7ccb7e392c3a 2025-08-27 11:46:12.326857 | 2025-08-27 11:46:12.327002 | LOOP [emit-job-header : Print node information] 2025-08-27 11:46:12.445980 | noble | ok: 2025-08-27 11:46:12.446187 | noble | # Node Information 2025-08-27 11:46:12.446223 | noble | Inventory Hostname: noble 2025-08-27 11:46:12.446248 | noble | Hostname: ubuntu 2025-08-27 11:46:12.446269 | noble | Username: zuul 2025-08-27 11:46:12.446290 | noble | Distro: Ubuntu 24.04 2025-08-27 11:46:12.446313 | noble | Provider: regiocloud-a 2025-08-27 11:46:12.446333 | noble | Region: 2025-08-27 11:46:12.446355 | noble | Label: ubuntu-noble-uefi 2025-08-27 11:46:12.446376 | noble | Product Name: OpenStack Nova 2025-08-27 11:46:12.446396 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe47:1a14 2025-08-27 11:46:12.465546 | 2025-08-27 11:46:12.465687 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-27 11:46:12.988911 | noble -> localhost | changed 2025-08-27 11:46:13.006520 | 2025-08-27 11:46:13.006683 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-27 11:46:14.094073 | noble -> localhost | changed 2025-08-27 11:46:14.111536 | 2025-08-27 11:46:14.111666 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-27 11:46:14.386242 | noble -> localhost | ok 2025-08-27 11:46:14.396493 | 2025-08-27 11:46:14.396646 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-27 11:46:14.430354 | noble | ok 2025-08-27 11:46:14.446719 | noble | included: /var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-27 11:46:14.454827 | 2025-08-27 11:46:14.454952 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-27 11:46:15.448298 | noble -> localhost | Generating public/private rsa key pair. 2025-08-27 11:46:15.448547 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/work/3552fe6d67274219b8748660a9851636_id_rsa 2025-08-27 11:46:15.448585 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/work/3552fe6d67274219b8748660a9851636_id_rsa.pub 2025-08-27 11:46:15.448612 | noble -> localhost | The key fingerprint is: 2025-08-27 11:46:15.448636 | noble -> localhost | SHA256:hJGw0z0Vik7CDWK4QgpymhHp8O5cVdex5noaLEQwjKo zuul-build-sshkey 2025-08-27 11:46:15.448660 | noble -> localhost | The key's randomart image is: 2025-08-27 11:46:15.448681 | noble -> localhost | +---[RSA 3072]----+ 2025-08-27 11:46:15.448702 | noble -> localhost | |.+o o++. oo.. | 2025-08-27 11:46:15.448723 | noble -> localhost | |Bo.o.=oB.o. .. | 2025-08-27 11:46:15.448767 | noble -> localhost | |BB .= *.*. o | 2025-08-27 11:46:15.448788 | noble -> localhost | |*.o =.o . o | 2025-08-27 11:46:15.448808 | noble -> localhost | |.o .. S . | 2025-08-27 11:46:15.448837 | noble -> localhost | |E . . . . . | 2025-08-27 11:46:15.448858 | noble -> localhost | | o . . + . | 2025-08-27 11:46:15.448879 | noble -> localhost | | o . + | 2025-08-27 11:46:15.448900 | noble -> localhost | | . | 2025-08-27 11:46:15.448921 | noble -> localhost | +----[SHA256]-----+ 2025-08-27 11:46:15.448973 | noble -> localhost | ok: Runtime: 0:00:00.447913 2025-08-27 11:46:15.458040 | 2025-08-27 11:46:15.458182 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-27 11:46:15.487717 | noble | ok 2025-08-27 11:46:15.499043 | noble | included: /var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-27 11:46:15.509086 | 2025-08-27 11:46:15.509194 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-27 11:46:15.533191 | noble | skipping: Conditional result was False 2025-08-27 11:46:15.541550 | 2025-08-27 11:46:15.541660 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-27 11:46:16.148161 | noble | changed 2025-08-27 11:46:16.154674 | 2025-08-27 11:46:16.154783 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-27 11:46:16.462081 | noble | ok 2025-08-27 11:46:16.468718 | 2025-08-27 11:46:16.468833 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-27 11:46:17.228987 | noble | changed 2025-08-27 11:46:17.235636 | 2025-08-27 11:46:17.235746 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-27 11:46:17.986005 | noble | changed 2025-08-27 11:46:17.992541 | 2025-08-27 11:46:17.992657 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-27 11:46:18.034251 | noble | skipping: Conditional result was False 2025-08-27 11:46:18.041352 | 2025-08-27 11:46:18.041472 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-27 11:46:18.642667 | noble -> localhost | changed 2025-08-27 11:46:18.679873 | 2025-08-27 11:46:18.680048 | TASK [add-build-sshkey : Add back temp key] 2025-08-27 11:46:19.064225 | noble -> localhost | Identity added: /var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/work/3552fe6d67274219b8748660a9851636_id_rsa (zuul-build-sshkey) 2025-08-27 11:46:19.064473 | noble -> localhost | ok: Runtime: 0:00:00.047166 2025-08-27 11:46:19.072232 | 2025-08-27 11:46:19.072378 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-27 11:46:19.527280 | noble | ok 2025-08-27 11:46:19.537530 | 2025-08-27 11:46:19.537654 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-27 11:46:19.572650 | noble | skipping: Conditional result was False 2025-08-27 11:46:19.678887 | 2025-08-27 11:46:19.679054 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-27 11:46:20.135753 | noble | ok 2025-08-27 11:46:20.148807 | 2025-08-27 11:46:20.148935 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-27 11:46:20.181274 | noble | ok 2025-08-27 11:46:20.195174 | 2025-08-27 11:46:20.195313 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-27 11:46:20.597797 | noble -> localhost | ok 2025-08-27 11:46:20.611510 | 2025-08-27 11:46:20.611758 | TASK [validate-host : Collect information about the host] 2025-08-27 11:46:22.057608 | noble | ok 2025-08-27 11:46:22.072983 | 2025-08-27 11:46:22.073138 | TASK [validate-host : Sanitize hostname] 2025-08-27 11:46:22.146980 | noble | ok 2025-08-27 11:46:22.157012 | 2025-08-27 11:46:22.157172 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-27 11:46:22.739561 | noble -> localhost | changed 2025-08-27 11:46:22.747648 | 2025-08-27 11:46:22.747765 | TASK [validate-host : Collect information about zuul worker] 2025-08-27 11:46:23.259764 | noble | ok 2025-08-27 11:46:23.268185 | 2025-08-27 11:46:23.268336 | TASK [validate-host : Write out all zuul information for each host] 2025-08-27 11:46:23.862818 | noble -> localhost | changed 2025-08-27 11:46:23.874274 | 2025-08-27 11:46:23.874407 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-27 11:46:24.230391 | noble | ok 2025-08-27 11:46:24.240398 | 2025-08-27 11:46:24.240523 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-27 11:46:25.241260 | noble | changed: 2025-08-27 11:46:25.241503 | noble | cd+++++++++ src/ 2025-08-27 11:46:25.241547 | noble | cd+++++++++ src/github.com/ 2025-08-27 11:46:25.241571 | noble | cd+++++++++ src/github.com/osism/ 2025-08-27 11:46:25.241592 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-08-27 11:46:25.241613 | noble | localhost | ok: "/var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/work/logs" 2025-08-27 12:00:06.606492 | noble -> localhost | changed: "/var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/work/artifacts" 2025-08-27 12:00:06.883226 | noble -> localhost | changed: "/var/lib/zuul/builds/3552fe6d67274219b8748660a9851636/work/docs" 2025-08-27 12:00:06.898538 | 2025-08-27 12:00:06.898727 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-08-27 12:00:08.203199 | noble | changed: 2025-08-27 12:00:08.203526 | noble | .d..t...... ./ 2025-08-27 12:00:08.203587 | noble | >f+++++++++ osism-esp.log 2025-08-27 12:00:08.203675 | noble | changed: All items complete 2025-08-27 12:00:08.203730 | 2025-08-27 12:00:08.902649 | noble | changed: .d..t...... ./ 2025-08-27 12:00:09.567270 | noble | changed: .d..t...... ./ 2025-08-27 12:00:09.598507 | 2025-08-27 12:00:09.598672 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-08-27 12:00:09.636358 | noble | skipping: Conditional result was False 2025-08-27 12:00:09.639212 | noble | skipping: Conditional result was False 2025-08-27 12:00:09.661088 | 2025-08-27 12:00:09.661225 | PLAY RECAP 2025-08-27 12:00:09.661305 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-08-27 12:00:09.661346 | 2025-08-27 12:00:09.788970 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-08-27 12:00:09.790518 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-27 12:00:10.532558 | 2025-08-27 12:00:10.532735 | PLAY [Base post] 2025-08-27 12:00:10.547648 | 2025-08-27 12:00:10.547792 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-27 12:00:11.187823 | noble | changed 2025-08-27 12:00:11.195623 | 2025-08-27 12:00:11.195729 | PLAY RECAP 2025-08-27 12:00:11.195790 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-27 12:00:11.195863 | 2025-08-27 12:00:11.308874 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-27 12:00:11.311219 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-27 12:00:12.141683 | 2025-08-27 12:00:12.141928 | PLAY [Base post-logs] 2025-08-27 12:00:12.152954 | 2025-08-27 12:00:12.153087 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-27 12:00:12.607149 | localhost | changed 2025-08-27 12:00:12.624825 | 2025-08-27 12:00:12.625008 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-27 12:00:12.652462 | localhost | ok 2025-08-27 12:00:12.658354 | 2025-08-27 12:00:12.658499 | TASK [Set zuul-log-path fact] 2025-08-27 12:00:12.675225 | localhost | ok 2025-08-27 12:00:12.687624 | 2025-08-27 12:00:12.687761 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-27 12:00:12.715316 | localhost | ok 2025-08-27 12:00:12.723209 | 2025-08-27 12:00:12.723392 | TASK [upload-logs : Create log directories] 2025-08-27 12:00:13.232826 | localhost | changed 2025-08-27 12:00:13.237522 | 2025-08-27 12:00:13.237676 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-27 12:00:13.739147 | localhost -> localhost | ok: Runtime: 0:00:00.007255 2025-08-27 12:00:13.743465 | 2025-08-27 12:00:13.743584 | TASK [upload-logs : Upload logs to log server] 2025-08-27 12:00:14.307745 | localhost | Output suppressed because no_log was given 2025-08-27 12:00:14.310455 | 2025-08-27 12:00:14.310597 | LOOP [upload-logs : Compress console log and json output] 2025-08-27 12:00:14.375853 | localhost | skipping: Conditional result was False 2025-08-27 12:00:14.385830 | localhost | skipping: Conditional result was False 2025-08-27 12:00:14.394761 | 2025-08-27 12:00:14.395011 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-27 12:00:14.454422 | localhost | skipping: Conditional result was False 2025-08-27 12:00:14.455091 | 2025-08-27 12:00:14.458921 | localhost | skipping: Conditional result was False 2025-08-27 12:00:14.472784 | 2025-08-27 12:00:14.473042 | LOOP [upload-logs : Upload console log and json output]