2025-01-08 14:05:48.907864 | Job console starting... 2025-01-08 14:05:48.929227 | Updating repositories 2025-01-08 14:05:48.977223 | Preparing job workspace 2025-01-08 14:05:51.701040 | Running Ansible setup... 2025-01-08 14:05:58.219166 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 14:05:59.835971 | 2025-01-08 14:05:59.836315 | PLAY [Base pre] 2025-01-08 14:05:59.911414 | 2025-01-08 14:05:59.911585 | TASK [Setup log path fact] 2025-01-08 14:05:59.951173 | ubuntu-noble | ok 2025-01-08 14:06:00.023191 | 2025-01-08 14:06:00.023382 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 14:06:00.152594 | ubuntu-noble | ok 2025-01-08 14:06:00.171129 | 2025-01-08 14:06:00.171272 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 14:06:00.255729 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.263830 | 2025-01-08 14:06:00.263972 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 14:06:00.336677 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.345468 | 2025-01-08 14:06:00.345602 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 14:06:00.415193 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.423905 | 2025-01-08 14:06:00.424033 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 14:06:00.479308 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.488194 | 2025-01-08 14:06:00.488329 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 14:06:00.533250 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:00.560109 | 2025-01-08 14:06:00.560250 | TASK [emit-job-header : Print job information] 2025-01-08 14:06:00.662893 | # Job Information 2025-01-08 14:06:00.663071 | Ansible Version: 2.15.3 2025-01-08 14:06:00.663104 | Job: python-black 2025-01-08 14:06:00.663132 | Pipeline: check 2025-01-08 14:06:00.663152 | Executor: 7d211f194f6a 2025-01-08 14:06:00.663170 | Triggered by: https://github.com/osism/openstack-image-manager/pull/866 2025-01-08 14:06:00.663189 | Event ID: a429d420-cdc9-11ef-8ae6-04912797aca1 2025-01-08 14:06:00.675668 | 2025-01-08 14:06:00.675795 | LOOP [emit-job-header : Print node information] 2025-01-08 14:06:01.018968 | ubuntu-noble | ok: 2025-01-08 14:06:01.019232 | ubuntu-noble | # Node Information 2025-01-08 14:06:01.019271 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 14:06:01.019295 | ubuntu-noble | Hostname: ubuntu 2025-01-08 14:06:01.019316 | ubuntu-noble | Username: zuul 2025-01-08 14:06:01.019336 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 14:06:01.019391 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 14:06:01.019412 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 14:06:01.019431 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 14:06:01.019452 | ubuntu-noble | Interface IP: 81.163.193.177 2025-01-08 14:06:01.052326 | 2025-01-08 14:06:01.052500 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 14:06:01.814263 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:01.822331 | 2025-01-08 14:06:01.822459 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 14:06:03.321283 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:03.332447 | 2025-01-08 14:06:03.332535 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 14:06:03.783516 | ubuntu-noble -> localhost | ok 2025-01-08 14:06:03.789305 | 2025-01-08 14:06:03.789394 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 14:06:03.839325 | ubuntu-noble | ok 2025-01-08 14:06:03.869772 | ubuntu-noble | included: /var/lib/zuul/builds/86aeb96d22a04a36be1d01afbc992963/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 14:06:03.877486 | 2025-01-08 14:06:03.877566 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 14:06:04.956283 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 14:06:04.956491 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/86aeb96d22a04a36be1d01afbc992963/work/86aeb96d22a04a36be1d01afbc992963_id_rsa 2025-01-08 14:06:04.956527 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/86aeb96d22a04a36be1d01afbc992963/work/86aeb96d22a04a36be1d01afbc992963_id_rsa.pub 2025-01-08 14:06:04.956551 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 14:06:04.956574 | ubuntu-noble -> localhost | SHA256:1aXjNun4WxRY1WybDXAz35WSRqeRS5i9o+o5ZYIrBl0 zuul-build-sshkey 2025-01-08 14:06:04.956594 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 14:06:04.956618 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 14:06:04.956639 | ubuntu-noble -> localhost | | *+O+=| 2025-01-08 14:06:04.956659 | ubuntu-noble -> localhost | | + &==*| 2025-01-08 14:06:04.956678 | ubuntu-noble -> localhost | | . B.=o*| 2025-01-08 14:06:04.956697 | ubuntu-noble -> localhost | | E . . * +.| 2025-01-08 14:06:04.956715 | ubuntu-noble -> localhost | | . . S * o | 2025-01-08 14:06:04.956734 | ubuntu-noble -> localhost | | . . . . B o | 2025-01-08 14:06:04.956753 | ubuntu-noble -> localhost | | . . * . . | 2025-01-08 14:06:04.956772 | ubuntu-noble -> localhost | | o . o.. . | 2025-01-08 14:06:04.956791 | ubuntu-noble -> localhost | | . . .o. o. | 2025-01-08 14:06:04.956810 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 14:06:04.956857 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.449773 2025-01-08 14:06:04.964308 | 2025-01-08 14:06:04.964422 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 14:06:04.998579 | ubuntu-noble | ok 2025-01-08 14:06:05.009284 | ubuntu-noble | included: /var/lib/zuul/builds/86aeb96d22a04a36be1d01afbc992963/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 14:06:05.019278 | 2025-01-08 14:06:05.019384 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 14:06:05.043323 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:05.050644 | 2025-01-08 14:06:05.050743 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 14:06:05.779252 | ubuntu-noble | changed 2025-01-08 14:06:05.787731 | 2025-01-08 14:06:05.787828 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 14:06:06.111105 | ubuntu-noble | ok 2025-01-08 14:06:06.118326 | 2025-01-08 14:06:06.118600 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 14:06:06.840073 | ubuntu-noble | changed 2025-01-08 14:06:06.845831 | 2025-01-08 14:06:06.845906 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 14:06:07.539443 | ubuntu-noble | changed 2025-01-08 14:06:07.545412 | 2025-01-08 14:06:07.545490 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 14:06:07.572589 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:07.586761 | 2025-01-08 14:06:07.586848 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 14:06:08.016012 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:08.051513 | 2025-01-08 14:06:08.051601 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 14:06:08.401868 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/86aeb96d22a04a36be1d01afbc992963/work/86aeb96d22a04a36be1d01afbc992963_id_rsa (zuul-build-sshkey) 2025-01-08 14:06:08.402139 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015594 2025-01-08 14:06:08.409481 | 2025-01-08 14:06:08.409577 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 14:06:08.818578 | ubuntu-noble | ok 2025-01-08 14:06:08.830488 | 2025-01-08 14:06:08.830578 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 14:06:08.874751 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 14:06:08.889841 | 2025-01-08 14:06:08.889957 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 14:06:09.323166 | ubuntu-noble | ok 2025-01-08 14:06:09.349345 | 2025-01-08 14:06:09.349463 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 14:06:09.389971 | ubuntu-noble | ok 2025-01-08 14:06:09.395354 | 2025-01-08 14:06:09.395448 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 14:06:09.687760 | ubuntu-noble -> localhost | ok 2025-01-08 14:06:09.699482 | 2025-01-08 14:06:09.699583 | TASK [validate-host : Collect information about the host] 2025-01-08 14:06:10.877691 | ubuntu-noble | ok 2025-01-08 14:06:10.889715 | 2025-01-08 14:06:10.889812 | TASK [validate-host : Sanitize hostname] 2025-01-08 14:06:10.959617 | ubuntu-noble | ok 2025-01-08 14:06:10.965698 | 2025-01-08 14:06:10.965788 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 14:06:11.530475 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:11.537162 | 2025-01-08 14:06:11.537256 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 14:06:12.160438 | ubuntu-noble | ok 2025-01-08 14:06:12.175169 | 2025-01-08 14:06:12.175279 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 14:06:12.774324 | ubuntu-noble -> localhost | changed 2025-01-08 14:06:12.788964 | 2025-01-08 14:06:12.789078 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 14:06:13.104866 | ubuntu-noble | ok 2025-01-08 14:06:13.122565 | 2025-01-08 14:06:13.122671 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 14:06:14.088215 | ubuntu-noble | changed: 2025-01-08 14:06:14.088391 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 14:06:14.088427 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 14:06:14.088451 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 14:06:14.088471 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-01-08 14:06:14.088491 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005332 2025-01-08 14:06:46.232377 | 2025-01-08 14:06:46.232545 | TASK [upload-logs : Upload logs to log server] 2025-01-08 14:06:46.953633 | localhost | Output suppressed because no_log was given 2025-01-08 14:06:46.957081 | 2025-01-08 14:06:46.957188 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 14:06:47.028929 | localhost | skipping: Conditional result was False 2025-01-08 14:06:47.049326 | localhost | skipping: Conditional result was False 2025-01-08 14:06:47.063832 | 2025-01-08 14:06:47.064059 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 14:06:47.129899 | localhost | skipping: Conditional result was False 2025-01-08 14:06:47.130682 | 2025-01-08 14:06:47.142778 | localhost | skipping: Conditional result was False 2025-01-08 14:06:47.160707 | 2025-01-08 14:06:47.160928 | LOOP [upload-logs : Upload console log and json output]