2025-10-24 03:15:15.622608 | Job console starting 2025-10-24 03:15:15.631601 | Updating git repos 2025-10-24 03:15:15.652039 | Cloning repos into workspace 2025-10-24 03:15:15.715757 | Restoring repo states 2025-10-24 03:15:15.720200 | Merging changes 2025-10-24 03:15:15.720213 | Checking out repos 2025-10-24 03:15:15.768868 | Preparing playbooks 2025-10-24 03:15:16.662182 | Running Ansible setup 2025-10-24 03:15:21.722168 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-24 03:15:22.528443 | 2025-10-24 03:15:22.528552 | PLAY [Base pre] 2025-10-24 03:15:22.542871 | 2025-10-24 03:15:22.542973 | TASK [Setup log path fact] 2025-10-24 03:15:22.560047 | debian-bookworm | ok 2025-10-24 03:15:22.572035 | 2025-10-24 03:15:22.572163 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-24 03:15:22.632964 | debian-bookworm | ok 2025-10-24 03:15:22.640712 | 2025-10-24 03:15:22.640798 | TASK [emit-job-header : Print job information] 2025-10-24 03:15:22.726665 | # Job Information 2025-10-24 03:15:22.726795 | Ansible Version: 2.16.14 2025-10-24 03:15:22.726823 | Job: python-black 2025-10-24 03:15:22.726863 | Pipeline: periodic-daily 2025-10-24 03:15:22.726882 | Executor: 521e9411259a 2025-10-24 03:15:22.726899 | Triggered by: https://github.com/osism/testbed 2025-10-24 03:15:22.726916 | Event ID: 2d0c3cf9da0041eea86de0ee95299452 2025-10-24 03:15:22.731102 | 2025-10-24 03:15:22.731180 | LOOP [emit-job-header : Print node information] 2025-10-24 03:15:22.899044 | debian-bookworm | ok: 2025-10-24 03:15:22.899258 | debian-bookworm | # Node Information 2025-10-24 03:15:22.899290 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-24 03:15:22.899312 | debian-bookworm | Hostname: debian 2025-10-24 03:15:22.899330 | debian-bookworm | Username: zuul 2025-10-24 03:15:22.899347 | debian-bookworm | Distro: Debian 12.12 2025-10-24 03:15:22.899367 | debian-bookworm | Provider: regiocloud-a 2025-10-24 03:15:22.899384 | debian-bookworm | Region: 2025-10-24 03:15:22.899402 | debian-bookworm | Label: debian-bookworm 2025-10-24 03:15:22.899418 | debian-bookworm | Product Name: OpenStack Nova 2025-10-24 03:15:22.899434 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe46:8ae2 2025-10-24 03:15:22.913614 | 2025-10-24 03:15:22.913712 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-24 03:15:23.521463 | debian-bookworm -> localhost | changed 2025-10-24 03:15:23.532336 | 2025-10-24 03:15:23.532434 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-24 03:15:24.917333 | debian-bookworm -> localhost | changed 2025-10-24 03:15:24.962949 | 2025-10-24 03:15:24.963563 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-24 03:15:25.392179 | debian-bookworm -> localhost | ok 2025-10-24 03:15:25.400838 | 2025-10-24 03:15:25.401225 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-24 03:15:25.421038 | debian-bookworm | ok 2025-10-24 03:15:25.448633 | debian-bookworm | included: /var/lib/zuul/builds/1fbdd169f3e848a38d7010f3369a610f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-24 03:15:25.458377 | 2025-10-24 03:15:25.458459 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-24 03:15:27.193374 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-24 03:15:27.193621 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1fbdd169f3e848a38d7010f3369a610f/work/1fbdd169f3e848a38d7010f3369a610f_id_rsa 2025-10-24 03:15:27.193671 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1fbdd169f3e848a38d7010f3369a610f/work/1fbdd169f3e848a38d7010f3369a610f_id_rsa.pub 2025-10-24 03:15:27.193705 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-24 03:15:27.193737 | debian-bookworm -> localhost | SHA256:WZJC0+PFGEzQQp1ESXfokZEUsN2iBQler8Ldi0dW9vY zuul-build-sshkey 2025-10-24 03:15:27.193764 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-24 03:15:27.193799 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-24 03:15:27.193828 | debian-bookworm -> localhost | | .*%XX=*. | 2025-10-24 03:15:27.193855 | debian-bookworm -> localhost | | o.o@BBo | 2025-10-24 03:15:27.193880 | debian-bookworm -> localhost | | oo++*.+ | 2025-10-24 03:15:27.193904 | debian-bookworm -> localhost | | . o.O.+ . | 2025-10-24 03:15:27.193929 | debian-bookworm -> localhost | | o S + o | 2025-10-24 03:15:27.193961 | debian-bookworm -> localhost | | . + . . . | 2025-10-24 03:15:27.193987 | debian-bookworm -> localhost | | . o E| 2025-10-24 03:15:27.194012 | debian-bookworm -> localhost | | . | 2025-10-24 03:15:27.194038 | debian-bookworm -> localhost | | | 2025-10-24 03:15:27.194077 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-24 03:15:27.194144 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.305725 2025-10-24 03:15:27.201422 | 2025-10-24 03:15:27.201526 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-24 03:15:27.220089 | debian-bookworm | ok 2025-10-24 03:15:27.229319 | debian-bookworm | included: /var/lib/zuul/builds/1fbdd169f3e848a38d7010f3369a610f/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-24 03:15:27.238135 | 2025-10-24 03:15:27.238216 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-24 03:15:27.250919 | debian-bookworm | skipping: Conditional result was False 2025-10-24 03:15:27.256796 | 2025-10-24 03:15:27.256879 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-24 03:15:27.895959 | debian-bookworm | changed 2025-10-24 03:15:27.902959 | 2025-10-24 03:15:27.903050 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-24 03:15:28.170028 | debian-bookworm | ok 2025-10-24 03:15:28.179128 | 2025-10-24 03:15:28.179252 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-24 03:15:28.916346 | debian-bookworm | changed 2025-10-24 03:15:28.927558 | 2025-10-24 03:15:28.927659 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-24 03:15:29.612851 | debian-bookworm | changed 2025-10-24 03:15:29.618129 | 2025-10-24 03:15:29.618218 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-24 03:15:29.640846 | debian-bookworm | skipping: Conditional result was False 2025-10-24 03:15:29.646337 | 2025-10-24 03:15:29.646422 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-24 03:15:30.032293 | debian-bookworm -> localhost | changed 2025-10-24 03:15:30.042497 | 2025-10-24 03:15:30.042585 | TASK [add-build-sshkey : Add back temp key] 2025-10-24 03:15:30.339480 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1fbdd169f3e848a38d7010f3369a610f/work/1fbdd169f3e848a38d7010f3369a610f_id_rsa (zuul-build-sshkey) 2025-10-24 03:15:30.340138 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013423 2025-10-24 03:15:30.357330 | 2025-10-24 03:15:30.357509 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-24 03:15:30.792372 | debian-bookworm | ok 2025-10-24 03:15:30.804801 | 2025-10-24 03:15:30.804924 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-24 03:15:30.828625 | debian-bookworm | skipping: Conditional result was False 2025-10-24 03:15:30.838926 | 2025-10-24 03:15:30.839039 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-24 03:15:31.294222 | debian-bookworm | ok 2025-10-24 03:15:31.307140 | 2025-10-24 03:15:31.307268 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-24 03:15:31.336956 | debian-bookworm | ok 2025-10-24 03:15:31.342970 | 2025-10-24 03:15:31.343119 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-24 03:15:31.662436 | debian-bookworm -> localhost | ok 2025-10-24 03:15:31.677436 | 2025-10-24 03:15:31.677590 | TASK [validate-host : Collect information about the host] 2025-10-24 03:15:32.862987 | debian-bookworm | ok 2025-10-24 03:15:32.874517 | 2025-10-24 03:15:32.874737 | TASK [validate-host : Sanitize hostname] 2025-10-24 03:15:33.026780 | debian-bookworm | ok 2025-10-24 03:15:33.123825 | 2025-10-24 03:15:33.123961 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-24 03:15:33.803437 | debian-bookworm -> localhost | changed 2025-10-24 03:15:33.809607 | 2025-10-24 03:15:33.809722 | TASK [validate-host : Collect information about zuul worker] 2025-10-24 03:15:34.291641 | debian-bookworm | ok 2025-10-24 03:15:34.303193 | 2025-10-24 03:15:34.303728 | TASK [validate-host : Write out all zuul information for each host] 2025-10-24 03:15:34.965095 | debian-bookworm -> localhost | changed 2025-10-24 03:15:34.975091 | 2025-10-24 03:15:34.975210 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-24 03:15:35.266905 | debian-bookworm | ok 2025-10-24 03:15:35.272916 | 2025-10-24 03:15:35.273029 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-24 03:15:36.626182 | debian-bookworm | changed: 2025-10-24 03:15:36.626415 | debian-bookworm | cd+++++++++ src/ 2025-10-24 03:15:36.626453 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-24 03:15:36.626477 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-24 03:15:36.626498 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-24 03:15:36.626518 | debian-bookworm | localhost | ok: Runtime: 0:00:00.023340 2025-10-24 03:16:02.878468 | 2025-10-24 03:16:02.878589 | TASK [upload-logs : Upload logs to log server] 2025-10-24 03:16:03.410532 | localhost | Output suppressed because no_log was given 2025-10-24 03:16:03.412398 | 2025-10-24 03:16:03.412503 | LOOP [upload-logs : Compress console log and json output] 2025-10-24 03:16:03.476198 | localhost | skipping: Conditional result was False 2025-10-24 03:16:03.482281 | localhost | skipping: Conditional result was False 2025-10-24 03:16:03.489425 | 2025-10-24 03:16:03.489540 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-24 03:16:03.534937 | localhost | skipping: Conditional result was False 2025-10-24 03:16:03.535336 | 2025-10-24 03:16:03.540077 | localhost | skipping: Conditional result was False 2025-10-24 03:16:03.545470 | 2025-10-24 03:16:03.545580 | LOOP [upload-logs : Upload console log and json output]