2025-09-23 16:36:44.151852 | Job console starting 2025-09-23 16:36:44.176715 | Updating git repos 2025-09-23 16:36:44.239265 | Cloning repos into workspace 2025-09-23 16:36:44.292137 | Restoring repo states 2025-09-23 16:36:44.302938 | Merging changes 2025-09-23 16:36:44.805247 | Checking out repos 2025-09-23 16:36:44.852777 | Preparing playbooks 2025-09-23 16:36:45.594787 | Running Ansible setup 2025-09-23 16:36:50.093039 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-23 16:36:50.943525 | 2025-09-23 16:36:50.943701 | PLAY [Base pre] 2025-09-23 16:36:50.984225 | 2025-09-23 16:36:50.984388 | TASK [Setup log path fact] 2025-09-23 16:36:51.013728 | debian-bookworm | ok 2025-09-23 16:36:51.043035 | 2025-09-23 16:36:51.043247 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-23 16:36:51.073585 | debian-bookworm | ok 2025-09-23 16:36:51.083656 | 2025-09-23 16:36:51.083783 | TASK [emit-job-header : Print job information] 2025-09-23 16:36:51.133064 | # Job Information 2025-09-23 16:36:51.133302 | Ansible Version: 2.16.14 2025-09-23 16:36:51.133338 | Job: ansible-collection-ensure-readme 2025-09-23 16:36:51.133372 | Pipeline: check 2025-09-23 16:36:51.133395 | Executor: 521e9411259a 2025-09-23 16:36:51.133416 | Triggered by: https://github.com/osism/ansible-collection-validations/pull/244 2025-09-23 16:36:51.133438 | Event ID: 785f9c10-989b-11f0-98e7-2d92741d8cfe 2025-09-23 16:36:51.138317 | 2025-09-23 16:36:51.138425 | LOOP [emit-job-header : Print node information] 2025-09-23 16:36:51.241522 | debian-bookworm | ok: 2025-09-23 16:36:51.241781 | debian-bookworm | # Node Information 2025-09-23 16:36:51.241817 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-23 16:36:51.241842 | debian-bookworm | Hostname: debian 2025-09-23 16:36:51.241865 | debian-bookworm | Username: zuul 2025-09-23 16:36:51.241886 | debian-bookworm | Distro: Debian 12.12 2025-09-23 16:36:51.241909 | debian-bookworm | Provider: regiocloud-a 2025-09-23 16:36:51.241931 | debian-bookworm | Region: 2025-09-23 16:36:51.241952 | debian-bookworm | Label: debian-bookworm 2025-09-23 16:36:51.241971 | debian-bookworm | Product Name: OpenStack Nova 2025-09-23 16:36:51.241991 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1a:1b81 2025-09-23 16:36:51.264727 | 2025-09-23 16:36:51.264859 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-23 16:36:52.198040 | debian-bookworm -> localhost | changed 2025-09-23 16:36:52.205581 | 2025-09-23 16:36:52.205703 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-23 16:36:53.565997 | debian-bookworm -> localhost | changed 2025-09-23 16:36:53.577290 | 2025-09-23 16:36:53.577426 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-23 16:36:53.998869 | debian-bookworm -> localhost | ok 2025-09-23 16:36:54.009363 | 2025-09-23 16:36:54.009505 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-23 16:36:54.038545 | debian-bookworm | ok 2025-09-23 16:36:54.057543 | debian-bookworm | included: /var/lib/zuul/builds/c99f4c24a543418c9b2b4fdaf3169e7e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-23 16:36:54.064136 | 2025-09-23 16:36:54.064266 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-23 16:36:56.493704 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-23 16:36:56.493875 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c99f4c24a543418c9b2b4fdaf3169e7e/work/c99f4c24a543418c9b2b4fdaf3169e7e_id_rsa 2025-09-23 16:36:56.493909 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c99f4c24a543418c9b2b4fdaf3169e7e/work/c99f4c24a543418c9b2b4fdaf3169e7e_id_rsa.pub 2025-09-23 16:36:56.493931 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-23 16:36:56.493951 | debian-bookworm -> localhost | SHA256:ZRQOyOGY+n9jH3GFdzL71VbKB9+bc7918xmrKXvLa8Y zuul-build-sshkey 2025-09-23 16:36:56.493981 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-23 16:36:56.493999 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-23 16:36:56.494017 | debian-bookworm -> localhost | | ..o. o. | 2025-09-23 16:36:56.494035 | debian-bookworm -> localhost | | +o + . | 2025-09-23 16:36:56.494052 | debian-bookworm -> localhost | | o . + . * o| 2025-09-23 16:36:56.494069 | debian-bookworm -> localhost | | . o + O+| 2025-09-23 16:36:56.494085 | debian-bookworm -> localhost | | . S . . + B| 2025-09-23 16:36:56.494127 | debian-bookworm -> localhost | | . o ++| 2025-09-23 16:36:56.494146 | debian-bookworm -> localhost | | . . . *=| 2025-09-23 16:36:56.494163 | debian-bookworm -> localhost | | . + o.E. %| 2025-09-23 16:36:56.494180 | debian-bookworm -> localhost | | .o o..**+++| 2025-09-23 16:36:56.494197 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-23 16:36:56.494243 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.647901 2025-09-23 16:36:56.499231 | 2025-09-23 16:36:56.499300 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-23 16:36:56.515067 | debian-bookworm | ok 2025-09-23 16:36:56.522344 | debian-bookworm | included: /var/lib/zuul/builds/c99f4c24a543418c9b2b4fdaf3169e7e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-23 16:36:56.529845 | 2025-09-23 16:36:56.529911 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-23 16:36:56.542024 | debian-bookworm | skipping: Conditional result was False 2025-09-23 16:36:56.546487 | 2025-09-23 16:36:56.546554 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-23 16:36:57.209241 | debian-bookworm | changed 2025-09-23 16:36:57.214784 | 2025-09-23 16:36:57.214988 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-23 16:36:57.478455 | debian-bookworm | ok 2025-09-23 16:36:57.484733 | 2025-09-23 16:36:57.484895 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-23 16:36:58.140488 | debian-bookworm | changed 2025-09-23 16:36:58.147052 | 2025-09-23 16:36:58.147160 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-23 16:36:58.752700 | debian-bookworm | changed 2025-09-23 16:36:58.762559 | 2025-09-23 16:36:58.762667 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-23 16:36:58.775443 | debian-bookworm | skipping: Conditional result was False 2025-09-23 16:36:58.781076 | 2025-09-23 16:36:58.781199 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-23 16:36:59.171084 | debian-bookworm -> localhost | changed 2025-09-23 16:36:59.183561 | 2025-09-23 16:36:59.183677 | TASK [add-build-sshkey : Add back temp key] 2025-09-23 16:36:59.466344 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c99f4c24a543418c9b2b4fdaf3169e7e/work/c99f4c24a543418c9b2b4fdaf3169e7e_id_rsa (zuul-build-sshkey) 2025-09-23 16:36:59.466660 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017234 2025-09-23 16:36:59.477271 | 2025-09-23 16:36:59.477373 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-23 16:36:59.880594 | debian-bookworm | ok 2025-09-23 16:36:59.885387 | 2025-09-23 16:36:59.885472 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-23 16:36:59.908152 | debian-bookworm | skipping: Conditional result was False 2025-09-23 16:36:59.917990 | 2025-09-23 16:36:59.918088 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-23 16:37:00.365130 | debian-bookworm | ok 2025-09-23 16:37:00.375071 | 2025-09-23 16:37:00.375166 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-23 16:37:00.412534 | debian-bookworm | ok 2025-09-23 16:37:00.417310 | 2025-09-23 16:37:00.417392 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-23 16:37:00.651952 | debian-bookworm -> localhost | ok 2025-09-23 16:37:00.657957 | 2025-09-23 16:37:00.658042 | TASK [validate-host : Collect information about the host] 2025-09-23 16:37:01.764697 | debian-bookworm | ok 2025-09-23 16:37:01.784142 | 2025-09-23 16:37:01.784279 | TASK [validate-host : Sanitize hostname] 2025-09-23 16:37:01.868446 | debian-bookworm | ok 2025-09-23 16:37:01.903493 | 2025-09-23 16:37:01.903591 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-23 16:37:02.402506 | debian-bookworm -> localhost | changed 2025-09-23 16:37:02.408187 | 2025-09-23 16:37:02.408273 | TASK [validate-host : Collect information about zuul worker] 2025-09-23 16:37:02.833611 | debian-bookworm | ok 2025-09-23 16:37:02.840263 | 2025-09-23 16:37:02.840380 | TASK [validate-host : Write out all zuul information for each host] 2025-09-23 16:37:03.307773 | debian-bookworm -> localhost | changed 2025-09-23 16:37:03.315393 | 2025-09-23 16:37:03.315469 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-23 16:37:03.606155 | debian-bookworm | ok 2025-09-23 16:37:03.610403 | 2025-09-23 16:37:03.610476 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-23 16:37:04.414678 | debian-bookworm | changed: 2025-09-23 16:37:04.414817 | debian-bookworm | cd+++++++++ src/ 2025-09-23 16:37:04.414861 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-23 16:37:04.414882 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-23 16:37:04.414900 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-validations/ 2025-09-23 16:37:04.414918 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006841 2025-09-23 16:37:13.078021 | 2025-09-23 16:37:13.078148 | TASK [upload-logs : Upload logs to log server] 2025-09-23 16:37:13.637336 | localhost | Output suppressed because no_log was given 2025-09-23 16:37:13.641422 | 2025-09-23 16:37:13.641642 | LOOP [upload-logs : Compress console log and json output] 2025-09-23 16:37:13.690527 | localhost | skipping: Conditional result was False 2025-09-23 16:37:13.697468 | localhost | skipping: Conditional result was False 2025-09-23 16:37:13.701881 | 2025-09-23 16:37:13.701981 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-23 16:37:13.746601 | localhost | skipping: Conditional result was False 2025-09-23 16:37:13.747164 | 2025-09-23 16:37:13.751131 | localhost | skipping: Conditional result was False 2025-09-23 16:37:13.763414 | 2025-09-23 16:37:13.763609 | LOOP [upload-logs : Upload console log and json output]