2025-12-01 14:36:19.864667 | Job console starting 2025-12-01 14:36:19.892710 | Updating git repos 2025-12-01 14:36:19.945723 | Cloning repos into workspace 2025-12-01 14:36:20.116229 | Restoring repo states 2025-12-01 14:36:20.135171 | Merging changes 2025-12-01 14:36:20.945019 | Checking out repos 2025-12-01 14:36:21.049337 | Preparing playbooks 2025-12-01 14:36:22.120644 | Running Ansible setup 2025-12-01 14:36:27.550164 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-01 14:36:28.822536 | 2025-12-01 14:36:28.822727 | PLAY [Base pre] 2025-12-01 14:36:28.859620 | 2025-12-01 14:36:28.859799 | TASK [Setup log path fact] 2025-12-01 14:36:28.904714 | debian-bookworm | ok 2025-12-01 14:36:28.931431 | 2025-12-01 14:36:28.931620 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-01 14:36:28.991076 | debian-bookworm | ok 2025-12-01 14:36:29.003050 | 2025-12-01 14:36:29.004137 | TASK [emit-job-header : Print job information] 2025-12-01 14:36:29.066394 | # Job Information 2025-12-01 14:36:29.066602 | Ansible Version: 2.16.14 2025-12-01 14:36:29.066640 | Job: python-black 2025-12-01 14:36:29.066672 | Pipeline: check 2025-12-01 14:36:29.066695 | Executor: 521e9411259a 2025-12-01 14:36:29.066716 | Triggered by: https://github.com/osism/python-osism/pull/1870 2025-12-01 14:36:29.066738 | Event ID: 84084d10-cebe-11f0-8743-35ba16f309c6 2025-12-01 14:36:29.076563 | 2025-12-01 14:36:29.076720 | LOOP [emit-job-header : Print node information] 2025-12-01 14:36:29.235872 | debian-bookworm | ok: 2025-12-01 14:36:29.237248 | debian-bookworm | # Node Information 2025-12-01 14:36:29.237338 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-01 14:36:29.237369 | debian-bookworm | Hostname: debian 2025-12-01 14:36:29.237393 | debian-bookworm | Username: zuul 2025-12-01 14:36:29.237415 | debian-bookworm | Distro: Debian 12.12 2025-12-01 14:36:29.237440 | debian-bookworm | Provider: regiocloud-a 2025-12-01 14:36:29.237462 | debian-bookworm | Region: 2025-12-01 14:36:29.237483 | debian-bookworm | Label: debian-bookworm 2025-12-01 14:36:29.237502 | debian-bookworm | Product Name: OpenStack Nova 2025-12-01 14:36:29.237521 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe13:b2ed 2025-12-01 14:36:29.257412 | 2025-12-01 14:36:29.257555 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-01 14:36:29.931376 | debian-bookworm -> localhost | changed 2025-12-01 14:36:29.939318 | 2025-12-01 14:36:29.939461 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-01 14:36:32.002497 | debian-bookworm -> localhost | changed 2025-12-01 14:36:32.015018 | 2025-12-01 14:36:32.015168 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-01 14:36:32.596990 | debian-bookworm -> localhost | ok 2025-12-01 14:36:32.605879 | 2025-12-01 14:36:32.606037 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-01 14:36:32.661707 | debian-bookworm | ok 2025-12-01 14:36:32.697331 | debian-bookworm | included: /var/lib/zuul/builds/5d1995fba5214872a9ce5c7166cd5d40/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-01 14:36:32.715336 | 2025-12-01 14:36:32.715468 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-01 14:36:36.025232 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-01 14:36:36.025549 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5d1995fba5214872a9ce5c7166cd5d40/work/5d1995fba5214872a9ce5c7166cd5d40_id_rsa 2025-12-01 14:36:36.025591 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5d1995fba5214872a9ce5c7166cd5d40/work/5d1995fba5214872a9ce5c7166cd5d40_id_rsa.pub 2025-12-01 14:36:36.025618 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-01 14:36:36.025645 | debian-bookworm -> localhost | SHA256:EXt7E8NoV615YUDqOwOzaqudAbIwye40uV7BvgmNYdU zuul-build-sshkey 2025-12-01 14:36:36.025668 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-01 14:36:36.025703 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-01 14:36:36.025725 | debian-bookworm -> localhost | | . .oo. | 2025-12-01 14:36:36.025747 | debian-bookworm -> localhost | | . o o.. o.| 2025-12-01 14:36:36.025767 | debian-bookworm -> localhost | | . E o +.= .o.| 2025-12-01 14:36:36.025787 | debian-bookworm -> localhost | |. + +.o oo .| 2025-12-01 14:36:36.025806 | debian-bookworm -> localhost | | B + . So..o . | 2025-12-01 14:36:36.025833 | debian-bookworm -> localhost | |o O + . +... | 2025-12-01 14:36:36.025854 | debian-bookworm -> localhost | | B = . . + | 2025-12-01 14:36:36.025874 | debian-bookworm -> localhost | |o = o ..+ o | 2025-12-01 14:36:36.025894 | debian-bookworm -> localhost | |.+ o .o=. | 2025-12-01 14:36:36.025913 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-01 14:36:36.025988 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.309337 2025-12-01 14:36:36.033258 | 2025-12-01 14:36:36.033381 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-01 14:36:36.085831 | debian-bookworm | ok 2025-12-01 14:36:36.102281 | debian-bookworm | included: /var/lib/zuul/builds/5d1995fba5214872a9ce5c7166cd5d40/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-01 14:36:36.168374 | 2025-12-01 14:36:36.168517 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-01 14:36:36.213464 | debian-bookworm | skipping: Conditional result was False 2025-12-01 14:36:36.220463 | 2025-12-01 14:36:36.220590 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-01 14:36:37.115079 | debian-bookworm | changed 2025-12-01 14:36:37.120653 | 2025-12-01 14:36:37.120758 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-01 14:36:37.392225 | debian-bookworm | ok 2025-12-01 14:36:37.399957 | 2025-12-01 14:36:37.400121 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-01 14:36:38.173060 | debian-bookworm | changed 2025-12-01 14:36:38.183270 | 2025-12-01 14:36:38.183443 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-01 14:36:38.853309 | debian-bookworm | changed 2025-12-01 14:36:38.861737 | 2025-12-01 14:36:38.861887 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-01 14:36:38.897408 | debian-bookworm | skipping: Conditional result was False 2025-12-01 14:36:38.903890 | 2025-12-01 14:36:38.904139 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-01 14:36:39.386195 | debian-bookworm -> localhost | changed 2025-12-01 14:36:39.398022 | 2025-12-01 14:36:39.398146 | TASK [add-build-sshkey : Add back temp key] 2025-12-01 14:36:39.777119 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5d1995fba5214872a9ce5c7166cd5d40/work/5d1995fba5214872a9ce5c7166cd5d40_id_rsa (zuul-build-sshkey) 2025-12-01 14:36:39.777549 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011464 2025-12-01 14:36:39.792893 | 2025-12-01 14:36:39.793110 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-01 14:36:40.270133 | debian-bookworm | ok 2025-12-01 14:36:40.277637 | 2025-12-01 14:36:40.277771 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-01 14:36:40.324773 | debian-bookworm | skipping: Conditional result was False 2025-12-01 14:36:40.340351 | 2025-12-01 14:36:40.340496 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-01 14:36:40.818160 | debian-bookworm | ok 2025-12-01 14:36:40.829371 | 2025-12-01 14:36:40.829514 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-01 14:36:40.879458 | debian-bookworm | ok 2025-12-01 14:36:40.884693 | 2025-12-01 14:36:40.884817 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-01 14:36:41.196432 | debian-bookworm -> localhost | ok 2025-12-01 14:36:41.203483 | 2025-12-01 14:36:41.203601 | TASK [validate-host : Collect information about the host] 2025-12-01 14:36:42.385124 | debian-bookworm | ok 2025-12-01 14:36:42.402404 | 2025-12-01 14:36:42.402605 | TASK [validate-host : Sanitize hostname] 2025-12-01 14:36:42.527038 | debian-bookworm | ok 2025-12-01 14:36:42.582599 | 2025-12-01 14:36:42.582764 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-01 14:36:43.187167 | debian-bookworm -> localhost | changed 2025-12-01 14:36:43.197391 | 2025-12-01 14:36:43.197579 | TASK [validate-host : Collect information about zuul worker] 2025-12-01 14:36:43.741587 | debian-bookworm | ok 2025-12-01 14:36:43.762125 | 2025-12-01 14:36:43.762254 | TASK [validate-host : Write out all zuul information for each host] 2025-12-01 14:36:44.613320 | debian-bookworm -> localhost | changed 2025-12-01 14:36:44.627426 | 2025-12-01 14:36:44.627573 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-01 14:36:44.920514 | debian-bookworm | ok 2025-12-01 14:36:44.927026 | 2025-12-01 14:36:44.927155 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-01 14:36:46.042878 | debian-bookworm | changed: 2025-12-01 14:36:46.043147 | debian-bookworm | cd+++++++++ src/ 2025-12-01 14:36:46.043187 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-01 14:36:46.043213 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-01 14:36:46.043235 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-01 14:36:46.043256 | debian-bookworm | Containerfile 2025-12-01 14:36:46.043370 | debian-bookworm | Containerfile 2025-12-01 14:36:46.070146 | debian-bookworm | localhost | ok: Runtime: 0:00:00.027889 2025-12-01 14:37:18.459813 | 2025-12-01 14:37:18.459916 | TASK [upload-logs : Upload logs to log server] 2025-12-01 14:37:19.851349 | localhost | Output suppressed because no_log was given 2025-12-01 14:37:19.853018 | 2025-12-01 14:37:19.853109 | LOOP [upload-logs : Compress console log and json output] 2025-12-01 14:37:19.946545 | localhost | skipping: Conditional result was False 2025-12-01 14:37:19.963505 | localhost | skipping: Conditional result was False 2025-12-01 14:37:19.972892 | 2025-12-01 14:37:19.973063 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-01 14:37:20.068409 | localhost | skipping: Conditional result was False 2025-12-01 14:37:20.068634 | 2025-12-01 14:37:20.086470 | localhost | skipping: Conditional result was False 2025-12-01 14:37:20.089287 | 2025-12-01 14:37:20.089393 | LOOP [upload-logs : Upload console log and json output]