2025-09-23 17:39:45.259015 | Job console starting 2025-09-23 17:39:45.283167 | Updating git repos 2025-09-23 17:39:45.316342 | Cloning repos into workspace 2025-09-23 17:39:45.373368 | Restoring repo states 2025-09-23 17:39:45.389632 | Merging changes 2025-09-23 17:39:46.145183 | Checking out repos 2025-09-23 17:39:46.190531 | Preparing playbooks 2025-09-23 17:39:47.531532 | Running Ansible setup 2025-09-23 17:39:53.240138 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 17:39:54.139486 | 2025-09-23 17:39:54.139605 | PLAY [Base pre] 2025-09-23 17:39:54.164154 | 2025-09-23 17:39:54.164264 | TASK [Setup log path fact] 2025-09-23 17:39:54.191093 | debian-bookworm | ok 2025-09-23 17:39:54.211686 | 2025-09-23 17:39:54.211843 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 17:39:54.239314 | debian-bookworm | ok 2025-09-23 17:39:54.247003 | 2025-09-23 17:39:54.247095 | TASK [emit-job-header : Print job information] 2025-09-23 17:39:54.284063 | # Job Information 2025-09-23 17:39:54.284199 | Ansible Version: 2.16.14 2025-09-23 17:39:54.284227 | Job: python-black 2025-09-23 17:39:54.284253 | Pipeline: check 2025-09-23 17:39:54.284272 | Executor: 521e9411259a 2025-09-23 17:39:54.284288 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/786 2025-09-23 17:39:54.284306 | Event ID: 461a42b0-98a4-11f0-9da8-c4669fff2c58 2025-09-23 17:39:54.288170 | 2025-09-23 17:39:54.288245 | LOOP [emit-job-header : Print node information] 2025-09-23 17:39:54.462555 | debian-bookworm | ok: 2025-09-23 17:39:54.462747 | debian-bookworm | # Node Information 2025-09-23 17:39:54.462824 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 17:39:54.462873 | debian-bookworm | Hostname: debian 2025-09-23 17:39:54.462897 | debian-bookworm | Username: zuul 2025-09-23 17:39:54.462919 | debian-bookworm | Distro: Debian 12.12 2025-09-23 17:39:54.462944 | debian-bookworm | Provider: regiocloud-a 2025-09-23 17:39:54.462965 | debian-bookworm | Region: 2025-09-23 17:39:54.462985 | debian-bookworm | Label: debian-bookworm 2025-09-23 17:39:54.463005 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 17:39:54.463024 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe73:b1b7 2025-09-23 17:39:54.481119 | 2025-09-23 17:39:54.481221 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 17:39:55.415509 | debian-bookworm -> localhost | changed 2025-09-23 17:39:55.421093 | 2025-09-23 17:39:55.421178 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 17:39:57.271503 | debian-bookworm -> localhost | changed 2025-09-23 17:39:57.291041 | 2025-09-23 17:39:57.291942 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 17:39:58.038947 | debian-bookworm -> localhost | ok 2025-09-23 17:39:58.043838 | 2025-09-23 17:39:58.043929 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 17:39:58.070332 | debian-bookworm | ok 2025-09-23 17:39:58.087996 | debian-bookworm | included: /var/lib/zuul/builds/51d7200b482845d38ef961b02aa46376/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 17:39:58.092677 | 2025-09-23 17:39:58.092750 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 17:39:59.224181 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 17:39:59.224339 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/51d7200b482845d38ef961b02aa46376/work/51d7200b482845d38ef961b02aa46376_id_rsa 2025-09-23 17:39:59.224370 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/51d7200b482845d38ef961b02aa46376/work/51d7200b482845d38ef961b02aa46376_id_rsa.pub 2025-09-23 17:39:59.224392 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 17:39:59.224412 | debian-bookworm -> localhost | SHA256:M9wLf1ZIQ+QmARxdAPJ7oKyj4C7cEtZZuinFoODTqRE zuul-build-sshkey 2025-09-23 17:39:59.224430 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 17:39:59.224453 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 17:39:59.224472 | debian-bookworm -> localhost | | ..+=o++ | 2025-09-23 17:39:59.224489 | debian-bookworm -> localhost | | o. .+ | 2025-09-23 17:39:59.224506 | debian-bookworm -> localhost | | o . = | 2025-09-23 17:39:59.224523 | debian-bookworm -> localhost | |.E o o + + o | 2025-09-23 17:39:59.224539 | debian-bookworm -> localhost | |+ * = o S o . . | 2025-09-23 17:39:59.224561 | debian-bookworm -> localhost | |.* O . * . . | 2025-09-23 17:39:59.224579 | debian-bookworm -> localhost | |+ B = o o | 2025-09-23 17:39:59.224596 | debian-bookworm -> localhost | |o* = . o | 2025-09-23 17:39:59.224614 | debian-bookworm -> localhost | |oo+ | 2025-09-23 17:39:59.224631 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 17:39:59.224673 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.484209 2025-09-23 17:39:59.229849 | 2025-09-23 17:39:59.229929 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 17:39:59.245957 | debian-bookworm | ok 2025-09-23 17:39:59.253599 | debian-bookworm | included: /var/lib/zuul/builds/51d7200b482845d38ef961b02aa46376/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 17:39:59.260872 | 2025-09-23 17:39:59.260940 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 17:39:59.283363 | debian-bookworm | skipping: Conditional result was False 2025-09-23 17:39:59.288021 | 2025-09-23 17:39:59.288100 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 17:39:59.861575 | debian-bookworm | changed 2025-09-23 17:39:59.865789 | 2025-09-23 17:39:59.865861 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 17:40:00.139259 | debian-bookworm | ok 2025-09-23 17:40:00.143634 | 2025-09-23 17:40:00.143718 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 17:40:00.757213 | debian-bookworm | changed 2025-09-23 17:40:00.762716 | 2025-09-23 17:40:00.762806 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 17:40:01.479434 | debian-bookworm | changed 2025-09-23 17:40:01.493043 | 2025-09-23 17:40:01.493152 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 17:40:01.516478 | debian-bookworm | skipping: Conditional result was False 2025-09-23 17:40:01.522932 | 2025-09-23 17:40:01.523029 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 17:40:02.063196 | debian-bookworm -> localhost | changed 2025-09-23 17:40:02.074160 | 2025-09-23 17:40:02.074258 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 17:40:02.493878 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/51d7200b482845d38ef961b02aa46376/work/51d7200b482845d38ef961b02aa46376_id_rsa (zuul-build-sshkey) 2025-09-23 17:40:02.494094 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023180 2025-09-23 17:40:02.502061 | 2025-09-23 17:40:02.502162 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 17:40:02.887340 | debian-bookworm | ok 2025-09-23 17:40:02.892164 | 2025-09-23 17:40:02.892253 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 17:40:02.915051 | debian-bookworm | skipping: Conditional result was False 2025-09-23 17:40:02.924733 | 2025-09-23 17:40:02.924833 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 17:40:03.411630 | debian-bookworm | ok 2025-09-23 17:40:03.420093 | 2025-09-23 17:40:03.420178 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 17:40:03.452206 | debian-bookworm | ok 2025-09-23 17:40:03.458601 | 2025-09-23 17:40:03.458709 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 17:40:03.859742 | debian-bookworm -> localhost | ok 2025-09-23 17:40:03.865727 | 2025-09-23 17:40:03.865829 | TASK [validate-host : Collect information about the host] 2025-09-23 17:40:05.173935 | debian-bookworm | ok 2025-09-23 17:40:05.183740 | 2025-09-23 17:40:05.183860 | TASK [validate-host : Sanitize hostname] 2025-09-23 17:40:05.283124 | debian-bookworm | ok 2025-09-23 17:40:05.344030 | 2025-09-23 17:40:05.344141 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 17:40:06.104879 | debian-bookworm -> localhost | changed 2025-09-23 17:40:06.109354 | 2025-09-23 17:40:06.109436 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 17:40:06.541915 | debian-bookworm | ok 2025-09-23 17:40:06.548309 | 2025-09-23 17:40:06.548391 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 17:40:07.234497 | debian-bookworm -> localhost | changed 2025-09-23 17:40:07.243541 | 2025-09-23 17:40:07.243645 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 17:40:07.537329 | debian-bookworm | ok 2025-09-23 17:40:07.542463 | 2025-09-23 17:40:07.542547 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 17:40:08.592937 | debian-bookworm | changed: 2025-09-23 17:40:08.593086 | debian-bookworm | cd+++++++++ src/ 2025-09-23 17:40:08.593115 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 17:40:08.593135 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 17:40:08.593153 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-09-23 17:40:08.593169 | debian-bookworm | Containerfile 2025-09-23 17:40:08.593277 | debian-bookworm | localhost | ok: Runtime: 0:00:00.016968 2025-09-23 17:40:33.951478 | 2025-09-23 17:40:33.951601 | TASK [upload-logs : Upload logs to log server] 2025-09-23 17:40:34.648791 | localhost | Output suppressed because no_log was given 2025-09-23 17:40:34.650978 | 2025-09-23 17:40:34.651086 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 17:40:34.701178 | localhost | skipping: Conditional result was False 2025-09-23 17:40:34.705936 | localhost | skipping: Conditional result was False 2025-09-23 17:40:34.719052 | 2025-09-23 17:40:34.719219 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 17:40:34.766326 | localhost | skipping: Conditional result was False 2025-09-23 17:40:34.767037 | 2025-09-23 17:40:34.770258 | localhost | skipping: Conditional result was False 2025-09-23 17:40:34.783684 | 2025-09-23 17:40:34.783966 | LOOP [upload-logs : Upload console log and json output]