2026-08-22 13:55:53.735046 | Job console starting 2026-08-22 13:55:53.775096 | Updating git repos 2026-08-22 13:55:53.831455 | Cloning repos into workspace 2026-08-22 13:55:53.902316 | Restoring repo states 2026-08-22 13:55:53.913655 | Merging changes 2026-08-22 13:55:54.512639 | Checking out repos 2026-08-22 13:55:54.565697 | Preparing playbooks 2026-08-22 13:55:55.560228 | Running Ansible setup 2026-08-22 13:56:00.322921 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-22 13:56:01.259085 | 2026-08-22 13:56:01.259282 | PLAY [Base pre] 2026-08-22 13:56:01.280831 | 2026-08-22 13:56:01.281061 | TASK [Setup log path fact] 2026-08-22 13:56:01.308725 | debian-bookworm | ok 2026-08-22 13:56:01.328142 | 2026-08-22 13:56:01.328333 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-22 13:56:01.368731 | debian-bookworm | ok 2026-08-22 13:56:01.379189 | 2026-08-22 13:56:01.379328 | TASK [emit-job-header : Print job information] 2026-08-22 13:56:01.419569 | # Job Information 2026-08-22 13:56:01.419846 | Ansible Version: 2.16.14 2026-08-22 13:56:01.419884 | Job: python-black 2026-08-22 13:56:01.420083 | Pipeline: check 2026-08-22 13:56:01.420121 | Executor: 521e9411259a 2026-08-22 13:56:01.420143 | Triggered by: https://github.com/osism/testbed/pull/2949 2026-08-22 13:56:01.420167 | Event ID: 2a3357b0-9e31-11f1-9888-822fb8b56d05 2026-08-22 13:56:01.425709 | 2026-08-22 13:56:01.425864 | LOOP [emit-job-header : Print node information] 2026-08-22 13:56:01.546441 | debian-bookworm | ok: 2026-08-22 13:56:01.546743 | debian-bookworm | # Node Information 2026-08-22 13:56:01.546784 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-22 13:56:01.546809 | debian-bookworm | Hostname: debian 2026-08-22 13:56:01.546864 | debian-bookworm | Username: zuul 2026-08-22 13:56:01.546887 | debian-bookworm | Distro: Debian 12.15 2026-08-22 13:56:01.546929 | debian-bookworm | Provider: regiocloud-a 2026-08-22 13:56:01.546950 | debian-bookworm | Region: 2026-08-22 13:56:01.546973 | debian-bookworm | Label: debian-bookworm 2026-08-22 13:56:01.546993 | debian-bookworm | Product Name: OpenStack Nova 2026-08-22 13:56:01.547013 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4c:bb60 2026-08-22 13:56:01.563622 | 2026-08-22 13:56:01.563786 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-22 13:56:02.078249 | debian-bookworm -> localhost | changed 2026-08-22 13:56:02.086486 | 2026-08-22 13:56:02.086658 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-22 13:56:03.391920 | debian-bookworm -> localhost | changed 2026-08-22 13:56:03.407467 | 2026-08-22 13:56:03.407704 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-22 13:56:03.708317 | debian-bookworm -> localhost | ok 2026-08-22 13:56:03.715318 | 2026-08-22 13:56:03.715461 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-22 13:56:03.747225 | debian-bookworm | ok 2026-08-22 13:56:03.767362 | debian-bookworm | included: /var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-22 13:56:03.774068 | 2026-08-22 13:56:03.774203 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-22 13:56:05.353708 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-22 13:56:05.354029 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/work/4e6f747d4f894c789460026c2cb448c7_id_rsa 2026-08-22 13:56:05.354073 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/work/4e6f747d4f894c789460026c2cb448c7_id_rsa.pub 2026-08-22 13:56:05.354100 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-22 13:56:05.354125 | debian-bookworm -> localhost | SHA256:Zb24GTFPE3xAeG+DFSlom//6g9DTf5KtnzVfnrgrBRQ zuul-build-sshkey 2026-08-22 13:56:05.354148 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-22 13:56:05.354187 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-22 13:56:05.354209 | debian-bookworm -> localhost | | E=..o | 2026-08-22 13:56:05.354231 | debian-bookworm -> localhost | | +o+.+ | 2026-08-22 13:56:05.354250 | debian-bookworm -> localhost | | .*++* | 2026-08-22 13:56:05.354270 | debian-bookworm -> localhost | | ooB.o+ | 2026-08-22 13:56:05.354290 | debian-bookworm -> localhost | | S oo+o . | 2026-08-22 13:56:05.354314 | debian-bookworm -> localhost | | .++.. | 2026-08-22 13:56:05.354334 | debian-bookworm -> localhost | | o..+ =o| 2026-08-22 13:56:05.354353 | debian-bookworm -> localhost | | .. *.@| 2026-08-22 13:56:05.354374 | debian-bookworm -> localhost | | o*=B=| 2026-08-22 13:56:05.354394 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-22 13:56:05.354454 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.969653 2026-08-22 13:56:05.361354 | 2026-08-22 13:56:05.361499 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-22 13:56:05.398014 | debian-bookworm | ok 2026-08-22 13:56:05.411410 | debian-bookworm | included: /var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-22 13:56:05.420012 | 2026-08-22 13:56:05.420128 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-22 13:56:05.454824 | debian-bookworm | skipping: Conditional result was False 2026-08-22 13:56:05.463053 | 2026-08-22 13:56:05.463204 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-22 13:56:06.141290 | debian-bookworm | changed 2026-08-22 13:56:06.150523 | 2026-08-22 13:56:06.150680 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-22 13:56:06.429687 | debian-bookworm | ok 2026-08-22 13:56:06.439026 | 2026-08-22 13:56:06.439197 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-22 13:56:07.158484 | debian-bookworm | changed 2026-08-22 13:56:07.170347 | 2026-08-22 13:56:07.170590 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-22 13:56:07.847366 | debian-bookworm | changed 2026-08-22 13:56:07.856182 | 2026-08-22 13:56:07.856345 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-22 13:56:07.891556 | debian-bookworm | skipping: Conditional result was False 2026-08-22 13:56:07.906401 | 2026-08-22 13:56:07.906587 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-22 13:56:08.405567 | debian-bookworm -> localhost | changed 2026-08-22 13:56:08.428058 | 2026-08-22 13:56:08.428239 | TASK [add-build-sshkey : Add back temp key] 2026-08-22 13:56:08.835269 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/work/4e6f747d4f894c789460026c2cb448c7_id_rsa (zuul-build-sshkey) 2026-08-22 13:56:08.835543 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.046993 2026-08-22 13:56:08.844159 | 2026-08-22 13:56:08.844281 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-22 13:56:09.280073 | debian-bookworm | ok 2026-08-22 13:56:09.294490 | 2026-08-22 13:56:09.294661 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-22 13:56:09.330101 | debian-bookworm | skipping: Conditional result was False 2026-08-22 13:56:09.343352 | 2026-08-22 13:56:09.343519 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-22 13:56:09.809228 | debian-bookworm | ok 2026-08-22 13:56:09.823433 | 2026-08-22 13:56:09.823585 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-22 13:56:09.854926 | debian-bookworm | ok 2026-08-22 13:56:09.861464 | 2026-08-22 13:56:09.861617 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-22 13:56:10.163321 | debian-bookworm -> localhost | ok 2026-08-22 13:56:10.170234 | 2026-08-22 13:56:10.170365 | TASK [validate-host : Collect information about the host] 2026-08-22 13:56:11.385812 | debian-bookworm | ok 2026-08-22 13:56:11.401363 | 2026-08-22 13:56:11.401515 | TASK [validate-host : Sanitize hostname] 2026-08-22 13:56:11.571908 | debian-bookworm | ok 2026-08-22 13:56:11.576886 | 2026-08-22 13:56:11.577070 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-22 13:56:12.191972 | debian-bookworm -> localhost | changed 2026-08-22 13:56:12.199402 | 2026-08-22 13:56:12.199542 | TASK [validate-host : Collect information about zuul worker] 2026-08-22 13:56:12.657278 | debian-bookworm | ok 2026-08-22 13:56:12.664387 | 2026-08-22 13:56:12.664546 | TASK [validate-host : Write out all zuul information for each host] 2026-08-22 13:56:13.281738 | debian-bookworm -> localhost | changed 2026-08-22 13:56:13.291937 | 2026-08-22 13:56:13.292082 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-22 13:56:13.563713 | debian-bookworm | ok 2026-08-22 13:56:13.579416 | 2026-08-22 13:56:13.579566 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-22 13:56:15.311291 | debian-bookworm | changed: 2026-08-22 13:56:15.311564 | debian-bookworm | cd+++++++++ src/ 2026-08-22 13:56:15.311604 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-22 13:56:15.311628 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-22 13:56:15.311650 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-08-22 13:56:15.311670 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/work/logs" 2026-08-22 13:57:16.515356 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/work/artifacts" 2026-08-22 13:57:16.778170 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/4e6f747d4f894c789460026c2cb448c7/work/docs" 2026-08-22 13:57:16.790454 | 2026-08-22 13:57:16.790604 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-22 13:57:17.610141 | debian-bookworm | changed: 2026-08-22 13:57:17.610426 | debian-bookworm | .d..t...... ./ 2026-08-22 13:57:17.610477 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-22 13:57:17.610509 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-22 13:57:17.610539 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-22 13:57:17.610567 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-22 13:57:17.610595 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-22 13:57:17.610646 | debian-bookworm | changed: All items complete 2026-08-22 13:57:17.610679 | 2026-08-22 13:57:18.176958 | debian-bookworm | changed: .d..t...... ./ 2026-08-22 13:57:18.738609 | debian-bookworm | changed: .d..t...... ./ 2026-08-22 13:57:18.776365 | 2026-08-22 13:57:18.776552 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-22 13:57:19.120765 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.010066 2026-08-22 13:57:19.402760 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007552 2026-08-22 13:57:19.416987 | 2026-08-22 13:57:19.417127 | PLAY RECAP 2026-08-22 13:57:19.417186 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-08-22 13:57:19.417211 | 2026-08-22 13:57:19.647583 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-22 13:57:19.648472 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-22 13:57:20.390356 | 2026-08-22 13:57:20.390546 | PLAY [Base post] 2026-08-22 13:57:20.405095 | 2026-08-22 13:57:20.405260 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-22 13:57:20.973009 | debian-bookworm | changed 2026-08-22 13:57:20.991031 | 2026-08-22 13:57:20.991179 | PLAY RECAP 2026-08-22 13:57:20.991250 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-22 13:57:20.991314 | 2026-08-22 13:57:21.177568 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-22 13:57:21.178778 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-22 13:57:21.984473 | 2026-08-22 13:57:21.984662 | PLAY [Base post-logs] 2026-08-22 13:57:21.995691 | 2026-08-22 13:57:21.995851 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-22 13:57:22.510311 | localhost | changed 2026-08-22 13:57:22.527242 | 2026-08-22 13:57:22.533637 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-22 13:57:22.563378 | localhost | ok 2026-08-22 13:57:22.574311 | 2026-08-22 13:57:22.574644 | TASK [Set zuul-log-path fact] 2026-08-22 13:57:22.601935 | localhost | ok 2026-08-22 13:57:22.610806 | 2026-08-22 13:57:22.610995 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-22 13:57:22.647030 | localhost | ok 2026-08-22 13:57:22.650738 | 2026-08-22 13:57:22.650874 | TASK [upload-logs : Create log directories] 2026-08-22 13:57:23.192364 | localhost | changed 2026-08-22 13:57:23.195350 | 2026-08-22 13:57:23.195459 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-22 13:57:23.727191 | localhost -> localhost | ok: Runtime: 0:00:00.007738 2026-08-22 13:57:23.732337 | 2026-08-22 13:57:23.732477 | TASK [upload-logs : Upload logs to log server] 2026-08-22 13:57:24.317175 | localhost | Output suppressed because no_log was given 2026-08-22 13:57:24.319322 | 2026-08-22 13:57:24.319429 | LOOP [upload-logs : Compress console log and json output] 2026-08-22 13:57:24.371963 | localhost | skipping: Conditional result was False 2026-08-22 13:57:24.382669 | localhost | skipping: Conditional result was False 2026-08-22 13:57:24.390755 | 2026-08-22 13:57:24.391026 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-22 13:57:24.457377 | localhost | skipping: Conditional result was False 2026-08-22 13:57:24.457733 | 2026-08-22 13:57:24.463060 | localhost | skipping: Conditional result was False 2026-08-22 13:57:24.473360 | 2026-08-22 13:57:24.473501 | LOOP [upload-logs : Upload console log and json output]