2025-02-05 20:27:34.904343 | Job console starting... 2025-02-05 20:27:34.921931 | Updating repositories 2025-02-05 20:27:34.975167 | Preparing job workspace 2025-02-05 20:27:36.691001 | Running Ansible setup... 2025-02-05 20:27:42.179548 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-05 20:27:43.075879 | 2025-02-05 20:27:43.076135 | PLAY [Base pre] 2025-02-05 20:27:43.121741 | 2025-02-05 20:27:43.121946 | TASK [Setup log path fact] 2025-02-05 20:27:43.164342 | ubuntu-noble | ok 2025-02-05 20:27:43.190940 | 2025-02-05 20:27:43.191105 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-05 20:27:43.253817 | ubuntu-noble | ok 2025-02-05 20:27:43.261435 | 2025-02-05 20:27:43.261575 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-05 20:27:43.328652 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:43.336565 | 2025-02-05 20:27:43.336726 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-05 20:27:43.444854 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:43.452948 | 2025-02-05 20:27:43.464509 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-05 20:27:43.513024 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:43.524989 | 2025-02-05 20:27:43.527647 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-05 20:27:43.552991 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:43.560465 | 2025-02-05 20:27:43.560587 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-05 20:27:43.595661 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:43.612956 | 2025-02-05 20:27:43.613098 | TASK [emit-job-header : Print job information] 2025-02-05 20:27:43.681189 | # Job Information 2025-02-05 20:27:43.681436 | Ansible Version: 2.15.3 2025-02-05 20:27:43.681476 | Job: ansible-collection-ensure-readme 2025-02-05 20:27:43.681514 | Pipeline: check 2025-02-05 20:27:43.681542 | Executor: 7d211f194f6a 2025-02-05 20:27:43.681570 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1751 2025-02-05 20:27:43.681596 | Event ID: 8944d550-e3ff-11ef-9be6-e35781a6a0df 2025-02-05 20:27:43.693154 | 2025-02-05 20:27:43.693320 | LOOP [emit-job-header : Print node information] 2025-02-05 20:27:43.862040 | ubuntu-noble | ok: 2025-02-05 20:27:43.862254 | ubuntu-noble | # Node Information 2025-02-05 20:27:43.862288 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-05 20:27:43.862312 | ubuntu-noble | Hostname: ubuntu 2025-02-05 20:27:43.862334 | ubuntu-noble | Username: zuul 2025-02-05 20:27:43.862355 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-05 20:27:43.862375 | ubuntu-noble | Provider: regiocloud-a 2025-02-05 20:27:43.862394 | ubuntu-noble | Label: ubuntu-noble 2025-02-05 20:27:43.862414 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-05 20:27:43.862434 | ubuntu-noble | Interface IP: 81.163.192.66 2025-02-05 20:27:43.878304 | 2025-02-05 20:27:43.878450 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-05 20:27:44.651959 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:44.666592 | 2025-02-05 20:27:44.666866 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-05 20:27:46.013764 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:46.025203 | 2025-02-05 20:27:46.025290 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-05 20:27:46.353705 | ubuntu-noble -> localhost | ok 2025-02-05 20:27:46.359592 | 2025-02-05 20:27:46.359675 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-05 20:27:46.388343 | ubuntu-noble | ok 2025-02-05 20:27:46.417766 | ubuntu-noble | included: /var/lib/zuul/builds/7f7e2d820bf0447a9cd1a3ca75c8db97/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-05 20:27:46.431886 | 2025-02-05 20:27:46.432108 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-05 20:27:47.692862 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-05 20:27:47.693040 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f7e2d820bf0447a9cd1a3ca75c8db97/work/7f7e2d820bf0447a9cd1a3ca75c8db97_id_rsa 2025-02-05 20:27:47.693071 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f7e2d820bf0447a9cd1a3ca75c8db97/work/7f7e2d820bf0447a9cd1a3ca75c8db97_id_rsa.pub 2025-02-05 20:27:47.693091 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-05 20:27:47.693110 | ubuntu-noble -> localhost | SHA256:qh9pvqkDIo8JaCm/LjkV3PqXk4dNhzQ93ER1EdQahYc zuul-build-sshkey 2025-02-05 20:27:47.693128 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-05 20:27:47.693149 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-05 20:27:47.693167 | ubuntu-noble -> localhost | | ...oB*| 2025-02-05 20:27:47.693184 | ubuntu-noble -> localhost | | . E.o| 2025-02-05 20:27:47.693200 | ubuntu-noble -> localhost | | . . o o + | 2025-02-05 20:27:47.693216 | ubuntu-noble -> localhost | | o . o + . . | 2025-02-05 20:27:47.693231 | ubuntu-noble -> localhost | |. .o .So . | 2025-02-05 20:27:47.693247 | ubuntu-noble -> localhost | |*o+ oo . | 2025-02-05 20:27:47.693263 | ubuntu-noble -> localhost | |=O o =* . | 2025-02-05 20:27:47.693279 | ubuntu-noble -> localhost | |* o o+*oo | 2025-02-05 20:27:47.693295 | ubuntu-noble -> localhost | | +o.o==+ | 2025-02-05 20:27:47.693311 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-05 20:27:47.693350 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.415675 2025-02-05 20:27:47.699628 | 2025-02-05 20:27:47.699715 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-05 20:27:47.748678 | ubuntu-noble | ok 2025-02-05 20:27:47.766092 | ubuntu-noble | included: /var/lib/zuul/builds/7f7e2d820bf0447a9cd1a3ca75c8db97/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-05 20:27:47.794639 | 2025-02-05 20:27:47.794738 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-05 20:27:47.863747 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:47.872481 | 2025-02-05 20:27:47.872577 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-05 20:27:48.733608 | ubuntu-noble | changed 2025-02-05 20:27:48.740291 | 2025-02-05 20:27:48.740401 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-05 20:27:49.025255 | ubuntu-noble | ok 2025-02-05 20:27:49.032615 | 2025-02-05 20:27:49.032713 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-05 20:27:49.701844 | ubuntu-noble | changed 2025-02-05 20:27:49.712608 | 2025-02-05 20:27:49.712714 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-05 20:27:50.412157 | ubuntu-noble | changed 2025-02-05 20:27:50.420561 | 2025-02-05 20:27:50.420654 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-05 20:27:50.444242 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:50.451705 | 2025-02-05 20:27:50.451801 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-05 20:27:50.894364 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:50.908858 | 2025-02-05 20:27:50.908986 | TASK [add-build-sshkey : Add back temp key] 2025-02-05 20:27:51.349955 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/7f7e2d820bf0447a9cd1a3ca75c8db97/work/7f7e2d820bf0447a9cd1a3ca75c8db97_id_rsa (zuul-build-sshkey) 2025-02-05 20:27:51.350197 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.012270 2025-02-05 20:27:51.362005 | 2025-02-05 20:27:51.362099 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-05 20:27:51.808120 | ubuntu-noble | ok 2025-02-05 20:27:51.827643 | 2025-02-05 20:27:51.827834 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-05 20:27:51.879739 | ubuntu-noble | skipping: Conditional result was False 2025-02-05 20:27:51.919910 | 2025-02-05 20:27:51.920373 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-05 20:27:52.412678 | ubuntu-noble | ok 2025-02-05 20:27:52.444825 | 2025-02-05 20:27:52.445295 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-05 20:27:52.534687 | ubuntu-noble | ok 2025-02-05 20:27:52.541504 | 2025-02-05 20:27:52.541624 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-05 20:27:53.098025 | ubuntu-noble -> localhost | ok 2025-02-05 20:27:53.105794 | 2025-02-05 20:27:53.105929 | TASK [validate-host : Collect information about the host] 2025-02-05 20:27:54.667295 | ubuntu-noble | ok 2025-02-05 20:27:54.709982 | 2025-02-05 20:27:54.710480 | TASK [validate-host : Sanitize hostname] 2025-02-05 20:27:54.845879 | ubuntu-noble | ok 2025-02-05 20:27:54.851952 | 2025-02-05 20:27:54.852048 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-05 20:27:55.651453 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:55.658616 | 2025-02-05 20:27:55.658725 | TASK [validate-host : Collect information about zuul worker] 2025-02-05 20:27:56.143612 | ubuntu-noble | ok 2025-02-05 20:27:56.159459 | 2025-02-05 20:27:56.159564 | TASK [validate-host : Write out all zuul information for each host] 2025-02-05 20:27:56.756928 | ubuntu-noble -> localhost | changed 2025-02-05 20:27:56.781936 | 2025-02-05 20:27:56.782059 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-05 20:27:57.102437 | ubuntu-noble | ok 2025-02-05 20:27:57.111942 | 2025-02-05 20:27:57.112042 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-05 20:27:58.421662 | ubuntu-noble | changed: 2025-02-05 20:27:58.421824 | ubuntu-noble | cd+++++++++ src/ 2025-02-05 20:27:58.421856 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-05 20:27:58.421880 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-05 20:27:58.421914 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-05 20:27:58.421934 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007420 2025-02-05 20:28:17.326277 | 2025-02-05 20:28:17.326426 | TASK [upload-logs : Upload logs to log server] 2025-02-05 20:28:17.950715 | localhost | Output suppressed because no_log was given 2025-02-05 20:28:17.954706 | 2025-02-05 20:28:17.954833 | LOOP [upload-logs : Compress console log and json output] 2025-02-05 20:28:18.020201 | localhost | skipping: Conditional result was False 2025-02-05 20:28:18.058071 | localhost | skipping: Conditional result was False 2025-02-05 20:28:18.067873 | 2025-02-05 20:28:18.068087 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-05 20:28:18.137695 | localhost | skipping: Conditional result was False 2025-02-05 20:28:18.138476 | 2025-02-05 20:28:18.150501 | localhost | skipping: Conditional result was False 2025-02-05 20:28:18.162697 | 2025-02-05 20:28:18.162879 | LOOP [upload-logs : Upload console log and json output]