2025-10-20 15:08:37.952710 | Job console starting 2025-10-20 15:08:37.963661 | Updating git repos 2025-10-20 15:08:37.992250 | Cloning repos into workspace 2025-10-20 15:08:38.073045 | Restoring repo states 2025-10-20 15:08:38.081559 | Merging changes 2025-10-20 15:08:38.081579 | Checking out repos 2025-10-20 15:08:38.152733 | Preparing playbooks 2025-10-20 15:08:38.967681 | Running Ansible setup 2025-10-20 15:08:45.017410 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-20 15:08:45.844565 | 2025-10-20 15:08:45.844691 | PLAY [Base pre] 2025-10-20 15:08:45.860652 | 2025-10-20 15:08:45.860766 | TASK [Setup log path fact] 2025-10-20 15:08:45.880634 | noble | ok 2025-10-20 15:08:45.904158 | 2025-10-20 15:08:45.904290 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-20 15:08:45.956934 | noble | ok 2025-10-20 15:08:45.975403 | 2025-10-20 15:08:45.975513 | TASK [emit-job-header : Print job information] 2025-10-20 15:08:46.044735 | # Job Information 2025-10-20 15:08:46.044899 | Ansible Version: 2.16.14 2025-10-20 15:08:46.044935 | Job: openstack-ironic-images-publish-osism-node 2025-10-20 15:08:46.044969 | Pipeline: post 2025-10-20 15:08:46.045003 | Executor: 521e9411259a 2025-10-20 15:08:46.045025 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/ec88c4c491b1c4d136b0381caa95d56070ba0965 2025-10-20 15:08:46.045047 | Event ID: 31902896-adc6-11f0-896a-1bab6c83a29a 2025-10-20 15:08:46.051456 | 2025-10-20 15:08:46.051550 | LOOP [emit-job-header : Print node information] 2025-10-20 15:08:46.159243 | noble | ok: 2025-10-20 15:08:46.162115 | noble | # Node Information 2025-10-20 15:08:46.162189 | noble | Inventory Hostname: noble 2025-10-20 15:08:46.162218 | noble | Hostname: ubuntu 2025-10-20 15:08:46.162242 | noble | Username: zuul 2025-10-20 15:08:46.162262 | noble | Distro: Ubuntu 24.04 2025-10-20 15:08:46.162285 | noble | Provider: regiocloud-a 2025-10-20 15:08:46.162307 | noble | Region: 2025-10-20 15:08:46.162329 | noble | Label: ubuntu-noble-uefi 2025-10-20 15:08:46.162349 | noble | Product Name: OpenStack Nova 2025-10-20 15:08:46.162370 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed6:eefb 2025-10-20 15:08:46.180714 | 2025-10-20 15:08:46.180820 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-20 15:08:46.690122 | noble -> localhost | changed 2025-10-20 15:08:46.698061 | 2025-10-20 15:08:46.698178 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-20 15:08:47.961977 | noble -> localhost | changed 2025-10-20 15:08:47.975091 | 2025-10-20 15:08:47.975190 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-20 15:08:48.349860 | noble -> localhost | ok 2025-10-20 15:08:48.356569 | 2025-10-20 15:08:48.356663 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-20 15:08:48.374542 | noble | ok 2025-10-20 15:08:48.389738 | noble | included: /var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-20 15:08:48.397085 | 2025-10-20 15:08:48.397166 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-20 15:08:50.242951 | noble -> localhost | Generating public/private rsa key pair. 2025-10-20 15:08:50.243398 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/work/98f7e050467843e7883662d07b8efa50_id_rsa 2025-10-20 15:08:50.243489 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/work/98f7e050467843e7883662d07b8efa50_id_rsa.pub 2025-10-20 15:08:50.243559 | noble -> localhost | The key fingerprint is: 2025-10-20 15:08:50.243622 | noble -> localhost | SHA256:5cnhrJmLETaGpXVh4wGiySYV/MlvzVmeJ0cnRzUC27E zuul-build-sshkey 2025-10-20 15:08:50.243679 | noble -> localhost | The key's randomart image is: 2025-10-20 15:08:50.243733 | noble -> localhost | +---[RSA 3072]----+ 2025-10-20 15:08:50.243785 | noble -> localhost | | .o.. ..= ..o o.| 2025-10-20 15:08:50.243858 | noble -> localhost | | o.o . o + o = .| 2025-10-20 15:08:50.243911 | noble -> localhost | |. =o .o o o. E | 2025-10-20 15:08:50.243960 | noble -> localhost | | o += . *.oo o | 2025-10-20 15:08:50.244028 | noble -> localhost | | o.=oS+*o + | 2025-10-20 15:08:50.244094 | noble -> localhost | | ooo+++ o | 2025-10-20 15:08:50.244147 | noble -> localhost | | .. + + | 2025-10-20 15:08:50.244196 | noble -> localhost | | o . | 2025-10-20 15:08:50.244249 | noble -> localhost | | . . | 2025-10-20 15:08:50.244300 | noble -> localhost | +----[SHA256]-----+ 2025-10-20 15:08:50.244433 | noble -> localhost | ok: Runtime: 0:00:01.388071 2025-10-20 15:08:50.259029 | 2025-10-20 15:08:50.259167 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-20 15:08:50.290559 | noble | ok 2025-10-20 15:08:50.303436 | noble | included: /var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-20 15:08:50.322879 | 2025-10-20 15:08:50.323091 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-20 15:08:50.347779 | noble | skipping: Conditional result was False 2025-10-20 15:08:50.363522 | 2025-10-20 15:08:50.363655 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-20 15:08:50.981667 | noble | changed 2025-10-20 15:08:50.991464 | 2025-10-20 15:08:50.991579 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-20 15:08:51.324381 | noble | ok 2025-10-20 15:08:51.340473 | 2025-10-20 15:08:51.340611 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-20 15:08:52.126942 | noble | changed 2025-10-20 15:08:52.133064 | 2025-10-20 15:08:52.133160 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-20 15:08:52.901233 | noble | changed 2025-10-20 15:08:52.907298 | 2025-10-20 15:08:52.907385 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-20 15:08:52.941884 | noble | skipping: Conditional result was False 2025-10-20 15:08:52.949181 | 2025-10-20 15:08:52.949273 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-20 15:08:53.345963 | noble -> localhost | changed 2025-10-20 15:08:53.356689 | 2025-10-20 15:08:53.356776 | TASK [add-build-sshkey : Add back temp key] 2025-10-20 15:08:53.679178 | noble -> localhost | Identity added: /var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/work/98f7e050467843e7883662d07b8efa50_id_rsa (zuul-build-sshkey) 2025-10-20 15:08:53.679424 | noble -> localhost | ok: Runtime: 0:00:00.010097 2025-10-20 15:08:53.686372 | 2025-10-20 15:08:53.686472 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-20 15:08:54.165401 | noble | ok 2025-10-20 15:08:54.173310 | 2025-10-20 15:08:54.173446 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-20 15:08:54.197859 | noble | skipping: Conditional result was False 2025-10-20 15:08:54.276197 | 2025-10-20 15:08:54.276349 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-20 15:08:54.740053 | noble | ok 2025-10-20 15:08:54.756563 | 2025-10-20 15:08:54.756724 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-20 15:08:54.803751 | noble | ok 2025-10-20 15:08:54.812880 | 2025-10-20 15:08:54.813042 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-20 15:08:55.123091 | noble -> localhost | ok 2025-10-20 15:08:55.132848 | 2025-10-20 15:08:55.132997 | TASK [validate-host : Collect information about the host] 2025-10-20 15:08:56.602414 | noble | ok 2025-10-20 15:08:56.618649 | 2025-10-20 15:08:56.618785 | TASK [validate-host : Sanitize hostname] 2025-10-20 15:08:56.684643 | noble | ok 2025-10-20 15:08:56.690663 | 2025-10-20 15:08:56.690779 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-20 15:08:57.304723 | noble -> localhost | changed 2025-10-20 15:08:57.313043 | 2025-10-20 15:08:57.313177 | TASK [validate-host : Collect information about zuul worker] 2025-10-20 15:08:57.788007 | noble | ok 2025-10-20 15:08:57.795543 | 2025-10-20 15:08:57.795678 | TASK [validate-host : Write out all zuul information for each host] 2025-10-20 15:08:58.436392 | noble -> localhost | changed 2025-10-20 15:08:58.447468 | 2025-10-20 15:08:58.447596 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-20 15:08:58.768897 | noble | ok 2025-10-20 15:08:58.779196 | 2025-10-20 15:08:58.779339 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-20 15:08:59.720517 | noble | changed: 2025-10-20 15:08:59.720726 | noble | cd+++++++++ src/ 2025-10-20 15:08:59.720762 | noble | cd+++++++++ src/github.com/ 2025-10-20 15:08:59.720787 | noble | cd+++++++++ src/github.com/osism/ 2025-10-20 15:08:59.720808 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-20 15:08:59.720829 | noble | localhost | ok: "/var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/work/logs" 2025-10-20 15:22:53.979655 | noble -> localhost | changed: "/var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/work/artifacts" 2025-10-20 15:22:54.242285 | noble -> localhost | changed: "/var/lib/zuul/builds/98f7e050467843e7883662d07b8efa50/work/docs" 2025-10-20 15:22:54.257333 | 2025-10-20 15:22:54.257467 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-20 15:22:55.564490 | noble | changed: 2025-10-20 15:22:55.564772 | noble | .d..t...... ./ 2025-10-20 15:22:55.564823 | noble | >f+++++++++ osism-node.log 2025-10-20 15:22:55.564889 | noble | changed: All items complete 2025-10-20 15:22:55.564933 | 2025-10-20 15:22:56.220550 | noble | changed: .d..t...... ./ 2025-10-20 15:22:56.887527 | noble | changed: .d..t...... ./ 2025-10-20 15:22:56.912090 | 2025-10-20 15:22:56.912227 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-20 15:22:56.949664 | noble | skipping: Conditional result was False 2025-10-20 15:22:56.952596 | noble | skipping: Conditional result was False 2025-10-20 15:22:56.976771 | 2025-10-20 15:22:56.976916 | PLAY RECAP 2025-10-20 15:22:56.976989 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-10-20 15:22:56.977027 | 2025-10-20 15:22:57.099696 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-20 15:22:57.100636 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-20 15:22:57.852664 | 2025-10-20 15:22:57.852811 | PLAY [Base post] 2025-10-20 15:22:57.867105 | 2025-10-20 15:22:57.867228 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-20 15:22:58.527811 | noble | changed 2025-10-20 15:22:58.537827 | 2025-10-20 15:22:58.537960 | PLAY RECAP 2025-10-20 15:22:58.538059 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-20 15:22:58.538136 | 2025-10-20 15:22:58.657477 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-20 15:22:58.658448 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-20 15:22:59.429074 | 2025-10-20 15:22:59.429248 | PLAY [Base post-logs] 2025-10-20 15:22:59.440241 | 2025-10-20 15:22:59.440374 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-20 15:22:59.874629 | localhost | changed 2025-10-20 15:22:59.892642 | 2025-10-20 15:22:59.892821 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-20 15:22:59.929146 | localhost | ok 2025-10-20 15:22:59.932580 | 2025-10-20 15:22:59.932674 | TASK [Set zuul-log-path fact] 2025-10-20 15:22:59.947625 | localhost | ok 2025-10-20 15:22:59.955349 | 2025-10-20 15:22:59.955452 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-20 15:22:59.980073 | localhost | ok 2025-10-20 15:22:59.983076 | 2025-10-20 15:22:59.983173 | TASK [upload-logs : Create log directories] 2025-10-20 15:23:00.459441 | localhost | changed 2025-10-20 15:23:00.464670 | 2025-10-20 15:23:00.464834 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-20 15:23:00.955996 | localhost -> localhost | ok: Runtime: 0:00:00.007220 2025-10-20 15:23:00.962636 | 2025-10-20 15:23:00.962758 | TASK [upload-logs : Upload logs to log server] 2025-10-20 15:23:01.504718 | localhost | Output suppressed because no_log was given 2025-10-20 15:23:01.506724 | 2025-10-20 15:23:01.506855 | LOOP [upload-logs : Compress console log and json output] 2025-10-20 15:23:01.559195 | localhost | skipping: Conditional result was False 2025-10-20 15:23:01.564135 | localhost | skipping: Conditional result was False 2025-10-20 15:23:01.575628 | 2025-10-20 15:23:01.575810 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-20 15:23:01.620536 | localhost | skipping: Conditional result was False 2025-10-20 15:23:01.621225 | 2025-10-20 15:23:01.624720 | localhost | skipping: Conditional result was False 2025-10-20 15:23:01.638223 | 2025-10-20 15:23:01.638436 | LOOP [upload-logs : Upload console log and json output]