2025-08-15 10:33:42.086600 | Job console starting 2025-08-15 10:33:42.096802 | Updating git repos 2025-08-15 10:33:42.113362 | Cloning repos into workspace 2025-08-15 10:33:42.161182 | Restoring repo states 2025-08-15 10:33:42.171348 | Merging changes 2025-08-15 10:33:42.171368 | Checking out repos 2025-08-15 10:33:42.222670 | Preparing playbooks 2025-08-15 10:33:42.888203 | Running Ansible setup 2025-08-15 10:33:48.681852 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-15 10:33:49.412720 | 2025-08-15 10:33:49.412876 | PLAY [Base pre] 2025-08-15 10:33:49.430224 | 2025-08-15 10:33:49.430361 | TASK [Setup log path fact] 2025-08-15 10:33:49.449822 | noble | ok 2025-08-15 10:33:49.467359 | 2025-08-15 10:33:49.467509 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-15 10:33:49.497780 | noble | ok 2025-08-15 10:33:49.510122 | 2025-08-15 10:33:49.510245 | TASK [emit-job-header : Print job information] 2025-08-15 10:33:49.556268 | # Job Information 2025-08-15 10:33:49.556533 | Ansible Version: 2.16.14 2025-08-15 10:33:49.556592 | Job: openstack-ironic-images-publish-osism-node 2025-08-15 10:33:49.556646 | Pipeline: post 2025-08-15 10:33:49.556685 | Executor: 521e9411259a 2025-08-15 10:33:49.556719 | Triggered by: https://github.com/osism/openstack-ironic-images 2025-08-15 10:33:49.556757 | Event ID: e4a60fb224404ac7bd7cce69fe38a43f 2025-08-15 10:33:49.565864 | 2025-08-15 10:33:49.566069 | LOOP [emit-job-header : Print node information] 2025-08-15 10:33:49.696383 | noble | ok: 2025-08-15 10:33:49.696672 | noble | # Node Information 2025-08-15 10:33:49.696720 | noble | Inventory Hostname: noble 2025-08-15 10:33:49.696751 | noble | Hostname: ubuntu 2025-08-15 10:33:49.696778 | noble | Username: zuul 2025-08-15 10:33:49.696804 | noble | Distro: Ubuntu 24.04 2025-08-15 10:33:49.696833 | noble | Provider: regiocloud-a 2025-08-15 10:33:49.696858 | noble | Region: 2025-08-15 10:33:49.696884 | noble | Label: ubuntu-noble-uefi 2025-08-15 10:33:49.696908 | noble | Product Name: OpenStack Nova 2025-08-15 10:33:49.696981 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe10:f8e9 2025-08-15 10:33:49.726913 | 2025-08-15 10:33:49.727162 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-15 10:33:50.211812 | noble -> localhost | changed 2025-08-15 10:33:50.228969 | 2025-08-15 10:33:50.229128 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-15 10:33:51.330013 | noble -> localhost | changed 2025-08-15 10:33:51.356354 | 2025-08-15 10:33:51.356500 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-15 10:33:51.655627 | noble -> localhost | ok 2025-08-15 10:33:51.666354 | 2025-08-15 10:33:51.666510 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-15 10:33:51.688248 | noble | ok 2025-08-15 10:33:51.706685 | noble | included: /var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-15 10:33:51.714908 | 2025-08-15 10:33:51.715067 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-15 10:33:52.656078 | noble -> localhost | Generating public/private rsa key pair. 2025-08-15 10:33:52.656436 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/work/23afc43ecddb4a769004a7dcb739e27c_id_rsa 2025-08-15 10:33:52.656501 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/work/23afc43ecddb4a769004a7dcb739e27c_id_rsa.pub 2025-08-15 10:33:52.656544 | noble -> localhost | The key fingerprint is: 2025-08-15 10:33:52.656584 | noble -> localhost | SHA256:V8O1mpAWZwsvTMC3c59ZUj/RobKJNLE2R2IatxYcayI zuul-build-sshkey 2025-08-15 10:33:52.656620 | noble -> localhost | The key's randomart image is: 2025-08-15 10:33:52.656653 | noble -> localhost | +---[RSA 3072]----+ 2025-08-15 10:33:52.656686 | noble -> localhost | | ooO=.o ..o| 2025-08-15 10:33:52.656719 | noble -> localhost | | *+XO o.oo| 2025-08-15 10:33:52.656833 | noble -> localhost | | E o %*+*...o| 2025-08-15 10:33:52.656868 | noble -> localhost | | . *.B+=+..o| 2025-08-15 10:33:52.656901 | noble -> localhost | | S..=o. =.| 2025-08-15 10:33:52.656964 | noble -> localhost | | . + | 2025-08-15 10:33:52.656999 | noble -> localhost | | | 2025-08-15 10:33:52.657030 | noble -> localhost | | | 2025-08-15 10:33:52.657063 | noble -> localhost | | | 2025-08-15 10:33:52.657095 | noble -> localhost | +----[SHA256]-----+ 2025-08-15 10:33:52.657176 | noble -> localhost | ok: Runtime: 0:00:00.432746 2025-08-15 10:33:52.668678 | 2025-08-15 10:33:52.668816 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-15 10:33:52.701110 | noble | ok 2025-08-15 10:33:52.713943 | noble | included: /var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-15 10:33:52.723085 | 2025-08-15 10:33:52.723190 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-15 10:33:52.746523 | noble | skipping: Conditional result was False 2025-08-15 10:33:52.755497 | 2025-08-15 10:33:52.755616 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-15 10:33:53.399089 | noble | changed 2025-08-15 10:33:53.414113 | 2025-08-15 10:33:53.414245 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-15 10:33:53.696906 | noble | ok 2025-08-15 10:33:53.710208 | 2025-08-15 10:33:53.710349 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-15 10:33:54.510798 | noble | changed 2025-08-15 10:33:54.522389 | 2025-08-15 10:33:54.522660 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-15 10:33:55.306781 | noble | changed 2025-08-15 10:33:55.313343 | 2025-08-15 10:33:55.313445 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-15 10:33:55.347266 | noble | skipping: Conditional result was False 2025-08-15 10:33:55.354400 | 2025-08-15 10:33:55.354504 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-15 10:33:55.800038 | noble -> localhost | changed 2025-08-15 10:33:55.815581 | 2025-08-15 10:33:55.815709 | TASK [add-build-sshkey : Add back temp key] 2025-08-15 10:33:56.152837 | noble -> localhost | Identity added: /var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/work/23afc43ecddb4a769004a7dcb739e27c_id_rsa (zuul-build-sshkey) 2025-08-15 10:33:56.153116 | noble -> localhost | ok: Runtime: 0:00:00.020945 2025-08-15 10:33:56.160544 | 2025-08-15 10:33:56.160650 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-15 10:33:56.605007 | noble | ok 2025-08-15 10:33:56.612615 | 2025-08-15 10:33:56.612733 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-15 10:33:56.636731 | noble | skipping: Conditional result was False 2025-08-15 10:33:56.685675 | 2025-08-15 10:33:56.686016 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-15 10:33:57.140899 | noble | ok 2025-08-15 10:33:57.152210 | 2025-08-15 10:33:57.152331 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-15 10:33:57.184859 | noble | ok 2025-08-15 10:33:57.194314 | 2025-08-15 10:33:57.194428 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-15 10:33:57.505800 | noble -> localhost | ok 2025-08-15 10:33:57.522296 | 2025-08-15 10:33:57.522463 | TASK [validate-host : Collect information about the host] 2025-08-15 10:33:58.984596 | noble | ok 2025-08-15 10:33:59.001465 | 2025-08-15 10:33:59.001589 | TASK [validate-host : Sanitize hostname] 2025-08-15 10:33:59.068245 | noble | ok 2025-08-15 10:33:59.077156 | 2025-08-15 10:33:59.077299 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-15 10:33:59.678795 | noble -> localhost | changed 2025-08-15 10:33:59.685487 | 2025-08-15 10:33:59.685597 | TASK [validate-host : Collect information about zuul worker] 2025-08-15 10:34:00.153750 | noble | ok 2025-08-15 10:34:00.160607 | 2025-08-15 10:34:00.160735 | TASK [validate-host : Write out all zuul information for each host] 2025-08-15 10:34:00.700520 | noble -> localhost | changed 2025-08-15 10:34:00.711719 | 2025-08-15 10:34:00.711850 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-15 10:34:01.048460 | noble | ok 2025-08-15 10:34:01.057898 | 2025-08-15 10:34:01.058093 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-15 10:34:02.070403 | noble | changed: 2025-08-15 10:34:02.070624 | noble | cd+++++++++ src/ 2025-08-15 10:34:02.070659 | noble | cd+++++++++ src/github.com/ 2025-08-15 10:34:02.070684 | noble | cd+++++++++ src/github.com/osism/ 2025-08-15 10:34:02.070705 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-08-15 10:34:02.070725 | noble | localhost | ok: "/var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/work/logs" 2025-08-15 10:50:21.361063 | noble -> localhost | changed: "/var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/work/artifacts" 2025-08-15 10:50:21.618718 | noble -> localhost | changed: "/var/lib/zuul/builds/23afc43ecddb4a769004a7dcb739e27c/work/docs" 2025-08-15 10:50:21.644259 | 2025-08-15 10:50:21.644424 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-08-15 10:50:22.944484 | noble | changed: 2025-08-15 10:50:22.944790 | noble | .d..t...... ./ 2025-08-15 10:50:22.944846 | noble | >f+++++++++ osism-node.log 2025-08-15 10:50:22.944915 | noble | changed: All items complete 2025-08-15 10:50:22.944960 | 2025-08-15 10:50:23.609819 | noble | changed: .d..t...... ./ 2025-08-15 10:50:24.292813 | noble | changed: .d..t...... ./ 2025-08-15 10:50:24.323573 | 2025-08-15 10:50:24.323724 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-08-15 10:50:24.360016 | noble | skipping: Conditional result was False 2025-08-15 10:50:24.362797 | noble | skipping: Conditional result was False 2025-08-15 10:50:24.387584 | 2025-08-15 10:50:24.387708 | PLAY RECAP 2025-08-15 10:50:24.387793 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-08-15 10:50:24.387837 | 2025-08-15 10:50:24.509788 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-08-15 10:50:24.510873 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-15 10:50:25.249831 | 2025-08-15 10:50:25.249988 | PLAY [Base post] 2025-08-15 10:50:25.264673 | 2025-08-15 10:50:25.264800 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-08-15 10:50:25.891008 | noble | changed 2025-08-15 10:50:25.900706 | 2025-08-15 10:50:25.900820 | PLAY RECAP 2025-08-15 10:50:25.900895 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-08-15 10:50:25.900970 | 2025-08-15 10:50:26.012751 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-08-15 10:50:26.014993 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-08-15 10:50:26.787755 | 2025-08-15 10:50:26.787923 | PLAY [Base post-logs] 2025-08-15 10:50:26.798771 | 2025-08-15 10:50:26.798932 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-08-15 10:50:27.261865 | localhost | changed 2025-08-15 10:50:27.272123 | 2025-08-15 10:50:27.272309 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-08-15 10:50:27.308621 | localhost | ok 2025-08-15 10:50:27.312132 | 2025-08-15 10:50:27.312254 | TASK [Set zuul-log-path fact] 2025-08-15 10:50:27.327492 | localhost | ok 2025-08-15 10:50:27.335673 | 2025-08-15 10:50:27.335780 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-15 10:50:27.361416 | localhost | ok 2025-08-15 10:50:27.366705 | 2025-08-15 10:50:27.366870 | TASK [upload-logs : Create log directories] 2025-08-15 10:50:27.865360 | localhost | changed 2025-08-15 10:50:27.871082 | 2025-08-15 10:50:27.871315 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-08-15 10:50:28.363932 | localhost -> localhost | ok: Runtime: 0:00:00.004911 2025-08-15 10:50:28.368417 | 2025-08-15 10:50:28.368530 | TASK [upload-logs : Upload logs to log server] 2025-08-15 10:50:28.928447 | localhost | Output suppressed because no_log was given 2025-08-15 10:50:28.932756 | 2025-08-15 10:50:28.932936 | LOOP [upload-logs : Compress console log and json output] 2025-08-15 10:50:28.992445 | localhost | skipping: Conditional result was False 2025-08-15 10:50:28.997887 | localhost | skipping: Conditional result was False 2025-08-15 10:50:29.005050 | 2025-08-15 10:50:29.005295 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-15 10:50:29.054295 | localhost | skipping: Conditional result was False 2025-08-15 10:50:29.054789 | 2025-08-15 10:50:29.058620 | localhost | skipping: Conditional result was False 2025-08-15 10:50:29.074182 | 2025-08-15 10:50:29.074422 | LOOP [upload-logs : Upload console log and json output]