2025-03-13 18:52:02.275320 | Job console starting... 2025-03-13 18:52:02.306287 | Updating repositories 2025-03-13 18:52:02.348970 | Preparing job workspace 2025-03-13 18:52:04.355520 | Running Ansible setup... 2025-03-13 18:52:09.852808 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-13 18:52:10.638392 | 2025-03-13 18:52:10.638554 | PLAY [Base pre] 2025-03-13 18:52:10.672638 | 2025-03-13 18:52:10.672791 | TASK [Setup log path fact] 2025-03-13 18:52:10.724712 | ubuntu-noble | ok 2025-03-13 18:52:10.748763 | 2025-03-13 18:52:10.748916 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-13 18:52:10.791200 | ubuntu-noble | ok 2025-03-13 18:52:10.798411 | 2025-03-13 18:52:10.798537 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-13 18:52:10.835732 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:10.842934 | 2025-03-13 18:52:10.843056 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-13 18:52:10.898330 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:10.907072 | 2025-03-13 18:52:10.907234 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-13 18:52:10.954376 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:10.961882 | 2025-03-13 18:52:10.962015 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-13 18:52:10.997077 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:11.015430 | 2025-03-13 18:52:11.015583 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-13 18:52:11.040215 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:11.073004 | 2025-03-13 18:52:11.073147 | TASK [emit-job-header : Print job information] 2025-03-13 18:52:11.125749 | # Job Information 2025-03-13 18:52:11.125938 | Ansible Version: 2.15.3 2025-03-13 18:52:11.125970 | Job: python-black 2025-03-13 18:52:11.125999 | Pipeline: check 2025-03-13 18:52:11.126019 | Executor: 7d211f194f6a 2025-03-13 18:52:11.126038 | Triggered by: https://github.com/osism/testbed/pull/2615 2025-03-13 18:52:11.126056 | Event ID: 3b03d4f0-003c-11f0-9b86-6045dff4364b 2025-03-13 18:52:11.133682 | 2025-03-13 18:52:11.133808 | LOOP [emit-job-header : Print node information] 2025-03-13 18:52:11.291130 | ubuntu-noble | ok: 2025-03-13 18:52:11.291333 | ubuntu-noble | # Node Information 2025-03-13 18:52:11.291376 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-13 18:52:11.291406 | ubuntu-noble | Hostname: ubuntu 2025-03-13 18:52:11.291832 | ubuntu-noble | Username: zuul 2025-03-13 18:52:11.292029 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-13 18:52:11.292067 | ubuntu-noble | Provider: regiocloud-a 2025-03-13 18:52:11.292091 | ubuntu-noble | Label: ubuntu-noble 2025-03-13 18:52:11.292148 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-13 18:52:11.292173 | ubuntu-noble | Interface IP: 81.163.192.79 2025-03-13 18:52:11.315457 | 2025-03-13 18:52:11.315572 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-13 18:52:11.716157 | ubuntu-noble -> localhost | changed 2025-03-13 18:52:11.723919 | 2025-03-13 18:52:11.724010 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-13 18:52:12.571194 | ubuntu-noble -> localhost | changed 2025-03-13 18:52:12.586033 | 2025-03-13 18:52:12.586137 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-13 18:52:12.822803 | ubuntu-noble -> localhost | ok 2025-03-13 18:52:12.830103 | 2025-03-13 18:52:12.830206 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-13 18:52:12.860655 | ubuntu-noble | ok 2025-03-13 18:52:12.875951 | ubuntu-noble | included: /var/lib/zuul/builds/acfce3f8591c471db54495ecf6e85a0c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-13 18:52:12.883358 | 2025-03-13 18:52:12.883445 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-13 18:52:13.497384 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-13 18:52:13.497606 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/acfce3f8591c471db54495ecf6e85a0c/work/acfce3f8591c471db54495ecf6e85a0c_id_rsa 2025-03-13 18:52:13.497645 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/acfce3f8591c471db54495ecf6e85a0c/work/acfce3f8591c471db54495ecf6e85a0c_id_rsa.pub 2025-03-13 18:52:13.497669 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-13 18:52:13.497691 | ubuntu-noble -> localhost | SHA256:TDLPfiKgEqgJw6CcMIR5wR7AXLYIBbxjbIHYwb6YPgA zuul-build-sshkey 2025-03-13 18:52:13.497715 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-13 18:52:13.497739 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-13 18:52:13.497761 | ubuntu-noble -> localhost | |@X== | 2025-03-13 18:52:13.497781 | ubuntu-noble -> localhost | |B=O . | 2025-03-13 18:52:13.497800 | ubuntu-noble -> localhost | |==oo o . | 2025-03-13 18:52:13.497819 | ubuntu-noble -> localhost | |EB+ B | 2025-03-13 18:52:13.497838 | ubuntu-noble -> localhost | |X*... S | 2025-03-13 18:52:13.497856 | ubuntu-noble -> localhost | |*+.. . . | 2025-03-13 18:52:13.497875 | ubuntu-noble -> localhost | |* . . o . | 2025-03-13 18:52:13.497895 | ubuntu-noble -> localhost | | + . o | 2025-03-13 18:52:13.497914 | ubuntu-noble -> localhost | | . | 2025-03-13 18:52:13.497933 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-13 18:52:13.497978 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.140154 2025-03-13 18:52:13.505539 | 2025-03-13 18:52:13.505637 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-13 18:52:13.537122 | ubuntu-noble | ok 2025-03-13 18:52:13.552600 | ubuntu-noble | included: /var/lib/zuul/builds/acfce3f8591c471db54495ecf6e85a0c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-13 18:52:13.561891 | 2025-03-13 18:52:13.561987 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-13 18:52:13.586319 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:13.602178 | 2025-03-13 18:52:13.602531 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-13 18:52:14.433564 | ubuntu-noble | changed 2025-03-13 18:52:14.442101 | 2025-03-13 18:52:14.442295 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-13 18:52:14.822031 | ubuntu-noble | ok 2025-03-13 18:52:14.830898 | 2025-03-13 18:52:14.830999 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-13 18:52:15.660346 | ubuntu-noble | changed 2025-03-13 18:52:15.670052 | 2025-03-13 18:52:15.670294 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-13 18:52:16.495779 | ubuntu-noble | changed 2025-03-13 18:52:16.504552 | 2025-03-13 18:52:16.504656 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-13 18:52:16.528364 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:16.537496 | 2025-03-13 18:52:16.537595 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-13 18:52:17.276099 | ubuntu-noble -> localhost | changed 2025-03-13 18:52:17.286800 | 2025-03-13 18:52:17.286884 | TASK [add-build-sshkey : Add back temp key] 2025-03-13 18:52:17.561787 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/acfce3f8591c471db54495ecf6e85a0c/work/acfce3f8591c471db54495ecf6e85a0c_id_rsa (zuul-build-sshkey) 2025-03-13 18:52:17.561987 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013796 2025-03-13 18:52:17.605805 | 2025-03-13 18:52:17.606813 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-13 18:52:18.154737 | ubuntu-noble | ok 2025-03-13 18:52:18.160843 | 2025-03-13 18:52:18.160931 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-13 18:52:18.194787 | ubuntu-noble | skipping: Conditional result was False 2025-03-13 18:52:18.209891 | 2025-03-13 18:52:18.209986 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-13 18:52:18.706254 | ubuntu-noble | ok 2025-03-13 18:52:18.722386 | 2025-03-13 18:52:18.722494 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-13 18:52:18.767678 | ubuntu-noble | ok 2025-03-13 18:52:18.774405 | 2025-03-13 18:52:18.774498 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-13 18:52:19.097591 | ubuntu-noble -> localhost | ok 2025-03-13 18:52:19.110149 | 2025-03-13 18:52:19.110337 | TASK [validate-host : Collect information about the host] 2025-03-13 18:52:20.439737 | ubuntu-noble | ok 2025-03-13 18:52:20.456587 | 2025-03-13 18:52:20.456694 | TASK [validate-host : Sanitize hostname] 2025-03-13 18:52:20.541647 | ubuntu-noble | ok 2025-03-13 18:52:20.550715 | 2025-03-13 18:52:20.550900 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-13 18:52:21.253669 | ubuntu-noble -> localhost | changed 2025-03-13 18:52:21.276460 | 2025-03-13 18:52:21.276659 | TASK [validate-host : Collect information about zuul worker] 2025-03-13 18:52:21.806371 | ubuntu-noble | ok 2025-03-13 18:52:21.812999 | 2025-03-13 18:52:21.813094 | TASK [validate-host : Write out all zuul information for each host] 2025-03-13 18:52:22.457064 | ubuntu-noble -> localhost | changed 2025-03-13 18:52:22.474473 | 2025-03-13 18:52:22.474629 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-13 18:52:22.878294 | ubuntu-noble | ok 2025-03-13 18:52:22.893411 | 2025-03-13 18:52:22.893543 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-13 18:52:24.408157 | ubuntu-noble | changed: 2025-03-13 18:52:24.409049 | ubuntu-noble | cd+++++++++ src/ 2025-03-13 18:52:24.409567 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-13 18:52:24.410048 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-13 18:52:24.410332 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-13 18:52:24.410605 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.009085 2025-03-13 18:53:04.514654 | 2025-03-13 18:53:04.514765 | TASK [upload-logs : Upload logs to log server] 2025-03-13 18:53:05.157647 | localhost | Output suppressed because no_log was given 2025-03-13 18:53:05.161602 | 2025-03-13 18:53:05.161724 | LOOP [upload-logs : Compress console log and json output] 2025-03-13 18:53:05.229470 | localhost | skipping: Conditional result was False 2025-03-13 18:53:05.246182 | localhost | skipping: Conditional result was False 2025-03-13 18:53:05.259166 | 2025-03-13 18:53:05.259339 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-13 18:53:05.321561 | localhost | skipping: Conditional result was False 2025-03-13 18:53:05.322038 | 2025-03-13 18:53:05.334418 | localhost | skipping: Conditional result was False 2025-03-13 18:53:05.339842 | 2025-03-13 18:53:05.339949 | LOOP [upload-logs : Upload console log and json output]