2025-02-26 02:31:31.742607 | Job console starting... 2025-02-26 02:31:31.760033 | Updating repositories 2025-02-26 02:31:31.831161 | Preparing job workspace 2025-02-26 02:31:34.303971 | Running Ansible setup... 2025-02-26 02:31:39.943578 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-26 02:31:40.708094 | 2025-02-26 02:31:40.708217 | PLAY [Base pre] 2025-02-26 02:31:40.763862 | 2025-02-26 02:31:40.763992 | TASK [Setup log path fact] 2025-02-26 02:31:40.822290 | ubuntu-noble | ok 2025-02-26 02:31:40.883878 | 2025-02-26 02:31:40.884011 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-26 02:31:40.966593 | ubuntu-noble | ok 2025-02-26 02:31:40.985368 | 2025-02-26 02:31:40.985476 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-26 02:31:41.029614 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:41.036764 | 2025-02-26 02:31:41.036884 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-26 02:31:41.121508 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:41.128606 | 2025-02-26 02:31:41.128713 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-26 02:31:41.182815 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:41.189874 | 2025-02-26 02:31:41.189974 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-26 02:31:41.223745 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:41.230624 | 2025-02-26 02:31:41.230725 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-26 02:31:41.296710 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:41.316054 | 2025-02-26 02:31:41.316156 | TASK [emit-job-header : Print job information] 2025-02-26 02:31:41.470738 | # Job Information 2025-02-26 02:31:41.470894 | Ansible Version: 2.15.3 2025-02-26 02:31:41.470927 | Job: python-black 2025-02-26 02:31:41.470956 | Pipeline: check 2025-02-26 02:31:41.470976 | Executor: 7d211f194f6a 2025-02-26 02:31:41.470995 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1771 2025-02-26 02:31:41.471013 | Event ID: c551b2d0-f3e9-11ef-9d4a-fce00588900c 2025-02-26 02:31:41.489678 | 2025-02-26 02:31:41.489779 | LOOP [emit-job-header : Print node information] 2025-02-26 02:31:41.686955 | ubuntu-noble | ok: 2025-02-26 02:31:41.687210 | ubuntu-noble | # Node Information 2025-02-26 02:31:41.687248 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-26 02:31:41.687273 | ubuntu-noble | Hostname: ubuntu 2025-02-26 02:31:41.687294 | ubuntu-noble | Username: zuul 2025-02-26 02:31:41.687315 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-26 02:31:41.687335 | ubuntu-noble | Provider: regiocloud-a 2025-02-26 02:31:41.687354 | ubuntu-noble | Label: ubuntu-noble 2025-02-26 02:31:41.687373 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-26 02:31:41.687393 | ubuntu-noble | Interface IP: 81.163.193.97 2025-02-26 02:31:41.702781 | 2025-02-26 02:31:41.702892 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-26 02:31:42.358335 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:42.367389 | 2025-02-26 02:31:42.367542 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-26 02:31:43.788749 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:43.810237 | 2025-02-26 02:31:43.810338 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-26 02:31:44.134803 | ubuntu-noble -> localhost | ok 2025-02-26 02:31:44.142102 | 2025-02-26 02:31:44.142196 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-26 02:31:44.191098 | ubuntu-noble | ok 2025-02-26 02:31:44.229670 | ubuntu-noble | included: /var/lib/zuul/builds/f30af36e19134bd982e14fc99e15408e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-26 02:31:44.261247 | 2025-02-26 02:31:44.261372 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-26 02:31:45.657510 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-26 02:31:45.657701 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f30af36e19134bd982e14fc99e15408e/work/f30af36e19134bd982e14fc99e15408e_id_rsa 2025-02-26 02:31:45.657733 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f30af36e19134bd982e14fc99e15408e/work/f30af36e19134bd982e14fc99e15408e_id_rsa.pub 2025-02-26 02:31:45.657754 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-26 02:31:45.657773 | ubuntu-noble -> localhost | SHA256:kclE9Wzlyqh8y8Zw1Pm3R5ByUXX6HVrfhMrdiqMfO4Q zuul-build-sshkey 2025-02-26 02:31:45.657791 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-26 02:31:45.657812 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-26 02:31:45.657829 | ubuntu-noble -> localhost | | .o.. ..=| 2025-02-26 02:31:45.657846 | ubuntu-noble -> localhost | | o o o o.o.| 2025-02-26 02:31:45.657863 | ubuntu-noble -> localhost | | = .+.+=.| 2025-02-26 02:31:45.657878 | ubuntu-noble -> localhost | | ..=++*+=| 2025-02-26 02:31:45.657894 | ubuntu-noble -> localhost | | S...==..*| 2025-02-26 02:31:45.657910 | ubuntu-noble -> localhost | | ...E ....o| 2025-02-26 02:31:45.657925 | ubuntu-noble -> localhost | | o+..+ .o.| 2025-02-26 02:31:45.657941 | ubuntu-noble -> localhost | | ooo.+ ..| 2025-02-26 02:31:45.657958 | ubuntu-noble -> localhost | | .+.o. .| 2025-02-26 02:31:45.657974 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-26 02:31:45.658029 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.710073 2025-02-26 02:31:45.669383 | 2025-02-26 02:31:45.669463 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-26 02:31:45.709659 | ubuntu-noble | ok 2025-02-26 02:31:45.730459 | ubuntu-noble | included: /var/lib/zuul/builds/f30af36e19134bd982e14fc99e15408e/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-26 02:31:45.738026 | 2025-02-26 02:31:45.738101 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-26 02:31:45.771576 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:45.777528 | 2025-02-26 02:31:45.777619 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-26 02:31:46.538285 | ubuntu-noble | changed 2025-02-26 02:31:46.546000 | 2025-02-26 02:31:46.546091 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-26 02:31:46.861598 | ubuntu-noble | ok 2025-02-26 02:31:46.871829 | 2025-02-26 02:31:46.871935 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-26 02:31:47.667289 | ubuntu-noble | changed 2025-02-26 02:31:47.677165 | 2025-02-26 02:31:47.677264 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-26 02:31:48.435507 | ubuntu-noble | changed 2025-02-26 02:31:48.442718 | 2025-02-26 02:31:48.442806 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-26 02:31:48.466310 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:48.473769 | 2025-02-26 02:31:48.473854 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-26 02:31:49.071050 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:49.134392 | 2025-02-26 02:31:49.134505 | TASK [add-build-sshkey : Add back temp key] 2025-02-26 02:31:49.576763 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/f30af36e19134bd982e14fc99e15408e/work/f30af36e19134bd982e14fc99e15408e_id_rsa (zuul-build-sshkey) 2025-02-26 02:31:49.576955 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.023077 2025-02-26 02:31:49.584434 | 2025-02-26 02:31:49.584553 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-26 02:31:50.048017 | ubuntu-noble | ok 2025-02-26 02:31:50.054344 | 2025-02-26 02:31:50.054433 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-26 02:31:50.078023 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:50.090485 | 2025-02-26 02:31:50.090574 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-26 02:31:50.548641 | ubuntu-noble | ok 2025-02-26 02:31:50.564763 | 2025-02-26 02:31:50.564864 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-26 02:31:50.596831 | ubuntu-noble | ok 2025-02-26 02:31:50.603337 | 2025-02-26 02:31:50.603427 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-26 02:31:50.860611 | ubuntu-noble -> localhost | ok 2025-02-26 02:31:50.867995 | 2025-02-26 02:31:50.868085 | TASK [validate-host : Collect information about the host] 2025-02-26 02:31:52.155355 | ubuntu-noble | ok 2025-02-26 02:31:52.169504 | 2025-02-26 02:31:52.169594 | TASK [validate-host : Sanitize hostname] 2025-02-26 02:31:52.220499 | ubuntu-noble | ok 2025-02-26 02:31:52.227151 | 2025-02-26 02:31:52.227230 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-26 02:31:52.744211 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:52.750990 | 2025-02-26 02:31:52.751078 | TASK [validate-host : Collect information about zuul worker] 2025-02-26 02:31:53.300280 | ubuntu-noble | ok 2025-02-26 02:31:53.308584 | 2025-02-26 02:31:53.308680 | TASK [validate-host : Write out all zuul information for each host] 2025-02-26 02:31:53.842301 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:53.852113 | 2025-02-26 02:31:53.852195 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-26 02:31:54.214878 | ubuntu-noble | ok 2025-02-26 02:31:54.220193 | 2025-02-26 02:31:54.220268 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-26 02:31:55.622768 | ubuntu-noble | changed: 2025-02-26 02:31:55.622986 | ubuntu-noble | cd+++++++++ src/ 2025-02-26 02:31:55.623017 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-26 02:31:55.623037 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-26 02:31:55.623064 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-26 02:31:55.623082 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004622 2025-02-26 02:32:31.366714 | 2025-02-26 02:32:31.366883 | TASK [upload-logs : Upload logs to log server] 2025-02-26 02:32:31.970128 | localhost | Output suppressed because no_log was given 2025-02-26 02:32:31.973534 | 2025-02-26 02:32:31.973642 | LOOP [upload-logs : Compress console log and json output] 2025-02-26 02:32:32.046731 | localhost | skipping: Conditional result was False 2025-02-26 02:32:32.065300 | localhost | skipping: Conditional result was False 2025-02-26 02:32:32.074769 | 2025-02-26 02:32:32.074891 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-26 02:32:32.140304 | localhost | skipping: Conditional result was False 2025-02-26 02:32:32.141213 | 2025-02-26 02:32:32.153285 | localhost | skipping: Conditional result was False 2025-02-26 02:32:32.165096 | 2025-02-26 02:32:32.165212 | LOOP [upload-logs : Upload console log and json output]