2025-09-29 22:39:16.272562 | Job console starting 2025-09-29 22:39:16.283610 | Updating git repos 2025-09-29 22:39:16.323243 | Cloning repos into workspace 2025-09-29 22:39:16.376396 | Restoring repo states 2025-09-29 22:39:16.380896 | Merging changes 2025-09-29 22:39:16.886816 | Checking out repos 2025-09-29 22:39:16.971617 | Preparing playbooks 2025-09-29 22:39:17.802528 | Running Ansible setup 2025-09-29 22:39:22.298556 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-29 22:39:23.212803 | 2025-09-29 22:39:23.212960 | PLAY [Base pre] 2025-09-29 22:39:23.233223 | 2025-09-29 22:39:23.233358 | TASK [Setup log path fact] 2025-09-29 22:39:23.262965 | debian-bookworm | ok 2025-09-29 22:39:23.278267 | 2025-09-29 22:39:23.278417 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-29 22:39:23.345117 | debian-bookworm | ok 2025-09-29 22:39:23.355113 | 2025-09-29 22:39:23.355229 | TASK [emit-job-header : Print job information] 2025-09-29 22:39:23.395577 | # Job Information 2025-09-29 22:39:23.395753 | Ansible Version: 2.16.14 2025-09-29 22:39:23.395789 | Job: python-black 2025-09-29 22:39:23.395824 | Pipeline: check 2025-09-29 22:39:23.395848 | Executor: 521e9411259a 2025-09-29 22:39:23.395870 | Triggered by: https://github.com/osism/testbed/pull/2777 2025-09-29 22:39:23.395951 | Event ID: 1b51eb10-9d85-11f0-81d4-d393c717f33c 2025-09-29 22:39:23.400872 | 2025-09-29 22:39:23.400980 | LOOP [emit-job-header : Print node information] 2025-09-29 22:39:23.522480 | debian-bookworm | ok: 2025-09-29 22:39:23.522681 | debian-bookworm | # Node Information 2025-09-29 22:39:23.522716 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-29 22:39:23.522741 | debian-bookworm | Hostname: debian 2025-09-29 22:39:23.522763 | debian-bookworm | Username: zuul 2025-09-29 22:39:23.522784 | debian-bookworm | Distro: Debian 12.12 2025-09-29 22:39:23.522806 | debian-bookworm | Provider: regiocloud-a 2025-09-29 22:39:23.522826 | debian-bookworm | Region: 2025-09-29 22:39:23.522886 | debian-bookworm | Label: debian-bookworm 2025-09-29 22:39:23.522907 | debian-bookworm | Product Name: OpenStack Nova 2025-09-29 22:39:23.522926 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe55:3807 2025-09-29 22:39:23.542003 | 2025-09-29 22:39:23.542185 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-29 22:39:24.056103 | debian-bookworm -> localhost | changed 2025-09-29 22:39:24.063409 | 2025-09-29 22:39:24.063528 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-29 22:39:25.148904 | debian-bookworm -> localhost | changed 2025-09-29 22:39:25.160494 | 2025-09-29 22:39:25.160614 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-29 22:39:25.541096 | debian-bookworm -> localhost | ok 2025-09-29 22:39:25.547861 | 2025-09-29 22:39:25.548001 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-29 22:39:25.577376 | debian-bookworm | ok 2025-09-29 22:39:25.596823 | debian-bookworm | included: /var/lib/zuul/builds/75e1651a718f491aa4a3fc6b736a076f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-29 22:39:25.603125 | 2025-09-29 22:39:25.603233 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-29 22:39:27.320836 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-29 22:39:27.321056 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/75e1651a718f491aa4a3fc6b736a076f/work/75e1651a718f491aa4a3fc6b736a076f_id_rsa 2025-09-29 22:39:27.321100 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/75e1651a718f491aa4a3fc6b736a076f/work/75e1651a718f491aa4a3fc6b736a076f_id_rsa.pub 2025-09-29 22:39:27.321129 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-29 22:39:27.321155 | debian-bookworm -> localhost | SHA256:AiVpnr0XW0ROloUH1HDaT9qccRPiXGpDqTwAadJhQ0I zuul-build-sshkey 2025-09-29 22:39:27.321179 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-29 22:39:27.321211 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-29 22:39:27.321234 | debian-bookworm -> localhost | | oE+*+oBBoo.o | 2025-09-29 22:39:27.321257 | debian-bookworm -> localhost | | o+o+.=++*.+ .| 2025-09-29 22:39:27.321279 | debian-bookworm -> localhost | | o.oo .=.o*o..| 2025-09-29 22:39:27.321299 | debian-bookworm -> localhost | | o.. . .+.*.+.| 2025-09-29 22:39:27.321320 | debian-bookworm -> localhost | | ..S+ o = | 2025-09-29 22:39:27.321344 | debian-bookworm -> localhost | | ..o | 2025-09-29 22:39:27.321367 | debian-bookworm -> localhost | | . | 2025-09-29 22:39:27.321389 | debian-bookworm -> localhost | | | 2025-09-29 22:39:27.321413 | debian-bookworm -> localhost | | | 2025-09-29 22:39:27.321435 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-29 22:39:27.321488 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.262410 2025-09-29 22:39:27.327951 | 2025-09-29 22:39:27.328066 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-29 22:39:27.356816 | debian-bookworm | ok 2025-09-29 22:39:27.366104 | debian-bookworm | included: /var/lib/zuul/builds/75e1651a718f491aa4a3fc6b736a076f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-29 22:39:27.374902 | 2025-09-29 22:39:27.374980 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-29 22:39:27.397453 | debian-bookworm | skipping: Conditional result was False 2025-09-29 22:39:27.403864 | 2025-09-29 22:39:27.403952 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-29 22:39:28.006209 | debian-bookworm | changed 2025-09-29 22:39:28.014611 | 2025-09-29 22:39:28.014731 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-29 22:39:28.284008 | debian-bookworm | ok 2025-09-29 22:39:28.292634 | 2025-09-29 22:39:28.292754 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-29 22:39:28.938480 | debian-bookworm | changed 2025-09-29 22:39:28.944882 | 2025-09-29 22:39:28.944963 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-29 22:39:29.517694 | debian-bookworm | changed 2025-09-29 22:39:29.523012 | 2025-09-29 22:39:29.523117 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-29 22:39:29.535869 | debian-bookworm | skipping: Conditional result was False 2025-09-29 22:39:29.541406 | 2025-09-29 22:39:29.541490 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-29 22:39:29.928500 | debian-bookworm -> localhost | changed 2025-09-29 22:39:29.938922 | 2025-09-29 22:39:29.939015 | TASK [add-build-sshkey : Add back temp key] 2025-09-29 22:39:30.226420 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/75e1651a718f491aa4a3fc6b736a076f/work/75e1651a718f491aa4a3fc6b736a076f_id_rsa (zuul-build-sshkey) 2025-09-29 22:39:30.226613 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015267 2025-09-29 22:39:30.233779 | 2025-09-29 22:39:30.233859 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-29 22:39:30.607986 | debian-bookworm | ok 2025-09-29 22:39:30.615383 | 2025-09-29 22:39:30.615500 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-29 22:39:30.638777 | debian-bookworm | skipping: Conditional result was False 2025-09-29 22:39:30.652854 | 2025-09-29 22:39:30.652956 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-29 22:39:31.078962 | debian-bookworm | ok 2025-09-29 22:39:31.091107 | 2025-09-29 22:39:31.091200 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-29 22:39:31.135561 | debian-bookworm | ok 2025-09-29 22:39:31.143860 | 2025-09-29 22:39:31.143989 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-29 22:39:31.424447 | debian-bookworm -> localhost | ok 2025-09-29 22:39:31.430444 | 2025-09-29 22:39:31.430538 | TASK [validate-host : Collect information about the host] 2025-09-29 22:39:32.470128 | debian-bookworm | ok 2025-09-29 22:39:32.493459 | 2025-09-29 22:39:32.493582 | TASK [validate-host : Sanitize hostname] 2025-09-29 22:39:32.594087 | debian-bookworm | ok 2025-09-29 22:39:32.633670 | 2025-09-29 22:39:32.633800 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-29 22:39:33.193570 | debian-bookworm -> localhost | changed 2025-09-29 22:39:33.199594 | 2025-09-29 22:39:33.199714 | TASK [validate-host : Collect information about zuul worker] 2025-09-29 22:39:33.601948 | debian-bookworm | ok 2025-09-29 22:39:33.606906 | 2025-09-29 22:39:33.607019 | TASK [validate-host : Write out all zuul information for each host] 2025-09-29 22:39:34.167189 | debian-bookworm -> localhost | changed 2025-09-29 22:39:34.178218 | 2025-09-29 22:39:34.178340 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-29 22:39:34.454384 | debian-bookworm | ok 2025-09-29 22:39:34.463021 | 2025-09-29 22:39:34.463234 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-29 22:39:35.723680 | debian-bookworm | changed: 2025-09-29 22:39:35.723995 | debian-bookworm | cd+++++++++ src/ 2025-09-29 22:39:35.724069 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-29 22:39:35.724095 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-29 22:39:35.724116 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-09-29 22:39:35.724135 | debian-bookworm | localhost | ok: Runtime: 0:00:00.003878 2025-09-29 22:40:00.518663 | 2025-09-29 22:40:00.518773 | TASK [upload-logs : Upload logs to log server] 2025-09-29 22:40:01.097386 | localhost | Output suppressed because no_log was given 2025-09-29 22:40:01.099430 | 2025-09-29 22:40:01.099538 | LOOP [upload-logs : Compress console log and json output] 2025-09-29 22:40:01.154716 | localhost | skipping: Conditional result was False 2025-09-29 22:40:01.163223 | localhost | skipping: Conditional result was False 2025-09-29 22:40:01.180427 | 2025-09-29 22:40:01.180558 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-29 22:40:01.231583 | localhost | skipping: Conditional result was False 2025-09-29 22:40:01.232294 | 2025-09-29 22:40:01.236819 | localhost | skipping: Conditional result was False 2025-09-29 22:40:01.246256 | 2025-09-29 22:40:01.246398 | LOOP [upload-logs : Upload console log and json output]