2025-01-08 10:23:00.712541 | Job console starting... 2025-01-08 10:23:00.720876 | Updating repositories 2025-01-08 10:23:00.808016 | Preparing job workspace 2025-01-08 10:23:03.217982 | Running Ansible setup... 2025-01-08 10:23:09.186881 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 10:23:10.127052 | 2025-01-08 10:23:10.127174 | PLAY [Base pre] 2025-01-08 10:23:10.165098 | 2025-01-08 10:23:10.165209 | TASK [Setup log path fact] 2025-01-08 10:23:10.208109 | ubuntu-noble | ok 2025-01-08 10:23:10.261893 | 2025-01-08 10:23:10.262053 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 10:23:10.324158 | ubuntu-noble | ok 2025-01-08 10:23:10.333074 | 2025-01-08 10:23:10.333175 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 10:23:10.402966 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:10.409007 | 2025-01-08 10:23:10.409105 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 10:23:10.473921 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:10.481111 | 2025-01-08 10:23:10.481214 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 10:23:10.510253 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:10.517535 | 2025-01-08 10:23:10.517673 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 10:23:10.546507 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:10.552651 | 2025-01-08 10:23:10.552745 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 10:23:10.587013 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:10.604038 | 2025-01-08 10:23:10.604142 | TASK [emit-job-header : Print job information] 2025-01-08 10:23:10.709508 | # Job Information 2025-01-08 10:23:10.709670 | Ansible Version: 2.15.3 2025-01-08 10:23:10.709697 | Job: python-black 2025-01-08 10:23:10.709722 | Pipeline: check 2025-01-08 10:23:10.709739 | Executor: 7d211f194f6a 2025-01-08 10:23:10.709755 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/688 2025-01-08 10:23:10.709771 | Event ID: 6412c1e0-cdaa-11ef-974a-131408f3dc20 2025-01-08 10:23:10.715220 | 2025-01-08 10:23:10.715313 | LOOP [emit-job-header : Print node information] 2025-01-08 10:23:10.829705 | ubuntu-noble | ok: 2025-01-08 10:23:10.830014 | ubuntu-noble | # Node Information 2025-01-08 10:23:10.830056 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 10:23:10.830077 | ubuntu-noble | Hostname: ubuntu 2025-01-08 10:23:10.830095 | ubuntu-noble | Username: zuul 2025-01-08 10:23:10.830112 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 10:23:10.830128 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 10:23:10.830144 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 10:23:10.830160 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 10:23:10.830177 | ubuntu-noble | Interface IP: 81.163.192.109 2025-01-08 10:23:10.851364 | 2025-01-08 10:23:10.851475 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 10:23:11.512362 | ubuntu-noble -> localhost | changed 2025-01-08 10:23:11.521848 | 2025-01-08 10:23:11.521949 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 10:23:13.141003 | ubuntu-noble -> localhost | changed 2025-01-08 10:23:13.152363 | 2025-01-08 10:23:13.152453 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 10:23:13.512215 | ubuntu-noble -> localhost | ok 2025-01-08 10:23:13.521778 | 2025-01-08 10:23:13.521870 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 10:23:13.586762 | ubuntu-noble | ok 2025-01-08 10:23:13.621850 | ubuntu-noble | included: /var/lib/zuul/builds/b600b87142454b3abe814664ffbc5b2e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 10:23:13.642134 | 2025-01-08 10:23:13.642229 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 10:23:14.568037 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 10:23:14.568199 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/b600b87142454b3abe814664ffbc5b2e/work/b600b87142454b3abe814664ffbc5b2e_id_rsa 2025-01-08 10:23:14.568229 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/b600b87142454b3abe814664ffbc5b2e/work/b600b87142454b3abe814664ffbc5b2e_id_rsa.pub 2025-01-08 10:23:14.568417 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 10:23:14.568556 | ubuntu-noble -> localhost | SHA256:dmlpu/EAxsilbeibfRRFXJng6rq8LL7UKx4Spw1g/zw zuul-build-sshkey 2025-01-08 10:23:14.568839 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 10:23:14.568893 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 10:23:14.568917 | ubuntu-noble -> localhost | | ooo.o | 2025-01-08 10:23:14.568936 | ubuntu-noble -> localhost | | .o o | 2025-01-08 10:23:14.568953 | ubuntu-noble -> localhost | | o . .. | 2025-01-08 10:23:14.568969 | ubuntu-noble -> localhost | | . o . B .+ | 2025-01-08 10:23:14.568986 | ubuntu-noble -> localhost | | + * S B. | 2025-01-08 10:23:14.569002 | ubuntu-noble -> localhost | | X = *.. | 2025-01-08 10:23:14.569019 | ubuntu-noble -> localhost | | o E ..= | 2025-01-08 10:23:14.569036 | ubuntu-noble -> localhost | | ooO o.= | 2025-01-08 10:23:14.569052 | ubuntu-noble -> localhost | | o*+O+. . | 2025-01-08 10:23:14.569068 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 10:23:14.569116 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.272272 2025-01-08 10:23:14.579956 | 2025-01-08 10:23:14.580032 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 10:23:14.636636 | ubuntu-noble | ok 2025-01-08 10:23:14.651252 | ubuntu-noble | included: /var/lib/zuul/builds/b600b87142454b3abe814664ffbc5b2e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 10:23:14.669652 | 2025-01-08 10:23:14.669750 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 10:23:14.703733 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:14.710570 | 2025-01-08 10:23:14.710669 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 10:23:15.398634 | ubuntu-noble | changed 2025-01-08 10:23:15.430148 | 2025-01-08 10:23:15.430320 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 10:23:15.752513 | ubuntu-noble | ok 2025-01-08 10:23:15.761788 | 2025-01-08 10:23:15.761889 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 10:23:16.587538 | ubuntu-noble | changed 2025-01-08 10:23:16.622832 | 2025-01-08 10:23:16.623190 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 10:23:17.381720 | ubuntu-noble | changed 2025-01-08 10:23:17.396156 | 2025-01-08 10:23:17.396276 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 10:23:17.454981 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:17.463297 | 2025-01-08 10:23:17.463406 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 10:23:18.360493 | ubuntu-noble -> localhost | changed 2025-01-08 10:23:18.410341 | 2025-01-08 10:23:18.416909 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 10:23:19.056504 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/b600b87142454b3abe814664ffbc5b2e/work/b600b87142454b3abe814664ffbc5b2e_id_rsa (zuul-build-sshkey) 2025-01-08 10:23:19.056785 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.038330 2025-01-08 10:23:19.063011 | 2025-01-08 10:23:19.063101 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 10:23:19.482145 | ubuntu-noble | ok 2025-01-08 10:23:19.497906 | 2025-01-08 10:23:19.498049 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 10:23:19.551986 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 10:23:19.562812 | 2025-01-08 10:23:19.562903 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 10:23:19.984222 | ubuntu-noble | ok 2025-01-08 10:23:20.018268 | 2025-01-08 10:23:20.018380 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 10:23:20.066200 | ubuntu-noble | ok 2025-01-08 10:23:20.071773 | 2025-01-08 10:23:20.071851 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 10:23:20.361445 | ubuntu-noble -> localhost | ok 2025-01-08 10:23:20.367578 | 2025-01-08 10:23:20.367678 | TASK [validate-host : Collect information about the host] 2025-01-08 10:23:21.550043 | ubuntu-noble | ok 2025-01-08 10:23:21.573719 | 2025-01-08 10:23:21.573833 | TASK [validate-host : Sanitize hostname] 2025-01-08 10:23:21.639232 | ubuntu-noble | ok 2025-01-08 10:23:21.648419 | 2025-01-08 10:23:21.648516 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 10:23:22.306432 | ubuntu-noble -> localhost | changed 2025-01-08 10:23:22.316993 | 2025-01-08 10:23:22.317090 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 10:23:22.760878 | ubuntu-noble | ok 2025-01-08 10:23:22.771949 | 2025-01-08 10:23:22.772055 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 10:23:23.520783 | ubuntu-noble -> localhost | changed 2025-01-08 10:23:23.535054 | 2025-01-08 10:23:23.535156 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 10:23:23.864270 | ubuntu-noble | ok 2025-01-08 10:23:23.874605 | 2025-01-08 10:23:23.874687 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 10:23:24.627027 | ubuntu-noble | changed: 2025-01-08 10:23:24.627206 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 10:23:24.627241 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 10:23:24.627266 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 10:23:24.627289 | ubuntu-noble | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-01-08 10:23:24.627311 | ubuntu-noble | Containerfile 2025-01-08 10:23:24.627453 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.003968 2025-01-08 10:23:58.988247 | 2025-01-08 10:23:58.988341 | TASK [upload-logs : Upload logs to log server] 2025-01-08 10:23:59.624982 | localhost | Output suppressed because no_log was given 2025-01-08 10:23:59.627884 | 2025-01-08 10:23:59.627972 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 10:23:59.702262 | localhost | skipping: Conditional result was False 2025-01-08 10:23:59.720097 | localhost | skipping: Conditional result was False 2025-01-08 10:23:59.726439 | 2025-01-08 10:23:59.726534 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 10:23:59.787843 | localhost | skipping: Conditional result was False 2025-01-08 10:23:59.788069 | 2025-01-08 10:23:59.801869 | localhost | skipping: Conditional result was False 2025-01-08 10:23:59.819513 | 2025-01-08 10:23:59.819629 | LOOP [upload-logs : Upload console log and json output]