2025-03-05 22:57:32.039894 | Job console starting... 2025-03-05 22:57:32.080486 | Updating repositories 2025-03-05 22:57:32.134042 | Preparing job workspace 2025-03-05 22:57:35.310876 | Running Ansible setup... 2025-03-05 22:57:42.941795 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-05 22:57:43.837135 | 2025-03-05 22:57:43.837241 | PLAY [Base pre] 2025-03-05 22:57:43.887807 | 2025-03-05 22:57:43.887915 | TASK [Setup log path fact] 2025-03-05 22:57:43.944334 | ubuntu-noble | ok 2025-03-05 22:57:44.002659 | 2025-03-05 22:57:44.002783 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-05 22:57:44.093953 | ubuntu-noble | ok 2025-03-05 22:57:44.105811 | 2025-03-05 22:57:44.105921 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-05 22:57:44.164450 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:44.170235 | 2025-03-05 22:57:44.170353 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-05 22:57:44.305505 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:44.311359 | 2025-03-05 22:57:44.311450 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-05 22:57:44.375567 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:44.381465 | 2025-03-05 22:57:44.381559 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-05 22:57:44.419782 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:44.428915 | 2025-03-05 22:57:44.429032 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-05 22:57:44.475175 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:44.485935 | 2025-03-05 22:57:44.486028 | TASK [emit-job-header : Print job information] 2025-03-05 22:57:44.555651 | # Job Information 2025-03-05 22:57:44.555793 | Ansible Version: 2.15.3 2025-03-05 22:57:44.555818 | Job: python-black 2025-03-05 22:57:44.555841 | Pipeline: check 2025-03-05 22:57:44.555858 | Executor: 7d211f194f6a 2025-03-05 22:57:44.555875 | Triggered by: https://github.com/osism/cfg-cookiecutter/pull/710 2025-03-05 22:57:44.555890 | Event ID: 338f9600-fa15-11ef-8bc5-30a18308c61b 2025-03-05 22:57:44.560943 | 2025-03-05 22:57:44.561027 | LOOP [emit-job-header : Print node information] 2025-03-05 22:57:44.726107 | ubuntu-noble | ok: 2025-03-05 22:57:44.726325 | ubuntu-noble | # Node Information 2025-03-05 22:57:44.726361 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-05 22:57:44.726381 | ubuntu-noble | Hostname: ubuntu 2025-03-05 22:57:44.726399 | ubuntu-noble | Username: zuul 2025-03-05 22:57:44.726416 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-05 22:57:44.726432 | ubuntu-noble | Provider: regiocloud-a 2025-03-05 22:57:44.726447 | ubuntu-noble | Label: ubuntu-noble 2025-03-05 22:57:44.726463 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-05 22:57:44.726480 | ubuntu-noble | Interface IP: 81.163.193.71 2025-03-05 22:57:44.739226 | 2025-03-05 22:57:44.739324 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-05 22:57:45.708564 | ubuntu-noble -> localhost | changed 2025-03-05 22:57:45.715407 | 2025-03-05 22:57:45.715489 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-05 22:57:47.174319 | ubuntu-noble -> localhost | changed 2025-03-05 22:57:47.188301 | 2025-03-05 22:57:47.188404 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-05 22:57:47.842807 | ubuntu-noble -> localhost | ok 2025-03-05 22:57:47.848645 | 2025-03-05 22:57:47.848726 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-05 22:57:47.876918 | ubuntu-noble | ok 2025-03-05 22:57:47.910071 | ubuntu-noble | included: /var/lib/zuul/builds/ddeb6d1b6d894ce1b167d51d99f04d10/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-05 22:57:47.955398 | 2025-03-05 22:57:47.955488 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-05 22:57:48.948700 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-05 22:57:48.948889 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ddeb6d1b6d894ce1b167d51d99f04d10/work/ddeb6d1b6d894ce1b167d51d99f04d10_id_rsa 2025-03-05 22:57:48.948920 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ddeb6d1b6d894ce1b167d51d99f04d10/work/ddeb6d1b6d894ce1b167d51d99f04d10_id_rsa.pub 2025-03-05 22:57:48.948940 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-05 22:57:48.948959 | ubuntu-noble -> localhost | SHA256:VvrqOmSUAxIi251JbgSt9KWgd6NbEa1DVXywlSw5btM zuul-build-sshkey 2025-03-05 22:57:48.948977 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-05 22:57:48.948998 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-05 22:57:48.949015 | ubuntu-noble -> localhost | |o o+oo.oo+.. | 2025-03-05 22:57:48.949032 | ubuntu-noble -> localhost | |.++==oo =o+ | 2025-03-05 22:57:48.949047 | ubuntu-noble -> localhost | |.o.*** o.=. | 2025-03-05 22:57:48.949063 | ubuntu-noble -> localhost | |. o.O + +oE | 2025-03-05 22:57:48.949078 | ubuntu-noble -> localhost | | . o = oS. | 2025-03-05 22:57:48.949093 | ubuntu-noble -> localhost | | . . o. . | 2025-03-05 22:57:48.949109 | ubuntu-noble -> localhost | | o o . | 2025-03-05 22:57:48.949125 | ubuntu-noble -> localhost | | . . . | 2025-03-05 22:57:48.949141 | ubuntu-noble -> localhost | | .+o | 2025-03-05 22:57:48.949156 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-05 22:57:48.949192 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.186415 2025-03-05 22:57:48.955592 | 2025-03-05 22:57:48.955678 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-05 22:57:49.004798 | ubuntu-noble | ok 2025-03-05 22:57:49.022610 | ubuntu-noble | included: /var/lib/zuul/builds/ddeb6d1b6d894ce1b167d51d99f04d10/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-05 22:57:49.039712 | 2025-03-05 22:57:49.039801 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-05 22:57:49.080126 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:49.086215 | 2025-03-05 22:57:49.086325 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-05 22:57:49.927229 | ubuntu-noble | changed 2025-03-05 22:57:49.933554 | 2025-03-05 22:57:49.933633 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-05 22:57:50.295474 | ubuntu-noble | ok 2025-03-05 22:57:50.304837 | 2025-03-05 22:57:50.304922 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-05 22:57:51.085851 | ubuntu-noble | changed 2025-03-05 22:57:51.092705 | 2025-03-05 22:57:51.092795 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-05 22:57:51.880926 | ubuntu-noble | changed 2025-03-05 22:57:51.888348 | 2025-03-05 22:57:51.888491 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-05 22:57:51.912518 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:51.919455 | 2025-03-05 22:57:51.919538 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-05 22:57:52.269790 | ubuntu-noble -> localhost | changed 2025-03-05 22:57:52.306865 | 2025-03-05 22:57:52.306970 | TASK [add-build-sshkey : Add back temp key] 2025-03-05 22:57:52.586231 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/ddeb6d1b6d894ce1b167d51d99f04d10/work/ddeb6d1b6d894ce1b167d51d99f04d10_id_rsa (zuul-build-sshkey) 2025-03-05 22:57:52.586453 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013595 2025-03-05 22:57:52.593677 | 2025-03-05 22:57:52.593767 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-05 22:57:53.041823 | ubuntu-noble | ok 2025-03-05 22:57:53.062562 | 2025-03-05 22:57:53.062661 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-05 22:57:53.098377 | ubuntu-noble | skipping: Conditional result was False 2025-03-05 22:57:53.113936 | 2025-03-05 22:57:53.114045 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-05 22:57:53.610502 | ubuntu-noble | ok 2025-03-05 22:57:53.633545 | 2025-03-05 22:57:53.633653 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-05 22:57:53.680041 | ubuntu-noble | ok 2025-03-05 22:57:53.692135 | 2025-03-05 22:57:53.692234 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-05 22:57:54.235783 | ubuntu-noble -> localhost | ok 2025-03-05 22:57:54.242795 | 2025-03-05 22:57:54.242878 | TASK [validate-host : Collect information about the host] 2025-03-05 22:57:55.676663 | ubuntu-noble | ok 2025-03-05 22:57:55.702434 | 2025-03-05 22:57:55.702549 | TASK [validate-host : Sanitize hostname] 2025-03-05 22:57:55.803128 | ubuntu-noble | ok 2025-03-05 22:57:55.821065 | 2025-03-05 22:57:55.821171 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-05 22:57:56.736010 | ubuntu-noble -> localhost | changed 2025-03-05 22:57:56.742583 | 2025-03-05 22:57:56.742672 | TASK [validate-host : Collect information about zuul worker] 2025-03-05 22:57:57.248319 | ubuntu-noble | ok 2025-03-05 22:57:57.255750 | 2025-03-05 22:57:57.255844 | TASK [validate-host : Write out all zuul information for each host] 2025-03-05 22:57:58.008333 | ubuntu-noble -> localhost | changed 2025-03-05 22:57:58.019741 | 2025-03-05 22:57:58.019832 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-05 22:57:58.411237 | ubuntu-noble | ok 2025-03-05 22:57:58.420331 | 2025-03-05 22:57:58.420457 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-05 22:57:59.365980 | ubuntu-noble | changed: 2025-03-05 22:57:59.366114 | ubuntu-noble | cd+++++++++ src/ 2025-03-05 22:57:59.366141 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-05 22:57:59.366164 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-05 22:57:59.366182 | ubuntu-noble | cd+++++++++ src/github.com/osism/cfg-cookiecutter/ 2025-03-05 22:57:59.366199 | ubuntu-noble | Containerfile 2025-03-05 22:57:59.366334 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.018284 2025-03-05 22:58:37.652367 | 2025-03-05 22:58:37.652473 | TASK [upload-logs : Upload logs to log server] 2025-03-05 22:58:38.210066 | localhost | Output suppressed because no_log was given 2025-03-05 22:58:38.218156 | 2025-03-05 22:58:38.218267 | LOOP [upload-logs : Compress console log and json output] 2025-03-05 22:58:38.275819 | localhost | skipping: Conditional result was False 2025-03-05 22:58:38.289898 | localhost | skipping: Conditional result was False 2025-03-05 22:58:38.298087 | 2025-03-05 22:58:38.298171 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-05 22:58:38.345908 | localhost | skipping: Conditional result was False 2025-03-05 22:58:38.346225 | 2025-03-05 22:58:38.383744 | localhost | skipping: Conditional result was False 2025-03-05 22:58:38.396689 | 2025-03-05 22:58:38.396793 | LOOP [upload-logs : Upload console log and json output]