2025-09-06 03:01:10.280185 | Job console starting 2025-09-06 03:01:10.287494 | Updating git repos 2025-09-06 03:01:10.449121 | Cloning repos into workspace 2025-09-06 03:01:10.542530 | Restoring repo states 2025-09-06 03:01:10.562492 | Merging changes 2025-09-06 03:01:10.562505 | Checking out repos 2025-09-06 03:01:10.666859 | Preparing playbooks 2025-09-06 03:01:12.231270 | Running Ansible setup 2025-09-06 03:01:18.174015 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-06 03:01:19.064297 | 2025-09-06 03:01:19.064411 | PLAY [Base pre] 2025-09-06 03:01:19.103937 | 2025-09-06 03:01:19.104048 | TASK [Setup log path fact] 2025-09-06 03:01:19.137027 | debian-bookworm | ok 2025-09-06 03:01:19.150753 | 2025-09-06 03:01:19.162957 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-06 03:01:19.221073 | debian-bookworm | ok 2025-09-06 03:01:19.236920 | 2025-09-06 03:01:19.237019 | TASK [emit-job-header : Print job information] 2025-09-06 03:01:19.332053 | # Job Information 2025-09-06 03:01:19.332186 | Ansible Version: 2.16.14 2025-09-06 03:01:19.332215 | Job: python-black 2025-09-06 03:01:19.332241 | Pipeline: periodic-daily 2025-09-06 03:01:19.332260 | Executor: 521e9411259a 2025-09-06 03:01:19.332277 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2025-09-06 03:01:19.332294 | Event ID: f391a413db9d454bb9f053714f1b6a2f 2025-09-06 03:01:19.336576 | 2025-09-06 03:01:19.336654 | LOOP [emit-job-header : Print node information] 2025-09-06 03:01:19.619723 | debian-bookworm | ok: 2025-09-06 03:01:19.619887 | debian-bookworm | # Node Information 2025-09-06 03:01:19.619921 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-06 03:01:19.619949 | debian-bookworm | Hostname: debian 2025-09-06 03:01:19.619973 | debian-bookworm | Username: zuul 2025-09-06 03:01:19.619996 | debian-bookworm | Distro: Debian 12.11 2025-09-06 03:01:19.620021 | debian-bookworm | Provider: regiocloud-a 2025-09-06 03:01:19.620043 | debian-bookworm | Region: 2025-09-06 03:01:19.620065 | debian-bookworm | Label: debian-bookworm 2025-09-06 03:01:19.620087 | debian-bookworm | Product Name: OpenStack Nova 2025-09-06 03:01:19.620109 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee7:cadf 2025-09-06 03:01:19.640170 | 2025-09-06 03:01:19.640455 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-06 03:01:20.983678 | debian-bookworm -> localhost | changed 2025-09-06 03:01:20.989533 | 2025-09-06 03:01:20.989644 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-06 03:01:23.594684 | debian-bookworm -> localhost | changed 2025-09-06 03:01:23.603637 | 2025-09-06 03:01:23.603732 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-06 03:01:24.001737 | debian-bookworm -> localhost | ok 2025-09-06 03:01:24.006642 | 2025-09-06 03:01:24.006730 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-06 03:01:24.035009 | debian-bookworm | ok 2025-09-06 03:01:24.060568 | debian-bookworm | included: /var/lib/zuul/builds/096401e481034ef899ceb44c375d7dcb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-06 03:01:24.065481 | 2025-09-06 03:01:24.065589 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-06 03:01:26.481834 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-06 03:01:26.482055 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/096401e481034ef899ceb44c375d7dcb/work/096401e481034ef899ceb44c375d7dcb_id_rsa 2025-09-06 03:01:26.482089 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/096401e481034ef899ceb44c375d7dcb/work/096401e481034ef899ceb44c375d7dcb_id_rsa.pub 2025-09-06 03:01:26.483036 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-06 03:01:26.483070 | debian-bookworm -> localhost | SHA256:DDN5Dj9bv8+dXgmMshQ5ej0fU07GlubqA4HEwhvWMOc zuul-build-sshkey 2025-09-06 03:01:26.483092 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-06 03:01:26.483121 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-06 03:01:26.483141 | debian-bookworm -> localhost | | .o+. | 2025-09-06 03:01:26.483160 | debian-bookworm -> localhost | | *++. . .| 2025-09-06 03:01:26.483592 | debian-bookworm -> localhost | | B *E. O | 2025-09-06 03:01:26.483626 | debian-bookworm -> localhost | | @..+.o O | 2025-09-06 03:01:26.483649 | debian-bookworm -> localhost | | .S+.+.= o | 2025-09-06 03:01:26.483672 | debian-bookworm -> localhost | | o+ooo = .| 2025-09-06 03:01:26.483691 | debian-bookworm -> localhost | | .. oo ..| 2025-09-06 03:01:26.483708 | debian-bookworm -> localhost | | .+ .o| 2025-09-06 03:01:26.483726 | debian-bookworm -> localhost | | .o=o.| 2025-09-06 03:01:26.483744 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-06 03:01:26.483786 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.110896 2025-09-06 03:01:26.493873 | 2025-09-06 03:01:26.493942 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-06 03:01:26.548428 | debian-bookworm | ok 2025-09-06 03:01:26.558201 | debian-bookworm | included: /var/lib/zuul/builds/096401e481034ef899ceb44c375d7dcb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-06 03:01:26.617143 | 2025-09-06 03:01:26.617233 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-06 03:01:26.650231 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:01:26.660015 | 2025-09-06 03:01:26.660179 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-06 03:01:27.658635 | debian-bookworm | changed 2025-09-06 03:01:27.663948 | 2025-09-06 03:01:27.666987 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-06 03:01:27.977481 | debian-bookworm | ok 2025-09-06 03:01:27.981882 | 2025-09-06 03:01:27.981957 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-06 03:01:28.910103 | debian-bookworm | changed 2025-09-06 03:01:28.919224 | 2025-09-06 03:01:28.919323 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-06 03:01:29.736800 | debian-bookworm | changed 2025-09-06 03:01:29.747620 | 2025-09-06 03:01:29.748680 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-06 03:01:29.828772 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:01:29.838635 | 2025-09-06 03:01:29.838725 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-06 03:01:30.466871 | debian-bookworm -> localhost | changed 2025-09-06 03:01:30.475401 | 2025-09-06 03:01:30.475488 | TASK [add-build-sshkey : Add back temp key] 2025-09-06 03:01:31.585923 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/096401e481034ef899ceb44c375d7dcb/work/096401e481034ef899ceb44c375d7dcb_id_rsa (zuul-build-sshkey) 2025-09-06 03:01:31.586099 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.037684 2025-09-06 03:01:31.592325 | 2025-09-06 03:01:31.592404 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-06 03:01:32.419244 | debian-bookworm | ok 2025-09-06 03:01:32.427702 | 2025-09-06 03:01:32.427889 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-06 03:01:32.479755 | debian-bookworm | skipping: Conditional result was False 2025-09-06 03:01:32.489356 | 2025-09-06 03:01:32.489444 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-06 03:01:33.113324 | debian-bookworm | ok 2025-09-06 03:01:33.121950 | 2025-09-06 03:01:33.122040 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-06 03:01:33.204664 | debian-bookworm | ok 2025-09-06 03:01:33.209075 | 2025-09-06 03:01:33.209226 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-06 03:01:34.329577 | debian-bookworm -> localhost | ok 2025-09-06 03:01:34.336746 | 2025-09-06 03:01:34.336833 | TASK [validate-host : Collect information about the host] 2025-09-06 03:01:35.722809 | debian-bookworm | ok 2025-09-06 03:01:35.749115 | 2025-09-06 03:01:35.749214 | TASK [validate-host : Sanitize hostname] 2025-09-06 03:01:35.932021 | debian-bookworm | ok 2025-09-06 03:01:35.978289 | 2025-09-06 03:01:35.978385 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-06 03:01:38.066003 | debian-bookworm -> localhost | changed 2025-09-06 03:01:38.070493 | 2025-09-06 03:01:38.070632 | TASK [validate-host : Collect information about zuul worker] 2025-09-06 03:01:38.546307 | debian-bookworm | ok 2025-09-06 03:01:38.550910 | 2025-09-06 03:01:38.551000 | TASK [validate-host : Write out all zuul information for each host] 2025-09-06 03:01:39.638929 | debian-bookworm -> localhost | changed 2025-09-06 03:01:39.647021 | 2025-09-06 03:01:39.647111 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-06 03:01:39.936894 | debian-bookworm | ok 2025-09-06 03:01:39.941340 | 2025-09-06 03:01:39.941417 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-06 03:01:41.285047 | debian-bookworm | changed: 2025-09-06 03:01:41.285201 | debian-bookworm | cd+++++++++ src/ 2025-09-06 03:01:41.285234 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-06 03:01:41.285258 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-06 03:01:41.285279 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2025-09-06 03:01:41.285299 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006990 2025-09-06 03:02:11.808024 | 2025-09-06 03:02:11.808584 | TASK [upload-logs : Upload logs to log server] 2025-09-06 03:02:12.998248 | localhost | Output suppressed because no_log was given 2025-09-06 03:02:12.999624 | 2025-09-06 03:02:12.999698 | LOOP [upload-logs : Compress console log and json output] 2025-09-06 03:02:13.126788 | localhost | skipping: Conditional result was False 2025-09-06 03:02:13.141958 | localhost | skipping: Conditional result was False 2025-09-06 03:02:13.145738 | 2025-09-06 03:02:13.145825 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-06 03:02:13.296598 | localhost | skipping: Conditional result was False 2025-09-06 03:02:13.296808 | 2025-09-06 03:02:13.324608 | localhost | skipping: Conditional result was False 2025-09-06 03:02:13.335515 | 2025-09-06 03:02:13.335616 | LOOP [upload-logs : Upload console log and json output]