2025-06-03 07:50:49.018710 | Job console starting 2025-06-03 07:50:49.029054 | Updating git repos 2025-06-03 07:50:49.068913 | Cloning repos into workspace 2025-06-03 07:50:49.121003 | Restoring repo states 2025-06-03 07:50:49.129453 | Merging changes 2025-06-03 07:50:49.763966 | Checking out repos 2025-06-03 07:50:49.809867 | Preparing playbooks 2025-06-03 07:50:50.401763 | Running Ansible setup 2025-06-03 07:50:55.094053 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-03 07:50:55.930520 | 2025-06-03 07:50:55.930689 | PLAY [Base pre] 2025-06-03 07:50:55.960544 | 2025-06-03 07:50:55.960712 | TASK [Setup log path fact] 2025-06-03 07:50:55.983294 | debian-bookworm | ok 2025-06-03 07:50:56.003176 | 2025-06-03 07:50:56.003356 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-03 07:50:56.037447 | debian-bookworm | ok 2025-06-03 07:50:56.054132 | 2025-06-03 07:50:56.054277 | TASK [emit-job-header : Print job information] 2025-06-03 07:50:56.095598 | # Job Information 2025-06-03 07:50:56.095912 | Ansible Version: 2.16.14 2025-06-03 07:50:56.095955 | Job: python-black 2025-06-03 07:50:56.095991 | Pipeline: check 2025-06-03 07:50:56.096014 | Executor: 521e9411259a 2025-06-03 07:50:56.096035 | Triggered by: https://github.com/osism/testbed/pull/2701 2025-06-03 07:50:56.096057 | Event ID: 6b9498e0-404f-11f0-8fac-eec63dc7f5ea 2025-06-03 07:50:56.105707 | 2025-06-03 07:50:56.105892 | LOOP [emit-job-header : Print node information] 2025-06-03 07:50:56.282900 | debian-bookworm | ok: 2025-06-03 07:50:56.283116 | debian-bookworm | # Node Information 2025-06-03 07:50:56.283153 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-03 07:50:56.283202 | debian-bookworm | Hostname: debian 2025-06-03 07:50:56.283225 | debian-bookworm | Username: zuul 2025-06-03 07:50:56.283516 | debian-bookworm | Distro: Debian 12.11 2025-06-03 07:50:56.283592 | debian-bookworm | Provider: regiocloud-a 2025-06-03 07:50:56.283620 | debian-bookworm | Region: 2025-06-03 07:50:56.283644 | debian-bookworm | Label: debian-bookworm 2025-06-03 07:50:56.283665 | debian-bookworm | Product Name: OpenStack Nova 2025-06-03 07:50:56.283687 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feb8:624e 2025-06-03 07:50:56.310503 | 2025-06-03 07:50:56.310646 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-03 07:50:56.834606 | debian-bookworm -> localhost | changed 2025-06-03 07:50:56.842325 | 2025-06-03 07:50:56.842442 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-03 07:50:58.323918 | debian-bookworm -> localhost | changed 2025-06-03 07:50:58.353057 | 2025-06-03 07:50:58.353252 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-03 07:50:58.766957 | debian-bookworm -> localhost | ok 2025-06-03 07:50:58.774144 | 2025-06-03 07:50:58.774284 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-03 07:50:58.805847 | debian-bookworm | ok 2025-06-03 07:50:58.839529 | debian-bookworm | included: /var/lib/zuul/builds/e028ece765ec4c2eb9710c55f9eaaa36/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-03 07:50:58.848279 | 2025-06-03 07:50:58.848400 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-03 07:51:00.875456 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-03 07:51:00.875727 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e028ece765ec4c2eb9710c55f9eaaa36/work/e028ece765ec4c2eb9710c55f9eaaa36_id_rsa 2025-06-03 07:51:00.875769 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e028ece765ec4c2eb9710c55f9eaaa36/work/e028ece765ec4c2eb9710c55f9eaaa36_id_rsa.pub 2025-06-03 07:51:00.875797 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-03 07:51:00.875842 | debian-bookworm -> localhost | SHA256:n0+kk6lryt32/QPUcE7TweYC5fX5kk0kOwMD4ntDy6U zuul-build-sshkey 2025-06-03 07:51:00.875866 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-03 07:51:00.875900 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-03 07:51:00.875924 | debian-bookworm -> localhost | | . ..+.oo+| 2025-06-03 07:51:00.875947 | debian-bookworm -> localhost | | . . ..+.X+| 2025-06-03 07:51:00.875969 | debian-bookworm -> localhost | | . . o.%.+| 2025-06-03 07:51:00.875989 | debian-bookworm -> localhost | | + + o X.| 2025-06-03 07:51:00.876010 | debian-bookworm -> localhost | | S E o + o| 2025-06-03 07:51:00.876033 | debian-bookworm -> localhost | | o B . . | 2025-06-03 07:51:00.876053 | debian-bookworm -> localhost | | B . . | 2025-06-03 07:51:00.876073 | debian-bookworm -> localhost | | . ..o.+ . . | 2025-06-03 07:51:00.876095 | debian-bookworm -> localhost | | oo+o..o ..o| 2025-06-03 07:51:00.876116 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-03 07:51:00.876172 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.421823 2025-06-03 07:51:00.883433 | 2025-06-03 07:51:00.883650 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-03 07:51:00.914381 | debian-bookworm | ok 2025-06-03 07:51:00.928561 | debian-bookworm | included: /var/lib/zuul/builds/e028ece765ec4c2eb9710c55f9eaaa36/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-03 07:51:00.940271 | 2025-06-03 07:51:00.940369 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-03 07:51:00.964041 | debian-bookworm | skipping: Conditional result was False 2025-06-03 07:51:00.972179 | 2025-06-03 07:51:00.972308 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-03 07:51:01.609874 | debian-bookworm | changed 2025-06-03 07:51:01.618384 | 2025-06-03 07:51:01.618506 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-03 07:51:01.890995 | debian-bookworm | ok 2025-06-03 07:51:01.907986 | 2025-06-03 07:51:01.908186 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-03 07:51:02.596508 | debian-bookworm | changed 2025-06-03 07:51:02.612083 | 2025-06-03 07:51:02.612272 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-03 07:51:03.252197 | debian-bookworm | changed 2025-06-03 07:51:03.261168 | 2025-06-03 07:51:03.261304 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-03 07:51:03.288350 | debian-bookworm | skipping: Conditional result was False 2025-06-03 07:51:03.303038 | 2025-06-03 07:51:03.303247 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-03 07:51:03.773495 | debian-bookworm -> localhost | changed 2025-06-03 07:51:03.798535 | 2025-06-03 07:51:03.798711 | TASK [add-build-sshkey : Add back temp key] 2025-06-03 07:51:04.150282 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e028ece765ec4c2eb9710c55f9eaaa36/work/e028ece765ec4c2eb9710c55f9eaaa36_id_rsa (zuul-build-sshkey) 2025-06-03 07:51:04.150564 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.021997 2025-06-03 07:51:04.158781 | 2025-06-03 07:51:04.158998 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-03 07:51:04.579781 | debian-bookworm | ok 2025-06-03 07:51:04.587450 | 2025-06-03 07:51:04.587583 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-03 07:51:04.621965 | debian-bookworm | skipping: Conditional result was False 2025-06-03 07:51:04.641248 | 2025-06-03 07:51:04.641416 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-03 07:51:05.106364 | debian-bookworm | ok 2025-06-03 07:51:05.122588 | 2025-06-03 07:51:05.122722 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-03 07:51:05.177067 | debian-bookworm | ok 2025-06-03 07:51:05.184304 | 2025-06-03 07:51:05.184424 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-03 07:51:05.493343 | debian-bookworm -> localhost | ok 2025-06-03 07:51:05.508152 | 2025-06-03 07:51:05.508298 | TASK [validate-host : Collect information about the host] 2025-06-03 07:51:06.632446 | debian-bookworm | ok 2025-06-03 07:51:06.644582 | 2025-06-03 07:51:06.644701 | TASK [validate-host : Sanitize hostname] 2025-06-03 07:51:06.776557 | debian-bookworm | ok 2025-06-03 07:51:06.827533 | 2025-06-03 07:51:06.827699 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-03 07:51:07.453467 | debian-bookworm -> localhost | changed 2025-06-03 07:51:07.462906 | 2025-06-03 07:51:07.463130 | TASK [validate-host : Collect information about zuul worker] 2025-06-03 07:51:07.897487 | debian-bookworm | ok 2025-06-03 07:51:07.905555 | 2025-06-03 07:51:07.905704 | TASK [validate-host : Write out all zuul information for each host] 2025-06-03 07:51:08.493518 | debian-bookworm -> localhost | changed 2025-06-03 07:51:08.513929 | 2025-06-03 07:51:08.514092 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-03 07:51:08.803117 | debian-bookworm | ok 2025-06-03 07:51:08.812238 | 2025-06-03 07:51:08.812362 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-03 07:51:09.893956 | debian-bookworm | changed: 2025-06-03 07:51:09.894286 | debian-bookworm | cd+++++++++ src/ 2025-06-03 07:51:09.894350 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-03 07:51:09.894393 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-03 07:51:09.894432 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-06-03 07:51:09.894468 | debian-bookworm | localhost | ok: Runtime: 0:00:00.011547 2025-06-03 07:51:35.015228 | 2025-06-03 07:51:35.015343 | TASK [upload-logs : Upload logs to log server] 2025-06-03 07:51:35.642714 | localhost | Output suppressed because no_log was given 2025-06-03 07:51:35.646619 | 2025-06-03 07:51:35.646785 | LOOP [upload-logs : Compress console log and json output] 2025-06-03 07:51:35.708402 | localhost | skipping: Conditional result was False 2025-06-03 07:51:35.714190 | localhost | skipping: Conditional result was False 2025-06-03 07:51:35.727265 | 2025-06-03 07:51:35.727463 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-03 07:51:35.776996 | localhost | skipping: Conditional result was False 2025-06-03 07:51:35.777750 | 2025-06-03 07:51:35.781974 | localhost | skipping: Conditional result was False 2025-06-03 07:51:35.791600 | 2025-06-03 07:51:35.791889 | LOOP [upload-logs : Upload console log and json output]