2025-01-27 18:31:56.844411 | Job console starting... 2025-01-27 18:31:56.889082 | Updating repositories 2025-01-27 18:31:56.976220 | Preparing job workspace 2025-01-27 18:32:00.458895 | Running Ansible setup... 2025-01-27 18:32:07.566897 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-27 18:32:08.639503 | 2025-01-27 18:32:08.639626 | PLAY [Base pre] 2025-01-27 18:32:08.670392 | 2025-01-27 18:32:08.670510 | TASK [Setup log path fact] 2025-01-27 18:32:08.736955 | ubuntu-noble | ok 2025-01-27 18:32:08.794347 | 2025-01-27 18:32:08.794479 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-27 18:32:08.844913 | ubuntu-noble | ok 2025-01-27 18:32:08.851303 | 2025-01-27 18:32:08.851399 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-27 18:32:08.905569 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:08.917536 | 2025-01-27 18:32:08.917766 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-27 18:32:08.991249 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:08.999031 | 2025-01-27 18:32:08.999139 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-27 18:32:09.043004 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:09.050302 | 2025-01-27 18:32:09.050407 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-27 18:32:09.084168 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:09.091593 | 2025-01-27 18:32:09.091697 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-27 18:32:09.115383 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:09.141774 | 2025-01-27 18:32:09.141888 | TASK [emit-job-header : Print job information] 2025-01-27 18:32:09.212542 | # Job Information 2025-01-27 18:32:09.212700 | Ansible Version: 2.15.3 2025-01-27 18:32:09.212732 | Job: python-black 2025-01-27 18:32:09.212760 | Pipeline: check 2025-01-27 18:32:09.212781 | Executor: 7d211f194f6a 2025-01-27 18:32:09.212800 | Triggered by: https://github.com/osism/openstack-image-manager/pull/873 2025-01-27 18:32:09.212819 | Event ID: f7d63060-dcdc-11ef-9eb2-4f89d75c0d57 2025-01-27 18:32:09.225853 | 2025-01-27 18:32:09.225962 | LOOP [emit-job-header : Print node information] 2025-01-27 18:32:09.439427 | ubuntu-noble | ok: 2025-01-27 18:32:09.439613 | ubuntu-noble | # Node Information 2025-01-27 18:32:09.439641 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-27 18:32:09.439661 | ubuntu-noble | Hostname: ubuntu 2025-01-27 18:32:09.439678 | ubuntu-noble | Username: zuul 2025-01-27 18:32:09.439695 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-27 18:32:09.439712 | ubuntu-noble | Provider: regiocloud-a 2025-01-27 18:32:09.439728 | ubuntu-noble | Label: ubuntu-noble 2025-01-27 18:32:09.439744 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-27 18:32:09.439761 | ubuntu-noble | Interface IP: 81.163.192.226 2025-01-27 18:32:09.456123 | 2025-01-27 18:32:09.456226 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-27 18:32:10.228489 | ubuntu-noble -> localhost | changed 2025-01-27 18:32:10.236159 | 2025-01-27 18:32:10.236252 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-27 18:32:11.648226 | ubuntu-noble -> localhost | changed 2025-01-27 18:32:11.662038 | 2025-01-27 18:32:11.662139 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-27 18:32:11.939808 | ubuntu-noble -> localhost | ok 2025-01-27 18:32:11.947163 | 2025-01-27 18:32:11.947262 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-27 18:32:11.994808 | ubuntu-noble | ok 2025-01-27 18:32:12.038242 | ubuntu-noble | included: /var/lib/zuul/builds/dc6084ae196041f4b5b07d84d360bfd0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-27 18:32:12.054888 | 2025-01-27 18:32:12.055143 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-27 18:32:13.308401 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-27 18:32:13.308558 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/dc6084ae196041f4b5b07d84d360bfd0/work/dc6084ae196041f4b5b07d84d360bfd0_id_rsa 2025-01-27 18:32:13.308587 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/dc6084ae196041f4b5b07d84d360bfd0/work/dc6084ae196041f4b5b07d84d360bfd0_id_rsa.pub 2025-01-27 18:32:13.308608 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-27 18:32:13.308626 | ubuntu-noble -> localhost | SHA256:iLJL6pX0yas1wpY+U2UTwmMdt9ty5VcscqSKQJ/Vsqo zuul-build-sshkey 2025-01-27 18:32:13.308644 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-27 18:32:13.308664 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-27 18:32:13.308682 | ubuntu-noble -> localhost | | . o..... . | 2025-01-27 18:32:13.308698 | ubuntu-noble -> localhost | | * +.oo .o . | 2025-01-27 18:32:13.308714 | ubuntu-noble -> localhost | | . + +. oo.o o| 2025-01-27 18:32:13.308730 | ubuntu-noble -> localhost | | . * .+.oo ..| 2025-01-27 18:32:13.308746 | ubuntu-noble -> localhost | | ... + S+.o . . | 2025-01-27 18:32:13.308761 | ubuntu-noble -> localhost | | oo=.. . o . | 2025-01-27 18:32:13.308776 | ubuntu-noble -> localhost | | oB.* . | 2025-01-27 18:32:13.308793 | ubuntu-noble -> localhost | | o++o E | 2025-01-27 18:32:13.308809 | ubuntu-noble -> localhost | |o..o+. | 2025-01-27 18:32:13.308825 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-27 18:32:13.308861 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.430090 2025-01-27 18:32:13.314868 | 2025-01-27 18:32:13.314946 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-27 18:32:13.340869 | ubuntu-noble | ok 2025-01-27 18:32:13.349517 | ubuntu-noble | included: /var/lib/zuul/builds/dc6084ae196041f4b5b07d84d360bfd0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-27 18:32:13.357196 | 2025-01-27 18:32:13.357266 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-27 18:32:13.382711 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:13.388513 | 2025-01-27 18:32:13.388586 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-27 18:32:14.065415 | ubuntu-noble | changed 2025-01-27 18:32:14.075062 | 2025-01-27 18:32:14.075180 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-27 18:32:14.374825 | ubuntu-noble | ok 2025-01-27 18:32:14.383891 | 2025-01-27 18:32:14.384022 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-27 18:32:15.059400 | ubuntu-noble | changed 2025-01-27 18:32:15.067341 | 2025-01-27 18:32:15.067490 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-27 18:32:15.741229 | ubuntu-noble | changed 2025-01-27 18:32:15.749692 | 2025-01-27 18:32:15.749792 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-27 18:32:15.784062 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:15.803087 | 2025-01-27 18:32:15.803214 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-27 18:32:16.282874 | ubuntu-noble -> localhost | changed 2025-01-27 18:32:16.304157 | 2025-01-27 18:32:16.304412 | TASK [add-build-sshkey : Add back temp key] 2025-01-27 18:32:16.978406 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/dc6084ae196041f4b5b07d84d360bfd0/work/dc6084ae196041f4b5b07d84d360bfd0_id_rsa (zuul-build-sshkey) 2025-01-27 18:32:16.978564 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.024253 2025-01-27 18:32:16.985472 | 2025-01-27 18:32:16.985566 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-27 18:32:17.397730 | ubuntu-noble | ok 2025-01-27 18:32:17.407310 | 2025-01-27 18:32:17.407441 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-27 18:32:17.470265 | ubuntu-noble | skipping: Conditional result was False 2025-01-27 18:32:17.484138 | 2025-01-27 18:32:17.484249 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-27 18:32:17.894033 | ubuntu-noble | ok 2025-01-27 18:32:17.906992 | 2025-01-27 18:32:17.907089 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-27 18:32:17.936704 | ubuntu-noble | ok 2025-01-27 18:32:17.943795 | 2025-01-27 18:32:17.943886 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-27 18:32:18.329137 | ubuntu-noble -> localhost | ok 2025-01-27 18:32:18.349051 | 2025-01-27 18:32:18.349152 | TASK [validate-host : Collect information about the host] 2025-01-27 18:32:19.749034 | ubuntu-noble | ok 2025-01-27 18:32:19.810780 | 2025-01-27 18:32:19.811015 | TASK [validate-host : Sanitize hostname] 2025-01-27 18:32:19.901418 | ubuntu-noble | ok 2025-01-27 18:32:19.911463 | 2025-01-27 18:32:19.911601 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-27 18:32:20.855094 | ubuntu-noble -> localhost | changed 2025-01-27 18:32:20.863327 | 2025-01-27 18:32:20.863463 | TASK [validate-host : Collect information about zuul worker] 2025-01-27 18:32:21.428317 | ubuntu-noble | ok 2025-01-27 18:32:21.435426 | 2025-01-27 18:32:21.435555 | TASK [validate-host : Write out all zuul information for each host] 2025-01-27 18:32:22.107344 | ubuntu-noble -> localhost | changed 2025-01-27 18:32:22.129874 | 2025-01-27 18:32:22.130068 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-27 18:32:22.444859 | ubuntu-noble | ok 2025-01-27 18:32:22.452160 | 2025-01-27 18:32:22.452282 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-27 18:32:24.084086 | ubuntu-noble | changed: 2025-01-27 18:32:24.084289 | ubuntu-noble | cd+++++++++ src/ 2025-01-27 18:32:24.084329 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-27 18:32:24.084358 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-27 18:32:24.084400 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-27 18:32:24.084428 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007387 2025-01-27 18:32:56.276532 | 2025-01-27 18:32:56.276660 | TASK [upload-logs : Upload logs to log server] 2025-01-27 18:32:56.855743 | localhost | Output suppressed because no_log was given 2025-01-27 18:32:56.859169 | 2025-01-27 18:32:56.859287 | LOOP [upload-logs : Compress console log and json output] 2025-01-27 18:32:56.934625 | localhost | skipping: Conditional result was False 2025-01-27 18:32:56.952870 | localhost | skipping: Conditional result was False 2025-01-27 18:32:56.967689 | 2025-01-27 18:32:56.967901 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-27 18:32:57.047178 | localhost | skipping: Conditional result was False 2025-01-27 18:32:57.047493 | 2025-01-27 18:32:57.077643 | localhost | skipping: Conditional result was False 2025-01-27 18:32:57.092316 | 2025-01-27 18:32:57.092460 | LOOP [upload-logs : Upload console log and json output]