2025-01-21 22:13:17.759223 | Job console starting... 2025-01-21 22:13:17.794199 | Updating repositories 2025-01-21 22:13:17.830842 | Preparing job workspace 2025-01-21 22:13:19.720826 | Running Ansible setup... 2025-01-21 22:13:25.016131 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-21 22:13:25.771146 | 2025-01-21 22:13:25.771306 | PLAY [Base pre] 2025-01-21 22:13:25.803395 | 2025-01-21 22:13:25.803561 | TASK [Setup log path fact] 2025-01-21 22:13:25.836905 | ubuntu-noble | ok 2025-01-21 22:13:25.868021 | 2025-01-21 22:13:25.868175 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-21 22:13:25.920157 | ubuntu-noble | ok 2025-01-21 22:13:25.927390 | 2025-01-21 22:13:25.927512 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-21 22:13:25.962595 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:25.969942 | 2025-01-21 22:13:25.970074 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-21 22:13:26.035758 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:26.043799 | 2025-01-21 22:13:26.043926 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-21 22:13:26.076997 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:26.096813 | 2025-01-21 22:13:26.096944 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-21 22:13:26.131887 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:26.139283 | 2025-01-21 22:13:26.139403 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-21 22:13:26.174202 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:26.190488 | 2025-01-21 22:13:26.190645 | TASK [emit-job-header : Print job information] 2025-01-21 22:13:26.247595 | # Job Information 2025-01-21 22:13:26.247770 | Ansible Version: 2.15.3 2025-01-21 22:13:26.247802 | Job: python-black 2025-01-21 22:13:26.247830 | Pipeline: check 2025-01-21 22:13:26.247849 | Executor: 7d211f194f6a 2025-01-21 22:13:26.247867 | Triggered by: https://github.com/osism/openstack-project-manager/pull/212 2025-01-21 22:13:26.247885 | Event ID: e4d49ed0-d844-11ef-8ecf-a73350ea066d 2025-01-21 22:13:26.254330 | 2025-01-21 22:13:26.254439 | LOOP [emit-job-header : Print node information] 2025-01-21 22:13:26.404711 | ubuntu-noble | ok: 2025-01-21 22:13:26.405043 | ubuntu-noble | # Node Information 2025-01-21 22:13:26.405085 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-21 22:13:26.405109 | ubuntu-noble | Hostname: ubuntu 2025-01-21 22:13:26.405131 | ubuntu-noble | Username: zuul 2025-01-21 22:13:26.405152 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-21 22:13:26.405171 | ubuntu-noble | Provider: regiocloud-a 2025-01-21 22:13:26.405190 | ubuntu-noble | Label: ubuntu-noble 2025-01-21 22:13:26.405210 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-21 22:13:26.405230 | ubuntu-noble | Interface IP: 81.163.193.182 2025-01-21 22:13:26.426002 | 2025-01-21 22:13:26.426144 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-21 22:13:26.942189 | ubuntu-noble -> localhost | changed 2025-01-21 22:13:26.950618 | 2025-01-21 22:13:26.950734 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-21 22:13:28.129824 | ubuntu-noble -> localhost | changed 2025-01-21 22:13:28.147379 | 2025-01-21 22:13:28.147506 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-21 22:13:28.427839 | ubuntu-noble -> localhost | ok 2025-01-21 22:13:28.437510 | 2025-01-21 22:13:28.437670 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-21 22:13:28.471802 | ubuntu-noble | ok 2025-01-21 22:13:28.488810 | ubuntu-noble | included: /var/lib/zuul/builds/dc88773cdbd24fd4b45ce0132e2f736d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-21 22:13:28.496772 | 2025-01-21 22:13:28.496887 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-21 22:13:29.395575 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-21 22:13:29.395865 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/dc88773cdbd24fd4b45ce0132e2f736d/work/dc88773cdbd24fd4b45ce0132e2f736d_id_rsa 2025-01-21 22:13:29.395906 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/dc88773cdbd24fd4b45ce0132e2f736d/work/dc88773cdbd24fd4b45ce0132e2f736d_id_rsa.pub 2025-01-21 22:13:29.395932 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-21 22:13:29.395956 | ubuntu-noble -> localhost | SHA256:/2KbhIW2x47yjy3HI8rSPyJFxxn15hJdxGQZ5oBNOVw zuul-build-sshkey 2025-01-21 22:13:29.395980 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-21 22:13:29.396006 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-21 22:13:29.396028 | ubuntu-noble -> localhost | | ..=o*Eo | 2025-01-21 22:13:29.396049 | ubuntu-noble -> localhost | | . .o=*o | 2025-01-21 22:13:29.396070 | ubuntu-noble -> localhost | | . o. +.. | 2025-01-21 22:13:29.396090 | ubuntu-noble -> localhost | | . +. + | 2025-01-21 22:13:29.396110 | ubuntu-noble -> localhost | | . .S o . | 2025-01-21 22:13:29.396129 | ubuntu-noble -> localhost | | .. * . | 2025-01-21 22:13:29.396149 | ubuntu-noble -> localhost | | o o.= | 2025-01-21 22:13:29.396169 | ubuntu-noble -> localhost | | o.+ +B*o | 2025-01-21 22:13:29.396190 | ubuntu-noble -> localhost | | oo*=B*+. | 2025-01-21 22:13:29.396209 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-21 22:13:29.396259 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.138327 2025-01-21 22:13:29.405555 | 2025-01-21 22:13:29.405692 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-21 22:13:29.443297 | ubuntu-noble | ok 2025-01-21 22:13:29.458318 | ubuntu-noble | included: /var/lib/zuul/builds/dc88773cdbd24fd4b45ce0132e2f736d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-21 22:13:29.474268 | 2025-01-21 22:13:29.474392 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-21 22:13:29.515495 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:29.524086 | 2025-01-21 22:13:29.524206 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-21 22:13:30.365925 | ubuntu-noble | changed 2025-01-21 22:13:30.379109 | 2025-01-21 22:13:30.379260 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-21 22:13:30.696274 | ubuntu-noble | ok 2025-01-21 22:13:30.704835 | 2025-01-21 22:13:30.704958 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-21 22:13:31.414569 | ubuntu-noble | changed 2025-01-21 22:13:31.431258 | 2025-01-21 22:13:31.431401 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-21 22:13:32.194298 | ubuntu-noble | changed 2025-01-21 22:13:32.203445 | 2025-01-21 22:13:32.203580 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-21 22:13:32.238305 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:32.248515 | 2025-01-21 22:13:32.248674 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-21 22:13:32.781759 | ubuntu-noble -> localhost | changed 2025-01-21 22:13:32.795603 | 2025-01-21 22:13:32.795730 | TASK [add-build-sshkey : Add back temp key] 2025-01-21 22:13:33.243727 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/dc88773cdbd24fd4b45ce0132e2f736d/work/dc88773cdbd24fd4b45ce0132e2f736d_id_rsa (zuul-build-sshkey) 2025-01-21 22:13:33.243981 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014261 2025-01-21 22:13:33.265081 | 2025-01-21 22:13:33.265225 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-21 22:13:33.753389 | ubuntu-noble | ok 2025-01-21 22:13:33.759861 | 2025-01-21 22:13:33.759971 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-21 22:13:33.795138 | ubuntu-noble | skipping: Conditional result was False 2025-01-21 22:13:33.809511 | 2025-01-21 22:13:33.809683 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-21 22:13:34.227969 | ubuntu-noble | ok 2025-01-21 22:13:34.252815 | 2025-01-21 22:13:34.252950 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-21 22:13:34.305011 | ubuntu-noble | ok 2025-01-21 22:13:34.312266 | 2025-01-21 22:13:34.312415 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-21 22:13:34.612632 | ubuntu-noble -> localhost | ok 2025-01-21 22:13:34.621964 | 2025-01-21 22:13:34.622092 | TASK [validate-host : Collect information about the host] 2025-01-21 22:13:35.779432 | ubuntu-noble | ok 2025-01-21 22:13:35.791803 | 2025-01-21 22:13:35.791926 | TASK [validate-host : Sanitize hostname] 2025-01-21 22:13:35.864914 | ubuntu-noble | ok 2025-01-21 22:13:35.871879 | 2025-01-21 22:13:35.872007 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-21 22:13:36.643237 | ubuntu-noble -> localhost | changed 2025-01-21 22:13:36.652770 | 2025-01-21 22:13:36.652906 | TASK [validate-host : Collect information about zuul worker] 2025-01-21 22:13:37.322133 | ubuntu-noble | ok 2025-01-21 22:13:37.340836 | 2025-01-21 22:13:37.340985 | TASK [validate-host : Write out all zuul information for each host] 2025-01-21 22:13:38.080799 | ubuntu-noble -> localhost | changed 2025-01-21 22:13:38.095296 | 2025-01-21 22:13:38.095438 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-21 22:13:38.443353 | ubuntu-noble | ok 2025-01-21 22:13:38.450972 | 2025-01-21 22:13:38.451105 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-21 22:13:39.481445 | ubuntu-noble | changed: 2025-01-21 22:13:39.481689 | ubuntu-noble | cd+++++++++ src/ 2025-01-21 22:13:39.481728 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-21 22:13:39.481753 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-21 22:13:39.481775 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-project-manager/ 2025-01-21 22:13:39.481795 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.009434 2025-01-21 22:14:11.556277 | 2025-01-21 22:14:11.556801 | TASK [upload-logs : Upload logs to log server] 2025-01-21 22:14:12.183568 | localhost | Output suppressed because no_log was given 2025-01-21 22:14:12.186916 | 2025-01-21 22:14:12.187019 | LOOP [upload-logs : Compress console log and json output] 2025-01-21 22:14:12.268488 | localhost | skipping: Conditional result was False 2025-01-21 22:14:12.285985 | localhost | skipping: Conditional result was False 2025-01-21 22:14:12.297205 | 2025-01-21 22:14:12.297324 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-21 22:14:12.370149 | localhost | skipping: Conditional result was False 2025-01-21 22:14:12.370872 | 2025-01-21 22:14:12.382992 | localhost | skipping: Conditional result was False 2025-01-21 22:14:12.394358 | 2025-01-21 22:14:12.394610 | LOOP [upload-logs : Upload console log and json output]