2025-10-30 02:39:50.784286 | Job console starting 2025-10-30 02:39:50.799943 | Updating git repos 2025-10-30 02:39:50.830284 | Cloning repos into workspace 2025-10-30 02:39:50.889018 | Restoring repo states 2025-10-30 02:39:50.901219 | Merging changes 2025-10-30 02:39:51.563310 | Checking out repos 2025-10-30 02:39:51.650944 | Preparing playbooks 2025-10-30 02:39:53.121335 | Running Ansible setup 2025-10-30 02:39:59.929782 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-30 02:40:01.864888 | 2025-10-30 02:40:01.865008 | PLAY [Base pre] 2025-10-30 02:40:01.888086 | 2025-10-30 02:40:01.888196 | TASK [Setup log path fact] 2025-10-30 02:40:01.914819 | debian-bookworm | ok 2025-10-30 02:40:01.951008 | 2025-10-30 02:40:01.951208 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-30 02:40:01.978392 | debian-bookworm | ok 2025-10-30 02:40:01.988597 | 2025-10-30 02:40:01.988690 | TASK [emit-job-header : Print job information] 2025-10-30 02:40:02.030575 | # Job Information 2025-10-30 02:40:02.030713 | Ansible Version: 2.16.14 2025-10-30 02:40:02.030742 | Job: python-black 2025-10-30 02:40:02.030768 | Pipeline: check 2025-10-30 02:40:02.030787 | Executor: 521e9411259a 2025-10-30 02:40:02.030803 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/793 2025-10-30 02:40:02.030821 | Event ID: afe3ca70-b539-11f0-81cc-a683d83162ac 2025-10-30 02:40:02.034728 | 2025-10-30 02:40:02.034805 | LOOP [emit-job-header : Print node information] 2025-10-30 02:40:02.167242 | debian-bookworm | ok: 2025-10-30 02:40:02.167447 | debian-bookworm | # Node Information 2025-10-30 02:40:02.167482 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-30 02:40:02.167518 | debian-bookworm | Hostname: debian 2025-10-30 02:40:02.167540 | debian-bookworm | Username: zuul 2025-10-30 02:40:02.167561 | debian-bookworm | Distro: Debian 12.12 2025-10-30 02:40:02.167584 | debian-bookworm | Provider: regiocloud-a 2025-10-30 02:40:02.167604 | debian-bookworm | Region: 2025-10-30 02:40:02.167626 | debian-bookworm | Label: debian-bookworm 2025-10-30 02:40:02.167645 | debian-bookworm | Product Name: OpenStack Nova 2025-10-30 02:40:02.167664 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe42:a3c 2025-10-30 02:40:02.187186 | 2025-10-30 02:40:02.187282 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-30 02:40:03.108555 | debian-bookworm -> localhost | changed 2025-10-30 02:40:03.117251 | 2025-10-30 02:40:03.117353 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-30 02:40:04.922268 | debian-bookworm -> localhost | changed 2025-10-30 02:40:04.940487 | 2025-10-30 02:40:04.940624 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-30 02:40:05.575242 | debian-bookworm -> localhost | ok 2025-10-30 02:40:05.580004 | 2025-10-30 02:40:05.580088 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-30 02:40:05.627002 | debian-bookworm | ok 2025-10-30 02:40:05.650762 | debian-bookworm | included: /var/lib/zuul/builds/dcc85189023243e9806462dd095bbaa6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-30 02:40:05.664980 | 2025-10-30 02:40:05.665064 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-30 02:40:07.739172 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-30 02:40:07.739327 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/dcc85189023243e9806462dd095bbaa6/work/dcc85189023243e9806462dd095bbaa6_id_rsa 2025-10-30 02:40:07.739358 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/dcc85189023243e9806462dd095bbaa6/work/dcc85189023243e9806462dd095bbaa6_id_rsa.pub 2025-10-30 02:40:07.739381 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-30 02:40:07.739402 | debian-bookworm -> localhost | SHA256:UStWKh+Ufq+CwGDdEUwx0uxMhcfnSRpd4J2HlZhO5IU zuul-build-sshkey 2025-10-30 02:40:07.739420 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-30 02:40:07.739446 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-30 02:40:07.739464 | debian-bookworm -> localhost | | .==*oo++o+o. | 2025-10-30 02:40:07.739482 | debian-bookworm -> localhost | | .Bo==++E=. | 2025-10-30 02:40:07.739499 | debian-bookworm -> localhost | | . =.+**o+= . | 2025-10-30 02:40:07.739539 | debian-bookworm -> localhost | | o . ++o++ .. | 2025-10-30 02:40:07.739556 | debian-bookworm -> localhost | | . o S. . | 2025-10-30 02:40:07.739577 | debian-bookworm -> localhost | | o . | 2025-10-30 02:40:07.739594 | debian-bookworm -> localhost | | . . . | 2025-10-30 02:40:07.739610 | debian-bookworm -> localhost | | . . . | 2025-10-30 02:40:07.739626 | debian-bookworm -> localhost | | . | 2025-10-30 02:40:07.739643 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-30 02:40:07.739684 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.994883 2025-10-30 02:40:07.744789 | 2025-10-30 02:40:07.744866 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-30 02:40:07.793822 | debian-bookworm | ok 2025-10-30 02:40:07.803392 | debian-bookworm | included: /var/lib/zuul/builds/dcc85189023243e9806462dd095bbaa6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-30 02:40:07.812228 | 2025-10-30 02:40:07.812316 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-30 02:40:07.845346 | debian-bookworm | skipping: Conditional result was False 2025-10-30 02:40:07.851021 | 2025-10-30 02:40:07.851114 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-30 02:40:08.492221 | debian-bookworm | changed 2025-10-30 02:40:08.496653 | 2025-10-30 02:40:08.496722 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-30 02:40:08.768131 | debian-bookworm | ok 2025-10-30 02:40:08.773708 | 2025-10-30 02:40:08.773797 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-30 02:40:09.439378 | debian-bookworm | changed 2025-10-30 02:40:09.451920 | 2025-10-30 02:40:09.452053 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-30 02:40:10.107744 | debian-bookworm | changed 2025-10-30 02:40:10.113392 | 2025-10-30 02:40:10.113524 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-30 02:40:10.146566 | debian-bookworm | skipping: Conditional result was False 2025-10-30 02:40:10.152498 | 2025-10-30 02:40:10.152605 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-30 02:40:10.970349 | debian-bookworm -> localhost | changed 2025-10-30 02:40:10.990771 | 2025-10-30 02:40:10.990893 | TASK [add-build-sshkey : Add back temp key] 2025-10-30 02:40:11.652535 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/dcc85189023243e9806462dd095bbaa6/work/dcc85189023243e9806462dd095bbaa6_id_rsa (zuul-build-sshkey) 2025-10-30 02:40:11.652733 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018970 2025-10-30 02:40:11.659870 | 2025-10-30 02:40:11.659965 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-30 02:40:12.132259 | debian-bookworm | ok 2025-10-30 02:40:12.137607 | 2025-10-30 02:40:12.137692 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-30 02:40:12.170593 | debian-bookworm | skipping: Conditional result was False 2025-10-30 02:40:12.180690 | 2025-10-30 02:40:12.180786 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-30 02:40:12.616662 | debian-bookworm | ok 2025-10-30 02:40:12.625105 | 2025-10-30 02:40:12.625194 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-30 02:40:12.672203 | debian-bookworm | ok 2025-10-30 02:40:12.676260 | 2025-10-30 02:40:12.676338 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-30 02:40:13.103950 | debian-bookworm -> localhost | ok 2025-10-30 02:40:13.112205 | 2025-10-30 02:40:13.112290 | TASK [validate-host : Collect information about the host] 2025-10-30 02:40:14.225981 | debian-bookworm | ok 2025-10-30 02:40:14.239010 | 2025-10-30 02:40:14.239116 | TASK [validate-host : Sanitize hostname] 2025-10-30 02:40:14.338721 | debian-bookworm | ok 2025-10-30 02:40:14.393550 | 2025-10-30 02:40:14.393645 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-30 02:40:15.299483 | debian-bookworm -> localhost | changed 2025-10-30 02:40:15.304873 | 2025-10-30 02:40:15.304953 | TASK [validate-host : Collect information about zuul worker] 2025-10-30 02:40:15.735203 | debian-bookworm | ok 2025-10-30 02:40:15.738966 | 2025-10-30 02:40:15.739041 | TASK [validate-host : Write out all zuul information for each host] 2025-10-30 02:40:16.720075 | debian-bookworm -> localhost | changed 2025-10-30 02:40:16.727476 | 2025-10-30 02:40:16.727581 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-30 02:40:16.996458 | debian-bookworm | ok 2025-10-30 02:40:17.001710 | 2025-10-30 02:40:17.001813 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-30 02:40:17.854712 | debian-bookworm | changed: 2025-10-30 02:40:17.854983 | debian-bookworm | cd+++++++++ src/ 2025-10-30 02:40:17.855022 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-30 02:40:17.855043 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-30 02:40:17.855061 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-10-30 02:40:17.855078 | debian-bookworm | Containerfile 2025-10-30 02:40:17.855185 | debian-bookworm | localhost | ok: Runtime: 0:00:00.019650 2025-10-30 02:40:43.023951 | 2025-10-30 02:40:43.024068 | TASK [upload-logs : Upload logs to log server] 2025-10-30 02:40:43.815589 | localhost | Output suppressed because no_log was given 2025-10-30 02:40:43.817754 | 2025-10-30 02:40:43.817866 | LOOP [upload-logs : Compress console log and json output] 2025-10-30 02:40:43.867970 | localhost | skipping: Conditional result was False 2025-10-30 02:40:43.874300 | localhost | skipping: Conditional result was False 2025-10-30 02:40:43.884757 | 2025-10-30 02:40:43.884869 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-30 02:40:44.057754 | localhost | skipping: Conditional result was False 2025-10-30 02:40:44.058040 | 2025-10-30 02:40:44.077768 | localhost | skipping: Conditional result was False 2025-10-30 02:40:44.083372 | 2025-10-30 02:40:44.083555 | LOOP [upload-logs : Upload console log and json output]