2025-06-23 19:59:59.363340 | Job console starting 2025-06-23 19:59:59.407734 | Updating git repos 2025-06-23 19:59:59.464060 | Cloning repos into workspace 2025-06-23 19:59:59.571964 | Restoring repo states 2025-06-23 19:59:59.596163 | Merging changes 2025-06-23 20:00:00.164523 | Checking out repos 2025-06-23 20:00:00.230553 | Preparing playbooks 2025-06-23 20:00:01.314360 | Running Ansible setup 2025-06-23 20:00:06.597917 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-23 20:00:07.521447 | 2025-06-23 20:00:07.521580 | PLAY [Base pre] 2025-06-23 20:00:07.559683 | 2025-06-23 20:00:07.559875 | TASK [Setup log path fact] 2025-06-23 20:00:07.580306 | debian-bookworm | ok 2025-06-23 20:00:07.603130 | 2025-06-23 20:00:07.603282 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-23 20:00:07.636437 | debian-bookworm | ok 2025-06-23 20:00:07.653854 | 2025-06-23 20:00:07.653970 | TASK [emit-job-header : Print job information] 2025-06-23 20:00:07.707763 | # Job Information 2025-06-23 20:00:07.707977 | Ansible Version: 2.16.14 2025-06-23 20:00:07.708026 | Job: python-black 2025-06-23 20:00:07.708069 | Pipeline: check 2025-06-23 20:00:07.708099 | Executor: 521e9411259a 2025-06-23 20:00:07.708137 | Triggered by: https://github.com/osism/python-osism/pull/1502 2025-06-23 20:00:07.708168 | Event ID: 9f4f26a0-506c-11f0-9fce-1631ba309c39 2025-06-23 20:00:07.713879 | 2025-06-23 20:00:07.713987 | LOOP [emit-job-header : Print node information] 2025-06-23 20:00:07.905788 | debian-bookworm | ok: 2025-06-23 20:00:07.906029 | debian-bookworm | # Node Information 2025-06-23 20:00:07.906065 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-23 20:00:07.906090 | debian-bookworm | Hostname: debian 2025-06-23 20:00:07.906124 | debian-bookworm | Username: zuul 2025-06-23 20:00:07.906146 | debian-bookworm | Distro: Debian 12.11 2025-06-23 20:00:07.906169 | debian-bookworm | Provider: regiocloud-a 2025-06-23 20:00:07.906191 | debian-bookworm | Region: 2025-06-23 20:00:07.906213 | debian-bookworm | Label: debian-bookworm 2025-06-23 20:00:07.906232 | debian-bookworm | Product Name: OpenStack Nova 2025-06-23 20:00:07.906252 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe44:296f 2025-06-23 20:00:07.924084 | 2025-06-23 20:00:07.924206 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-23 20:00:08.582581 | debian-bookworm -> localhost | changed 2025-06-23 20:00:08.589400 | 2025-06-23 20:00:08.589513 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-23 20:00:10.080530 | debian-bookworm -> localhost | changed 2025-06-23 20:00:10.098259 | 2025-06-23 20:00:10.098417 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-23 20:00:10.539180 | debian-bookworm -> localhost | ok 2025-06-23 20:00:10.545716 | 2025-06-23 20:00:10.545849 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-23 20:00:10.595383 | debian-bookworm | ok 2025-06-23 20:00:10.624688 | debian-bookworm | included: /var/lib/zuul/builds/b8ecc5b15d464df9bd98b9837494ce24/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-23 20:00:10.641803 | 2025-06-23 20:00:10.641953 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-23 20:00:13.072859 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-23 20:00:13.073134 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b8ecc5b15d464df9bd98b9837494ce24/work/b8ecc5b15d464df9bd98b9837494ce24_id_rsa 2025-06-23 20:00:13.073180 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b8ecc5b15d464df9bd98b9837494ce24/work/b8ecc5b15d464df9bd98b9837494ce24_id_rsa.pub 2025-06-23 20:00:13.073207 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-23 20:00:13.073233 | debian-bookworm -> localhost | SHA256:Cizjx532N6wjS4xLVCRwagBXzjM8Z7LPnRyIdsa+c/k zuul-build-sshkey 2025-06-23 20:00:13.073255 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-23 20:00:13.073291 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-23 20:00:13.073313 | debian-bookworm -> localhost | |+ oo+ . | 2025-06-23 20:00:13.073335 | debian-bookworm -> localhost | | o * o | 2025-06-23 20:00:13.073355 | debian-bookworm -> localhost | | o O + | 2025-06-23 20:00:13.073375 | debian-bookworm -> localhost | | . . % . | 2025-06-23 20:00:13.073394 | debian-bookworm -> localhost | | o B = S | 2025-06-23 20:00:13.073417 | debian-bookworm -> localhost | | . * @ = o | 2025-06-23 20:00:13.073437 | debian-bookworm -> localhost | | . = @ +o | 2025-06-23 20:00:13.073457 | debian-bookworm -> localhost | | o +oo+ + | 2025-06-23 20:00:13.073477 | debian-bookworm -> localhost | | . o=o=E. | 2025-06-23 20:00:13.073497 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-23 20:00:13.073552 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.840967 2025-06-23 20:00:13.080102 | 2025-06-23 20:00:13.080246 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-23 20:00:13.108601 | debian-bookworm | ok 2025-06-23 20:00:13.118322 | debian-bookworm | included: /var/lib/zuul/builds/b8ecc5b15d464df9bd98b9837494ce24/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-23 20:00:13.127544 | 2025-06-23 20:00:13.127660 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-23 20:00:13.151770 | debian-bookworm | skipping: Conditional result was False 2025-06-23 20:00:13.157683 | 2025-06-23 20:00:13.157787 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-23 20:00:13.818659 | debian-bookworm | changed 2025-06-23 20:00:13.827702 | 2025-06-23 20:00:13.827830 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-23 20:00:14.083585 | debian-bookworm | ok 2025-06-23 20:00:14.091247 | 2025-06-23 20:00:14.091384 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-23 20:00:14.723546 | debian-bookworm | changed 2025-06-23 20:00:14.736724 | 2025-06-23 20:00:14.736873 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-23 20:00:15.326036 | debian-bookworm | changed 2025-06-23 20:00:15.332063 | 2025-06-23 20:00:15.332236 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-23 20:00:15.357256 | debian-bookworm | skipping: Conditional result was False 2025-06-23 20:00:15.367162 | 2025-06-23 20:00:15.367297 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-23 20:00:15.930337 | debian-bookworm -> localhost | changed 2025-06-23 20:00:15.941754 | 2025-06-23 20:00:15.941887 | TASK [add-build-sshkey : Add back temp key] 2025-06-23 20:00:16.522477 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b8ecc5b15d464df9bd98b9837494ce24/work/b8ecc5b15d464df9bd98b9837494ce24_id_rsa (zuul-build-sshkey) 2025-06-23 20:00:16.522825 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018242 2025-06-23 20:00:16.531585 | 2025-06-23 20:00:16.531740 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-23 20:00:17.143179 | debian-bookworm | ok 2025-06-23 20:00:17.150320 | 2025-06-23 20:00:17.150483 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-23 20:00:17.175042 | debian-bookworm | skipping: Conditional result was False 2025-06-23 20:00:17.186476 | 2025-06-23 20:00:17.194234 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-23 20:00:17.639553 | debian-bookworm | ok 2025-06-23 20:00:17.659908 | 2025-06-23 20:00:17.660057 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-23 20:00:17.697187 | debian-bookworm | ok 2025-06-23 20:00:17.704139 | 2025-06-23 20:00:17.704265 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-23 20:00:18.093084 | debian-bookworm -> localhost | ok 2025-06-23 20:00:18.103699 | 2025-06-23 20:00:18.103944 | TASK [validate-host : Collect information about the host] 2025-06-23 20:00:19.505678 | debian-bookworm | ok 2025-06-23 20:00:19.533044 | 2025-06-23 20:00:19.533217 | TASK [validate-host : Sanitize hostname] 2025-06-23 20:00:19.726534 | debian-bookworm | ok 2025-06-23 20:00:19.872085 | 2025-06-23 20:00:19.872276 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-23 20:00:20.995483 | debian-bookworm -> localhost | changed 2025-06-23 20:00:21.015733 | 2025-06-23 20:00:21.015911 | TASK [validate-host : Collect information about zuul worker] 2025-06-23 20:00:21.757344 | debian-bookworm | ok 2025-06-23 20:00:21.764571 | 2025-06-23 20:00:21.764675 | TASK [validate-host : Write out all zuul information for each host] 2025-06-23 20:00:22.760254 | debian-bookworm -> localhost | changed 2025-06-23 20:00:22.770661 | 2025-06-23 20:00:22.770760 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-23 20:00:23.069455 | debian-bookworm | ok 2025-06-23 20:00:23.096011 | 2025-06-23 20:00:23.096143 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-23 20:00:24.307416 | debian-bookworm | changed: 2025-06-23 20:00:24.307663 | debian-bookworm | cd+++++++++ src/ 2025-06-23 20:00:24.307705 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-23 20:00:24.307762 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-23 20:00:24.307825 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-06-23 20:00:24.307853 | debian-bookworm | Containerfile 2025-06-23 20:00:24.307975 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007427 2025-06-23 20:00:50.511519 | 2025-06-23 20:00:50.511740 | TASK [upload-logs : Upload logs to log server] 2025-06-23 20:00:51.121334 | localhost | Output suppressed because no_log was given 2025-06-23 20:00:51.124939 | 2025-06-23 20:00:51.125190 | LOOP [upload-logs : Compress console log and json output] 2025-06-23 20:00:51.186901 | localhost | skipping: Conditional result was False 2025-06-23 20:00:51.192366 | localhost | skipping: Conditional result was False 2025-06-23 20:00:51.204027 | 2025-06-23 20:00:51.204251 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-23 20:00:51.255560 | localhost | skipping: Conditional result was False 2025-06-23 20:00:51.256250 | 2025-06-23 20:00:51.258973 | localhost | skipping: Conditional result was False 2025-06-23 20:00:51.266762 | 2025-06-23 20:00:51.267041 | LOOP [upload-logs : Upload console log and json output]