2025-01-28 23:24:03.254630 | Job console starting... 2025-01-28 23:24:03.276498 | Updating repositories 2025-01-28 23:24:03.326474 | Preparing job workspace 2025-01-28 23:24:04.928007 | Running Ansible setup... 2025-01-28 23:24:09.910407 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-01-28 23:24:10.644448 | 2025-01-28 23:24:10.644640 | PLAY [Base pre] 2025-01-28 23:24:10.674405 | 2025-01-28 23:24:10.674531 | TASK [Setup log path fact] 2025-01-28 23:24:10.706221 | ubuntu-noble | ok 2025-01-28 23:24:10.727895 | 2025-01-28 23:24:10.728021 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-28 23:24:10.798495 | ubuntu-noble | ok 2025-01-28 23:24:10.808293 | 2025-01-28 23:24:10.808510 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-28 23:24:10.864505 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:10.880505 | 2025-01-28 23:24:10.880710 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-28 23:24:10.946851 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:10.963277 | 2025-01-28 23:24:10.963468 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-01-28 23:24:10.999416 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:11.007894 | 2025-01-28 23:24:11.008034 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-01-28 23:24:11.043132 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:11.050929 | 2025-01-28 23:24:11.051062 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-01-28 23:24:11.076669 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:11.093866 | 2025-01-28 23:24:11.093992 | TASK [emit-job-header : Print job information] 2025-01-28 23:24:11.148645 | # Job Information 2025-01-28 23:24:11.148874 | Ansible Version: 2.15.3 2025-01-28 23:24:11.148907 | Job: ansible-collection-ensure-readme 2025-01-28 23:24:11.148937 | Pipeline: check 2025-01-28 23:24:11.148957 | Executor: 7d211f194f6a 2025-01-28 23:24:11.148976 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1696 2025-01-28 23:24:11.148995 | Event ID: 9cce5810-ddc1-11ef-8bc9-1499198786a9 2025-01-28 23:24:11.155659 | 2025-01-28 23:24:11.155781 | LOOP [emit-job-header : Print node information] 2025-01-28 23:24:11.305076 | ubuntu-noble | ok: 2025-01-28 23:24:11.305513 | ubuntu-noble | # Node Information 2025-01-28 23:24:11.305594 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-01-28 23:24:11.305654 | ubuntu-noble | Hostname: ubuntu 2025-01-28 23:24:11.305703 | ubuntu-noble | Username: zuul 2025-01-28 23:24:11.305750 | ubuntu-noble | Distro: Ubuntu 24.04 2025-01-28 23:24:11.305794 | ubuntu-noble | Provider: regiocloud-a 2025-01-28 23:24:11.305838 | ubuntu-noble | Label: ubuntu-noble 2025-01-28 23:24:11.305881 | ubuntu-noble | Product Name: OpenStack Nova 2025-01-28 23:24:11.305928 | ubuntu-noble | Interface IP: 81.163.192.60 2025-01-28 23:24:11.331709 | 2025-01-28 23:24:11.331900 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-01-28 23:24:11.830080 | ubuntu-noble -> localhost | changed 2025-01-28 23:24:11.845602 | 2025-01-28 23:24:11.845781 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-01-28 23:24:12.959733 | ubuntu-noble -> localhost | changed 2025-01-28 23:24:12.975186 | 2025-01-28 23:24:12.975354 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-01-28 23:24:13.277695 | ubuntu-noble -> localhost | ok 2025-01-28 23:24:13.291384 | 2025-01-28 23:24:13.291557 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-01-28 23:24:13.335036 | ubuntu-noble | ok 2025-01-28 23:24:13.352752 | ubuntu-noble | included: /var/lib/zuul/builds/54cf6f0288ef4ee882890b90b79471fd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-01-28 23:24:13.360746 | 2025-01-28 23:24:13.360849 | TASK [add-build-sshkey : Create Temp SSH key] 2025-01-28 23:24:14.234841 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-01-28 23:24:14.235434 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/54cf6f0288ef4ee882890b90b79471fd/work/54cf6f0288ef4ee882890b90b79471fd_id_rsa 2025-01-28 23:24:14.235552 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/54cf6f0288ef4ee882890b90b79471fd/work/54cf6f0288ef4ee882890b90b79471fd_id_rsa.pub 2025-01-28 23:24:14.235626 | ubuntu-noble -> localhost | The key fingerprint is: 2025-01-28 23:24:14.235692 | ubuntu-noble -> localhost | SHA256:HG3OwvE9EyeBhHrpNH9AfZ3ehYvf+3ssNwGAM2KyOoI zuul-build-sshkey 2025-01-28 23:24:14.235754 | ubuntu-noble -> localhost | The key's randomart image is: 2025-01-28 23:24:14.235826 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-01-28 23:24:14.235888 | ubuntu-noble -> localhost | | ooo. o.| 2025-01-28 23:24:14.235944 | ubuntu-noble -> localhost | | . o.=o...o.o| 2025-01-28 23:24:14.236000 | ubuntu-noble -> localhost | | +.+o= +oo.o| 2025-01-28 23:24:14.236055 | ubuntu-noble -> localhost | | ..o=B...=...| 2025-01-28 23:24:14.236110 | ubuntu-noble -> localhost | |. . +So+.+... | 2025-01-28 23:24:14.236165 | ubuntu-noble -> localhost | |E. o ... .o...| 2025-01-28 23:24:14.236220 | ubuntu-noble -> localhost | | . . . .o| 2025-01-28 23:24:14.236276 | ubuntu-noble -> localhost | | .o+| 2025-01-28 23:24:14.236547 | ubuntu-noble -> localhost | | +*| 2025-01-28 23:24:14.236623 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-01-28 23:24:14.236772 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.339121 2025-01-28 23:24:14.254089 | 2025-01-28 23:24:14.254271 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-01-28 23:24:14.292200 | ubuntu-noble | ok 2025-01-28 23:24:14.306703 | ubuntu-noble | included: /var/lib/zuul/builds/54cf6f0288ef4ee882890b90b79471fd/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-01-28 23:24:14.319378 | 2025-01-28 23:24:14.319497 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-01-28 23:24:14.344736 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:14.354303 | 2025-01-28 23:24:14.354459 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-01-28 23:24:15.029713 | ubuntu-noble | changed 2025-01-28 23:24:15.037255 | 2025-01-28 23:24:15.037422 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-01-28 23:24:15.330022 | ubuntu-noble | ok 2025-01-28 23:24:15.340158 | 2025-01-28 23:24:15.340295 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-01-28 23:24:15.996801 | ubuntu-noble | changed 2025-01-28 23:24:16.004412 | 2025-01-28 23:24:16.004559 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-01-28 23:24:16.655904 | ubuntu-noble | changed 2025-01-28 23:24:16.664516 | 2025-01-28 23:24:16.664651 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-01-28 23:24:16.690351 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:16.699728 | 2025-01-28 23:24:16.699847 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-01-28 23:24:17.183856 | ubuntu-noble -> localhost | changed 2025-01-28 23:24:17.198507 | 2025-01-28 23:24:17.198673 | TASK [add-build-sshkey : Add back temp key] 2025-01-28 23:24:17.624828 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/54cf6f0288ef4ee882890b90b79471fd/work/54cf6f0288ef4ee882890b90b79471fd_id_rsa (zuul-build-sshkey) 2025-01-28 23:24:17.625109 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.013102 2025-01-28 23:24:17.635120 | 2025-01-28 23:24:17.635269 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-01-28 23:24:18.093758 | ubuntu-noble | ok 2025-01-28 23:24:18.100845 | 2025-01-28 23:24:18.101002 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-01-28 23:24:18.137551 | ubuntu-noble | skipping: Conditional result was False 2025-01-28 23:24:18.152381 | 2025-01-28 23:24:18.152580 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-01-28 23:24:18.615242 | ubuntu-noble | ok 2025-01-28 23:24:18.633742 | 2025-01-28 23:24:18.633911 | TASK [validate-host : Define zuul_info_dir fact] 2025-01-28 23:24:18.665692 | ubuntu-noble | ok 2025-01-28 23:24:18.672428 | 2025-01-28 23:24:18.672547 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-01-28 23:24:18.973141 | ubuntu-noble -> localhost | ok 2025-01-28 23:24:18.982279 | 2025-01-28 23:24:18.982416 | TASK [validate-host : Collect information about the host] 2025-01-28 23:24:20.161650 | ubuntu-noble | ok 2025-01-28 23:24:20.174354 | 2025-01-28 23:24:20.174495 | TASK [validate-host : Sanitize hostname] 2025-01-28 23:24:20.249921 | ubuntu-noble | ok 2025-01-28 23:24:20.258137 | 2025-01-28 23:24:20.258300 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-01-28 23:24:20.852009 | ubuntu-noble -> localhost | changed 2025-01-28 23:24:20.867772 | 2025-01-28 23:24:20.867964 | TASK [validate-host : Collect information about zuul worker] 2025-01-28 23:24:21.383652 | ubuntu-noble | ok 2025-01-28 23:24:21.394664 | 2025-01-28 23:24:21.394848 | TASK [validate-host : Write out all zuul information for each host] 2025-01-28 23:24:21.951462 | ubuntu-noble -> localhost | changed 2025-01-28 23:24:21.967075 | 2025-01-28 23:24:21.967814 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-01-28 23:24:22.214823 | ubuntu-noble | ok 2025-01-28 23:24:22.229526 | 2025-01-28 23:24:22.229675 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-01-28 23:24:23.410277 | ubuntu-noble | changed: 2025-01-28 23:24:23.410626 | ubuntu-noble | cd+++++++++ src/ 2025-01-28 23:24:23.410751 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-01-28 23:24:23.410789 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-01-28 23:24:23.410820 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-01-28 23:24:23.410849 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007795 2025-01-28 23:24:41.362635 | 2025-01-28 23:24:41.362776 | TASK [upload-logs : Upload logs to log server] 2025-01-28 23:24:42.178937 | localhost | Output suppressed because no_log was given 2025-01-28 23:24:42.184762 | 2025-01-28 23:24:42.184920 | LOOP [upload-logs : Compress console log and json output] 2025-01-28 23:24:42.272984 | localhost | skipping: Conditional result was False 2025-01-28 23:24:42.298282 | localhost | skipping: Conditional result was False 2025-01-28 23:24:42.315248 | 2025-01-28 23:24:42.315508 | LOOP [upload-logs : Upload compressed console log and json output] 2025-01-28 23:24:42.382027 | localhost | skipping: Conditional result was False 2025-01-28 23:24:42.382432 | 2025-01-28 23:24:42.401139 | localhost | skipping: Conditional result was False 2025-01-28 23:24:42.414797 | 2025-01-28 23:24:42.414993 | LOOP [upload-logs : Upload console log and json output]