2025-04-11 22:14:56.704570 | Job console starting... 2025-04-11 22:14:56.756020 | Updating repositories 2025-04-11 22:14:56.785306 | Preparing job workspace 2025-04-11 22:14:58.775466 | Running Ansible setup... 2025-04-11 22:15:03.944548 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-04-11 22:15:04.781994 | 2025-04-11 22:15:04.782152 | PLAY [Base pre] 2025-04-11 22:15:04.811452 | 2025-04-11 22:15:04.811593 | TASK [Setup log path fact] 2025-04-11 22:15:04.852920 | ubuntu-noble | ok 2025-04-11 22:15:04.871686 | 2025-04-11 22:15:04.871827 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-04-11 22:15:04.902350 | ubuntu-noble | ok 2025-04-11 22:15:04.915176 | 2025-04-11 22:15:04.915289 | TASK [emit-job-header : Print job information] 2025-04-11 22:15:04.966838 | # Job Information 2025-04-11 22:15:04.967032 | Ansible Version: 2.15.3 2025-04-11 22:15:04.967067 | Job: python-black 2025-04-11 22:15:04.967096 | Pipeline: check 2025-04-11 22:15:04.967116 | Executor: 7d211f194f6a 2025-04-11 22:15:04.967136 | Triggered by: https://github.com/osism/testbed/pull/2654 2025-04-11 22:15:04.967154 | Event ID: 61df0ca0-1722-11f0-8c73-9298f2e5f5f7 2025-04-11 22:15:04.973625 | 2025-04-11 22:15:04.973736 | LOOP [emit-job-header : Print node information] 2025-04-11 22:15:05.115688 | ubuntu-noble | ok: 2025-04-11 22:15:05.115883 | ubuntu-noble | # Node Information 2025-04-11 22:15:05.115916 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-04-11 22:15:05.115940 | ubuntu-noble | Hostname: ubuntu 2025-04-11 22:15:05.115961 | ubuntu-noble | Username: zuul 2025-04-11 22:15:05.115982 | ubuntu-noble | Distro: Ubuntu 24.04 2025-04-11 22:15:05.116005 | ubuntu-noble | Provider: regiocloud-a 2025-04-11 22:15:05.116025 | ubuntu-noble | Label: ubuntu-noble 2025-04-11 22:15:05.116046 | ubuntu-noble | Product Name: OpenStack Nova 2025-04-11 22:15:05.116065 | ubuntu-noble | Interface IP: 81.163.192.191 2025-04-11 22:15:05.138314 | 2025-04-11 22:15:05.138484 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-04-11 22:15:05.706082 | ubuntu-noble -> localhost | changed 2025-04-11 22:15:05.723532 | 2025-04-11 22:15:05.723694 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-04-11 22:15:06.748620 | ubuntu-noble -> localhost | changed 2025-04-11 22:15:06.763335 | 2025-04-11 22:15:06.763462 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-04-11 22:15:07.074231 | ubuntu-noble -> localhost | ok 2025-04-11 22:15:07.088859 | 2025-04-11 22:15:07.089033 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-04-11 22:15:07.120404 | ubuntu-noble | ok 2025-04-11 22:15:07.136563 | ubuntu-noble | included: /var/lib/zuul/builds/466a0ed365cf415ca7550560ecea4f00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-04-11 22:15:07.143983 | 2025-04-11 22:15:07.144071 | TASK [add-build-sshkey : Create Temp SSH key] 2025-04-11 22:15:07.778327 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-04-11 22:15:07.778520 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/466a0ed365cf415ca7550560ecea4f00/work/466a0ed365cf415ca7550560ecea4f00_id_rsa 2025-04-11 22:15:07.778555 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/466a0ed365cf415ca7550560ecea4f00/work/466a0ed365cf415ca7550560ecea4f00_id_rsa.pub 2025-04-11 22:15:07.778580 | ubuntu-noble -> localhost | The key fingerprint is: 2025-04-11 22:15:07.778603 | ubuntu-noble -> localhost | SHA256:CBp/7m6gmoz0KvXjdneXHkB1jqizESS5lFW57Z8Zp5g zuul-build-sshkey 2025-04-11 22:15:07.778624 | ubuntu-noble -> localhost | The key's randomart image is: 2025-04-11 22:15:07.778645 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-04-11 22:15:07.778665 | ubuntu-noble -> localhost | | .+o.... . | 2025-04-11 22:15:07.778684 | ubuntu-noble -> localhost | | +o .o + | 2025-04-11 22:15:07.778703 | ubuntu-noble -> localhost | | . .. .. oo. . | 2025-04-11 22:15:07.778735 | ubuntu-noble -> localhost | | + ... +. . | 2025-04-11 22:15:07.778756 | ubuntu-noble -> localhost | | . . o S .. | 2025-04-11 22:15:07.778774 | ubuntu-noble -> localhost | | . .o + .. . .| 2025-04-11 22:15:07.778798 | ubuntu-noble -> localhost | | o o .. . .= * | 2025-04-11 22:15:07.778818 | ubuntu-noble -> localhost | |=.o +.o . . E.= | 2025-04-11 22:15:07.778838 | ubuntu-noble -> localhost | |=+.+.=o. . o. | 2025-04-11 22:15:07.778870 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-04-11 22:15:07.778918 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.159170 2025-04-11 22:15:07.787331 | 2025-04-11 22:15:07.787455 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-04-11 22:15:07.825805 | ubuntu-noble | ok 2025-04-11 22:15:07.840061 | ubuntu-noble | included: /var/lib/zuul/builds/466a0ed365cf415ca7550560ecea4f00/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-04-11 22:15:07.849731 | 2025-04-11 22:15:07.849816 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-04-11 22:15:07.873474 | ubuntu-noble | skipping: Conditional result was False 2025-04-11 22:15:07.880613 | 2025-04-11 22:15:07.880700 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-04-11 22:15:08.588290 | ubuntu-noble | changed 2025-04-11 22:15:08.599316 | 2025-04-11 22:15:08.599491 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-04-11 22:15:08.893812 | ubuntu-noble | ok 2025-04-11 22:15:08.904312 | 2025-04-11 22:15:08.904452 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-04-11 22:15:09.642973 | ubuntu-noble | changed 2025-04-11 22:15:09.653466 | 2025-04-11 22:15:09.653582 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-04-11 22:15:10.362288 | ubuntu-noble | changed 2025-04-11 22:15:10.369136 | 2025-04-11 22:15:10.369223 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-04-11 22:15:10.392672 | ubuntu-noble | skipping: Conditional result was False 2025-04-11 22:15:10.399992 | 2025-04-11 22:15:10.400080 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-04-11 22:15:10.778633 | ubuntu-noble -> localhost | changed 2025-04-11 22:15:10.792629 | 2025-04-11 22:15:10.792732 | TASK [add-build-sshkey : Add back temp key] 2025-04-11 22:15:11.102494 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/466a0ed365cf415ca7550560ecea4f00/work/466a0ed365cf415ca7550560ecea4f00_id_rsa (zuul-build-sshkey) 2025-04-11 22:15:11.102697 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.016776 2025-04-11 22:15:11.110884 | 2025-04-11 22:15:11.110973 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-04-11 22:15:11.533813 | ubuntu-noble | ok 2025-04-11 22:15:11.545278 | 2025-04-11 22:15:11.545421 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-04-11 22:15:11.580412 | ubuntu-noble | skipping: Conditional result was False 2025-04-11 22:15:11.593720 | 2025-04-11 22:15:11.593812 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-04-11 22:15:12.031689 | ubuntu-noble | ok 2025-04-11 22:15:12.067533 | 2025-04-11 22:15:12.067625 | TASK [validate-host : Define zuul_info_dir fact] 2025-04-11 22:15:12.106239 | ubuntu-noble | ok 2025-04-11 22:15:12.116751 | 2025-04-11 22:15:12.116898 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-04-11 22:15:12.386509 | ubuntu-noble -> localhost | ok 2025-04-11 22:15:12.403334 | 2025-04-11 22:15:12.403506 | TASK [validate-host : Collect information about the host] 2025-04-11 22:15:13.517477 | ubuntu-noble | ok 2025-04-11 22:15:13.530879 | 2025-04-11 22:15:13.530994 | TASK [validate-host : Sanitize hostname] 2025-04-11 22:15:13.603859 | ubuntu-noble | ok 2025-04-11 22:15:13.613421 | 2025-04-11 22:15:13.613574 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-04-11 22:15:14.152128 | ubuntu-noble -> localhost | changed 2025-04-11 22:15:14.159871 | 2025-04-11 22:15:14.160003 | TASK [validate-host : Collect information about zuul worker] 2025-04-11 22:15:14.623193 | ubuntu-noble | ok 2025-04-11 22:15:14.629863 | 2025-04-11 22:15:14.629978 | TASK [validate-host : Write out all zuul information for each host] 2025-04-11 22:15:15.194446 | ubuntu-noble -> localhost | changed 2025-04-11 22:15:15.210695 | 2025-04-11 22:15:15.210828 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-04-11 22:15:15.508991 | ubuntu-noble | ok 2025-04-11 22:15:15.516571 | 2025-04-11 22:15:15.516685 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-04-11 22:15:16.727718 | ubuntu-noble | changed: 2025-04-11 22:15:16.727912 | ubuntu-noble | cd+++++++++ src/ 2025-04-11 22:15:16.727947 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-04-11 22:15:16.727972 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-04-11 22:15:16.727993 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-04-11 22:15:16.728013 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007300 2025-04-11 22:15:47.935183 | 2025-04-11 22:15:47.935328 | TASK [upload-logs : Upload logs to log server] 2025-04-11 22:15:48.526888 | localhost | Output suppressed because no_log was given 2025-04-11 22:15:48.530514 | 2025-04-11 22:15:48.530630 | LOOP [upload-logs : Compress console log and json output] 2025-04-11 22:15:48.605614 | localhost | skipping: Conditional result was False 2025-04-11 22:15:48.626742 | localhost | skipping: Conditional result was False 2025-04-11 22:15:48.631554 | 2025-04-11 22:15:48.631693 | LOOP [upload-logs : Upload compressed console log and json output] 2025-04-11 22:15:48.724027 | localhost | skipping: Conditional result was False 2025-04-11 22:15:48.724346 | 2025-04-11 22:15:48.744088 | localhost | skipping: Conditional result was False 2025-04-11 22:15:48.751472 | 2025-04-11 22:15:48.751634 | LOOP [upload-logs : Upload console log and json output]