2025-09-19 16:05:23.866705 | Job console starting 2025-09-19 16:05:23.881192 | Updating git repos 2025-09-19 16:05:23.916537 | Cloning repos into workspace 2025-09-19 16:05:23.967653 | Restoring repo states 2025-09-19 16:05:23.972098 | Merging changes 2025-09-19 16:05:24.471748 | Checking out repos 2025-09-19 16:05:24.562862 | Preparing playbooks 2025-09-19 16:05:25.273250 | Running Ansible setup 2025-09-19 16:05:29.729137 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-19 16:05:30.503567 | 2025-09-19 16:05:30.503850 | PLAY [Base pre] 2025-09-19 16:05:30.540506 | 2025-09-19 16:05:30.540748 | TASK [Setup log path fact] 2025-09-19 16:05:30.568871 | debian-bookworm | ok 2025-09-19 16:05:30.583186 | 2025-09-19 16:05:30.583314 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-19 16:05:30.611922 | debian-bookworm | ok 2025-09-19 16:05:30.621157 | 2025-09-19 16:05:30.621254 | TASK [emit-job-header : Print job information] 2025-09-19 16:05:30.670028 | # Job Information 2025-09-19 16:05:30.670184 | Ansible Version: 2.16.14 2025-09-19 16:05:30.670218 | Job: python-black 2025-09-19 16:05:30.670249 | Pipeline: check 2025-09-19 16:05:30.670271 | Executor: 521e9411259a 2025-09-19 16:05:30.670291 | Triggered by: https://github.com/osism/testbed/pull/2768 2025-09-19 16:05:30.670312 | Event ID: 6e479240-9572-11f0-8730-14502eca6946 2025-09-19 16:05:30.675061 | 2025-09-19 16:05:30.675161 | LOOP [emit-job-header : Print node information] 2025-09-19 16:05:30.770442 | debian-bookworm | ok: 2025-09-19 16:05:30.770639 | debian-bookworm | # Node Information 2025-09-19 16:05:30.770718 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-19 16:05:30.770751 | debian-bookworm | Hostname: debian 2025-09-19 16:05:30.770774 | debian-bookworm | Username: zuul 2025-09-19 16:05:30.770795 | debian-bookworm | Distro: Debian 12.12 2025-09-19 16:05:30.770818 | debian-bookworm | Provider: regiocloud-a 2025-09-19 16:05:30.770854 | debian-bookworm | Region: 2025-09-19 16:05:30.770876 | debian-bookworm | Label: debian-bookworm 2025-09-19 16:05:30.770896 | debian-bookworm | Product Name: OpenStack Nova 2025-09-19 16:05:30.770916 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe96:a664 2025-09-19 16:05:30.791011 | 2025-09-19 16:05:30.791125 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-19 16:05:31.393999 | debian-bookworm -> localhost | changed 2025-09-19 16:05:31.401439 | 2025-09-19 16:05:31.401563 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-19 16:05:32.524260 | debian-bookworm -> localhost | changed 2025-09-19 16:05:32.535173 | 2025-09-19 16:05:32.535292 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-19 16:05:32.835778 | debian-bookworm -> localhost | ok 2025-09-19 16:05:32.842136 | 2025-09-19 16:05:32.842263 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-19 16:05:32.871137 | debian-bookworm | ok 2025-09-19 16:05:32.888480 | debian-bookworm | included: /var/lib/zuul/builds/22a9a73cb79d4faa8456222904902722/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-19 16:05:32.894549 | 2025-09-19 16:05:32.894650 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-19 16:05:33.919469 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-19 16:05:33.919744 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/22a9a73cb79d4faa8456222904902722/work/22a9a73cb79d4faa8456222904902722_id_rsa 2025-09-19 16:05:33.919787 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/22a9a73cb79d4faa8456222904902722/work/22a9a73cb79d4faa8456222904902722_id_rsa.pub 2025-09-19 16:05:33.919815 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-19 16:05:33.919841 | debian-bookworm -> localhost | SHA256:eLSVE6CcwxpWZsgMxOei78X9XdyHQjRCL2Lj1hg8oz0 zuul-build-sshkey 2025-09-19 16:05:33.919864 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-19 16:05:33.919900 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-19 16:05:33.919923 | debian-bookworm -> localhost | | oo+ .+ ..o | 2025-09-19 16:05:33.919945 | debian-bookworm -> localhost | | . =* + . + | 2025-09-19 16:05:33.919965 | debian-bookworm -> localhost | | oo * X * + | 2025-09-19 16:05:33.919985 | debian-bookworm -> localhost | | ...o O @ = . | 2025-09-19 16:05:33.920005 | debian-bookworm -> localhost | | . .. o E . . | 2025-09-19 16:05:33.920030 | debian-bookworm -> localhost | |. . . o . o . . | 2025-09-19 16:05:33.920050 | debian-bookworm -> localhost | | . o . + o .| 2025-09-19 16:05:33.920070 | debian-bookworm -> localhost | | .. . . . . . | 2025-09-19 16:05:33.920091 | debian-bookworm -> localhost | | .. . . | 2025-09-19 16:05:33.920112 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-19 16:05:33.920169 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.565367 2025-09-19 16:05:33.927018 | 2025-09-19 16:05:33.927126 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-19 16:05:33.955231 | debian-bookworm | ok 2025-09-19 16:05:33.964761 | debian-bookworm | included: /var/lib/zuul/builds/22a9a73cb79d4faa8456222904902722/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-19 16:05:33.973841 | 2025-09-19 16:05:33.973940 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-19 16:05:33.987775 | debian-bookworm | skipping: Conditional result was False 2025-09-19 16:05:33.993417 | 2025-09-19 16:05:33.993519 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-19 16:05:34.614140 | debian-bookworm | changed 2025-09-19 16:05:34.621939 | 2025-09-19 16:05:34.622049 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-19 16:05:34.882875 | debian-bookworm | ok 2025-09-19 16:05:34.892235 | 2025-09-19 16:05:34.892376 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-19 16:05:35.551439 | debian-bookworm | changed 2025-09-19 16:05:35.560484 | 2025-09-19 16:05:35.560621 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-19 16:05:36.221959 | debian-bookworm | changed 2025-09-19 16:05:36.227605 | 2025-09-19 16:05:36.227733 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-19 16:05:36.251101 | debian-bookworm | skipping: Conditional result was False 2025-09-19 16:05:36.257463 | 2025-09-19 16:05:36.257565 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-19 16:05:36.694606 | debian-bookworm -> localhost | changed 2025-09-19 16:05:36.708218 | 2025-09-19 16:05:36.708345 | TASK [add-build-sshkey : Add back temp key] 2025-09-19 16:05:37.046171 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/22a9a73cb79d4faa8456222904902722/work/22a9a73cb79d4faa8456222904902722_id_rsa (zuul-build-sshkey) 2025-09-19 16:05:37.046499 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021005 2025-09-19 16:05:37.056847 | 2025-09-19 16:05:37.056957 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-19 16:05:37.464108 | debian-bookworm | ok 2025-09-19 16:05:37.470373 | 2025-09-19 16:05:37.470503 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-19 16:05:37.504661 | debian-bookworm | skipping: Conditional result was False 2025-09-19 16:05:37.519485 | 2025-09-19 16:05:37.519646 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-19 16:05:37.949087 | debian-bookworm | ok 2025-09-19 16:05:37.961433 | 2025-09-19 16:05:37.961564 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-19 16:05:37.991213 | debian-bookworm | ok 2025-09-19 16:05:37.996326 | 2025-09-19 16:05:37.996430 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-19 16:05:38.328535 | debian-bookworm -> localhost | ok 2025-09-19 16:05:38.335884 | 2025-09-19 16:05:38.335998 | TASK [validate-host : Collect information about the host] 2025-09-19 16:05:39.442084 | debian-bookworm | ok 2025-09-19 16:05:39.453415 | 2025-09-19 16:05:39.453540 | TASK [validate-host : Sanitize hostname] 2025-09-19 16:05:39.555915 | debian-bookworm | ok 2025-09-19 16:05:39.597187 | 2025-09-19 16:05:39.597313 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-19 16:05:40.129866 | debian-bookworm -> localhost | changed 2025-09-19 16:05:40.135875 | 2025-09-19 16:05:40.135996 | TASK [validate-host : Collect information about zuul worker] 2025-09-19 16:05:40.551106 | debian-bookworm | ok 2025-09-19 16:05:40.556011 | 2025-09-19 16:05:40.556139 | TASK [validate-host : Write out all zuul information for each host] 2025-09-19 16:05:41.080321 | debian-bookworm -> localhost | changed 2025-09-19 16:05:41.089988 | 2025-09-19 16:05:41.090107 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-19 16:05:41.390418 | debian-bookworm | ok 2025-09-19 16:05:41.400246 | 2025-09-19 16:05:41.400387 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-19 16:05:42.623040 | debian-bookworm | changed: 2025-09-19 16:05:42.623264 | debian-bookworm | cd+++++++++ src/ 2025-09-19 16:05:42.623300 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-19 16:05:42.623323 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-19 16:05:42.623345 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-09-19 16:05:42.623365 | debian-bookworm | localhost | ok: Runtime: 0:00:00.005201 2025-09-19 16:06:07.393980 | 2025-09-19 16:06:07.394115 | TASK [upload-logs : Upload logs to log server] 2025-09-19 16:06:07.966284 | localhost | Output suppressed because no_log was given 2025-09-19 16:06:07.968271 | 2025-09-19 16:06:07.968385 | LOOP [upload-logs : Compress console log and json output] 2025-09-19 16:06:08.025574 | localhost | skipping: Conditional result was False 2025-09-19 16:06:08.030924 | localhost | skipping: Conditional result was False 2025-09-19 16:06:08.037850 | 2025-09-19 16:06:08.038063 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-19 16:06:08.082876 | localhost | skipping: Conditional result was False 2025-09-19 16:06:08.083290 | 2025-09-19 16:06:08.087501 | localhost | skipping: Conditional result was False 2025-09-19 16:06:08.097678 | 2025-09-19 16:06:08.097858 | LOOP [upload-logs : Upload console log and json output]