2025-02-18 08:16:35.820702 | Job console starting... 2025-02-18 08:16:35.842280 | Updating repositories 2025-02-18 08:16:35.912840 | Preparing job workspace 2025-02-18 08:16:38.439149 | Running Ansible setup... 2025-02-18 08:16:45.247052 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-18 08:16:46.378648 | 2025-02-18 08:16:46.378817 | PLAY [Base pre] 2025-02-18 08:16:46.425754 | 2025-02-18 08:16:46.425924 | TASK [Setup log path fact] 2025-02-18 08:16:46.469324 | ubuntu-noble | ok 2025-02-18 08:16:46.491569 | 2025-02-18 08:16:46.491730 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 08:16:46.568034 | ubuntu-noble | ok 2025-02-18 08:16:46.575066 | 2025-02-18 08:16:46.575195 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 08:16:46.610819 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.618375 | 2025-02-18 08:16:46.618541 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 08:16:46.675495 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.683308 | 2025-02-18 08:16:46.683441 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-18 08:16:46.719477 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.726993 | 2025-02-18 08:16:46.727123 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-18 08:16:46.752149 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.759589 | 2025-02-18 08:16:46.759710 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-18 08:16:46.784398 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:46.802850 | 2025-02-18 08:16:46.802985 | TASK [emit-job-header : Print job information] 2025-02-18 08:16:46.896378 | # Job Information 2025-02-18 08:16:46.896662 | Ansible Version: 2.15.3 2025-02-18 08:16:46.896695 | Job: ansible-collection-ensure-readme 2025-02-18 08:16:46.896725 | Pipeline: check 2025-02-18 08:16:46.896746 | Executor: 7d211f194f6a 2025-02-18 08:16:46.896765 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1763 2025-02-18 08:16:46.896783 | Event ID: a64d02a0-edd0-11ef-95bf-a93972d2b6a0 2025-02-18 08:16:46.903386 | 2025-02-18 08:16:46.903522 | LOOP [emit-job-header : Print node information] 2025-02-18 08:16:47.201356 | ubuntu-noble | ok: 2025-02-18 08:16:47.205004 | ubuntu-noble | # Node Information 2025-02-18 08:16:47.205096 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-18 08:16:47.205125 | ubuntu-noble | Hostname: ubuntu 2025-02-18 08:16:47.205148 | ubuntu-noble | Username: zuul 2025-02-18 08:16:47.205169 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-18 08:16:47.205189 | ubuntu-noble | Provider: regiocloud-a 2025-02-18 08:16:47.205208 | ubuntu-noble | Label: ubuntu-noble 2025-02-18 08:16:47.205227 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-18 08:16:47.205247 | ubuntu-noble | Interface IP: 81.163.193.55 2025-02-18 08:16:47.233987 | 2025-02-18 08:16:47.234121 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-18 08:16:47.904321 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:47.915982 | 2025-02-18 08:16:47.916121 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-18 08:16:49.585717 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:49.601487 | 2025-02-18 08:16:49.601636 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-18 08:16:50.077083 | ubuntu-noble -> localhost | ok 2025-02-18 08:16:50.085390 | 2025-02-18 08:16:50.085571 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-18 08:16:50.158045 | ubuntu-noble | ok 2025-02-18 08:16:50.187900 | ubuntu-noble | included: /var/lib/zuul/builds/ae9954fa367240a29d883d85e090f8b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-18 08:16:50.207363 | 2025-02-18 08:16:50.207524 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-18 08:16:51.343659 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-18 08:16:51.343840 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/ae9954fa367240a29d883d85e090f8b1/work/ae9954fa367240a29d883d85e090f8b1_id_rsa 2025-02-18 08:16:51.343875 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/ae9954fa367240a29d883d85e090f8b1/work/ae9954fa367240a29d883d85e090f8b1_id_rsa.pub 2025-02-18 08:16:51.343899 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-18 08:16:51.343921 | ubuntu-noble -> localhost | SHA256:PVYpTjQO7KJr5m2n8m0b0/ySpV5gbwedAPizJB6SCkc zuul-build-sshkey 2025-02-18 08:16:51.343943 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-18 08:16:51.343967 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-18 08:16:51.343989 | ubuntu-noble -> localhost | | ...+ | 2025-02-18 08:16:51.344009 | ubuntu-noble -> localhost | | E o+ o . | 2025-02-18 08:16:51.344028 | ubuntu-noble -> localhost | | . o .+ + | 2025-02-18 08:16:51.344048 | ubuntu-noble -> localhost | | . . + +++o o . | 2025-02-18 08:16:51.344066 | ubuntu-noble -> localhost | | o o +S+Bo. o | 2025-02-18 08:16:51.344085 | ubuntu-noble -> localhost | | o .=.+.. | 2025-02-18 08:16:51.344104 | ubuntu-noble -> localhost | | . o o++ . | 2025-02-18 08:16:51.344123 | ubuntu-noble -> localhost | | =...oo++ . | 2025-02-18 08:16:51.344142 | ubuntu-noble -> localhost | | +.+++oo... | 2025-02-18 08:16:51.344161 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-18 08:16:51.344204 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.427717 2025-02-18 08:16:51.357224 | 2025-02-18 08:16:51.357322 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-18 08:16:51.412542 | ubuntu-noble | ok 2025-02-18 08:16:51.427371 | ubuntu-noble | included: /var/lib/zuul/builds/ae9954fa367240a29d883d85e090f8b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-18 08:16:51.447369 | 2025-02-18 08:16:51.447472 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-18 08:16:51.507064 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:51.521496 | 2025-02-18 08:16:51.521602 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-18 08:16:52.266531 | ubuntu-noble | changed 2025-02-18 08:16:52.277171 | 2025-02-18 08:16:52.277314 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-18 08:16:52.576403 | ubuntu-noble | ok 2025-02-18 08:16:52.583077 | 2025-02-18 08:16:52.583166 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-18 08:16:53.294618 | ubuntu-noble | changed 2025-02-18 08:16:53.302049 | 2025-02-18 08:16:53.302137 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-18 08:16:54.039565 | ubuntu-noble | changed 2025-02-18 08:16:54.062841 | 2025-02-18 08:16:54.062969 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-18 08:16:54.118174 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:54.126623 | 2025-02-18 08:16:54.126725 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-18 08:16:54.876328 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:54.976899 | 2025-02-18 08:16:54.977015 | TASK [add-build-sshkey : Add back temp key] 2025-02-18 08:16:55.375479 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/ae9954fa367240a29d883d85e090f8b1/work/ae9954fa367240a29d883d85e090f8b1_id_rsa (zuul-build-sshkey) 2025-02-18 08:16:55.375706 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.024632 2025-02-18 08:16:55.382945 | 2025-02-18 08:16:55.383038 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-18 08:16:55.761017 | ubuntu-noble | ok 2025-02-18 08:16:55.767714 | 2025-02-18 08:16:55.767808 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-18 08:16:55.787381 | ubuntu-noble | skipping: Conditional result was False 2025-02-18 08:16:55.800193 | 2025-02-18 08:16:55.800285 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-18 08:16:56.219542 | ubuntu-noble | ok 2025-02-18 08:16:56.232324 | 2025-02-18 08:16:56.232445 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-18 08:16:56.282270 | ubuntu-noble | ok 2025-02-18 08:16:56.288551 | 2025-02-18 08:16:56.288639 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-18 08:16:56.627124 | ubuntu-noble -> localhost | ok 2025-02-18 08:16:56.634577 | 2025-02-18 08:16:56.634677 | TASK [validate-host : Collect information about the host] 2025-02-18 08:16:57.870665 | ubuntu-noble | ok 2025-02-18 08:16:57.883981 | 2025-02-18 08:16:57.884120 | TASK [validate-host : Sanitize hostname] 2025-02-18 08:16:57.969940 | ubuntu-noble | ok 2025-02-18 08:16:57.976490 | 2025-02-18 08:16:57.976637 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-18 08:16:58.841837 | ubuntu-noble -> localhost | changed 2025-02-18 08:16:58.848996 | 2025-02-18 08:16:58.849121 | TASK [validate-host : Collect information about zuul worker] 2025-02-18 08:16:59.418126 | ubuntu-noble | ok 2025-02-18 08:16:59.424876 | 2025-02-18 08:16:59.425001 | TASK [validate-host : Write out all zuul information for each host] 2025-02-18 08:17:00.147158 | ubuntu-noble -> localhost | changed 2025-02-18 08:17:00.170922 | 2025-02-18 08:17:00.171056 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-18 08:17:00.528020 | ubuntu-noble | ok 2025-02-18 08:17:00.535018 | 2025-02-18 08:17:00.535133 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-18 08:17:01.763534 | ubuntu-noble | changed: 2025-02-18 08:17:01.763743 | ubuntu-noble | cd+++++++++ src/ 2025-02-18 08:17:01.763775 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-18 08:17:01.763798 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-18 08:17:01.763819 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-18 08:17:01.763839 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007258 2025-02-18 08:17:21.481568 | 2025-02-18 08:17:21.481741 | TASK [upload-logs : Upload logs to log server] 2025-02-18 08:17:22.109655 | localhost | Output suppressed because no_log was given 2025-02-18 08:17:22.118143 | 2025-02-18 08:17:22.118385 | LOOP [upload-logs : Compress console log and json output] 2025-02-18 08:17:22.196742 | localhost | skipping: Conditional result was False 2025-02-18 08:17:22.218602 | localhost | skipping: Conditional result was False 2025-02-18 08:17:22.229042 | 2025-02-18 08:17:22.229210 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-18 08:17:22.297567 | localhost | skipping: Conditional result was False 2025-02-18 08:17:22.298235 | 2025-02-18 08:17:22.310431 | localhost | skipping: Conditional result was False 2025-02-18 08:17:22.319395 | 2025-02-18 08:17:22.319573 | LOOP [upload-logs : Upload console log and json output]