2025-02-26 02:31:31.354599 | Job console starting... 2025-02-26 02:31:31.374976 | Updating repositories 2025-02-26 02:31:31.418386 | Preparing job workspace 2025-02-26 02:31:33.607881 | Running Ansible setup... 2025-02-26 02:31:39.253733 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-02-26 02:31:39.974077 | 2025-02-26 02:31:39.974183 | PLAY [Base pre] 2025-02-26 02:31:40.015757 | 2025-02-26 02:31:40.015893 | TASK [Setup log path fact] 2025-02-26 02:31:40.067606 | ubuntu-noble | ok 2025-02-26 02:31:40.113899 | 2025-02-26 02:31:40.114027 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-26 02:31:40.156380 | ubuntu-noble | ok 2025-02-26 02:31:40.162860 | 2025-02-26 02:31:40.163065 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-26 02:31:40.197081 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:40.204056 | 2025-02-26 02:31:40.204157 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-26 02:31:40.247942 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:40.255022 | 2025-02-26 02:31:40.255120 | TASK [set-zuul-log-path-fact : Set log path for a change] 2025-02-26 02:31:40.279090 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:40.286056 | 2025-02-26 02:31:40.286154 | TASK [set-zuul-log-path-fact : Set log path for a ref update] 2025-02-26 02:31:40.310045 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:40.319463 | 2025-02-26 02:31:40.319588 | TASK [set-zuul-log-path-fact : Set log path for a periodic job] 2025-02-26 02:31:40.373934 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:40.388722 | 2025-02-26 02:31:40.388836 | TASK [emit-job-header : Print job information] 2025-02-26 02:31:40.439025 | # Job Information 2025-02-26 02:31:40.439171 | Ansible Version: 2.15.3 2025-02-26 02:31:40.439201 | Job: ansible-collection-ensure-readme 2025-02-26 02:31:40.439231 | Pipeline: check 2025-02-26 02:31:40.439251 | Executor: 7d211f194f6a 2025-02-26 02:31:40.439270 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1771 2025-02-26 02:31:40.439289 | Event ID: c551b2d0-f3e9-11ef-9d4a-fce00588900c 2025-02-26 02:31:40.447747 | 2025-02-26 02:31:40.447836 | LOOP [emit-job-header : Print node information] 2025-02-26 02:31:40.711932 | ubuntu-noble | ok: 2025-02-26 02:31:40.712087 | ubuntu-noble | # Node Information 2025-02-26 02:31:40.712120 | ubuntu-noble | Inventory Hostname: ubuntu-noble 2025-02-26 02:31:40.712145 | ubuntu-noble | Hostname: ubuntu 2025-02-26 02:31:40.712167 | ubuntu-noble | Username: zuul 2025-02-26 02:31:40.712188 | ubuntu-noble | Distro: Ubuntu 24.04 2025-02-26 02:31:40.712209 | ubuntu-noble | Provider: regiocloud-a 2025-02-26 02:31:40.712228 | ubuntu-noble | Label: ubuntu-noble 2025-02-26 02:31:40.712247 | ubuntu-noble | Product Name: OpenStack Nova 2025-02-26 02:31:40.712268 | ubuntu-noble | Interface IP: 81.163.192.54 2025-02-26 02:31:40.738158 | 2025-02-26 02:31:40.738272 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-02-26 02:31:41.494695 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:41.502817 | 2025-02-26 02:31:41.502915 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-02-26 02:31:43.105567 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:43.159065 | 2025-02-26 02:31:43.159173 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-02-26 02:31:43.605652 | ubuntu-noble -> localhost | ok 2025-02-26 02:31:43.613792 | 2025-02-26 02:31:43.613886 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-02-26 02:31:43.643637 | ubuntu-noble | ok 2025-02-26 02:31:43.669688 | ubuntu-noble | included: /var/lib/zuul/builds/f7a65f4f88fc4147864530d3f96a9600/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-02-26 02:31:43.688681 | 2025-02-26 02:31:43.688782 | TASK [add-build-sshkey : Create Temp SSH key] 2025-02-26 02:31:44.821409 | ubuntu-noble -> localhost | Generating public/private rsa key pair. 2025-02-26 02:31:44.821646 | ubuntu-noble -> localhost | Your identification has been saved in /var/lib/zuul/builds/f7a65f4f88fc4147864530d3f96a9600/work/f7a65f4f88fc4147864530d3f96a9600_id_rsa 2025-02-26 02:31:44.821685 | ubuntu-noble -> localhost | Your public key has been saved in /var/lib/zuul/builds/f7a65f4f88fc4147864530d3f96a9600/work/f7a65f4f88fc4147864530d3f96a9600_id_rsa.pub 2025-02-26 02:31:44.821709 | ubuntu-noble -> localhost | The key fingerprint is: 2025-02-26 02:31:44.821733 | ubuntu-noble -> localhost | SHA256:zhpcQoH52Pp4tMSlBGulteZULUiNGbuZvI8MiDoW9aA zuul-build-sshkey 2025-02-26 02:31:44.821755 | ubuntu-noble -> localhost | The key's randomart image is: 2025-02-26 02:31:44.821780 | ubuntu-noble -> localhost | +---[RSA 3072]----+ 2025-02-26 02:31:44.821801 | ubuntu-noble -> localhost | | ++* . | 2025-02-26 02:31:44.821821 | ubuntu-noble -> localhost | | + *o+ . | 2025-02-26 02:31:44.821841 | ubuntu-noble -> localhost | | Xoo . | 2025-02-26 02:31:44.821860 | ubuntu-noble -> localhost | | o=oB+. | 2025-02-26 02:31:44.821879 | ubuntu-noble -> localhost | | o.oB*oS | 2025-02-26 02:31:44.821898 | ubuntu-noble -> localhost | | E ..o** | 2025-02-26 02:31:44.821916 | ubuntu-noble -> localhost | |. o .=+.o | 2025-02-26 02:31:44.821936 | ubuntu-noble -> localhost | |o. .o+= | 2025-02-26 02:31:44.821956 | ubuntu-noble -> localhost | |o. .+ . | 2025-02-26 02:31:44.821974 | ubuntu-noble -> localhost | +----[SHA256]-----+ 2025-02-26 02:31:44.822019 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.459748 2025-02-26 02:31:44.830647 | 2025-02-26 02:31:44.830779 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-02-26 02:31:44.869198 | ubuntu-noble | ok 2025-02-26 02:31:44.877944 | ubuntu-noble | included: /var/lib/zuul/builds/f7a65f4f88fc4147864530d3f96a9600/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-02-26 02:31:44.896017 | 2025-02-26 02:31:44.896103 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-02-26 02:31:44.929789 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:44.941090 | 2025-02-26 02:31:44.941218 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-02-26 02:31:45.834486 | ubuntu-noble | changed 2025-02-26 02:31:45.844914 | 2025-02-26 02:31:45.845006 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-02-26 02:31:46.157079 | ubuntu-noble | ok 2025-02-26 02:31:46.162660 | 2025-02-26 02:31:46.162740 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-02-26 02:31:46.903120 | ubuntu-noble | changed 2025-02-26 02:31:46.910007 | 2025-02-26 02:31:46.910094 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-02-26 02:31:47.637998 | ubuntu-noble | changed 2025-02-26 02:31:47.645340 | 2025-02-26 02:31:47.645438 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-02-26 02:31:47.673676 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:47.680064 | 2025-02-26 02:31:47.680142 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-02-26 02:31:48.289803 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:48.342444 | 2025-02-26 02:31:48.342571 | TASK [add-build-sshkey : Add back temp key] 2025-02-26 02:31:48.660244 | ubuntu-noble -> localhost | Identity added: /var/lib/zuul/builds/f7a65f4f88fc4147864530d3f96a9600/work/f7a65f4f88fc4147864530d3f96a9600_id_rsa (zuul-build-sshkey) 2025-02-26 02:31:48.660464 | ubuntu-noble -> localhost | ok: Runtime: 0:00:00.009957 2025-02-26 02:31:48.667875 | 2025-02-26 02:31:48.667969 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-02-26 02:31:49.284664 | ubuntu-noble | ok 2025-02-26 02:31:49.291103 | 2025-02-26 02:31:49.291204 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-02-26 02:31:49.351152 | ubuntu-noble | skipping: Conditional result was False 2025-02-26 02:31:49.377012 | 2025-02-26 02:31:49.377121 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-02-26 02:31:49.824833 | ubuntu-noble | ok 2025-02-26 02:31:49.849907 | 2025-02-26 02:31:49.850012 | TASK [validate-host : Define zuul_info_dir fact] 2025-02-26 02:31:49.880633 | ubuntu-noble | ok 2025-02-26 02:31:49.886435 | 2025-02-26 02:31:49.886539 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-02-26 02:31:50.216914 | ubuntu-noble -> localhost | ok 2025-02-26 02:31:50.224275 | 2025-02-26 02:31:50.224364 | TASK [validate-host : Collect information about the host] 2025-02-26 02:31:51.511771 | ubuntu-noble | ok 2025-02-26 02:31:51.523486 | 2025-02-26 02:31:51.523574 | TASK [validate-host : Sanitize hostname] 2025-02-26 02:31:51.599767 | ubuntu-noble | ok 2025-02-26 02:31:51.608238 | 2025-02-26 02:31:51.608352 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-02-26 02:31:52.050864 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:52.066708 | 2025-02-26 02:31:52.067066 | TASK [validate-host : Collect information about zuul worker] 2025-02-26 02:31:52.521425 | ubuntu-noble | ok 2025-02-26 02:31:52.526699 | 2025-02-26 02:31:52.526781 | TASK [validate-host : Write out all zuul information for each host] 2025-02-26 02:31:53.001291 | ubuntu-noble -> localhost | changed 2025-02-26 02:31:53.012978 | 2025-02-26 02:31:53.013078 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-02-26 02:31:53.329749 | ubuntu-noble | ok 2025-02-26 02:31:53.336194 | 2025-02-26 02:31:53.336287 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-02-26 02:31:54.811610 | ubuntu-noble | changed: 2025-02-26 02:31:54.811767 | ubuntu-noble | cd+++++++++ src/ 2025-02-26 02:31:54.811799 | ubuntu-noble | cd+++++++++ src/github.com/ 2025-02-26 02:31:54.811821 | ubuntu-noble | cd+++++++++ src/github.com/osism/ 2025-02-26 02:31:54.811842 | ubuntu-noble | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-02-26 02:31:54.811862 | ubuntu-noble | localhost | ok: Runtime: 0:00:00.007524 2025-02-26 02:32:14.891154 | 2025-02-26 02:32:14.891265 | TASK [upload-logs : Upload logs to log server] 2025-02-26 02:32:15.492544 | localhost | Output suppressed because no_log was given 2025-02-26 02:32:15.496845 | 2025-02-26 02:32:15.496974 | LOOP [upload-logs : Compress console log and json output] 2025-02-26 02:32:15.578134 | localhost | skipping: Conditional result was False 2025-02-26 02:32:15.602652 | localhost | skipping: Conditional result was False 2025-02-26 02:32:15.610645 | 2025-02-26 02:32:15.610802 | LOOP [upload-logs : Upload compressed console log and json output] 2025-02-26 02:32:15.688876 | localhost | skipping: Conditional result was False 2025-02-26 02:32:15.689779 | 2025-02-26 02:32:15.702118 | localhost | skipping: Conditional result was False 2025-02-26 02:32:15.719058 | 2025-02-26 02:32:15.719284 | LOOP [upload-logs : Upload console log and json output]