2025-09-25 16:40:11.873521 | Job console starting 2025-09-25 16:40:11.910431 | Updating git repos 2025-09-25 16:40:11.981351 | Cloning repos into workspace 2025-09-25 16:40:12.034293 | Restoring repo states 2025-09-25 16:40:12.059543 | Merging changes 2025-09-25 16:40:12.647958 | Checking out repos 2025-09-25 16:40:12.728321 | Preparing playbooks 2025-09-25 16:40:14.016116 | Running Ansible setup 2025-09-25 16:40:20.626752 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-25 16:40:22.470622 | 2025-09-25 16:40:22.470744 | PLAY [Base pre] 2025-09-25 16:40:22.500820 | 2025-09-25 16:40:22.500945 | TASK [Setup log path fact] 2025-09-25 16:40:22.527310 | debian-bookworm | ok 2025-09-25 16:40:22.581985 | 2025-09-25 16:40:22.582627 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-25 16:40:22.656481 | debian-bookworm | ok 2025-09-25 16:40:22.674603 | 2025-09-25 16:40:22.674715 | TASK [emit-job-header : Print job information] 2025-09-25 16:40:22.805133 | # Job Information 2025-09-25 16:40:22.805283 | Ansible Version: 2.16.14 2025-09-25 16:40:22.805320 | Job: python-black 2025-09-25 16:40:22.805352 | Pipeline: check 2025-09-25 16:40:22.805376 | Executor: 521e9411259a 2025-09-25 16:40:22.805398 | Triggered by: https://github.com/osism/python-osism/pull/1709 2025-09-25 16:40:22.805421 | Event ID: 4843a050-9a2e-11f0-9a54-431ea0b6132c 2025-09-25 16:40:22.810279 | 2025-09-25 16:40:22.810370 | LOOP [emit-job-header : Print node information] 2025-09-25 16:40:22.987139 | debian-bookworm | ok: 2025-09-25 16:40:22.987320 | debian-bookworm | # Node Information 2025-09-25 16:40:22.987388 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-25 16:40:22.987417 | debian-bookworm | Hostname: debian 2025-09-25 16:40:22.987441 | debian-bookworm | Username: zuul 2025-09-25 16:40:22.987461 | debian-bookworm | Distro: Debian 12.12 2025-09-25 16:40:22.987485 | debian-bookworm | Provider: regiocloud-a 2025-09-25 16:40:22.987506 | debian-bookworm | Region: 2025-09-25 16:40:22.987527 | debian-bookworm | Label: debian-bookworm 2025-09-25 16:40:22.987547 | debian-bookworm | Product Name: OpenStack Nova 2025-09-25 16:40:22.987567 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe73:752c 2025-09-25 16:40:23.007535 | 2025-09-25 16:40:23.007646 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-25 16:40:24.269171 | debian-bookworm -> localhost | changed 2025-09-25 16:40:24.277753 | 2025-09-25 16:40:24.277938 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-25 16:40:26.561560 | debian-bookworm -> localhost | changed 2025-09-25 16:40:26.571774 | 2025-09-25 16:40:26.571864 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-25 16:40:27.205728 | debian-bookworm -> localhost | ok 2025-09-25 16:40:27.211158 | 2025-09-25 16:40:27.211245 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-25 16:40:27.237925 | debian-bookworm | ok 2025-09-25 16:40:27.277383 | debian-bookworm | included: /var/lib/zuul/builds/c9c493a8ab4244d1924789dbabd6fa56/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-25 16:40:27.285542 | 2025-09-25 16:40:27.285667 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-25 16:40:30.275550 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-25 16:40:30.275710 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c9c493a8ab4244d1924789dbabd6fa56/work/c9c493a8ab4244d1924789dbabd6fa56_id_rsa 2025-09-25 16:40:30.275740 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c9c493a8ab4244d1924789dbabd6fa56/work/c9c493a8ab4244d1924789dbabd6fa56_id_rsa.pub 2025-09-25 16:40:30.275762 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-25 16:40:30.275782 | debian-bookworm -> localhost | SHA256:MyezBlPF8kaQTdw4K5zyQBr9DdEEAWKhsR3d4oy+VYU zuul-build-sshkey 2025-09-25 16:40:30.275800 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-25 16:40:30.275826 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-25 16:40:30.275844 | debian-bookworm -> localhost | | . =+.o=#+o | 2025-09-25 16:40:30.275862 | debian-bookworm -> localhost | | *.o= E.X . | 2025-09-25 16:40:30.275878 | debian-bookworm -> localhost | | o .B +.O o | 2025-09-25 16:40:30.275895 | debian-bookworm -> localhost | | o =.* = | 2025-09-25 16:40:30.275911 | debian-bookworm -> localhost | | . o=So. | 2025-09-25 16:40:30.275931 | debian-bookworm -> localhost | | . .o.B | 2025-09-25 16:40:30.275949 | debian-bookworm -> localhost | | o o | 2025-09-25 16:40:30.275966 | debian-bookworm -> localhost | | . . | 2025-09-25 16:40:30.275983 | debian-bookworm -> localhost | | | 2025-09-25 16:40:30.276000 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-25 16:40:30.276037 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.812781 2025-09-25 16:40:30.281167 | 2025-09-25 16:40:30.281248 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-25 16:40:30.317727 | debian-bookworm | ok 2025-09-25 16:40:30.325032 | debian-bookworm | included: /var/lib/zuul/builds/c9c493a8ab4244d1924789dbabd6fa56/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-25 16:40:30.339394 | 2025-09-25 16:40:30.339473 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-25 16:40:30.366096 | debian-bookworm | skipping: Conditional result was False 2025-09-25 16:40:30.370848 | 2025-09-25 16:40:30.370929 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-25 16:40:31.039145 | debian-bookworm | changed 2025-09-25 16:40:31.046659 | 2025-09-25 16:40:31.046756 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-25 16:40:31.365231 | debian-bookworm | ok 2025-09-25 16:40:31.371771 | 2025-09-25 16:40:31.372855 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-25 16:40:32.173201 | debian-bookworm | changed 2025-09-25 16:40:32.183024 | 2025-09-25 16:40:32.183151 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-25 16:40:33.124103 | debian-bookworm | changed 2025-09-25 16:40:33.129709 | 2025-09-25 16:40:33.129797 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-25 16:40:33.157404 | debian-bookworm | skipping: Conditional result was False 2025-09-25 16:40:33.165116 | 2025-09-25 16:40:33.165218 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-25 16:40:34.382489 | debian-bookworm -> localhost | changed 2025-09-25 16:40:34.399233 | 2025-09-25 16:40:34.399330 | TASK [add-build-sshkey : Add back temp key] 2025-09-25 16:40:35.228880 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c9c493a8ab4244d1924789dbabd6fa56/work/c9c493a8ab4244d1924789dbabd6fa56_id_rsa (zuul-build-sshkey) 2025-09-25 16:40:35.229087 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.034870 2025-09-25 16:40:35.235506 | 2025-09-25 16:40:35.235599 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-25 16:40:35.770958 | debian-bookworm | ok 2025-09-25 16:40:35.775390 | 2025-09-25 16:40:35.775468 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-25 16:40:35.857434 | debian-bookworm | skipping: Conditional result was False 2025-09-25 16:40:35.867522 | 2025-09-25 16:40:35.867609 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-25 16:40:36.304643 | debian-bookworm | ok 2025-09-25 16:40:36.317023 | 2025-09-25 16:40:36.317128 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-25 16:40:36.368952 | debian-bookworm | ok 2025-09-25 16:40:36.381667 | 2025-09-25 16:40:36.381762 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-25 16:40:36.906572 | debian-bookworm -> localhost | ok 2025-09-25 16:40:36.911611 | 2025-09-25 16:40:36.911687 | TASK [validate-host : Collect information about the host] 2025-09-25 16:40:38.033438 | debian-bookworm | ok 2025-09-25 16:40:38.049493 | 2025-09-25 16:40:38.049574 | TASK [validate-host : Sanitize hostname] 2025-09-25 16:40:38.201533 | debian-bookworm | ok 2025-09-25 16:40:38.276747 | 2025-09-25 16:40:38.276837 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-25 16:40:39.532495 | debian-bookworm -> localhost | changed 2025-09-25 16:40:39.543797 | 2025-09-25 16:40:39.543888 | TASK [validate-host : Collect information about zuul worker] 2025-09-25 16:40:40.262327 | debian-bookworm | ok 2025-09-25 16:40:40.267772 | 2025-09-25 16:40:40.267856 | TASK [validate-host : Write out all zuul information for each host] 2025-09-25 16:40:41.484204 | debian-bookworm -> localhost | changed 2025-09-25 16:40:41.494016 | 2025-09-25 16:40:41.494123 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-25 16:40:41.785589 | debian-bookworm | ok 2025-09-25 16:40:41.790315 | 2025-09-25 16:40:41.790392 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-25 16:40:43.125321 | debian-bookworm | changed: 2025-09-25 16:40:43.125512 | debian-bookworm | cd+++++++++ src/ 2025-09-25 16:40:43.125551 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-25 16:40:43.125575 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-25 16:40:43.125597 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-09-25 16:40:43.125616 | debian-bookworm | Containerfile 2025-09-25 16:40:43.125726 | debian-bookworm | Containerfile 2025-09-25 16:40:43.195442 | debian-bookworm | localhost | ok: Runtime: 0:00:00.003039 2025-09-25 16:41:12.512776 | 2025-09-25 16:41:12.512868 | TASK [upload-logs : Upload logs to log server] 2025-09-25 16:41:13.109151 | localhost | Output suppressed because no_log was given 2025-09-25 16:41:13.110535 | 2025-09-25 16:41:13.110604 | LOOP [upload-logs : Compress console log and json output] 2025-09-25 16:41:13.163131 | localhost | skipping: Conditional result was False 2025-09-25 16:41:13.180368 | localhost | skipping: Conditional result was False 2025-09-25 16:41:13.185965 | 2025-09-25 16:41:13.186048 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-25 16:41:13.259092 | localhost | skipping: Conditional result was False 2025-09-25 16:41:13.259354 | 2025-09-25 16:41:13.265512 | localhost | skipping: Conditional result was False 2025-09-25 16:41:13.277508 | 2025-09-25 16:41:13.277701 | LOOP [upload-logs : Upload console log and json output]