2025-11-04 16:32:43.416396 | Job console starting 2025-11-04 16:32:43.428402 | Updating git repos 2025-11-04 16:32:43.444669 | Cloning repos into workspace 2025-11-04 16:32:43.481796 | Restoring repo states 2025-11-04 16:32:43.487267 | Merging changes 2025-11-04 16:32:43.487287 | Checking out repos 2025-11-04 16:32:43.531125 | Preparing playbooks 2025-11-04 16:32:44.061818 | Running Ansible setup 2025-11-04 16:32:49.856018 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-04 16:32:50.585978 | 2025-11-04 16:32:50.586156 | PLAY [Base pre] 2025-11-04 16:32:50.603191 | 2025-11-04 16:32:50.603316 | TASK [Setup log path fact] 2025-11-04 16:32:50.633395 | noble | ok 2025-11-04 16:32:50.650889 | 2025-11-04 16:32:50.651020 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-04 16:32:50.698810 | noble | ok 2025-11-04 16:32:50.715622 | 2025-11-04 16:32:50.715741 | TASK [emit-job-header : Print job information] 2025-11-04 16:32:50.770869 | # Job Information 2025-11-04 16:32:50.771129 | Ansible Version: 2.16.14 2025-11-04 16:32:50.771188 | Job: openstack-ironic-images-publish-osism-node 2025-11-04 16:32:50.771246 | Pipeline: post 2025-11-04 16:32:50.771287 | Executor: 521e9411259a 2025-11-04 16:32:50.771324 | Triggered by: https://github.com/osism/openstack-ironic-images/commit/750e56b5be193722b442f08b4143e96753100169 2025-11-04 16:32:50.771362 | Event ID: 776df48e-b99b-11f0-9cf0-fb5849fcb887 2025-11-04 16:32:50.781149 | 2025-11-04 16:32:50.781278 | LOOP [emit-job-header : Print node information] 2025-11-04 16:32:50.908344 | noble | ok: 2025-11-04 16:32:50.908735 | noble | # Node Information 2025-11-04 16:32:50.908812 | noble | Inventory Hostname: noble 2025-11-04 16:32:50.908865 | noble | Hostname: ubuntu 2025-11-04 16:32:50.908911 | noble | Username: zuul 2025-11-04 16:32:50.908952 | noble | Distro: Ubuntu 24.04 2025-11-04 16:32:50.908999 | noble | Provider: regiocloud-a 2025-11-04 16:32:50.909042 | noble | Region: 2025-11-04 16:32:50.909109 | noble | Label: ubuntu-noble-uefi 2025-11-04 16:32:50.909159 | noble | Product Name: OpenStack Nova 2025-11-04 16:32:50.909222 | noble | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed5:88fd 2025-11-04 16:32:50.922298 | 2025-11-04 16:32:50.922415 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-04 16:32:51.382184 | noble -> localhost | changed 2025-11-04 16:32:51.390539 | 2025-11-04 16:32:51.390666 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-04 16:32:52.396416 | noble -> localhost | changed 2025-11-04 16:32:52.411028 | 2025-11-04 16:32:52.411180 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-04 16:32:52.689162 | noble -> localhost | ok 2025-11-04 16:32:52.702621 | 2025-11-04 16:32:52.702780 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-04 16:32:52.741230 | noble | ok 2025-11-04 16:32:52.763544 | noble | included: /var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-04 16:32:52.771475 | 2025-11-04 16:32:52.771569 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-04 16:32:54.731224 | noble -> localhost | Generating public/private rsa key pair. 2025-11-04 16:32:54.731787 | noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/work/6e7f267b05c844dd9ae361f87b98e262_id_rsa 2025-11-04 16:32:54.731903 | noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/work/6e7f267b05c844dd9ae361f87b98e262_id_rsa.pub 2025-11-04 16:32:54.731982 | noble -> localhost | The key fingerprint is: 2025-11-04 16:32:54.732077 | noble -> localhost | SHA256:9Ik5JzEFBFB2QoDptJwoXEkVuq6CTY+6FczqFq7RP4k zuul-build-sshkey 2025-11-04 16:32:54.732147 | noble -> localhost | The key's randomart image is: 2025-11-04 16:32:54.732210 | noble -> localhost | +---[RSA 3072]----+ 2025-11-04 16:32:54.732272 | noble -> localhost | | .+=BBo+.. | 2025-11-04 16:32:54.732355 | noble -> localhost | | +o.. o . | 2025-11-04 16:32:54.732418 | noble -> localhost | |.=.+ + | 2025-11-04 16:32:54.732475 | noble -> localhost | |o+= . . * . | 2025-11-04 16:32:54.732532 | noble -> localhost | |. +. S + | 2025-11-04 16:32:54.732603 | noble -> localhost | | +o. + | 2025-11-04 16:32:54.732663 | noble -> localhost | |=o+= . | 2025-11-04 16:32:54.732719 | noble -> localhost | |+=E.+ | 2025-11-04 16:32:54.732780 | noble -> localhost | |B= .. | 2025-11-04 16:32:54.732840 | noble -> localhost | +----[SHA256]-----+ 2025-11-04 16:32:54.732978 | noble -> localhost | ok: Runtime: 0:00:01.486848 2025-11-04 16:32:54.748439 | 2025-11-04 16:32:54.748600 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-04 16:32:54.785684 | noble | ok 2025-11-04 16:32:54.798948 | noble | included: /var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-04 16:32:54.808204 | 2025-11-04 16:32:54.808301 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-04 16:32:54.831850 | noble | skipping: Conditional result was False 2025-11-04 16:32:54.840967 | 2025-11-04 16:32:54.841114 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-04 16:32:55.476581 | noble | changed 2025-11-04 16:32:55.486041 | 2025-11-04 16:32:55.486206 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-04 16:32:55.857881 | noble | ok 2025-11-04 16:32:55.867242 | 2025-11-04 16:32:55.867367 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-04 16:32:56.667537 | noble | changed 2025-11-04 16:32:56.676037 | 2025-11-04 16:32:56.676205 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-04 16:32:57.492149 | noble | changed 2025-11-04 16:32:57.501660 | 2025-11-04 16:32:57.501787 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-04 16:32:57.528791 | noble | skipping: Conditional result was False 2025-11-04 16:32:57.540556 | 2025-11-04 16:32:57.540688 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-04 16:32:57.981539 | noble -> localhost | changed 2025-11-04 16:32:58.005833 | 2025-11-04 16:32:58.005968 | TASK [add-build-sshkey : Add back temp key] 2025-11-04 16:32:58.335660 | noble -> localhost | Identity added: /var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/work/6e7f267b05c844dd9ae361f87b98e262_id_rsa (zuul-build-sshkey) 2025-11-04 16:32:58.336224 | noble -> localhost | ok: Runtime: 0:00:00.010012 2025-11-04 16:32:58.351006 | 2025-11-04 16:32:58.351197 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-04 16:32:58.818613 | noble | ok 2025-11-04 16:32:58.828092 | 2025-11-04 16:32:58.828222 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-04 16:32:58.863336 | noble | skipping: Conditional result was False 2025-11-04 16:32:58.923617 | 2025-11-04 16:32:58.923747 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-04 16:32:59.378071 | noble | ok 2025-11-04 16:32:59.394393 | 2025-11-04 16:32:59.394535 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-04 16:32:59.438379 | noble | ok 2025-11-04 16:32:59.448099 | 2025-11-04 16:32:59.448217 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-04 16:32:59.779247 | noble -> localhost | ok 2025-11-04 16:32:59.786794 | 2025-11-04 16:32:59.786941 | TASK [validate-host : Collect information about the host] 2025-11-04 16:33:01.245024 | noble | ok 2025-11-04 16:33:01.260553 | 2025-11-04 16:33:01.260669 | TASK [validate-host : Sanitize hostname] 2025-11-04 16:33:01.319365 | noble | ok 2025-11-04 16:33:01.324758 | 2025-11-04 16:33:01.324860 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-04 16:33:01.877549 | noble -> localhost | changed 2025-11-04 16:33:01.891411 | 2025-11-04 16:33:01.891593 | TASK [validate-host : Collect information about zuul worker] 2025-11-04 16:33:02.361619 | noble | ok 2025-11-04 16:33:02.371366 | 2025-11-04 16:33:02.371509 | TASK [validate-host : Write out all zuul information for each host] 2025-11-04 16:33:02.902121 | noble -> localhost | changed 2025-11-04 16:33:02.912916 | 2025-11-04 16:33:02.913026 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-04 16:33:03.255575 | noble | ok 2025-11-04 16:33:03.261768 | 2025-11-04 16:33:03.261869 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-04 16:33:04.193236 | noble | changed: 2025-11-04 16:33:04.193522 | noble | cd+++++++++ src/ 2025-11-04 16:33:04.193581 | noble | cd+++++++++ src/github.com/ 2025-11-04 16:33:04.193632 | noble | cd+++++++++ src/github.com/osism/ 2025-11-04 16:33:04.193674 | noble | cd+++++++++ src/github.com/osism/openstack-ironic-images/ 2025-11-04 16:33:04.193713 | noble | localhost | ok: "/var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/work/logs" 2025-11-04 16:45:38.613126 | noble -> localhost | changed: "/var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/work/artifacts" 2025-11-04 16:45:38.877299 | noble -> localhost | changed: "/var/lib/zuul/builds/6e7f267b05c844dd9ae361f87b98e262/work/docs" 2025-11-04 16:45:38.903092 | 2025-11-04 16:45:38.903402 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-11-04 16:45:40.147176 | noble | changed: 2025-11-04 16:45:40.147564 | noble | .d..t...... ./ 2025-11-04 16:45:40.147642 | noble | >f+++++++++ osism-node.log 2025-11-04 16:45:40.147731 | noble | changed: All items complete 2025-11-04 16:45:40.147796 | 2025-11-04 16:45:40.795838 | noble | changed: .d..t...... ./ 2025-11-04 16:45:41.459174 | noble | changed: .d..t...... ./ 2025-11-04 16:45:41.488657 | 2025-11-04 16:45:41.488796 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-11-04 16:45:41.522788 | noble | skipping: Conditional result was False 2025-11-04 16:45:41.525612 | noble | skipping: Conditional result was False 2025-11-04 16:45:41.546715 | 2025-11-04 16:45:41.546821 | PLAY RECAP 2025-11-04 16:45:41.546913 | noble | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2025-11-04 16:45:41.546951 | 2025-11-04 16:45:41.659285 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2025-11-04 16:45:41.660182 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-04 16:45:42.355089 | 2025-11-04 16:45:42.355260 | PLAY [Base post] 2025-11-04 16:45:42.369599 | 2025-11-04 16:45:42.369744 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2025-11-04 16:45:42.955017 | noble | changed 2025-11-04 16:45:42.964619 | 2025-11-04 16:45:42.964732 | PLAY RECAP 2025-11-04 16:45:42.964856 | noble | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-11-04 16:45:42.964936 | 2025-11-04 16:45:43.075068 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2025-11-04 16:45:43.075986 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2025-11-04 16:45:43.833423 | 2025-11-04 16:45:43.833581 | PLAY [Base post-logs] 2025-11-04 16:45:43.844154 | 2025-11-04 16:45:43.844307 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-11-04 16:45:44.278049 | localhost | changed 2025-11-04 16:45:44.288010 | 2025-11-04 16:45:44.288142 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-11-04 16:45:44.323698 | localhost | ok 2025-11-04 16:45:44.326899 | 2025-11-04 16:45:44.326996 | TASK [Set zuul-log-path fact] 2025-11-04 16:45:44.341844 | localhost | ok 2025-11-04 16:45:44.349538 | 2025-11-04 16:45:44.349644 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-04 16:45:44.374790 | localhost | ok 2025-11-04 16:45:44.378795 | 2025-11-04 16:45:44.378973 | TASK [upload-logs : Create log directories] 2025-11-04 16:45:44.851669 | localhost | changed 2025-11-04 16:45:44.854443 | 2025-11-04 16:45:44.854548 | TASK [upload-logs : Ensure logs are readable before uploading] 2025-11-04 16:45:45.355584 | localhost -> localhost | ok: Runtime: 0:00:00.007119 2025-11-04 16:45:45.359848 | 2025-11-04 16:45:45.359967 | TASK [upload-logs : Upload logs to log server] 2025-11-04 16:45:45.901791 | localhost | Output suppressed because no_log was given 2025-11-04 16:45:45.903762 | 2025-11-04 16:45:45.903866 | LOOP [upload-logs : Compress console log and json output] 2025-11-04 16:45:45.959108 | localhost | skipping: Conditional result was False 2025-11-04 16:45:45.966049 | localhost | skipping: Conditional result was False 2025-11-04 16:45:45.970894 | 2025-11-04 16:45:45.971022 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-04 16:45:46.028523 | localhost | skipping: Conditional result was False 2025-11-04 16:45:46.029124 | 2025-11-04 16:45:46.032425 | localhost | skipping: Conditional result was False 2025-11-04 16:45:46.038129 | 2025-11-04 16:45:46.038309 | LOOP [upload-logs : Upload console log and json output]