2025-09-26 16:59:57.051480 | Job console starting 2025-09-26 16:59:57.081635 | Updating git repos 2025-09-26 16:59:57.121570 | Cloning repos into workspace 2025-09-26 16:59:57.186642 | Restoring repo states 2025-09-26 16:59:57.190558 | Merging changes 2025-09-26 16:59:57.723072 | Checking out repos 2025-09-26 16:59:57.814991 | Preparing playbooks 2025-09-26 16:59:58.481367 | Running Ansible setup 2025-09-26 17:00:02.914077 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-26 17:00:03.724607 | 2025-09-26 17:00:03.724764 | PLAY [Base pre] 2025-09-26 17:00:03.743680 | 2025-09-26 17:00:03.743836 | TASK [Setup log path fact] 2025-09-26 17:00:03.763426 | debian-bookworm | ok 2025-09-26 17:00:03.780873 | 2025-09-26 17:00:03.781060 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-26 17:00:03.841095 | debian-bookworm | ok 2025-09-26 17:00:03.852190 | 2025-09-26 17:00:03.852316 | TASK [emit-job-header : Print job information] 2025-09-26 17:00:03.913789 | # Job Information 2025-09-26 17:00:03.913979 | Ansible Version: 2.16.14 2025-09-26 17:00:03.914016 | Job: python-black 2025-09-26 17:00:03.914049 | Pipeline: check 2025-09-26 17:00:03.914072 | Executor: 521e9411259a 2025-09-26 17:00:03.914092 | Triggered by: https://github.com/osism/testbed/pull/2762 2025-09-26 17:00:03.914115 | Event ID: 358d7590-9afa-11f0-9977-07f6131f3342 2025-09-26 17:00:03.923643 | 2025-09-26 17:00:03.923843 | LOOP [emit-job-header : Print node information] 2025-09-26 17:00:04.053488 | debian-bookworm | ok: 2025-09-26 17:00:04.053755 | debian-bookworm | # Node Information 2025-09-26 17:00:04.053791 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-26 17:00:04.053817 | debian-bookworm | Hostname: debian 2025-09-26 17:00:04.053839 | debian-bookworm | Username: zuul 2025-09-26 17:00:04.053859 | debian-bookworm | Distro: Debian 12.12 2025-09-26 17:00:04.053883 | debian-bookworm | Provider: regiocloud-a 2025-09-26 17:00:04.053904 | debian-bookworm | Region: 2025-09-26 17:00:04.053924 | debian-bookworm | Label: debian-bookworm 2025-09-26 17:00:04.053982 | debian-bookworm | Product Name: OpenStack Nova 2025-09-26 17:00:04.054004 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe73:ed96 2025-09-26 17:00:04.072640 | 2025-09-26 17:00:04.072790 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-26 17:00:04.556002 | debian-bookworm -> localhost | changed 2025-09-26 17:00:04.563565 | 2025-09-26 17:00:04.563693 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-26 17:00:05.783992 | debian-bookworm -> localhost | changed 2025-09-26 17:00:05.800150 | 2025-09-26 17:00:05.800281 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-26 17:00:06.091733 | debian-bookworm -> localhost | ok 2025-09-26 17:00:06.098460 | 2025-09-26 17:00:06.098601 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-26 17:00:06.129970 | debian-bookworm | ok 2025-09-26 17:00:06.147760 | debian-bookworm | included: /var/lib/zuul/builds/02586b7a08f44ab3a9f1838c55e7d788/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-26 17:00:06.154592 | 2025-09-26 17:00:06.154706 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-26 17:00:07.293996 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-26 17:00:07.294237 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/02586b7a08f44ab3a9f1838c55e7d788/work/02586b7a08f44ab3a9f1838c55e7d788_id_rsa 2025-09-26 17:00:07.294277 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/02586b7a08f44ab3a9f1838c55e7d788/work/02586b7a08f44ab3a9f1838c55e7d788_id_rsa.pub 2025-09-26 17:00:07.294303 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-26 17:00:07.294328 | debian-bookworm -> localhost | SHA256:Bn/SsLUfkeXRDpjYPpUCdFDmv0fbFpEXBQsJqRpeDto zuul-build-sshkey 2025-09-26 17:00:07.294350 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-26 17:00:07.294385 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-26 17:00:07.294408 | debian-bookworm -> localhost | | .=O=++=o| 2025-09-26 17:00:07.294429 | debian-bookworm -> localhost | | o+*=+o+| 2025-09-26 17:00:07.294450 | debian-bookworm -> localhost | | . ....+oo=.| 2025-09-26 17:00:07.294469 | debian-bookworm -> localhost | | ooo= .oo +| 2025-09-26 17:00:07.294488 | debian-bookworm -> localhost | | + *S + .....| 2025-09-26 17:00:07.294514 | debian-bookworm -> localhost | | . E..o . . o+| 2025-09-26 17:00:07.294537 | debian-bookworm -> localhost | | . ..+| 2025-09-26 17:00:07.294558 | debian-bookworm -> localhost | | o | 2025-09-26 17:00:07.294579 | debian-bookworm -> localhost | | | 2025-09-26 17:00:07.294600 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-26 17:00:07.294653 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.655327 2025-09-26 17:00:07.301339 | 2025-09-26 17:00:07.301456 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-26 17:00:07.333601 | debian-bookworm | ok 2025-09-26 17:00:07.343741 | debian-bookworm | included: /var/lib/zuul/builds/02586b7a08f44ab3a9f1838c55e7d788/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-26 17:00:07.354207 | 2025-09-26 17:00:07.354312 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-26 17:00:07.378417 | debian-bookworm | skipping: Conditional result was False 2025-09-26 17:00:07.384284 | 2025-09-26 17:00:07.384389 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-26 17:00:08.031125 | debian-bookworm | changed 2025-09-26 17:00:08.039649 | 2025-09-26 17:00:08.039782 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-26 17:00:08.310280 | debian-bookworm | ok 2025-09-26 17:00:08.319483 | 2025-09-26 17:00:08.319640 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-26 17:00:09.011157 | debian-bookworm | changed 2025-09-26 17:00:09.020224 | 2025-09-26 17:00:09.020361 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-26 17:00:09.715288 | debian-bookworm | changed 2025-09-26 17:00:09.724254 | 2025-09-26 17:00:09.724400 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-26 17:00:09.749270 | debian-bookworm | skipping: Conditional result was False 2025-09-26 17:00:09.763567 | 2025-09-26 17:00:09.763742 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-26 17:00:10.229335 | debian-bookworm -> localhost | changed 2025-09-26 17:00:10.240938 | 2025-09-26 17:00:10.241097 | TASK [add-build-sshkey : Add back temp key] 2025-09-26 17:00:10.630672 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/02586b7a08f44ab3a9f1838c55e7d788/work/02586b7a08f44ab3a9f1838c55e7d788_id_rsa (zuul-build-sshkey) 2025-09-26 17:00:10.631031 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.037390 2025-09-26 17:00:10.639317 | 2025-09-26 17:00:10.639440 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-26 17:00:11.062060 | debian-bookworm | ok 2025-09-26 17:00:11.069716 | 2025-09-26 17:00:11.069858 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-26 17:00:11.105228 | debian-bookworm | skipping: Conditional result was False 2025-09-26 17:00:11.118784 | 2025-09-26 17:00:11.118923 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-26 17:00:11.589929 | debian-bookworm | ok 2025-09-26 17:00:11.600897 | 2025-09-26 17:00:11.601043 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-26 17:00:11.631366 | debian-bookworm | ok 2025-09-26 17:00:11.636471 | 2025-09-26 17:00:11.636584 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-26 17:00:11.955512 | debian-bookworm -> localhost | ok 2025-09-26 17:00:11.962179 | 2025-09-26 17:00:11.962298 | TASK [validate-host : Collect information about the host] 2025-09-26 17:00:13.102621 | debian-bookworm | ok 2025-09-26 17:00:13.117122 | 2025-09-26 17:00:13.117251 | TASK [validate-host : Sanitize hostname] 2025-09-26 17:00:13.218731 | debian-bookworm | ok 2025-09-26 17:00:13.261098 | 2025-09-26 17:00:13.261241 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-26 17:00:13.801635 | debian-bookworm -> localhost | changed 2025-09-26 17:00:13.807657 | 2025-09-26 17:00:13.807769 | TASK [validate-host : Collect information about zuul worker] 2025-09-26 17:00:14.231794 | debian-bookworm | ok 2025-09-26 17:00:14.237011 | 2025-09-26 17:00:14.237121 | TASK [validate-host : Write out all zuul information for each host] 2025-09-26 17:00:14.757548 | debian-bookworm -> localhost | changed 2025-09-26 17:00:14.771579 | 2025-09-26 17:00:14.771705 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-26 17:00:15.088962 | debian-bookworm | ok 2025-09-26 17:00:15.097663 | 2025-09-26 17:00:15.097792 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-26 17:00:16.396497 | debian-bookworm | changed: 2025-09-26 17:00:16.396755 | debian-bookworm | cd+++++++++ src/ 2025-09-26 17:00:16.396801 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-26 17:00:16.396830 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-26 17:00:16.396856 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-09-26 17:00:16.396880 | debian-bookworm | localhost | ok: Runtime: 0:00:00.003826 2025-09-26 17:00:41.305257 | 2025-09-26 17:00:41.305378 | TASK [upload-logs : Upload logs to log server] 2025-09-26 17:00:41.899700 | localhost | Output suppressed because no_log was given 2025-09-26 17:00:41.901588 | 2025-09-26 17:00:41.901695 | LOOP [upload-logs : Compress console log and json output] 2025-09-26 17:00:41.959897 | localhost | skipping: Conditional result was False 2025-09-26 17:00:41.966474 | localhost | skipping: Conditional result was False 2025-09-26 17:00:41.973140 | 2025-09-26 17:00:41.973302 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-26 17:00:42.020378 | localhost | skipping: Conditional result was False 2025-09-26 17:00:42.020619 | 2025-09-26 17:00:42.025496 | localhost | skipping: Conditional result was False 2025-09-26 17:00:42.035016 | 2025-09-26 17:00:42.035247 | LOOP [upload-logs : Upload console log and json output]