2025-05-19 17:15:52.666854 | Job console starting 2025-05-19 17:15:52.697473 | Updating git repos 2025-05-19 17:15:52.753200 | Cloning repos into workspace 2025-05-19 17:15:52.854929 | Restoring repo states 2025-05-19 17:15:52.868333 | Merging changes 2025-05-19 17:15:53.399382 | Checking out repos 2025-05-19 17:15:53.444188 | Preparing playbooks 2025-05-19 17:15:54.267043 | Running Ansible setup 2025-05-19 17:15:58.892354 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-19 17:15:59.691188 | 2025-05-19 17:15:59.691376 | PLAY [Base pre] 2025-05-19 17:15:59.708188 | 2025-05-19 17:15:59.708355 | TASK [Setup log path fact] 2025-05-19 17:15:59.729433 | debian-bookworm | ok 2025-05-19 17:15:59.750939 | 2025-05-19 17:15:59.751142 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-19 17:15:59.807723 | debian-bookworm | ok 2025-05-19 17:15:59.824245 | 2025-05-19 17:15:59.824422 | TASK [emit-job-header : Print job information] 2025-05-19 17:15:59.899515 | # Job Information 2025-05-19 17:15:59.899748 | Ansible Version: 2.16.14 2025-05-19 17:15:59.899784 | Job: python-black 2025-05-19 17:15:59.899817 | Pipeline: check 2025-05-19 17:15:59.899841 | Executor: 521e9411259a 2025-05-19 17:15:59.899862 | Triggered by: https://github.com/osism/testbed/pull/2692 2025-05-19 17:15:59.899885 | Event ID: e633f040-34d4-11f0-8d84-6d9294b119d2 2025-05-19 17:15:59.904975 | 2025-05-19 17:15:59.905096 | LOOP [emit-job-header : Print node information] 2025-05-19 17:16:00.031597 | debian-bookworm | ok: 2025-05-19 17:16:00.031872 | debian-bookworm | # Node Information 2025-05-19 17:16:00.031912 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-05-19 17:16:00.031940 | debian-bookworm | Hostname: debian 2025-05-19 17:16:00.031965 | debian-bookworm | Username: zuul 2025-05-19 17:16:00.031988 | debian-bookworm | Distro: Debian 12.11 2025-05-19 17:16:00.032015 | debian-bookworm | Provider: regiocloud-a 2025-05-19 17:16:00.032038 | debian-bookworm | Region: 2025-05-19 17:16:00.032062 | debian-bookworm | Label: debian-bookworm 2025-05-19 17:16:00.032083 | debian-bookworm | Product Name: OpenStack Nova 2025-05-19 17:16:00.032104 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe62:b16c 2025-05-19 17:16:00.050651 | 2025-05-19 17:16:00.050880 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-19 17:16:00.587426 | debian-bookworm -> localhost | changed 2025-05-19 17:16:00.602171 | 2025-05-19 17:16:00.602613 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-19 17:16:01.863120 | debian-bookworm -> localhost | changed 2025-05-19 17:16:01.880338 | 2025-05-19 17:16:01.880620 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-19 17:16:02.265142 | debian-bookworm -> localhost | ok 2025-05-19 17:16:02.273199 | 2025-05-19 17:16:02.273335 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-19 17:16:02.302941 | debian-bookworm | ok 2025-05-19 17:16:02.322196 | debian-bookworm | included: /var/lib/zuul/builds/25e7326e6273485e95c1dce98c3663dd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-19 17:16:02.337851 | 2025-05-19 17:16:02.337982 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-19 17:16:03.500996 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-05-19 17:16:03.501277 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/25e7326e6273485e95c1dce98c3663dd/work/25e7326e6273485e95c1dce98c3663dd_id_rsa 2025-05-19 17:16:03.501316 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/25e7326e6273485e95c1dce98c3663dd/work/25e7326e6273485e95c1dce98c3663dd_id_rsa.pub 2025-05-19 17:16:03.501343 | debian-bookworm -> localhost | The key fingerprint is: 2025-05-19 17:16:03.501367 | debian-bookworm -> localhost | SHA256:4w4WnxWp9QRkjVXI+yuw5oaMgintGDg9GYltXdKkBAQ zuul-build-sshkey 2025-05-19 17:16:03.501390 | debian-bookworm -> localhost | The key's randomart image is: 2025-05-19 17:16:03.501428 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-05-19 17:16:03.501450 | debian-bookworm -> localhost | |Eoo.. . .+=.o. | 2025-05-19 17:16:03.501471 | debian-bookworm -> localhost | | . + ..o+ | 2025-05-19 17:16:03.501491 | debian-bookworm -> localhost | | o o + .. | 2025-05-19 17:16:03.501510 | debian-bookworm -> localhost | | o o o o +. | 2025-05-19 17:16:03.501530 | debian-bookworm -> localhost | |. = . . S . .. | 2025-05-19 17:16:03.501557 | debian-bookworm -> localhost | |.o o + +. . | 2025-05-19 17:16:03.501578 | debian-bookworm -> localhost | |+.+o oo+. o . | 2025-05-19 17:16:03.501597 | debian-bookworm -> localhost | |.++....oo + . . | 2025-05-19 17:16:03.501618 | debian-bookworm -> localhost | |.o. . .+. . | 2025-05-19 17:16:03.501638 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-05-19 17:16:03.501690 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.606521 2025-05-19 17:16:03.508607 | 2025-05-19 17:16:03.508755 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-19 17:16:03.553351 | debian-bookworm | ok 2025-05-19 17:16:03.575836 | debian-bookworm | included: /var/lib/zuul/builds/25e7326e6273485e95c1dce98c3663dd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-19 17:16:03.585512 | 2025-05-19 17:16:03.585635 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-19 17:16:03.609837 | debian-bookworm | skipping: Conditional result was False 2025-05-19 17:16:03.616163 | 2025-05-19 17:16:03.616272 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-19 17:16:04.383898 | debian-bookworm | changed 2025-05-19 17:16:04.391448 | 2025-05-19 17:16:04.391590 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-19 17:16:04.663966 | debian-bookworm | ok 2025-05-19 17:16:04.673405 | 2025-05-19 17:16:04.673561 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-19 17:16:05.358498 | debian-bookworm | changed 2025-05-19 17:16:05.369306 | 2025-05-19 17:16:05.369483 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-19 17:16:06.055472 | debian-bookworm | changed 2025-05-19 17:16:06.072437 | 2025-05-19 17:16:06.073272 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-19 17:16:06.104153 | debian-bookworm | skipping: Conditional result was False 2025-05-19 17:16:06.118113 | 2025-05-19 17:16:06.118286 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-19 17:16:06.671421 | debian-bookworm -> localhost | changed 2025-05-19 17:16:06.684849 | 2025-05-19 17:16:06.684984 | TASK [add-build-sshkey : Add back temp key] 2025-05-19 17:16:07.061506 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/25e7326e6273485e95c1dce98c3663dd/work/25e7326e6273485e95c1dce98c3663dd_id_rsa (zuul-build-sshkey) 2025-05-19 17:16:07.061796 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.042343 2025-05-19 17:16:07.070349 | 2025-05-19 17:16:07.070488 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-19 17:16:07.507992 | debian-bookworm | ok 2025-05-19 17:16:07.513911 | 2025-05-19 17:16:07.514121 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-19 17:16:07.539683 | debian-bookworm | skipping: Conditional result was False 2025-05-19 17:16:07.551044 | 2025-05-19 17:16:07.551197 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-19 17:16:07.988822 | debian-bookworm | ok 2025-05-19 17:16:08.010093 | 2025-05-19 17:16:08.010249 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-19 17:16:08.046592 | debian-bookworm | ok 2025-05-19 17:16:08.058238 | 2025-05-19 17:16:08.058404 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-19 17:16:08.464570 | debian-bookworm -> localhost | ok 2025-05-19 17:16:08.472137 | 2025-05-19 17:16:08.472300 | TASK [validate-host : Collect information about the host] 2025-05-19 17:16:09.726093 | debian-bookworm | ok 2025-05-19 17:16:09.736836 | 2025-05-19 17:16:09.736971 | TASK [validate-host : Sanitize hostname] 2025-05-19 17:16:09.875373 | debian-bookworm | ok 2025-05-19 17:16:09.933824 | 2025-05-19 17:16:09.934111 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-19 17:16:10.600111 | debian-bookworm -> localhost | changed 2025-05-19 17:16:10.606995 | 2025-05-19 17:16:10.607130 | TASK [validate-host : Collect information about zuul worker] 2025-05-19 17:16:11.034254 | debian-bookworm | ok 2025-05-19 17:16:11.041633 | 2025-05-19 17:16:11.041981 | TASK [validate-host : Write out all zuul information for each host] 2025-05-19 17:16:11.671488 | debian-bookworm -> localhost | changed 2025-05-19 17:16:11.681999 | 2025-05-19 17:16:11.682117 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-19 17:16:11.955395 | debian-bookworm | ok 2025-05-19 17:16:11.961400 | 2025-05-19 17:16:11.961519 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-19 17:16:13.237675 | debian-bookworm | changed: 2025-05-19 17:16:13.238256 | debian-bookworm | cd+++++++++ src/ 2025-05-19 17:16:13.238299 | debian-bookworm | cd+++++++++ src/github.com/ 2025-05-19 17:16:13.238326 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-05-19 17:16:13.238396 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-05-19 17:16:13.238418 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006041 2025-05-19 17:16:39.219544 | 2025-05-19 17:16:39.219825 | TASK [upload-logs : Upload logs to log server] 2025-05-19 17:16:39.848561 | localhost | Output suppressed because no_log was given 2025-05-19 17:16:39.850879 | 2025-05-19 17:16:39.851024 | LOOP [upload-logs : Compress console log and json output] 2025-05-19 17:16:39.902485 | localhost | skipping: Conditional result was False 2025-05-19 17:16:39.908595 | localhost | skipping: Conditional result was False 2025-05-19 17:16:39.920251 | 2025-05-19 17:16:39.920412 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-19 17:16:39.970783 | localhost | skipping: Conditional result was False 2025-05-19 17:16:39.971178 | 2025-05-19 17:16:39.986579 | localhost | skipping: Conditional result was False 2025-05-19 17:16:39.994076 | 2025-05-19 17:16:39.994433 | LOOP [upload-logs : Upload console log and json output]