2025-03-12 16:19:46.727522 | Job console starting... 2025-03-12 16:19:46.740530 | Updating repositories 2025-03-12 16:19:46.770416 | Preparing job workspace 2025-03-12 16:19:48.148379 | Running Ansible setup... 2025-03-12 16:19:53.199852 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-12 16:19:53.902991 | 2025-03-12 16:19:53.903149 | PLAY [Base pre] 2025-03-12 16:19:53.945857 | 2025-03-12 16:19:53.946007 | TASK [Setup log path fact] 2025-03-12 16:19:53.987513 | ubuntu-noble | ok 2025-03-12 16:19:54.010245 | 2025-03-12 16:19:54.010403 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-12 16:19:54.082998 | ubuntu-noble | ok 2025-03-12 16:19:54.089779 | 2025-03-12 16:19:54.089900 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-12 16:19:54.134983 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:19:54.148593 | 2025-03-12 16:19:54.148768 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-12 16:19:54.193962 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:19:54.201538 | 2025-03-12 16:19:54.201666 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-12 16:19:54.226026 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:19:54.236676 | 2025-03-12 16:19:54.236951 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-12 16:19:54.272799 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:19:54.283118 | 2025-03-12 16:19:54.283336 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-12 16:19:54.308446 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:19:54.330380 | 2025-03-12 16:19:54.330498 | TASK [emit-job-header : Print job information] 2025-03-12 16:19:54.384882 | # Job Information 2025-03-12 16:19:54.385286 | Ansible Version: 2.15.3 2025-03-12 16:19:54.385419 | Job: flake8 2025-03-12 16:19:54.385570 | Pipeline: check 2025-03-12 16:19:54.385609 | Executor: 7d211f194f6a 2025-03-12 16:19:54.385716 | Triggered by: https://github.com/osism/testbed/pull/2630 2025-03-12 16:19:54.385896 | Event ID: c8e99f50-ff5d-11ef-8573-d1d6b37823e0 2025-03-12 16:19:54.399830 | 2025-03-12 16:19:54.400156 | LOOP [emit-job-header : Print node information] 2025-03-12 16:19:54.574120 | ubuntu-noble | ok: 2025-03-12 16:19:54.574474 | ubuntu-noble | # Node Information 2025-03-12 16:19:54.574524 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-12 16:19:54.574591 | ubuntu-noble | Hostname: ubuntu 2025-03-12 16:19:54.574708 | ubuntu-noble | Username: zuul 2025-03-12 16:19:54.574775 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-12 16:19:54.574799 | ubuntu-noble | Provider: regiocloud-a 2025-03-12 16:19:54.574820 | ubuntu-noble | Label: ubuntu-noble 2025-03-12 16:19:54.574839 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-12 16:19:54.574860 | ubuntu-noble | Interface IP: 81.163.193.182 2025-03-12 16:19:54.596588 | 2025-03-12 16:19:54.596708 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-12 16:19:55.099140 | ubuntu-noble -> localhost | changed 2025-03-12 16:19:55.110704 | 2025-03-12 16:19:55.110850 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-12 16:19:56.171025 | ubuntu-noble -> localhost | changed 2025-03-12 16:19:56.192338 | 2025-03-12 16:19:56.192562 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-12 16:19:56.557355 | ubuntu-noble -> localhost | ok 2025-03-12 16:19:56.573104 | 2025-03-12 16:19:56.573247 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-12 16:19:56.606659 | ubuntu-noble | ok 2025-03-12 16:19:56.629646 | ubuntu-noble | included: /var/lib/zuul/builds/200f81fb58594143b4ee963487267e22/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-12 16:19:56.639705 | 2025-03-12 16:19:56.639849 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-12 16:19:57.270707 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-12 16:19:57.270988 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/200f81fb58594143b4ee963487267e22/work/200f81fb58594143b4ee963487267e22_id_rsa 2025-03-12 16:19:57.271030 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/200f81fb58594143b4ee963487267e22/work/200f81fb58594143b4ee963487267e22_id_rsa.pub 2025-03-12 16:19:57.271055 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-12 16:19:57.271078 | ubuntu-noble -> localhost | SHA256:puhFPs+pW+MZ1cpzlyDjt87sjS7a8VE241YYvTKeja4 zuul-build-sshkey 2025-03-12 16:19:57.271101 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-12 16:19:57.271125 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-12 16:19:57.271146 | ubuntu-noble -> localhost | | | 2025-03-12 16:19:57.271166 | ubuntu-noble -> localhost | | . | 2025-03-12 16:19:57.271185 | ubuntu-noble -> localhost | | . .| 2025-03-12 16:19:57.271205 | ubuntu-noble -> localhost | | . o.| 2025-03-12 16:19:57.271225 | ubuntu-noble -> localhost | | . S + oo*..| 2025-03-12 16:19:57.271245 | ubuntu-noble -> localhost | | + o + +.=*= | 2025-03-12 16:19:57.271265 | ubuntu-noble -> localhost | | . = + * ++=. | 2025-03-12 16:19:57.271284 | ubuntu-noble -> localhost | | . . * *.O.B | 2025-03-12 16:19:57.271304 | ubuntu-noble -> localhost | | . ooB..E@.. | 2025-03-12 16:19:57.271322 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-12 16:19:57.271369 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.065604 2025-03-12 16:19:57.282627 | 2025-03-12 16:19:57.282767 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-12 16:19:57.322569 | ubuntu-noble | ok 2025-03-12 16:19:57.333949 | ubuntu-noble | included: /var/lib/zuul/builds/200f81fb58594143b4ee963487267e22/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-12 16:19:57.343536 | 2025-03-12 16:19:57.343637 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-12 16:19:57.368267 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:19:57.378729 | 2025-03-12 16:19:57.378875 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-12 16:19:58.263600 | ubuntu-noble | changed 2025-03-12 16:19:58.271200 | 2025-03-12 16:19:58.271311 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-12 16:19:58.611459 | ubuntu-noble | ok 2025-03-12 16:19:58.624615 | 2025-03-12 16:19:58.625354 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-12 16:19:59.455404 | ubuntu-noble | changed 2025-03-12 16:19:59.464781 | 2025-03-12 16:19:59.464908 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-12 16:20:00.217257 | ubuntu-noble | changed 2025-03-12 16:20:00.224699 | 2025-03-12 16:20:00.224852 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-12 16:20:00.249895 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:20:00.262192 | 2025-03-12 16:20:00.262301 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-12 16:20:00.876258 | ubuntu-noble -> localhost | changed 2025-03-12 16:20:00.893156 | 2025-03-12 16:20:00.893329 | TASK [add-build-sshkey : Add back temp key] 2025-03-12 16:20:01.421874 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/200f81fb58594143b4ee963487267e22/work/200f81fb58594143b4ee963487267e22_id_rsa (zuul-build-sshkey) 2025-03-12 16:20:01.422117 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.010104 2025-03-12 16:20:01.465956 | 2025-03-12 16:20:01.466088 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-12 16:20:01.944824 | ubuntu-noble | ok 2025-03-12 16:20:01.953434 | 2025-03-12 16:20:01.953556 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-12 16:20:01.988407 | ubuntu-noble | skipping: Conditional result was False 2025-03-12 16:20:02.002774 | 2025-03-12 16:20:02.002901 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-12 16:20:02.461361 | ubuntu-noble | ok 2025-03-12 16:20:02.483195 | 2025-03-12 16:20:02.483352 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-12 16:20:02.539632 | ubuntu-noble | ok 2025-03-12 16:20:02.552942 | 2025-03-12 16:20:02.553072 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-12 16:20:02.980925 | ubuntu-noble -> localhost | ok 2025-03-12 16:20:02.988801 | 2025-03-12 16:20:02.988914 | TASK [validate-host : Collect information about the host] 2025-03-12 16:20:04.249911 | ubuntu-noble | ok 2025-03-12 16:20:04.266196 | 2025-03-12 16:20:04.266467 | TASK [validate-host : Sanitize hostname] 2025-03-12 16:20:04.341428 | ubuntu-noble | ok 2025-03-12 16:20:04.353826 | 2025-03-12 16:20:04.353998 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-12 16:20:05.017480 | ubuntu-noble -> localhost | changed 2025-03-12 16:20:05.024711 | 2025-03-12 16:20:05.024867 | TASK [validate-host : Collect information about zuul worker] 2025-03-12 16:20:05.612579 | ubuntu-noble | ok 2025-03-12 16:20:05.622642 | 2025-03-12 16:20:05.622797 | TASK [validate-host : Write out all zuul information for each host] 2025-03-12 16:20:06.263495 | ubuntu-noble -> localhost | changed 2025-03-12 16:20:06.287129 | 2025-03-12 16:20:06.287308 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-12 16:20:06.607500 | ubuntu-noble | ok 2025-03-12 16:20:06.614497 | 2025-03-12 16:20:06.614606 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-12 16:20:07.905684 | ubuntu-noble | changed: 2025-03-12 16:20:07.906322 | ubuntu-noble | cd+++++++++ src/ 2025-03-12 16:20:07.906787 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-12 16:20:07.906850 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-12 16:20:07.906875 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-03-12 16:20:07.906898 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.004182 2025-03-12 16:20:45.081157 | 2025-03-12 16:20:45.081272 | TASK [upload-logs : Upload logs to log server] 2025-03-12 16:20:45.690581 | localhost | Output suppressed because no_log was given 2025-03-12 16:20:45.694477 | 2025-03-12 16:20:45.694589 | LOOP [upload-logs : Compress console log and json output] 2025-03-12 16:20:45.769232 | localhost | skipping: Conditional result was False 2025-03-12 16:20:45.785962 | localhost | skipping: Conditional result was False 2025-03-12 16:20:45.801635 | 2025-03-12 16:20:45.801883 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-12 16:20:45.878331 | localhost | skipping: Conditional result was False 2025-03-12 16:20:45.878623 | 2025-03-12 16:20:45.891567 | localhost | skipping: Conditional result was False 2025-03-12 16:20:45.901699 | 2025-03-12 16:20:45.901965 | LOOP [upload-logs : Upload console log and json output]