2025-03-14 19:45:16.107040 | Job console starting... 2025-03-14 19:45:16.124714 | Updating repositories 2025-03-14 19:45:16.181384 | Preparing job workspace 2025-03-14 19:45:17.799952 | Running Ansible setup... 2025-03-14 19:45:22.803810 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-14 19:45:23.509707 | 2025-03-14 19:45:23.509826 | PLAY [Base pre] 2025-03-14 19:45:23.538375 | 2025-03-14 19:45:23.538488 | TASK [Setup log path fact] 2025-03-14 19:45:23.578485 | ubuntu-noble | ok 2025-03-14 19:45:23.599853 | 2025-03-14 19:45:23.599966 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-14 19:45:23.640238 | ubuntu-noble | ok 2025-03-14 19:45:23.648640 | 2025-03-14 19:45:23.648731 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-14 19:45:23.682521 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:23.690978 | 2025-03-14 19:45:23.691081 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-14 19:45:23.725153 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:23.732974 | 2025-03-14 19:45:23.733086 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-14 19:45:23.758717 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:23.765412 | 2025-03-14 19:45:23.765503 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-14 19:45:23.779319 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:23.786070 | 2025-03-14 19:45:23.786157 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-14 19:45:23.821159 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:23.837662 | 2025-03-14 19:45:23.837761 | TASK [emit-job-header : Print job information] 2025-03-14 19:45:23.888159 | # Job Information 2025-03-14 19:45:23.888304 | Ansible Version: 2.15.3 2025-03-14 19:45:23.888335 | Job: python-black 2025-03-14 19:45:23.888366 | Pipeline: check 2025-03-14 19:45:23.888404 | Executor: 7d211f194f6a 2025-03-14 19:45:23.888424 | Triggered by: https://github.com/osism/testbed/pull/2635 2025-03-14 19:45:23.888442 | Event ID: d5af82d0-010c-11f0-80c5-f5333e2728c3 2025-03-14 19:45:23.894480 | 2025-03-14 19:45:23.894564 | LOOP [emit-job-header : Print node information] 2025-03-14 19:45:24.049900 | ubuntu-noble | ok: 2025-03-14 19:45:24.050131 | ubuntu-noble | # Node Information 2025-03-14 19:45:24.050168 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-14 19:45:24.050193 | ubuntu-noble | Hostname: ubuntu 2025-03-14 19:45:24.050214 | ubuntu-noble | Username: zuul 2025-03-14 19:45:24.050235 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-14 19:45:24.050255 | ubuntu-noble | Provider: regiocloud-a 2025-03-14 19:45:24.050274 | ubuntu-noble | Label: ubuntu-noble 2025-03-14 19:45:24.050294 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-14 19:45:24.050314 | ubuntu-noble | Interface IP: 81.163.192.54 2025-03-14 19:45:24.072784 | 2025-03-14 19:45:24.072880 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-14 19:45:24.661848 | ubuntu-noble -> localhost | changed 2025-03-14 19:45:24.669404 | 2025-03-14 19:45:24.669489 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-14 19:45:25.682495 | ubuntu-noble -> localhost | changed 2025-03-14 19:45:25.693811 | 2025-03-14 19:45:25.693884 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-14 19:45:25.930921 | ubuntu-noble -> localhost | ok 2025-03-14 19:45:25.939193 | 2025-03-14 19:45:25.939285 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-14 19:45:25.970422 | ubuntu-noble | ok 2025-03-14 19:45:25.986131 | ubuntu-noble | included: /var/lib/zuul/builds/bbb5457c974042458df254252dc62e47/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-14 19:45:25.993481 | 2025-03-14 19:45:25.993560 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-14 19:45:26.593691 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-14 19:45:26.594197 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/bbb5457c974042458df254252dc62e47/work/bbb5457c974042458df254252dc62e47_id_rsa 2025-03-14 19:45:26.594291 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/bbb5457c974042458df254252dc62e47/work/bbb5457c974042458df254252dc62e47_id_rsa.pub 2025-03-14 19:45:26.594353 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-14 19:45:26.594413 | ubuntu-noble -> localhost | SHA256:d2p6YTrdhOmBYOnDYrGtfFUEMMUT1q+rDFSsW9aW3F0 zuul-build-sshkey 2025-03-14 19:45:26.594468 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-14 19:45:26.594529 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-14 19:45:26.594584 | ubuntu-noble -> localhost | | o+=+ | 2025-03-14 19:45:26.594695 | ubuntu-noble -> localhost | | +o o | 2025-03-14 19:45:26.594754 | ubuntu-noble -> localhost | | .oo . E| 2025-03-14 19:45:26.594804 | ubuntu-noble -> localhost | | . +o o.o.. . | 2025-03-14 19:45:26.594847 | ubuntu-noble -> localhost | | Bo.So*+o . | 2025-03-14 19:45:26.594887 | ubuntu-noble -> localhost | | +.=+ooBo. | 2025-03-14 19:45:26.594927 | ubuntu-noble -> localhost | | o ooo =o* | 2025-03-14 19:45:26.594969 | ubuntu-noble -> localhost | | o .ooo= . | 2025-03-14 19:45:26.595010 | ubuntu-noble -> localhost | | . ++ | 2025-03-14 19:45:26.595048 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-14 19:45:26.595146 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.180939 2025-03-14 19:45:26.609706 | 2025-03-14 19:45:26.609809 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-14 19:45:26.640982 | ubuntu-noble | ok 2025-03-14 19:45:26.650788 | ubuntu-noble | included: /var/lib/zuul/builds/bbb5457c974042458df254252dc62e47/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-14 19:45:26.658394 | 2025-03-14 19:45:26.658460 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-14 19:45:26.681250 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:26.686804 | 2025-03-14 19:45:26.686872 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-14 19:45:27.478786 | ubuntu-noble | changed 2025-03-14 19:45:27.488915 | 2025-03-14 19:45:27.489042 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-14 19:45:27.826131 | ubuntu-noble | ok 2025-03-14 19:45:27.834619 | 2025-03-14 19:45:27.834818 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-14 19:45:28.623918 | ubuntu-noble | changed 2025-03-14 19:45:28.634553 | 2025-03-14 19:45:28.634689 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-14 19:45:29.347759 | ubuntu-noble | changed 2025-03-14 19:45:29.355323 | 2025-03-14 19:45:29.355416 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-14 19:45:29.379342 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:29.386050 | 2025-03-14 19:45:29.386135 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-14 19:45:29.765242 | ubuntu-noble -> localhost | changed 2025-03-14 19:45:29.780121 | 2025-03-14 19:45:29.780259 | TASK [add-build-sshkey : Add back temp key] 2025-03-14 19:45:30.097994 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/bbb5457c974042458df254252dc62e47/work/bbb5457c974042458df254252dc62e47_id_rsa (zuul-build-sshkey) 2025-03-14 19:45:30.098194 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.012008 2025-03-14 19:45:30.105729 | 2025-03-14 19:45:30.105821 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-14 19:45:30.544238 | ubuntu-noble | ok 2025-03-14 19:45:30.550468 | 2025-03-14 19:45:30.550558 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-14 19:45:30.574259 | ubuntu-noble | skipping: Conditional result was False 2025-03-14 19:45:30.587138 | 2025-03-14 19:45:30.587244 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-14 19:45:31.037289 | ubuntu-noble | ok 2025-03-14 19:45:31.049427 | 2025-03-14 19:45:31.049509 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-14 19:45:31.078530 | ubuntu-noble | ok 2025-03-14 19:45:31.083818 | 2025-03-14 19:45:31.083892 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-14 19:45:31.333347 | ubuntu-noble -> localhost | ok 2025-03-14 19:45:31.346791 | 2025-03-14 19:45:31.346914 | TASK [validate-host : Collect information about the host] 2025-03-14 19:45:32.632744 | ubuntu-noble | ok 2025-03-14 19:45:32.646144 | 2025-03-14 19:45:32.646250 | TASK [validate-host : Sanitize hostname] 2025-03-14 19:45:32.718211 | ubuntu-noble | ok 2025-03-14 19:45:32.725462 | 2025-03-14 19:45:32.725566 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-14 19:45:33.313336 | ubuntu-noble -> localhost | changed 2025-03-14 19:45:33.319903 | 2025-03-14 19:45:33.319992 | TASK [validate-host : Collect information about zuul worker] 2025-03-14 19:45:33.818220 | ubuntu-noble | ok 2025-03-14 19:45:33.824589 | 2025-03-14 19:45:33.824714 | TASK [validate-host : Write out all zuul information for each host] 2025-03-14 19:45:34.328082 | ubuntu-noble -> localhost | changed 2025-03-14 19:45:34.340470 | 2025-03-14 19:45:34.340555 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-14 19:45:34.666913 | ubuntu-noble | ok 2025-03-14 19:45:34.678392 | 2025-03-14 19:45:34.678488 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-14 19:45:36.013053 | ubuntu-noble | changed: 2025-03-14 19:45:36.013261 | ubuntu-noble | cd+++++++++ src/ 2025-03-14 19:45:36.013296 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-14 19:45:36.013320 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-14 19:45:36.013342 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-14 19:45:36.013362 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.013312 2025-03-14 19:46:13.730006 | 2025-03-14 19:46:13.730104 | TASK [upload-logs : Upload logs to log server] 2025-03-14 19:46:14.278899 | localhost | Output suppressed because no_log was given 2025-03-14 19:46:14.282207 | 2025-03-14 19:46:14.282375 | LOOP [upload-logs : Compress console log and json output] 2025-03-14 19:46:14.350078 | localhost | skipping: Conditional result was False 2025-03-14 19:46:14.371232 | localhost | skipping: Conditional result was False 2025-03-14 19:46:14.381507 | 2025-03-14 19:46:14.381612 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-14 19:46:14.452507 | localhost | skipping: Conditional result was False 2025-03-14 19:46:14.452784 | 2025-03-14 19:46:14.462291 | localhost | skipping: Conditional result was False 2025-03-14 19:46:14.470250 | 2025-03-14 19:46:14.470347 | LOOP [upload-logs : Upload console log and json output]