2025-02-02 20:36:31.274052 | Job console starting... 2025-02-02 20:36:31.319249 | Updating repositories 2025-02-02 20:36:31.350541 | Preparing job workspace 2025-02-02 20:36:32.884537 | Running Ansible setup... 2025-02-02 20:36:37.911382 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-02 20:36:38.648118 | 2025-02-02 20:36:38.648251 | PLAY [Base pre] 2025-02-02 20:36:38.686617 | 2025-02-02 20:36:38.686747 | TASK [Setup log path fact] 2025-02-02 20:36:38.710086 | ubuntu-noble | ok 2025-02-02 20:36:38.732086 | 2025-02-02 20:36:38.732213 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-02 20:36:38.784997 | ubuntu-noble | ok 2025-02-02 20:36:38.791442 | 2025-02-02 20:36:38.791538 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-02 20:36:38.825567 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:38.832613 | 2025-02-02 20:36:38.832717 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-02 20:36:38.876747 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:38.883806 | 2025-02-02 20:36:38.883916 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-02 20:36:38.897800 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:38.905902 | 2025-02-02 20:36:38.906017 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-02 20:36:38.937288 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:38.944379 | 2025-02-02 20:36:38.944495 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-02 20:36:38.978457 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:39.007082 | 2025-02-02 20:36:39.007202 | TASK [emit-job-header : Print job information] 2025-02-02 20:36:39.058183 | # Job Information 2025-02-02 20:36:39.058335 | Ansible Version: 2.15.3 2025-02-02 20:36:39.058365 | Job: python-black 2025-02-02 20:36:39.058393 | Pipeline: check 2025-02-02 20:36:39.058414 | Executor: 7d211f194f6a 2025-02-02 20:36:39.058432 | Triggered by: https://github.com/osism/zuul-jobs/pull/140 2025-02-02 20:36:39.058450 | Event ID: 5d4aa080-e1a5-11ef-84ef-0570947b5f77 2025-02-02 20:36:39.068894 | 2025-02-02 20:36:39.069003 | LOOP [emit-job-header : Print node information] 2025-02-02 20:36:39.265500 | ubuntu-noble | ok: 2025-02-02 20:36:39.265734 | ubuntu-noble | # Node Information 2025-02-02 20:36:39.265772 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-02 20:36:39.265796 | ubuntu-noble | Hostname: ubuntu 2025-02-02 20:36:39.265817 | ubuntu-noble | Username: zuul 2025-02-02 20:36:39.265837 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-02 20:36:39.265856 | ubuntu-noble | Provider: regiocloud-a 2025-02-02 20:36:39.265902 | ubuntu-noble | Label: ubuntu-noble 2025-02-02 20:36:39.265923 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-02 20:36:39.265944 | ubuntu-noble | Interface IP: 81.163.192.26 2025-02-02 20:36:39.279362 | 2025-02-02 20:36:39.279479 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-02 20:36:39.674629 | ubuntu-noble -> localhost | changed 2025-02-02 20:36:39.682261 | 2025-02-02 20:36:39.682370 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-02 20:36:40.754214 | ubuntu-noble -> localhost | changed 2025-02-02 20:36:40.767375 | 2025-02-02 20:36:40.767469 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-02 20:36:41.067072 | ubuntu-noble -> localhost | ok 2025-02-02 20:36:41.075329 | 2025-02-02 20:36:41.075433 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-02 20:36:41.106053 | ubuntu-noble | ok 2025-02-02 20:36:41.125773 | ubuntu-noble | included: /var/lib/zuul/builds/ec93475d871340f48e5e7dd58ec757df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-02 20:36:41.133318 | 2025-02-02 20:36:41.133417 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-02 20:36:41.695158 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-02 20:36:41.695403 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ec93475d871340f48e5e7dd58ec757df/work/ec93475d871340f48e5e7dd58ec757df_id_rsa 2025-02-02 20:36:41.695458 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ec93475d871340f48e5e7dd58ec757df/work/ec93475d871340f48e5e7dd58ec757df_id_rsa.pub 2025-02-02 20:36:41.695491 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-02 20:36:41.695521 | ubuntu-noble -> localhost | SHA256:9yS7tdlCFEsYGLrFjSQXVICeMg30tROkPCPlqpUlrdU zuul-build-sshkey 2025-02-02 20:36:41.695555 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-02 20:36:41.695595 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-02 20:36:41.695631 | ubuntu-noble -> localhost | | .. o+@*oo | 2025-02-02 20:36:41.695663 | ubuntu-noble -> localhost | | .*.X.=. o | 2025-02-02 20:36:41.695693 | ubuntu-noble -> localhost | | o+%.E .. o | 2025-02-02 20:36:41.695724 | ubuntu-noble -> localhost | | oO+= . o | 2025-02-02 20:36:41.695758 | ubuntu-noble -> localhost | | =o. S o.. | 2025-02-02 20:36:41.695788 | ubuntu-noble -> localhost | | o . =. | 2025-02-02 20:36:41.695818 | ubuntu-noble -> localhost | | . ..o | 2025-02-02 20:36:41.695848 | ubuntu-noble -> localhost | | o.+ | 2025-02-02 20:36:41.695892 | ubuntu-noble -> localhost | | . o.. | 2025-02-02 20:36:41.695919 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-02 20:36:41.695971 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.136678 2025-02-02 20:36:41.707067 | 2025-02-02 20:36:41.707202 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-02 20:36:41.738181 | ubuntu-noble | ok 2025-02-02 20:36:41.751722 | ubuntu-noble | included: /var/lib/zuul/builds/ec93475d871340f48e5e7dd58ec757df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-02 20:36:41.761619 | 2025-02-02 20:36:41.761704 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-02 20:36:41.776243 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:41.788121 | 2025-02-02 20:36:41.788238 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-02 20:36:42.370032 | ubuntu-noble | changed 2025-02-02 20:36:42.384041 | 2025-02-02 20:36:42.384159 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-02 20:36:42.664281 | ubuntu-noble | ok 2025-02-02 20:36:42.674080 | 2025-02-02 20:36:42.674216 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-02 20:36:43.296649 | ubuntu-noble | changed 2025-02-02 20:36:43.305088 | 2025-02-02 20:36:43.305196 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-02 20:36:43.938524 | ubuntu-noble | changed 2025-02-02 20:36:43.953568 | 2025-02-02 20:36:43.953689 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-02 20:36:43.978263 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:43.986247 | 2025-02-02 20:36:43.986363 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-02 20:36:44.552615 | ubuntu-noble -> localhost | changed 2025-02-02 20:36:44.566222 | 2025-02-02 20:36:44.566340 | TASK [add-build-sshkey : Add back temp key] 2025-02-02 20:36:44.939385 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/ec93475d871340f48e5e7dd58ec757df/work/ec93475d871340f48e5e7dd58ec757df_id_rsa (zuul-build-sshkey) 2025-02-02 20:36:44.939632 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010421 2025-02-02 20:36:44.948088 | 2025-02-02 20:36:44.948222 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-02 20:36:45.430438 | ubuntu-noble | ok 2025-02-02 20:36:45.437456 | 2025-02-02 20:36:45.437575 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-02 20:36:45.472500 | ubuntu-noble | skipping: Conditional result was False 2025-02-02 20:36:45.486418 | 2025-02-02 20:36:45.486547 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-02 20:36:45.930132 | ubuntu-noble | ok 2025-02-02 20:36:45.944959 | 2025-02-02 20:36:45.945090 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-02 20:36:45.982384 | ubuntu-noble | ok 2025-02-02 20:36:45.990248 | 2025-02-02 20:36:45.990351 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-02 20:36:46.249669 | ubuntu-noble -> localhost | ok 2025-02-02 20:36:46.262940 | 2025-02-02 20:36:46.263211 | TASK [validate-host : Collect information about the host] 2025-02-02 20:36:47.391583 | ubuntu-noble | ok 2025-02-02 20:36:47.407234 | 2025-02-02 20:36:47.407373 | TASK [validate-host : Sanitize hostname] 2025-02-02 20:36:47.468711 | ubuntu-noble | ok 2025-02-02 20:36:47.476824 | 2025-02-02 20:36:47.476997 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-02 20:36:48.115816 | ubuntu-noble -> localhost | changed 2025-02-02 20:36:48.132769 | 2025-02-02 20:36:48.132929 | TASK [validate-host : Collect information about zuul worker] 2025-02-02 20:36:48.601802 | ubuntu-noble | ok 2025-02-02 20:36:48.610216 | 2025-02-02 20:36:48.610341 | TASK [validate-host : Write out all zuul information for each host] 2025-02-02 20:36:49.224142 | ubuntu-noble -> localhost | changed 2025-02-02 20:36:49.248170 | 2025-02-02 20:36:49.248344 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-02 20:36:49.556011 | ubuntu-noble | ok 2025-02-02 20:36:49.566156 | 2025-02-02 20:36:49.566293 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-02 20:36:50.338928 | ubuntu-noble | changed: 2025-02-02 20:36:50.339167 | ubuntu-noble | cd+++++++++ src/ 2025-02-02 20:36:50.339208 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-02 20:36:50.339238 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-02 20:36:50.339264 | ubuntu-noble | cd+++++++++ src/github.com/osism/zuul-jobs/ 2025-02-02 20:36:50.339290 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.009787 2025-02-02 20:37:22.427917 | 2025-02-02 20:37:22.428069 | TASK [upload-logs : Upload logs to log server] 2025-02-02 20:37:23.126482 | localhost | Output suppressed because no_log was given 2025-02-02 20:37:23.133361 | 2025-02-02 20:37:23.133524 | LOOP [upload-logs : Compress console log and json output] 2025-02-02 20:37:23.201563 | localhost | skipping: Conditional result was False 2025-02-02 20:37:23.218715 | localhost | skipping: Conditional result was False 2025-02-02 20:37:23.232356 | 2025-02-02 20:37:23.232606 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-02 20:37:23.296731 | localhost | skipping: Conditional result was False 2025-02-02 20:37:23.297351 | 2025-02-02 20:37:23.309091 | localhost | skipping: Conditional result was False 2025-02-02 20:37:23.320695 | 2025-02-02 20:37:23.320983 | LOOP [upload-logs : Upload console log and json output]