2025-12-09 19:03:49.318372 | Job console starting 2025-12-09 19:03:49.375881 | Updating git repos 2025-12-09 19:03:49.441683 | Cloning repos into workspace 2025-12-09 19:03:49.544580 | Restoring repo states 2025-12-09 19:03:49.564585 | Merging changes 2025-12-09 19:03:50.321891 | Checking out repos 2025-12-09 19:03:50.395811 | Preparing playbooks 2025-12-09 19:03:51.449092 | Running Ansible setup 2025-12-09 19:03:58.387974 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-09 19:03:59.736452 | 2025-12-09 19:03:59.736680 | PLAY [Base pre] 2025-12-09 19:03:59.787205 | 2025-12-09 19:03:59.787366 | TASK [Setup log path fact] 2025-12-09 19:03:59.819324 | debian-bookworm | ok 2025-12-09 19:03:59.840006 | 2025-12-09 19:03:59.840405 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-09 19:03:59.905210 | debian-bookworm | ok 2025-12-09 19:03:59.915561 | 2025-12-09 19:03:59.915749 | TASK [emit-job-header : Print job information] 2025-12-09 19:04:00.029020 | # Job Information 2025-12-09 19:04:00.029374 | Ansible Version: 2.16.14 2025-12-09 19:04:00.029427 | Job: python-black 2025-12-09 19:04:00.029465 | Pipeline: check 2025-12-09 19:04:00.029493 | Executor: 521e9411259a 2025-12-09 19:04:00.029516 | Triggered by: https://github.com/osism/testbed/pull/2806 2025-12-09 19:04:00.029540 | Event ID: c661d720-d531-11f0-80d6-fde886aef6b5 2025-12-09 19:04:00.045437 | 2025-12-09 19:04:00.045590 | LOOP [emit-job-header : Print node information] 2025-12-09 19:04:00.175212 | debian-bookworm | ok: 2025-12-09 19:04:00.175413 | debian-bookworm | # Node Information 2025-12-09 19:04:00.175448 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-09 19:04:00.175474 | debian-bookworm | Hostname: debian 2025-12-09 19:04:00.175497 | debian-bookworm | Username: zuul 2025-12-09 19:04:00.175519 | debian-bookworm | Distro: Debian 12.12 2025-12-09 19:04:00.175543 | debian-bookworm | Provider: regiocloud-a 2025-12-09 19:04:00.175565 | debian-bookworm | Region: 2025-12-09 19:04:00.175586 | debian-bookworm | Label: debian-bookworm 2025-12-09 19:04:00.175606 | debian-bookworm | Product Name: OpenStack Nova 2025-12-09 19:04:00.175643 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1f:23a 2025-12-09 19:04:00.194198 | 2025-12-09 19:04:00.194335 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-09 19:04:00.784822 | debian-bookworm -> localhost | changed 2025-12-09 19:04:00.794262 | 2025-12-09 19:04:00.794426 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-09 19:04:02.171830 | debian-bookworm -> localhost | changed 2025-12-09 19:04:02.196494 | 2025-12-09 19:04:02.196760 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-09 19:04:02.707384 | debian-bookworm -> localhost | ok 2025-12-09 19:04:02.714542 | 2025-12-09 19:04:02.714730 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-09 19:04:02.770743 | debian-bookworm | ok 2025-12-09 19:04:02.816237 | debian-bookworm | included: /var/lib/zuul/builds/865abd8bf821413da8b8e5249dc96de8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-09 19:04:02.832491 | 2025-12-09 19:04:02.832661 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-09 19:04:04.533300 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-09 19:04:04.533541 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/865abd8bf821413da8b8e5249dc96de8/work/865abd8bf821413da8b8e5249dc96de8_id_rsa 2025-12-09 19:04:04.533582 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/865abd8bf821413da8b8e5249dc96de8/work/865abd8bf821413da8b8e5249dc96de8_id_rsa.pub 2025-12-09 19:04:04.533609 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-09 19:04:04.533667 | debian-bookworm -> localhost | SHA256:jncP6QyNn04T2hnw5U9NU65ZBXPwQ0/kK8v6v4d4/xU zuul-build-sshkey 2025-12-09 19:04:04.533697 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-09 19:04:04.533740 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-09 19:04:04.533764 | debian-bookworm -> localhost | | +=*| 2025-12-09 19:04:04.533787 | debian-bookworm -> localhost | | .B+| 2025-12-09 19:04:04.533808 | debian-bookworm -> localhost | | . . oB| 2025-12-09 19:04:04.533829 | debian-bookworm -> localhost | | o o *+| 2025-12-09 19:04:04.533849 | debian-bookworm -> localhost | | S + ..+E.| 2025-12-09 19:04:04.533877 | debian-bookworm -> localhost | | o = =.oo .| 2025-12-09 19:04:04.533898 | debian-bookworm -> localhost | | . * X +...| 2025-12-09 19:04:04.533918 | debian-bookworm -> localhost | | . B =o o o| 2025-12-09 19:04:04.533939 | debian-bookworm -> localhost | | .*.oo.+*| 2025-12-09 19:04:04.533960 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-09 19:04:04.534014 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.929354 2025-12-09 19:04:04.540929 | 2025-12-09 19:04:04.541067 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-09 19:04:04.587935 | debian-bookworm | ok 2025-12-09 19:04:04.598322 | debian-bookworm | included: /var/lib/zuul/builds/865abd8bf821413da8b8e5249dc96de8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-09 19:04:04.607985 | 2025-12-09 19:04:04.608111 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-09 19:04:04.632273 | debian-bookworm | skipping: Conditional result was False 2025-12-09 19:04:04.639512 | 2025-12-09 19:04:04.639691 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-09 19:04:05.347377 | debian-bookworm | changed 2025-12-09 19:04:05.353325 | 2025-12-09 19:04:05.353446 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-09 19:04:05.613154 | debian-bookworm | ok 2025-12-09 19:04:05.623740 | 2025-12-09 19:04:05.623906 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-09 19:04:06.277326 | debian-bookworm | changed 2025-12-09 19:04:06.287531 | 2025-12-09 19:04:06.287737 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-09 19:04:06.950880 | debian-bookworm | changed 2025-12-09 19:04:06.958874 | 2025-12-09 19:04:06.959012 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-09 19:04:06.983094 | debian-bookworm | skipping: Conditional result was False 2025-12-09 19:04:06.989508 | 2025-12-09 19:04:06.989689 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-09 19:04:07.520700 | debian-bookworm -> localhost | changed 2025-12-09 19:04:07.533771 | 2025-12-09 19:04:07.533912 | TASK [add-build-sshkey : Add back temp key] 2025-12-09 19:04:07.937860 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/865abd8bf821413da8b8e5249dc96de8/work/865abd8bf821413da8b8e5249dc96de8_id_rsa (zuul-build-sshkey) 2025-12-09 19:04:07.938357 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012469 2025-12-09 19:04:07.954344 | 2025-12-09 19:04:07.954510 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-09 19:04:08.372825 | debian-bookworm | ok 2025-12-09 19:04:08.379912 | 2025-12-09 19:04:08.380058 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-09 19:04:08.415627 | debian-bookworm | skipping: Conditional result was False 2025-12-09 19:04:08.432567 | 2025-12-09 19:04:08.432733 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-09 19:04:08.907464 | debian-bookworm | ok 2025-12-09 19:04:08.923770 | 2025-12-09 19:04:08.923914 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-09 19:04:08.964092 | debian-bookworm | ok 2025-12-09 19:04:08.969409 | 2025-12-09 19:04:08.969527 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-09 19:04:09.267297 | debian-bookworm -> localhost | ok 2025-12-09 19:04:09.274037 | 2025-12-09 19:04:09.274154 | TASK [validate-host : Collect information about the host] 2025-12-09 19:04:10.549510 | debian-bookworm | ok 2025-12-09 19:04:10.569671 | 2025-12-09 19:04:10.569812 | TASK [validate-host : Sanitize hostname] 2025-12-09 19:04:10.686163 | debian-bookworm | ok 2025-12-09 19:04:10.728813 | 2025-12-09 19:04:10.728951 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-09 19:04:11.514287 | debian-bookworm -> localhost | changed 2025-12-09 19:04:11.524533 | 2025-12-09 19:04:11.524714 | TASK [validate-host : Collect information about zuul worker] 2025-12-09 19:04:11.967803 | debian-bookworm | ok 2025-12-09 19:04:11.972950 | 2025-12-09 19:04:11.973079 | TASK [validate-host : Write out all zuul information for each host] 2025-12-09 19:04:12.772134 | debian-bookworm -> localhost | changed 2025-12-09 19:04:12.786436 | 2025-12-09 19:04:12.786586 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-09 19:04:13.068943 | debian-bookworm | ok 2025-12-09 19:04:13.079678 | 2025-12-09 19:04:13.079846 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-09 19:04:14.339389 | debian-bookworm | changed: 2025-12-09 19:04:14.339768 | debian-bookworm | cd+++++++++ src/ 2025-12-09 19:04:14.339810 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-09 19:04:14.339835 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-09 19:04:14.339857 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-12-09 19:04:14.339878 | debian-bookworm | localhost | ok: Runtime: 0:00:00.012470 2025-12-09 19:04:44.418720 | 2025-12-09 19:04:44.418865 | TASK [upload-logs : Upload logs to log server] 2025-12-09 19:04:45.107632 | localhost | Output suppressed because no_log was given 2025-12-09 19:04:45.109396 | 2025-12-09 19:04:45.109487 | LOOP [upload-logs : Compress console log and json output] 2025-12-09 19:04:45.175816 | localhost | skipping: Conditional result was False 2025-12-09 19:04:45.181458 | localhost | skipping: Conditional result was False 2025-12-09 19:04:45.185286 | 2025-12-09 19:04:45.185381 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-09 19:04:45.228555 | localhost | skipping: Conditional result was False 2025-12-09 19:04:45.228812 | 2025-12-09 19:04:45.232493 | localhost | skipping: Conditional result was False 2025-12-09 19:04:45.242171 | 2025-12-09 19:04:45.242273 | LOOP [upload-logs : Upload console log and json output]