2025-01-11 21:02:12.011496 | Job console starting... 2025-01-11 21:02:12.040889 | Updating repositories 2025-01-11 21:02:12.080757 | Preparing job workspace 2025-01-11 21:02:13.942194 | Running Ansible setup... 2025-01-11 21:02:19.611715 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-11 21:02:20.413402 | 2025-01-11 21:02:20.413572 | PLAY [Base pre] 2025-01-11 21:02:20.444820 | 2025-01-11 21:02:20.444979 | TASK [Setup log path fact] 2025-01-11 21:02:20.489125 | ubuntu-noble | ok 2025-01-11 21:02:20.513755 | 2025-01-11 21:02:20.513948 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-11 21:02:20.600247 | ubuntu-noble | ok 2025-01-11 21:02:20.607214 | 2025-01-11 21:02:20.607367 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-11 21:02:20.642373 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:20.649600 | 2025-01-11 21:02:20.649718 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-11 21:02:20.724985 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:20.733429 | 2025-01-11 21:02:20.733565 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-11 21:02:20.762335 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:20.770547 | 2025-01-11 21:02:20.770669 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-11 21:02:20.805216 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:20.812847 | 2025-01-11 21:02:20.812977 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-11 21:02:20.837539 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:20.854334 | 2025-01-11 21:02:20.854473 | TASK [emit-job-header : Print job information] 2025-01-11 21:02:20.936475 | # Job Information 2025-01-11 21:02:20.936662 | Ansible Version: 2.15.3 2025-01-11 21:02:20.936696 | Job: python-black 2025-01-11 21:02:20.936725 | Pipeline: check 2025-01-11 21:02:20.936746 | Executor: 7d211f194f6a 2025-01-11 21:02:20.936765 | Triggered by: https://github.com/osism/testbed/pull/2579 2025-01-11 21:02:20.936783 | Event ID: 4f3f6540-d05f-11ef-919a-27014f5fb658 2025-01-11 21:02:20.943254 | 2025-01-11 21:02:20.943394 | LOOP [emit-job-header : Print node information] 2025-01-11 21:02:21.097556 | ubuntu-noble | ok: 2025-01-11 21:02:21.097828 | ubuntu-noble | # Node Information 2025-01-11 21:02:21.097868 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-11 21:02:21.097892 | ubuntu-noble | Hostname: ubuntu 2025-01-11 21:02:21.097936 | ubuntu-noble | Username: zuul 2025-01-11 21:02:21.097957 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-11 21:02:21.097976 | ubuntu-noble | Provider: regiocloud-a 2025-01-11 21:02:21.097995 | ubuntu-noble | Label: ubuntu-noble 2025-01-11 21:02:21.098014 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-11 21:02:21.098035 | ubuntu-noble | Interface IP: 81.163.193.159 2025-01-11 21:02:21.115464 | 2025-01-11 21:02:21.115605 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-11 21:02:21.789004 | ubuntu-noble -> localhost | changed 2025-01-11 21:02:21.797646 | 2025-01-11 21:02:21.797780 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-11 21:02:22.952644 | ubuntu-noble -> localhost | changed 2025-01-11 21:02:22.988853 | 2025-01-11 21:02:22.988999 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-11 21:02:23.369112 | ubuntu-noble -> localhost | ok 2025-01-11 21:02:23.381180 | 2025-01-11 21:02:23.381311 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-11 21:02:23.433837 | ubuntu-noble | ok 2025-01-11 21:02:23.474097 | ubuntu-noble | included: /var/lib/zuul/builds/62d9272eab884af4bf31b95f6a26982e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-11 21:02:23.486071 | 2025-01-11 21:02:23.486212 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-11 21:02:24.949351 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-11 21:02:24.949624 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/62d9272eab884af4bf31b95f6a26982e/work/62d9272eab884af4bf31b95f6a26982e_id_rsa 2025-01-11 21:02:24.949665 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/62d9272eab884af4bf31b95f6a26982e/work/62d9272eab884af4bf31b95f6a26982e_id_rsa.pub 2025-01-11 21:02:24.949690 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-11 21:02:24.949713 | ubuntu-noble -> localhost | SHA256:+lVu0ND+uU8sz2MY7rMfiYKKbvf4QLFY/sbw2YxL7mQ zuul-build-sshkey 2025-01-11 21:02:24.949734 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-11 21:02:24.949758 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-11 21:02:24.949778 | ubuntu-noble -> localhost | | | 2025-01-11 21:02:24.949798 | ubuntu-noble -> localhost | | . | 2025-01-11 21:02:24.949817 | ubuntu-noble -> localhost | | o . . | 2025-01-11 21:02:24.949835 | ubuntu-noble -> localhost | | + o + | 2025-01-11 21:02:24.949854 | ubuntu-noble -> localhost | | . =S . + | 2025-01-11 21:02:24.949872 | ubuntu-noble -> localhost | | ..= *+ o.o.| 2025-01-11 21:02:24.949890 | ubuntu-noble -> localhost | | .. E.++.*oo| 2025-01-11 21:02:24.949940 | ubuntu-noble -> localhost | | ..oX....+ O.| 2025-01-11 21:02:24.949963 | ubuntu-noble -> localhost | | oo.o=* .o*o=| 2025-01-11 21:02:24.949983 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-11 21:02:24.950035 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.629204 2025-01-11 21:02:24.958055 | 2025-01-11 21:02:24.958250 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-11 21:02:24.991477 | ubuntu-noble | ok 2025-01-11 21:02:25.003813 | ubuntu-noble | included: /var/lib/zuul/builds/62d9272eab884af4bf31b95f6a26982e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-11 21:02:25.014633 | 2025-01-11 21:02:25.014746 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-11 21:02:25.039918 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:25.050236 | 2025-01-11 21:02:25.050379 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-11 21:02:26.052803 | ubuntu-noble | changed 2025-01-11 21:02:26.063643 | 2025-01-11 21:02:26.064150 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-11 21:02:26.390739 | ubuntu-noble | ok 2025-01-11 21:02:26.400437 | 2025-01-11 21:02:26.400555 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-11 21:02:27.136807 | ubuntu-noble | changed 2025-01-11 21:02:27.144859 | 2025-01-11 21:02:27.144977 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-11 21:02:27.876725 | ubuntu-noble | changed 2025-01-11 21:02:27.886727 | 2025-01-11 21:02:27.886849 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-11 21:02:27.922593 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:27.933702 | 2025-01-11 21:02:27.933833 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-11 21:02:28.796460 | ubuntu-noble -> localhost | changed 2025-01-11 21:02:28.955618 | 2025-01-11 21:02:28.955830 | TASK [add-build-sshkey : Add back temp key] 2025-01-11 21:02:29.532256 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/62d9272eab884af4bf31b95f6a26982e/work/62d9272eab884af4bf31b95f6a26982e_id_rsa (zuul-build-sshkey) 2025-01-11 21:02:29.532613 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.034520 2025-01-11 21:02:29.541157 | 2025-01-11 21:02:29.541281 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-11 21:02:29.955169 | ubuntu-noble | ok 2025-01-11 21:02:29.962098 | 2025-01-11 21:02:29.962218 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-11 21:02:30.023870 | ubuntu-noble | skipping: Conditional result was False 2025-01-11 21:02:30.048293 | 2025-01-11 21:02:30.048513 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-11 21:02:30.511575 | ubuntu-noble | ok 2025-01-11 21:02:30.536026 | 2025-01-11 21:02:30.536173 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-11 21:02:30.588664 | ubuntu-noble | ok 2025-01-11 21:02:30.595399 | 2025-01-11 21:02:30.595519 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-11 21:02:30.960112 | ubuntu-noble -> localhost | ok 2025-01-11 21:02:30.979992 | 2025-01-11 21:02:30.980106 | TASK [validate-host : Collect information about the host] 2025-01-11 21:02:32.255251 | ubuntu-noble | ok 2025-01-11 21:02:32.267900 | 2025-01-11 21:02:32.268030 | TASK [validate-host : Sanitize hostname] 2025-01-11 21:02:32.361149 | ubuntu-noble | ok 2025-01-11 21:02:32.368586 | 2025-01-11 21:02:32.368713 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-11 21:02:32.952571 | ubuntu-noble -> localhost | changed 2025-01-11 21:02:32.960003 | 2025-01-11 21:02:32.960133 | TASK [validate-host : Collect information about zuul worker] 2025-01-11 21:02:33.474197 | ubuntu-noble | ok 2025-01-11 21:02:33.480589 | 2025-01-11 21:02:33.480712 | TASK [validate-host : Write out all zuul information for each host] 2025-01-11 21:02:34.274804 | ubuntu-noble -> localhost | changed 2025-01-11 21:02:34.294210 | 2025-01-11 21:02:34.294406 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-11 21:02:34.600948 | ubuntu-noble | ok 2025-01-11 21:02:34.613158 | 2025-01-11 21:02:34.613369 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-11 21:02:36.150585 | ubuntu-noble | changed: 2025-01-11 21:02:36.150797 | ubuntu-noble | cd+++++++++ src/ 2025-01-11 21:02:36.150829 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-11 21:02:36.150852 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-11 21:02:36.150873 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-01-11 21:02:36.150893 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.015390 2025-01-11 21:03:10.469192 | 2025-01-11 21:03:10.469353 | TASK [upload-logs : Upload logs to log server] 2025-01-11 21:03:11.048833 | localhost | Output suppressed because no_log was given 2025-01-11 21:03:11.052166 | 2025-01-11 21:03:11.052271 | LOOP [upload-logs : Compress console log and json output] 2025-01-11 21:03:11.137010 | localhost | skipping: Conditional result was False 2025-01-11 21:03:11.138152 | localhost | skipping: Conditional result was False 2025-01-11 21:03:11.152553 | 2025-01-11 21:03:11.152668 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-11 21:03:11.217433 | localhost | skipping: Conditional result was False 2025-01-11 21:03:11.217989 | 2025-01-11 21:03:11.231146 | localhost | skipping: Conditional result was False 2025-01-11 21:03:11.242176 | 2025-01-11 21:03:11.242293 | LOOP [upload-logs : Upload console log and json output]