2025-10-14 15:31:28.341362 | Job console starting 2025-10-14 15:31:28.382685 | Updating git repos 2025-10-14 15:31:28.425575 | Cloning repos into workspace 2025-10-14 15:31:28.467547 | Restoring repo states 2025-10-14 15:31:28.477849 | Merging changes 2025-10-14 15:31:29.034179 | Checking out repos 2025-10-14 15:31:29.085714 | Preparing playbooks 2025-10-14 15:31:30.007003 | Running Ansible setup 2025-10-14 15:31:37.588543 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-14 15:31:38.536869 | 2025-10-14 15:31:38.537024 | PLAY [Base pre] 2025-10-14 15:31:38.558688 | 2025-10-14 15:31:38.558826 | TASK [Setup log path fact] 2025-10-14 15:31:38.620144 | noble | ok 2025-10-14 15:31:38.654453 | 2025-10-14 15:31:38.654616 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 15:31:38.700089 | noble | ok 2025-10-14 15:31:38.716786 | 2025-10-14 15:31:38.716925 | TASK [emit-job-header : Print job information] 2025-10-14 15:31:38.788444 | # Job Information 2025-10-14 15:31:38.788629 | Ansible Version: 2.16.14 2025-10-14 15:31:38.788664 | Job: openstack-ironic-images-build-osism-node 2025-10-14 15:31:38.788696 | Pipeline: check 2025-10-14 15:31:38.788720 | Executor: 521e9411259a 2025-10-14 15:31:38.788740 | Triggered by: https://github.com/osism/openstack-ironic-images/pull/150 2025-10-14 15:31:38.788762 | Event ID: 6a4f6920-a912-11f0-8912-0474b4f00ead 2025-10-14 15:31:38.795821 | 2025-10-14 15:31:38.795933 | LOOP [emit-job-header : Print node information] 2025-10-14 15:31:39.031360 | noble | ok: 2025-10-14 15:31:39.031555 | noble | # Node Information 2025-10-14 15:31:39.031589 | noble | Inventory Hostname: noble 2025-10-14 15:31:39.031615 | noble | Hostname: ubuntu 2025-10-14 15:31:39.031638 | noble | Username: zuul 2025-10-14 15:31:39.031658 | noble | Distro: Ubuntu 24.04 2025-10-14 15:31:39.031682 | noble | Provider: regiocloud-a 2025-10-14 15:31:39.031703 | noble | Region: 2025-10-14 15:31:39.031725 | noble | Label: ubuntu-noble-uefi 2025-10-14 15:31:39.031744 | noble | Product Name: OpenStack Nova 2025-10-14 15:31:39.031764 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe95:d428 2025-10-14 15:31:39.043763 | 2025-10-14 15:31:39.043885 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-14 15:31:39.729109 | noble -> localhost | changed 2025-10-14 15:31:39.740836 | 2025-10-14 15:31:39.740962 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-14 15:31:41.098198 | noble -> localhost | changed 2025-10-14 15:31:41.109751 | 2025-10-14 15:31:41.109852 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-14 15:31:41.506357 | noble -> localhost | ok 2025-10-14 15:31:41.512491 | 2025-10-14 15:31:41.512581 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-14 15:31:41.551077 | noble | ok 2025-10-14 15:31:41.568634 | noble | included: /var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-14 15:31:41.585112 | 2025-10-14 15:31:41.585178 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-14 15:31:42.293412 | noble -> localhost | Generating public/private rsa key pair. 2025-10-14 15:31:42.293648 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/work/955ccae3565140e9aa16c60f8d6f4679_id_rsa 2025-10-14 15:31:42.293680 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/work/955ccae3565140e9aa16c60f8d6f4679_id_rsa.pub 2025-10-14 15:31:42.293702 | noble -> localhost | The key fingerprint is: 2025-10-14 15:31:42.293724 | noble -> localhost | SHA256:vtpVZOpzHJdf/d+zTatficqcBcXnZ2N6NPxwVMQvF9Y zuul-build-sshkey 2025-10-14 15:31:42.293743 | noble -> localhost | The key's randomart image is: 2025-10-14 15:31:42.293761 | noble -> localhost | +---[RSA 3072]----+ 2025-10-14 15:31:42.293778 | noble -> localhost | | o+| 2025-10-14 15:31:42.293806 | noble -> localhost | | . oE| 2025-10-14 15:31:42.293824 | noble -> localhost | | oo..+| 2025-10-14 15:31:42.293841 | noble -> localhost | | +. =o+| 2025-10-14 15:31:42.293858 | noble -> localhost | | S ..o +X*| 2025-10-14 15:31:42.293884 | noble -> localhost | | . . o.o==O| 2025-10-14 15:31:42.293902 | noble -> localhost | | . + o+ o*| 2025-10-14 15:31:42.293918 | noble -> localhost | | . ooo+ .o*| 2025-10-14 15:31:42.293936 | noble -> localhost | | ..o = .o+*| 2025-10-14 15:31:42.293952 | noble -> localhost | +----[SHA256]-----+ 2025-10-14 15:31:42.293996 | noble -> localhost | ok: Runtime: 0:00:00.215504 2025-10-14 15:31:42.300271 | 2025-10-14 15:31:42.300359 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-14 15:31:42.318206 | noble | ok 2025-10-14 15:31:42.326216 | noble | included: /var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-14 15:31:42.333620 | 2025-10-14 15:31:42.333692 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-14 15:31:42.356586 | noble | skipping: Conditional result was False 2025-10-14 15:31:42.362677 | 2025-10-14 15:31:42.362749 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-14 15:31:42.959887 | noble | changed 2025-10-14 15:31:42.968436 | 2025-10-14 15:31:42.968536 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-14 15:31:43.270471 | noble | ok 2025-10-14 15:31:43.276717 | 2025-10-14 15:31:43.276805 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-14 15:31:44.081723 | noble | changed 2025-10-14 15:31:44.095177 | 2025-10-14 15:31:44.095347 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-14 15:31:44.935648 | noble | changed 2025-10-14 15:31:44.944261 | 2025-10-14 15:31:44.944355 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-14 15:31:44.970410 | noble | skipping: Conditional result was False 2025-10-14 15:31:44.979004 | 2025-10-14 15:31:44.979119 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-14 15:31:45.389790 | noble -> localhost | changed 2025-10-14 15:31:45.411355 | 2025-10-14 15:31:45.411461 | TASK [add-build-sshkey : Add back temp key] 2025-10-14 15:31:45.698925 | noble -> localhost | Identity added: /var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/work/955ccae3565140e9aa16c60f8d6f4679_id_rsa (zuul-build-sshkey) 2025-10-14 15:31:45.699203 | noble -> localhost | ok: Runtime: 0:00:00.009750 2025-10-14 15:31:45.707100 | 2025-10-14 15:31:45.707190 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-14 15:31:46.155495 | noble | ok 2025-10-14 15:31:46.163865 | 2025-10-14 15:31:46.163967 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-14 15:31:46.197706 | noble | skipping: Conditional result was False 2025-10-14 15:31:46.240464 | 2025-10-14 15:31:46.240564 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-14 15:31:46.657575 | noble | ok 2025-10-14 15:31:46.666490 | 2025-10-14 15:31:46.666563 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-14 15:31:46.703301 | noble | ok 2025-10-14 15:31:46.709882 | 2025-10-14 15:31:46.709950 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-14 15:31:47.019996 | noble -> localhost | ok 2025-10-14 15:31:47.027549 | 2025-10-14 15:31:47.027645 | TASK [validate-host : Collect information about the host] 2025-10-14 15:31:48.422621 | noble | ok 2025-10-14 15:31:48.436398 | 2025-10-14 15:31:48.436504 | TASK [validate-host : Sanitize hostname] 2025-10-14 15:31:48.498769 | noble | ok 2025-10-14 15:31:48.505932 | 2025-10-14 15:31:48.506031 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-14 15:31:49.002476 | noble -> localhost | changed 2025-10-14 15:31:49.008507 | 2025-10-14 15:31:49.008601 | TASK [validate-host : Collect information about zuul worker] 2025-10-14 15:31:49.497719 | noble | ok 2025-10-14 15:31:49.504537 | 2025-10-14 15:31:49.504655 | TASK [validate-host : Write out all zuul information for each host] 2025-10-14 15:31:50.187969 | noble -> localhost | changed 2025-10-14 15:31:50.199927 | 2025-10-14 15:31:50.200047 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-14 15:31:50.524441 | noble | ok 2025-10-14 15:31:50.533024 | 2025-10-14 15:31:50.533166 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-14 15:31:51.390127 | noble | changed: 2025-10-14 15:31:51.390337 | noble | cd+++++++++ src/ 2025-10-14 15:31:51.390372 | noble | cd+++++++++ src/github.com/ 2025-10-14 15:31:51.390396 | noble | cd+++++++++ src/github.com/osism/ 2025-10-14 15:31:51.390417 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-10-14 15:31:51.390438 | noble | localhost | ok: "/var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/work/logs" 2025-10-14 15:43:59.909058 | noble -> localhost | changed: "/var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/work/artifacts" 2025-10-14 15:44:00.176621 | noble -> localhost | changed: "/var/lib/zuul/builds/955ccae3565140e9aa16c60f8d6f4679/work/docs" 2025-10-14 15:44:00.199674 | 2025-10-14 15:44:00.199824 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-14 15:44:01.500422 | noble | changed: 2025-10-14 15:44:01.500788 | noble | .d..t...... ./ 2025-10-14 15:44:01.500849 | noble | >f+++++++++ osism-node.log 2025-10-14 15:44:01.500930 | noble | changed: All items complete 2025-10-14 15:44:01.500979 | 2025-10-14 15:44:02.131453 | noble | changed: .d..t...... ./ 2025-10-14 15:44:02.766359 | noble | changed: .d..t...... ./ 2025-10-14 15:44:02.798171 | 2025-10-14 15:44:02.798311 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-14 15:44:03.312526 | noble -> localhost | ok: Item: artifacts Runtime: 0:00:00.008571 2025-10-14 15:44:03.576608 | noble -> localhost | ok: Item: docs Runtime: 0:00:00.009850 2025-10-14 15:44:03.590394 | 2025-10-14 15:44:03.590556 | PLAY RECAP 2025-10-14 15:44:03.590610 | noble | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2025-10-14 15:44:03.590634 | 2025-10-14 15:44:03.711533 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-10-14 15:44:03.713730 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 15:44:04.434272 | 2025-10-14 15:44:04.434422 | PLAY [Base post] 2025-10-14 15:44:04.448527 | 2025-10-14 15:44:04.448649 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-10-14 15:44:05.096424 | noble | changed 2025-10-14 15:44:05.105880 | 2025-10-14 15:44:05.105995 | PLAY RECAP 2025-10-14 15:44:05.106070 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-14 15:44:05.106148 | 2025-10-14 15:44:05.214468 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-10-14 15:44:05.216750 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-10-14 15:44:05.976762 | 2025-10-14 15:44:05.976937 | PLAY [Base post-logs] 2025-10-14 15:44:05.987549 | 2025-10-14 15:44:05.987699 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-14 15:44:06.460352 | localhost | changed 2025-10-14 15:44:06.475569 | 2025-10-14 15:44:06.475754 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-14 15:44:06.514035 | localhost | ok 2025-10-14 15:44:06.520370 | 2025-10-14 15:44:06.520538 | TASK [Set zuul-log-path fact] 2025-10-14 15:44:06.537424 | localhost | ok 2025-10-14 15:44:06.547339 | 2025-10-14 15:44:06.547458 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-14 15:44:06.572348 | localhost | ok 2025-10-14 15:44:06.576777 | 2025-10-14 15:44:06.576926 | TASK [upload-logs : Create log directories] 2025-10-14 15:44:07.071139 | localhost | changed 2025-10-14 15:44:07.074118 | 2025-10-14 15:44:07.074229 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-10-14 15:44:07.566930 | localhost -> localhost | ok: Runtime: 0:00:00.007008 2025-10-14 15:44:07.573354 | 2025-10-14 15:44:07.573474 | TASK [upload-logs : Upload logs to log server] 2025-10-14 15:44:08.168965 | localhost | Output suppressed because no_log was given 2025-10-14 15:44:08.172189 | 2025-10-14 15:44:08.172351 | LOOP [upload-logs : Compress console log and json output] 2025-10-14 15:44:08.227273 | localhost | skipping: Conditional result was False 2025-10-14 15:44:08.234813 | localhost | skipping: Conditional result was False 2025-10-14 15:44:08.244809 | 2025-10-14 15:44:08.245040 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-14 15:44:08.301084 | localhost | skipping: Conditional result was False 2025-10-14 15:44:08.301583 | 2025-10-14 15:44:08.305604 | localhost | skipping: Conditional result was False 2025-10-14 15:44:08.318326 | 2025-10-14 15:44:08.318572 | LOOP [upload-logs : Upload console log and json output]