2025-01-22 15:34:44.463716 | Job console starting... 2025-01-22 15:34:44.484783 | Updating repositories 2025-01-22 15:34:44.514786 | Preparing job workspace 2025-01-22 15:34:46.017014 | Running Ansible setup... 2025-01-22 15:34:51.195182 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-22 15:34:51.915423 | 2025-01-22 15:34:51.915606 | PLAY [Base pre] 2025-01-22 15:34:51.957816 | 2025-01-22 15:34:51.957992 | TASK [Setup log path fact] 2025-01-22 15:34:51.982287 | ubuntu-noble | ok 2025-01-22 15:34:52.007748 | 2025-01-22 15:34:52.007927 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-22 15:34:52.050131 | ubuntu-noble | ok 2025-01-22 15:34:52.057423 | 2025-01-22 15:34:52.057563 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-22 15:34:52.103294 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:52.110841 | 2025-01-22 15:34:52.110979 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-22 15:34:52.156399 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:52.164725 | 2025-01-22 15:34:52.164863 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-22 15:34:52.189769 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:52.197234 | 2025-01-22 15:34:52.197366 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-22 15:34:52.222461 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:52.238241 | 2025-01-22 15:34:52.238428 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-22 15:34:52.273696 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:52.289370 | 2025-01-22 15:34:52.289500 | TASK [emit-job-header : Print job information] 2025-01-22 15:34:52.342701 | # Job Information 2025-01-22 15:34:52.342887 | Ansible Version: 2.15.3 2025-01-22 15:34:52.342919 | Job: python-black 2025-01-22 15:34:52.342948 | Pipeline: check 2025-01-22 15:34:52.342968 | Executor: 7d211f194f6a 2025-01-22 15:34:52.342987 | Triggered by: https://github.com/osism/testbed/pull/2592 2025-01-22 15:34:52.343006 | Event ID: 622e57e0-d8d6-11ef-8c48-e5f0b0dd580c 2025-01-22 15:34:52.351013 | 2025-01-22 15:34:52.351132 | LOOP [emit-job-header : Print node information] 2025-01-22 15:34:52.502155 | ubuntu-noble | ok: 2025-01-22 15:34:52.502456 | ubuntu-noble | # Node Information 2025-01-22 15:34:52.502494 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-22 15:34:52.502519 | ubuntu-noble | Hostname: ubuntu 2025-01-22 15:34:52.502540 | ubuntu-noble | Username: zuul 2025-01-22 15:34:52.502561 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-22 15:34:52.502580 | ubuntu-noble | Provider: regiocloud-a 2025-01-22 15:34:52.502599 | ubuntu-noble | Label: ubuntu-noble 2025-01-22 15:34:52.502619 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-22 15:34:52.502639 | ubuntu-noble | Interface IP: 81.163.193.18 2025-01-22 15:34:52.523335 | 2025-01-22 15:34:52.523470 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-22 15:34:53.042425 | ubuntu-noble -> localhost | changed 2025-01-22 15:34:53.051220 | 2025-01-22 15:34:53.051381 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-22 15:34:54.231670 | ubuntu-noble -> localhost | changed 2025-01-22 15:34:54.248419 | 2025-01-22 15:34:54.248566 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-22 15:34:54.660011 | ubuntu-noble -> localhost | ok 2025-01-22 15:34:54.668903 | 2025-01-22 15:34:54.669027 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-22 15:34:54.699683 | ubuntu-noble | ok 2025-01-22 15:34:54.716335 | ubuntu-noble | included: /var/lib/zuul/builds/4e507aeb893c47049ccd95b4c0b56a0a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-22 15:34:54.724369 | 2025-01-22 15:34:54.724497 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-22 15:34:55.395560 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-22 15:34:55.395836 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/4e507aeb893c47049ccd95b4c0b56a0a/work/4e507aeb893c47049ccd95b4c0b56a0a_id_rsa 2025-01-22 15:34:55.395876 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/4e507aeb893c47049ccd95b4c0b56a0a/work/4e507aeb893c47049ccd95b4c0b56a0a_id_rsa.pub 2025-01-22 15:34:55.395902 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-22 15:34:55.395925 | ubuntu-noble -> localhost | SHA256:zc17dDzIi8xcDAcA8s4GnrBOg+IbyNyJd9kj3uR0nJI zuul-build-sshkey 2025-01-22 15:34:55.395948 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-22 15:34:55.395972 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-22 15:34:55.395993 | ubuntu-noble -> localhost | | . ..... | 2025-01-22 15:34:55.396013 | ubuntu-noble -> localhost | | o . | 2025-01-22 15:34:55.396032 | ubuntu-noble -> localhost | | . . . . . | 2025-01-22 15:34:55.396052 | ubuntu-noble -> localhost | | . + = o o= .. | 2025-01-22 15:34:55.396071 | ubuntu-noble -> localhost | |.. + o +S o o=..o| 2025-01-22 15:34:55.396090 | ubuntu-noble -> localhost | |* = o + o = oo...| 2025-01-22 15:34:55.396109 | ubuntu-noble -> localhost | |.* = + E + =... | 2025-01-22 15:34:55.396128 | ubuntu-noble -> localhost | | + o * + . | 2025-01-22 15:34:55.396147 | ubuntu-noble -> localhost | | . . o | 2025-01-22 15:34:55.396165 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-22 15:34:55.396215 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.108586 2025-01-22 15:34:55.404324 | 2025-01-22 15:34:55.404501 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-22 15:34:55.436726 | ubuntu-noble | ok 2025-01-22 15:34:55.448463 | ubuntu-noble | included: /var/lib/zuul/builds/4e507aeb893c47049ccd95b4c0b56a0a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-22 15:34:55.458127 | 2025-01-22 15:34:55.458232 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-22 15:34:55.482943 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:55.491364 | 2025-01-22 15:34:55.491484 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-22 15:34:56.367540 | ubuntu-noble | changed 2025-01-22 15:34:56.377263 | 2025-01-22 15:34:56.377400 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-22 15:34:56.690345 | ubuntu-noble | ok 2025-01-22 15:34:56.703969 | 2025-01-22 15:34:56.704144 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-22 15:34:57.471298 | ubuntu-noble | changed 2025-01-22 15:34:57.481294 | 2025-01-22 15:34:57.481444 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-22 15:34:58.251331 | ubuntu-noble | changed 2025-01-22 15:34:58.259637 | 2025-01-22 15:34:58.259759 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-22 15:34:58.295231 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:58.308533 | 2025-01-22 15:34:58.308693 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-22 15:34:58.866616 | ubuntu-noble -> localhost | changed 2025-01-22 15:34:58.882946 | 2025-01-22 15:34:58.883093 | TASK [add-build-sshkey : Add back temp key] 2025-01-22 15:34:59.289085 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/4e507aeb893c47049ccd95b4c0b56a0a/work/4e507aeb893c47049ccd95b4c0b56a0a_id_rsa (zuul-build-sshkey) 2025-01-22 15:34:59.289342 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.026059 2025-01-22 15:34:59.334980 | 2025-01-22 15:34:59.335115 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-22 15:34:59.744956 | ubuntu-noble | ok 2025-01-22 15:34:59.754758 | 2025-01-22 15:34:59.754894 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-22 15:34:59.790932 | ubuntu-noble | skipping: Conditional result was False 2025-01-22 15:34:59.815352 | 2025-01-22 15:34:59.815495 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-22 15:35:00.289487 | ubuntu-noble | ok 2025-01-22 15:35:00.306223 | 2025-01-22 15:35:00.306371 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-22 15:35:00.357042 | ubuntu-noble | ok 2025-01-22 15:35:00.366821 | 2025-01-22 15:35:00.366969 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-22 15:35:00.688216 | ubuntu-noble -> localhost | ok 2025-01-22 15:35:00.697036 | 2025-01-22 15:35:00.697166 | TASK [validate-host : Collect information about the host] 2025-01-22 15:35:01.969720 | ubuntu-noble | ok 2025-01-22 15:35:01.983159 | 2025-01-22 15:35:01.983328 | TASK [validate-host : Sanitize hostname] 2025-01-22 15:35:02.101214 | ubuntu-noble | ok 2025-01-22 15:35:02.118902 | 2025-01-22 15:35:02.119044 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-22 15:35:02.689975 | ubuntu-noble -> localhost | changed 2025-01-22 15:35:02.705730 | 2025-01-22 15:35:02.705915 | TASK [validate-host : Collect information about zuul worker] 2025-01-22 15:35:03.202537 | ubuntu-noble | ok 2025-01-22 15:35:03.226387 | 2025-01-22 15:35:03.232455 | TASK [validate-host : Write out all zuul information for each host] 2025-01-22 15:35:03.799492 | ubuntu-noble -> localhost | changed 2025-01-22 15:35:03.812511 | 2025-01-22 15:35:03.812654 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-22 15:35:04.141031 | ubuntu-noble | ok 2025-01-22 15:35:04.151723 | 2025-01-22 15:35:04.151855 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-22 15:35:05.465211 | ubuntu-noble | changed: 2025-01-22 15:35:05.465482 | ubuntu-noble | cd+++++++++ src/ 2025-01-22 15:35:05.465518 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-22 15:35:05.465541 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-22 15:35:05.465562 | ubuntu-noble | cd+++++++++ src/github.com/osism/testbed/ 2025-01-22 15:35:05.465581 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.012733 2025-01-22 15:35:39.183706 | 2025-01-22 15:35:39.183832 | TASK [upload-logs : Upload logs to log server] 2025-01-22 15:35:39.837946 | localhost | Output suppressed because no_log was given 2025-01-22 15:35:39.841432 | 2025-01-22 15:35:39.841542 | LOOP [upload-logs : Compress console log and json output] 2025-01-22 15:35:39.907043 | localhost | skipping: Conditional result was False 2025-01-22 15:35:39.925093 | localhost | skipping: Conditional result was False 2025-01-22 15:35:39.940534 | 2025-01-22 15:35:39.940673 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-22 15:35:40.013970 | localhost | skipping: Conditional result was False 2025-01-22 15:35:40.014296 | 2025-01-22 15:35:40.016524 | localhost | skipping: Conditional result was False 2025-01-22 15:35:40.021423 | 2025-01-22 15:35:40.021534 | LOOP [upload-logs : Upload console log and json output]