2025-12-09 12:38:00.679845 | Job console starting 2025-12-09 12:38:00.693180 | Updating git repos 2025-12-09 12:38:00.749945 | Cloning repos into workspace 2025-12-09 12:38:00.804540 | Restoring repo states 2025-12-09 12:38:00.821664 | Merging changes 2025-12-09 12:38:01.357448 | Checking out repos 2025-12-09 12:38:01.439259 | Preparing playbooks 2025-12-09 12:38:02.284104 | Running Ansible setup 2025-12-09 12:38:07.210357 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-09 12:38:08.051661 | 2025-12-09 12:38:08.053024 | PLAY [Base pre] 2025-12-09 12:38:08.090310 | 2025-12-09 12:38:08.090477 | TASK [Setup log path fact] 2025-12-09 12:38:08.120370 | debian-bookworm | ok 2025-12-09 12:38:08.150861 | 2025-12-09 12:38:08.151042 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-09 12:38:08.196147 | debian-bookworm | ok 2025-12-09 12:38:08.215032 | 2025-12-09 12:38:08.215179 | TASK [emit-job-header : Print job information] 2025-12-09 12:38:08.254610 | # Job Information 2025-12-09 12:38:08.254815 | Ansible Version: 2.16.14 2025-12-09 12:38:08.254875 | Job: python-black 2025-12-09 12:38:08.254909 | Pipeline: check 2025-12-09 12:38:08.254933 | Executor: 521e9411259a 2025-12-09 12:38:08.254954 | Triggered by: https://github.com/osism/testbed/pull/2802 2025-12-09 12:38:08.254977 | Event ID: e04f5260-d4fb-11f0-89a4-10b965cff206 2025-12-09 12:38:08.260405 | 2025-12-09 12:38:08.260532 | LOOP [emit-job-header : Print node information] 2025-12-09 12:38:08.387479 | debian-bookworm | ok: 2025-12-09 12:38:08.387786 | debian-bookworm | # Node Information 2025-12-09 12:38:08.387836 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-09 12:38:08.387870 | debian-bookworm | Hostname: debian 2025-12-09 12:38:08.387898 | debian-bookworm | Username: zuul 2025-12-09 12:38:08.387925 | debian-bookworm | Distro: Debian 12.12 2025-12-09 12:38:08.387956 | debian-bookworm | Provider: regiocloud-a 2025-12-09 12:38:08.387984 | debian-bookworm | Region: 2025-12-09 12:38:08.388012 | debian-bookworm | Label: debian-bookworm 2025-12-09 12:38:08.388039 | debian-bookworm | Product Name: OpenStack Nova 2025-12-09 12:38:08.388064 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fecf:6c9d 2025-12-09 12:38:08.403449 | 2025-12-09 12:38:08.403617 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-09 12:38:08.929933 | debian-bookworm -> localhost | changed 2025-12-09 12:38:08.937524 | 2025-12-09 12:38:08.937695 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-09 12:38:10.161664 | debian-bookworm -> localhost | changed 2025-12-09 12:38:10.173772 | 2025-12-09 12:38:10.173906 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-09 12:38:10.485777 | debian-bookworm -> localhost | ok 2025-12-09 12:38:10.498236 | 2025-12-09 12:38:10.498408 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-09 12:38:10.528825 | debian-bookworm | ok 2025-12-09 12:38:10.550099 | debian-bookworm | included: /var/lib/zuul/builds/dee50357371143389b55a195046660de/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-09 12:38:10.556733 | 2025-12-09 12:38:10.556855 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-09 12:38:11.603093 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-09 12:38:11.603340 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/dee50357371143389b55a195046660de/work/dee50357371143389b55a195046660de_id_rsa 2025-12-09 12:38:11.603381 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/dee50357371143389b55a195046660de/work/dee50357371143389b55a195046660de_id_rsa.pub 2025-12-09 12:38:11.603409 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-09 12:38:11.603436 | debian-bookworm -> localhost | SHA256:zHU5Vvid2XqoiPx5OYhgEzJ3obdMPOi05KjjM2ERGLQ zuul-build-sshkey 2025-12-09 12:38:11.603459 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-09 12:38:11.603509 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-09 12:38:11.603534 | debian-bookworm -> localhost | |o+ .. | 2025-12-09 12:38:11.603568 | debian-bookworm -> localhost | |. o . .o | 2025-12-09 12:38:11.603590 | debian-bookworm -> localhost | | E . + .. =. .+| 2025-12-09 12:38:11.603610 | debian-bookworm -> localhost | | . o Bo*. o ..oo| 2025-12-09 12:38:11.603631 | debian-bookworm -> localhost | | . X BSo o | 2025-12-09 12:38:11.603654 | debian-bookworm -> localhost | | o . B o o .| 2025-12-09 12:38:11.603674 | debian-bookworm -> localhost | | . o . + o o o . | 2025-12-09 12:38:11.603694 | debian-bookworm -> localhost | | = + o.= | 2025-12-09 12:38:11.603715 | debian-bookworm -> localhost | | ..+ .o. . | 2025-12-09 12:38:11.603735 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-09 12:38:11.603793 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.513900 2025-12-09 12:38:11.610463 | 2025-12-09 12:38:11.610626 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-09 12:38:11.639432 | debian-bookworm | ok 2025-12-09 12:38:11.655381 | debian-bookworm | included: /var/lib/zuul/builds/dee50357371143389b55a195046660de/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-09 12:38:11.666932 | 2025-12-09 12:38:11.667430 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-09 12:38:11.701772 | debian-bookworm | skipping: Conditional result was False 2025-12-09 12:38:11.708379 | 2025-12-09 12:38:11.708518 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-09 12:38:12.366194 | debian-bookworm | changed 2025-12-09 12:38:12.373682 | 2025-12-09 12:38:12.373836 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-09 12:38:12.646635 | debian-bookworm | ok 2025-12-09 12:38:12.656016 | 2025-12-09 12:38:12.656194 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-09 12:38:13.348448 | debian-bookworm | changed 2025-12-09 12:38:13.359680 | 2025-12-09 12:38:13.359816 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-09 12:38:14.047231 | debian-bookworm | changed 2025-12-09 12:38:14.057419 | 2025-12-09 12:38:14.057638 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-09 12:38:14.092228 | debian-bookworm | skipping: Conditional result was False 2025-12-09 12:38:14.101058 | 2025-12-09 12:38:14.101204 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-09 12:38:14.616739 | debian-bookworm -> localhost | changed 2025-12-09 12:38:14.628119 | 2025-12-09 12:38:14.628271 | TASK [add-build-sshkey : Add back temp key] 2025-12-09 12:38:14.976625 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/dee50357371143389b55a195046660de/work/dee50357371143389b55a195046660de_id_rsa (zuul-build-sshkey) 2025-12-09 12:38:14.976893 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016146 2025-12-09 12:38:14.985294 | 2025-12-09 12:38:14.985427 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-09 12:38:15.424548 | debian-bookworm | ok 2025-12-09 12:38:15.430239 | 2025-12-09 12:38:15.430376 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-09 12:38:15.465116 | debian-bookworm | skipping: Conditional result was False 2025-12-09 12:38:15.481188 | 2025-12-09 12:38:15.481354 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-09 12:38:15.932319 | debian-bookworm | ok 2025-12-09 12:38:15.948439 | 2025-12-09 12:38:15.948603 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-09 12:38:15.992802 | debian-bookworm | ok 2025-12-09 12:38:16.011464 | 2025-12-09 12:38:16.011674 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-09 12:38:16.362653 | debian-bookworm -> localhost | ok 2025-12-09 12:38:16.370547 | 2025-12-09 12:38:16.370701 | TASK [validate-host : Collect information about the host] 2025-12-09 12:38:17.555129 | debian-bookworm | ok 2025-12-09 12:38:17.567221 | 2025-12-09 12:38:17.567353 | TASK [validate-host : Sanitize hostname] 2025-12-09 12:38:17.687835 | debian-bookworm | ok 2025-12-09 12:38:17.730736 | 2025-12-09 12:38:17.730896 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-09 12:38:18.356188 | debian-bookworm -> localhost | changed 2025-12-09 12:38:18.362877 | 2025-12-09 12:38:18.363007 | TASK [validate-host : Collect information about zuul worker] 2025-12-09 12:38:18.829744 | debian-bookworm | ok 2025-12-09 12:38:18.835890 | 2025-12-09 12:38:18.836005 | TASK [validate-host : Write out all zuul information for each host] 2025-12-09 12:38:19.487251 | debian-bookworm -> localhost | changed 2025-12-09 12:38:19.503070 | 2025-12-09 12:38:19.503207 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-09 12:38:19.787922 | debian-bookworm | ok 2025-12-09 12:38:19.804794 | 2025-12-09 12:38:19.804949 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-09 12:38:21.087892 | debian-bookworm | changed: 2025-12-09 12:38:21.088277 | debian-bookworm | cd+++++++++ src/ 2025-12-09 12:38:21.088351 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-09 12:38:21.088401 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-09 12:38:21.088446 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-12-09 12:38:21.088490 | debian-bookworm | localhost | ok: Runtime: 0:00:00.011237 2025-12-09 12:38:47.165189 | 2025-12-09 12:38:47.165320 | TASK [upload-logs : Upload logs to log server] 2025-12-09 12:38:47.785277 | localhost | Output suppressed because no_log was given 2025-12-09 12:38:47.787360 | 2025-12-09 12:38:47.787481 | LOOP [upload-logs : Compress console log and json output] 2025-12-09 12:38:47.850400 | localhost | skipping: Conditional result was False 2025-12-09 12:38:47.858401 | localhost | skipping: Conditional result was False 2025-12-09 12:38:47.866378 | 2025-12-09 12:38:47.866511 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-09 12:38:47.914759 | localhost | skipping: Conditional result was False 2025-12-09 12:38:47.915109 | 2025-12-09 12:38:47.919994 | localhost | skipping: Conditional result was False 2025-12-09 12:38:47.924179 | 2025-12-09 12:38:47.924303 | LOOP [upload-logs : Upload console log and json output]