2025-02-17 18:40:14.643370 | Job console starting... 2025-02-17 18:40:14.689924 | Updating repositories 2025-02-17 18:40:14.726369 | Preparing job workspace 2025-02-17 18:40:16.420195 | Running Ansible setup... 2025-02-17 18:40:21.589606 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-17 18:40:22.347330 | 2025-02-17 18:40:22.347503 | PLAY [Base pre] 2025-02-17 18:40:22.377815 | 2025-02-17 18:40:22.377974 | TASK [Setup log path fact] 2025-02-17 18:40:22.411244 | ubuntu-noble | ok 2025-02-17 18:40:22.436935 | 2025-02-17 18:40:22.437113 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-17 18:40:22.488547 | ubuntu-noble | ok 2025-02-17 18:40:22.496186 | 2025-02-17 18:40:22.496315 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-17 18:40:22.533044 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:22.540500 | 2025-02-17 18:40:22.540634 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-17 18:40:22.607840 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:22.615658 | 2025-02-17 18:40:22.615794 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-17 18:40:22.643160 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:22.650699 | 2025-02-17 18:40:22.650827 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-17 18:40:22.675300 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:22.682857 | 2025-02-17 18:40:22.683023 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-17 18:40:22.707791 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:22.735975 | 2025-02-17 18:40:22.736153 | TASK [emit-job-header : Print job information] 2025-02-17 18:40:22.799822 | # Job Information 2025-02-17 18:40:22.800030 | Ansible Version: 2.15.3 2025-02-17 18:40:22.800065 | Job: python-black 2025-02-17 18:40:22.800094 | Pipeline: check 2025-02-17 18:40:22.800115 | Executor: 7d211f194f6a 2025-02-17 18:40:22.800134 | Triggered by: https://github.com/osism/testbed/pull/2610 2025-02-17 18:40:22.800153 | Event ID: 9b4ba770-ed5e-11ef-9c3d-7b64a049743b 2025-02-17 18:40:22.806682 | 2025-02-17 18:40:22.806806 | LOOP [emit-job-header : Print node information] 2025-02-17 18:40:22.952222 | ubuntu-noble | ok: 2025-02-17 18:40:22.952519 | ubuntu-noble | # Node Information 2025-02-17 18:40:22.952560 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-17 18:40:22.952585 | ubuntu-noble | Hostname: ubuntu 2025-02-17 18:40:22.952606 | ubuntu-noble | Username: zuul 2025-02-17 18:40:22.952626 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-17 18:40:22.952646 | ubuntu-noble | Provider: regiocloud-a 2025-02-17 18:40:22.952666 | ubuntu-noble | Label: ubuntu-noble 2025-02-17 18:40:22.952684 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-17 18:40:22.952705 | ubuntu-noble | Interface IP: 81.163.193.103 2025-02-17 18:40:22.969798 | 2025-02-17 18:40:22.969944 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-17 18:40:23.500083 | ubuntu-noble -> localhost | changed 2025-02-17 18:40:23.509870 | 2025-02-17 18:40:23.510010 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-17 18:40:24.681356 | ubuntu-noble -> localhost | changed 2025-02-17 18:40:24.704843 | 2025-02-17 18:40:24.704977 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-17 18:40:25.025660 | ubuntu-noble -> localhost | ok 2025-02-17 18:40:25.035459 | 2025-02-17 18:40:25.035585 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-17 18:40:25.078182 | ubuntu-noble | ok 2025-02-17 18:40:25.096451 | ubuntu-noble | included: /var/lib/zuul/builds/a000caa3f0cd4f49a31e4e80cd42a8d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-17 18:40:25.114020 | 2025-02-17 18:40:25.114163 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-17 18:40:25.789751 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-17 18:40:25.790000 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/a000caa3f0cd4f49a31e4e80cd42a8d2/work/a000caa3f0cd4f49a31e4e80cd42a8d2_id_rsa 2025-02-17 18:40:25.790052 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/a000caa3f0cd4f49a31e4e80cd42a8d2/work/a000caa3f0cd4f49a31e4e80cd42a8d2_id_rsa.pub 2025-02-17 18:40:25.790078 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-17 18:40:25.790101 | ubuntu-noble -> localhost | SHA256:W/9qy24wXsiA/I/9sp4/WDEUm6XSQre7jCqOXOaBjLs zuul-build-sshkey 2025-02-17 18:40:25.790124 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-17 18:40:25.790149 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-17 18:40:25.790171 | ubuntu-noble -> localhost | | . o.. | 2025-02-17 18:40:25.790192 | ubuntu-noble -> localhost | | . o.* | 2025-02-17 18:40:25.790211 | ubuntu-noble -> localhost | | . . o.* | 2025-02-17 18:40:25.790229 | ubuntu-noble -> localhost | | o . oo. | 2025-02-17 18:40:25.790249 | ubuntu-noble -> localhost | | .So...o | 2025-02-17 18:40:25.790267 | ubuntu-noble -> localhost | | o . .o=+o. | 2025-02-17 18:40:25.790287 | ubuntu-noble -> localhost | | . o + .=.*+ | 2025-02-17 18:40:25.790307 | ubuntu-noble -> localhost | | o =....*o+. | 2025-02-17 18:40:25.790327 | ubuntu-noble -> localhost | | E.o.o...+OB+. | 2025-02-17 18:40:25.790346 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-17 18:40:25.790401 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.164165 2025-02-17 18:40:25.798435 | 2025-02-17 18:40:25.798556 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-17 18:40:25.846050 | ubuntu-noble | ok 2025-02-17 18:40:25.869735 | ubuntu-noble | included: /var/lib/zuul/builds/a000caa3f0cd4f49a31e4e80cd42a8d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-17 18:40:25.893586 | 2025-02-17 18:40:25.893707 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-17 18:40:25.949566 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:25.957501 | 2025-02-17 18:40:25.957616 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-17 18:40:26.777146 | ubuntu-noble | changed 2025-02-17 18:40:26.786922 | 2025-02-17 18:40:26.787086 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-17 18:40:27.098204 | ubuntu-noble | ok 2025-02-17 18:40:27.109081 | 2025-02-17 18:40:27.109213 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-17 18:40:27.884945 | ubuntu-noble | changed 2025-02-17 18:40:27.893661 | 2025-02-17 18:40:27.893783 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-17 18:40:28.637868 | ubuntu-noble | changed 2025-02-17 18:40:28.649684 | 2025-02-17 18:40:28.649809 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-17 18:40:28.676681 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:28.713291 | 2025-02-17 18:40:28.713546 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-17 18:40:29.112264 | ubuntu-noble -> localhost | changed 2025-02-17 18:40:29.126173 | 2025-02-17 18:40:29.126313 | TASK [add-build-sshkey : Add back temp key] 2025-02-17 18:40:29.529599 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/a000caa3f0cd4f49a31e4e80cd42a8d2/work/a000caa3f0cd4f49a31e4e80cd42a8d2_id_rsa (zuul-build-sshkey) 2025-02-17 18:40:29.529848 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.009971 2025-02-17 18:40:29.537860 | 2025-02-17 18:40:29.537972 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-17 18:40:30.086549 | ubuntu-noble | ok 2025-02-17 18:40:30.093766 | 2025-02-17 18:40:30.093878 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-17 18:40:30.118562 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 18:40:30.132514 | 2025-02-17 18:40:30.132627 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-17 18:40:30.560950 | ubuntu-noble | ok 2025-02-17 18:40:30.578569 | 2025-02-17 18:40:30.578689 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-17 18:40:30.622392 | ubuntu-noble | ok 2025-02-17 18:40:30.629960 | 2025-02-17 18:40:30.630081 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-17 18:40:30.934301 | ubuntu-noble -> localhost | ok 2025-02-17 18:40:30.942341 | 2025-02-17 18:40:30.942456 | TASK [validate-host : Collect information about the host] 2025-02-17 18:40:32.194702 | ubuntu-noble | ok 2025-02-17 18:40:32.210932 | 2025-02-17 18:40:32.211073 | TASK [validate-host : Sanitize hostname] 2025-02-17 18:40:32.287280 | ubuntu-noble | ok 2025-02-17 18:40:32.295940 | 2025-02-17 18:40:32.296120 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-17 18:40:32.914140 | ubuntu-noble -> localhost | changed 2025-02-17 18:40:32.923680 | 2025-02-17 18:40:32.923822 | TASK [validate-host : Collect information about zuul worker] 2025-02-17 18:40:33.433535 | ubuntu-noble | ok 2025-02-17 18:40:33.442565 | 2025-02-17 18:40:33.442700 | TASK [validate-host : Write out all zuul information for each host] 2025-02-17 18:40:34.076464 | ubuntu-noble -> localhost | changed 2025-02-17 18:40:34.094275 | 2025-02-17 18:40:34.094406 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-17 18:40:34.428851 | ubuntu-noble | ok 2025-02-17 18:40:34.438378 | 2025-02-17 18:40:34.438500 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-17 18:40:35.855039 | ubuntu-noble | changed: 2025-02-17 18:40:35.855234 | ubuntu-noble | cd+++++++++ src/ 2025-02-17 18:40:35.855267 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-17 18:40:35.855292 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-17 18:40:35.855312 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-02-17 18:40:35.855333 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.006128 2025-02-17 18:41:08.752508 | 2025-02-17 18:41:08.752626 | TASK [upload-logs : Upload logs to log server] 2025-02-17 18:41:09.337313 | localhost | Output suppressed because no_log was given 2025-02-17 18:41:09.341128 | 2025-02-17 18:41:09.341255 | LOOP [upload-logs : Compress console log and json output] 2025-02-17 18:41:09.433696 | localhost | skipping: Conditional result was False 2025-02-17 18:41:09.452361 | localhost | skipping: Conditional result was False 2025-02-17 18:41:09.465092 | 2025-02-17 18:41:09.465302 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-17 18:41:09.535909 | localhost | skipping: Conditional result was False 2025-02-17 18:41:09.536222 | 2025-02-17 18:41:09.549456 | localhost | skipping: Conditional result was False 2025-02-17 18:41:09.560660 | 2025-02-17 18:41:09.560814 | LOOP [upload-logs : Upload console log and json output]