2025-02-17 20:00:07.019006 | Job console starting... 2025-02-17 20:00:07.043229 | Updating repositories 2025-02-17 20:00:07.118118 | Preparing job workspace 2025-02-17 20:00:09.170094 | Running Ansible setup... 2025-02-17 20:00:15.523160 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-17 20:00:16.618710 | 2025-02-17 20:00:16.619014 | PLAY [Base pre] 2025-02-17 20:00:16.659308 | 2025-02-17 20:00:16.659468 | TASK [Setup log path fact] 2025-02-17 20:00:16.702106 | ubuntu-noble | ok 2025-02-17 20:00:16.753136 | 2025-02-17 20:00:16.753299 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-17 20:00:16.856186 | ubuntu-noble | ok 2025-02-17 20:00:16.863166 | 2025-02-17 20:00:16.863297 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-17 20:00:16.905140 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:16.912623 | 2025-02-17 20:00:16.912759 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-17 20:00:16.957802 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:16.965420 | 2025-02-17 20:00:16.965554 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-17 20:00:17.001067 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:17.013655 | 2025-02-17 20:00:17.013792 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-17 20:00:17.060719 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:17.068374 | 2025-02-17 20:00:17.068541 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-17 20:00:17.125712 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:17.143171 | 2025-02-17 20:00:17.143309 | TASK [emit-job-header : Print job information] 2025-02-17 20:00:17.227846 | # Job Information 2025-02-17 20:00:17.228050 | Ansible Version: 2.15.3 2025-02-17 20:00:17.228082 | Job: python-black 2025-02-17 20:00:17.228110 | Pipeline: check 2025-02-17 20:00:17.228131 | Executor: 7d211f194f6a 2025-02-17 20:00:17.228150 | Triggered by: https://github.com/osism/cfg-generics/pull/515 2025-02-17 20:00:17.228168 | Event ID: c2d7db50-ed69-11ef-8648-0b7a0f55505a 2025-02-17 20:00:17.237210 | 2025-02-17 20:00:17.237347 | LOOP [emit-job-header : Print node information] 2025-02-17 20:00:17.473271 | ubuntu-noble | ok: 2025-02-17 20:00:17.473464 | ubuntu-noble | # Node Information 2025-02-17 20:00:17.473497 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-17 20:00:17.473521 | ubuntu-noble | Hostname: ubuntu 2025-02-17 20:00:17.473541 | ubuntu-noble | Username: zuul 2025-02-17 20:00:17.473561 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-17 20:00:17.473580 | ubuntu-noble | Provider: regiocloud-a 2025-02-17 20:00:17.473598 | ubuntu-noble | Label: ubuntu-noble 2025-02-17 20:00:17.473618 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-17 20:00:17.473638 | ubuntu-noble | Interface IP: 81.163.193.182 2025-02-17 20:00:17.490649 | 2025-02-17 20:00:17.490785 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-17 20:00:18.261546 | ubuntu-noble -> localhost | changed 2025-02-17 20:00:18.269301 | 2025-02-17 20:00:18.269393 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-17 20:00:19.682724 | ubuntu-noble -> localhost | changed 2025-02-17 20:00:19.706205 | 2025-02-17 20:00:19.706305 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-17 20:00:20.105948 | ubuntu-noble -> localhost | ok 2025-02-17 20:00:20.112123 | 2025-02-17 20:00:20.112207 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-17 20:00:20.140242 | ubuntu-noble | ok 2025-02-17 20:00:20.158570 | ubuntu-noble | included: /var/lib/zuul/builds/9c98fa3578b64f07bb2b7d541f4e9e33/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-17 20:00:20.176329 | 2025-02-17 20:00:20.176427 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-17 20:00:20.788195 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-17 20:00:20.788408 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/9c98fa3578b64f07bb2b7d541f4e9e33/work/9c98fa3578b64f07bb2b7d541f4e9e33_id_rsa 2025-02-17 20:00:20.788449 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/9c98fa3578b64f07bb2b7d541f4e9e33/work/9c98fa3578b64f07bb2b7d541f4e9e33_id_rsa.pub 2025-02-17 20:00:20.788474 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-17 20:00:20.788499 | ubuntu-noble -> localhost | SHA256:qlHwe5/93kN5T6GIkYtS23/OzR3B1iZupdD2fbHaJyM zuul-build-sshkey 2025-02-17 20:00:20.788522 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-17 20:00:20.788546 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-17 20:00:20.788567 | ubuntu-noble -> localhost | | | 2025-02-17 20:00:20.788588 | ubuntu-noble -> localhost | | | 2025-02-17 20:00:20.788607 | ubuntu-noble -> localhost | | . . | 2025-02-17 20:00:20.788630 | ubuntu-noble -> localhost | | o . o ....| 2025-02-17 20:00:20.788649 | ubuntu-noble -> localhost | | + S + o ==*| 2025-02-17 20:00:20.788669 | ubuntu-noble -> localhost | | o = + . =.OB| 2025-02-17 20:00:20.788688 | ubuntu-noble -> localhost | | . + . . =+*| 2025-02-17 20:00:20.788708 | ubuntu-noble -> localhost | | o . . +EoBo*| 2025-02-17 20:00:20.788727 | ubuntu-noble -> localhost | | . o +Bo*=| 2025-02-17 20:00:20.788746 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-17 20:00:20.788794 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.115069 2025-02-17 20:00:20.797238 | 2025-02-17 20:00:20.797333 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-17 20:00:20.841572 | ubuntu-noble | ok 2025-02-17 20:00:20.853895 | ubuntu-noble | included: /var/lib/zuul/builds/9c98fa3578b64f07bb2b7d541f4e9e33/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-17 20:00:20.864356 | 2025-02-17 20:00:20.864540 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-17 20:00:20.888486 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:20.896430 | 2025-02-17 20:00:20.896528 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-17 20:00:21.602499 | ubuntu-noble | changed 2025-02-17 20:00:21.613530 | 2025-02-17 20:00:21.613682 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-17 20:00:21.918418 | ubuntu-noble | ok 2025-02-17 20:00:21.925359 | 2025-02-17 20:00:21.925460 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-17 20:00:22.624320 | ubuntu-noble | changed 2025-02-17 20:00:22.631364 | 2025-02-17 20:00:22.631464 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-17 20:00:23.286246 | ubuntu-noble | changed 2025-02-17 20:00:23.291967 | 2025-02-17 20:00:23.292053 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-17 20:00:23.315213 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:23.321160 | 2025-02-17 20:00:23.321239 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-17 20:00:23.829195 | ubuntu-noble -> localhost | changed 2025-02-17 20:00:23.840124 | 2025-02-17 20:00:23.840215 | TASK [add-build-sshkey : Add back temp key] 2025-02-17 20:00:24.194651 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/9c98fa3578b64f07bb2b7d541f4e9e33/work/9c98fa3578b64f07bb2b7d541f4e9e33_id_rsa (zuul-build-sshkey) 2025-02-17 20:00:24.194822 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.007204 2025-02-17 20:00:24.221934 | 2025-02-17 20:00:24.222020 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-17 20:00:24.579099 | ubuntu-noble | ok 2025-02-17 20:00:24.585963 | 2025-02-17 20:00:24.586064 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-17 20:00:24.620193 | ubuntu-noble | skipping: Conditional result was False 2025-02-17 20:00:24.641233 | 2025-02-17 20:00:24.641350 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-17 20:00:25.083284 | ubuntu-noble | ok 2025-02-17 20:00:25.100140 | 2025-02-17 20:00:25.100257 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-17 20:00:25.143769 | ubuntu-noble | ok 2025-02-17 20:00:25.154338 | 2025-02-17 20:00:25.154450 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-17 20:00:25.403622 | ubuntu-noble -> localhost | ok 2025-02-17 20:00:25.411260 | 2025-02-17 20:00:25.411355 | TASK [validate-host : Collect information about the host] 2025-02-17 20:00:26.565290 | ubuntu-noble | ok 2025-02-17 20:00:26.576985 | 2025-02-17 20:00:26.577087 | TASK [validate-host : Sanitize hostname] 2025-02-17 20:00:26.640639 | ubuntu-noble | ok 2025-02-17 20:00:26.648134 | 2025-02-17 20:00:26.648249 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-17 20:00:27.406854 | ubuntu-noble -> localhost | changed 2025-02-17 20:00:27.413501 | 2025-02-17 20:00:27.413602 | TASK [validate-host : Collect information about zuul worker] 2025-02-17 20:00:27.900277 | ubuntu-noble | ok 2025-02-17 20:00:27.909246 | 2025-02-17 20:00:27.909363 | TASK [validate-host : Write out all zuul information for each host] 2025-02-17 20:00:28.485196 | ubuntu-noble -> localhost | changed 2025-02-17 20:00:28.496922 | 2025-02-17 20:00:28.497019 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-17 20:00:28.821354 | ubuntu-noble | ok 2025-02-17 20:00:28.828182 | 2025-02-17 20:00:28.828277 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-17 20:00:29.796829 | ubuntu-noble | changed: 2025-02-17 20:00:29.797003 | ubuntu-noble | cd+++++++++ src/ 2025-02-17 20:00:29.797037 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-17 20:00:29.797060 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-17 20:00:29.797081 | ubuntu-noble | cd+++++++++ src/github.com/osism/cfg-generics/ 2025-02-17 20:00:29.797100 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005428 2025-02-17 20:01:01.167442 | 2025-02-17 20:01:01.167557 | TASK [upload-logs : Upload logs to log server] 2025-02-17 20:01:01.747396 | localhost | Output suppressed because no_log was given 2025-02-17 20:01:01.751940 | 2025-02-17 20:01:01.752082 | LOOP [upload-logs : Compress console log and json output] 2025-02-17 20:01:01.828011 | localhost | skipping: Conditional result was False 2025-02-17 20:01:01.848204 | localhost | skipping: Conditional result was False 2025-02-17 20:01:01.859001 | 2025-02-17 20:01:01.859145 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-17 20:01:01.966568 | localhost | skipping: Conditional result was False 2025-02-17 20:01:01.966903 | 2025-02-17 20:01:01.986959 | localhost | skipping: Conditional result was False 2025-02-17 20:01:01.993149 | 2025-02-17 20:01:01.993291 | LOOP [upload-logs : Upload console log and json output]