2025-03-22 14:57:36.738215 | Job console starting... 2025-03-22 14:57:36.790975 | Updating repositories 2025-03-22 14:57:36.951352 | Preparing job workspace 2025-03-22 14:57:38.774383 | Running Ansible setup... 2025-03-22 14:57:44.630907 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-22 14:57:45.663987 | 2025-03-22 14:57:45.664145 | PLAY [Base pre] 2025-03-22 14:57:45.721157 | 2025-03-22 14:57:45.721324 | TASK [Setup log path fact] 2025-03-22 14:57:45.753663 | ubuntu-noble | ok 2025-03-22 14:57:45.779081 | 2025-03-22 14:57:45.779230 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-22 14:57:45.841325 | ubuntu-noble | ok 2025-03-22 14:57:45.848026 | 2025-03-22 14:57:45.848138 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-22 14:57:45.895316 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:45.910579 | 2025-03-22 14:57:45.910739 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-22 14:57:45.998303 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:46.010075 | 2025-03-22 14:57:46.010228 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-22 14:57:46.049650 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:46.061739 | 2025-03-22 14:57:46.061868 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-22 14:57:46.100585 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:46.110715 | 2025-03-22 14:57:46.110846 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-22 14:57:46.135968 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:46.152211 | 2025-03-22 14:57:46.152326 | TASK [emit-job-header : Print job information] 2025-03-22 14:57:46.248739 | # Job Information 2025-03-22 14:57:46.248957 | Ansible Version: 2.15.3 2025-03-22 14:57:46.248990 | Job: python-black 2025-03-22 14:57:46.249019 | Pipeline: check 2025-03-22 14:57:46.249039 | Executor: 7d211f194f6a 2025-03-22 14:57:46.249057 | Triggered by: https://github.com/osism/python-osism/pull/1239 2025-03-22 14:57:46.249075 | Event ID: f84e2900-072d-11f0-88bf-694ce0b4971d 2025-03-22 14:57:46.259737 | 2025-03-22 14:57:46.259849 | LOOP [emit-job-header : Print node information] 2025-03-22 14:57:46.501942 | ubuntu-noble | ok: 2025-03-22 14:57:46.502872 | ubuntu-noble | # Node Information 2025-03-22 14:57:46.502972 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-22 14:57:46.503000 | ubuntu-noble | Hostname: ubuntu 2025-03-22 14:57:46.503022 | ubuntu-noble | Username: zuul 2025-03-22 14:57:46.503043 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-22 14:57:46.503064 | ubuntu-noble | Provider: regiocloud-a 2025-03-22 14:57:46.503084 | ubuntu-noble | Label: ubuntu-noble 2025-03-22 14:57:46.503103 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-22 14:57:46.503125 | ubuntu-noble | Interface IP: 81.163.193.77 2025-03-22 14:57:46.525632 | 2025-03-22 14:57:46.525775 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-22 14:57:47.124788 | ubuntu-noble -> localhost | changed 2025-03-22 14:57:47.133360 | 2025-03-22 14:57:47.133449 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-22 14:57:48.368068 | ubuntu-noble -> localhost | changed 2025-03-22 14:57:48.379126 | 2025-03-22 14:57:48.379209 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-22 14:57:48.721321 | ubuntu-noble -> localhost | ok 2025-03-22 14:57:48.730615 | 2025-03-22 14:57:48.730691 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-22 14:57:48.758431 | ubuntu-noble | ok 2025-03-22 14:57:48.771731 | ubuntu-noble | included: /var/lib/zuul/builds/10c367e13e78457791e3fad34a4d6b89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-22 14:57:48.777990 | 2025-03-22 14:57:48.778127 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-22 14:57:50.241606 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-22 14:57:50.241902 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/10c367e13e78457791e3fad34a4d6b89/work/10c367e13e78457791e3fad34a4d6b89_id_rsa 2025-03-22 14:57:50.241957 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/10c367e13e78457791e3fad34a4d6b89/work/10c367e13e78457791e3fad34a4d6b89_id_rsa.pub 2025-03-22 14:57:50.241994 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-22 14:57:50.242026 | ubuntu-noble -> localhost | SHA256:EX1b7pr8fHWSFPaM4ucSL4OZAM8zOIzLeZPyRtGzmG0 zuul-build-sshkey 2025-03-22 14:57:50.242057 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-22 14:57:50.242092 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-22 14:57:50.242123 | ubuntu-noble -> localhost | | .. | 2025-03-22 14:57:50.242152 | ubuntu-noble -> localhost | | .. . + | 2025-03-22 14:57:50.242180 | ubuntu-noble -> localhost | | .. . = = | 2025-03-22 14:57:50.242207 | ubuntu-noble -> localhost | | o o. o + o| 2025-03-22 14:57:50.242234 | ubuntu-noble -> localhost | | o XSo . + . | 2025-03-22 14:57:50.242260 | ubuntu-noble -> localhost | | . B E o = o| 2025-03-22 14:57:50.242286 | ubuntu-noble -> localhost | | . + + + = B .o| 2025-03-22 14:57:50.242332 | ubuntu-noble -> localhost | | = = + B.o .| 2025-03-22 14:57:50.242362 | ubuntu-noble -> localhost | | =.. =o. | 2025-03-22 14:57:50.242389 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-22 14:57:50.242459 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.817323 2025-03-22 14:57:50.252474 | 2025-03-22 14:57:50.252601 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-22 14:57:50.292265 | ubuntu-noble | ok 2025-03-22 14:57:50.305434 | ubuntu-noble | included: /var/lib/zuul/builds/10c367e13e78457791e3fad34a4d6b89/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-22 14:57:50.314245 | 2025-03-22 14:57:50.314312 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-22 14:57:50.338239 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:50.345623 | 2025-03-22 14:57:50.345715 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-22 14:57:51.295461 | ubuntu-noble | changed 2025-03-22 14:57:51.304611 | 2025-03-22 14:57:51.304728 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-22 14:57:51.685799 | ubuntu-noble | ok 2025-03-22 14:57:51.706446 | 2025-03-22 14:57:51.706571 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-22 14:57:52.482291 | ubuntu-noble | changed 2025-03-22 14:57:52.489828 | 2025-03-22 14:57:52.489914 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-22 14:57:53.210714 | ubuntu-noble | changed 2025-03-22 14:57:53.217856 | 2025-03-22 14:57:53.217952 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-22 14:57:53.263254 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:53.271161 | 2025-03-22 14:57:53.271253 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-22 14:57:53.661129 | ubuntu-noble -> localhost | changed 2025-03-22 14:57:53.673352 | 2025-03-22 14:57:53.673435 | TASK [add-build-sshkey : Add back temp key] 2025-03-22 14:57:54.047741 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/10c367e13e78457791e3fad34a4d6b89/work/10c367e13e78457791e3fad34a4d6b89_id_rsa (zuul-build-sshkey) 2025-03-22 14:57:54.047982 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.006394 2025-03-22 14:57:54.059414 | 2025-03-22 14:57:54.059509 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-22 14:57:54.496369 | ubuntu-noble | ok 2025-03-22 14:57:54.507257 | 2025-03-22 14:57:54.507346 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-22 14:57:54.571800 | ubuntu-noble | skipping: Conditional result was False 2025-03-22 14:57:54.584888 | 2025-03-22 14:57:54.584986 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-22 14:57:55.055445 | ubuntu-noble | ok 2025-03-22 14:57:55.069677 | 2025-03-22 14:57:55.069766 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-22 14:57:55.101011 | ubuntu-noble | ok 2025-03-22 14:57:55.107509 | 2025-03-22 14:57:55.107604 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-22 14:57:55.358771 | ubuntu-noble -> localhost | ok 2025-03-22 14:57:55.373512 | 2025-03-22 14:57:55.373666 | TASK [validate-host : Collect information about the host] 2025-03-22 14:57:56.608237 | ubuntu-noble | ok 2025-03-22 14:57:56.623308 | 2025-03-22 14:57:56.623397 | TASK [validate-host : Sanitize hostname] 2025-03-22 14:57:56.697981 | ubuntu-noble | ok 2025-03-22 14:57:56.705307 | 2025-03-22 14:57:56.705405 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-22 14:57:57.310500 | ubuntu-noble -> localhost | changed 2025-03-22 14:57:57.317884 | 2025-03-22 14:57:57.317977 | TASK [validate-host : Collect information about zuul worker] 2025-03-22 14:57:57.794735 | ubuntu-noble | ok 2025-03-22 14:57:57.800927 | 2025-03-22 14:57:57.801017 | TASK [validate-host : Write out all zuul information for each host] 2025-03-22 14:57:58.418650 | ubuntu-noble -> localhost | changed 2025-03-22 14:57:58.430369 | 2025-03-22 14:57:58.430459 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-22 14:57:58.826472 | ubuntu-noble | ok 2025-03-22 14:57:58.833747 | 2025-03-22 14:57:58.833839 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-22 14:57:59.688633 | ubuntu-noble | changed: 2025-03-22 14:57:59.688906 | ubuntu-noble | cd+++++++++ src/ 2025-03-22 14:57:59.688940 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-22 14:57:59.688964 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-22 14:57:59.688986 | ubuntu-noble | cd+++++++++ src/github.com/osism/python-osism/ 2025-03-22 14:57:59.689006 | ubuntu-noble | Containerfile 2025-03-22 14:57:59.689120 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.005629 2025-03-22 14:58:40.270254 | 2025-03-22 14:58:40.270371 | TASK [upload-logs : Upload logs to log server] 2025-03-22 14:58:40.824786 | localhost | Output suppressed because no_log was given 2025-03-22 14:58:40.829017 | 2025-03-22 14:58:40.829124 | LOOP [upload-logs : Compress console log and json output] 2025-03-22 14:58:40.896962 | localhost | skipping: Conditional result was False 2025-03-22 14:58:40.912837 | localhost | skipping: Conditional result was False 2025-03-22 14:58:40.922857 | 2025-03-22 14:58:40.922995 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-22 14:58:40.983769 | localhost | skipping: Conditional result was False 2025-03-22 14:58:40.984067 | 2025-03-22 14:58:40.999335 | localhost | skipping: Conditional result was False 2025-03-22 14:58:41.015261 | 2025-03-22 14:58:41.015581 | LOOP [upload-logs : Upload console log and json output]