2025-10-12 03:01:27.778651 | Job console starting 2025-10-12 03:01:27.800842 | Updating git repos 2025-10-12 03:01:27.920578 | Cloning repos into workspace 2025-10-12 03:01:27.960141 | Restoring repo states 2025-10-12 03:01:27.981095 | Merging changes 2025-10-12 03:01:27.981114 | Checking out repos 2025-10-12 03:01:28.027167 | Preparing playbooks 2025-10-12 03:01:30.112264 | Running Ansible setup 2025-10-12 03:01:36.586116 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-12 03:01:37.158334 | 2025-10-12 03:01:37.158437 | PLAY [Base pre] 2025-10-12 03:01:37.172473 | 2025-10-12 03:01:37.172557 | TASK [Setup log path fact] 2025-10-12 03:01:37.202969 | debian-bookworm | ok 2025-10-12 03:01:37.220838 | 2025-10-12 03:01:37.220930 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-12 03:01:37.258549 | debian-bookworm | ok 2025-10-12 03:01:37.278348 | 2025-10-12 03:01:37.278431 | TASK [emit-job-header : Print job information] 2025-10-12 03:01:37.305529 | # Job Information 2025-10-12 03:01:37.305666 | Ansible Version: 2.16.14 2025-10-12 03:01:37.305695 | Job: python-black 2025-10-12 03:01:37.305722 | Pipeline: periodic-daily 2025-10-12 03:01:37.305741 | Executor: 521e9411259a 2025-10-12 03:01:37.305759 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2025-10-12 03:01:37.305786 | Event ID: 2f463b66e53a4634acf0e5f4ddfcb7cb 2025-10-12 03:01:37.309730 | 2025-10-12 03:01:37.309838 | LOOP [emit-job-header : Print node information] 2025-10-12 03:01:37.393701 | debian-bookworm | ok: 2025-10-12 03:01:37.393869 | debian-bookworm | # Node Information 2025-10-12 03:01:37.393905 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-12 03:01:37.393931 | debian-bookworm | Hostname: debian 2025-10-12 03:01:37.393953 | debian-bookworm | Username: zuul 2025-10-12 03:01:37.393995 | debian-bookworm | Distro: Debian 12.12 2025-10-12 03:01:37.394021 | debian-bookworm | Provider: regiocloud-a 2025-10-12 03:01:37.394043 | debian-bookworm | Region: 2025-10-12 03:01:37.394064 | debian-bookworm | Label: debian-bookworm 2025-10-12 03:01:37.394085 | debian-bookworm | Product Name: OpenStack Nova 2025-10-12 03:01:37.394106 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe81:ed06 2025-10-12 03:01:37.413182 | 2025-10-12 03:01:37.413279 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-12 03:01:38.079056 | debian-bookworm -> localhost | changed 2025-10-12 03:01:38.084738 | 2025-10-12 03:01:38.084835 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-12 03:01:39.374949 | debian-bookworm -> localhost | changed 2025-10-12 03:01:39.385975 | 2025-10-12 03:01:39.386083 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-12 03:01:39.823568 | debian-bookworm -> localhost | ok 2025-10-12 03:01:39.829404 | 2025-10-12 03:01:39.829496 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-12 03:01:39.847921 | debian-bookworm | ok 2025-10-12 03:01:39.866671 | debian-bookworm | included: /var/lib/zuul/builds/26ea90104ae643f6aa11c833fd438d28/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-12 03:01:39.872684 | 2025-10-12 03:01:39.872776 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-12 03:01:41.361644 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-12 03:01:41.361812 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/26ea90104ae643f6aa11c833fd438d28/work/26ea90104ae643f6aa11c833fd438d28_id_rsa 2025-10-12 03:01:41.361842 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/26ea90104ae643f6aa11c833fd438d28/work/26ea90104ae643f6aa11c833fd438d28_id_rsa.pub 2025-10-12 03:01:41.361863 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-12 03:01:41.361883 | debian-bookworm -> localhost | SHA256:BlvVXOD9y3nALIagmkWBUoL3P39VigBomt/1DrqhsDo zuul-build-sshkey 2025-10-12 03:01:41.361901 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-12 03:01:41.361926 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-12 03:01:41.361944 | debian-bookworm -> localhost | | ....o. .o.o. | 2025-10-12 03:01:41.361962 | debian-bookworm -> localhost | |. o.+ .. . .o. | 2025-10-12 03:01:41.361978 | debian-bookworm -> localhost | | . * o.o . . | 2025-10-12 03:01:41.361994 | debian-bookworm -> localhost | | o .. =o. . o.. | 2025-10-12 03:01:41.362010 | debian-bookworm -> localhost | | . o+.So..oo+ .| 2025-10-12 03:01:41.362031 | debian-bookworm -> localhost | | .++.. o.o...o| 2025-10-12 03:01:41.362047 | debian-bookworm -> localhost | | . o .+ o . +.| 2025-10-12 03:01:41.362063 | debian-bookworm -> localhost | |E o .... o .| 2025-10-12 03:01:41.362080 | debian-bookworm -> localhost | |.o. . .. . | 2025-10-12 03:01:41.362096 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-12 03:01:41.362137 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.966552 2025-10-12 03:01:41.367171 | 2025-10-12 03:01:41.367250 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-12 03:01:41.383126 | debian-bookworm | ok 2025-10-12 03:01:41.390528 | debian-bookworm | included: /var/lib/zuul/builds/26ea90104ae643f6aa11c833fd438d28/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-12 03:01:41.397409 | 2025-10-12 03:01:41.397476 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-12 03:01:41.410164 | debian-bookworm | skipping: Conditional result was False 2025-10-12 03:01:41.414511 | 2025-10-12 03:01:41.414582 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-12 03:01:42.073003 | debian-bookworm | changed 2025-10-12 03:01:42.077342 | 2025-10-12 03:01:42.077417 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-12 03:01:42.325509 | debian-bookworm | ok 2025-10-12 03:01:42.330563 | 2025-10-12 03:01:42.330637 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-12 03:01:42.991758 | debian-bookworm | changed 2025-10-12 03:01:42.997111 | 2025-10-12 03:01:42.997183 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-12 03:01:43.621635 | debian-bookworm | changed 2025-10-12 03:01:43.638046 | 2025-10-12 03:01:43.638153 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-12 03:01:43.665559 | debian-bookworm | skipping: Conditional result was False 2025-10-12 03:01:43.672304 | 2025-10-12 03:01:43.672396 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-12 03:01:44.628992 | debian-bookworm -> localhost | changed 2025-10-12 03:01:44.646009 | 2025-10-12 03:01:44.646114 | TASK [add-build-sshkey : Add back temp key] 2025-10-12 03:01:45.737731 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/26ea90104ae643f6aa11c833fd438d28/work/26ea90104ae643f6aa11c833fd438d28_id_rsa (zuul-build-sshkey) 2025-10-12 03:01:45.737920 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.047927 2025-10-12 03:01:45.744208 | 2025-10-12 03:01:45.744291 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-12 03:01:46.561324 | debian-bookworm | ok 2025-10-12 03:01:46.566261 | 2025-10-12 03:01:46.566341 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-12 03:01:46.604612 | debian-bookworm | skipping: Conditional result was False 2025-10-12 03:01:46.613086 | 2025-10-12 03:01:46.613178 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-12 03:01:47.175173 | debian-bookworm | ok 2025-10-12 03:01:47.183777 | 2025-10-12 03:01:47.183871 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-12 03:01:47.200235 | debian-bookworm | ok 2025-10-12 03:01:47.204124 | 2025-10-12 03:01:47.204194 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-12 03:01:47.495165 | debian-bookworm -> localhost | ok 2025-10-12 03:01:47.500194 | 2025-10-12 03:01:47.500274 | TASK [validate-host : Collect information about the host] 2025-10-12 03:01:48.617388 | debian-bookworm | ok 2025-10-12 03:01:48.635697 | 2025-10-12 03:01:48.635823 | TASK [validate-host : Sanitize hostname] 2025-10-12 03:01:48.822690 | debian-bookworm | ok 2025-10-12 03:01:48.875776 | 2025-10-12 03:01:48.875897 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-12 03:01:49.535128 | debian-bookworm -> localhost | changed 2025-10-12 03:01:49.540553 | 2025-10-12 03:01:49.540632 | TASK [validate-host : Collect information about zuul worker] 2025-10-12 03:01:49.899870 | debian-bookworm | ok 2025-10-12 03:01:49.912916 | 2025-10-12 03:01:49.913011 | TASK [validate-host : Write out all zuul information for each host] 2025-10-12 03:01:50.699188 | debian-bookworm -> localhost | changed 2025-10-12 03:01:50.708661 | 2025-10-12 03:01:50.708760 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-12 03:01:50.977697 | debian-bookworm | ok 2025-10-12 03:01:50.984435 | 2025-10-12 03:01:50.984528 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-12 03:01:52.201177 | debian-bookworm | changed: 2025-10-12 03:01:52.201311 | debian-bookworm | cd+++++++++ src/ 2025-10-12 03:01:52.201340 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-12 03:01:52.201360 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-12 03:01:52.201376 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2025-10-12 03:01:52.201393 | debian-bookworm | localhost | ok: Runtime: 0:00:00.032186 2025-10-12 03:02:22.720554 | 2025-10-12 03:02:22.720633 | TASK [upload-logs : Upload logs to log server] 2025-10-12 03:02:23.647723 | localhost | Output suppressed because no_log was given 2025-10-12 03:02:23.653002 | 2025-10-12 03:02:23.653331 | LOOP [upload-logs : Compress console log and json output] 2025-10-12 03:02:23.740277 | localhost | skipping: Conditional result was False 2025-10-12 03:02:23.752195 | localhost | skipping: Conditional result was False 2025-10-12 03:02:23.765039 | 2025-10-12 03:02:23.765142 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-12 03:02:23.866275 | localhost | skipping: Conditional result was False 2025-10-12 03:02:23.866503 | 2025-10-12 03:02:23.877318 | localhost | skipping: Conditional result was False 2025-10-12 03:02:23.886688 | 2025-10-12 03:02:23.886784 | LOOP [upload-logs : Upload console log and json output]