2025-06-12 23:01:10.122482 | Job console starting 2025-06-12 23:01:10.170796 | Updating git repos 2025-06-12 23:01:10.229146 | Cloning repos into workspace 2025-06-12 23:01:10.305125 | Restoring repo states 2025-06-12 23:01:10.327682 | Merging changes 2025-06-12 23:01:10.922966 | Checking out repos 2025-06-12 23:01:11.050621 | Preparing playbooks 2025-06-12 23:01:12.239590 | Running Ansible setup 2025-06-12 23:01:20.547989 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-12 23:01:22.707631 | 2025-06-12 23:01:22.707772 | PLAY [Base pre] 2025-06-12 23:01:22.771282 | 2025-06-12 23:01:22.771422 | TASK [Setup log path fact] 2025-06-12 23:01:22.813128 | debian-bookworm | ok 2025-06-12 23:01:22.849254 | 2025-06-12 23:01:22.849415 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-12 23:01:22.919281 | debian-bookworm | ok 2025-06-12 23:01:22.962603 | 2025-06-12 23:01:22.962719 | TASK [emit-job-header : Print job information] 2025-06-12 23:01:23.022990 | # Job Information 2025-06-12 23:01:23.023154 | Ansible Version: 2.16.14 2025-06-12 23:01:23.023189 | Job: python-black 2025-06-12 23:01:23.023221 | Pipeline: check 2025-06-12 23:01:23.023244 | Executor: 521e9411259a 2025-06-12 23:01:23.023266 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/759 2025-06-12 23:01:23.023288 | Event ID: 1bbc62f0-47e1-11f0-901a-d017e0e8d78f 2025-06-12 23:01:23.027977 | 2025-06-12 23:01:23.028070 | LOOP [emit-job-header : Print node information] 2025-06-12 23:01:23.141459 | debian-bookworm | ok: 2025-06-12 23:01:23.141715 | debian-bookworm | # Node Information 2025-06-12 23:01:23.141757 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-12 23:01:23.141783 | debian-bookworm | Hostname: debian 2025-06-12 23:01:23.141805 | debian-bookworm | Username: zuul 2025-06-12 23:01:23.141826 | debian-bookworm | Distro: Debian 12.11 2025-06-12 23:01:23.141853 | debian-bookworm | Provider: regiocloud-a 2025-06-12 23:01:23.141875 | debian-bookworm | Region: 2025-06-12 23:01:23.141895 | debian-bookworm | Label: debian-bookworm 2025-06-12 23:01:23.141915 | debian-bookworm | Product Name: OpenStack Nova 2025-06-12 23:01:23.141935 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3a:6e7d 2025-06-12 23:01:23.164760 | 2025-06-12 23:01:23.164876 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-12 23:01:24.441618 | debian-bookworm -> localhost | changed 2025-06-12 23:01:24.450025 | 2025-06-12 23:01:24.450134 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-12 23:01:26.671722 | debian-bookworm -> localhost | changed 2025-06-12 23:01:26.686956 | 2025-06-12 23:01:26.687056 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-12 23:01:27.331838 | debian-bookworm -> localhost | ok 2025-06-12 23:01:27.337859 | 2025-06-12 23:01:27.337962 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-12 23:01:27.376418 | debian-bookworm | ok 2025-06-12 23:01:27.403875 | debian-bookworm | included: /var/lib/zuul/builds/f139e8ea47824238a2218399f1aa2dd8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-12 23:01:27.425187 | 2025-06-12 23:01:27.425296 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-12 23:01:29.247946 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-12 23:01:29.248143 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f139e8ea47824238a2218399f1aa2dd8/work/f139e8ea47824238a2218399f1aa2dd8_id_rsa 2025-06-12 23:01:29.248182 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f139e8ea47824238a2218399f1aa2dd8/work/f139e8ea47824238a2218399f1aa2dd8_id_rsa.pub 2025-06-12 23:01:29.248209 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-12 23:01:29.248235 | debian-bookworm -> localhost | SHA256:qfUJ9j6g7zi7/8EKDkogKQLTXSD67J0bkp72MCdirHs zuul-build-sshkey 2025-06-12 23:01:29.248257 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-12 23:01:29.248287 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-12 23:01:29.248309 | debian-bookworm -> localhost | | . ... | 2025-06-12 23:01:29.248331 | debian-bookworm -> localhost | | o o . | 2025-06-12 23:01:29.248352 | debian-bookworm -> localhost | |+ . . | 2025-06-12 23:01:29.248372 | debian-bookworm -> localhost | |.= . | 2025-06-12 23:01:29.248442 | debian-bookworm -> localhost | |* o S | 2025-06-12 23:01:29.248471 | debian-bookworm -> localhost | |=o o . +.= . | 2025-06-12 23:01:29.248493 | debian-bookworm -> localhost | |.oO * o. .* | 2025-06-12 23:01:29.248513 | debian-bookworm -> localhost | |o+EO =oo o.. | 2025-06-12 23:01:29.248534 | debian-bookworm -> localhost | |o++.o =B=.o. | 2025-06-12 23:01:29.248554 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-12 23:01:29.248611 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.734493 2025-06-12 23:01:29.254685 | 2025-06-12 23:01:29.254779 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-12 23:01:29.292151 | debian-bookworm | ok 2025-06-12 23:01:29.306036 | debian-bookworm | included: /var/lib/zuul/builds/f139e8ea47824238a2218399f1aa2dd8/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-12 23:01:29.315006 | 2025-06-12 23:01:29.315093 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-12 23:01:29.337792 | debian-bookworm | skipping: Conditional result was False 2025-06-12 23:01:29.343017 | 2025-06-12 23:01:29.343179 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-12 23:01:29.990738 | debian-bookworm | changed 2025-06-12 23:01:29.996233 | 2025-06-12 23:01:29.996327 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-12 23:01:30.225615 | debian-bookworm | ok 2025-06-12 23:01:30.230901 | 2025-06-12 23:01:30.230991 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-12 23:01:30.868746 | debian-bookworm | changed 2025-06-12 23:01:30.877975 | 2025-06-12 23:01:30.878106 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-12 23:01:31.572737 | debian-bookworm | changed 2025-06-12 23:01:31.583432 | 2025-06-12 23:01:31.584157 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-12 23:01:31.613620 | debian-bookworm | skipping: Conditional result was False 2025-06-12 23:01:31.619625 | 2025-06-12 23:01:31.619729 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-12 23:01:32.223593 | debian-bookworm -> localhost | changed 2025-06-12 23:01:32.237512 | 2025-06-12 23:01:32.237623 | TASK [add-build-sshkey : Add back temp key] 2025-06-12 23:01:33.034349 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f139e8ea47824238a2218399f1aa2dd8/work/f139e8ea47824238a2218399f1aa2dd8_id_rsa (zuul-build-sshkey) 2025-06-12 23:01:33.034588 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.024909 2025-06-12 23:01:33.042282 | 2025-06-12 23:01:33.042388 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-12 23:01:33.530426 | debian-bookworm | ok 2025-06-12 23:01:33.534690 | 2025-06-12 23:01:33.534878 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-12 23:01:33.567584 | debian-bookworm | skipping: Conditional result was False 2025-06-12 23:01:33.576358 | 2025-06-12 23:01:33.576464 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-12 23:01:34.137740 | debian-bookworm | ok 2025-06-12 23:01:34.148719 | 2025-06-12 23:01:34.148810 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-12 23:01:34.197417 | debian-bookworm | ok 2025-06-12 23:01:34.201876 | 2025-06-12 23:01:34.201965 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-12 23:01:34.830758 | debian-bookworm -> localhost | ok 2025-06-12 23:01:34.836679 | 2025-06-12 23:01:34.836762 | TASK [validate-host : Collect information about the host] 2025-06-12 23:01:36.075065 | debian-bookworm | ok 2025-06-12 23:01:36.097592 | 2025-06-12 23:01:36.097702 | TASK [validate-host : Sanitize hostname] 2025-06-12 23:01:36.323720 | debian-bookworm | ok 2025-06-12 23:01:36.353890 | 2025-06-12 23:01:36.353976 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-12 23:01:37.143656 | debian-bookworm -> localhost | changed 2025-06-12 23:01:37.148211 | 2025-06-12 23:01:37.148295 | TASK [validate-host : Collect information about zuul worker] 2025-06-12 23:01:37.749854 | debian-bookworm | ok 2025-06-12 23:01:37.753678 | 2025-06-12 23:01:37.753758 | TASK [validate-host : Write out all zuul information for each host] 2025-06-12 23:01:38.526006 | debian-bookworm -> localhost | changed 2025-06-12 23:01:38.542022 | 2025-06-12 23:01:38.542123 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-12 23:01:38.804295 | debian-bookworm | ok 2025-06-12 23:01:38.809760 | 2025-06-12 23:01:38.809848 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-12 23:01:39.865920 | debian-bookworm | changed: 2025-06-12 23:01:39.866097 | debian-bookworm | cd+++++++++ src/ 2025-06-12 23:01:39.866132 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-12 23:01:39.866157 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-12 23:01:39.866178 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-06-12 23:01:39.866198 | debian-bookworm | Containerfile 2025-06-12 23:01:39.866328 | debian-bookworm | localhost | ok: Runtime: 0:00:00.047136 2025-06-12 23:02:05.407832 | 2025-06-12 23:02:05.407954 | TASK [upload-logs : Upload logs to log server] 2025-06-12 23:02:06.602334 | localhost | Output suppressed because no_log was given 2025-06-12 23:02:06.608259 | 2025-06-12 23:02:06.609054 | LOOP [upload-logs : Compress console log and json output] 2025-06-12 23:02:06.804255 | localhost | skipping: Conditional result was False 2025-06-12 23:02:06.828399 | localhost | skipping: Conditional result was False 2025-06-12 23:02:06.840343 | 2025-06-12 23:02:06.840516 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-12 23:02:06.974648 | localhost | skipping: Conditional result was False 2025-06-12 23:02:06.974999 | 2025-06-12 23:02:06.988699 | localhost | skipping: Conditional result was False 2025-06-12 23:02:07.004979 | 2025-06-12 23:02:07.005329 | LOOP [upload-logs : Upload console log and json output]