2025-09-02 18:47:24.454563 | Job console starting 2025-09-02 18:47:24.483940 | Updating git repos 2025-09-02 18:47:24.518639 | Cloning repos into workspace 2025-09-02 18:47:24.574067 | Restoring repo states 2025-09-02 18:47:24.578801 | Merging changes 2025-09-02 18:47:25.055059 | Checking out repos 2025-09-02 18:47:25.140357 | Preparing playbooks 2025-09-02 18:47:26.384294 | Running Ansible setup 2025-09-02 18:47:30.908157 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-02 18:47:31.855414 | 2025-09-02 18:47:31.855540 | PLAY [Base pre] 2025-09-02 18:47:31.875473 | 2025-09-02 18:47:31.875636 | TASK [Setup log path fact] 2025-09-02 18:47:31.893265 | debian-bookworm | ok 2025-09-02 18:47:31.918448 | 2025-09-02 18:47:31.918581 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-02 18:47:31.947445 | debian-bookworm | ok 2025-09-02 18:47:31.956759 | 2025-09-02 18:47:31.956866 | TASK [emit-job-header : Print job information] 2025-09-02 18:47:31.996827 | # Job Information 2025-09-02 18:47:31.996995 | Ansible Version: 2.16.14 2025-09-02 18:47:31.997030 | Job: python-black 2025-09-02 18:47:31.997063 | Pipeline: check 2025-09-02 18:47:31.997087 | Executor: 521e9411259a 2025-09-02 18:47:31.997108 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/777 2025-09-02 18:47:31.997129 | Event ID: 3edd1ef0-882d-11f0-8075-81dd0ccc40e8 2025-09-02 18:47:32.002050 | 2025-09-02 18:47:32.002150 | LOOP [emit-job-header : Print node information] 2025-09-02 18:47:32.098910 | debian-bookworm | ok: 2025-09-02 18:47:32.099150 | debian-bookworm | # Node Information 2025-09-02 18:47:32.099188 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-02 18:47:32.099214 | debian-bookworm | Hostname: debian 2025-09-02 18:47:32.099237 | debian-bookworm | Username: zuul 2025-09-02 18:47:32.099259 | debian-bookworm | Distro: Debian 12.11 2025-09-02 18:47:32.099283 | debian-bookworm | Provider: regiocloud-a 2025-09-02 18:47:32.099304 | debian-bookworm | Region: 2025-09-02 18:47:32.099324 | debian-bookworm | Label: debian-bookworm 2025-09-02 18:47:32.099344 | debian-bookworm | Product Name: OpenStack Nova 2025-09-02 18:47:32.099363 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fece:9ac4 2025-09-02 18:47:32.116845 | 2025-09-02 18:47:32.116976 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-02 18:47:32.575823 | debian-bookworm -> localhost | changed 2025-09-02 18:47:32.582493 | 2025-09-02 18:47:32.582592 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-02 18:47:34.526145 | debian-bookworm -> localhost | changed 2025-09-02 18:47:34.534827 | 2025-09-02 18:47:34.534952 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-02 18:47:35.182014 | debian-bookworm -> localhost | ok 2025-09-02 18:47:35.187989 | 2025-09-02 18:47:35.188098 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-02 18:47:35.230652 | debian-bookworm | ok 2025-09-02 18:47:35.269289 | debian-bookworm | included: /var/lib/zuul/builds/c16c1524a8fc43cebe1fbe8d82229047/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-02 18:47:35.294047 | 2025-09-02 18:47:35.294156 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-02 18:47:37.442748 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-02 18:47:37.442942 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c16c1524a8fc43cebe1fbe8d82229047/work/c16c1524a8fc43cebe1fbe8d82229047_id_rsa 2025-09-02 18:47:37.442974 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c16c1524a8fc43cebe1fbe8d82229047/work/c16c1524a8fc43cebe1fbe8d82229047_id_rsa.pub 2025-09-02 18:47:37.442996 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-02 18:47:37.443016 | debian-bookworm -> localhost | SHA256:LLttj77xFDdSfntatwcu00srMXxTV7LF7fZ6KLdgyWQ zuul-build-sshkey 2025-09-02 18:47:37.443035 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-02 18:47:37.443062 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-02 18:47:37.443080 | debian-bookworm -> localhost | | ..| 2025-09-02 18:47:37.443098 | debian-bookworm -> localhost | | . =| 2025-09-02 18:47:37.443114 | debian-bookworm -> localhost | | . =.| 2025-09-02 18:47:37.443131 | debian-bookworm -> localhost | | . o . =| 2025-09-02 18:47:37.443148 | debian-bookworm -> localhost | | . S o.E .oo| 2025-09-02 18:47:37.443170 | debian-bookworm -> localhost | | o B+++..| 2025-09-02 18:47:37.443187 | debian-bookworm -> localhost | | . . . =*o=+| 2025-09-02 18:47:37.443203 | debian-bookworm -> localhost | | o.= .=o*==| 2025-09-02 18:47:37.443220 | debian-bookworm -> localhost | | .o=oo *==.| 2025-09-02 18:47:37.443236 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-02 18:47:37.443280 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.055762 2025-09-02 18:47:37.448695 | 2025-09-02 18:47:37.448788 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-02 18:47:37.495612 | debian-bookworm | ok 2025-09-02 18:47:37.507832 | debian-bookworm | included: /var/lib/zuul/builds/c16c1524a8fc43cebe1fbe8d82229047/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-02 18:47:37.523378 | 2025-09-02 18:47:37.523473 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-02 18:47:37.559049 | debian-bookworm | skipping: Conditional result was False 2025-09-02 18:47:37.564088 | 2025-09-02 18:47:37.564171 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-02 18:47:38.368833 | debian-bookworm | changed 2025-09-02 18:47:38.375161 | 2025-09-02 18:47:38.375244 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-02 18:47:38.677683 | debian-bookworm | ok 2025-09-02 18:47:38.690125 | 2025-09-02 18:47:38.690235 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-02 18:47:39.439513 | debian-bookworm | changed 2025-09-02 18:47:39.445790 | 2025-09-02 18:47:39.445902 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-02 18:47:40.224458 | debian-bookworm | changed 2025-09-02 18:47:40.232218 | 2025-09-02 18:47:40.232317 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-02 18:47:40.265552 | debian-bookworm | skipping: Conditional result was False 2025-09-02 18:47:40.271319 | 2025-09-02 18:47:40.271476 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-02 18:47:40.749158 | debian-bookworm -> localhost | changed 2025-09-02 18:47:40.763287 | 2025-09-02 18:47:40.763380 | TASK [add-build-sshkey : Add back temp key] 2025-09-02 18:47:41.012083 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c16c1524a8fc43cebe1fbe8d82229047/work/c16c1524a8fc43cebe1fbe8d82229047_id_rsa (zuul-build-sshkey) 2025-09-02 18:47:41.012594 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008927 2025-09-02 18:47:41.026481 | 2025-09-02 18:47:41.026604 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-02 18:47:41.433505 | debian-bookworm | ok 2025-09-02 18:47:41.439936 | 2025-09-02 18:47:41.440092 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-02 18:47:41.463037 | debian-bookworm | skipping: Conditional result was False 2025-09-02 18:47:41.472816 | 2025-09-02 18:47:41.472936 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-02 18:47:41.916210 | debian-bookworm | ok 2025-09-02 18:47:41.927670 | 2025-09-02 18:47:41.927775 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-02 18:47:41.961436 | debian-bookworm | ok 2025-09-02 18:47:41.967498 | 2025-09-02 18:47:41.967592 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-02 18:47:42.338100 | debian-bookworm -> localhost | ok 2025-09-02 18:47:42.344435 | 2025-09-02 18:47:42.344529 | TASK [validate-host : Collect information about the host] 2025-09-02 18:47:43.454905 | debian-bookworm | ok 2025-09-02 18:47:43.469580 | 2025-09-02 18:47:43.469680 | TASK [validate-host : Sanitize hostname] 2025-09-02 18:47:43.652635 | debian-bookworm | ok 2025-09-02 18:47:43.695776 | 2025-09-02 18:47:43.695900 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-02 18:47:44.668548 | debian-bookworm -> localhost | changed 2025-09-02 18:47:44.674006 | 2025-09-02 18:47:44.674091 | TASK [validate-host : Collect information about zuul worker] 2025-09-02 18:47:45.112186 | debian-bookworm | ok 2025-09-02 18:47:45.131595 | 2025-09-02 18:47:45.131707 | TASK [validate-host : Write out all zuul information for each host] 2025-09-02 18:47:45.952439 | debian-bookworm -> localhost | changed 2025-09-02 18:47:45.962706 | 2025-09-02 18:47:45.962794 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-02 18:47:46.236338 | debian-bookworm | ok 2025-09-02 18:47:46.245634 | 2025-09-02 18:47:46.245727 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-02 18:47:47.396393 | debian-bookworm | changed: 2025-09-02 18:47:47.396572 | debian-bookworm | cd+++++++++ src/ 2025-09-02 18:47:47.396607 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-02 18:47:47.396632 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-02 18:47:47.396653 | debian-bookworm | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-09-02 18:47:47.396673 | debian-bookworm | Containerfile 2025-09-02 18:47:47.396804 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006989 2025-09-02 18:48:14.026174 | 2025-09-02 18:48:14.026290 | TASK [upload-logs : Upload logs to log server] 2025-09-02 18:48:14.620949 | localhost | Output suppressed because no_log was given 2025-09-02 18:48:14.625134 | 2025-09-02 18:48:14.625315 | LOOP [upload-logs : Compress console log and json output] 2025-09-02 18:48:14.676858 | localhost | skipping: Conditional result was False 2025-09-02 18:48:14.683553 | localhost | skipping: Conditional result was False 2025-09-02 18:48:14.694902 | 2025-09-02 18:48:14.695103 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-02 18:48:14.753583 | localhost | skipping: Conditional result was False 2025-09-02 18:48:14.753879 | 2025-09-02 18:48:14.759893 | localhost | skipping: Conditional result was False 2025-09-02 18:48:14.767333 | 2025-09-02 18:48:14.767448 | LOOP [upload-logs : Upload console log and json output]