2025-03-12 16:31:46.738765 | Job console starting... 2025-03-12 16:31:46.755678 | Updating repositories 2025-03-12 16:31:46.790388 | Preparing job workspace 2025-03-12 16:31:48.617937 | Running Ansible setup... 2025-03-12 16:31:54.369847 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-12 16:31:55.107574 | 2025-03-12 16:31:55.107685 | PLAY [Base pre] 2025-03-12 16:31:55.142591 | 2025-03-12 16:31:55.142695 | TASK [Setup log path fact] 2025-03-12 16:31:55.161585 | ubuntu-noble | ok 2025-03-12 16:31:55.186584 | 2025-03-12 16:31:55.186683 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-12 16:31:55.246041 | ubuntu-noble | ok 2025-03-12 16:31:55.253762 | 2025-03-12 16:31:55.254219 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-12 16:31:55.291984 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.298267 | 2025-03-12 16:31:55.298352 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-12 16:31:55.331962 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.338611 | 2025-03-12 16:31:55.338722 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-12 16:31:55.362860 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.377310 | 2025-03-12 16:31:55.377403 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-12 16:31:55.412207 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.421668 | 2025-03-12 16:31:55.421762 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-12 16:31:55.446271 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:55.474563 | 2025-03-12 16:31:55.474744 | TASK [emit-job-header : Print job information] 2025-03-12 16:31:55.566513 | # Job Information 2025-03-12 16:31:55.566715 | Ansible Version: 2.15.3 2025-03-12 16:31:55.566748 | Job: python-black 2025-03-12 16:31:55.566776 | Pipeline: check 2025-03-12 16:31:55.567149 | Executor: 7d211f194f6a 2025-03-12 16:31:55.567259 | Triggered by: https://github.com/osism/testbed/pull/2631 2025-03-12 16:31:55.567284 | Event ID: 736883a0-ff5f-11ef-8ab1-25b48a284636 2025-03-12 16:31:55.577359 | 2025-03-12 16:31:55.577450 | LOOP [emit-job-header : Print node information] 2025-03-12 16:31:55.775964 | ubuntu-noble | ok: 2025-03-12 16:31:55.776243 | ubuntu-noble | # Node Information 2025-03-12 16:31:55.776285 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-12 16:31:55.776313 | ubuntu-noble | Hostname: ubuntu 2025-03-12 16:31:55.776338 | ubuntu-noble | Username: zuul 2025-03-12 16:31:55.776362 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-12 16:31:55.776398 | ubuntu-noble | Provider: regiocloud-a 2025-03-12 16:31:55.776434 | ubuntu-noble | Label: ubuntu-noble 2025-03-12 16:31:55.776458 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-12 16:31:55.776481 | ubuntu-noble | Interface IP: 81.163.193.159 2025-03-12 16:31:55.793693 | 2025-03-12 16:31:55.793788 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-12 16:31:56.554902 | ubuntu-noble -> localhost | changed 2025-03-12 16:31:56.563421 | 2025-03-12 16:31:56.563567 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-12 16:31:58.014475 | ubuntu-noble -> localhost | changed 2025-03-12 16:31:58.041253 | 2025-03-12 16:31:58.041364 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-12 16:31:58.384346 | ubuntu-noble -> localhost | ok 2025-03-12 16:31:58.401321 | 2025-03-12 16:31:58.401417 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-12 16:31:58.431418 | ubuntu-noble | ok 2025-03-12 16:31:58.446634 | ubuntu-noble | included: /var/lib/zuul/builds/5aaff48dfb344098a7cc7f8ef5438008/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-12 16:31:58.452893 | 2025-03-12 16:31:58.452975 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-12 16:31:59.720190 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-12 16:31:59.720462 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/5aaff48dfb344098a7cc7f8ef5438008/work/5aaff48dfb344098a7cc7f8ef5438008_id_rsa 2025-03-12 16:31:59.720505 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/5aaff48dfb344098a7cc7f8ef5438008/work/5aaff48dfb344098a7cc7f8ef5438008_id_rsa.pub 2025-03-12 16:31:59.720531 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-12 16:31:59.720555 | ubuntu-noble -> localhost | SHA256:w/FXZ5zMDXOxsTerXLGxraA47bGLtX5aoKsMR4Xf9eM zuul-build-sshkey 2025-03-12 16:31:59.720577 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-12 16:31:59.720603 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-12 16:31:59.720627 | ubuntu-noble -> localhost | | ooo| 2025-03-12 16:31:59.720649 | ubuntu-noble -> localhost | | . o=*| 2025-03-12 16:31:59.720670 | ubuntu-noble -> localhost | | . o . .X*| 2025-03-12 16:31:59.720690 | ubuntu-noble -> localhost | | + + . o oX| 2025-03-12 16:31:59.720709 | ubuntu-noble -> localhost | | . S + o o=.| 2025-03-12 16:31:59.720729 | ubuntu-noble -> localhost | | . = +.ooo | 2025-03-12 16:31:59.720749 | ubuntu-noble -> localhost | | . . + = .oE | 2025-03-12 16:31:59.720770 | ubuntu-noble -> localhost | | + * =. | 2025-03-12 16:31:59.720791 | ubuntu-noble -> localhost | | o.o.B+ | 2025-03-12 16:31:59.720811 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-12 16:31:59.720862 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.576827 2025-03-12 16:31:59.732756 | 2025-03-12 16:31:59.733057 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-12 16:31:59.774866 | ubuntu-noble | ok 2025-03-12 16:31:59.794946 | ubuntu-noble | included: /var/lib/zuul/builds/5aaff48dfb344098a7cc7f8ef5438008/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-12 16:31:59.814835 | 2025-03-12 16:31:59.815037 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-12 16:31:59.864049 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:31:59.877978 | 2025-03-12 16:31:59.878080 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-12 16:32:00.691505 | ubuntu-noble | changed 2025-03-12 16:32:00.700408 | 2025-03-12 16:32:00.700543 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-12 16:32:01.011225 | ubuntu-noble | ok 2025-03-12 16:32:01.023646 | 2025-03-12 16:32:01.023754 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-12 16:32:01.793088 | ubuntu-noble | changed 2025-03-12 16:32:01.807065 | 2025-03-12 16:32:01.807154 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-12 16:32:02.532184 | ubuntu-noble | changed 2025-03-12 16:32:02.545223 | 2025-03-12 16:32:02.545524 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-12 16:32:02.573161 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:32:02.593679 | 2025-03-12 16:32:02.593874 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-12 16:32:03.128357 | ubuntu-noble -> localhost | changed 2025-03-12 16:32:03.218343 | 2025-03-12 16:32:03.218508 | TASK [add-build-sshkey : Add back temp key] 2025-03-12 16:32:03.625377 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/5aaff48dfb344098a7cc7f8ef5438008/work/5aaff48dfb344098a7cc7f8ef5438008_id_rsa (zuul-build-sshkey) 2025-03-12 16:32:03.625573 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010409 2025-03-12 16:32:03.633116 | 2025-03-12 16:32:03.633204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-12 16:32:04.090606 | ubuntu-noble | ok 2025-03-12 16:32:04.102551 | 2025-03-12 16:32:04.102667 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-12 16:32:04.137666 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:32:04.157364 | 2025-03-12 16:32:04.157465 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-12 16:32:04.613465 | ubuntu-noble | ok 2025-03-12 16:32:04.627800 | 2025-03-12 16:32:04.627939 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-12 16:32:04.668987 | ubuntu-noble | ok 2025-03-12 16:32:04.702058 | 2025-03-12 16:32:04.703027 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-12 16:32:05.313334 | ubuntu-noble -> localhost | ok 2025-03-12 16:32:05.323777 | 2025-03-12 16:32:05.323894 | TASK [validate-host : Collect information about the host] 2025-03-12 16:32:06.605928 | ubuntu-noble | ok 2025-03-12 16:32:06.617905 | 2025-03-12 16:32:06.618022 | TASK [validate-host : Sanitize hostname] 2025-03-12 16:32:06.758884 | ubuntu-noble | ok 2025-03-12 16:32:06.782675 | 2025-03-12 16:32:06.783353 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-12 16:32:07.632883 | ubuntu-noble -> localhost | changed 2025-03-12 16:32:07.646297 | 2025-03-12 16:32:07.646423 | TASK [validate-host : Collect information about zuul worker] 2025-03-12 16:32:08.237045 | ubuntu-noble | ok 2025-03-12 16:32:08.244247 | 2025-03-12 16:32:08.244355 | TASK [validate-host : Write out all zuul information for each host] 2025-03-12 16:32:08.851299 | ubuntu-noble -> localhost | changed 2025-03-12 16:32:08.872501 | 2025-03-12 16:32:08.872645 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-12 16:32:09.241089 | ubuntu-noble | ok 2025-03-12 16:32:09.249053 | 2025-03-12 16:32:09.249162 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-12 16:32:10.611432 | ubuntu-noble | changed: 2025-03-12 16:32:10.612804 | ubuntu-noble | cd+++++++++ src/ 2025-03-12 16:32:10.613404 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-12 16:32:10.614298 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-12 16:32:10.614848 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-12 16:32:10.615465 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004223 2025-03-12 16:32:47.371768 | 2025-03-12 16:32:47.371890 | TASK [upload-logs : Upload logs to log server] 2025-03-12 16:32:47.927230 | localhost | Output suppressed because no_log was given 2025-03-12 16:32:47.935690 | 2025-03-12 16:32:47.935979 | LOOP [upload-logs : Compress console log and json output] 2025-03-12 16:32:48.009058 | localhost | skipping: Conditional result was False 2025-03-12 16:32:48.028637 | localhost | skipping: Conditional result was False 2025-03-12 16:32:48.039723 | 2025-03-12 16:32:48.039835 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-12 16:32:48.129905 | localhost | skipping: Conditional result was False 2025-03-12 16:32:48.130226 | 2025-03-12 16:32:48.135776 | localhost | skipping: Conditional result was False 2025-03-12 16:32:48.140353 | 2025-03-12 16:32:48.140490 | LOOP [upload-logs : Upload console log and json output]