2025-01-24 17:06:36.120822 | Job console starting... 2025-01-24 17:06:36.145454 | Updating repositories 2025-01-24 17:06:36.189082 | Preparing job workspace 2025-01-24 17:06:37.757525 | Running Ansible setup... 2025-01-24 17:06:42.716068 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-24 17:06:43.438875 | 2025-01-24 17:06:43.439051 | PLAY [Base pre] 2025-01-24 17:06:43.469276 | 2025-01-24 17:06:43.469441 | TASK [Setup log path fact] 2025-01-24 17:06:43.501332 | ubuntu-noble | ok 2025-01-24 17:06:43.523877 | 2025-01-24 17:06:43.524037 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-24 17:06:43.575865 | ubuntu-noble | ok 2025-01-24 17:06:43.582675 | 2025-01-24 17:06:43.582804 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-24 17:06:43.628169 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:43.635953 | 2025-01-24 17:06:43.636105 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-24 17:06:43.681484 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:43.689258 | 2025-01-24 17:06:43.689391 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-24 17:06:43.708140 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:43.715729 | 2025-01-24 17:06:43.715853 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-24 17:06:43.750495 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:43.758138 | 2025-01-24 17:06:43.758296 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-24 17:06:43.782901 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:43.798231 | 2025-01-24 17:06:43.798382 | TASK [emit-job-header : Print job information] 2025-01-24 17:06:43.861195 | # Job Information 2025-01-24 17:06:43.861399 | Ansible Version: 2.15.3 2025-01-24 17:06:43.861433 | Job: python-black 2025-01-24 17:06:43.861461 | Pipeline: check 2025-01-24 17:06:43.861482 | Executor: 7d211f194f6a 2025-01-24 17:06:43.861501 | Triggered by: https://github.com/osism/container-images-kolla/pull/537 2025-01-24 17:06:43.861519 | Event ID: 8d632ad0-da75-11ef-81a6-6208194b3179 2025-01-24 17:06:43.868021 | 2025-01-24 17:06:43.868141 | LOOP [emit-job-header : Print node information] 2025-01-24 17:06:44.011487 | ubuntu-noble | ok: 2025-01-24 17:06:44.011674 | ubuntu-noble | # Node Information 2025-01-24 17:06:44.011708 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-24 17:06:44.011731 | ubuntu-noble | Hostname: ubuntu 2025-01-24 17:06:44.011753 | ubuntu-noble | Username: zuul 2025-01-24 17:06:44.011772 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-24 17:06:44.011791 | ubuntu-noble | Provider: regiocloud-a 2025-01-24 17:06:44.011811 | ubuntu-noble | Label: ubuntu-noble 2025-01-24 17:06:44.011829 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-24 17:06:44.011850 | ubuntu-noble | Interface IP: 81.163.192.173 2025-01-24 17:06:44.027871 | 2025-01-24 17:06:44.028003 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-24 17:06:44.503319 | ubuntu-noble -> localhost | changed 2025-01-24 17:06:44.512117 | 2025-01-24 17:06:44.512255 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-24 17:06:45.586544 | ubuntu-noble -> localhost | changed 2025-01-24 17:06:45.602819 | 2025-01-24 17:06:45.602944 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-24 17:06:45.888895 | ubuntu-noble -> localhost | ok 2025-01-24 17:06:45.897325 | 2025-01-24 17:06:45.897458 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-24 17:06:45.948115 | ubuntu-noble | ok 2025-01-24 17:06:45.964924 | ubuntu-noble | included: /var/lib/zuul/builds/c402e2d7651948c39dbd9ef6e1bb4a87/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-24 17:06:45.973052 | 2025-01-24 17:06:45.973155 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-24 17:06:46.730276 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-24 17:06:46.730506 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/c402e2d7651948c39dbd9ef6e1bb4a87/work/c402e2d7651948c39dbd9ef6e1bb4a87_id_rsa 2025-01-24 17:06:46.730544 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/c402e2d7651948c39dbd9ef6e1bb4a87/work/c402e2d7651948c39dbd9ef6e1bb4a87_id_rsa.pub 2025-01-24 17:06:46.730569 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-24 17:06:46.730593 | ubuntu-noble -> localhost | SHA256:Hhsjy3lLaqcKQl1r9TDN/Sp1A7F1Rpi9yQAkRRIvTHU zuul-build-sshkey 2025-01-24 17:06:46.730617 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-24 17:06:46.730643 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-24 17:06:46.730664 | ubuntu-noble -> localhost | | =*B.E=+ | 2025-01-24 17:06:46.730685 | ubuntu-noble -> localhost | | = = =+o. | 2025-01-24 17:06:46.730704 | ubuntu-noble -> localhost | | . + = = o o| 2025-01-24 17:06:46.730724 | ubuntu-noble -> localhost | | . . o + . o + | 2025-01-24 17:06:46.730743 | ubuntu-noble -> localhost | | . . o. S. . + | 2025-01-24 17:06:46.730763 | ubuntu-noble -> localhost | |. .. = =. o . | 2025-01-24 17:06:46.730782 | ubuntu-noble -> localhost | |. . + =. . | 2025-01-24 17:06:46.730803 | ubuntu-noble -> localhost | | . . .+... | 2025-01-24 17:06:46.730823 | ubuntu-noble -> localhost | | .ooo. | 2025-01-24 17:06:46.730842 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-24 17:06:46.730893 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.209389 2025-01-24 17:06:46.738716 | 2025-01-24 17:06:46.738824 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-24 17:06:46.769603 | ubuntu-noble | ok 2025-01-24 17:06:46.781401 | ubuntu-noble | included: /var/lib/zuul/builds/c402e2d7651948c39dbd9ef6e1bb4a87/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-24 17:06:46.791132 | 2025-01-24 17:06:46.791266 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-24 17:06:46.815873 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:46.825172 | 2025-01-24 17:06:46.825309 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-24 17:06:47.473398 | ubuntu-noble | changed 2025-01-24 17:06:47.480641 | 2025-01-24 17:06:47.480757 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-24 17:06:47.747074 | ubuntu-noble | ok 2025-01-24 17:06:47.754097 | 2025-01-24 17:06:47.754245 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-24 17:06:48.432712 | ubuntu-noble | changed 2025-01-24 17:06:48.439763 | 2025-01-24 17:06:48.439872 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-24 17:06:49.072646 | ubuntu-noble | changed 2025-01-24 17:06:49.082884 | 2025-01-24 17:06:49.083017 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-24 17:06:49.125043 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:49.134055 | 2025-01-24 17:06:49.134221 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-24 17:06:49.690310 | ubuntu-noble -> localhost | changed 2025-01-24 17:06:49.706349 | 2025-01-24 17:06:49.706493 | TASK [add-build-sshkey : Add back temp key] 2025-01-24 17:06:50.102528 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/c402e2d7651948c39dbd9ef6e1bb4a87/work/c402e2d7651948c39dbd9ef6e1bb4a87_id_rsa (zuul-build-sshkey) 2025-01-24 17:06:50.102771 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.021152 2025-01-24 17:06:50.110732 | 2025-01-24 17:06:50.110850 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-24 17:06:50.692694 | ubuntu-noble | ok 2025-01-24 17:06:50.699604 | 2025-01-24 17:06:50.699724 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-24 17:06:50.735272 | ubuntu-noble | skipping: Conditional result was False 2025-01-24 17:06:50.759570 | 2025-01-24 17:06:50.759702 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-24 17:06:51.217082 | ubuntu-noble | ok 2025-01-24 17:06:51.248580 | 2025-01-24 17:06:51.248734 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-24 17:06:51.349466 | ubuntu-noble | ok 2025-01-24 17:06:51.356478 | 2025-01-24 17:06:51.356599 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-24 17:06:52.076896 | ubuntu-noble -> localhost | ok 2025-01-24 17:06:52.092100 | 2025-01-24 17:06:52.092255 | TASK [validate-host : Collect information about the host] 2025-01-24 17:06:53.321321 | ubuntu-noble | ok 2025-01-24 17:06:53.361232 | 2025-01-24 17:06:53.361716 | TASK [validate-host : Sanitize hostname] 2025-01-24 17:06:53.436198 | ubuntu-noble | ok 2025-01-24 17:06:53.442582 | 2025-01-24 17:06:53.442702 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-24 17:06:54.002737 | ubuntu-noble -> localhost | changed 2025-01-24 17:06:54.010267 | 2025-01-24 17:06:54.010401 | TASK [validate-host : Collect information about zuul worker] 2025-01-24 17:06:54.518959 | ubuntu-noble | ok 2025-01-24 17:06:54.531244 | 2025-01-24 17:06:54.531386 | TASK [validate-host : Write out all zuul information for each host] 2025-01-24 17:06:55.223325 | ubuntu-noble -> localhost | changed 2025-01-24 17:06:55.244596 | 2025-01-24 17:06:55.244768 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-24 17:06:55.552476 | ubuntu-noble | ok 2025-01-24 17:06:55.559814 | 2025-01-24 17:06:55.559928 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-24 17:06:56.682108 | ubuntu-noble | changed: 2025-01-24 17:06:56.682312 | ubuntu-noble | cd+++++++++ src/ 2025-01-24 17:06:56.682347 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-24 17:06:56.682371 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-24 17:06:56.682394 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-images-kolla/ 2025-01-24 17:06:56.682414 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004425 2025-01-24 17:07:24.652272 | 2025-01-24 17:07:24.652412 | TASK [upload-logs : Upload logs to log server] 2025-01-24 17:07:25.226809 | localhost | Output suppressed because no_log was given 2025-01-24 17:07:25.233805 | 2025-01-24 17:07:25.233987 | LOOP [upload-logs : Compress console log and json output] 2025-01-24 17:07:25.305941 | localhost | skipping: Conditional result was False 2025-01-24 17:07:25.325617 | localhost | skipping: Conditional result was False 2025-01-24 17:07:25.342298 | 2025-01-24 17:07:25.342502 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-24 17:07:25.431139 | localhost | skipping: Conditional result was False 2025-01-24 17:07:25.432047 | 2025-01-24 17:07:25.455907 | localhost | skipping: Conditional result was False 2025-01-24 17:07:25.466518 | 2025-01-24 17:07:25.466629 | LOOP [upload-logs : Upload console log and json output]