2025-03-21 12:52:24.826533 | Job console starting... 2025-03-21 12:52:24.837810 | Updating repositories 2025-03-21 12:52:24.870096 | Preparing job workspace 2025-03-21 12:52:26.364182 | Running Ansible setup... 2025-03-21 12:52:31.615750 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-03-21 12:52:32.333711 | 2025-03-21 12:52:32.333862 | PLAY [Base pre] 2025-03-21 12:52:32.364299 | 2025-03-21 12:52:32.364472 | TASK [Setup log path fact] 2025-03-21 12:52:32.396132 | ubuntu-noble | ok 2025-03-21 12:52:32.419272 | 2025-03-21 12:52:32.419534 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-21 12:52:32.493601 | ubuntu-noble | ok 2025-03-21 12:52:32.507802 | 2025-03-21 12:52:32.507930 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-21 12:52:32.543407 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:32.551294 | 2025-03-21 12:52:32.551426 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-21 12:52:32.617645 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:32.625836 | 2025-03-21 12:52:32.625962 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-03-21 12:52:32.650395 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:32.658139 | 2025-03-21 12:52:32.658254 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-03-21 12:52:32.682282 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:32.690077 | 2025-03-21 12:52:32.690192 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-03-21 12:52:32.727109 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:32.742565 | 2025-03-21 12:52:32.742787 | TASK [emit-job-header : Print job information] 2025-03-21 12:52:32.793833 | # Job Information 2025-03-21 12:52:32.794001 | Ansible Version: 2.15.3 2025-03-21 12:52:32.794048 | Job: ansible-collection-ensure-readme 2025-03-21 12:52:32.794078 | Pipeline: check 2025-03-21 12:52:32.794098 | Executor: 7d211f194f6a 2025-03-21 12:52:32.794117 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1779 2025-03-21 12:52:32.794136 | Event ID: fd9b8c80-0652-11f0-9c8d-3f4d1f10d4e7 2025-03-21 12:52:32.800937 | 2025-03-21 12:52:32.801151 | LOOP [emit-job-header : Print node information] 2025-03-21 12:52:32.973396 | ubuntu-noble | ok: 2025-03-21 12:52:32.973595 | ubuntu-noble | # Node Information 2025-03-21 12:52:32.973630 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-03-21 12:52:32.973656 | ubuntu-noble | Hostname: ubuntu 2025-03-21 12:52:32.973684 | ubuntu-noble | Username: zuul 2025-03-21 12:52:32.973715 | ubuntu-noble | Distro: Ubuntu 24.04 2025-03-21 12:52:32.973737 | ubuntu-noble | Provider: regiocloud-a 2025-03-21 12:52:32.973758 | ubuntu-noble | Label: ubuntu-noble 2025-03-21 12:52:32.973778 | ubuntu-noble | Product Name: OpenStack Nova 2025-03-21 12:52:32.973970 | ubuntu-noble | Interface IP: 81.163.193.58 2025-03-21 12:52:33.000221 | 2025-03-21 12:52:33.000365 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-03-21 12:52:33.482970 | ubuntu-noble -> localhost | changed 2025-03-21 12:52:33.491536 | 2025-03-21 12:52:33.491662 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-03-21 12:52:34.585544 | ubuntu-noble -> localhost | changed 2025-03-21 12:52:34.599348 | 2025-03-21 12:52:34.599479 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-03-21 12:52:34.894986 | ubuntu-noble -> localhost | ok 2025-03-21 12:52:34.910643 | 2025-03-21 12:52:34.910793 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-03-21 12:52:34.956194 | ubuntu-noble | ok 2025-03-21 12:52:34.972755 | ubuntu-noble | included: /var/lib/zuul/builds/a784fc64a3284ae1b20758a6046b9074/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-03-21 12:52:34.980347 | 2025-03-21 12:52:34.980486 | TASK [add-build-sshkey : Create Temp SSH key] 2025-03-21 12:52:35.862853 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-03-21 12:52:35.863373 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/a784fc64a3284ae1b20758a6046b9074/work/a784fc64a3284ae1b20758a6046b9074_id_rsa 2025-03-21 12:52:35.863479 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/a784fc64a3284ae1b20758a6046b9074/work/a784fc64a3284ae1b20758a6046b9074_id_rsa.pub 2025-03-21 12:52:35.863551 | ubuntu-noble -> localhost | The key fingerprint is: 2025-03-21 12:52:35.863615 | ubuntu-noble -> localhost | SHA256:hrd8Q2K2NCBWb1vFzKHDv/Co498wKEmTJG0ftRgkEV0 zuul-build-sshkey 2025-03-21 12:52:35.863676 | ubuntu-noble -> localhost | The key's randomart image is: 2025-03-21 12:52:35.863747 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-03-21 12:52:35.863808 | ubuntu-noble -> localhost | | ==o.E+o. | 2025-03-21 12:52:35.863865 | ubuntu-noble -> localhost | | o o.= ++ | 2025-03-21 12:52:35.863921 | ubuntu-noble -> localhost | | + = = * | 2025-03-21 12:52:35.863976 | ubuntu-noble -> localhost | | . = * + o | 2025-03-21 12:52:35.864052 | ubuntu-noble -> localhost | | = S o . | 2025-03-21 12:52:35.864111 | ubuntu-noble -> localhost | | . X B + . | 2025-03-21 12:52:35.864168 | ubuntu-noble -> localhost | | o = * o | 2025-03-21 12:52:35.864227 | ubuntu-noble -> localhost | | ..o = | 2025-03-21 12:52:35.864285 | ubuntu-noble -> localhost | | .oo. . | 2025-03-21 12:52:35.864344 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-03-21 12:52:35.864613 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.362891 2025-03-21 12:52:35.881180 | 2025-03-21 12:52:35.881334 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-03-21 12:52:35.932932 | ubuntu-noble | ok 2025-03-21 12:52:35.946946 | ubuntu-noble | included: /var/lib/zuul/builds/a784fc64a3284ae1b20758a6046b9074/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-03-21 12:52:35.956530 | 2025-03-21 12:52:35.956627 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-03-21 12:52:35.981297 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:35.988648 | 2025-03-21 12:52:35.988746 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-03-21 12:52:36.811495 | ubuntu-noble | changed 2025-03-21 12:52:36.822454 | 2025-03-21 12:52:36.822575 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-03-21 12:52:37.138416 | ubuntu-noble | ok 2025-03-21 12:52:37.149573 | 2025-03-21 12:52:37.149710 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-03-21 12:52:37.853153 | ubuntu-noble | changed 2025-03-21 12:52:37.863127 | 2025-03-21 12:52:37.863250 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-03-21 12:52:38.618350 | ubuntu-noble | changed 2025-03-21 12:52:38.625939 | 2025-03-21 12:52:38.626091 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-03-21 12:52:38.660870 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:38.670060 | 2025-03-21 12:52:38.670176 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-03-21 12:52:39.112636 | ubuntu-noble -> localhost | changed 2025-03-21 12:52:39.126389 | 2025-03-21 12:52:39.126514 | TASK [add-build-sshkey : Add back temp key] 2025-03-21 12:52:39.456494 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/a784fc64a3284ae1b20758a6046b9074/work/a784fc64a3284ae1b20758a6046b9074_id_rsa (zuul-build-sshkey) 2025-03-21 12:52:39.456730 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.015085 2025-03-21 12:52:39.465861 | 2025-03-21 12:52:39.465977 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-03-21 12:52:39.907176 | ubuntu-noble | ok 2025-03-21 12:52:39.917907 | 2025-03-21 12:52:39.918060 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-03-21 12:52:39.953869 | ubuntu-noble | skipping: Conditional result was False 2025-03-21 12:52:39.967905 | 2025-03-21 12:52:39.968014 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-03-21 12:52:40.384546 | ubuntu-noble | ok 2025-03-21 12:52:40.400214 | 2025-03-21 12:52:40.400340 | TASK [validate-host : Define zuul_info_dir fact] 2025-03-21 12:52:40.432868 | ubuntu-noble | ok 2025-03-21 12:52:40.439442 | 2025-03-21 12:52:40.439548 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-03-21 12:52:40.729703 | ubuntu-noble -> localhost | ok 2025-03-21 12:52:40.738108 | 2025-03-21 12:52:40.738220 | TASK [validate-host : Collect information about the host] 2025-03-21 12:52:41.954994 | ubuntu-noble | ok 2025-03-21 12:52:41.970491 | 2025-03-21 12:52:41.970622 | TASK [validate-host : Sanitize hostname] 2025-03-21 12:52:42.031828 | ubuntu-noble | ok 2025-03-21 12:52:42.038360 | 2025-03-21 12:52:42.038473 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-03-21 12:52:42.588835 | ubuntu-noble -> localhost | changed 2025-03-21 12:52:42.596234 | 2025-03-21 12:52:42.596350 | TASK [validate-host : Collect information about zuul worker] 2025-03-21 12:52:43.072209 | ubuntu-noble | ok 2025-03-21 12:52:43.081655 | 2025-03-21 12:52:43.081794 | TASK [validate-host : Write out all zuul information for each host] 2025-03-21 12:52:43.618433 | ubuntu-noble -> localhost | changed 2025-03-21 12:52:43.631299 | 2025-03-21 12:52:43.631412 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-03-21 12:52:43.960654 | ubuntu-noble | ok 2025-03-21 12:52:43.970133 | 2025-03-21 12:52:43.970262 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-03-21 12:52:45.217228 | ubuntu-noble | changed: 2025-03-21 12:52:45.217506 | ubuntu-noble | cd+++++++++ src/ 2025-03-21 12:52:45.217563 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-03-21 12:52:45.217601 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-03-21 12:52:45.217636 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-03-21 12:52:45.217669 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007116 2025-03-21 12:53:04.138169 | 2025-03-21 12:53:04.138332 | TASK [upload-logs : Upload logs to log server] 2025-03-21 12:53:04.723701 | localhost | Output suppressed because no_log was given 2025-03-21 12:53:04.728341 | 2025-03-21 12:53:04.728501 | LOOP [upload-logs : Compress console log and json output] 2025-03-21 12:53:04.793207 | localhost | skipping: Conditional result was False 2025-03-21 12:53:04.809821 | localhost | skipping: Conditional result was False 2025-03-21 12:53:04.824050 | 2025-03-21 12:53:04.824253 | LOOP [upload-logs : Upload compressed console log and json output] 2025-03-21 12:53:04.887528 | localhost | skipping: Conditional result was False 2025-03-21 12:53:04.887819 | 2025-03-21 12:53:04.916306 | localhost | skipping: Conditional result was False 2025-03-21 12:53:04.929072 | 2025-03-21 12:53:04.929306 | LOOP [upload-logs : Upload console log and json output]