2025-02-27 18:18:40.522881 | Job console starting... 2025-02-27 18:18:40.533946 | Updating repositories 2025-02-27 18:18:40.619408 | Preparing job workspace 2025-02-27 18:18:42.971037 | Running Ansible setup... 2025-02-27 18:18:50.010499 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-27 18:18:51.376433 | 2025-02-27 18:18:51.376611 | PLAY [Base pre] 2025-02-27 18:18:51.416992 | 2025-02-27 18:18:51.417140 | TASK [Setup log path fact] 2025-02-27 18:18:51.495894 | ubuntu-noble | ok 2025-02-27 18:18:51.551405 | 2025-02-27 18:18:51.551606 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-27 18:18:51.645238 | ubuntu-noble | ok 2025-02-27 18:18:51.658484 | 2025-02-27 18:18:51.659523 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-27 18:18:51.748471 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:51.773982 | 2025-02-27 18:18:51.774147 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-27 18:18:51.861129 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:51.870693 | 2025-02-27 18:18:51.870829 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-27 18:18:51.921929 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:51.929958 | 2025-02-27 18:18:51.930063 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-27 18:18:51.976268 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:51.983621 | 2025-02-27 18:18:51.983717 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-27 18:18:52.035910 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:52.060683 | 2025-02-27 18:18:52.060795 | TASK [emit-job-header : Print job information] 2025-02-27 18:18:52.130281 | # Job Information 2025-02-27 18:18:52.130466 | Ansible Version: 2.15.3 2025-02-27 18:18:52.130498 | Job: python-black 2025-02-27 18:18:52.130526 | Pipeline: check 2025-02-27 18:18:52.130546 | Executor: 7d211f194f6a 2025-02-27 18:18:52.130565 | Triggered by: https://github.com/osism/python-osism/pull/1191 2025-02-27 18:18:52.130583 | Event ID: 407301a0-f537-11ef-8c15-69a4a17a24cd 2025-02-27 18:18:52.148909 | 2025-02-27 18:18:52.149002 | LOOP [emit-job-header : Print node information] 2025-02-27 18:18:52.363890 | ubuntu-noble | ok: 2025-02-27 18:18:52.364128 | ubuntu-noble | # Node Information 2025-02-27 18:18:52.364163 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-27 18:18:52.364187 | ubuntu-noble | Hostname: ubuntu 2025-02-27 18:18:52.364208 | ubuntu-noble | Username: zuul 2025-02-27 18:18:52.364227 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-27 18:18:52.364247 | ubuntu-noble | Provider: regiocloud-a 2025-02-27 18:18:52.364265 | ubuntu-noble | Label: ubuntu-noble 2025-02-27 18:18:52.364284 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-27 18:18:52.364316 | ubuntu-noble | Interface IP: 81.163.193.73 2025-02-27 18:18:52.381870 | 2025-02-27 18:18:52.381990 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-27 18:18:53.228161 | ubuntu-noble -> localhost | changed 2025-02-27 18:18:53.235463 | 2025-02-27 18:18:53.235541 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-27 18:18:54.569216 | ubuntu-noble -> localhost | changed 2025-02-27 18:18:54.581491 | 2025-02-27 18:18:54.581577 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-27 18:18:55.080044 | ubuntu-noble -> localhost | ok 2025-02-27 18:18:55.086522 | 2025-02-27 18:18:55.086606 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-27 18:18:55.147537 | ubuntu-noble | ok 2025-02-27 18:18:55.166430 | ubuntu-noble | included: /var/lib/zuul/builds/e00e43f653114fd5a3e148e0ceb49029/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-27 18:18:55.196573 | 2025-02-27 18:18:55.196730 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-27 18:18:56.450171 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-27 18:18:56.450389 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e00e43f653114fd5a3e148e0ceb49029/work/e00e43f653114fd5a3e148e0ceb49029_id_rsa 2025-02-27 18:18:56.450420 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e00e43f653114fd5a3e148e0ceb49029/work/e00e43f653114fd5a3e148e0ceb49029_id_rsa.pub 2025-02-27 18:18:56.450441 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-27 18:18:56.450460 | ubuntu-noble -> localhost | SHA256:6+CRXyMD20c3r7CS9voeAKhxFK5GtPHPzwQzEDJwjec zuul-build-sshkey 2025-02-27 18:18:56.450479 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-27 18:18:56.450499 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-27 18:18:56.450517 | ubuntu-noble -> localhost | |..*o=o | 2025-02-27 18:18:56.450534 | ubuntu-noble -> localhost | | o.Ooo | 2025-02-27 18:18:56.450550 | ubuntu-noble -> localhost | | +o= = | 2025-02-27 18:18:56.450566 | ubuntu-noble -> localhost | | . =Eo = | 2025-02-27 18:18:56.450582 | ubuntu-noble -> localhost | | + + S . o | 2025-02-27 18:18:56.450598 | ubuntu-noble -> localhost | | . O + . o | 2025-02-27 18:18:56.450614 | ubuntu-noble -> localhost | | = B.* . | 2025-02-27 18:18:56.450631 | ubuntu-noble -> localhost | | . =+= = . | 2025-02-27 18:18:56.450648 | ubuntu-noble -> localhost | | ..+*= . | 2025-02-27 18:18:56.450663 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-27 18:18:56.450707 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.527945 2025-02-27 18:18:56.457063 | 2025-02-27 18:18:56.457152 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-27 18:18:56.516641 | ubuntu-noble | ok 2025-02-27 18:18:56.530648 | ubuntu-noble | included: /var/lib/zuul/builds/e00e43f653114fd5a3e148e0ceb49029/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-27 18:18:56.551601 | 2025-02-27 18:18:56.551702 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-27 18:18:56.595881 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:56.602106 | 2025-02-27 18:18:56.602199 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-27 18:18:57.432398 | ubuntu-noble | changed 2025-02-27 18:18:57.440099 | 2025-02-27 18:18:57.440179 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-27 18:18:57.786852 | ubuntu-noble | ok 2025-02-27 18:18:57.797454 | 2025-02-27 18:18:57.797549 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-27 18:18:58.595443 | ubuntu-noble | changed 2025-02-27 18:18:58.604334 | 2025-02-27 18:18:58.604464 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-27 18:18:59.435456 | ubuntu-noble | changed 2025-02-27 18:18:59.451943 | 2025-02-27 18:18:59.452565 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-27 18:18:59.498230 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:18:59.518882 | 2025-02-27 18:18:59.518985 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-27 18:19:00.663210 | ubuntu-noble -> localhost | changed 2025-02-27 18:19:00.690496 | 2025-02-27 18:19:00.690595 | TASK [add-build-sshkey : Add back temp key] 2025-02-27 18:19:01.314197 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e00e43f653114fd5a3e148e0ceb49029/work/e00e43f653114fd5a3e148e0ceb49029_id_rsa (zuul-build-sshkey) 2025-02-27 18:19:01.314478 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.012056 2025-02-27 18:19:01.323842 | 2025-02-27 18:19:01.323940 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-27 18:19:02.115410 | ubuntu-noble | ok 2025-02-27 18:19:02.122980 | 2025-02-27 18:19:02.123071 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-27 18:19:02.178950 | ubuntu-noble | skipping: Conditional result was False 2025-02-27 18:19:02.215995 | 2025-02-27 18:19:02.216478 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-27 18:19:02.784428 | ubuntu-noble | ok 2025-02-27 18:19:02.798492 | 2025-02-27 18:19:02.798614 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-27 18:19:02.843058 | ubuntu-noble | ok 2025-02-27 18:19:02.855005 | 2025-02-27 18:19:02.855092 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-27 18:19:03.226083 | ubuntu-noble -> localhost | ok 2025-02-27 18:19:03.233869 | 2025-02-27 18:19:03.234025 | TASK [validate-host : Collect information about the host] 2025-02-27 18:19:04.517844 | ubuntu-noble | ok 2025-02-27 18:19:04.532665 | 2025-02-27 18:19:04.532757 | TASK [validate-host : Sanitize hostname] 2025-02-27 18:19:04.602372 | ubuntu-noble | ok 2025-02-27 18:19:04.611749 | 2025-02-27 18:19:04.611855 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-27 18:19:05.192588 | ubuntu-noble -> localhost | changed 2025-02-27 18:19:05.201439 | 2025-02-27 18:19:05.201529 | TASK [validate-host : Collect information about zuul worker] 2025-02-27 18:19:05.825148 | ubuntu-noble | ok 2025-02-27 18:19:05.837514 | 2025-02-27 18:19:05.837779 | TASK [validate-host : Write out all zuul information for each host] 2025-02-27 18:19:06.495140 | ubuntu-noble -> localhost | changed 2025-02-27 18:19:06.515475 | 2025-02-27 18:19:06.515589 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-27 18:19:06.920660 | ubuntu-noble | ok 2025-02-27 18:19:06.931185 | 2025-02-27 18:19:06.931347 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-27 18:19:08.232221 | ubuntu-noble | changed: 2025-02-27 18:19:08.232449 | ubuntu-noble | cd+++++++++ src/ 2025-02-27 18:19:08.232485 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-27 18:19:08.232509 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-27 18:19:08.232531 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-02-27 18:19:08.232551 | ubuntu-noble | Containerfile 2025-02-27 18:19:08.232662 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.017733 2025-02-27 18:19:49.442620 | 2025-02-27 18:19:49.442743 | TASK [upload-logs : Upload logs to log server] 2025-02-27 18:19:50.133197 | localhost | Output suppressed because no_log was given 2025-02-27 18:19:50.136837 | 2025-02-27 18:19:50.136955 | LOOP [upload-logs : Compress console log and json output] 2025-02-27 18:19:50.215304 | localhost | skipping: Conditional result was False 2025-02-27 18:19:50.228725 | localhost | skipping: Conditional result was False 2025-02-27 18:19:50.240201 | 2025-02-27 18:19:50.240383 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-27 18:19:50.320213 | localhost | skipping: Conditional result was False 2025-02-27 18:19:50.320622 | 2025-02-27 18:19:50.346821 | localhost | skipping: Conditional result was False 2025-02-27 18:19:50.355601 | 2025-02-27 18:19:50.355989 | LOOP [upload-logs : Upload console log and json output]