2025-05-28 16:25:36.996751 | Job console starting 2025-05-28 16:25:37.032411 | Updating git repos 2025-05-28 16:25:37.084083 | Cloning repos into workspace 2025-05-28 16:25:37.158784 | Restoring repo states 2025-05-28 16:25:37.169706 | Merging changes 2025-05-28 16:25:37.703224 | Checking out repos 2025-05-28 16:25:37.803387 | Preparing playbooks 2025-05-28 16:25:38.627503 | Running Ansible setup 2025-05-28 16:25:44.241833 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-28 16:25:45.857576 | 2025-05-28 16:25:45.857779 | PLAY [Base pre] 2025-05-28 16:25:45.884760 | 2025-05-28 16:25:45.885028 | TASK [Setup log path fact] 2025-05-28 16:25:45.922768 | debian-bookworm | ok 2025-05-28 16:25:45.947726 | 2025-05-28 16:25:45.948016 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-28 16:25:45.989451 | debian-bookworm | ok 2025-05-28 16:25:45.999629 | 2025-05-28 16:25:45.999762 | TASK [emit-job-header : Print job information] 2025-05-28 16:25:46.040618 | # Job Information 2025-05-28 16:25:46.040849 | Ansible Version: 2.16.14 2025-05-28 16:25:46.040888 | Job: python-black 2025-05-28 16:25:46.040920 | Pipeline: check 2025-05-28 16:25:46.040979 | Executor: 521e9411259a 2025-05-28 16:25:46.041002 | Triggered by: https://github.com/osism/testbed/pull/2654 2025-05-28 16:25:46.041025 | Event ID: 5e237320-3be0-11f0-8c42-782fa0596d9a 2025-05-28 16:25:46.046110 | 2025-05-28 16:25:46.046225 | LOOP [emit-job-header : Print node information] 2025-05-28 16:25:46.252756 | debian-bookworm | ok: 2025-05-28 16:25:46.253106 | debian-bookworm | # Node Information 2025-05-28 16:25:46.253149 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-05-28 16:25:46.253175 | debian-bookworm | Hostname: debian 2025-05-28 16:25:46.253198 | debian-bookworm | Username: zuul 2025-05-28 16:25:46.253219 | debian-bookworm | Distro: Debian 12.11 2025-05-28 16:25:46.253246 | debian-bookworm | Provider: regiocloud-a 2025-05-28 16:25:46.253268 | debian-bookworm | Region: 2025-05-28 16:25:46.253288 | debian-bookworm | Label: debian-bookworm 2025-05-28 16:25:46.253308 | debian-bookworm | Product Name: OpenStack Nova 2025-05-28 16:25:46.253328 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe16:83ab 2025-05-28 16:25:46.272448 | 2025-05-28 16:25:46.273574 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-28 16:25:46.930176 | debian-bookworm -> localhost | changed 2025-05-28 16:25:46.938867 | 2025-05-28 16:25:46.939062 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-28 16:25:48.645176 | debian-bookworm -> localhost | changed 2025-05-28 16:25:48.661863 | 2025-05-28 16:25:48.662543 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-28 16:25:49.196766 | debian-bookworm -> localhost | ok 2025-05-28 16:25:49.203629 | 2025-05-28 16:25:49.203756 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-28 16:25:49.256356 | debian-bookworm | ok 2025-05-28 16:25:49.284593 | debian-bookworm | included: /var/lib/zuul/builds/1957856517d84486b0bdb845d82b74be/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-28 16:25:49.292582 | 2025-05-28 16:25:49.292727 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-28 16:25:50.847675 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-05-28 16:25:50.848826 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1957856517d84486b0bdb845d82b74be/work/1957856517d84486b0bdb845d82b74be_id_rsa 2025-05-28 16:25:50.849261 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1957856517d84486b0bdb845d82b74be/work/1957856517d84486b0bdb845d82b74be_id_rsa.pub 2025-05-28 16:25:50.849639 | debian-bookworm -> localhost | The key fingerprint is: 2025-05-28 16:25:50.849853 | debian-bookworm -> localhost | SHA256:v3BQrWEqzMdAnhP5Fo2IQNT0x7zqcTgJ+jmqipt96B8 zuul-build-sshkey 2025-05-28 16:25:50.850108 | debian-bookworm -> localhost | The key's randomart image is: 2025-05-28 16:25:50.850496 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-05-28 16:25:50.850560 | debian-bookworm -> localhost | | o++..oo o | 2025-05-28 16:25:50.851173 | debian-bookworm -> localhost | | o+o=o .. | 2025-05-28 16:25:50.851385 | debian-bookworm -> localhost | | *.+.+ . | 2025-05-28 16:25:50.851434 | debian-bookworm -> localhost | | o =o= o | 2025-05-28 16:25:50.851710 | debian-bookworm -> localhost | | . +.S . | 2025-05-28 16:25:50.851922 | debian-bookworm -> localhost | | . . * o | 2025-05-28 16:25:50.852216 | debian-bookworm -> localhost | | ..E * o o | 2025-05-28 16:25:50.852437 | debian-bookworm -> localhost | |.o..o+ + o . | 2025-05-28 16:25:50.852483 | debian-bookworm -> localhost | |B++++.. . | 2025-05-28 16:25:50.852522 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-05-28 16:25:50.852959 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.627545 2025-05-28 16:25:50.885488 | 2025-05-28 16:25:50.885925 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-28 16:25:50.960663 | debian-bookworm | ok 2025-05-28 16:25:51.002284 | debian-bookworm | included: /var/lib/zuul/builds/1957856517d84486b0bdb845d82b74be/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-28 16:25:51.029122 | 2025-05-28 16:25:51.030476 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-28 16:25:51.087599 | debian-bookworm | skipping: Conditional result was False 2025-05-28 16:25:51.094598 | 2025-05-28 16:25:51.094732 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-28 16:25:51.881093 | debian-bookworm | changed 2025-05-28 16:25:51.887799 | 2025-05-28 16:25:51.887914 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-28 16:25:52.176320 | debian-bookworm | ok 2025-05-28 16:25:52.184976 | 2025-05-28 16:25:52.185147 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-28 16:25:52.907712 | debian-bookworm | changed 2025-05-28 16:25:52.921249 | 2025-05-28 16:25:52.921724 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-28 16:25:53.613434 | debian-bookworm | changed 2025-05-28 16:25:53.630388 | 2025-05-28 16:25:53.630713 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-28 16:25:53.656545 | debian-bookworm | skipping: Conditional result was False 2025-05-28 16:25:53.671566 | 2025-05-28 16:25:53.671732 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-28 16:25:54.332064 | debian-bookworm -> localhost | changed 2025-05-28 16:25:54.344332 | 2025-05-28 16:25:54.344469 | TASK [add-build-sshkey : Add back temp key] 2025-05-28 16:25:54.930361 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1957856517d84486b0bdb845d82b74be/work/1957856517d84486b0bdb845d82b74be_id_rsa (zuul-build-sshkey) 2025-05-28 16:25:54.930649 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020212 2025-05-28 16:25:54.951212 | 2025-05-28 16:25:54.951395 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-28 16:25:55.408491 | debian-bookworm | ok 2025-05-28 16:25:55.414718 | 2025-05-28 16:25:55.414879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-28 16:25:55.439705 | debian-bookworm | skipping: Conditional result was False 2025-05-28 16:25:55.451986 | 2025-05-28 16:25:55.452128 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-28 16:25:55.959092 | debian-bookworm | ok 2025-05-28 16:25:55.977321 | 2025-05-28 16:25:55.977506 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-28 16:25:56.008425 | debian-bookworm | ok 2025-05-28 16:25:56.020315 | 2025-05-28 16:25:56.020585 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-28 16:25:56.336448 | debian-bookworm -> localhost | ok 2025-05-28 16:25:56.343885 | 2025-05-28 16:25:56.344101 | TASK [validate-host : Collect information about the host] 2025-05-28 16:25:57.545136 | debian-bookworm | ok 2025-05-28 16:25:57.559519 | 2025-05-28 16:25:57.559668 | TASK [validate-host : Sanitize hostname] 2025-05-28 16:25:57.717256 | debian-bookworm | ok 2025-05-28 16:25:57.773693 | 2025-05-28 16:25:57.773871 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-28 16:25:58.381078 | debian-bookworm -> localhost | changed 2025-05-28 16:25:58.388039 | 2025-05-28 16:25:58.388165 | TASK [validate-host : Collect information about zuul worker] 2025-05-28 16:25:58.833475 | debian-bookworm | ok 2025-05-28 16:25:58.844914 | 2025-05-28 16:25:58.845158 | TASK [validate-host : Write out all zuul information for each host] 2025-05-28 16:25:59.470104 | debian-bookworm -> localhost | changed 2025-05-28 16:25:59.483595 | 2025-05-28 16:25:59.487187 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-28 16:25:59.765942 | debian-bookworm | ok 2025-05-28 16:25:59.776990 | 2025-05-28 16:25:59.777144 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-28 16:26:00.979469 | debian-bookworm | changed: 2025-05-28 16:26:00.979721 | debian-bookworm | cd+++++++++ src/ 2025-05-28 16:26:00.979757 | debian-bookworm | cd+++++++++ src/github.com/ 2025-05-28 16:26:00.979781 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-05-28 16:26:00.979803 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-05-28 16:26:00.979823 | debian-bookworm | localhost | ok: Runtime: 0:00:00.005620 2025-05-28 16:26:26.772884 | 2025-05-28 16:26:26.773061 | TASK [upload-logs : Upload logs to log server] 2025-05-28 16:26:27.415275 | localhost | Output suppressed because no_log was given 2025-05-28 16:26:27.419632 | 2025-05-28 16:26:27.419762 | LOOP [upload-logs : Compress console log and json output] 2025-05-28 16:26:27.478751 | localhost | skipping: Conditional result was False 2025-05-28 16:26:27.485065 | localhost | skipping: Conditional result was False 2025-05-28 16:26:27.490020 | 2025-05-28 16:26:27.490197 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-28 16:26:27.550112 | localhost | skipping: Conditional result was False 2025-05-28 16:26:27.550466 | 2025-05-28 16:26:27.555646 | localhost | skipping: Conditional result was False 2025-05-28 16:26:27.573230 | 2025-05-28 16:26:27.573451 | LOOP [upload-logs : Upload console log and json output]