2025-10-31 11:31:44.636590 | Job console starting 2025-10-31 11:31:44.648200 | Updating git repos 2025-10-31 11:31:44.724060 | Cloning repos into workspace 2025-10-31 11:31:44.797771 | Restoring repo states 2025-10-31 11:31:44.837520 | Merging changes 2025-10-31 11:31:45.460190 | Checking out repos 2025-10-31 11:31:45.541575 | Preparing playbooks 2025-10-31 11:31:46.620963 | Running Ansible setup 2025-10-31 11:31:52.749467 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-31 11:31:53.970192 | 2025-10-31 11:31:53.970360 | PLAY [Base pre] 2025-10-31 11:31:54.014155 | 2025-10-31 11:31:54.014320 | TASK [Setup log path fact] 2025-10-31 11:31:54.067498 | debian-bookworm | ok 2025-10-31 11:31:54.097264 | 2025-10-31 11:31:54.097440 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-31 11:31:54.127830 | debian-bookworm | ok 2025-10-31 11:31:54.144725 | 2025-10-31 11:31:54.144869 | TASK [emit-job-header : Print job information] 2025-10-31 11:31:54.183938 | # Job Information 2025-10-31 11:31:54.184138 | Ansible Version: 2.16.14 2025-10-31 11:31:54.184174 | Job: python-black 2025-10-31 11:31:54.184207 | Pipeline: check 2025-10-31 11:31:54.184231 | Executor: 521e9411259a 2025-10-31 11:31:54.184252 | Triggered by: https://github.com/osism/python-osism/pull/1714 2025-10-31 11:31:54.184275 | Event ID: 28503ed0-b64d-11f0-951d-b359c77960d1 2025-10-31 11:31:54.190996 | 2025-10-31 11:31:54.191132 | LOOP [emit-job-header : Print node information] 2025-10-31 11:31:54.509721 | debian-bookworm | ok: 2025-10-31 11:31:54.509911 | debian-bookworm | # Node Information 2025-10-31 11:31:54.509948 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-31 11:31:54.510033 | debian-bookworm | Hostname: debian 2025-10-31 11:31:54.510058 | debian-bookworm | Username: zuul 2025-10-31 11:31:54.510079 | debian-bookworm | Distro: Debian 12.12 2025-10-31 11:31:54.510121 | debian-bookworm | Provider: regiocloud-a 2025-10-31 11:31:54.510143 | debian-bookworm | Region: 2025-10-31 11:31:54.510164 | debian-bookworm | Label: debian-bookworm 2025-10-31 11:31:54.510183 | debian-bookworm | Product Name: OpenStack Nova 2025-10-31 11:31:54.510202 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe78:5507 2025-10-31 11:31:54.532684 | 2025-10-31 11:31:54.532814 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-31 11:31:55.581547 | debian-bookworm -> localhost | changed 2025-10-31 11:31:55.590683 | 2025-10-31 11:31:55.590984 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-31 11:31:57.739232 | debian-bookworm -> localhost | changed 2025-10-31 11:31:57.750214 | 2025-10-31 11:31:57.750305 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-31 11:31:58.171385 | debian-bookworm -> localhost | ok 2025-10-31 11:31:58.176935 | 2025-10-31 11:31:58.177025 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-31 11:31:58.215363 | debian-bookworm | ok 2025-10-31 11:31:58.237952 | debian-bookworm | included: /var/lib/zuul/builds/5034cef8ce6a4969a94d224e1e7328dc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-31 11:31:58.262764 | 2025-10-31 11:31:58.262865 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-31 11:31:59.908749 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-31 11:31:59.908912 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5034cef8ce6a4969a94d224e1e7328dc/work/5034cef8ce6a4969a94d224e1e7328dc_id_rsa 2025-10-31 11:31:59.908944 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5034cef8ce6a4969a94d224e1e7328dc/work/5034cef8ce6a4969a94d224e1e7328dc_id_rsa.pub 2025-10-31 11:31:59.908966 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-31 11:31:59.908987 | debian-bookworm -> localhost | SHA256:T4cMsELjyW64L4e6nFJoaRRUEmyACvCMWt5XYB24C2o zuul-build-sshkey 2025-10-31 11:31:59.909005 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-31 11:31:59.909033 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-31 11:31:59.909052 | debian-bookworm -> localhost | |O+o.o +o.. | 2025-10-31 11:31:59.909070 | debian-bookworm -> localhost | |oB.+ +.+. | 2025-10-31 11:31:59.909087 | debian-bookworm -> localhost | |= = = ..o | 2025-10-31 11:31:59.909124 | debian-bookworm -> localhost | |o+ +.... o . | 2025-10-31 11:31:59.909143 | debian-bookworm -> localhost | |o.+.+...S + . | 2025-10-31 11:31:59.909164 | debian-bookworm -> localhost | |.+Eo .. o . | 2025-10-31 11:31:59.909180 | debian-bookworm -> localhost | |oo.. . | 2025-10-31 11:31:59.909196 | debian-bookworm -> localhost | |o +.. | 2025-10-31 11:31:59.909213 | debian-bookworm -> localhost | |+= o. | 2025-10-31 11:31:59.909229 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-31 11:31:59.909270 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.894858 2025-10-31 11:31:59.914259 | 2025-10-31 11:31:59.914337 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-31 11:31:59.950274 | debian-bookworm | ok 2025-10-31 11:31:59.958329 | debian-bookworm | included: /var/lib/zuul/builds/5034cef8ce6a4969a94d224e1e7328dc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-31 11:31:59.970434 | 2025-10-31 11:31:59.970498 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-31 11:32:00.002843 | debian-bookworm | skipping: Conditional result was False 2025-10-31 11:32:00.007390 | 2025-10-31 11:32:00.007461 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-31 11:32:00.759428 | debian-bookworm | changed 2025-10-31 11:32:00.767339 | 2025-10-31 11:32:00.767446 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-31 11:32:01.031772 | debian-bookworm | ok 2025-10-31 11:32:01.038754 | 2025-10-31 11:32:01.038926 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-31 11:32:01.660298 | debian-bookworm | changed 2025-10-31 11:32:01.667229 | 2025-10-31 11:32:01.667315 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-31 11:32:02.318813 | debian-bookworm | changed 2025-10-31 11:32:02.325211 | 2025-10-31 11:32:02.325305 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-31 11:32:02.338204 | debian-bookworm | skipping: Conditional result was False 2025-10-31 11:32:02.343797 | 2025-10-31 11:32:02.343897 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-31 11:32:02.738431 | debian-bookworm -> localhost | changed 2025-10-31 11:32:02.752617 | 2025-10-31 11:32:02.752725 | TASK [add-build-sshkey : Add back temp key] 2025-10-31 11:32:03.251291 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5034cef8ce6a4969a94d224e1e7328dc/work/5034cef8ce6a4969a94d224e1e7328dc_id_rsa (zuul-build-sshkey) 2025-10-31 11:32:03.251504 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013877 2025-10-31 11:32:03.258637 | 2025-10-31 11:32:03.258724 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-31 11:32:03.653947 | debian-bookworm | ok 2025-10-31 11:32:03.667121 | 2025-10-31 11:32:03.667228 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-31 11:32:03.700115 | debian-bookworm | skipping: Conditional result was False 2025-10-31 11:32:03.710108 | 2025-10-31 11:32:03.710211 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-31 11:32:04.152570 | debian-bookworm | ok 2025-10-31 11:32:04.164760 | 2025-10-31 11:32:04.164862 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-31 11:32:04.192325 | debian-bookworm | ok 2025-10-31 11:32:04.213640 | 2025-10-31 11:32:04.213746 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-31 11:32:04.540679 | debian-bookworm -> localhost | ok 2025-10-31 11:32:04.546786 | 2025-10-31 11:32:04.546890 | TASK [validate-host : Collect information about the host] 2025-10-31 11:32:05.668428 | debian-bookworm | ok 2025-10-31 11:32:05.687467 | 2025-10-31 11:32:05.689331 | TASK [validate-host : Sanitize hostname] 2025-10-31 11:32:05.769480 | debian-bookworm | ok 2025-10-31 11:32:05.819571 | 2025-10-31 11:32:05.819677 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-31 11:32:06.363285 | debian-bookworm -> localhost | changed 2025-10-31 11:32:06.368526 | 2025-10-31 11:32:06.368624 | TASK [validate-host : Collect information about zuul worker] 2025-10-31 11:32:06.748498 | debian-bookworm | ok 2025-10-31 11:32:06.752936 | 2025-10-31 11:32:06.753023 | TASK [validate-host : Write out all zuul information for each host] 2025-10-31 11:32:07.543773 | debian-bookworm -> localhost | changed 2025-10-31 11:32:07.553289 | 2025-10-31 11:32:07.553381 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-31 11:32:07.834204 | debian-bookworm | ok 2025-10-31 11:32:07.839394 | 2025-10-31 11:32:07.839478 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-31 11:32:09.052646 | debian-bookworm | changed: 2025-10-31 11:32:09.052837 | debian-bookworm | cd+++++++++ src/ 2025-10-31 11:32:09.052868 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-31 11:32:09.052888 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-31 11:32:09.052906 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-10-31 11:32:09.052923 | debian-bookworm | Containerfile 2025-10-31 11:32:09.053030 | debian-bookworm | Containerfile 2025-10-31 11:32:09.060675 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007788 2025-10-31 11:32:35.729037 | 2025-10-31 11:32:35.729208 | TASK [upload-logs : Upload logs to log server] 2025-10-31 11:32:36.279928 | localhost | Output suppressed because no_log was given 2025-10-31 11:32:36.283596 | 2025-10-31 11:32:36.283775 | LOOP [upload-logs : Compress console log and json output] 2025-10-31 11:32:36.339039 | localhost | skipping: Conditional result was False 2025-10-31 11:32:36.343706 | localhost | skipping: Conditional result was False 2025-10-31 11:32:36.356588 | 2025-10-31 11:32:36.356824 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-31 11:32:36.401625 | localhost | skipping: Conditional result was False 2025-10-31 11:32:36.402131 | 2025-10-31 11:32:36.405847 | localhost | skipping: Conditional result was False 2025-10-31 11:32:36.418229 | 2025-10-31 11:32:36.418416 | LOOP [upload-logs : Upload console log and json output]