2025-02-03 12:11:50.388171 | Job console starting... 2025-02-03 12:11:50.404773 | Updating repositories 2025-02-03 12:11:50.464627 | Preparing job workspace 2025-02-03 12:11:52.186412 | Running Ansible setup... 2025-02-03 12:11:57.357771 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-03 12:11:58.111112 | 2025-02-03 12:11:58.111285 | PLAY [Base pre] 2025-02-03 12:11:58.142270 | 2025-02-03 12:11:58.142431 | TASK [Setup log path fact] 2025-02-03 12:11:58.175425 | ubuntu-noble | ok 2025-02-03 12:11:58.200224 | 2025-02-03 12:11:58.200371 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 12:11:58.252615 | ubuntu-noble | ok 2025-02-03 12:11:58.259496 | 2025-02-03 12:11:58.259619 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 12:11:58.295730 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:11:58.312945 | 2025-02-03 12:11:58.313142 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 12:11:58.370530 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:11:58.387194 | 2025-02-03 12:11:58.387366 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-03 12:11:58.413198 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:11:58.426711 | 2025-02-03 12:11:58.426854 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-03 12:11:58.452453 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:11:58.466588 | 2025-02-03 12:11:58.466721 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-03 12:11:58.491211 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:11:58.508318 | 2025-02-03 12:11:58.508464 | TASK [emit-job-header : Print job information] 2025-02-03 12:11:58.560081 | # Job Information 2025-02-03 12:11:58.560265 | Ansible Version: 2.15.3 2025-02-03 12:11:58.560295 | Job: python-black 2025-02-03 12:11:58.560323 | Pipeline: check 2025-02-03 12:11:58.560344 | Executor: 7d211f194f6a 2025-02-03 12:11:58.560362 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1696 2025-02-03 12:11:58.560381 | Event ID: 19d37740-e21e-11ef-9be5-d31e6485e318 2025-02-03 12:11:58.566860 | 2025-02-03 12:11:58.567002 | LOOP [emit-job-header : Print node information] 2025-02-03 12:11:58.712520 | ubuntu-noble | ok: 2025-02-03 12:11:58.712806 | ubuntu-noble | # Node Information 2025-02-03 12:11:58.712844 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-03 12:11:58.712869 | ubuntu-noble | Hostname: ubuntu 2025-02-03 12:11:58.712898 | ubuntu-noble | Username: zuul 2025-02-03 12:11:58.712928 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-03 12:11:58.712974 | ubuntu-noble | Provider: regiocloud-a 2025-02-03 12:11:58.713007 | ubuntu-noble | Label: ubuntu-noble 2025-02-03 12:11:58.713037 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-03 12:11:58.713060 | ubuntu-noble | Interface IP: 81.163.192.198 2025-02-03 12:11:58.735823 | 2025-02-03 12:11:58.736012 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-03 12:11:59.277464 | ubuntu-noble -> localhost | changed 2025-02-03 12:11:59.291279 | 2025-02-03 12:11:59.291415 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-03 12:12:00.388106 | ubuntu-noble -> localhost | changed 2025-02-03 12:12:00.402265 | 2025-02-03 12:12:00.402396 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-03 12:12:00.715454 | ubuntu-noble -> localhost | ok 2025-02-03 12:12:00.724270 | 2025-02-03 12:12:00.732438 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-03 12:12:00.764075 | ubuntu-noble | ok 2025-02-03 12:12:00.794782 | ubuntu-noble | included: /var/lib/zuul/builds/2b1f335b1be0437ba95fba01855df0ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-03 12:12:00.812310 | 2025-02-03 12:12:00.812472 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-03 12:12:01.539525 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-03 12:12:01.539747 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/2b1f335b1be0437ba95fba01855df0ac/work/2b1f335b1be0437ba95fba01855df0ac_id_rsa 2025-02-03 12:12:01.539784 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/2b1f335b1be0437ba95fba01855df0ac/work/2b1f335b1be0437ba95fba01855df0ac_id_rsa.pub 2025-02-03 12:12:01.539809 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-03 12:12:01.539832 | ubuntu-noble -> localhost | SHA256:IJvVCKAYtt910CsxOwgdWPFWF4A3/03DR6StoPdwDmc zuul-build-sshkey 2025-02-03 12:12:01.539854 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-03 12:12:01.539880 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-03 12:12:01.539901 | ubuntu-noble -> localhost | |....=+o.oo.o. ..| 2025-02-03 12:12:01.539921 | ubuntu-noble -> localhost | |oo.o o.*o+. o.| 2025-02-03 12:12:01.539941 | ubuntu-noble -> localhost | |o. o =+*.+ . o..| 2025-02-03 12:12:01.540054 | ubuntu-noble -> localhost | | . .*o=.. o . =.| 2025-02-03 12:12:01.540092 | ubuntu-noble -> localhost | | .o. S . = E o| 2025-02-03 12:12:01.540117 | ubuntu-noble -> localhost | | . X . | 2025-02-03 12:12:01.540139 | ubuntu-noble -> localhost | | o | 2025-02-03 12:12:01.540161 | ubuntu-noble -> localhost | | | 2025-02-03 12:12:01.540182 | ubuntu-noble -> localhost | | | 2025-02-03 12:12:01.540202 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-03 12:12:01.540258 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.206736 2025-02-03 12:12:01.548601 | 2025-02-03 12:12:01.548727 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-03 12:12:01.582167 | ubuntu-noble | ok 2025-02-03 12:12:01.593733 | ubuntu-noble | included: /var/lib/zuul/builds/2b1f335b1be0437ba95fba01855df0ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-03 12:12:01.603633 | 2025-02-03 12:12:01.603735 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-03 12:12:01.628919 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:12:01.636645 | 2025-02-03 12:12:01.636774 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-03 12:12:02.322429 | ubuntu-noble | changed 2025-02-03 12:12:02.330344 | 2025-02-03 12:12:02.330455 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-03 12:12:02.609246 | ubuntu-noble | ok 2025-02-03 12:12:02.622834 | 2025-02-03 12:12:02.623113 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-03 12:12:03.326446 | ubuntu-noble | changed 2025-02-03 12:12:03.334765 | 2025-02-03 12:12:03.334881 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-03 12:12:03.937473 | ubuntu-noble | changed 2025-02-03 12:12:03.945720 | 2025-02-03 12:12:03.945835 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-03 12:12:03.970895 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:12:03.978445 | 2025-02-03 12:12:03.978567 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-03 12:12:04.421902 | ubuntu-noble -> localhost | changed 2025-02-03 12:12:04.435714 | 2025-02-03 12:12:04.435840 | TASK [add-build-sshkey : Add back temp key] 2025-02-03 12:12:04.872626 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/2b1f335b1be0437ba95fba01855df0ac/work/2b1f335b1be0437ba95fba01855df0ac_id_rsa (zuul-build-sshkey) 2025-02-03 12:12:04.872866 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.014762 2025-02-03 12:12:04.880650 | 2025-02-03 12:12:04.880770 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-03 12:12:05.352178 | ubuntu-noble | ok 2025-02-03 12:12:05.358708 | 2025-02-03 12:12:05.358822 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-03 12:12:05.394176 | ubuntu-noble | skipping: Conditional result was False 2025-02-03 12:12:05.418059 | 2025-02-03 12:12:05.418178 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-03 12:12:05.837449 | ubuntu-noble | ok 2025-02-03 12:12:05.854781 | 2025-02-03 12:12:05.854907 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-03 12:12:05.887418 | ubuntu-noble | ok 2025-02-03 12:12:05.894498 | 2025-02-03 12:12:05.894603 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-03 12:12:06.245260 | ubuntu-noble -> localhost | ok 2025-02-03 12:12:06.253298 | 2025-02-03 12:12:06.253416 | TASK [validate-host : Collect information about the host] 2025-02-03 12:12:07.412935 | ubuntu-noble | ok 2025-02-03 12:12:07.427447 | 2025-02-03 12:12:07.427569 | TASK [validate-host : Sanitize hostname] 2025-02-03 12:12:07.509671 | ubuntu-noble | ok 2025-02-03 12:12:07.533664 | 2025-02-03 12:12:07.534056 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-03 12:12:08.238161 | ubuntu-noble -> localhost | changed 2025-02-03 12:12:08.251413 | 2025-02-03 12:12:08.251620 | TASK [validate-host : Collect information about zuul worker] 2025-02-03 12:12:08.702295 | ubuntu-noble | ok 2025-02-03 12:12:08.710611 | 2025-02-03 12:12:08.711132 | TASK [validate-host : Write out all zuul information for each host] 2025-02-03 12:12:09.383855 | ubuntu-noble -> localhost | changed 2025-02-03 12:12:09.397226 | 2025-02-03 12:12:09.397342 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-03 12:12:09.689771 | ubuntu-noble | ok 2025-02-03 12:12:09.697750 | 2025-02-03 12:12:09.697859 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-03 12:12:10.875272 | ubuntu-noble | changed: 2025-02-03 12:12:10.875513 | ubuntu-noble | cd+++++++++ src/ 2025-02-03 12:12:10.875556 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-03 12:12:10.875587 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-03 12:12:10.875615 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-03 12:12:10.875642 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007172 2025-02-03 12:12:44.854919 | 2025-02-03 12:12:44.855167 | TASK [upload-logs : Upload logs to log server] 2025-02-03 12:12:45.433095 | localhost | Output suppressed because no_log was given 2025-02-03 12:12:45.439182 | 2025-02-03 12:12:45.439338 | LOOP [upload-logs : Compress console log and json output] 2025-02-03 12:12:45.513788 | localhost | skipping: Conditional result was False 2025-02-03 12:12:45.531941 | localhost | skipping: Conditional result was False 2025-02-03 12:12:45.553084 | 2025-02-03 12:12:45.553324 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-03 12:12:45.626760 | localhost | skipping: Conditional result was False 2025-02-03 12:12:45.627478 | 2025-02-03 12:12:45.639422 | localhost | skipping: Conditional result was False 2025-02-03 12:12:45.652169 | 2025-02-03 12:12:45.652327 | LOOP [upload-logs : Upload console log and json output]