2025-02-01 22:04:13.549559 | Job console starting... 2025-02-01 22:04:13.611749 | Updating repositories 2025-02-01 22:04:13.693771 | Preparing job workspace 2025-02-01 22:04:16.444245 | Running Ansible setup... 2025-02-01 22:04:24.131674 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-01 22:04:25.012310 | 2025-02-01 22:04:25.012454 | PLAY [Base pre] 2025-02-01 22:04:25.065695 | 2025-02-01 22:04:25.065822 | TASK [Setup log path fact] 2025-02-01 22:04:25.087335 | ubuntu-noble | ok 2025-02-01 22:04:25.115756 | 2025-02-01 22:04:25.115886 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-01 22:04:25.188930 | ubuntu-noble | ok 2025-02-01 22:04:25.201633 | 2025-02-01 22:04:25.201745 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-01 22:04:25.254018 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:25.264062 | 2025-02-01 22:04:25.264413 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-01 22:04:25.338149 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:25.345313 | 2025-02-01 22:04:25.345442 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-01 22:04:25.389956 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:25.396828 | 2025-02-01 22:04:25.396943 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-01 22:04:25.421217 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:25.429438 | 2025-02-01 22:04:25.429560 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-01 22:04:25.495782 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:25.517740 | 2025-02-01 22:04:25.517861 | TASK [emit-job-header : Print job information] 2025-02-01 22:04:25.564016 | # Job Information 2025-02-01 22:04:25.564256 | Ansible Version: 2.15.3 2025-02-01 22:04:25.564293 | Job: python-black 2025-02-01 22:04:25.564326 | Pipeline: check 2025-02-01 22:04:25.564351 | Executor: 7d211f194f6a 2025-02-01 22:04:25.564370 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1744 2025-02-01 22:04:25.574685 | Event ID: 733b5f00-e0e8-11ef-8b03-a2032ffb3905 2025-02-01 22:04:25.581148 | 2025-02-01 22:04:25.581239 | LOOP [emit-job-header : Print node information] 2025-02-01 22:04:25.735726 | ubuntu-noble | ok: 2025-02-01 22:04:25.735886 | ubuntu-noble | # Node Information 2025-02-01 22:04:25.735943 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-01 22:04:25.735969 | ubuntu-noble | Hostname: ubuntu 2025-02-01 22:04:25.735990 | ubuntu-noble | Username: zuul 2025-02-01 22:04:25.736010 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-01 22:04:25.736030 | ubuntu-noble | Provider: regiocloud-a 2025-02-01 22:04:25.736050 | ubuntu-noble | Label: ubuntu-noble 2025-02-01 22:04:25.736069 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-01 22:04:25.736089 | ubuntu-noble | Interface IP: 81.163.192.80 2025-02-01 22:04:25.756183 | 2025-02-01 22:04:25.756290 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-01 22:04:26.361734 | ubuntu-noble -> localhost | changed 2025-02-01 22:04:26.370179 | 2025-02-01 22:04:26.370284 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-01 22:04:27.748273 | ubuntu-noble -> localhost | changed 2025-02-01 22:04:27.761725 | 2025-02-01 22:04:27.761827 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-01 22:04:28.087471 | ubuntu-noble -> localhost | ok 2025-02-01 22:04:28.094407 | 2025-02-01 22:04:28.094504 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-01 22:04:28.134280 | ubuntu-noble | ok 2025-02-01 22:04:28.163466 | ubuntu-noble | included: /var/lib/zuul/builds/d4bf1327994541d5bbf3d776ab025ece/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-01 22:04:28.182501 | 2025-02-01 22:04:28.182600 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-01 22:04:29.653669 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-01 22:04:29.654119 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/d4bf1327994541d5bbf3d776ab025ece/work/d4bf1327994541d5bbf3d776ab025ece_id_rsa 2025-02-01 22:04:29.654217 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/d4bf1327994541d5bbf3d776ab025ece/work/d4bf1327994541d5bbf3d776ab025ece_id_rsa.pub 2025-02-01 22:04:29.654280 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-01 22:04:29.654338 | ubuntu-noble -> localhost | SHA256:SvPJeZuYEr55Xh8L47fgV2as9k+OLqH8CUsDvZSCqYQ zuul-build-sshkey 2025-02-01 22:04:29.654393 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-01 22:04:29.654457 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-01 22:04:29.654513 | ubuntu-noble -> localhost | | | 2025-02-01 22:04:29.654565 | ubuntu-noble -> localhost | | | 2025-02-01 22:04:29.654616 | ubuntu-noble -> localhost | | | 2025-02-01 22:04:29.654664 | ubuntu-noble -> localhost | | . o . . | 2025-02-01 22:04:29.654712 | ubuntu-noble -> localhost | | E . = S + . | 2025-02-01 22:04:29.654761 | ubuntu-noble -> localhost | | . o.= * .. = | 2025-02-01 22:04:29.654809 | ubuntu-noble -> localhost | | ....=.@..* .| 2025-02-01 22:04:29.654858 | ubuntu-noble -> localhost | | o..Oo@*+ + | 2025-02-01 22:04:29.654907 | ubuntu-noble -> localhost | | o=+ *=*=+.o| 2025-02-01 22:04:29.654977 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-01 22:04:29.655097 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.910322 2025-02-01 22:04:29.670741 | 2025-02-01 22:04:29.670877 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-01 22:04:29.726118 | ubuntu-noble | ok 2025-02-01 22:04:29.745720 | ubuntu-noble | included: /var/lib/zuul/builds/d4bf1327994541d5bbf3d776ab025ece/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-01 22:04:29.756333 | 2025-02-01 22:04:29.756449 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-01 22:04:29.780943 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:29.788126 | 2025-02-01 22:04:29.788219 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-01 22:04:30.492768 | ubuntu-noble | changed 2025-02-01 22:04:30.501710 | 2025-02-01 22:04:30.501963 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-01 22:04:30.822572 | ubuntu-noble | ok 2025-02-01 22:04:30.830821 | 2025-02-01 22:04:30.830933 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-01 22:04:31.573833 | ubuntu-noble | changed 2025-02-01 22:04:31.620300 | 2025-02-01 22:04:31.622525 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-01 22:04:32.474648 | ubuntu-noble | changed 2025-02-01 22:04:32.488918 | 2025-02-01 22:04:32.489048 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-01 22:04:32.534252 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:32.541989 | 2025-02-01 22:04:32.542095 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-01 22:04:33.368457 | ubuntu-noble -> localhost | changed 2025-02-01 22:04:33.426789 | 2025-02-01 22:04:33.426961 | TASK [add-build-sshkey : Add back temp key] 2025-02-01 22:04:33.778632 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/d4bf1327994541d5bbf3d776ab025ece/work/d4bf1327994541d5bbf3d776ab025ece_id_rsa (zuul-build-sshkey) 2025-02-01 22:04:33.778878 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010775 2025-02-01 22:04:33.791274 | 2025-02-01 22:04:33.791372 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-01 22:04:34.246816 | ubuntu-noble | ok 2025-02-01 22:04:34.253434 | 2025-02-01 22:04:34.253526 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-01 22:04:34.343083 | ubuntu-noble | skipping: Conditional result was False 2025-02-01 22:04:34.357320 | 2025-02-01 22:04:34.357443 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-01 22:04:34.821732 | ubuntu-noble | ok 2025-02-01 22:04:34.833799 | 2025-02-01 22:04:34.833897 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-01 22:04:34.864581 | ubuntu-noble | ok 2025-02-01 22:04:34.870961 | 2025-02-01 22:04:34.871053 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-01 22:04:35.235063 | ubuntu-noble -> localhost | ok 2025-02-01 22:04:35.258614 | 2025-02-01 22:04:35.258733 | TASK [validate-host : Collect information about the host] 2025-02-01 22:04:36.371690 | ubuntu-noble | ok 2025-02-01 22:04:36.418747 | 2025-02-01 22:04:36.418864 | TASK [validate-host : Sanitize hostname] 2025-02-01 22:04:36.500574 | ubuntu-noble | ok 2025-02-01 22:04:36.514198 | 2025-02-01 22:04:36.514305 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-01 22:04:37.233719 | ubuntu-noble -> localhost | changed 2025-02-01 22:04:37.242322 | 2025-02-01 22:04:37.242429 | TASK [validate-host : Collect information about zuul worker] 2025-02-01 22:04:37.842952 | ubuntu-noble | ok 2025-02-01 22:04:37.849122 | 2025-02-01 22:04:37.849213 | TASK [validate-host : Write out all zuul information for each host] 2025-02-01 22:04:38.640781 | ubuntu-noble -> localhost | changed 2025-02-01 22:04:38.664696 | 2025-02-01 22:04:38.664810 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-01 22:04:39.041756 | ubuntu-noble | ok 2025-02-01 22:04:39.058222 | 2025-02-01 22:04:39.058336 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-01 22:04:40.581503 | ubuntu-noble | changed: 2025-02-01 22:04:40.581709 | ubuntu-noble | cd+++++++++ src/ 2025-02-01 22:04:40.581743 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-01 22:04:40.581767 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-01 22:04:40.581787 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-01 22:04:40.581806 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007570 2025-02-01 22:05:15.723011 | 2025-02-01 22:05:15.723272 | TASK [upload-logs : Upload logs to log server] 2025-02-01 22:05:16.315437 | localhost | Output suppressed because no_log was given 2025-02-01 22:05:16.319029 | 2025-02-01 22:05:16.319219 | LOOP [upload-logs : Compress console log and json output] 2025-02-01 22:05:16.401777 | localhost | skipping: Conditional result was False 2025-02-01 22:05:16.422383 | localhost | skipping: Conditional result was False 2025-02-01 22:05:16.436085 | 2025-02-01 22:05:16.436281 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-01 22:05:16.501376 | localhost | skipping: Conditional result was False 2025-02-01 22:05:16.501679 | 2025-02-01 22:05:16.527182 | localhost | skipping: Conditional result was False 2025-02-01 22:05:16.539072 | 2025-02-01 22:05:16.539196 | LOOP [upload-logs : Upload console log and json output]