2025-01-08 16:50:09.634595 | Job console starting... 2025-01-08 16:50:09.658414 | Updating repositories 2025-01-08 16:50:09.693404 | Preparing job workspace 2025-01-08 16:50:11.188049 | Running Ansible setup... 2025-01-08 16:50:16.145513 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-08 16:50:16.848248 | 2025-01-08 16:50:16.848408 | PLAY [Base pre] 2025-01-08 16:50:16.888878 | 2025-01-08 16:50:16.889027 | TASK [Setup log path fact] 2025-01-08 16:50:16.930435 | ubuntu-noble | ok 2025-01-08 16:50:16.952455 | 2025-01-08 16:50:16.952601 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 16:50:16.994002 | ubuntu-noble | ok 2025-01-08 16:50:17.002406 | 2025-01-08 16:50:17.002524 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 16:50:17.037783 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:17.045062 | 2025-01-08 16:50:17.045184 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 16:50:17.089694 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:17.097181 | 2025-01-08 16:50:17.097297 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-08 16:50:17.121604 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:17.128847 | 2025-01-08 16:50:17.128961 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-08 16:50:17.153077 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:17.160271 | 2025-01-08 16:50:17.160382 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-08 16:50:17.184595 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:17.211292 | 2025-01-08 16:50:17.211432 | TASK [emit-job-header : Print job information] 2025-01-08 16:50:17.262729 | # Job Information 2025-01-08 16:50:17.262913 | Ansible Version: 2.15.3 2025-01-08 16:50:17.262945 | Job: python-black 2025-01-08 16:50:17.262974 | Pipeline: check 2025-01-08 16:50:17.262995 | Executor: 7d211f194f6a 2025-01-08 16:50:17.263014 | Triggered by: https://github.com/osism/container-images-kolla/pull/530 2025-01-08 16:50:17.263032 | Event ID: 9a520690-cde0-11ef-8b3d-405c9f7fcea1 2025-01-08 16:50:17.269563 | 2025-01-08 16:50:17.269710 | LOOP [emit-job-header : Print node information] 2025-01-08 16:50:17.434467 | ubuntu-noble | ok: 2025-01-08 16:50:17.434804 | ubuntu-noble | # Node Information 2025-01-08 16:50:17.434858 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-08 16:50:17.434894 | ubuntu-noble | Hostname: ubuntu 2025-01-08 16:50:17.434927 | ubuntu-noble | Username: zuul 2025-01-08 16:50:17.434959 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-08 16:50:17.434989 | ubuntu-noble | Provider: regiocloud-a 2025-01-08 16:50:17.435018 | ubuntu-noble | Label: ubuntu-noble 2025-01-08 16:50:17.435046 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-08 16:50:17.435077 | ubuntu-noble | Interface IP: 81.163.192.81 2025-01-08 16:50:17.453688 | 2025-01-08 16:50:17.453822 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-08 16:50:17.928049 | ubuntu-noble -> localhost | changed 2025-01-08 16:50:17.936672 | 2025-01-08 16:50:17.936795 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-08 16:50:18.960363 | ubuntu-noble -> localhost | changed 2025-01-08 16:50:18.990221 | 2025-01-08 16:50:18.990389 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-08 16:50:19.282366 | ubuntu-noble -> localhost | ok 2025-01-08 16:50:19.297112 | 2025-01-08 16:50:19.297250 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-08 16:50:19.332746 | ubuntu-noble | ok 2025-01-08 16:50:19.352367 | ubuntu-noble | included: /var/lib/zuul/builds/e7bf64b7d9044c6bbe363d877b23d855/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-08 16:50:19.360521 | 2025-01-08 16:50:19.360661 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-08 16:50:19.923573 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-08 16:50:19.923867 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e7bf64b7d9044c6bbe363d877b23d855/work/e7bf64b7d9044c6bbe363d877b23d855_id_rsa 2025-01-08 16:50:19.923912 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e7bf64b7d9044c6bbe363d877b23d855/work/e7bf64b7d9044c6bbe363d877b23d855_id_rsa.pub 2025-01-08 16:50:19.923938 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-08 16:50:19.923961 | ubuntu-noble -> localhost | SHA256:mgQjrLf0h/nNeYoGLyTI1Jx3wBnxXg4nsczuYvtgFEI zuul-build-sshkey 2025-01-08 16:50:19.923985 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-08 16:50:19.924012 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-08 16:50:19.924034 | ubuntu-noble -> localhost | | E.o+. | 2025-01-08 16:50:19.924054 | ubuntu-noble -> localhost | | .. ++ o | 2025-01-08 16:50:19.924073 | ubuntu-noble -> localhost | | =.+..B o | 2025-01-08 16:50:19.924092 | ubuntu-noble -> localhost | | o =.++.* | 2025-01-08 16:50:19.924111 | ubuntu-noble -> localhost | |+.o ..ooS. | 2025-01-08 16:50:19.924130 | ubuntu-noble -> localhost | |.+.o++.o | 2025-01-08 16:50:19.924148 | ubuntu-noble -> localhost | | .o+B+. | 2025-01-08 16:50:19.924168 | ubuntu-noble -> localhost | | +o*+ .. | 2025-01-08 16:50:19.924188 | ubuntu-noble -> localhost | | ++.=o | 2025-01-08 16:50:19.924207 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-08 16:50:19.924263 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.079719 2025-01-08 16:50:19.933281 | 2025-01-08 16:50:19.933400 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-08 16:50:19.962358 | ubuntu-noble | ok 2025-01-08 16:50:19.979610 | ubuntu-noble | included: /var/lib/zuul/builds/e7bf64b7d9044c6bbe363d877b23d855/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-08 16:50:19.990320 | 2025-01-08 16:50:19.990424 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-08 16:50:20.014598 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:20.022122 | 2025-01-08 16:50:20.022235 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-08 16:50:20.762434 | ubuntu-noble | changed 2025-01-08 16:50:20.771727 | 2025-01-08 16:50:20.771841 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-08 16:50:21.079474 | ubuntu-noble | ok 2025-01-08 16:50:21.088577 | 2025-01-08 16:50:21.088709 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-08 16:50:21.819862 | ubuntu-noble | changed 2025-01-08 16:50:21.829362 | 2025-01-08 16:50:21.829482 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-08 16:50:22.559656 | ubuntu-noble | changed 2025-01-08 16:50:22.569890 | 2025-01-08 16:50:22.570050 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-08 16:50:22.604813 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:22.614146 | 2025-01-08 16:50:22.614261 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-08 16:50:23.059348 | ubuntu-noble -> localhost | changed 2025-01-08 16:50:23.088297 | 2025-01-08 16:50:23.088428 | TASK [add-build-sshkey : Add back temp key] 2025-01-08 16:50:23.438400 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e7bf64b7d9044c6bbe363d877b23d855/work/e7bf64b7d9044c6bbe363d877b23d855_id_rsa (zuul-build-sshkey) 2025-01-08 16:50:23.438676 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010143 2025-01-08 16:50:23.446698 | 2025-01-08 16:50:23.446808 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-08 16:50:23.890547 | ubuntu-noble | ok 2025-01-08 16:50:23.930748 | 2025-01-08 16:50:23.930895 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-08 16:50:23.961695 | ubuntu-noble | skipping: Conditional result was False 2025-01-08 16:50:23.978341 | 2025-01-08 16:50:23.978467 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-08 16:50:24.407032 | ubuntu-noble | ok 2025-01-08 16:50:24.430604 | 2025-01-08 16:50:24.430770 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-08 16:50:24.481095 | ubuntu-noble | ok 2025-01-08 16:50:24.488435 | 2025-01-08 16:50:24.488553 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-08 16:50:24.800865 | ubuntu-noble -> localhost | ok 2025-01-08 16:50:24.809140 | 2025-01-08 16:50:24.809261 | TASK [validate-host : Collect information about the host] 2025-01-08 16:50:26.032489 | ubuntu-noble | ok 2025-01-08 16:50:26.047964 | 2025-01-08 16:50:26.048094 | TASK [validate-host : Sanitize hostname] 2025-01-08 16:50:26.109875 | ubuntu-noble | ok 2025-01-08 16:50:26.116244 | 2025-01-08 16:50:26.116355 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-08 16:50:26.651054 | ubuntu-noble -> localhost | changed 2025-01-08 16:50:26.661267 | 2025-01-08 16:50:26.661402 | TASK [validate-host : Collect information about zuul worker] 2025-01-08 16:50:27.141131 | ubuntu-noble | ok 2025-01-08 16:50:27.150752 | 2025-01-08 16:50:27.150890 | TASK [validate-host : Write out all zuul information for each host] 2025-01-08 16:50:27.687043 | ubuntu-noble -> localhost | changed 2025-01-08 16:50:27.702129 | 2025-01-08 16:50:27.702253 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-08 16:50:28.026937 | ubuntu-noble | ok 2025-01-08 16:50:28.041053 | 2025-01-08 16:50:28.041208 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-08 16:50:28.987526 | ubuntu-noble | changed: 2025-01-08 16:50:28.987798 | ubuntu-noble | cd+++++++++ src/ 2025-01-08 16:50:28.987842 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-08 16:50:28.987872 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-08 16:50:28.987898 | ubuntu-noble | cd+++++++++ src/github.com/osism/container-images-kolla/ 2025-01-08 16:50:28.987923 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.012408 2025-01-08 16:51:01.022371 | 2025-01-08 16:51:01.022506 | TASK [upload-logs : Upload logs to log server] 2025-01-08 16:51:01.585991 | localhost | Output suppressed because no_log was given 2025-01-08 16:51:01.589130 | 2025-01-08 16:51:01.589243 | LOOP [upload-logs : Compress console log and json output] 2025-01-08 16:51:01.662385 | localhost | skipping: Conditional result was False 2025-01-08 16:51:01.680382 | localhost | skipping: Conditional result was False 2025-01-08 16:51:01.694479 | 2025-01-08 16:51:01.694682 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-08 16:51:01.757330 | localhost | skipping: Conditional result was False 2025-01-08 16:51:01.757653 | 2025-01-08 16:51:01.770458 | localhost | skipping: Conditional result was False 2025-01-08 16:51:01.778352 | 2025-01-08 16:51:01.778503 | LOOP [upload-logs : Upload console log and json output]