2025-03-01 02:31:35.829031 | Job console starting... 2025-03-01 02:31:35.839883 | Updating repositories 2025-03-01 02:31:35.862890 | Preparing job workspace 2025-03-01 02:31:37.226829 | Running Ansible setup... 2025-03-01 02:31:42.253200 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-01 02:31:42.938638 | 2025-03-01 02:31:42.938761 | PLAY [Base pre] 2025-03-01 02:31:42.966445 | 2025-03-01 02:31:42.966578 | TASK [Setup log path fact] 2025-03-01 02:31:42.986704 | ubuntu-noble | ok 2025-03-01 02:31:43.008115 | 2025-03-01 02:31:43.008236 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-01 02:31:43.058302 | ubuntu-noble | ok 2025-03-01 02:31:43.064872 | 2025-03-01 02:31:43.064970 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-01 02:31:43.099322 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:43.106218 | 2025-03-01 02:31:43.106332 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-01 02:31:43.201461 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:43.208639 | 2025-03-01 02:31:43.208742 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-01 02:31:43.252995 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:43.260167 | 2025-03-01 02:31:43.260265 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-01 02:31:43.289676 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:43.296425 | 2025-03-01 02:31:43.296544 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-01 02:31:43.320342 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:43.335194 | 2025-03-01 02:31:43.335306 | TASK [emit-job-header : Print job information] 2025-03-01 02:31:43.386372 | # Job Information 2025-03-01 02:31:43.386547 | Ansible Version: 2.15.3 2025-03-01 02:31:43.386584 | Job: python-black 2025-03-01 02:31:43.386612 | Pipeline: check 2025-03-01 02:31:43.386633 | Executor: 7d211f194f6a 2025-03-01 02:31:43.386652 | Triggered by: https://github.com/osism/python-osism/pull/1212 2025-03-01 02:31:43.386670 | Event ID: 3e5970c0-f645-11ef-9970-829e17f4313b 2025-03-01 02:31:43.392774 | 2025-03-01 02:31:43.392863 | LOOP [emit-job-header : Print node information] 2025-03-01 02:31:43.531588 | ubuntu-noble | ok: 2025-03-01 02:31:43.531881 | ubuntu-noble | # Node Information 2025-03-01 02:31:43.531923 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-01 02:31:43.531948 | ubuntu-noble | Hostname: ubuntu 2025-03-01 02:31:43.531970 | ubuntu-noble | Username: zuul 2025-03-01 02:31:43.531991 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-01 02:31:43.532010 | ubuntu-noble | Provider: regiocloud-a 2025-03-01 02:31:43.532029 | ubuntu-noble | Label: ubuntu-noble 2025-03-01 02:31:43.532048 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-01 02:31:43.532068 | ubuntu-noble | Interface IP: 81.163.192.126 2025-03-01 02:31:43.556532 | 2025-03-01 02:31:43.556637 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-01 02:31:44.009642 | ubuntu-noble -> localhost | changed 2025-03-01 02:31:44.042834 | 2025-03-01 02:31:44.042950 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-01 02:31:45.248469 | ubuntu-noble -> localhost | changed 2025-03-01 02:31:45.261434 | 2025-03-01 02:31:45.261560 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-01 02:31:45.580043 | ubuntu-noble -> localhost | ok 2025-03-01 02:31:45.588622 | 2025-03-01 02:31:45.588718 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-01 02:31:45.628686 | ubuntu-noble | ok 2025-03-01 02:31:45.654618 | ubuntu-noble | included: /var/lib/zuul/builds/f591d7a62eed43859edc16b2a8b6e592/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-01 02:31:45.672007 | 2025-03-01 02:31:45.672104 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-01 02:31:46.350164 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-01 02:31:46.350317 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f591d7a62eed43859edc16b2a8b6e592/work/f591d7a62eed43859edc16b2a8b6e592_id_rsa 2025-03-01 02:31:46.350347 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f591d7a62eed43859edc16b2a8b6e592/work/f591d7a62eed43859edc16b2a8b6e592_id_rsa.pub 2025-03-01 02:31:46.350367 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-01 02:31:46.350386 | ubuntu-noble -> localhost | SHA256:sbaguAf9rBwpVai1WWDhEGoKtNqJJpt0SXD8npm4m24 zuul-build-sshkey 2025-03-01 02:31:46.350404 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-01 02:31:46.350424 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-01 02:31:46.350442 | ubuntu-noble -> localhost | | o+o+. | 2025-03-01 02:31:46.350459 | ubuntu-noble -> localhost | |..+= o | 2025-03-01 02:31:46.350475 | ubuntu-noble -> localhost | |oo .= o . | 2025-03-01 02:31:46.350491 | ubuntu-noble -> localhost | |=o.+.* o | 2025-03-01 02:31:46.350506 | ubuntu-noble -> localhost | |=o++* = S | 2025-03-01 02:31:46.350534 | ubuntu-noble -> localhost | |+oo+.B o . | 2025-03-01 02:31:46.350551 | ubuntu-noble -> localhost | |o o.=o . | 2025-03-01 02:31:46.350568 | ubuntu-noble -> localhost | | Eo.o | 2025-03-01 02:31:46.350584 | ubuntu-noble -> localhost | | +=+. | 2025-03-01 02:31:46.350600 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-01 02:31:46.350636 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.153600 2025-03-01 02:31:46.357013 | 2025-03-01 02:31:46.357112 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-01 02:31:46.377875 | ubuntu-noble | ok 2025-03-01 02:31:46.388674 | ubuntu-noble | included: /var/lib/zuul/builds/f591d7a62eed43859edc16b2a8b6e592/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-01 02:31:46.396380 | 2025-03-01 02:31:46.396480 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-01 02:31:46.410009 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:46.416486 | 2025-03-01 02:31:46.416580 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-01 02:31:47.221227 | ubuntu-noble | changed 2025-03-01 02:31:47.231002 | 2025-03-01 02:31:47.231108 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-01 02:31:47.552455 | ubuntu-noble | ok 2025-03-01 02:31:47.561807 | 2025-03-01 02:31:47.561910 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-01 02:31:48.277476 | ubuntu-noble | changed 2025-03-01 02:31:48.288164 | 2025-03-01 02:31:48.288319 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-01 02:31:49.048026 | ubuntu-noble | changed 2025-03-01 02:31:49.059399 | 2025-03-01 02:31:49.059515 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-01 02:31:49.083462 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:49.091626 | 2025-03-01 02:31:49.091729 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-01 02:31:49.484559 | ubuntu-noble -> localhost | changed 2025-03-01 02:31:49.501661 | 2025-03-01 02:31:49.501767 | TASK [add-build-sshkey : Add back temp key] 2025-03-01 02:31:49.800166 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/f591d7a62eed43859edc16b2a8b6e592/work/f591d7a62eed43859edc16b2a8b6e592_id_rsa (zuul-build-sshkey) 2025-03-01 02:31:49.800459 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.011777 2025-03-01 02:31:49.810612 | 2025-03-01 02:31:49.810713 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-01 02:31:50.225662 | ubuntu-noble | ok 2025-03-01 02:31:50.231890 | 2025-03-01 02:31:50.231984 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-01 02:31:50.266259 | ubuntu-noble | skipping: Conditional result was False 2025-03-01 02:31:50.282162 | 2025-03-01 02:31:50.282276 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-01 02:31:50.666916 | ubuntu-noble | ok 2025-03-01 02:31:50.679740 | 2025-03-01 02:31:50.679828 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-01 02:31:50.721375 | ubuntu-noble | ok 2025-03-01 02:31:50.728036 | 2025-03-01 02:31:50.728117 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-01 02:31:51.005595 | ubuntu-noble -> localhost | ok 2025-03-01 02:31:51.012991 | 2025-03-01 02:31:51.013082 | TASK [validate-host : Collect information about the host] 2025-03-01 02:31:52.100738 | ubuntu-noble | ok 2025-03-01 02:31:52.113541 | 2025-03-01 02:31:52.113633 | TASK [validate-host : Sanitize hostname] 2025-03-01 02:31:52.180593 | ubuntu-noble | ok 2025-03-01 02:31:52.189126 | 2025-03-01 02:31:52.189245 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-01 02:31:52.691715 | ubuntu-noble -> localhost | changed 2025-03-01 02:31:52.699372 | 2025-03-01 02:31:52.699467 | TASK [validate-host : Collect information about zuul worker] 2025-03-01 02:31:53.155933 | ubuntu-noble | ok 2025-03-01 02:31:53.165347 | 2025-03-01 02:31:53.165457 | TASK [validate-host : Write out all zuul information for each host] 2025-03-01 02:31:53.647333 | ubuntu-noble -> localhost | changed 2025-03-01 02:31:53.659890 | 2025-03-01 02:31:53.659979 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-01 02:31:53.944848 | ubuntu-noble | ok 2025-03-01 02:31:53.951402 | 2025-03-01 02:31:53.951489 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-01 02:31:54.882686 | ubuntu-noble | changed: 2025-03-01 02:31:54.882884 | ubuntu-noble | cd+++++++++ src/ 2025-03-01 02:31:54.882917 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-01 02:31:54.882941 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-01 02:31:54.882962 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-03-01 02:31:54.882985 | ubuntu-noble | Containerfile 2025-03-01 02:31:54.883096 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.006786 2025-03-01 02:32:25.095165 | 2025-03-01 02:32:25.095291 | TASK [upload-logs : Upload logs to log server] 2025-03-01 02:32:25.687973 | localhost | Output suppressed because no_log was given 2025-03-01 02:32:25.692968 | 2025-03-01 02:32:25.693124 | LOOP [upload-logs : Compress console log and json output] 2025-03-01 02:32:25.767340 | localhost | skipping: Conditional result was False 2025-03-01 02:32:25.784814 | localhost | skipping: Conditional result was False 2025-03-01 02:32:25.797194 | 2025-03-01 02:32:25.797377 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-01 02:32:25.872838 | localhost | skipping: Conditional result was False 2025-03-01 02:32:25.873502 | 2025-03-01 02:32:25.885251 | localhost | skipping: Conditional result was False 2025-03-01 02:32:25.894734 | 2025-03-01 02:32:25.894892 | LOOP [upload-logs : Upload console log and json output]