2025-11-28 22:01:48.901757 | Job console starting 2025-11-28 22:01:48.941360 | Updating git repos 2025-11-28 22:01:49.050469 | Cloning repos into workspace 2025-11-28 22:01:49.128941 | Restoring repo states 2025-11-28 22:01:49.149473 | Merging changes 2025-11-28 22:01:49.926533 | Checking out repos 2025-11-28 22:01:50.097146 | Preparing playbooks 2025-11-28 22:01:51.335585 | Running Ansible setup 2025-11-28 22:01:57.187575 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-11-28 22:01:58.558712 | 2025-11-28 22:01:58.558915 | PLAY [Base pre] 2025-11-28 22:01:58.595737 | 2025-11-28 22:01:58.595970 | TASK [Setup log path fact] 2025-11-28 22:01:58.646875 | debian-bookworm | ok 2025-11-28 22:01:58.684481 | 2025-11-28 22:01:58.684665 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-11-28 22:01:58.736867 | debian-bookworm | ok 2025-11-28 22:01:58.754611 | 2025-11-28 22:01:58.754765 | TASK [emit-job-header : Print job information] 2025-11-28 22:01:58.831147 | # Job Information 2025-11-28 22:01:58.831429 | Ansible Version: 2.16.14 2025-11-28 22:01:58.831465 | Job: python-black 2025-11-28 22:01:58.831501 | Pipeline: check 2025-11-28 22:01:58.831524 | Executor: 521e9411259a 2025-11-28 22:01:58.831545 | Triggered by: https://github.com/osism/python-osism/pull/1864 2025-11-28 22:01:58.831567 | Event ID: d1806c50-cca5-11f0-8fdf-16590300b509 2025-11-28 22:01:58.838007 | 2025-11-28 22:01:58.838170 | LOOP [emit-job-header : Print node information] 2025-11-28 22:01:59.020987 | debian-bookworm | ok: 2025-11-28 22:01:59.021211 | debian-bookworm | # Node Information 2025-11-28 22:01:59.021246 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-11-28 22:01:59.021271 | debian-bookworm | Hostname: debian 2025-11-28 22:01:59.021292 | debian-bookworm | Username: zuul 2025-11-28 22:01:59.021313 | debian-bookworm | Distro: Debian 12.12 2025-11-28 22:01:59.021336 | debian-bookworm | Provider: regiocloud-a 2025-11-28 22:01:59.021357 | debian-bookworm | Region: 2025-11-28 22:01:59.021378 | debian-bookworm | Label: debian-bookworm 2025-11-28 22:01:59.021397 | debian-bookworm | Product Name: OpenStack Nova 2025-11-28 22:01:59.021417 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe5a:51e9 2025-11-28 22:01:59.035814 | 2025-11-28 22:01:59.035961 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-11-28 22:02:00.156604 | debian-bookworm -> localhost | changed 2025-11-28 22:02:00.164353 | 2025-11-28 22:02:00.164493 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-11-28 22:02:02.104126 | debian-bookworm -> localhost | changed 2025-11-28 22:02:02.125898 | 2025-11-28 22:02:02.126148 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-11-28 22:02:02.781330 | debian-bookworm -> localhost | ok 2025-11-28 22:02:02.787854 | 2025-11-28 22:02:02.787988 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-11-28 22:02:02.836661 | debian-bookworm | ok 2025-11-28 22:02:02.853374 | debian-bookworm | included: /var/lib/zuul/builds/11471848737e45b79de581a41c64f5bc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-11-28 22:02:02.858962 | 2025-11-28 22:02:02.859170 | TASK [add-build-sshkey : Create Temp SSH key] 2025-11-28 22:02:05.014018 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-11-28 22:02:05.014199 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/11471848737e45b79de581a41c64f5bc/work/11471848737e45b79de581a41c64f5bc_id_rsa 2025-11-28 22:02:05.014230 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/11471848737e45b79de581a41c64f5bc/work/11471848737e45b79de581a41c64f5bc_id_rsa.pub 2025-11-28 22:02:05.014252 | debian-bookworm -> localhost | The key fingerprint is: 2025-11-28 22:02:05.014271 | debian-bookworm -> localhost | SHA256:V2WBxVjlQpoCTzC0SSySW4ZdchKVNH0gUxvkr5Ov3nA zuul-build-sshkey 2025-11-28 22:02:05.014290 | debian-bookworm -> localhost | The key's randomart image is: 2025-11-28 22:02:05.014317 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-11-28 22:02:05.014336 | debian-bookworm -> localhost | | ++B#**. *Bo.| 2025-11-28 22:02:05.014354 | debian-bookworm -> localhost | | + ==o%.o+*.. | 2025-11-28 22:02:05.014371 | debian-bookworm -> localhost | | = .o =.+ . .| 2025-11-28 22:02:05.014389 | debian-bookworm -> localhost | | . + . | 2025-11-28 22:02:05.014406 | debian-bookworm -> localhost | | S . . | 2025-11-28 22:02:05.014429 | debian-bookworm -> localhost | | . o | 2025-11-28 22:02:05.014446 | debian-bookworm -> localhost | | = E | 2025-11-28 22:02:05.014462 | debian-bookworm -> localhost | | * | 2025-11-28 22:02:05.014480 | debian-bookworm -> localhost | | .o.o | 2025-11-28 22:02:05.014497 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-11-28 22:02:05.014541 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.428955 2025-11-28 22:02:05.019871 | 2025-11-28 22:02:05.019946 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-11-28 22:02:05.056628 | debian-bookworm | ok 2025-11-28 22:02:05.065350 | debian-bookworm | included: /var/lib/zuul/builds/11471848737e45b79de581a41c64f5bc/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-11-28 22:02:05.083281 | 2025-11-28 22:02:05.083373 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-11-28 22:02:05.121022 | debian-bookworm | skipping: Conditional result was False 2025-11-28 22:02:05.125777 | 2025-11-28 22:02:05.125864 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-11-28 22:02:05.754774 | debian-bookworm | changed 2025-11-28 22:02:05.764625 | 2025-11-28 22:02:05.764953 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-11-28 22:02:06.036870 | debian-bookworm | ok 2025-11-28 22:02:06.050109 | 2025-11-28 22:02:06.050236 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-11-28 22:02:06.661603 | debian-bookworm | changed 2025-11-28 22:02:06.667965 | 2025-11-28 22:02:06.668081 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-11-28 22:02:07.327379 | debian-bookworm | changed 2025-11-28 22:02:07.338225 | 2025-11-28 22:02:07.338338 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-11-28 22:02:07.361288 | debian-bookworm | skipping: Conditional result was False 2025-11-28 22:02:07.367253 | 2025-11-28 22:02:07.367348 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-11-28 22:02:07.872163 | debian-bookworm -> localhost | changed 2025-11-28 22:02:07.886290 | 2025-11-28 22:02:07.886391 | TASK [add-build-sshkey : Add back temp key] 2025-11-28 22:02:08.397819 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/11471848737e45b79de581a41c64f5bc/work/11471848737e45b79de581a41c64f5bc_id_rsa (zuul-build-sshkey) 2025-11-28 22:02:08.398028 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023648 2025-11-28 22:02:08.405393 | 2025-11-28 22:02:08.405485 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-11-28 22:02:08.797188 | debian-bookworm | ok 2025-11-28 22:02:08.802133 | 2025-11-28 22:02:08.802239 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-11-28 22:02:08.845301 | debian-bookworm | skipping: Conditional result was False 2025-11-28 22:02:08.855322 | 2025-11-28 22:02:08.855428 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-11-28 22:02:09.275595 | debian-bookworm | ok 2025-11-28 22:02:09.292267 | 2025-11-28 22:02:09.292362 | TASK [validate-host : Define zuul_info_dir fact] 2025-11-28 22:02:09.318707 | debian-bookworm | ok 2025-11-28 22:02:09.322736 | 2025-11-28 22:02:09.322809 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-11-28 22:02:09.750908 | debian-bookworm -> localhost | ok 2025-11-28 22:02:09.756008 | 2025-11-28 22:02:09.756117 | TASK [validate-host : Collect information about the host] 2025-11-28 22:02:10.830519 | debian-bookworm | ok 2025-11-28 22:02:10.840250 | 2025-11-28 22:02:10.840344 | TASK [validate-host : Sanitize hostname] 2025-11-28 22:02:10.928843 | debian-bookworm | ok 2025-11-28 22:02:10.966723 | 2025-11-28 22:02:10.966817 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-11-28 22:02:11.561332 | debian-bookworm -> localhost | changed 2025-11-28 22:02:11.566649 | 2025-11-28 22:02:11.566750 | TASK [validate-host : Collect information about zuul worker] 2025-11-28 22:02:12.048104 | debian-bookworm | ok 2025-11-28 22:02:12.052665 | 2025-11-28 22:02:12.052758 | TASK [validate-host : Write out all zuul information for each host] 2025-11-28 22:02:12.767333 | debian-bookworm -> localhost | changed 2025-11-28 22:02:12.776948 | 2025-11-28 22:02:12.777033 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-11-28 22:02:13.055364 | debian-bookworm | ok 2025-11-28 22:02:13.063406 | 2025-11-28 22:02:13.063501 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-11-28 22:02:14.255407 | debian-bookworm | changed: 2025-11-28 22:02:14.255704 | debian-bookworm | cd+++++++++ src/ 2025-11-28 22:02:14.255765 | debian-bookworm | cd+++++++++ src/github.com/ 2025-11-28 22:02:14.255807 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-11-28 22:02:14.255844 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-11-28 22:02:14.255880 | debian-bookworm | Containerfile 2025-11-28 22:02:14.256101 | debian-bookworm | Containerfile 2025-11-28 22:02:14.284800 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007279 2025-11-28 22:02:41.513358 | 2025-11-28 22:02:41.513477 | TASK [upload-logs : Upload logs to log server] 2025-11-28 22:02:42.089225 | localhost | Output suppressed because no_log was given 2025-11-28 22:02:42.092235 | 2025-11-28 22:02:42.092399 | LOOP [upload-logs : Compress console log and json output] 2025-11-28 22:02:42.147184 | localhost | skipping: Conditional result was False 2025-11-28 22:02:42.158085 | localhost | skipping: Conditional result was False 2025-11-28 22:02:42.165451 | 2025-11-28 22:02:42.165814 | LOOP [upload-logs : Upload compressed console log and json output] 2025-11-28 22:02:42.224033 | localhost | skipping: Conditional result was False 2025-11-28 22:02:42.224745 | 2025-11-28 22:02:42.228052 | localhost | skipping: Conditional result was False 2025-11-28 22:02:42.240970 | 2025-11-28 22:02:42.241195 | LOOP [upload-logs : Upload console log and json output]