2025-02-03 00:07:19.466280 | Job console starting... 2025-02-03 00:07:19.477883 | Updating repositories 2025-02-03 00:07:19.509541 | Preparing job workspace 2025-02-03 00:07:21.035778 | Running Ansible setup... 2025-02-03 00:07:26.708237 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-03 00:07:27.595097 | 2025-02-03 00:07:27.595314 | PLAY [Base pre] 2025-02-03 00:07:27.625870 | 2025-02-03 00:07:27.626010 | TASK [Setup log path fact] 2025-02-03 00:07:27.646922 | ubuntu-noble | ok 2025-02-03 00:07:27.669434 | 2025-02-03 00:07:27.669572 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 00:07:27.728996 | ubuntu-noble | ok 2025-02-03 00:07:27.738981 | 2025-02-03 00:07:27.739116 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 00:07:27.784567 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:27.796062 | 2025-02-03 00:07:27.796277 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 00:07:27.842302 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:27.851354 | 2025-02-03 00:07:27.851491 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 00:07:27.877622 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:27.886850 | 2025-02-03 00:07:27.886981 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 00:07:27.912040 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:27.921620 | 2025-02-03 00:07:27.922269 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 00:07:27.971372 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:27.993337 | 2025-02-03 00:07:27.993490 | TASK [emit-job-header : Print job information] 2025-02-03 00:07:28.050167 | # Job Information 2025-02-03 00:07:28.050384 | Ansible Version: 2.15.3 2025-02-03 00:07:28.050418 | Job: python-black 2025-02-03 00:07:28.050447 | Pipeline: check 2025-02-03 00:07:28.050468 | Executor: 7d211f194f6a 2025-02-03 00:07:28.050487 | Triggered by: https://github.com/osism/testbed/pull/2599 2025-02-03 00:07:28.050506 | Event ID: b4069510-e1c2-11ef-94aa-a5e676cfb205 2025-02-03 00:07:28.057435 | 2025-02-03 00:07:28.057554 | LOOP [emit-job-header : Print node information] 2025-02-03 00:07:28.208523 | ubuntu-noble | ok: 2025-02-03 00:07:28.208734 | ubuntu-noble | # Node Information 2025-02-03 00:07:28.208768 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-03 00:07:28.208791 | ubuntu-noble | Hostname: ubuntu 2025-02-03 00:07:28.208813 | ubuntu-noble | Username: zuul 2025-02-03 00:07:28.208833 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-03 00:07:28.208852 | ubuntu-noble | Provider: regiocloud-a 2025-02-03 00:07:28.208872 | ubuntu-noble | Label: ubuntu-noble 2025-02-03 00:07:28.208890 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-03 00:07:28.208910 | ubuntu-noble | Interface IP: 81.163.193.197 2025-02-03 00:07:28.229752 | 2025-02-03 00:07:28.229897 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-03 00:07:28.788375 | ubuntu-noble -> localhost | changed 2025-02-03 00:07:28.800306 | 2025-02-03 00:07:28.800496 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-03 00:07:30.149286 | ubuntu-noble -> localhost | changed 2025-02-03 00:07:30.165049 | 2025-02-03 00:07:30.165514 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-03 00:07:30.531284 | ubuntu-noble -> localhost | ok 2025-02-03 00:07:30.541320 | 2025-02-03 00:07:30.541447 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-03 00:07:30.572945 | ubuntu-noble | ok 2025-02-03 00:07:30.592004 | ubuntu-noble | included: /var/lib/zuul/builds/469ba238038046028b6e5a63e3ce8935/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-03 00:07:30.601311 | 2025-02-03 00:07:30.601546 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-03 00:07:31.376120 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-03 00:07:31.376413 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/469ba238038046028b6e5a63e3ce8935/work/469ba238038046028b6e5a63e3ce8935_id_rsa 2025-02-03 00:07:31.376711 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/469ba238038046028b6e5a63e3ce8935/work/469ba238038046028b6e5a63e3ce8935_id_rsa.pub 2025-02-03 00:07:31.376949 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-03 00:07:31.376985 | ubuntu-noble -> localhost | SHA256:r3GIb9UsEMM2g5R4tadU0+Zp2Rzz6hMCmuMfBmvrEgQ zuul-build-sshkey 2025-02-03 00:07:31.377051 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-03 00:07:31.377080 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-03 00:07:31.377103 | ubuntu-noble -> localhost | | o.=. o. | 2025-02-03 00:07:31.377123 | ubuntu-noble -> localhost | | E + Bo .o o | 2025-02-03 00:07:31.377144 | ubuntu-noble -> localhost | | o .o=.o = + | 2025-02-03 00:07:31.377164 | ubuntu-noble -> localhost | | ...o. = o .| 2025-02-03 00:07:31.377184 | ubuntu-noble -> localhost | | . S+ = . | 2025-02-03 00:07:31.377224 | ubuntu-noble -> localhost | | ..+=o + o | 2025-02-03 00:07:31.377245 | ubuntu-noble -> localhost | | .o=o=. o . | 2025-02-03 00:07:31.377266 | ubuntu-noble -> localhost | | .oo* . o | 2025-02-03 00:07:31.377286 | ubuntu-noble -> localhost | | +=.. . | 2025-02-03 00:07:31.377305 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-03 00:07:31.377369 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.201176 2025-02-03 00:07:31.396943 | 2025-02-03 00:07:31.397618 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-03 00:07:31.441093 | ubuntu-noble | ok 2025-02-03 00:07:31.456296 | ubuntu-noble | included: /var/lib/zuul/builds/469ba238038046028b6e5a63e3ce8935/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-03 00:07:31.469069 | 2025-02-03 00:07:31.469188 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-03 00:07:31.495953 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:31.509892 | 2025-02-03 00:07:31.510023 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-03 00:07:32.199851 | ubuntu-noble | changed 2025-02-03 00:07:32.209960 | 2025-02-03 00:07:32.210149 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-03 00:07:32.502046 | ubuntu-noble | ok 2025-02-03 00:07:32.511625 | 2025-02-03 00:07:32.511773 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-03 00:07:33.240845 | ubuntu-noble | changed 2025-02-03 00:07:33.250545 | 2025-02-03 00:07:33.250786 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-03 00:07:33.984672 | ubuntu-noble | changed 2025-02-03 00:07:33.993410 | 2025-02-03 00:07:33.993530 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-03 00:07:34.019677 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:34.036096 | 2025-02-03 00:07:34.036308 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-03 00:07:34.547788 | ubuntu-noble -> localhost | changed 2025-02-03 00:07:34.584950 | 2025-02-03 00:07:34.585096 | TASK [add-build-sshkey : Add back temp key] 2025-02-03 00:07:35.011390 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/469ba238038046028b6e5a63e3ce8935/work/469ba238038046028b6e5a63e3ce8935_id_rsa (zuul-build-sshkey) 2025-02-03 00:07:35.011637 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013608 2025-02-03 00:07:35.025889 | 2025-02-03 00:07:35.026013 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-03 00:07:35.453132 | ubuntu-noble | ok 2025-02-03 00:07:35.460674 | 2025-02-03 00:07:35.460795 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-03 00:07:35.506285 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 00:07:35.526374 | 2025-02-03 00:07:35.526514 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-03 00:07:35.973371 | ubuntu-noble | ok 2025-02-03 00:07:35.989393 | 2025-02-03 00:07:35.989527 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-03 00:07:36.031674 | ubuntu-noble | ok 2025-02-03 00:07:36.038741 | 2025-02-03 00:07:36.038853 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-03 00:07:36.343255 | ubuntu-noble -> localhost | ok 2025-02-03 00:07:36.359429 | 2025-02-03 00:07:36.359582 | TASK [validate-host : Collect information about the host] 2025-02-03 00:07:37.583522 | ubuntu-noble | ok 2025-02-03 00:07:37.597588 | 2025-02-03 00:07:37.597709 | TASK [validate-host : Sanitize hostname] 2025-02-03 00:07:37.676325 | ubuntu-noble | ok 2025-02-03 00:07:37.684985 | 2025-02-03 00:07:37.685116 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-03 00:07:38.345412 | ubuntu-noble -> localhost | changed 2025-02-03 00:07:38.365045 | 2025-02-03 00:07:38.365460 | TASK [validate-host : Collect information about zuul worker] 2025-02-03 00:07:38.934204 | ubuntu-noble | ok 2025-02-03 00:07:38.940754 | 2025-02-03 00:07:38.940884 | TASK [validate-host : Write out all zuul information for each host] 2025-02-03 00:07:39.539907 | ubuntu-noble -> localhost | changed 2025-02-03 00:07:39.556282 | 2025-02-03 00:07:39.556432 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-03 00:07:39.843659 | ubuntu-noble | ok 2025-02-03 00:07:39.853406 | 2025-02-03 00:07:39.853548 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-03 00:07:41.074604 | ubuntu-noble | changed: 2025-02-03 00:07:41.074868 | ubuntu-noble | cd+++++++++ src/ 2025-02-03 00:07:41.074915 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-03 00:07:41.074949 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-03 00:07:41.074979 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-02-03 00:07:41.075008 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005607 2025-02-03 00:08:14.062449 | 2025-02-03 00:08:14.062582 | TASK [upload-logs : Upload logs to log server] 2025-02-03 00:08:14.691409 | localhost | Output suppressed because no_log was given 2025-02-03 00:08:14.697889 | 2025-02-03 00:08:14.698023 | LOOP [upload-logs : Compress console log and json output] 2025-02-03 00:08:14.765624 | localhost | skipping: Conditional result was False 2025-02-03 00:08:14.783910 | localhost | skipping: Conditional result was False 2025-02-03 00:08:14.805160 | 2025-02-03 00:08:14.805443 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-03 00:08:14.870552 | localhost | skipping: Conditional result was False 2025-02-03 00:08:14.870958 | 2025-02-03 00:08:14.887305 | localhost | skipping: Conditional result was False 2025-02-03 00:08:14.900701 | 2025-02-03 00:08:14.900852 | LOOP [upload-logs : Upload console log and json output]