2025-03-02 01:09:43.267085 | Job console starting... 2025-03-02 01:09:43.300727 | Updating repositories 2025-03-02 01:09:43.340856 | Preparing job workspace 2025-03-02 01:09:46.099788 | Running Ansible setup... 2025-03-02 01:09:52.840739 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-02 01:09:54.022044 | 2025-03-02 01:09:54.022192 | PLAY [Base pre] 2025-03-02 01:09:54.063482 | 2025-03-02 01:09:54.063607 | TASK [Setup log path fact] 2025-03-02 01:09:54.124772 | ubuntu-noble | ok 2025-03-02 01:09:54.178422 | 2025-03-02 01:09:54.178554 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-02 01:09:54.254312 | ubuntu-noble | ok 2025-03-02 01:09:54.269924 | 2025-03-02 01:09:54.270031 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-02 01:09:54.357752 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:09:54.364873 | 2025-03-02 01:09:54.364973 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-02 01:09:54.429466 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:09:54.437735 | 2025-03-02 01:09:54.437855 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-02 01:09:54.463640 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:09:54.472443 | 2025-03-02 01:09:54.472566 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-02 01:09:54.522350 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:09:54.529514 | 2025-03-02 01:09:54.529617 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-02 01:09:54.581409 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:09:54.605757 | 2025-03-02 01:09:54.605865 | TASK [emit-job-header : Print job information] 2025-03-02 01:09:54.687341 | # Job Information 2025-03-02 01:09:54.687492 | Ansible Version: 2.15.3 2025-03-02 01:09:54.687522 | Job: python-black 2025-03-02 01:09:54.687550 | Pipeline: check 2025-03-02 01:09:54.687570 | Executor: 7d211f194f6a 2025-03-02 01:09:54.687589 | Triggered by: https://github.com/osism/openstack-simple-stress/pull/165 2025-03-02 01:09:54.687607 | Event ID: 007a43b0-f703-11ef-9464-1e9bf602beac 2025-03-02 01:09:54.700666 | 2025-03-02 01:09:54.700772 | LOOP [emit-job-header : Print node information] 2025-03-02 01:09:54.871580 | ubuntu-noble | ok: 2025-03-02 01:09:54.871720 | ubuntu-noble | # Node Information 2025-03-02 01:09:54.871746 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-02 01:09:54.871766 | ubuntu-noble | Hostname: ubuntu 2025-03-02 01:09:54.871784 | ubuntu-noble | Username: zuul 2025-03-02 01:09:54.871801 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-02 01:09:54.871817 | ubuntu-noble | Provider: regiocloud-a 2025-03-02 01:09:54.871833 | ubuntu-noble | Label: ubuntu-noble 2025-03-02 01:09:54.871848 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-02 01:09:54.871865 | ubuntu-noble | Interface IP: 81.163.193.153 2025-03-02 01:09:54.895923 | 2025-03-02 01:09:54.896020 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-02 01:09:55.448053 | ubuntu-noble -> localhost | changed 2025-03-02 01:09:55.454725 | 2025-03-02 01:09:55.454809 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-02 01:09:57.015307 | ubuntu-noble -> localhost | changed 2025-03-02 01:09:57.028645 | 2025-03-02 01:09:57.028745 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-02 01:09:57.396479 | ubuntu-noble -> localhost | ok 2025-03-02 01:09:57.403888 | 2025-03-02 01:09:57.403988 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-02 01:09:57.454775 | ubuntu-noble | ok 2025-03-02 01:09:57.486477 | ubuntu-noble | included: /var/lib/zuul/builds/e2e876ec527647f2800b9f88e2854c1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-02 01:09:57.493790 | 2025-03-02 01:09:57.493877 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-02 01:09:58.932226 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-02 01:09:58.932433 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/e2e876ec527647f2800b9f88e2854c1b/work/e2e876ec527647f2800b9f88e2854c1b_id_rsa 2025-03-02 01:09:58.932466 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/e2e876ec527647f2800b9f88e2854c1b/work/e2e876ec527647f2800b9f88e2854c1b_id_rsa.pub 2025-03-02 01:09:58.932487 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-02 01:09:58.932506 | ubuntu-noble -> localhost | SHA256:x5+hRrr6owLEwDYSeXMZ28eo9MrbApBmO2vB+p9uJ5g zuul-build-sshkey 2025-03-02 01:09:58.932524 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-02 01:09:58.932544 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-02 01:09:58.932561 | ubuntu-noble -> localhost | |oo .o | 2025-03-02 01:09:58.932577 | ubuntu-noble -> localhost | |++o oo o | 2025-03-02 01:09:58.932593 | ubuntu-noble -> localhost | |o=.oo o o | 2025-03-02 01:09:58.932609 | ubuntu-noble -> localhost | |ooo. o . . | 2025-03-02 01:09:58.932625 | ubuntu-noble -> localhost | |+o. . . S + . | 2025-03-02 01:09:58.932641 | ubuntu-noble -> localhost | | =o. . + o o | 2025-03-02 01:09:58.932656 | ubuntu-noble -> localhost | |. +=o . o o | 2025-03-02 01:09:58.932673 | ubuntu-noble -> localhost | |.oE =+. .o | 2025-03-02 01:09:58.932689 | ubuntu-noble -> localhost | |...+==++o. | 2025-03-02 01:09:58.932705 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-02 01:09:58.932744 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.634114 2025-03-02 01:09:58.938915 | 2025-03-02 01:09:58.938990 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-02 01:09:58.978779 | ubuntu-noble | ok 2025-03-02 01:09:58.987626 | ubuntu-noble | included: /var/lib/zuul/builds/e2e876ec527647f2800b9f88e2854c1b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-02 01:09:58.995611 | 2025-03-02 01:09:58.995683 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-02 01:09:59.019102 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:09:59.029281 | 2025-03-02 01:09:59.029365 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-02 01:09:59.831856 | ubuntu-noble | changed 2025-03-02 01:09:59.840378 | 2025-03-02 01:09:59.840506 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-02 01:10:00.170835 | ubuntu-noble | ok 2025-03-02 01:10:00.177678 | 2025-03-02 01:10:00.177773 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-02 01:10:01.007492 | ubuntu-noble | changed 2025-03-02 01:10:01.014649 | 2025-03-02 01:10:01.014745 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-02 01:10:01.858747 | ubuntu-noble | changed 2025-03-02 01:10:01.866658 | 2025-03-02 01:10:01.866755 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-02 01:10:01.890296 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:10:01.896318 | 2025-03-02 01:10:01.896413 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-02 01:10:02.264444 | ubuntu-noble -> localhost | changed 2025-03-02 01:10:02.297515 | 2025-03-02 01:10:02.297603 | TASK [add-build-sshkey : Add back temp key] 2025-03-02 01:10:02.576365 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/e2e876ec527647f2800b9f88e2854c1b/work/e2e876ec527647f2800b9f88e2854c1b_id_rsa (zuul-build-sshkey) 2025-03-02 01:10:02.576669 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.008607 2025-03-02 01:10:02.584305 | 2025-03-02 01:10:02.584407 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-02 01:10:03.128739 | ubuntu-noble | ok 2025-03-02 01:10:03.139517 | 2025-03-02 01:10:03.139611 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-02 01:10:03.192008 | ubuntu-noble | skipping: Conditional result was False 2025-03-02 01:10:03.213207 | 2025-03-02 01:10:03.213321 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-02 01:10:03.761574 | ubuntu-noble | ok 2025-03-02 01:10:03.773581 | 2025-03-02 01:10:03.773669 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-02 01:10:03.810968 | ubuntu-noble | ok 2025-03-02 01:10:03.819573 | 2025-03-02 01:10:03.819657 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-02 01:10:04.086159 | ubuntu-noble -> localhost | ok 2025-03-02 01:10:04.103468 | 2025-03-02 01:10:04.103590 | TASK [validate-host : Collect information about the host] 2025-03-02 01:10:05.415788 | ubuntu-noble | ok 2025-03-02 01:10:05.427422 | 2025-03-02 01:10:05.427515 | TASK [validate-host : Sanitize hostname] 2025-03-02 01:10:05.486738 | ubuntu-noble | ok 2025-03-02 01:10:05.502956 | 2025-03-02 01:10:05.503050 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-02 01:10:06.107480 | ubuntu-noble -> localhost | changed 2025-03-02 01:10:06.114191 | 2025-03-02 01:10:06.114291 | TASK [validate-host : Collect information about zuul worker] 2025-03-02 01:10:06.631952 | ubuntu-noble | ok 2025-03-02 01:10:06.637890 | 2025-03-02 01:10:06.637977 | TASK [validate-host : Write out all zuul information for each host] 2025-03-02 01:10:07.176376 | ubuntu-noble -> localhost | changed 2025-03-02 01:10:07.192955 | 2025-03-02 01:10:07.193051 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-02 01:10:07.569914 | ubuntu-noble | ok 2025-03-02 01:10:07.577571 | 2025-03-02 01:10:07.577663 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-02 01:10:08.503970 | ubuntu-noble | changed: 2025-03-02 01:10:08.504156 | ubuntu-noble | cd+++++++++ src/ 2025-03-02 01:10:08.504192 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-02 01:10:08.504215 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-02 01:10:08.504238 | ubuntu-noble | cd+++++++++ src/github.com/osism/openstack-simple-stress/ 2025-03-02 01:10:08.504258 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007717 2025-03-02 01:10:45.192871 | 2025-03-02 01:10:45.192984 | TASK [upload-logs : Upload logs to log server] 2025-03-02 01:10:45.812060 | localhost | Output suppressed because no_log was given 2025-03-02 01:10:45.815980 | 2025-03-02 01:10:45.816094 | LOOP [upload-logs : Compress console log and json output] 2025-03-02 01:10:45.882204 | localhost | skipping: Conditional result was False 2025-03-02 01:10:45.902903 | localhost | skipping: Conditional result was False 2025-03-02 01:10:45.917402 | 2025-03-02 01:10:45.917530 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-02 01:10:46.009901 | localhost | skipping: Conditional result was False 2025-03-02 01:10:46.010249 | 2025-03-02 01:10:46.023114 | localhost | skipping: Conditional result was False 2025-03-02 01:10:46.033201 | 2025-03-02 01:10:46.033359 | LOOP [upload-logs : Upload console log and json output]