2025-02-27 15:58:10.064489 | Job console starting... 2025-02-27 15:58:10.092952 | Updating repositories 2025-02-27 15:58:10.217575 | Preparing job workspace 2025-02-27 15:58:12.389963 | Running Ansible setup... 2025-02-27 15:58:18.532093 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-27 15:58:19.620845 | 2025-02-27 15:58:19.620967 | PLAY [Base pre] 2025-02-27 15:58:19.648222 | 2025-02-27 15:58:19.648344 | TASK [Setup log path fact] 2025-02-27 15:58:19.689153 | ubuntu-noble | ok 2025-02-27 15:58:19.721045 | 2025-02-27 15:58:19.721173 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-27 15:58:19.791931 | ubuntu-noble | ok 2025-02-27 15:58:19.798457 | 2025-02-27 15:58:19.798573 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-27 15:58:19.841769 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:19.848767 | 2025-02-27 15:58:19.848883 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-27 15:58:19.933725 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:19.940831 | 2025-02-27 15:58:19.940940 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-27 15:58:19.985244 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:19.992223 | 2025-02-27 15:58:19.992330 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-27 15:58:20.033492 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:20.040371 | 2025-02-27 15:58:20.040515 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-27 15:58:20.094560 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:20.111755 | 2025-02-27 15:58:20.111873 | TASK [emit-job-header : Print job information] 2025-02-27 15:58:20.162382 | # Job Information 2025-02-27 15:58:20.162546 | Ansible Version: 2.15.3 2025-02-27 15:58:20.162580 | Job: python-black 2025-02-27 15:58:20.162607 | Pipeline: check 2025-02-27 15:58:20.162627 | Executor: 7d211f194f6a 2025-02-27 15:58:20.162645 | Triggered by: https://github.com/osism/python-osism/pull/1208 2025-02-27 15:58:20.162663 | Event ID: 9f850490-f523-11ef-81de-5e508f14265a 2025-02-27 15:58:20.168854 | 2025-02-27 15:58:20.168950 | LOOP [emit-job-header : Print node information] 2025-02-27 15:58:20.336133 | ubuntu-noble | ok: 2025-02-27 15:58:20.339515 | ubuntu-noble | # Node Information 2025-02-27 15:58:20.339589 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-27 15:58:20.339618 | ubuntu-noble | Hostname: ubuntu 2025-02-27 15:58:20.339640 | ubuntu-noble | Username: zuul 2025-02-27 15:58:20.339661 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-27 15:58:20.339681 | ubuntu-noble | Provider: regiocloud-a 2025-02-27 15:58:20.339700 | ubuntu-noble | Label: ubuntu-noble 2025-02-27 15:58:20.339719 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-27 15:58:20.339739 | ubuntu-noble | Interface IP: 81.163.192.179 2025-02-27 15:58:20.365834 | 2025-02-27 15:58:20.365947 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-27 15:58:20.990233 | ubuntu-noble -> localhost | changed 2025-02-27 15:58:20.998289 | 2025-02-27 15:58:20.998394 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-27 15:58:22.225109 | ubuntu-noble -> localhost | changed 2025-02-27 15:58:22.245050 | 2025-02-27 15:58:22.245143 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-27 15:58:22.785549 | ubuntu-noble -> localhost | ok 2025-02-27 15:58:22.791621 | 2025-02-27 15:58:22.791713 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-27 15:58:22.840114 | ubuntu-noble | ok 2025-02-27 15:58:22.863986 | ubuntu-noble | included: /var/lib/zuul/builds/af15ab359b164df58aff0cb85fc40a4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-27 15:58:22.870315 | 2025-02-27 15:58:22.870409 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-27 15:58:24.025249 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-27 15:58:24.025500 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/af15ab359b164df58aff0cb85fc40a4c/work/af15ab359b164df58aff0cb85fc40a4c_id_rsa 2025-02-27 15:58:24.025551 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/af15ab359b164df58aff0cb85fc40a4c/work/af15ab359b164df58aff0cb85fc40a4c_id_rsa.pub 2025-02-27 15:58:24.025586 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-27 15:58:24.025619 | ubuntu-noble -> localhost | SHA256:rxl/YCiqNkBdK4rZ1+1plMOaA6CZUzUatZdA76GNcRM zuul-build-sshkey 2025-02-27 15:58:24.025650 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-27 15:58:24.025685 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-27 15:58:24.025715 | ubuntu-noble -> localhost | | o+ E | 2025-02-27 15:58:24.025743 | ubuntu-noble -> localhost | | . o= o | 2025-02-27 15:58:24.025771 | ubuntu-noble -> localhost | | .++.O | 2025-02-27 15:58:24.025800 | ubuntu-noble -> localhost | | .+o X o | 2025-02-27 15:58:24.025827 | ubuntu-noble -> localhost | |oB..+.ooSo | 2025-02-27 15:58:24.025854 | ubuntu-noble -> localhost | |O.. o o B.o | 2025-02-27 15:58:24.025882 | ubuntu-noble -> localhost | | o . o *.+.. | 2025-02-27 15:58:24.025910 | ubuntu-noble -> localhost | | o . + += . | 2025-02-27 15:58:24.025938 | ubuntu-noble -> localhost | | ..o oo .. | 2025-02-27 15:58:24.025964 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-27 15:58:24.026022 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.710464 2025-02-27 15:58:24.036110 | 2025-02-27 15:58:24.036221 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-27 15:58:24.082307 | ubuntu-noble | ok 2025-02-27 15:58:24.098260 | ubuntu-noble | included: /var/lib/zuul/builds/af15ab359b164df58aff0cb85fc40a4c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-27 15:58:24.110360 | 2025-02-27 15:58:24.110479 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-27 15:58:24.134118 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:24.147229 | 2025-02-27 15:58:24.147338 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-27 15:58:24.987333 | ubuntu-noble | changed 2025-02-27 15:58:24.996084 | 2025-02-27 15:58:24.996189 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-27 15:58:25.393921 | ubuntu-noble | ok 2025-02-27 15:58:25.401753 | 2025-02-27 15:58:25.401863 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-27 15:58:26.230952 | ubuntu-noble | changed 2025-02-27 15:58:26.237655 | 2025-02-27 15:58:26.237749 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-27 15:58:27.113580 | ubuntu-noble | changed 2025-02-27 15:58:27.123118 | 2025-02-27 15:58:27.123232 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-27 15:58:27.147091 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:27.154289 | 2025-02-27 15:58:27.154382 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-27 15:58:27.650209 | ubuntu-noble -> localhost | changed 2025-02-27 15:58:27.687281 | 2025-02-27 15:58:27.687499 | TASK [add-build-sshkey : Add back temp key] 2025-02-27 15:58:28.087204 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/af15ab359b164df58aff0cb85fc40a4c/work/af15ab359b164df58aff0cb85fc40a4c_id_rsa (zuul-build-sshkey) 2025-02-27 15:58:28.087488 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014643 2025-02-27 15:58:28.095104 | 2025-02-27 15:58:28.095196 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-27 15:58:28.547210 | ubuntu-noble | ok 2025-02-27 15:58:28.556564 | 2025-02-27 15:58:28.556675 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-27 15:58:28.580632 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 15:58:28.595749 | 2025-02-27 15:58:28.595861 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-27 15:58:29.066310 | ubuntu-noble | ok 2025-02-27 15:58:29.083481 | 2025-02-27 15:58:29.083609 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-27 15:58:29.115665 | ubuntu-noble | ok 2025-02-27 15:58:29.123542 | 2025-02-27 15:58:29.123649 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-27 15:58:29.383998 | ubuntu-noble -> localhost | ok 2025-02-27 15:58:29.391506 | 2025-02-27 15:58:29.391593 | TASK [validate-host : Collect information about the host] 2025-02-27 15:58:30.504320 | ubuntu-noble | ok 2025-02-27 15:58:30.516589 | 2025-02-27 15:58:30.516687 | TASK [validate-host : Sanitize hostname] 2025-02-27 15:58:30.595856 | ubuntu-noble | ok 2025-02-27 15:58:30.601815 | 2025-02-27 15:58:30.601915 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-27 15:58:31.269544 | ubuntu-noble -> localhost | changed 2025-02-27 15:58:31.277183 | 2025-02-27 15:58:31.277306 | TASK [validate-host : Collect information about zuul worker] 2025-02-27 15:58:31.877218 | ubuntu-noble | ok 2025-02-27 15:58:31.887367 | 2025-02-27 15:58:31.887548 | TASK [validate-host : Write out all zuul information for each host] 2025-02-27 15:58:32.563039 | ubuntu-noble -> localhost | changed 2025-02-27 15:58:32.575871 | 2025-02-27 15:58:32.575996 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-27 15:58:32.904622 | ubuntu-noble | ok 2025-02-27 15:58:32.923206 | 2025-02-27 15:58:32.923340 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-27 15:58:34.102043 | ubuntu-noble | changed: 2025-02-27 15:58:34.102244 | ubuntu-noble | cd+++++++++ src/ 2025-02-27 15:58:34.102277 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-27 15:58:34.102300 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-27 15:58:34.102321 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-02-27 15:58:34.102341 | ubuntu-noble | Containerfile 2025-02-27 15:58:34.102490 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.003871 2025-02-27 15:59:11.192039 | 2025-02-27 15:59:11.192208 | TASK [upload-logs : Upload logs to log server] 2025-02-27 15:59:11.775045 | localhost | Output suppressed because no_log was given 2025-02-27 15:59:11.778209 | 2025-02-27 15:59:11.778321 | LOOP [upload-logs : Compress console log and json output] 2025-02-27 15:59:11.847525 | localhost | skipping: Conditional result was False 2025-02-27 15:59:11.864718 | localhost | skipping: Conditional result was False 2025-02-27 15:59:11.874005 | 2025-02-27 15:59:11.874163 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-27 15:59:11.936851 | localhost | skipping: Conditional result was False 2025-02-27 15:59:11.937538 | 2025-02-27 15:59:11.949056 | localhost | skipping: Conditional result was False 2025-02-27 15:59:11.959895 | 2025-02-27 15:59:11.960080 | LOOP [upload-logs : Upload console log and json output]