2025-03-02 00:16:36.988020 | Job console starting... 2025-03-02 00:16:37.016019 | Updating repositories 2025-03-02 00:16:37.096002 | Preparing job workspace 2025-03-02 00:16:40.314040 | Running Ansible setup... 2025-03-02 00:16:46.093608 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-02 00:16:47.053366 | 2025-03-02 00:16:47.053481 | PLAY [Base pre] 2025-03-02 00:16:47.076232 | 2025-03-02 00:16:47.076326 | TASK [Setup log path fact] 2025-03-02 00:16:47.145879 | ubuntu-noble | ok 2025-03-02 00:16:47.175470 | 2025-03-02 00:16:47.175602 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-02 00:16:47.214792 | ubuntu-noble | ok 2025-03-02 00:16:47.228383 | 2025-03-02 00:16:47.228817 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-02 00:16:47.282843 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:47.288877 | 2025-03-02 00:16:47.288967 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-02 00:16:47.383906 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:47.389971 | 2025-03-02 00:16:47.390065 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-02 00:16:47.434315 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:47.440382 | 2025-03-02 00:16:47.440500 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-02 00:16:47.484975 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:47.490974 | 2025-03-02 00:16:47.491067 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-02 00:16:47.545966 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:47.557933 | 2025-03-02 00:16:47.558014 | TASK [emit-job-header : Print job information] 2025-03-02 00:16:47.661409 | # Job Information 2025-03-02 00:16:47.661541 | Ansible Version: 2.15.3 2025-03-02 00:16:47.661567 | Job: python-black 2025-03-02 00:16:47.661590 | Pipeline: check 2025-03-02 00:16:47.661607 | Executor: 7d211f194f6a 2025-03-02 00:16:47.661623 | Triggered by: https://github.com/osism/openstack-image-manager/pull/850 2025-03-02 00:16:47.661660 | Event ID: 95199280-f6fb-11ef-86e9-66272a0f6c1b 2025-03-02 00:16:47.666624 | 2025-03-02 00:16:47.666721 | LOOP [emit-job-header : Print node information] 2025-03-02 00:16:47.956145 | ubuntu-noble | ok: 2025-03-02 00:16:47.956288 | ubuntu-noble | # Node Information 2025-03-02 00:16:47.956316 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-02 00:16:47.956336 | ubuntu-noble | Hostname: ubuntu 2025-03-02 00:16:47.956353 | ubuntu-noble | Username: zuul 2025-03-02 00:16:47.956370 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-02 00:16:47.956399 | ubuntu-noble | Provider: regiocloud-a 2025-03-02 00:16:47.956417 | ubuntu-noble | Label: ubuntu-noble 2025-03-02 00:16:47.956433 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-02 00:16:47.956450 | ubuntu-noble | Interface IP: 81.163.193.53 2025-03-02 00:16:47.974541 | 2025-03-02 00:16:47.974662 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-02 00:16:48.682971 | ubuntu-noble -> localhost | changed 2025-03-02 00:16:48.689642 | 2025-03-02 00:16:48.689718 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-02 00:16:50.137504 | ubuntu-noble -> localhost | changed 2025-03-02 00:16:50.153113 | 2025-03-02 00:16:50.153202 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-02 00:16:50.669716 | ubuntu-noble -> localhost | ok 2025-03-02 00:16:50.675741 | 2025-03-02 00:16:50.675822 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-02 00:16:50.714059 | ubuntu-noble | ok 2025-03-02 00:16:50.727098 | ubuntu-noble | included: /var/lib/zuul/builds/0ad6acc14a304e8f953259aec9a5bb03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-02 00:16:50.734655 | 2025-03-02 00:16:50.734739 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-02 00:16:51.628281 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-02 00:16:51.628462 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/0ad6acc14a304e8f953259aec9a5bb03/work/0ad6acc14a304e8f953259aec9a5bb03_id_rsa 2025-03-02 00:16:51.628493 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/0ad6acc14a304e8f953259aec9a5bb03/work/0ad6acc14a304e8f953259aec9a5bb03_id_rsa.pub 2025-03-02 00:16:51.628515 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-02 00:16:51.628534 | ubuntu-noble -> localhost | SHA256:3LMeadIJ7E1eAQTX57TuQ7LQArML0wEPGGrfGBn5R4c zuul-build-sshkey 2025-03-02 00:16:51.628552 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-02 00:16:51.628573 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-02 00:16:51.628590 | ubuntu-noble -> localhost | | o+ .++. | 2025-03-02 00:16:51.628607 | ubuntu-noble -> localhost | | .ooo E.... o | 2025-03-02 00:16:51.628624 | ubuntu-noble -> localhost | | o o. = . .+ . | 2025-03-02 00:16:51.628651 | ubuntu-noble -> localhost | | . . +.o*. .o | 2025-03-02 00:16:51.628668 | ubuntu-noble -> localhost | | o .oS=+... | 2025-03-02 00:16:51.628684 | ubuntu-noble -> localhost | | o.o*o*o o | 2025-03-02 00:16:51.628700 | ubuntu-noble -> localhost | | oo.Xo = | 2025-03-02 00:16:51.628716 | ubuntu-noble -> localhost | | .+ .. o | 2025-03-02 00:16:51.628732 | ubuntu-noble -> localhost | | . . | 2025-03-02 00:16:51.628748 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-02 00:16:51.628785 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.379023 2025-03-02 00:16:51.635294 | 2025-03-02 00:16:51.635377 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-02 00:16:51.663703 | ubuntu-noble | ok 2025-03-02 00:16:51.673024 | ubuntu-noble | included: /var/lib/zuul/builds/0ad6acc14a304e8f953259aec9a5bb03/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-02 00:16:51.684295 | 2025-03-02 00:16:51.684363 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-02 00:16:51.698791 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:51.706033 | 2025-03-02 00:16:51.706117 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-02 00:16:52.538465 | ubuntu-noble | changed 2025-03-02 00:16:52.557495 | 2025-03-02 00:16:52.557698 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-02 00:16:52.878025 | ubuntu-noble | ok 2025-03-02 00:16:52.890432 | 2025-03-02 00:16:52.890534 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-02 00:16:53.620671 | ubuntu-noble | changed 2025-03-02 00:16:53.626962 | 2025-03-02 00:16:53.627043 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-02 00:16:54.303766 | ubuntu-noble | changed 2025-03-02 00:16:54.311106 | 2025-03-02 00:16:54.311197 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-02 00:16:54.335117 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:54.342122 | 2025-03-02 00:16:54.342208 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-02 00:16:54.962484 | ubuntu-noble -> localhost | changed 2025-03-02 00:16:54.986033 | 2025-03-02 00:16:54.986132 | TASK [add-build-sshkey : Add back temp key] 2025-03-02 00:16:55.349574 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/0ad6acc14a304e8f953259aec9a5bb03/work/0ad6acc14a304e8f953259aec9a5bb03_id_rsa (zuul-build-sshkey) 2025-03-02 00:16:55.349715 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.026156 2025-03-02 00:16:55.355477 | 2025-03-02 00:16:55.355546 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-02 00:16:55.748363 | ubuntu-noble | ok 2025-03-02 00:16:55.753849 | 2025-03-02 00:16:55.753936 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-02 00:16:55.777241 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 00:16:55.788089 | 2025-03-02 00:16:55.788176 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-02 00:16:56.216312 | ubuntu-noble | ok 2025-03-02 00:16:56.231961 | 2025-03-02 00:16:56.232055 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-02 00:16:56.275985 | ubuntu-noble | ok 2025-03-02 00:16:56.291333 | 2025-03-02 00:16:56.291442 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-02 00:16:56.538783 | ubuntu-noble -> localhost | ok 2025-03-02 00:16:56.550777 | 2025-03-02 00:16:56.550888 | TASK [validate-host : Collect information about the host] 2025-03-02 00:16:57.765709 | ubuntu-noble | ok 2025-03-02 00:16:57.777804 | 2025-03-02 00:16:57.777898 | TASK [validate-host : Sanitize hostname] 2025-03-02 00:16:57.842935 | ubuntu-noble | ok 2025-03-02 00:16:57.847930 | 2025-03-02 00:16:57.848013 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-02 00:16:58.495963 | ubuntu-noble -> localhost | changed 2025-03-02 00:16:58.504473 | 2025-03-02 00:16:58.504560 | TASK [validate-host : Collect information about zuul worker] 2025-03-02 00:16:58.952098 | ubuntu-noble | ok 2025-03-02 00:16:58.960853 | 2025-03-02 00:16:58.960946 | TASK [validate-host : Write out all zuul information for each host] 2025-03-02 00:16:59.960665 | ubuntu-noble -> localhost | changed 2025-03-02 00:16:59.970857 | 2025-03-02 00:16:59.970944 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-02 00:17:00.322377 | ubuntu-noble | ok 2025-03-02 00:17:00.327816 | 2025-03-02 00:17:00.327900 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-02 00:17:01.327663 | ubuntu-noble | changed: 2025-03-02 00:17:01.327821 | ubuntu-noble | cd+++++++++ src/ 2025-03-02 00:17:01.327852 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-02 00:17:01.327876 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-02 00:17:01.327898 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2025-03-02 00:17:01.327918 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.006576 2025-03-02 00:17:40.889040 | 2025-03-02 00:17:40.889190 | TASK [upload-logs : Upload logs to log server] 2025-03-02 00:17:41.482101 | localhost | Output suppressed because no_log was given 2025-03-02 00:17:41.485700 | 2025-03-02 00:17:41.485812 | LOOP [upload-logs : Compress console log and json output] 2025-03-02 00:17:41.555771 | localhost | skipping: Conditional result was False 2025-03-02 00:17:41.573397 | localhost | skipping: Conditional result was False 2025-03-02 00:17:41.590986 | 2025-03-02 00:17:41.591167 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-02 00:17:41.666318 | localhost | skipping: Conditional result was False 2025-03-02 00:17:41.666981 | 2025-03-02 00:17:41.678618 | localhost | skipping: Conditional result was False 2025-03-02 00:17:41.690342 | 2025-03-02 00:17:41.690584 | LOOP [upload-logs : Upload console log and json output]