2025-12-01 11:30:37.876561 | Job console starting 2025-12-01 11:30:37.917994 | Updating git repos 2025-12-01 11:30:37.981618 | Cloning repos into workspace 2025-12-01 11:30:38.041933 | Restoring repo states 2025-12-01 11:30:38.057332 | Merging changes 2025-12-01 11:30:38.689732 | Checking out repos 2025-12-01 11:30:38.802791 | Preparing playbooks 2025-12-01 11:30:40.081747 | Running Ansible setup 2025-12-01 11:30:48.433614 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-01 11:30:50.061602 | 2025-12-01 11:30:50.061786 | PLAY [Base pre] 2025-12-01 11:30:50.098473 | 2025-12-01 11:30:50.098644 | TASK [Setup log path fact] 2025-12-01 11:30:50.160344 | debian-bookworm | ok 2025-12-01 11:30:50.226370 | 2025-12-01 11:30:50.226561 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-01 11:30:50.325492 | debian-bookworm | ok 2025-12-01 11:30:50.364056 | 2025-12-01 11:30:50.364198 | TASK [emit-job-header : Print job information] 2025-12-01 11:30:50.445017 | # Job Information 2025-12-01 11:30:50.445217 | Ansible Version: 2.16.14 2025-12-01 11:30:50.445317 | Job: python-black 2025-12-01 11:30:50.445791 | Pipeline: check 2025-12-01 11:30:50.445892 | Executor: 521e9411259a 2025-12-01 11:30:50.445945 | Triggered by: https://github.com/osism/python-osism/pull/1868 2025-12-01 11:30:50.446176 | Event ID: 22dc8e80-cea9-11f0-90f8-1b8cb47f3c22 2025-12-01 11:30:50.467912 | 2025-12-01 11:30:50.468431 | LOOP [emit-job-header : Print node information] 2025-12-01 11:30:50.728697 | debian-bookworm | ok: 2025-12-01 11:30:50.729026 | debian-bookworm | # Node Information 2025-12-01 11:30:50.729085 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-01 11:30:50.729126 | debian-bookworm | Hostname: debian 2025-12-01 11:30:50.729151 | debian-bookworm | Username: zuul 2025-12-01 11:30:50.729175 | debian-bookworm | Distro: Debian 12.12 2025-12-01 11:30:50.729219 | debian-bookworm | Provider: regiocloud-a 2025-12-01 11:30:50.729247 | debian-bookworm | Region: 2025-12-01 11:30:50.729270 | debian-bookworm | Label: debian-bookworm 2025-12-01 11:30:50.729298 | debian-bookworm | Product Name: OpenStack Nova 2025-12-01 11:30:50.729330 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7c:1468 2025-12-01 11:30:50.747495 | 2025-12-01 11:30:50.747646 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-01 11:30:51.776304 | debian-bookworm -> localhost | changed 2025-12-01 11:30:51.784340 | 2025-12-01 11:30:51.784517 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-01 11:30:53.806959 | debian-bookworm -> localhost | changed 2025-12-01 11:30:53.824195 | 2025-12-01 11:30:53.824305 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-01 11:30:54.258243 | debian-bookworm -> localhost | ok 2025-12-01 11:30:54.264482 | 2025-12-01 11:30:54.264577 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-01 11:30:54.293030 | debian-bookworm | ok 2025-12-01 11:30:54.322746 | debian-bookworm | included: /var/lib/zuul/builds/1bd80cbf60114410af94e1c03a04926a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-01 11:30:54.329343 | 2025-12-01 11:30:54.329443 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-01 11:30:56.887475 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-01 11:30:56.887684 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1bd80cbf60114410af94e1c03a04926a/work/1bd80cbf60114410af94e1c03a04926a_id_rsa 2025-12-01 11:30:56.887723 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1bd80cbf60114410af94e1c03a04926a/work/1bd80cbf60114410af94e1c03a04926a_id_rsa.pub 2025-12-01 11:30:56.887751 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-01 11:30:56.887776 | debian-bookworm -> localhost | SHA256:2j0kispAuSXHSwaUuzM2W9IoWR1G4HFDMngMw1QbQzQ zuul-build-sshkey 2025-12-01 11:30:56.887799 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-01 11:30:56.887854 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-01 11:30:56.887880 | debian-bookworm -> localhost | |=*XE= | 2025-12-01 11:30:56.887903 | debian-bookworm -> localhost | |o=o=B. | 2025-12-01 11:30:56.887923 | debian-bookworm -> localhost | | oo+ . | 2025-12-01 11:30:56.887944 | debian-bookworm -> localhost | | .= . | 2025-12-01 11:30:56.887971 | debian-bookworm -> localhost | | *+* S . | 2025-12-01 11:30:56.887995 | debian-bookworm -> localhost | |+OBo.. + + | 2025-12-01 11:30:56.888015 | debian-bookworm -> localhost | |+.B.. o . o | 2025-12-01 11:30:56.888035 | debian-bookworm -> localhost | | + . . | 2025-12-01 11:30:56.888056 | debian-bookworm -> localhost | | o | 2025-12-01 11:30:56.888076 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-01 11:30:56.888128 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.521760 2025-12-01 11:30:56.894233 | 2025-12-01 11:30:56.894326 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-01 11:30:56.912323 | debian-bookworm | ok 2025-12-01 11:30:56.921343 | debian-bookworm | included: /var/lib/zuul/builds/1bd80cbf60114410af94e1c03a04926a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-01 11:30:56.930366 | 2025-12-01 11:30:56.930466 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-01 11:30:56.943879 | debian-bookworm | skipping: Conditional result was False 2025-12-01 11:30:56.962224 | 2025-12-01 11:30:56.962321 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-01 11:30:57.587486 | debian-bookworm | changed 2025-12-01 11:30:57.592801 | 2025-12-01 11:30:57.592906 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-01 11:30:57.843349 | debian-bookworm | ok 2025-12-01 11:30:57.851959 | 2025-12-01 11:30:57.852090 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-01 11:30:58.484509 | debian-bookworm | changed 2025-12-01 11:30:58.494098 | 2025-12-01 11:30:58.494223 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-01 11:30:59.111864 | debian-bookworm | changed 2025-12-01 11:30:59.126468 | 2025-12-01 11:30:59.126581 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-01 11:30:59.169951 | debian-bookworm | skipping: Conditional result was False 2025-12-01 11:30:59.175878 | 2025-12-01 11:30:59.175985 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-01 11:30:59.829324 | debian-bookworm -> localhost | changed 2025-12-01 11:30:59.851788 | 2025-12-01 11:30:59.851946 | TASK [add-build-sshkey : Add back temp key] 2025-12-01 11:31:00.350588 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1bd80cbf60114410af94e1c03a04926a/work/1bd80cbf60114410af94e1c03a04926a_id_rsa (zuul-build-sshkey) 2025-12-01 11:31:00.350810 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016312 2025-12-01 11:31:00.358046 | 2025-12-01 11:31:00.358137 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-01 11:31:00.761767 | debian-bookworm | ok 2025-12-01 11:31:00.768098 | 2025-12-01 11:31:00.768221 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-01 11:31:00.803468 | debian-bookworm | skipping: Conditional result was False 2025-12-01 11:31:00.815590 | 2025-12-01 11:31:00.815724 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-01 11:31:01.279237 | debian-bookworm | ok 2025-12-01 11:31:01.320231 | 2025-12-01 11:31:01.320394 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-01 11:31:01.360889 | debian-bookworm | ok 2025-12-01 11:31:01.366669 | 2025-12-01 11:31:01.366786 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-01 11:31:01.788502 | debian-bookworm -> localhost | ok 2025-12-01 11:31:01.796758 | 2025-12-01 11:31:01.796916 | TASK [validate-host : Collect information about the host] 2025-12-01 11:31:02.974015 | debian-bookworm | ok 2025-12-01 11:31:02.985211 | 2025-12-01 11:31:02.985343 | TASK [validate-host : Sanitize hostname] 2025-12-01 11:31:03.096470 | debian-bookworm | ok 2025-12-01 11:31:03.144227 | 2025-12-01 11:31:03.144363 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-01 11:31:04.055799 | debian-bookworm -> localhost | changed 2025-12-01 11:31:04.061866 | 2025-12-01 11:31:04.062001 | TASK [validate-host : Collect information about zuul worker] 2025-12-01 11:31:04.565991 | debian-bookworm | ok 2025-12-01 11:31:04.571545 | 2025-12-01 11:31:04.571742 | TASK [validate-host : Write out all zuul information for each host] 2025-12-01 11:31:05.528820 | debian-bookworm -> localhost | changed 2025-12-01 11:31:05.549699 | 2025-12-01 11:31:05.549862 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-01 11:31:05.825948 | debian-bookworm | ok 2025-12-01 11:31:05.839952 | 2025-12-01 11:31:05.840091 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-01 11:31:07.296632 | debian-bookworm | changed: 2025-12-01 11:31:07.296959 | debian-bookworm | cd+++++++++ src/ 2025-12-01 11:31:07.297001 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-01 11:31:07.297026 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-01 11:31:07.297048 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-01 11:31:07.297069 | debian-bookworm | Containerfile 2025-12-01 11:31:07.297198 | debian-bookworm | Containerfile 2025-12-01 11:31:07.357266 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006949 2025-12-01 11:31:35.176812 | 2025-12-01 11:31:35.176975 | TASK [upload-logs : Upload logs to log server] 2025-12-01 11:31:35.754681 | localhost | Output suppressed because no_log was given 2025-12-01 11:31:35.756775 | 2025-12-01 11:31:35.756913 | LOOP [upload-logs : Compress console log and json output] 2025-12-01 11:31:35.824086 | localhost | skipping: Conditional result was False 2025-12-01 11:31:35.831807 | localhost | skipping: Conditional result was False 2025-12-01 11:31:35.841419 | 2025-12-01 11:31:35.841668 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-01 11:31:35.892264 | localhost | skipping: Conditional result was False 2025-12-01 11:31:35.892954 | 2025-12-01 11:31:35.896294 | localhost | skipping: Conditional result was False 2025-12-01 11:31:35.905249 | 2025-12-01 11:31:35.905512 | LOOP [upload-logs : Upload console log and json output]