2025-05-31 19:56:35.059762 | Job console starting 2025-05-31 19:56:35.083132 | Updating git repos 2025-05-31 19:56:35.127859 | Cloning repos into workspace 2025-05-31 19:56:35.197161 | Restoring repo states 2025-05-31 19:56:35.213156 | Merging changes 2025-05-31 19:56:35.774731 | Checking out repos 2025-05-31 19:56:35.884299 | Preparing playbooks 2025-05-31 19:56:36.520570 | Running Ansible setup 2025-05-31 19:56:41.788834 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-05-31 19:56:42.665630 | 2025-05-31 19:56:42.665846 | PLAY [Base pre] 2025-05-31 19:56:42.683172 | 2025-05-31 19:56:42.683364 | TASK [Setup log path fact] 2025-05-31 19:56:42.713468 | debian-bookworm | ok 2025-05-31 19:56:42.744106 | 2025-05-31 19:56:42.744408 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-05-31 19:56:42.778198 | debian-bookworm | ok 2025-05-31 19:56:42.795977 | 2025-05-31 19:56:42.796126 | TASK [emit-job-header : Print job information] 2025-05-31 19:56:42.835854 | # Job Information 2025-05-31 19:56:42.836050 | Ansible Version: 2.16.14 2025-05-31 19:56:42.836086 | Job: python-black 2025-05-31 19:56:42.836118 | Pipeline: check 2025-05-31 19:56:42.836145 | Executor: 521e9411259a 2025-05-31 19:56:42.836176 | Triggered by: https://github.com/osism/ansible-collection-services/pull/1880 2025-05-31 19:56:42.836207 | Event ID: 55d04790-3e59-11f0-86d9-fa77b0786496 2025-05-31 19:56:42.841450 | 2025-05-31 19:56:42.841575 | LOOP [emit-job-header : Print node information] 2025-05-31 19:56:42.957816 | debian-bookworm | ok: 2025-05-31 19:56:42.958111 | debian-bookworm | # Node Information 2025-05-31 19:56:42.958149 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-05-31 19:56:42.958175 | debian-bookworm | Hostname: debian 2025-05-31 19:56:42.958198 | debian-bookworm | Username: zuul 2025-05-31 19:56:42.958219 | debian-bookworm | Distro: Debian 12.11 2025-05-31 19:56:42.958259 | debian-bookworm | Provider: regiocloud-a 2025-05-31 19:56:42.958281 | debian-bookworm | Region: 2025-05-31 19:56:42.958303 | debian-bookworm | Label: debian-bookworm 2025-05-31 19:56:42.958323 | debian-bookworm | Product Name: OpenStack Nova 2025-05-31 19:56:42.958343 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7c:87c4 2025-05-31 19:56:42.972028 | 2025-05-31 19:56:42.972168 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-05-31 19:56:43.607779 | debian-bookworm -> localhost | changed 2025-05-31 19:56:43.615597 | 2025-05-31 19:56:43.615746 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-05-31 19:56:44.939811 | debian-bookworm -> localhost | changed 2025-05-31 19:56:44.953802 | 2025-05-31 19:56:44.954096 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-05-31 19:56:45.493164 | debian-bookworm -> localhost | ok 2025-05-31 19:56:45.499770 | 2025-05-31 19:56:45.499910 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-05-31 19:56:45.562282 | debian-bookworm | ok 2025-05-31 19:56:45.611672 | debian-bookworm | included: /var/lib/zuul/builds/7af79e9f6b794b2ebdeeaa9c0a737bdf/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-05-31 19:56:45.627205 | 2025-05-31 19:56:45.627368 | TASK [add-build-sshkey : Create Temp SSH key] 2025-05-31 19:56:47.303764 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-05-31 19:56:47.304024 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7af79e9f6b794b2ebdeeaa9c0a737bdf/work/7af79e9f6b794b2ebdeeaa9c0a737bdf_id_rsa 2025-05-31 19:56:47.304065 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7af79e9f6b794b2ebdeeaa9c0a737bdf/work/7af79e9f6b794b2ebdeeaa9c0a737bdf_id_rsa.pub 2025-05-31 19:56:47.304090 | debian-bookworm -> localhost | The key fingerprint is: 2025-05-31 19:56:47.304114 | debian-bookworm -> localhost | SHA256:wSHyVyWg6KNjuSIF/oJUO4+7C/ceV065eiea/m0Hhrw zuul-build-sshkey 2025-05-31 19:56:47.304136 | debian-bookworm -> localhost | The key's randomart image is: 2025-05-31 19:56:47.304171 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-05-31 19:56:47.304193 | debian-bookworm -> localhost | | . . o.o.. | 2025-05-31 19:56:47.304215 | debian-bookworm -> localhost | | + + o . | 2025-05-31 19:56:47.304276 | debian-bookworm -> localhost | | . o + | 2025-05-31 19:56:47.304297 | debian-bookworm -> localhost | |. o . .. | 2025-05-31 19:56:47.304317 | debian-bookworm -> localhost | |... + S+. | 2025-05-31 19:56:47.304342 | debian-bookworm -> localhost | | o.= . +o.o | 2025-05-31 19:56:47.304362 | debian-bookworm -> localhost | |oo*.+. . oo . | 2025-05-31 19:56:47.304383 | debian-bookworm -> localhost | |+oo=..o oE.o . | 2025-05-31 19:56:47.304404 | debian-bookworm -> localhost | |..o+=o.=+.+.. | 2025-05-31 19:56:47.304424 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-05-31 19:56:47.304473 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.696090 2025-05-31 19:56:47.311235 | 2025-05-31 19:56:47.311361 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-05-31 19:56:47.360955 | debian-bookworm | ok 2025-05-31 19:56:47.377991 | debian-bookworm | included: /var/lib/zuul/builds/7af79e9f6b794b2ebdeeaa9c0a737bdf/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-05-31 19:56:47.407166 | 2025-05-31 19:56:47.407355 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-05-31 19:56:47.435506 | debian-bookworm | skipping: Conditional result was False 2025-05-31 19:56:47.441823 | 2025-05-31 19:56:47.441948 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-05-31 19:56:48.270923 | debian-bookworm | changed 2025-05-31 19:56:48.277018 | 2025-05-31 19:56:48.277143 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-05-31 19:56:48.536582 | debian-bookworm | ok 2025-05-31 19:56:48.543987 | 2025-05-31 19:56:48.544134 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-05-31 19:56:49.198515 | debian-bookworm | changed 2025-05-31 19:56:49.208763 | 2025-05-31 19:56:49.208917 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-05-31 19:56:49.829736 | debian-bookworm | changed 2025-05-31 19:56:49.836703 | 2025-05-31 19:56:49.836830 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-05-31 19:56:49.881541 | debian-bookworm | skipping: Conditional result was False 2025-05-31 19:56:49.904764 | 2025-05-31 19:56:49.905353 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-05-31 19:56:50.540286 | debian-bookworm -> localhost | changed 2025-05-31 19:56:50.564839 | 2025-05-31 19:56:50.564982 | TASK [add-build-sshkey : Add back temp key] 2025-05-31 19:56:51.094267 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7af79e9f6b794b2ebdeeaa9c0a737bdf/work/7af79e9f6b794b2ebdeeaa9c0a737bdf_id_rsa (zuul-build-sshkey) 2025-05-31 19:56:51.094780 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011706 2025-05-31 19:56:51.108454 | 2025-05-31 19:56:51.108614 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-05-31 19:56:51.527278 | debian-bookworm | ok 2025-05-31 19:56:51.535279 | 2025-05-31 19:56:51.535419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-05-31 19:56:51.589722 | debian-bookworm | skipping: Conditional result was False 2025-05-31 19:56:51.607155 | 2025-05-31 19:56:51.607316 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-05-31 19:56:52.098727 | debian-bookworm | ok 2025-05-31 19:56:52.124749 | 2025-05-31 19:56:52.124960 | TASK [validate-host : Define zuul_info_dir fact] 2025-05-31 19:56:52.193626 | debian-bookworm | ok 2025-05-31 19:56:52.199086 | 2025-05-31 19:56:52.199235 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-05-31 19:56:52.607485 | debian-bookworm -> localhost | ok 2025-05-31 19:56:52.616038 | 2025-05-31 19:56:52.616167 | TASK [validate-host : Collect information about the host] 2025-05-31 19:56:53.760410 | debian-bookworm | ok 2025-05-31 19:56:53.783670 | 2025-05-31 19:56:53.783832 | TASK [validate-host : Sanitize hostname] 2025-05-31 19:56:53.956722 | debian-bookworm | ok 2025-05-31 19:56:54.035589 | 2025-05-31 19:56:54.035750 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-05-31 19:56:54.673398 | debian-bookworm -> localhost | changed 2025-05-31 19:56:54.679693 | 2025-05-31 19:56:54.679811 | TASK [validate-host : Collect information about zuul worker] 2025-05-31 19:56:55.109594 | debian-bookworm | ok 2025-05-31 19:56:55.115002 | 2025-05-31 19:56:55.115123 | TASK [validate-host : Write out all zuul information for each host] 2025-05-31 19:56:55.793555 | debian-bookworm -> localhost | changed 2025-05-31 19:56:55.803584 | 2025-05-31 19:56:55.803715 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-05-31 19:56:56.081870 | debian-bookworm | ok 2025-05-31 19:56:56.090109 | 2025-05-31 19:56:56.090267 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-05-31 19:56:57.316875 | debian-bookworm | changed: 2025-05-31 19:56:57.317141 | debian-bookworm | cd+++++++++ src/ 2025-05-31 19:56:57.317183 | debian-bookworm | cd+++++++++ src/github.com/ 2025-05-31 19:56:57.317226 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-05-31 19:56:57.317250 | debian-bookworm | cd+++++++++ src/github.com/osism/ansible-collection-services/ 2025-05-31 19:56:57.317271 | debian-bookworm | localhost | ok: Runtime: 0:00:00.003799 2025-05-31 19:57:23.138131 | 2025-05-31 19:57:23.138255 | TASK [upload-logs : Upload logs to log server] 2025-05-31 19:57:23.724991 | localhost | Output suppressed because no_log was given 2025-05-31 19:57:23.729126 | 2025-05-31 19:57:23.729324 | LOOP [upload-logs : Compress console log and json output] 2025-05-31 19:57:23.781022 | localhost | skipping: Conditional result was False 2025-05-31 19:57:23.789552 | localhost | skipping: Conditional result was False 2025-05-31 19:57:23.801064 | 2025-05-31 19:57:23.801210 | LOOP [upload-logs : Upload compressed console log and json output] 2025-05-31 19:57:23.843672 | localhost | skipping: Conditional result was False 2025-05-31 19:57:23.843922 | 2025-05-31 19:57:23.848221 | localhost | skipping: Conditional result was False 2025-05-31 19:57:23.863333 | 2025-05-31 19:57:23.863587 | LOOP [upload-logs : Upload console log and json output]