2025-03-11 16:43:47.885577 | Job console starting... 2025-03-11 16:43:47.897685 | Updating repositories 2025-03-11 16:43:47.933271 | Preparing job workspace 2025-03-11 16:43:50.188015 | Running Ansible setup... 2025-03-11 16:43:55.316983 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-11 16:43:56.122070 | 2025-03-11 16:43:56.122232 | PLAY [Base pre] 2025-03-11 16:43:56.151901 | 2025-03-11 16:43:56.152056 | TASK [Setup log path fact] 2025-03-11 16:43:56.203486 | ubuntu-noble | ok 2025-03-11 16:43:56.225599 | 2025-03-11 16:43:56.225773 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-11 16:43:56.277549 | ubuntu-noble | ok 2025-03-11 16:43:56.284948 | 2025-03-11 16:43:56.285079 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-11 16:43:56.319975 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:43:56.327362 | 2025-03-11 16:43:56.327502 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-11 16:43:56.372280 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:43:56.379946 | 2025-03-11 16:43:56.380075 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-11 16:43:56.404240 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:43:56.411556 | 2025-03-11 16:43:56.412602 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-11 16:43:56.436864 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:43:56.444403 | 2025-03-11 16:43:56.444531 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-11 16:43:56.469079 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:43:56.497167 | 2025-03-11 16:43:56.497308 | TASK [emit-job-header : Print job information] 2025-03-11 16:43:56.569110 | # Job Information 2025-03-11 16:43:56.569296 | Ansible Version: 2.15.3 2025-03-11 16:43:56.569327 | Job: python-black 2025-03-11 16:43:56.569356 | Pipeline: check 2025-03-11 16:43:56.569377 | Executor: 7d211f194f6a 2025-03-11 16:43:56.569396 | Triggered by: https://github.com/osism/testbed/pull/2615 2025-03-11 16:43:56.569415 | Event ID: fc220720-fe97-11ef-9a2c-037f33f55ef7 2025-03-11 16:43:56.575760 | 2025-03-11 16:43:56.575870 | LOOP [emit-job-header : Print node information] 2025-03-11 16:43:56.716319 | ubuntu-noble | ok: 2025-03-11 16:43:56.716616 | ubuntu-noble | # Node Information 2025-03-11 16:43:56.716655 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-11 16:43:56.716679 | ubuntu-noble | Hostname: ubuntu 2025-03-11 16:43:56.716700 | ubuntu-noble | Username: zuul 2025-03-11 16:43:56.716719 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-11 16:43:56.716774 | ubuntu-noble | Provider: regiocloud-a 2025-03-11 16:43:56.716798 | ubuntu-noble | Label: ubuntu-noble 2025-03-11 16:43:56.716818 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-11 16:43:56.716839 | ubuntu-noble | Interface IP: 81.163.193.110 2025-03-11 16:43:56.734667 | 2025-03-11 16:43:56.734828 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-11 16:43:57.272773 | ubuntu-noble -> localhost | changed 2025-03-11 16:43:57.287314 | 2025-03-11 16:43:57.287471 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-11 16:43:58.432916 | ubuntu-noble -> localhost | changed 2025-03-11 16:43:58.455353 | 2025-03-11 16:43:58.455484 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-11 16:43:58.773067 | ubuntu-noble -> localhost | ok 2025-03-11 16:43:58.781930 | 2025-03-11 16:43:58.782048 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-11 16:43:58.809533 | ubuntu-noble | ok 2025-03-11 16:43:58.836043 | ubuntu-noble | included: /var/lib/zuul/builds/5fdc5f03279d42b2a097a4ca925d52f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-11 16:43:58.843923 | 2025-03-11 16:43:58.844037 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-11 16:43:59.611341 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-11 16:43:59.611558 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/5fdc5f03279d42b2a097a4ca925d52f9/work/5fdc5f03279d42b2a097a4ca925d52f9_id_rsa 2025-03-11 16:43:59.611596 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/5fdc5f03279d42b2a097a4ca925d52f9/work/5fdc5f03279d42b2a097a4ca925d52f9_id_rsa.pub 2025-03-11 16:43:59.611620 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-11 16:43:59.611643 | ubuntu-noble -> localhost | SHA256:A+juFOLnxqRPWTYBF4M8lUMTH0qDBG+5UyHGiqXnRO4 zuul-build-sshkey 2025-03-11 16:43:59.611665 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-11 16:43:59.611690 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-11 16:43:59.611712 | ubuntu-noble -> localhost | | .===%+. | 2025-03-11 16:43:59.611731 | ubuntu-noble -> localhost | | oo*BoB . | 2025-03-11 16:43:59.611791 | ubuntu-noble -> localhost | | * .=.=.. | 2025-03-11 16:43:59.611812 | ubuntu-noble -> localhost | |o =o o o | 2025-03-11 16:43:59.611831 | ubuntu-noble -> localhost | | =. = + S | 2025-03-11 16:43:59.611850 | ubuntu-noble -> localhost | | .Eo.* . . | 2025-03-11 16:43:59.611870 | ubuntu-noble -> localhost | | .+* | 2025-03-11 16:43:59.611890 | ubuntu-noble -> localhost | | .*o | 2025-03-11 16:43:59.611909 | ubuntu-noble -> localhost | | o+ | 2025-03-11 16:43:59.611929 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-11 16:43:59.611979 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.278630 2025-03-11 16:43:59.620210 | 2025-03-11 16:43:59.620331 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-11 16:43:59.651234 | ubuntu-noble | ok 2025-03-11 16:43:59.663629 | ubuntu-noble | included: /var/lib/zuul/builds/5fdc5f03279d42b2a097a4ca925d52f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-11 16:43:59.673647 | 2025-03-11 16:43:59.673773 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-11 16:43:59.698546 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:43:59.706070 | 2025-03-11 16:43:59.706182 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-11 16:44:00.507818 | ubuntu-noble | changed 2025-03-11 16:44:00.517291 | 2025-03-11 16:44:00.517407 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-11 16:44:00.845468 | ubuntu-noble | ok 2025-03-11 16:44:00.861003 | 2025-03-11 16:44:00.861207 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-11 16:44:01.623565 | ubuntu-noble | changed 2025-03-11 16:44:01.630937 | 2025-03-11 16:44:01.631060 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-11 16:44:02.418713 | ubuntu-noble | changed 2025-03-11 16:44:02.429341 | 2025-03-11 16:44:02.429467 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-11 16:44:02.454284 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:44:02.471874 | 2025-03-11 16:44:02.471997 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-11 16:44:02.924723 | ubuntu-noble -> localhost | changed 2025-03-11 16:44:02.939204 | 2025-03-11 16:44:02.939349 | TASK [add-build-sshkey : Add back temp key] 2025-03-11 16:44:03.292062 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/5fdc5f03279d42b2a097a4ca925d52f9/work/5fdc5f03279d42b2a097a4ca925d52f9_id_rsa (zuul-build-sshkey) 2025-03-11 16:44:03.292296 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015566 2025-03-11 16:44:03.301562 | 2025-03-11 16:44:03.301685 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-11 16:44:03.744646 | ubuntu-noble | ok 2025-03-11 16:44:03.751165 | 2025-03-11 16:44:03.751286 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-11 16:44:03.788966 | ubuntu-noble | skipping: Conditional result was False 2025-03-11 16:44:03.802774 | 2025-03-11 16:44:03.802902 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-11 16:44:04.239034 | ubuntu-noble | ok 2025-03-11 16:44:04.258708 | 2025-03-11 16:44:04.258886 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-11 16:44:04.291007 | ubuntu-noble | ok 2025-03-11 16:44:04.298143 | 2025-03-11 16:44:04.298265 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-11 16:44:04.589188 | ubuntu-noble -> localhost | ok 2025-03-11 16:44:04.597353 | 2025-03-11 16:44:04.597474 | TASK [validate-host : Collect information about the host] 2025-03-11 16:44:05.825976 | ubuntu-noble | ok 2025-03-11 16:44:05.838280 | 2025-03-11 16:44:05.838401 | TASK [validate-host : Sanitize hostname] 2025-03-11 16:44:05.917837 | ubuntu-noble | ok 2025-03-11 16:44:05.933557 | 2025-03-11 16:44:05.933870 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-11 16:44:06.534219 | ubuntu-noble -> localhost | changed 2025-03-11 16:44:06.541651 | 2025-03-11 16:44:06.541814 | TASK [validate-host : Collect information about zuul worker] 2025-03-11 16:44:07.078603 | ubuntu-noble | ok 2025-03-11 16:44:07.085078 | 2025-03-11 16:44:07.085202 | TASK [validate-host : Write out all zuul information for each host] 2025-03-11 16:44:07.623120 | ubuntu-noble -> localhost | changed 2025-03-11 16:44:07.646464 | 2025-03-11 16:44:07.646633 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-11 16:44:07.973500 | ubuntu-noble | ok 2025-03-11 16:44:07.985075 | 2025-03-11 16:44:07.985212 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-11 16:44:09.270969 | ubuntu-noble | changed: 2025-03-11 16:44:09.271170 | ubuntu-noble | cd+++++++++ src/ 2025-03-11 16:44:09.271203 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-11 16:44:09.271227 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-11 16:44:09.271248 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-11 16:44:09.271268 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.008059 2025-03-11 16:44:45.745632 | 2025-03-11 16:44:45.745783 | TASK [upload-logs : Upload logs to log server] 2025-03-11 16:44:46.379487 | localhost | Output suppressed because no_log was given 2025-03-11 16:44:46.382799 | 2025-03-11 16:44:46.382906 | LOOP [upload-logs : Compress console log and json output] 2025-03-11 16:44:46.445276 | localhost | skipping: Conditional result was False 2025-03-11 16:44:46.463164 | localhost | skipping: Conditional result was False 2025-03-11 16:44:46.473036 | 2025-03-11 16:44:46.473161 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-11 16:44:46.556524 | localhost | skipping: Conditional result was False 2025-03-11 16:44:46.556877 | 2025-03-11 16:44:46.558258 | localhost | skipping: Conditional result was False 2025-03-11 16:44:46.572617 | 2025-03-11 16:44:46.572746 | LOOP [upload-logs : Upload console log and json output]