2025-10-13 18:12:27.601637 | Job console starting 2025-10-13 18:12:27.620759 | Updating git repos 2025-10-13 18:12:27.719677 | Cloning repos into workspace 2025-10-13 18:12:27.803980 | Restoring repo states 2025-10-13 18:12:27.837947 | Merging changes 2025-10-13 18:12:28.472145 | Checking out repos 2025-10-13 18:12:28.509044 | Preparing playbooks 2025-10-13 18:12:29.694584 | Running Ansible setup 2025-10-13 18:12:34.606741 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-13 18:12:35.438913 | 2025-10-13 18:12:35.439041 | PLAY [Base pre] 2025-10-13 18:12:35.508671 | 2025-10-13 18:12:35.508794 | TASK [Setup log path fact] 2025-10-13 18:12:35.545133 | debian-bookworm | ok 2025-10-13 18:12:35.594472 | 2025-10-13 18:12:35.594982 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-13 18:12:35.682210 | debian-bookworm | ok 2025-10-13 18:12:35.712642 | 2025-10-13 18:12:35.712758 | TASK [emit-job-header : Print job information] 2025-10-13 18:12:35.765934 | # Job Information 2025-10-13 18:12:35.766103 | Ansible Version: 2.16.14 2025-10-13 18:12:35.766138 | Job: python-black 2025-10-13 18:12:35.766171 | Pipeline: check 2025-10-13 18:12:35.766195 | Executor: 521e9411259a 2025-10-13 18:12:35.766215 | Triggered by: https://github.com/osism/python-osism/pull/1682 2025-10-13 18:12:35.766238 | Event ID: 26ccd410-a860-11f0-8f05-ea1d8736d258 2025-10-13 18:12:35.773260 | 2025-10-13 18:12:35.773357 | LOOP [emit-job-header : Print node information] 2025-10-13 18:12:35.873869 | debian-bookworm | ok: 2025-10-13 18:12:35.874025 | debian-bookworm | # Node Information 2025-10-13 18:12:35.874058 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-13 18:12:35.874083 | debian-bookworm | Hostname: debian 2025-10-13 18:12:35.874105 | debian-bookworm | Username: zuul 2025-10-13 18:12:35.874126 | debian-bookworm | Distro: Debian 12.12 2025-10-13 18:12:35.874149 | debian-bookworm | Provider: regiocloud-a 2025-10-13 18:12:35.874170 | debian-bookworm | Region: 2025-10-13 18:12:35.874191 | debian-bookworm | Label: debian-bookworm 2025-10-13 18:12:35.874210 | debian-bookworm | Product Name: OpenStack Nova 2025-10-13 18:12:35.874229 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feee:2c5b 2025-10-13 18:12:35.890158 | 2025-10-13 18:12:35.890264 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-13 18:12:36.987230 | debian-bookworm -> localhost | changed 2025-10-13 18:12:36.993889 | 2025-10-13 18:12:36.993991 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-13 18:12:38.570387 | debian-bookworm -> localhost | changed 2025-10-13 18:12:38.579710 | 2025-10-13 18:12:38.579802 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-13 18:12:38.845055 | debian-bookworm -> localhost | ok 2025-10-13 18:12:38.849892 | 2025-10-13 18:12:38.849977 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-13 18:12:38.876280 | debian-bookworm | ok 2025-10-13 18:12:38.891822 | debian-bookworm | included: /var/lib/zuul/builds/77299ed4862b44bea4c1d8ef90c89624/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-13 18:12:38.897011 | 2025-10-13 18:12:38.897084 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-13 18:12:40.448341 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-13 18:12:40.448575 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/77299ed4862b44bea4c1d8ef90c89624/work/77299ed4862b44bea4c1d8ef90c89624_id_rsa 2025-10-13 18:12:40.448630 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/77299ed4862b44bea4c1d8ef90c89624/work/77299ed4862b44bea4c1d8ef90c89624_id_rsa.pub 2025-10-13 18:12:40.448670 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-13 18:12:40.448706 | debian-bookworm -> localhost | SHA256:ndz5plHUh8SPCpqKOiKJ9ZP6PQ93qe+/UO0xP0lDpd0 zuul-build-sshkey 2025-10-13 18:12:40.448742 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-13 18:12:40.448789 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-13 18:12:40.448828 | debian-bookworm -> localhost | | .. .| 2025-10-13 18:12:40.448865 | debian-bookworm -> localhost | | ..=o| 2025-10-13 18:12:40.448900 | debian-bookworm -> localhost | | ++E| 2025-10-13 18:12:40.448933 | debian-bookworm -> localhost | | o.o +o o| 2025-10-13 18:12:40.448968 | debian-bookworm -> localhost | | So+.+.=o | 2025-10-13 18:12:40.449010 | debian-bookworm -> localhost | | . o o.+.+o| 2025-10-13 18:12:40.449046 | debian-bookworm -> localhost | |.o . o... + . +o.| 2025-10-13 18:12:40.449073 | debian-bookworm -> localhost | |= . =.oo o . + .| 2025-10-13 18:12:40.449092 | debian-bookworm -> localhost | |..o=...oooo.+. | 2025-10-13 18:12:40.449110 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-13 18:12:40.449158 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.673096 2025-10-13 18:12:40.455325 | 2025-10-13 18:12:40.455405 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-13 18:12:40.505745 | debian-bookworm | ok 2025-10-13 18:12:40.516764 | debian-bookworm | included: /var/lib/zuul/builds/77299ed4862b44bea4c1d8ef90c89624/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-13 18:12:40.533791 | 2025-10-13 18:12:40.533878 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-13 18:12:40.583291 | debian-bookworm | skipping: Conditional result was False 2025-10-13 18:12:40.588411 | 2025-10-13 18:12:40.588490 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-13 18:12:41.527545 | debian-bookworm | changed 2025-10-13 18:12:41.533857 | 2025-10-13 18:12:41.533928 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-13 18:12:41.820287 | debian-bookworm | ok 2025-10-13 18:12:41.824683 | 2025-10-13 18:12:41.824759 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-13 18:12:42.597060 | debian-bookworm | changed 2025-10-13 18:12:42.602450 | 2025-10-13 18:12:42.602542 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-13 18:12:43.278276 | debian-bookworm | changed 2025-10-13 18:12:43.282815 | 2025-10-13 18:12:43.282982 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-13 18:12:43.295486 | debian-bookworm | skipping: Conditional result was False 2025-10-13 18:12:43.300264 | 2025-10-13 18:12:43.300333 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-13 18:12:43.669992 | debian-bookworm -> localhost | changed 2025-10-13 18:12:43.680352 | 2025-10-13 18:12:43.680439 | TASK [add-build-sshkey : Add back temp key] 2025-10-13 18:12:43.956620 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/77299ed4862b44bea4c1d8ef90c89624/work/77299ed4862b44bea4c1d8ef90c89624_id_rsa (zuul-build-sshkey) 2025-10-13 18:12:43.956808 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016418 2025-10-13 18:12:43.963002 | 2025-10-13 18:12:43.963084 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-13 18:12:44.467917 | debian-bookworm | ok 2025-10-13 18:12:44.473007 | 2025-10-13 18:12:44.473094 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-13 18:12:44.516486 | debian-bookworm | skipping: Conditional result was False 2025-10-13 18:12:44.524872 | 2025-10-13 18:12:44.524964 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-13 18:12:44.995657 | debian-bookworm | ok 2025-10-13 18:12:45.018112 | 2025-10-13 18:12:45.018205 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-13 18:12:45.061438 | debian-bookworm | ok 2025-10-13 18:12:45.065446 | 2025-10-13 18:12:45.065541 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-13 18:12:45.425584 | debian-bookworm -> localhost | ok 2025-10-13 18:12:45.431174 | 2025-10-13 18:12:45.431258 | TASK [validate-host : Collect information about the host] 2025-10-13 18:12:46.554687 | debian-bookworm | ok 2025-10-13 18:12:46.580775 | 2025-10-13 18:12:46.580883 | TASK [validate-host : Sanitize hostname] 2025-10-13 18:12:46.793181 | debian-bookworm | ok 2025-10-13 18:12:46.869778 | 2025-10-13 18:12:46.869881 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-13 18:12:48.211177 | debian-bookworm -> localhost | changed 2025-10-13 18:12:48.219301 | 2025-10-13 18:12:48.219395 | TASK [validate-host : Collect information about zuul worker] 2025-10-13 18:12:48.662453 | debian-bookworm | ok 2025-10-13 18:12:48.667020 | 2025-10-13 18:12:48.667160 | TASK [validate-host : Write out all zuul information for each host] 2025-10-13 18:12:49.788298 | debian-bookworm -> localhost | changed 2025-10-13 18:12:49.800762 | 2025-10-13 18:12:49.800847 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-13 18:12:50.111594 | debian-bookworm | ok 2025-10-13 18:12:50.115916 | 2025-10-13 18:12:50.115996 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-13 18:12:52.062933 | debian-bookworm | changed: 2025-10-13 18:12:52.063077 | debian-bookworm | cd+++++++++ src/ 2025-10-13 18:12:52.063105 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-13 18:12:52.063124 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-13 18:12:52.063142 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-10-13 18:12:52.063159 | debian-bookworm | Containerfile 2025-10-13 18:12:52.063251 | debian-bookworm | Containerfile 2025-10-13 18:12:52.141680 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006827 2025-10-13 18:13:20.422686 | 2025-10-13 18:13:20.422792 | TASK [upload-logs : Upload logs to log server] 2025-10-13 18:13:20.980632 | localhost | Output suppressed because no_log was given 2025-10-13 18:13:20.984667 | 2025-10-13 18:13:20.984879 | LOOP [upload-logs : Compress console log and json output] 2025-10-13 18:13:21.055666 | localhost | skipping: Conditional result was False 2025-10-13 18:13:21.061365 | localhost | skipping: Conditional result was False 2025-10-13 18:13:21.074366 | 2025-10-13 18:13:21.074483 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-13 18:13:21.125277 | localhost | skipping: Conditional result was False 2025-10-13 18:13:21.125557 | 2025-10-13 18:13:21.132182 | localhost | skipping: Conditional result was False 2025-10-13 18:13:21.143170 | 2025-10-13 18:13:21.143333 | LOOP [upload-logs : Upload console log and json output]