2025-02-07 08:36:08.526759 | Job console starting... 2025-02-07 08:36:08.544695 | Updating repositories 2025-02-07 08:36:08.604488 | Preparing job workspace 2025-02-07 08:36:11.088863 | Running Ansible setup... 2025-02-07 08:36:16.571815 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-07 08:36:17.690236 | 2025-02-07 08:36:17.690421 | PLAY [Base pre] 2025-02-07 08:36:17.732382 | 2025-02-07 08:36:17.732575 | TASK [Setup log path fact] 2025-02-07 08:36:17.764402 | ubuntu-noble | ok 2025-02-07 08:36:17.840302 | 2025-02-07 08:36:17.840599 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-07 08:36:17.944070 | ubuntu-noble | ok 2025-02-07 08:36:17.950844 | 2025-02-07 08:36:17.950959 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-07 08:36:18.031438 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:18.038788 | 2025-02-07 08:36:18.038925 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-07 08:36:18.145408 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:18.153715 | 2025-02-07 08:36:18.153863 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-07 08:36:18.232193 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:18.240447 | 2025-02-07 08:36:18.240584 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-07 08:36:18.303926 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:18.313432 | 2025-02-07 08:36:18.313582 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-07 08:36:18.359651 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:18.375472 | 2025-02-07 08:36:18.375617 | TASK [emit-job-header : Print job information] 2025-02-07 08:36:18.466497 | # Job Information 2025-02-07 08:36:18.466691 | Ansible Version: 2.15.3 2025-02-07 08:36:18.466743 | Job: ansible-collection-ensure-readme 2025-02-07 08:36:18.466774 | Pipeline: check 2025-02-07 08:36:18.466794 | Executor: 7d211f194f6a 2025-02-07 08:36:18.466813 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1751 2025-02-07 08:36:18.466831 | Event ID: 8e9d8940-e52e-11ef-985a-ea75c8d487a5 2025-02-07 08:36:18.474196 | 2025-02-07 08:36:18.474323 | LOOP [emit-job-header : Print node information] 2025-02-07 08:36:18.643099 | ubuntu-noble | ok: 2025-02-07 08:36:18.643425 | ubuntu-noble | # Node Information 2025-02-07 08:36:18.643469 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-07 08:36:18.643494 | ubuntu-noble | Hostname: ubuntu 2025-02-07 08:36:18.643517 | ubuntu-noble | Username: zuul 2025-02-07 08:36:18.643537 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-07 08:36:18.643557 | ubuntu-noble | Provider: regiocloud-a 2025-02-07 08:36:18.643576 | ubuntu-noble | Label: ubuntu-noble 2025-02-07 08:36:18.643595 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-07 08:36:18.643617 | ubuntu-noble | Interface IP: 81.163.193.73 2025-02-07 08:36:18.671867 | 2025-02-07 08:36:18.672011 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-07 08:36:19.302324 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:19.311172 | 2025-02-07 08:36:19.311293 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-07 08:36:20.887305 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:20.917515 | 2025-02-07 08:36:20.917651 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-07 08:36:21.248426 | ubuntu-noble -> localhost | ok 2025-02-07 08:36:21.255695 | 2025-02-07 08:36:21.255805 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-07 08:36:21.298872 | ubuntu-noble | ok 2025-02-07 08:36:21.324995 | ubuntu-noble | included: /var/lib/zuul/builds/3806689bd1e544d7a927146dae139d69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-07 08:36:21.342829 | 2025-02-07 08:36:21.342930 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-07 08:36:22.040754 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-07 08:36:22.040938 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/3806689bd1e544d7a927146dae139d69/work/3806689bd1e544d7a927146dae139d69_id_rsa 2025-02-07 08:36:22.040973 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/3806689bd1e544d7a927146dae139d69/work/3806689bd1e544d7a927146dae139d69_id_rsa.pub 2025-02-07 08:36:22.040998 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-07 08:36:22.041022 | ubuntu-noble -> localhost | SHA256:H33OBZ6EbfvBTYiFDDGibRJz9Upl7rfUw8MRb5i4dqQ zuul-build-sshkey 2025-02-07 08:36:22.041043 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-07 08:36:22.041067 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-07 08:36:22.041089 | ubuntu-noble -> localhost | | o o.=+o... | 2025-02-07 08:36:22.041109 | ubuntu-noble -> localhost | | * . *o* +o| 2025-02-07 08:36:22.041128 | ubuntu-noble -> localhost | | o o . * X.+| 2025-02-07 08:36:22.041147 | ubuntu-noble -> localhost | | o . + B+Bo| 2025-02-07 08:36:22.041165 | ubuntu-noble -> localhost | | S o E OB+| 2025-02-07 08:36:22.041184 | ubuntu-noble -> localhost | | . o B ++| 2025-02-07 08:36:22.041203 | ubuntu-noble -> localhost | | . + .| 2025-02-07 08:36:22.041223 | ubuntu-noble -> localhost | | | 2025-02-07 08:36:22.041243 | ubuntu-noble -> localhost | | | 2025-02-07 08:36:22.041261 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-07 08:36:22.041307 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.131077 2025-02-07 08:36:22.050563 | 2025-02-07 08:36:22.050654 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-07 08:36:22.081513 | ubuntu-noble | ok 2025-02-07 08:36:22.105223 | ubuntu-noble | included: /var/lib/zuul/builds/3806689bd1e544d7a927146dae139d69/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-07 08:36:22.120601 | 2025-02-07 08:36:22.120704 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-07 08:36:22.175331 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:22.182413 | 2025-02-07 08:36:22.182506 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-07 08:36:22.987996 | ubuntu-noble | changed 2025-02-07 08:36:23.002604 | 2025-02-07 08:36:23.002692 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-07 08:36:23.297101 | ubuntu-noble | ok 2025-02-07 08:36:23.305851 | 2025-02-07 08:36:23.305945 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-07 08:36:24.113682 | ubuntu-noble | changed 2025-02-07 08:36:24.121287 | 2025-02-07 08:36:24.121383 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-07 08:36:24.899997 | ubuntu-noble | changed 2025-02-07 08:36:24.906628 | 2025-02-07 08:36:24.906718 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-07 08:36:24.971824 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:24.977911 | 2025-02-07 08:36:24.977995 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-07 08:36:25.398459 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:25.412586 | 2025-02-07 08:36:25.412693 | TASK [add-build-sshkey : Add back temp key] 2025-02-07 08:36:25.824978 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/3806689bd1e544d7a927146dae139d69/work/3806689bd1e544d7a927146dae139d69_id_rsa (zuul-build-sshkey) 2025-02-07 08:36:25.825137 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.011567 2025-02-07 08:36:25.831832 | 2025-02-07 08:36:25.831920 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-07 08:36:26.202389 | ubuntu-noble | ok 2025-02-07 08:36:26.209890 | 2025-02-07 08:36:26.209983 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-07 08:36:26.267666 | ubuntu-noble | skipping: Conditional result was False 2025-02-07 08:36:26.284591 | 2025-02-07 08:36:26.284695 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-07 08:36:26.746395 | ubuntu-noble | ok 2025-02-07 08:36:26.770586 | 2025-02-07 08:36:26.770692 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-07 08:36:26.847605 | ubuntu-noble | ok 2025-02-07 08:36:26.858482 | 2025-02-07 08:36:26.858586 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-07 08:36:27.363776 | ubuntu-noble -> localhost | ok 2025-02-07 08:36:27.370055 | 2025-02-07 08:36:27.370131 | TASK [validate-host : Collect information about the host] 2025-02-07 08:36:28.586145 | ubuntu-noble | ok 2025-02-07 08:36:28.631561 | 2025-02-07 08:36:28.631701 | TASK [validate-host : Sanitize hostname] 2025-02-07 08:36:28.723286 | ubuntu-noble | ok 2025-02-07 08:36:28.729111 | 2025-02-07 08:36:28.729204 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-07 08:36:29.305127 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:29.318147 | 2025-02-07 08:36:29.318287 | TASK [validate-host : Collect information about zuul worker] 2025-02-07 08:36:29.811093 | ubuntu-noble | ok 2025-02-07 08:36:29.822097 | 2025-02-07 08:36:29.822202 | TASK [validate-host : Write out all zuul information for each host] 2025-02-07 08:36:30.634636 | ubuntu-noble -> localhost | changed 2025-02-07 08:36:30.649599 | 2025-02-07 08:36:30.649691 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-07 08:36:30.965253 | ubuntu-noble | ok 2025-02-07 08:36:30.972573 | 2025-02-07 08:36:30.972976 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-07 08:36:32.307805 | ubuntu-noble | changed: 2025-02-07 08:36:32.307959 | ubuntu-noble | cd+++++++++ src/ 2025-02-07 08:36:32.307990 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-07 08:36:32.308013 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-07 08:36:32.308034 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-07 08:36:32.308054 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007774 2025-02-07 08:36:51.535359 | 2025-02-07 08:36:51.535824 | TASK [upload-logs : Upload logs to log server] 2025-02-07 08:36:52.194404 | localhost | Output suppressed because no_log was given 2025-02-07 08:36:52.199885 | 2025-02-07 08:36:52.200049 | LOOP [upload-logs : Compress console log and json output] 2025-02-07 08:36:52.275918 | localhost | skipping: Conditional result was False 2025-02-07 08:36:52.293101 | localhost | skipping: Conditional result was False 2025-02-07 08:36:52.306670 | 2025-02-07 08:36:52.306860 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-07 08:36:52.372151 | localhost | skipping: Conditional result was False 2025-02-07 08:36:52.373074 | 2025-02-07 08:36:52.385515 | localhost | skipping: Conditional result was False 2025-02-07 08:36:52.399698 | 2025-02-07 08:36:52.399886 | LOOP [upload-logs : Upload console log and json output]