2025-04-22 22:07:13.675581 | Job console starting... 2025-04-22 22:07:13.692640 | Updating repositories 2025-04-22 22:07:13.740824 | Preparing job workspace 2025-04-22 22:07:15.393950 | Running Ansible setup... 2025-04-22 22:07:20.769604 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-04-22 22:07:21.509175 | 2025-04-22 22:07:21.509338 | PLAY [Base pre] 2025-04-22 22:07:21.539033 | 2025-04-22 22:07:21.539198 | TASK [Setup log path fact] 2025-04-22 22:07:21.581577 | ubuntu-noble | ok 2025-04-22 22:07:21.601532 | 2025-04-22 22:07:21.601689 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-22 22:07:21.632893 | ubuntu-noble | ok 2025-04-22 22:07:21.651110 | 2025-04-22 22:07:21.652425 | TASK [emit-job-header : Print job information] 2025-04-22 22:07:21.712220 | # Job Information 2025-04-22 22:07:21.712444 | Ansible Version: 2.15.3 2025-04-22 22:07:21.712479 | Job: python-black 2025-04-22 22:07:21.712508 | Pipeline: check 2025-04-22 22:07:21.712528 | Executor: 7d211f194f6a 2025-04-22 22:07:21.712547 | Triggered by: https://github.com/osism/testbed/pull/2668 2025-04-22 22:07:21.712565 | Event ID: 1f7b3800-1fc6-11f0-939d-211ff0e821c9 2025-04-22 22:07:21.719231 | 2025-04-22 22:07:21.719356 | LOOP [emit-job-header : Print node information] 2025-04-22 22:07:21.864651 | ubuntu-noble | ok: 2025-04-22 22:07:21.864978 | ubuntu-noble | # Node Information 2025-04-22 22:07:21.865019 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-04-22 22:07:21.865044 | ubuntu-noble | Hostname: ubuntu 2025-04-22 22:07:21.865104 | ubuntu-noble | Username: zuul 2025-04-22 22:07:21.865125 | ubuntu-noble | Distro: Ubuntu 24.04 2025-04-22 22:07:21.865150 | ubuntu-noble | Provider: regiocloud-a 2025-04-22 22:07:21.865170 | ubuntu-noble | Label: ubuntu-noble 2025-04-22 22:07:21.865190 | ubuntu-noble | Product Name: OpenStack Nova 2025-04-22 22:07:21.865209 | ubuntu-noble | Interface IP: 81.163.193.201 2025-04-22 22:07:21.883845 | 2025-04-22 22:07:21.883993 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-04-22 22:07:22.400766 | ubuntu-noble -> localhost | changed 2025-04-22 22:07:22.410687 | 2025-04-22 22:07:22.410821 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-04-22 22:07:23.516615 | ubuntu-noble -> localhost | changed 2025-04-22 22:07:23.531415 | 2025-04-22 22:07:23.531556 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-04-22 22:07:23.832507 | ubuntu-noble -> localhost | ok 2025-04-22 22:07:23.840145 | 2025-04-22 22:07:23.840284 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-04-22 22:07:23.871571 | ubuntu-noble | ok 2025-04-22 22:07:23.904822 | ubuntu-noble | included: /var/lib/zuul/builds/2ead7617ca0c48fbbdc0c36cf08d92bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-04-22 22:07:23.912905 | 2025-04-22 22:07:23.913026 | TASK [add-build-sshkey : Create Temp SSH key] 2025-04-22 22:07:24.651448 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-04-22 22:07:24.651716 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2ead7617ca0c48fbbdc0c36cf08d92bc/work/2ead7617ca0c48fbbdc0c36cf08d92bc_id_rsa 2025-04-22 22:07:24.651755 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2ead7617ca0c48fbbdc0c36cf08d92bc/work/2ead7617ca0c48fbbdc0c36cf08d92bc_id_rsa.pub 2025-04-22 22:07:24.651781 | ubuntu-noble -> localhost | The key fingerprint is: 2025-04-22 22:07:24.651805 | ubuntu-noble -> localhost | SHA256:IFd2YUjFzkiHBw5oybyRBpY79F0190xUmZPu87QBk00 zuul-build-sshkey 2025-04-22 22:07:24.651827 | ubuntu-noble -> localhost | The key's randomart image is: 2025-04-22 22:07:24.651847 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-04-22 22:07:24.651866 | ubuntu-noble -> localhost | | o= +..=BB...o.=| 2025-04-22 22:07:24.651885 | ubuntu-noble -> localhost | | .o X ==o+o + E | 2025-04-22 22:07:24.651904 | ubuntu-noble -> localhost | | . =.+oo.* B .| 2025-04-22 22:07:24.651936 | ubuntu-noble -> localhost | | o oo... o + o | 2025-04-22 22:07:24.651957 | ubuntu-noble -> localhost | | . S + | 2025-04-22 22:07:24.651977 | ubuntu-noble -> localhost | | +.| 2025-04-22 22:07:24.651998 | ubuntu-noble -> localhost | | .=| 2025-04-22 22:07:24.652018 | ubuntu-noble -> localhost | | ..| 2025-04-22 22:07:24.652037 | ubuntu-noble -> localhost | | | 2025-04-22 22:07:24.652076 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-04-22 22:07:24.652134 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.184866 2025-04-22 22:07:24.663946 | 2025-04-22 22:07:24.664086 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-04-22 22:07:24.697491 | ubuntu-noble | ok 2025-04-22 22:07:24.709039 | ubuntu-noble | included: /var/lib/zuul/builds/2ead7617ca0c48fbbdc0c36cf08d92bc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-04-22 22:07:24.727956 | 2025-04-22 22:07:24.728090 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-04-22 22:07:24.753183 | ubuntu-noble | skipping: Conditional result was False 2025-04-22 22:07:24.762238 | 2025-04-22 22:07:24.762358 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-04-22 22:07:25.488752 | ubuntu-noble | changed 2025-04-22 22:07:25.496842 | 2025-04-22 22:07:25.496961 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-04-22 22:07:25.802688 | ubuntu-noble | ok 2025-04-22 22:07:25.811792 | 2025-04-22 22:07:25.811913 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-04-22 22:07:26.532795 | ubuntu-noble | changed 2025-04-22 22:07:26.542180 | 2025-04-22 22:07:26.542316 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-04-22 22:07:27.284496 | ubuntu-noble | changed 2025-04-22 22:07:27.291639 | 2025-04-22 22:07:27.291750 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-04-22 22:07:27.316254 | ubuntu-noble | skipping: Conditional result was False 2025-04-22 22:07:27.324979 | 2025-04-22 22:07:27.325115 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-04-22 22:07:27.839783 | ubuntu-noble -> localhost | changed 2025-04-22 22:07:27.854705 | 2025-04-22 22:07:27.854837 | TASK [add-build-sshkey : Add back temp key] 2025-04-22 22:07:28.259835 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2ead7617ca0c48fbbdc0c36cf08d92bc/work/2ead7617ca0c48fbbdc0c36cf08d92bc_id_rsa (zuul-build-sshkey) 2025-04-22 22:07:28.260087 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.020831 2025-04-22 22:07:28.267921 | 2025-04-22 22:07:28.268032 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-04-22 22:07:28.722615 | ubuntu-noble | ok 2025-04-22 22:07:28.731092 | 2025-04-22 22:07:28.731241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-04-22 22:07:28.766604 | ubuntu-noble | skipping: Conditional result was False 2025-04-22 22:07:28.784206 | 2025-04-22 22:07:28.784364 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-04-22 22:07:29.319666 | ubuntu-noble | ok 2025-04-22 22:07:29.388981 | 2025-04-22 22:07:29.389141 | TASK [validate-host : Define zuul_info_dir fact] 2025-04-22 22:07:29.421615 | ubuntu-noble | ok 2025-04-22 22:07:29.429120 | 2025-04-22 22:07:29.429261 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-04-22 22:07:29.758271 | ubuntu-noble -> localhost | ok 2025-04-22 22:07:29.771252 | 2025-04-22 22:07:29.771456 | TASK [validate-host : Collect information about the host] 2025-04-22 22:07:30.979571 | ubuntu-noble | ok 2025-04-22 22:07:31.009868 | 2025-04-22 22:07:31.010007 | TASK [validate-host : Sanitize hostname] 2025-04-22 22:07:31.073546 | ubuntu-noble | ok 2025-04-22 22:07:31.080341 | 2025-04-22 22:07:31.080477 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-04-22 22:07:31.691332 | ubuntu-noble -> localhost | changed 2025-04-22 22:07:31.698679 | 2025-04-22 22:07:31.698858 | TASK [validate-host : Collect information about zuul worker] 2025-04-22 22:07:32.160763 | ubuntu-noble | ok 2025-04-22 22:07:32.169515 | 2025-04-22 22:07:32.169642 | TASK [validate-host : Write out all zuul information for each host] 2025-04-22 22:07:32.751125 | ubuntu-noble -> localhost | changed 2025-04-22 22:07:32.764512 | 2025-04-22 22:07:32.764639 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-04-22 22:07:33.086714 | ubuntu-noble | ok 2025-04-22 22:07:33.102820 | 2025-04-22 22:07:33.102951 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-04-22 22:07:34.176918 | ubuntu-noble | changed: 2025-04-22 22:07:34.177180 | ubuntu-noble | cd+++++++++ src/ 2025-04-22 22:07:34.177220 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-04-22 22:07:34.177249 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-04-22 22:07:34.177271 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-04-22 22:07:34.177291 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004796 2025-04-22 22:08:05.902403 | 2025-04-22 22:08:05.902519 | TASK [upload-logs : Upload logs to log server] 2025-04-22 22:08:06.401745 | localhost | Output suppressed because no_log was given 2025-04-22 22:08:06.405530 | 2025-04-22 22:08:06.405626 | LOOP [upload-logs : Compress console log and json output] 2025-04-22 22:08:06.460507 | localhost | skipping: Conditional result was False 2025-04-22 22:08:06.478499 | localhost | skipping: Conditional result was False 2025-04-22 22:08:06.486675 | 2025-04-22 22:08:06.486777 | LOOP [upload-logs : Upload compressed console log and json output] 2025-04-22 22:08:06.545620 | localhost | skipping: Conditional result was False 2025-04-22 22:08:06.546259 | 2025-04-22 22:08:06.555619 | localhost | skipping: Conditional result was False 2025-04-22 22:08:06.564985 | 2025-04-22 22:08:06.565112 | LOOP [upload-logs : Upload console log and json output]