2025-02-08 15:16:55.232617 | Job console starting... 2025-02-08 15:16:55.275314 | Updating repositories 2025-02-08 15:16:55.349012 | Preparing job workspace 2025-02-08 15:16:57.088851 | Running Ansible setup... 2025-02-08 15:17:02.253483 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-08 15:17:02.950931 | 2025-02-08 15:17:02.951130 | PLAY [Base pre] 2025-02-08 15:17:02.981461 | 2025-02-08 15:17:02.981619 | TASK [Setup log path fact] 2025-02-08 15:17:03.023811 | ubuntu-noble | ok 2025-02-08 15:17:03.063048 | 2025-02-08 15:17:03.063228 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-08 15:17:03.115340 | ubuntu-noble | ok 2025-02-08 15:17:03.123297 | 2025-02-08 15:17:03.123421 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-08 15:17:03.158227 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:03.165743 | 2025-02-08 15:17:03.165871 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-08 15:17:03.219935 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:03.228150 | 2025-02-08 15:17:03.228281 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-08 15:17:03.252972 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:03.260354 | 2025-02-08 15:17:03.260498 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-08 15:17:03.285574 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:03.293124 | 2025-02-08 15:17:03.293248 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-08 15:17:03.317872 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:03.332974 | 2025-02-08 15:17:03.333119 | TASK [emit-job-header : Print job information] 2025-02-08 15:17:03.385946 | # Job Information 2025-02-08 15:17:03.386163 | Ansible Version: 2.15.3 2025-02-08 15:17:03.386197 | Job: python-black 2025-02-08 15:17:03.386225 | Pipeline: check 2025-02-08 15:17:03.386245 | Executor: 7d211f194f6a 2025-02-08 15:17:03.386264 | Triggered by: https://github.com/osism/testbed/pull/2606 2025-02-08 15:17:03.386282 | Event ID: b6c43ca0-e62f-11ef-84fc-37f6df9a8e9f 2025-02-08 15:17:03.392719 | 2025-02-08 15:17:03.392828 | LOOP [emit-job-header : Print node information] 2025-02-08 15:17:03.576037 | ubuntu-noble | ok: 2025-02-08 15:17:03.576493 | ubuntu-noble | # Node Information 2025-02-08 15:17:03.576561 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-08 15:17:03.576588 | ubuntu-noble | Hostname: ubuntu 2025-02-08 15:17:03.576610 | ubuntu-noble | Username: zuul 2025-02-08 15:17:03.576631 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-08 15:17:03.576651 | ubuntu-noble | Provider: regiocloud-a 2025-02-08 15:17:03.576670 | ubuntu-noble | Label: ubuntu-noble 2025-02-08 15:17:03.576690 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-08 15:17:03.576710 | ubuntu-noble | Interface IP: 81.163.192.215 2025-02-08 15:17:03.601928 | 2025-02-08 15:17:03.602109 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-08 15:17:04.091686 | ubuntu-noble -> localhost | changed 2025-02-08 15:17:04.100706 | 2025-02-08 15:17:04.100837 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-08 15:17:05.348325 | ubuntu-noble -> localhost | changed 2025-02-08 15:17:05.366941 | 2025-02-08 15:17:05.367084 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-08 15:17:05.700957 | ubuntu-noble -> localhost | ok 2025-02-08 15:17:05.710113 | 2025-02-08 15:17:05.710240 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-08 15:17:05.756141 | ubuntu-noble | ok 2025-02-08 15:17:05.793266 | ubuntu-noble | included: /var/lib/zuul/builds/4e9d1eb6b0e54c33a1e809060b811862/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-08 15:17:05.808259 | 2025-02-08 15:17:05.816462 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-08 15:17:06.574953 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-08 15:17:06.575191 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/4e9d1eb6b0e54c33a1e809060b811862/work/4e9d1eb6b0e54c33a1e809060b811862_id_rsa 2025-02-08 15:17:06.575228 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/4e9d1eb6b0e54c33a1e809060b811862/work/4e9d1eb6b0e54c33a1e809060b811862_id_rsa.pub 2025-02-08 15:17:06.575253 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-08 15:17:06.575276 | ubuntu-noble -> localhost | SHA256:L68nOHv8E4Oxvne05O/FxVBzuVxoNnC7P6n1RhAAgXY zuul-build-sshkey 2025-02-08 15:17:06.575297 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-08 15:17:06.575321 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-08 15:17:06.575342 | ubuntu-noble -> localhost | | .oooo.o+| 2025-02-08 15:17:06.575362 | ubuntu-noble -> localhost | | o E .*++| 2025-02-08 15:17:06.575381 | ubuntu-noble -> localhost | | . . o+oo| 2025-02-08 15:17:06.575401 | ubuntu-noble -> localhost | | . .* | 2025-02-08 15:17:06.575420 | ubuntu-noble -> localhost | | S+ ..o| 2025-02-08 15:17:06.575440 | ubuntu-noble -> localhost | | o.o o o+| 2025-02-08 15:17:06.575460 | ubuntu-noble -> localhost | | +. .* . =+| 2025-02-08 15:17:06.575480 | ubuntu-noble -> localhost | | o =o+ + o.+| 2025-02-08 15:17:06.575500 | ubuntu-noble -> localhost | | .+oBoo +o..| 2025-02-08 15:17:06.575520 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-08 15:17:06.575569 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.197943 2025-02-08 15:17:06.602594 | 2025-02-08 15:17:06.602735 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-08 15:17:06.637856 | ubuntu-noble | ok 2025-02-08 15:17:06.650009 | ubuntu-noble | included: /var/lib/zuul/builds/4e9d1eb6b0e54c33a1e809060b811862/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-08 15:17:06.660825 | 2025-02-08 15:17:06.660958 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-08 15:17:06.686025 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:06.694294 | 2025-02-08 15:17:06.694415 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-08 15:17:07.402761 | ubuntu-noble | changed 2025-02-08 15:17:07.414953 | 2025-02-08 15:17:07.415182 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-08 15:17:07.719883 | ubuntu-noble | ok 2025-02-08 15:17:07.727591 | 2025-02-08 15:17:07.727714 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-08 15:17:08.486083 | ubuntu-noble | changed 2025-02-08 15:17:08.496227 | 2025-02-08 15:17:08.496357 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-08 15:17:09.273780 | ubuntu-noble | changed 2025-02-08 15:17:09.281936 | 2025-02-08 15:17:09.282144 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-08 15:17:09.316829 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:09.324689 | 2025-02-08 15:17:09.324808 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-08 15:17:09.829132 | ubuntu-noble -> localhost | changed 2025-02-08 15:17:09.843745 | 2025-02-08 15:17:09.843881 | TASK [add-build-sshkey : Add back temp key] 2025-02-08 15:17:10.227449 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/4e9d1eb6b0e54c33a1e809060b811862/work/4e9d1eb6b0e54c33a1e809060b811862_id_rsa (zuul-build-sshkey) 2025-02-08 15:17:10.227702 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.009843 2025-02-08 15:17:10.273291 | 2025-02-08 15:17:10.273435 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-08 15:17:10.661596 | ubuntu-noble | ok 2025-02-08 15:17:10.668560 | 2025-02-08 15:17:10.668680 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-08 15:17:10.703371 | ubuntu-noble | skipping: Conditional result was False 2025-02-08 15:17:10.717136 | 2025-02-08 15:17:10.717261 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-08 15:17:11.125380 | ubuntu-noble | ok 2025-02-08 15:17:11.140357 | 2025-02-08 15:17:11.140511 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-08 15:17:11.171317 | ubuntu-noble | ok 2025-02-08 15:17:11.177970 | 2025-02-08 15:17:11.178107 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-08 15:17:11.488039 | ubuntu-noble -> localhost | ok 2025-02-08 15:17:11.496116 | 2025-02-08 15:17:11.496225 | TASK [validate-host : Collect information about the host] 2025-02-08 15:17:12.748551 | ubuntu-noble | ok 2025-02-08 15:17:12.761359 | 2025-02-08 15:17:12.761493 | TASK [validate-host : Sanitize hostname] 2025-02-08 15:17:12.832913 | ubuntu-noble | ok 2025-02-08 15:17:12.839415 | 2025-02-08 15:17:12.839532 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-08 15:17:13.448167 | ubuntu-noble -> localhost | changed 2025-02-08 15:17:13.456894 | 2025-02-08 15:17:13.457015 | TASK [validate-host : Collect information about zuul worker] 2025-02-08 15:17:13.956725 | ubuntu-noble | ok 2025-02-08 15:17:13.962986 | 2025-02-08 15:17:13.963127 | TASK [validate-host : Write out all zuul information for each host] 2025-02-08 15:17:14.555938 | ubuntu-noble -> localhost | changed 2025-02-08 15:17:14.569711 | 2025-02-08 15:17:14.569839 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-08 15:17:14.901586 | ubuntu-noble | ok 2025-02-08 15:17:14.912539 | 2025-02-08 15:17:14.912677 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-08 15:17:16.188330 | ubuntu-noble | changed: 2025-02-08 15:17:16.188565 | ubuntu-noble | cd+++++++++ src/ 2025-02-08 15:17:16.188599 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-08 15:17:16.188622 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-08 15:17:16.188644 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-02-08 15:17:16.188664 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004520 2025-02-08 15:17:47.924482 | 2025-02-08 15:17:47.924593 | TASK [upload-logs : Upload logs to log server] 2025-02-08 15:17:48.511829 | localhost | Output suppressed because no_log was given 2025-02-08 15:17:48.515133 | 2025-02-08 15:17:48.515249 | LOOP [upload-logs : Compress console log and json output] 2025-02-08 15:17:48.590863 | localhost | skipping: Conditional result was False 2025-02-08 15:17:48.609089 | localhost | skipping: Conditional result was False 2025-02-08 15:17:48.621387 | 2025-02-08 15:17:48.621595 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-08 15:17:48.692179 | localhost | skipping: Conditional result was False 2025-02-08 15:17:48.692554 | 2025-02-08 15:17:48.705585 | localhost | skipping: Conditional result was False 2025-02-08 15:17:48.714232 | 2025-02-08 15:17:48.714345 | LOOP [upload-logs : Upload console log and json output]