2026-08-01 03:12:50.986987 | Job console starting 2026-08-01 03:12:51.002919 | Updating git repos 2026-08-01 03:12:51.057537 | Cloning repos into workspace 2026-08-01 03:12:51.132811 | Restoring repo states 2026-08-01 03:12:51.160295 | Merging changes 2026-08-01 03:12:51.160321 | Checking out repos 2026-08-01 03:12:51.240657 | Preparing playbooks 2026-08-01 03:12:52.272295 | Running Ansible setup 2026-08-01 03:12:58.102776 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-01 03:12:59.360401 | 2026-08-01 03:12:59.360600 | PLAY [Base pre] 2026-08-01 03:12:59.383653 | 2026-08-01 03:12:59.383827 | TASK [Setup log path fact] 2026-08-01 03:12:59.403961 | debian-bookworm | ok 2026-08-01 03:12:59.423236 | 2026-08-01 03:12:59.423482 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-01 03:12:59.467155 | debian-bookworm | ok 2026-08-01 03:12:59.484432 | 2026-08-01 03:12:59.484673 | TASK [emit-job-header : Print job information] 2026-08-01 03:12:59.538734 | # Job Information 2026-08-01 03:12:59.538987 | Ansible Version: 2.16.14 2026-08-01 03:12:59.539066 | Job: python-black 2026-08-01 03:12:59.539104 | Pipeline: periodic-daily 2026-08-01 03:12:59.539126 | Executor: 521e9411259a 2026-08-01 03:12:59.539147 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-08-01 03:12:59.539171 | Event ID: dc097ba82f854e49a7567e8ceab2bcc6 2026-08-01 03:12:59.544549 | 2026-08-01 03:12:59.544674 | LOOP [emit-job-header : Print node information] 2026-08-01 03:12:59.661775 | debian-bookworm | ok: 2026-08-01 03:12:59.662921 | debian-bookworm | # Node Information 2026-08-01 03:12:59.663009 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-01 03:12:59.663069 | debian-bookworm | Hostname: debian 2026-08-01 03:12:59.663094 | debian-bookworm | Username: zuul 2026-08-01 03:12:59.663116 | debian-bookworm | Distro: Debian 12.15 2026-08-01 03:12:59.663141 | debian-bookworm | Provider: regiocloud-a 2026-08-01 03:12:59.663163 | debian-bookworm | Region: 2026-08-01 03:12:59.663184 | debian-bookworm | Label: debian-bookworm 2026-08-01 03:12:59.663205 | debian-bookworm | Product Name: OpenStack Nova 2026-08-01 03:12:59.663225 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe51:99b7 2026-08-01 03:12:59.679818 | 2026-08-01 03:12:59.679932 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-01 03:13:00.390649 | debian-bookworm -> localhost | changed 2026-08-01 03:13:00.398599 | 2026-08-01 03:13:00.398708 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-01 03:13:01.383594 | debian-bookworm -> localhost | changed 2026-08-01 03:13:01.392406 | 2026-08-01 03:13:01.392511 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-01 03:13:01.677129 | debian-bookworm -> localhost | ok 2026-08-01 03:13:01.682595 | 2026-08-01 03:13:01.682688 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-01 03:13:01.721061 | debian-bookworm | ok 2026-08-01 03:13:01.733323 | debian-bookworm | included: /var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-01 03:13:01.738140 | 2026-08-01 03:13:01.738212 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-01 03:13:03.269121 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-01 03:13:03.269341 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/work/0038405f0381479f85f583fb37a74fd7_id_rsa 2026-08-01 03:13:03.269377 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/work/0038405f0381479f85f583fb37a74fd7_id_rsa.pub 2026-08-01 03:13:03.269403 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-01 03:13:03.269426 | debian-bookworm -> localhost | SHA256:3kGqPmlERXCH4ZedsWLLDCfJ5hcn4COz1AmlUoobyg8 zuul-build-sshkey 2026-08-01 03:13:03.269448 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-01 03:13:03.269478 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-01 03:13:03.269501 | debian-bookworm -> localhost | | ++*o. . | 2026-08-01 03:13:03.269523 | debian-bookworm -> localhost | | . o Oo= o + | 2026-08-01 03:13:03.269544 | debian-bookworm -> localhost | | o o =.% X = | 2026-08-01 03:13:03.269564 | debian-bookworm -> localhost | |.. o o.* % * | 2026-08-01 03:13:03.269584 | debian-bookworm -> localhost | |.E. .. S * | 2026-08-01 03:13:03.269611 | debian-bookworm -> localhost | | o .o o . | 2026-08-01 03:13:03.269633 | debian-bookworm -> localhost | | . .... . | 2026-08-01 03:13:03.269653 | debian-bookworm -> localhost | | .+ | 2026-08-01 03:13:03.269674 | debian-bookworm -> localhost | | ... | 2026-08-01 03:13:03.269694 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-01 03:13:03.269737 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.033228 2026-08-01 03:13:03.280628 | 2026-08-01 03:13:03.280746 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-01 03:13:03.308809 | debian-bookworm | ok 2026-08-01 03:13:03.319296 | debian-bookworm | included: /var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-01 03:13:03.327784 | 2026-08-01 03:13:03.327894 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-01 03:13:03.341282 | debian-bookworm | skipping: Conditional result was False 2026-08-01 03:13:03.348172 | 2026-08-01 03:13:03.348295 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-01 03:13:03.939443 | debian-bookworm | changed 2026-08-01 03:13:03.946703 | 2026-08-01 03:13:03.946801 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-01 03:13:04.174204 | debian-bookworm | ok 2026-08-01 03:13:04.180833 | 2026-08-01 03:13:04.180941 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-01 03:13:04.797508 | debian-bookworm | changed 2026-08-01 03:13:04.803780 | 2026-08-01 03:13:04.803887 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-01 03:13:05.441979 | debian-bookworm | changed 2026-08-01 03:13:05.449906 | 2026-08-01 03:13:05.450039 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-01 03:13:05.485030 | debian-bookworm | skipping: Conditional result was False 2026-08-01 03:13:05.491424 | 2026-08-01 03:13:05.491529 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-01 03:13:06.170264 | debian-bookworm -> localhost | changed 2026-08-01 03:13:06.186806 | 2026-08-01 03:13:06.186934 | TASK [add-build-sshkey : Add back temp key] 2026-08-01 03:13:06.546326 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/work/0038405f0381479f85f583fb37a74fd7_id_rsa (zuul-build-sshkey) 2026-08-01 03:13:06.546577 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023476 2026-08-01 03:13:06.564802 | 2026-08-01 03:13:06.564915 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-01 03:13:06.972496 | debian-bookworm | ok 2026-08-01 03:13:06.980762 | 2026-08-01 03:13:06.981099 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-01 03:13:07.007288 | debian-bookworm | skipping: Conditional result was False 2026-08-01 03:13:07.025273 | 2026-08-01 03:13:07.025387 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-01 03:13:07.466461 | debian-bookworm | ok 2026-08-01 03:13:07.476978 | 2026-08-01 03:13:07.477113 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-01 03:13:07.504936 | debian-bookworm | ok 2026-08-01 03:13:07.509877 | 2026-08-01 03:13:07.509966 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-01 03:13:07.767617 | debian-bookworm -> localhost | ok 2026-08-01 03:13:07.773893 | 2026-08-01 03:13:07.774011 | TASK [validate-host : Collect information about the host] 2026-08-01 03:13:08.806443 | debian-bookworm | ok 2026-08-01 03:13:08.835170 | 2026-08-01 03:13:08.835321 | TASK [validate-host : Sanitize hostname] 2026-08-01 03:13:09.035869 | debian-bookworm | ok 2026-08-01 03:13:09.041346 | 2026-08-01 03:13:09.041473 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-01 03:13:09.783813 | debian-bookworm -> localhost | changed 2026-08-01 03:13:09.790567 | 2026-08-01 03:13:09.790718 | TASK [validate-host : Collect information about zuul worker] 2026-08-01 03:13:10.233504 | debian-bookworm | ok 2026-08-01 03:13:10.241458 | 2026-08-01 03:13:10.241622 | TASK [validate-host : Write out all zuul information for each host] 2026-08-01 03:13:10.850071 | debian-bookworm -> localhost | changed 2026-08-01 03:13:10.862162 | 2026-08-01 03:13:10.862318 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-01 03:13:11.108418 | debian-bookworm | ok 2026-08-01 03:13:11.121531 | 2026-08-01 03:13:11.121670 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-01 03:13:12.020556 | debian-bookworm | changed: 2026-08-01 03:13:12.021059 | debian-bookworm | cd+++++++++ src/ 2026-08-01 03:13:12.021123 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-01 03:13:12.021159 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-01 03:13:12.021192 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-08-01 03:13:12.021222 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/work/logs" 2026-08-01 03:14:16.344880 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/work/artifacts" 2026-08-01 03:14:16.778053 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/0038405f0381479f85f583fb37a74fd7/work/docs" 2026-08-01 03:14:16.801417 | 2026-08-01 03:14:16.801550 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-01 03:14:17.980351 | debian-bookworm | changed: 2026-08-01 03:14:17.980525 | debian-bookworm | .d..t...... ./ 2026-08-01 03:14:17.980554 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-01 03:14:17.980573 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-01 03:14:17.980591 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-01 03:14:17.980608 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-01 03:14:17.980625 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-01 03:14:17.980656 | debian-bookworm | changed: All items complete 2026-08-01 03:14:17.980674 | 2026-08-01 03:14:18.820631 | debian-bookworm | changed: .d..t...... ./ 2026-08-01 03:14:20.234774 | debian-bookworm | changed: .d..t...... ./ 2026-08-01 03:14:20.263014 | 2026-08-01 03:14:20.263126 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-01 03:14:20.314373 | debian-bookworm | skipping: Conditional result was False 2026-08-01 03:14:20.318106 | debian-bookworm | skipping: Conditional result was False 2026-08-01 03:14:20.331519 | 2026-08-01 03:14:20.331601 | PLAY RECAP 2026-08-01 03:14:20.331642 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-01 03:14:20.331662 | 2026-08-01 03:14:20.691168 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-01 03:14:20.692223 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-01 03:14:21.989920 | 2026-08-01 03:14:21.990071 | PLAY [Base post] 2026-08-01 03:14:22.021857 | 2026-08-01 03:14:22.022007 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-01 03:14:22.761244 | debian-bookworm | changed 2026-08-01 03:14:22.775463 | 2026-08-01 03:14:22.775706 | PLAY RECAP 2026-08-01 03:14:22.775774 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-01 03:14:22.775848 | 2026-08-01 03:14:23.066867 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-01 03:14:23.068014 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-01 03:14:24.359161 | 2026-08-01 03:14:24.359312 | PLAY [Base post-logs] 2026-08-01 03:14:24.376433 | 2026-08-01 03:14:24.376580 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-01 03:14:25.268105 | localhost | changed 2026-08-01 03:14:25.277443 | 2026-08-01 03:14:25.277596 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-01 03:14:25.327109 | localhost | ok 2026-08-01 03:14:25.330090 | 2026-08-01 03:14:25.330188 | TASK [Set zuul-log-path fact] 2026-08-01 03:14:25.354784 | localhost | ok 2026-08-01 03:14:25.362426 | 2026-08-01 03:14:25.362546 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-01 03:14:25.425848 | localhost | ok 2026-08-01 03:14:25.428799 | 2026-08-01 03:14:25.428905 | TASK [upload-logs : Create log directories] 2026-08-01 03:14:26.868804 | localhost | changed 2026-08-01 03:14:26.872143 | 2026-08-01 03:14:26.872245 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-01 03:14:28.342259 | localhost -> localhost | ok: Runtime: 0:00:00.030359 2026-08-01 03:14:28.345988 | 2026-08-01 03:14:28.346088 | TASK [upload-logs : Upload logs to log server] 2026-08-01 03:14:29.908968 | localhost | Output suppressed because no_log was given 2026-08-01 03:14:29.910414 | 2026-08-01 03:14:29.910489 | LOOP [upload-logs : Compress console log and json output] 2026-08-01 03:14:30.072514 | localhost | skipping: Conditional result was False 2026-08-01 03:14:30.089182 | localhost | skipping: Conditional result was False 2026-08-01 03:14:30.093477 | 2026-08-01 03:14:30.093578 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-01 03:14:30.164624 | localhost | skipping: Conditional result was False 2026-08-01 03:14:30.164885 | 2026-08-01 03:14:30.175548 | localhost | skipping: Conditional result was False 2026-08-01 03:14:30.192499 | 2026-08-01 03:14:30.192611 | LOOP [upload-logs : Upload console log and json output]