2026-09-19 06:03:12.744952 | Job console starting 2026-09-19 06:03:12.754474 | Updating git repos 2026-09-19 06:03:12.782525 | Cloning repos into workspace 2026-09-19 06:03:12.817040 | Restoring repo states 2026-09-19 06:03:12.824273 | Merging changes 2026-09-19 06:03:12.824290 | Checking out repos 2026-09-19 06:03:12.877374 | Preparing playbooks 2026-09-19 06:03:13.593040 | Running Ansible setup 2026-09-19 06:03:19.691826 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-19 06:03:20.453368 | 2026-09-19 06:03:20.453654 | PLAY [Base pre] 2026-09-19 06:03:20.473645 | 2026-09-19 06:03:20.473899 | TASK [Setup log path fact] 2026-09-19 06:03:20.494061 | noble | ok 2026-09-19 06:03:20.513281 | 2026-09-19 06:03:20.513438 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-19 06:03:20.543281 | noble | ok 2026-09-19 06:03:20.555451 | 2026-09-19 06:03:20.555580 | TASK [emit-job-header : Print job information] 2026-09-19 06:03:20.596468 | # Job Information 2026-09-19 06:03:20.596644 | Ansible Version: 2.16.14 2026-09-19 06:03:20.596679 | Job: openstack-ironic-images-publish-osism-esp 2026-09-19 06:03:20.596711 | Pipeline: periodic-weekly 2026-09-19 06:03:20.596734 | Executor: 521e9411259a 2026-09-19 06:03:20.596754 | Triggered by: https://github.com/osism/openstack-ironic-images 2026-09-19 06:03:20.596776 | Event ID: 80aa972c3c4247baa21e7d6217b76db1 2026-09-19 06:03:20.602680 | 2026-09-19 06:03:20.602797 | LOOP [emit-job-header : Print node information] 2026-09-19 06:03:20.721256 | noble | ok: 2026-09-19 06:03:20.721501 | noble | # Node Information 2026-09-19 06:03:20.721536 | noble | Inventory Hostname: noble 2026-09-19 06:03:20.721593 | noble | Hostname: ubuntu 2026-09-19 06:03:20.721615 | noble | Username: zuul 2026-09-19 06:03:20.721636 | noble | Distro: Ubuntu 24.04 2026-09-19 06:03:20.721659 | noble | Provider: regiocloud-a 2026-09-19 06:03:20.721681 | noble | Region: 2026-09-19 06:03:20.721702 | noble | Label: ubuntu-noble-uefi 2026-09-19 06:03:20.721722 | noble | Product Name: OpenStack Nova 2026-09-19 06:03:20.721741 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef3:efb6 2026-09-19 06:03:20.740775 | 2026-09-19 06:03:20.740996 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-19 06:03:21.243666 | noble -> localhost | changed 2026-09-19 06:03:21.259226 | 2026-09-19 06:03:21.259401 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-19 06:03:22.327011 | noble -> localhost | changed 2026-09-19 06:03:22.340850 | 2026-09-19 06:03:22.341031 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-19 06:03:22.599484 | noble -> localhost | ok 2026-09-19 06:03:22.606899 | 2026-09-19 06:03:22.607034 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-19 06:03:22.637637 | noble | ok 2026-09-19 06:03:22.656792 | noble | included: /var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-19 06:03:22.664394 | 2026-09-19 06:03:22.664615 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-19 06:03:24.221351 | noble -> localhost | Generating public/private rsa key pair. 2026-09-19 06:03:24.221593 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/work/5a41ad4d637441f5b1d4bfa10b63a020_id_rsa 2026-09-19 06:03:24.221634 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/work/5a41ad4d637441f5b1d4bfa10b63a020_id_rsa.pub 2026-09-19 06:03:24.221661 | noble -> localhost | The key fingerprint is: 2026-09-19 06:03:24.221687 | noble -> localhost | SHA256:+0JOcvqzCTsY/y8LcISVAvZWR/qgTdum8uGDvlFPSMA zuul-build-sshkey 2026-09-19 06:03:24.221710 | noble -> localhost | The key's randomart image is: 2026-09-19 06:03:24.221731 | noble -> localhost | +---[RSA 3072]----+ 2026-09-19 06:03:24.221752 | noble -> localhost | | oo. ooo | 2026-09-19 06:03:24.221785 | noble -> localhost | | . .E=.o | 2026-09-19 06:03:24.221806 | noble -> localhost | | +.* | 2026-09-19 06:03:24.221825 | noble -> localhost | | . * * | 2026-09-19 06:03:24.221845 | noble -> localhost | | o * S | 2026-09-19 06:03:24.221908 | noble -> localhost | | .+.=+. | 2026-09-19 06:03:24.221933 | noble -> localhost | | o==Bo | 2026-09-19 06:03:24.221953 | noble -> localhost | | o====o | 2026-09-19 06:03:24.221974 | noble -> localhost | | .o.+=*Bo | 2026-09-19 06:03:24.221995 | noble -> localhost | +----[SHA256]-----+ 2026-09-19 06:03:24.222051 | noble -> localhost | ok: Runtime: 0:00:01.081917 2026-09-19 06:03:24.229659 | 2026-09-19 06:03:24.229772 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-19 06:03:24.258964 | noble | ok 2026-09-19 06:03:24.269110 | noble | included: /var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-19 06:03:24.279353 | 2026-09-19 06:03:24.279454 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-19 06:03:24.302957 | noble | skipping: Conditional result was False 2026-09-19 06:03:24.309802 | 2026-09-19 06:03:24.309947 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-19 06:03:24.927797 | noble | changed 2026-09-19 06:03:24.940301 | 2026-09-19 06:03:24.940430 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-19 06:03:25.252406 | noble | ok 2026-09-19 06:03:25.267984 | 2026-09-19 06:03:25.268126 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-19 06:03:26.136770 | noble | changed 2026-09-19 06:03:26.143398 | 2026-09-19 06:03:26.143518 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-19 06:03:26.904635 | noble | changed 2026-09-19 06:03:26.911251 | 2026-09-19 06:03:26.911365 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-19 06:03:26.926667 | noble | skipping: Conditional result was False 2026-09-19 06:03:26.935352 | 2026-09-19 06:03:26.935460 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-19 06:03:27.385017 | noble -> localhost | changed 2026-09-19 06:03:27.398230 | 2026-09-19 06:03:27.398354 | TASK [add-build-sshkey : Add back temp key] 2026-09-19 06:03:27.729454 | noble -> localhost | Identity added: /var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/work/5a41ad4d637441f5b1d4bfa10b63a020_id_rsa (zuul-build-sshkey) 2026-09-19 06:03:27.730147 | noble -> localhost | ok: Runtime: 0:00:00.018723 2026-09-19 06:03:27.745535 | 2026-09-19 06:03:27.745730 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-19 06:03:28.214417 | noble | ok 2026-09-19 06:03:28.227732 | 2026-09-19 06:03:28.227949 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-19 06:03:28.253019 | noble | skipping: Conditional result was False 2026-09-19 06:03:28.314170 | 2026-09-19 06:03:28.314309 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-19 06:03:28.759981 | noble | ok 2026-09-19 06:03:28.791098 | 2026-09-19 06:03:28.791235 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-19 06:03:28.820058 | noble | ok 2026-09-19 06:03:28.825997 | 2026-09-19 06:03:28.826109 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-19 06:03:29.122327 | noble -> localhost | ok 2026-09-19 06:03:29.130155 | 2026-09-19 06:03:29.130284 | TASK [validate-host : Collect information about the host] 2026-09-19 06:03:30.637409 | noble | ok 2026-09-19 06:03:30.651646 | 2026-09-19 06:03:30.651769 | TASK [validate-host : Sanitize hostname] 2026-09-19 06:03:30.710788 | noble | ok 2026-09-19 06:03:30.716582 | 2026-09-19 06:03:30.716699 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-19 06:03:31.286377 | noble -> localhost | changed 2026-09-19 06:03:31.293121 | 2026-09-19 06:03:31.293241 | TASK [validate-host : Collect information about zuul worker] 2026-09-19 06:03:31.781913 | noble | ok 2026-09-19 06:03:31.790656 | 2026-09-19 06:03:31.790954 | TASK [validate-host : Write out all zuul information for each host] 2026-09-19 06:03:32.354110 | noble -> localhost | changed 2026-09-19 06:03:32.366452 | 2026-09-19 06:03:32.366575 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-19 06:03:32.698305 | noble | ok 2026-09-19 06:03:32.704941 | 2026-09-19 06:03:32.705053 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-19 06:03:33.673007 | noble | changed: 2026-09-19 06:03:33.673254 | noble | cd+++++++++ src/ 2026-09-19 06:03:33.673302 | noble | cd+++++++++ src/github.com/ 2026-09-19 06:03:33.673336 | noble | cd+++++++++ src/github.com/osism/ 2026-09-19 06:03:33.673367 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2026-09-19 06:03:33.673396 | noble | localhost | ok: "/var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/work/logs" 2026-09-19 06:07:01.955920 | noble -> localhost | changed: "/var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/work/artifacts" 2026-09-19 06:07:02.216474 | noble -> localhost | changed: "/var/lib/zuul/builds/5a41ad4d637441f5b1d4bfa10b63a020/work/docs" 2026-09-19 06:07:02.239417 | 2026-09-19 06:07:02.239626 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-19 06:07:03.565148 | noble | changed: 2026-09-19 06:07:03.565431 | noble | .d..t...... ./ 2026-09-19 06:07:03.565472 | noble | >f+++++++++ net-debug.post.txt 2026-09-19 06:07:03.565499 | noble | >f+++++++++ net-debug.pre.txt 2026-09-19 06:07:03.565522 | noble | >f+++++++++ osism-esp.log 2026-09-19 06:07:03.565544 | noble | >f+++++++++ sudo-debug.txt 2026-09-19 06:07:03.565669 | noble | >f+++++++++ sudo-strace.log 2026-09-19 06:07:03.565692 | noble | >f+++++++++ sudo-strace.log.time 2026-09-19 06:07:03.565735 | noble | changed: All items complete 2026-09-19 06:07:03.565763 | 2026-09-19 06:07:04.261876 | noble | changed: .d..t...... ./ 2026-09-19 06:07:04.983037 | noble | changed: .d..t...... ./ 2026-09-19 06:07:05.004063 | 2026-09-19 06:07:05.004192 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-19 06:07:05.029525 | noble | skipping: Conditional result was False 2026-09-19 06:07:05.032592 | noble | skipping: Conditional result was False 2026-09-19 06:07:05.048719 | 2026-09-19 06:07:05.048832 | PLAY RECAP 2026-09-19 06:07:05.048914 | noble | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-19 06:07:05.048960 | 2026-09-19 06:07:05.198882 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-19 06:07:05.200850 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-19 06:07:05.931127 | 2026-09-19 06:07:05.931283 | PLAY [Base post] 2026-09-19 06:07:05.945991 | 2026-09-19 06:07:05.946123 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-19 06:07:06.605815 | noble | changed 2026-09-19 06:07:06.616196 | 2026-09-19 06:07:06.616318 | PLAY RECAP 2026-09-19 06:07:06.616395 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-19 06:07:06.616472 | 2026-09-19 06:07:06.727491 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-19 06:07:06.729427 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-19 06:07:07.529341 | 2026-09-19 06:07:07.529499 | PLAY [Base post-logs] 2026-09-19 06:07:07.540312 | 2026-09-19 06:07:07.540440 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-19 06:07:08.006083 | localhost | changed 2026-09-19 06:07:08.023524 | 2026-09-19 06:07:08.023713 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-19 06:07:08.061996 | localhost | ok 2026-09-19 06:07:08.068969 | 2026-09-19 06:07:08.069142 | TASK [Set zuul-log-path fact] 2026-09-19 06:07:08.086813 | localhost | ok 2026-09-19 06:07:08.099929 | 2026-09-19 06:07:08.100072 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-19 06:07:08.137315 | localhost | ok 2026-09-19 06:07:08.141631 | 2026-09-19 06:07:08.141756 | TASK [upload-logs : Create log directories] 2026-09-19 06:07:08.626518 | localhost | changed 2026-09-19 06:07:08.629985 | 2026-09-19 06:07:08.630106 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-19 06:07:09.100746 | localhost -> localhost | ok: Runtime: 0:00:00.007653 2026-09-19 06:07:09.108895 | 2026-09-19 06:07:09.109057 | TASK [upload-logs : Upload logs to log server] 2026-09-19 06:07:09.672905 | localhost | Output suppressed because no_log was given 2026-09-19 06:07:09.676214 | 2026-09-19 06:07:09.676370 | LOOP [upload-logs : Compress console log and json output] 2026-09-19 06:07:09.726877 | localhost | skipping: Conditional result was False 2026-09-19 06:07:09.742820 | localhost | skipping: Conditional result was False 2026-09-19 06:07:09.757924 | 2026-09-19 06:07:09.758178 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-19 06:07:09.813179 | localhost | skipping: Conditional result was False 2026-09-19 06:07:09.813625 | 2026-09-19 06:07:09.818827 | localhost | skipping: Conditional result was False 2026-09-19 06:07:09.830088 | 2026-09-19 06:07:09.830275 | LOOP [upload-logs : Upload console log and json output]