2025-09-13 04:00:52.997973 | Job console starting 2025-09-13 04:00:53.015731 | Updating git repos 2025-09-13 04:00:53.052349 | Cloning repos into workspace 2025-09-13 04:00:53.105059 | Restoring repo states 2025-09-13 04:00:53.117738 | Merging changes 2025-09-13 04:00:53.703858 | Checking out repos 2025-09-13 04:00:53.783551 | Preparing playbooks 2025-09-13 04:00:54.664298 | Running Ansible setup 2025-09-13 04:01:01.168420 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-13 04:01:02.546872 | 2025-09-13 04:01:02.547016 | PLAY [Base pre] 2025-09-13 04:01:02.572799 | 2025-09-13 04:01:02.572932 | TASK [Setup log path fact] 2025-09-13 04:01:02.615548 | debian-bookworm | ok 2025-09-13 04:01:02.645487 | 2025-09-13 04:01:02.645618 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-13 04:01:02.698957 | debian-bookworm | ok 2025-09-13 04:01:02.715797 | 2025-09-13 04:01:02.715904 | TASK [emit-job-header : Print job information] 2025-09-13 04:01:02.786228 | # Job Information 2025-09-13 04:01:02.786397 | Ansible Version: 2.16.14 2025-09-13 04:01:02.786432 | Job: python-black 2025-09-13 04:01:02.786464 | Pipeline: check 2025-09-13 04:01:02.786487 | Executor: 521e9411259a 2025-09-13 04:01:02.786508 | Triggered by: https://github.com/osism/python-osism/pull/1700 2025-09-13 04:01:02.786531 | Event ID: 1993f700-9056-11f0-9826-6e34a8b90ca1 2025-09-13 04:01:02.791848 | 2025-09-13 04:01:02.791947 | LOOP [emit-job-header : Print node information] 2025-09-13 04:01:03.021056 | debian-bookworm | ok: 2025-09-13 04:01:03.021209 | debian-bookworm | # Node Information 2025-09-13 04:01:03.021243 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-13 04:01:03.021270 | debian-bookworm | Hostname: debian 2025-09-13 04:01:03.021345 | debian-bookworm | Username: zuul 2025-09-13 04:01:03.021366 | debian-bookworm | Distro: Debian 12.12 2025-09-13 04:01:03.021392 | debian-bookworm | Provider: regiocloud-a 2025-09-13 04:01:03.021414 | debian-bookworm | Region: 2025-09-13 04:01:03.021435 | debian-bookworm | Label: debian-bookworm 2025-09-13 04:01:03.021454 | debian-bookworm | Product Name: OpenStack Nova 2025-09-13 04:01:03.021473 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe97:f14d 2025-09-13 04:01:03.033451 | 2025-09-13 04:01:03.033557 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-13 04:01:03.649031 | debian-bookworm -> localhost | changed 2025-09-13 04:01:03.655855 | 2025-09-13 04:01:03.655961 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-13 04:01:05.325654 | debian-bookworm -> localhost | changed 2025-09-13 04:01:05.334627 | 2025-09-13 04:01:05.334714 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-13 04:01:05.674689 | debian-bookworm -> localhost | ok 2025-09-13 04:01:05.679773 | 2025-09-13 04:01:05.679857 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-13 04:01:05.726725 | debian-bookworm | ok 2025-09-13 04:01:05.752500 | debian-bookworm | included: /var/lib/zuul/builds/b1da2ede1200494fa951a2e5a218aaa6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-13 04:01:05.760850 | 2025-09-13 04:01:05.761124 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-13 04:01:08.357470 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-13 04:01:08.357632 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b1da2ede1200494fa951a2e5a218aaa6/work/b1da2ede1200494fa951a2e5a218aaa6_id_rsa 2025-09-13 04:01:08.357664 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b1da2ede1200494fa951a2e5a218aaa6/work/b1da2ede1200494fa951a2e5a218aaa6_id_rsa.pub 2025-09-13 04:01:08.357688 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-13 04:01:08.357709 | debian-bookworm -> localhost | SHA256:SG76llElghuh8cEABZTvuHXJrCRNqq2zIkHP3W1bF6U zuul-build-sshkey 2025-09-13 04:01:08.357729 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-13 04:01:08.357755 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-13 04:01:08.357775 | debian-bookworm -> localhost | |+==ooo | 2025-09-13 04:01:08.357794 | debian-bookworm -> localhost | | . ++.. . . . | 2025-09-13 04:01:08.357813 | debian-bookworm -> localhost | | o .o.. o o | 2025-09-13 04:01:08.357831 | debian-bookworm -> localhost | | . o.o .. E | 2025-09-13 04:01:08.357849 | debian-bookworm -> localhost | |. O + *.S . | 2025-09-13 04:01:08.357871 | debian-bookworm -> localhost | |.+ B O.. o . . | 2025-09-13 04:01:08.357889 | debian-bookworm -> localhost | |.o= + o. o . | 2025-09-13 04:01:08.358026 | debian-bookworm -> localhost | |=... .o . | 2025-09-13 04:01:08.358079 | debian-bookworm -> localhost | |=+ .. | 2025-09-13 04:01:08.358191 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-13 04:01:08.358349 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.668333 2025-09-13 04:01:08.369256 | 2025-09-13 04:01:08.369454 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-13 04:01:08.412685 | debian-bookworm | ok 2025-09-13 04:01:08.434437 | debian-bookworm | included: /var/lib/zuul/builds/b1da2ede1200494fa951a2e5a218aaa6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-13 04:01:08.449741 | 2025-09-13 04:01:08.449825 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-13 04:01:08.485740 | debian-bookworm | skipping: Conditional result was False 2025-09-13 04:01:08.499113 | 2025-09-13 04:01:08.499195 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-13 04:01:09.291706 | debian-bookworm | changed 2025-09-13 04:01:09.297155 | 2025-09-13 04:01:09.297241 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-13 04:01:09.535046 | debian-bookworm | ok 2025-09-13 04:01:09.544316 | 2025-09-13 04:01:09.544408 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-13 04:01:10.151548 | debian-bookworm | changed 2025-09-13 04:01:10.158192 | 2025-09-13 04:01:10.158322 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-13 04:01:10.790757 | debian-bookworm | changed 2025-09-13 04:01:10.796117 | 2025-09-13 04:01:10.796200 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-13 04:01:10.825781 | debian-bookworm | skipping: Conditional result was False 2025-09-13 04:01:10.835100 | 2025-09-13 04:01:10.835206 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-13 04:01:11.879705 | debian-bookworm -> localhost | changed 2025-09-13 04:01:11.891851 | 2025-09-13 04:01:11.891996 | TASK [add-build-sshkey : Add back temp key] 2025-09-13 04:01:12.518114 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b1da2ede1200494fa951a2e5a218aaa6/work/b1da2ede1200494fa951a2e5a218aaa6_id_rsa (zuul-build-sshkey) 2025-09-13 04:01:12.518361 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.027943 2025-09-13 04:01:12.527111 | 2025-09-13 04:01:12.527206 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-13 04:01:12.953739 | debian-bookworm | ok 2025-09-13 04:01:12.964303 | 2025-09-13 04:01:12.964413 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-13 04:01:12.997373 | debian-bookworm | skipping: Conditional result was False 2025-09-13 04:01:13.012864 | 2025-09-13 04:01:13.012977 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-13 04:01:13.515736 | debian-bookworm | ok 2025-09-13 04:01:13.532469 | 2025-09-13 04:01:13.532579 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-13 04:01:13.572997 | debian-bookworm | ok 2025-09-13 04:01:13.577938 | 2025-09-13 04:01:13.578028 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-13 04:01:14.233575 | debian-bookworm -> localhost | ok 2025-09-13 04:01:14.243682 | 2025-09-13 04:01:14.243789 | TASK [validate-host : Collect information about the host] 2025-09-13 04:01:15.507969 | debian-bookworm | ok 2025-09-13 04:01:15.527591 | 2025-09-13 04:01:15.527702 | TASK [validate-host : Sanitize hostname] 2025-09-13 04:01:15.701801 | debian-bookworm | ok 2025-09-13 04:01:15.731131 | 2025-09-13 04:01:15.731343 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-13 04:01:16.729980 | debian-bookworm -> localhost | changed 2025-09-13 04:01:16.735694 | 2025-09-13 04:01:16.735787 | TASK [validate-host : Collect information about zuul worker] 2025-09-13 04:01:17.203219 | debian-bookworm | ok 2025-09-13 04:01:17.207685 | 2025-09-13 04:01:17.207773 | TASK [validate-host : Write out all zuul information for each host] 2025-09-13 04:01:18.032170 | debian-bookworm -> localhost | changed 2025-09-13 04:01:18.042383 | 2025-09-13 04:01:18.042484 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-13 04:01:18.324312 | debian-bookworm | ok 2025-09-13 04:01:18.329501 | 2025-09-13 04:01:18.329599 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-13 04:01:19.851588 | debian-bookworm | changed: 2025-09-13 04:01:19.851738 | debian-bookworm | cd+++++++++ src/ 2025-09-13 04:01:19.851766 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-13 04:01:19.851787 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-13 04:01:19.851805 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-09-13 04:01:19.851822 | debian-bookworm | Containerfile 2025-09-13 04:01:19.851914 | debian-bookworm | Containerfile 2025-09-13 04:01:19.893489 | debian-bookworm | localhost | ok: Runtime: 0:00:00.030119 2025-09-13 04:01:47.907403 | 2025-09-13 04:01:47.907501 | TASK [upload-logs : Upload logs to log server] 2025-09-13 04:01:48.821725 | localhost | Output suppressed because no_log was given 2025-09-13 04:01:48.823544 | 2025-09-13 04:01:48.823632 | LOOP [upload-logs : Compress console log and json output] 2025-09-13 04:01:48.958814 | localhost | skipping: Conditional result was False 2025-09-13 04:01:48.983023 | localhost | skipping: Conditional result was False 2025-09-13 04:01:48.989864 | 2025-09-13 04:01:48.989961 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-13 04:01:49.107396 | localhost | skipping: Conditional result was False 2025-09-13 04:01:49.107615 | 2025-09-13 04:01:49.129509 | localhost | skipping: Conditional result was False 2025-09-13 04:01:49.136113 | 2025-09-13 04:01:49.136214 | LOOP [upload-logs : Upload console log and json output]