2025-03-19 22:57:42.888289 | Job console starting... 2025-03-19 22:57:42.921373 | Updating repositories 2025-03-19 22:57:42.975913 | Preparing job workspace 2025-03-19 22:57:45.107572 | Running Ansible setup... 2025-03-19 22:57:50.413494 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-19 22:57:51.202260 | 2025-03-19 22:57:51.202433 | PLAY [Base pre] 2025-03-19 22:57:51.233789 | 2025-03-19 22:57:51.233956 | TASK [Setup log path fact] 2025-03-19 22:57:51.266477 | ubuntu-noble | ok 2025-03-19 22:57:51.289388 | 2025-03-19 22:57:51.289545 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-19 22:57:51.347264 | ubuntu-noble | ok 2025-03-19 22:57:51.355863 | 2025-03-19 22:57:51.356004 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-19 22:57:51.385223 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:51.395045 | 2025-03-19 22:57:51.395205 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-19 22:57:51.440909 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:51.448690 | 2025-03-19 22:57:51.448831 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-19 22:57:51.475076 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:51.484111 | 2025-03-19 22:57:51.484259 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-19 22:57:51.511855 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:51.519545 | 2025-03-19 22:57:51.519684 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-19 22:57:51.544744 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:51.560280 | 2025-03-19 22:57:51.560464 | TASK [emit-job-header : Print job information] 2025-03-19 22:57:51.612988 | # Job Information 2025-03-19 22:57:51.613165 | Ansible Version: 2.15.3 2025-03-19 22:57:51.613196 | Job: python-black 2025-03-19 22:57:51.613223 | Pipeline: check 2025-03-19 22:57:51.613243 | Executor: 7d211f194f6a 2025-03-19 22:57:51.613262 | Triggered by: https://github.com/osism/testbed/pull/2615 2025-03-19 22:57:51.613280 | Event ID: 8bee7cc0-0515-11f0-9eae-0ab3159231d7 2025-03-19 22:57:51.619783 | 2025-03-19 22:57:51.619904 | LOOP [emit-job-header : Print node information] 2025-03-19 22:57:51.767075 | ubuntu-noble | ok: 2025-03-19 22:57:51.767363 | ubuntu-noble | # Node Information 2025-03-19 22:57:51.767505 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-19 22:57:51.767536 | ubuntu-noble | Hostname: ubuntu 2025-03-19 22:57:51.767558 | ubuntu-noble | Username: zuul 2025-03-19 22:57:51.767578 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-19 22:57:51.767598 | ubuntu-noble | Provider: regiocloud-a 2025-03-19 22:57:51.767617 | ubuntu-noble | Label: ubuntu-noble 2025-03-19 22:57:51.767636 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-19 22:57:51.767657 | ubuntu-noble | Interface IP: 81.163.193.171 2025-03-19 22:57:51.790804 | 2025-03-19 22:57:51.790959 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-19 22:57:52.342031 | ubuntu-noble -> localhost | changed 2025-03-19 22:57:52.350383 | 2025-03-19 22:57:52.350502 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-19 22:57:53.427162 | ubuntu-noble -> localhost | changed 2025-03-19 22:57:53.444652 | 2025-03-19 22:57:53.444791 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-19 22:57:53.755094 | ubuntu-noble -> localhost | ok 2025-03-19 22:57:53.763464 | 2025-03-19 22:57:53.763573 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-19 22:57:53.794510 | ubuntu-noble | ok 2025-03-19 22:57:53.823809 | ubuntu-noble | included: /var/lib/zuul/builds/2076cc12e9a341d79100c96798811c05/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-19 22:57:53.831669 | 2025-03-19 22:57:53.831798 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-19 22:57:54.521570 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-19 22:57:54.521813 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2076cc12e9a341d79100c96798811c05/work/2076cc12e9a341d79100c96798811c05_id_rsa 2025-03-19 22:57:54.521850 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2076cc12e9a341d79100c96798811c05/work/2076cc12e9a341d79100c96798811c05_id_rsa.pub 2025-03-19 22:57:54.521874 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-19 22:57:54.521896 | ubuntu-noble -> localhost | SHA256:Grs/b82EUB2z/dG6K/SezSHCj+WDg4ytYD/h16HMCGQ zuul-build-sshkey 2025-03-19 22:57:54.521918 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-19 22:57:54.521943 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-19 22:57:54.521965 | ubuntu-noble -> localhost | | .o. | 2025-03-19 22:57:54.521984 | ubuntu-noble -> localhost | | . .+ .| 2025-03-19 22:57:54.522004 | ubuntu-noble -> localhost | | . . ...| 2025-03-19 22:57:54.522023 | ubuntu-noble -> localhost | | E . o.| 2025-03-19 22:57:54.522041 | ubuntu-noble -> localhost | | o . S. . . .| 2025-03-19 22:57:54.522060 | ubuntu-noble -> localhost | | . = o.o . | 2025-03-19 22:57:54.522078 | ubuntu-noble -> localhost | | o= O +Bo+.. | 2025-03-19 22:57:54.522097 | ubuntu-noble -> localhost | | . o*.O.+X.o=.| 2025-03-19 22:57:54.522117 | ubuntu-noble -> localhost | | .+=+...== o| 2025-03-19 22:57:54.522135 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-19 22:57:54.522185 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.152561 2025-03-19 22:57:54.530333 | 2025-03-19 22:57:54.530437 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-19 22:57:54.562311 | ubuntu-noble | ok 2025-03-19 22:57:54.573215 | ubuntu-noble | included: /var/lib/zuul/builds/2076cc12e9a341d79100c96798811c05/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-19 22:57:54.582621 | 2025-03-19 22:57:54.582703 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-19 22:57:54.606626 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:54.613602 | 2025-03-19 22:57:54.613685 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-19 22:57:55.383355 | ubuntu-noble | changed 2025-03-19 22:57:55.393136 | 2025-03-19 22:57:55.393262 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-19 22:57:55.733339 | ubuntu-noble | ok 2025-03-19 22:57:55.743900 | 2025-03-19 22:57:55.744034 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-19 22:57:56.535676 | ubuntu-noble | changed 2025-03-19 22:57:56.546625 | 2025-03-19 22:57:56.546793 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-19 22:57:57.377184 | ubuntu-noble | changed 2025-03-19 22:57:57.387373 | 2025-03-19 22:57:57.387500 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-19 22:57:57.412093 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:57.422196 | 2025-03-19 22:57:57.422304 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-19 22:57:57.825714 | ubuntu-noble -> localhost | changed 2025-03-19 22:57:57.840069 | 2025-03-19 22:57:57.840164 | TASK [add-build-sshkey : Add back temp key] 2025-03-19 22:57:58.178638 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2076cc12e9a341d79100c96798811c05/work/2076cc12e9a341d79100c96798811c05_id_rsa (zuul-build-sshkey) 2025-03-19 22:57:58.178968 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.009488 2025-03-19 22:57:58.189676 | 2025-03-19 22:57:58.189781 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-19 22:57:58.667252 | ubuntu-noble | ok 2025-03-19 22:57:58.675095 | 2025-03-19 22:57:58.675186 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-19 22:57:58.709428 | ubuntu-noble | skipping: Conditional result was False 2025-03-19 22:57:58.727135 | 2025-03-19 22:57:58.727221 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-19 22:57:59.192431 | ubuntu-noble | ok 2025-03-19 22:57:59.220469 | 2025-03-19 22:57:59.220600 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-19 22:57:59.261741 | ubuntu-noble | ok 2025-03-19 22:57:59.268186 | 2025-03-19 22:57:59.268356 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-19 22:57:59.558171 | ubuntu-noble -> localhost | ok 2025-03-19 22:57:59.565704 | 2025-03-19 22:57:59.565817 | TASK [validate-host : Collect information about the host] 2025-03-19 22:58:00.781093 | ubuntu-noble | ok 2025-03-19 22:58:00.795057 | 2025-03-19 22:58:00.795234 | TASK [validate-host : Sanitize hostname] 2025-03-19 22:58:00.866176 | ubuntu-noble | ok 2025-03-19 22:58:00.872873 | 2025-03-19 22:58:00.872973 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-19 22:58:01.498159 | ubuntu-noble -> localhost | changed 2025-03-19 22:58:01.515535 | 2025-03-19 22:58:01.515700 | TASK [validate-host : Collect information about zuul worker] 2025-03-19 22:58:02.117493 | ubuntu-noble | ok 2025-03-19 22:58:02.123883 | 2025-03-19 22:58:02.123970 | TASK [validate-host : Write out all zuul information for each host] 2025-03-19 22:58:02.677678 | ubuntu-noble -> localhost | changed 2025-03-19 22:58:02.689339 | 2025-03-19 22:58:02.689429 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-19 22:58:03.026014 | ubuntu-noble | ok 2025-03-19 22:58:03.044287 | 2025-03-19 22:58:03.044472 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-19 22:58:04.393616 | ubuntu-noble | changed: 2025-03-19 22:58:04.393850 | ubuntu-noble | cd+++++++++ src/ 2025-03-19 22:58:04.393893 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-19 22:58:04.393927 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-19 22:58:04.393958 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-19 22:58:04.393979 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004225 2025-03-19 22:58:42.221307 | 2025-03-19 22:58:42.221424 | TASK [upload-logs : Upload logs to log server] 2025-03-19 22:58:42.823616 | localhost | Output suppressed because no_log was given 2025-03-19 22:58:42.829026 | 2025-03-19 22:58:42.829142 | LOOP [upload-logs : Compress console log and json output] 2025-03-19 22:58:42.904467 | localhost | skipping: Conditional result was False 2025-03-19 22:58:42.921933 | localhost | skipping: Conditional result was False 2025-03-19 22:58:42.929382 | 2025-03-19 22:58:42.929500 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-19 22:58:43.002715 | localhost | skipping: Conditional result was False 2025-03-19 22:58:43.003094 | 2025-03-19 22:58:43.005155 | localhost | skipping: Conditional result was False 2025-03-19 22:58:43.018608 | 2025-03-19 22:58:43.018753 | LOOP [upload-logs : Upload console log and json output]