2025-12-09 18:29:49.146554 | Job console starting 2025-12-09 18:29:49.159987 | Updating git repos 2025-12-09 18:29:49.194791 | Cloning repos into workspace 2025-12-09 18:29:49.259717 | Restoring repo states 2025-12-09 18:29:49.265517 | Merging changes 2025-12-09 18:29:49.824844 | Checking out repos 2025-12-09 18:29:49.954291 | Preparing playbooks 2025-12-09 18:29:50.793900 | Running Ansible setup 2025-12-09 18:29:55.983025 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-09 18:29:56.957117 | 2025-12-09 18:29:56.957392 | PLAY [Base pre] 2025-12-09 18:29:57.009786 | 2025-12-09 18:29:57.009958 | TASK [Setup log path fact] 2025-12-09 18:29:57.032473 | debian-bookworm | ok 2025-12-09 18:29:57.055790 | 2025-12-09 18:29:57.055974 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-09 18:29:57.087397 | debian-bookworm | ok 2025-12-09 18:29:57.110587 | 2025-12-09 18:29:57.110737 | TASK [emit-job-header : Print job information] 2025-12-09 18:29:57.151086 | # Job Information 2025-12-09 18:29:57.151287 | Ansible Version: 2.16.14 2025-12-09 18:29:57.151325 | Job: python-black 2025-12-09 18:29:57.151357 | Pipeline: check 2025-12-09 18:29:57.151379 | Executor: 521e9411259a 2025-12-09 18:29:57.151400 | Triggered by: https://github.com/osism/testbed/pull/2804 2025-12-09 18:29:57.151422 | Event ID: 061724b0-d52d-11f0-983e-33c8ebc1d121 2025-12-09 18:29:57.156449 | 2025-12-09 18:29:57.156574 | LOOP [emit-job-header : Print node information] 2025-12-09 18:29:57.273189 | debian-bookworm | ok: 2025-12-09 18:29:57.273589 | debian-bookworm | # Node Information 2025-12-09 18:29:57.273640 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-09 18:29:57.273669 | debian-bookworm | Hostname: debian 2025-12-09 18:29:57.273692 | debian-bookworm | Username: zuul 2025-12-09 18:29:57.273715 | debian-bookworm | Distro: Debian 12.12 2025-12-09 18:29:57.273739 | debian-bookworm | Provider: regiocloud-a 2025-12-09 18:29:57.273760 | debian-bookworm | Region: 2025-12-09 18:29:57.273781 | debian-bookworm | Label: debian-bookworm 2025-12-09 18:29:57.273801 | debian-bookworm | Product Name: OpenStack Nova 2025-12-09 18:29:57.273820 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe40:332a 2025-12-09 18:29:57.294758 | 2025-12-09 18:29:57.294926 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-09 18:29:57.811260 | debian-bookworm -> localhost | changed 2025-12-09 18:29:57.818946 | 2025-12-09 18:29:57.819096 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-09 18:29:59.350115 | debian-bookworm -> localhost | changed 2025-12-09 18:29:59.362610 | 2025-12-09 18:29:59.362756 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-09 18:29:59.823233 | debian-bookworm -> localhost | ok 2025-12-09 18:29:59.829793 | 2025-12-09 18:29:59.829945 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-09 18:29:59.859497 | debian-bookworm | ok 2025-12-09 18:29:59.878490 | debian-bookworm | included: /var/lib/zuul/builds/ca430c4e76704dcc83eb38cb922591f2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-09 18:29:59.887081 | 2025-12-09 18:29:59.887226 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-09 18:30:01.474348 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-09 18:30:01.474599 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ca430c4e76704dcc83eb38cb922591f2/work/ca430c4e76704dcc83eb38cb922591f2_id_rsa 2025-12-09 18:30:01.474639 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ca430c4e76704dcc83eb38cb922591f2/work/ca430c4e76704dcc83eb38cb922591f2_id_rsa.pub 2025-12-09 18:30:01.474665 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-09 18:30:01.474691 | debian-bookworm -> localhost | SHA256:TOelJlbs1bfADejjd3O0qdXOS/fBdNA5E2hGREQezc8 zuul-build-sshkey 2025-12-09 18:30:01.474713 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-09 18:30:01.474743 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-09 18:30:01.474765 | debian-bookworm -> localhost | | BO+. | 2025-12-09 18:30:01.474786 | debian-bookworm -> localhost | | . .o=++o| 2025-12-09 18:30:01.474805 | debian-bookworm -> localhost | | . = =+o*+| 2025-12-09 18:30:01.474823 | debian-bookworm -> localhost | | o = * .oE| 2025-12-09 18:30:01.474878 | debian-bookworm -> localhost | | S * . +=| 2025-12-09 18:30:01.474906 | debian-bookworm -> localhost | | . o . .o*+| 2025-12-09 18:30:01.474926 | debian-bookworm -> localhost | | . +*+| 2025-12-09 18:30:01.474945 | debian-bookworm -> localhost | | .. *| 2025-12-09 18:30:01.474965 | debian-bookworm -> localhost | | .o| 2025-12-09 18:30:01.474985 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-09 18:30:01.475040 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.728990 2025-12-09 18:30:01.488465 | 2025-12-09 18:30:01.488604 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-09 18:30:01.524758 | debian-bookworm | ok 2025-12-09 18:30:01.538536 | debian-bookworm | included: /var/lib/zuul/builds/ca430c4e76704dcc83eb38cb922591f2/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-09 18:30:01.553316 | 2025-12-09 18:30:01.553463 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-09 18:30:01.580404 | debian-bookworm | skipping: Conditional result was False 2025-12-09 18:30:01.587225 | 2025-12-09 18:30:01.587370 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-09 18:30:02.314276 | debian-bookworm | changed 2025-12-09 18:30:02.321067 | 2025-12-09 18:30:02.321254 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-09 18:30:02.588037 | debian-bookworm | ok 2025-12-09 18:30:02.598198 | 2025-12-09 18:30:02.598615 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-09 18:30:03.354101 | debian-bookworm | changed 2025-12-09 18:30:03.365065 | 2025-12-09 18:30:03.365239 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-09 18:30:04.077972 | debian-bookworm | changed 2025-12-09 18:30:04.086021 | 2025-12-09 18:30:04.086194 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-09 18:30:04.122442 | debian-bookworm | skipping: Conditional result was False 2025-12-09 18:30:04.132044 | 2025-12-09 18:30:04.132232 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-09 18:30:04.696215 | debian-bookworm -> localhost | changed 2025-12-09 18:30:04.721721 | 2025-12-09 18:30:04.721894 | TASK [add-build-sshkey : Add back temp key] 2025-12-09 18:30:05.360186 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ca430c4e76704dcc83eb38cb922591f2/work/ca430c4e76704dcc83eb38cb922591f2_id_rsa (zuul-build-sshkey) 2025-12-09 18:30:05.360448 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015727 2025-12-09 18:30:05.373598 | 2025-12-09 18:30:05.374502 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-09 18:30:05.816425 | debian-bookworm | ok 2025-12-09 18:30:05.821992 | 2025-12-09 18:30:05.822141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-09 18:30:05.847161 | debian-bookworm | skipping: Conditional result was False 2025-12-09 18:30:05.858724 | 2025-12-09 18:30:05.858879 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-09 18:30:06.391162 | debian-bookworm | ok 2025-12-09 18:30:06.408764 | 2025-12-09 18:30:06.408998 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-09 18:30:06.439843 | debian-bookworm | ok 2025-12-09 18:30:06.447597 | 2025-12-09 18:30:06.447731 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-09 18:30:06.820409 | debian-bookworm -> localhost | ok 2025-12-09 18:30:06.837392 | 2025-12-09 18:30:06.837575 | TASK [validate-host : Collect information about the host] 2025-12-09 18:30:09.138396 | debian-bookworm | ok 2025-12-09 18:30:09.150399 | 2025-12-09 18:30:09.150551 | TASK [validate-host : Sanitize hostname] 2025-12-09 18:30:09.293331 | debian-bookworm | ok 2025-12-09 18:30:09.347874 | 2025-12-09 18:30:09.348019 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-09 18:30:10.023456 | debian-bookworm -> localhost | changed 2025-12-09 18:30:10.031416 | 2025-12-09 18:30:10.031564 | TASK [validate-host : Collect information about zuul worker] 2025-12-09 18:30:10.493496 | debian-bookworm | ok 2025-12-09 18:30:10.500687 | 2025-12-09 18:30:10.500842 | TASK [validate-host : Write out all zuul information for each host] 2025-12-09 18:30:11.242641 | debian-bookworm -> localhost | changed 2025-12-09 18:30:11.255814 | 2025-12-09 18:30:11.256014 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-09 18:30:11.553607 | debian-bookworm | ok 2025-12-09 18:30:11.560800 | 2025-12-09 18:30:11.560930 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-09 18:30:12.778926 | debian-bookworm | changed: 2025-12-09 18:30:12.779188 | debian-bookworm | cd+++++++++ src/ 2025-12-09 18:30:12.779227 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-09 18:30:12.779252 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-09 18:30:12.779273 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-12-09 18:30:12.779293 | debian-bookworm | localhost | ok: Runtime: 0:00:00.005337 2025-12-09 18:30:40.125518 | 2025-12-09 18:30:40.125680 | TASK [upload-logs : Upload logs to log server] 2025-12-09 18:30:40.885247 | localhost | Output suppressed because no_log was given 2025-12-09 18:30:40.888468 | 2025-12-09 18:30:40.888600 | LOOP [upload-logs : Compress console log and json output] 2025-12-09 18:30:40.947222 | localhost | skipping: Conditional result was False 2025-12-09 18:30:40.957414 | localhost | skipping: Conditional result was False 2025-12-09 18:30:40.967298 | 2025-12-09 18:30:40.967431 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-09 18:30:41.023179 | localhost | skipping: Conditional result was False 2025-12-09 18:30:41.023608 | 2025-12-09 18:30:41.032191 | localhost | skipping: Conditional result was False 2025-12-09 18:30:41.048181 | 2025-12-09 18:30:41.048753 | LOOP [upload-logs : Upload console log and json output]