2026-06-25 03:39:13.466801 | Job console starting 2026-06-25 03:39:13.489882 | Updating git repos 2026-06-25 03:39:13.557368 | Cloning repos into workspace 2026-06-25 03:39:13.657388 | Restoring repo states 2026-06-25 03:39:13.685956 | Merging changes 2026-06-25 03:39:13.685975 | Checking out repos 2026-06-25 03:39:13.762128 | Preparing playbooks 2026-06-25 03:39:15.329421 | Running Ansible setup 2026-06-25 03:39:21.378112 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-25 03:39:22.744579 | 2026-06-25 03:39:22.744727 | PLAY [Base pre] 2026-06-25 03:39:22.765814 | 2026-06-25 03:39:22.765945 | TASK [Setup log path fact] 2026-06-25 03:39:22.786126 | debian-bookworm | ok 2026-06-25 03:39:22.809534 | 2026-06-25 03:39:22.809678 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-25 03:39:22.843035 | debian-bookworm | ok 2026-06-25 03:39:22.860360 | 2026-06-25 03:39:22.860506 | TASK [emit-job-header : Print job information] 2026-06-25 03:39:22.923715 | # Job Information 2026-06-25 03:39:22.923936 | Ansible Version: 2.16.14 2026-06-25 03:39:22.923974 | Job: python-black 2026-06-25 03:39:22.924009 | Pipeline: periodic-daily 2026-06-25 03:39:22.924032 | Executor: 521e9411259a 2026-06-25 03:39:22.924052 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-06-25 03:39:22.924075 | Event ID: e79a14e65e27475b813852d0dd56a117 2026-06-25 03:39:22.928860 | 2026-06-25 03:39:22.928946 | LOOP [emit-job-header : Print node information] 2026-06-25 03:39:23.068007 | debian-bookworm | ok: 2026-06-25 03:39:23.068192 | debian-bookworm | # Node Information 2026-06-25 03:39:23.068255 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-25 03:39:23.068283 | debian-bookworm | Hostname: debian 2026-06-25 03:39:23.068305 | debian-bookworm | Username: zuul 2026-06-25 03:39:23.068327 | debian-bookworm | Distro: Debian 12.14 2026-06-25 03:39:23.068350 | debian-bookworm | Provider: regiocloud-a 2026-06-25 03:39:23.068372 | debian-bookworm | Region: 2026-06-25 03:39:23.068392 | debian-bookworm | Label: debian-bookworm 2026-06-25 03:39:23.068412 | debian-bookworm | Product Name: OpenStack Nova 2026-06-25 03:39:23.068431 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fedb:593a 2026-06-25 03:39:23.082951 | 2026-06-25 03:39:23.083436 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-25 03:39:23.862343 | debian-bookworm -> localhost | changed 2026-06-25 03:39:23.873881 | 2026-06-25 03:39:23.875246 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-25 03:39:27.030311 | debian-bookworm -> localhost | changed 2026-06-25 03:39:27.043816 | 2026-06-25 03:39:27.043928 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-25 03:39:27.658733 | debian-bookworm -> localhost | ok 2026-06-25 03:39:27.665269 | 2026-06-25 03:39:27.665388 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-25 03:39:27.710351 | debian-bookworm | ok 2026-06-25 03:39:27.750176 | debian-bookworm | included: /var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-25 03:39:27.760703 | 2026-06-25 03:39:27.760821 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-25 03:39:29.829509 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-25 03:39:29.829837 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/work/bc6cc54f7dc347fea7bdd396f4812c98_id_rsa 2026-06-25 03:39:29.829898 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/work/bc6cc54f7dc347fea7bdd396f4812c98_id_rsa.pub 2026-06-25 03:39:29.829926 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-25 03:39:29.829951 | debian-bookworm -> localhost | SHA256:P7yii7hKBjlRpo1BdsTUX9lBeQLMkRdYS5oc0iZ9rlA zuul-build-sshkey 2026-06-25 03:39:29.829974 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-25 03:39:29.830016 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-25 03:39:29.830039 | debian-bookworm -> localhost | |oo*+. .=+X*+ | 2026-06-25 03:39:29.830061 | debian-bookworm -> localhost | |.B.. . .oE=*.. | 2026-06-25 03:39:29.830082 | debian-bookworm -> localhost | |+ . . =++.o | 2026-06-25 03:39:29.830102 | debian-bookworm -> localhost | | o o . | 2026-06-25 03:39:29.830122 | debian-bookworm -> localhost | |+ S . | 2026-06-25 03:39:29.830148 | debian-bookworm -> localhost | |.. + | 2026-06-25 03:39:29.830169 | debian-bookworm -> localhost | | o + | 2026-06-25 03:39:29.830189 | debian-bookworm -> localhost | |o . . . o | 2026-06-25 03:39:29.830209 | debian-bookworm -> localhost | |..o.. oo .. | 2026-06-25 03:39:29.830248 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-25 03:39:29.830305 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.906750 2026-06-25 03:39:29.839871 | 2026-06-25 03:39:29.840032 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-25 03:39:29.894549 | debian-bookworm | ok 2026-06-25 03:39:29.907299 | debian-bookworm | included: /var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-25 03:39:29.949322 | 2026-06-25 03:39:29.949583 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-25 03:39:30.006347 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:39:30.014601 | 2026-06-25 03:39:30.014768 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-25 03:39:30.986363 | debian-bookworm | changed 2026-06-25 03:39:30.991794 | 2026-06-25 03:39:30.991890 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-25 03:39:31.287755 | debian-bookworm | ok 2026-06-25 03:39:31.299840 | 2026-06-25 03:39:31.299963 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-25 03:39:32.111398 | debian-bookworm | changed 2026-06-25 03:39:32.120792 | 2026-06-25 03:39:32.120902 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-25 03:39:32.837189 | debian-bookworm | changed 2026-06-25 03:39:32.843829 | 2026-06-25 03:39:32.843940 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-25 03:39:32.887158 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:39:32.897179 | 2026-06-25 03:39:32.897311 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-25 03:39:34.315374 | debian-bookworm -> localhost | changed 2026-06-25 03:39:34.341450 | 2026-06-25 03:39:34.341566 | TASK [add-build-sshkey : Add back temp key] 2026-06-25 03:39:35.227599 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/work/bc6cc54f7dc347fea7bdd396f4812c98_id_rsa (zuul-build-sshkey) 2026-06-25 03:39:35.227879 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.028528 2026-06-25 03:39:35.240921 | 2026-06-25 03:39:35.242981 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-25 03:39:36.160414 | debian-bookworm | ok 2026-06-25 03:39:36.166651 | 2026-06-25 03:39:36.166757 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-25 03:39:36.215414 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:39:36.229303 | 2026-06-25 03:39:36.229463 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-25 03:39:36.910613 | debian-bookworm | ok 2026-06-25 03:39:36.932525 | 2026-06-25 03:39:36.932640 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-25 03:39:37.006584 | debian-bookworm | ok 2026-06-25 03:39:37.011618 | 2026-06-25 03:39:37.011717 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-25 03:39:37.532819 | debian-bookworm -> localhost | ok 2026-06-25 03:39:37.544708 | 2026-06-25 03:39:37.544835 | TASK [validate-host : Collect information about the host] 2026-06-25 03:39:38.783419 | debian-bookworm | ok 2026-06-25 03:39:38.811170 | 2026-06-25 03:39:38.811294 | TASK [validate-host : Sanitize hostname] 2026-06-25 03:39:39.157430 | debian-bookworm | ok 2026-06-25 03:39:39.164718 | 2026-06-25 03:39:39.164810 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-25 03:39:40.255514 | debian-bookworm -> localhost | changed 2026-06-25 03:39:40.261776 | 2026-06-25 03:39:40.261878 | TASK [validate-host : Collect information about zuul worker] 2026-06-25 03:39:40.729371 | debian-bookworm | ok 2026-06-25 03:39:40.742570 | 2026-06-25 03:39:40.742702 | TASK [validate-host : Write out all zuul information for each host] 2026-06-25 03:39:41.327313 | debian-bookworm -> localhost | changed 2026-06-25 03:39:41.335402 | 2026-06-25 03:39:41.335497 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-25 03:39:41.663047 | debian-bookworm | ok 2026-06-25 03:39:41.673456 | 2026-06-25 03:39:41.673562 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-25 03:39:42.891808 | debian-bookworm | changed: 2026-06-25 03:39:42.891987 | debian-bookworm | cd+++++++++ src/ 2026-06-25 03:39:42.892022 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-25 03:39:42.892046 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-25 03:39:42.892068 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-06-25 03:39:42.892088 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/work/logs" 2026-06-25 03:40:49.927313 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/work/artifacts" 2026-06-25 03:40:50.276867 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bc6cc54f7dc347fea7bdd396f4812c98/work/docs" 2026-06-25 03:40:50.292478 | 2026-06-25 03:40:50.292638 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-25 03:40:51.121883 | debian-bookworm | changed: 2026-06-25 03:40:51.122183 | debian-bookworm | .d..t...... ./ 2026-06-25 03:40:51.122260 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-06-25 03:40:51.122288 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-06-25 03:40:51.122310 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-25 03:40:51.122331 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-25 03:40:51.122351 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-25 03:40:51.122402 | debian-bookworm | changed: All items complete 2026-06-25 03:40:51.122444 | 2026-06-25 03:40:51.819980 | debian-bookworm | changed: .d..t...... ./ 2026-06-25 03:40:52.444753 | debian-bookworm | changed: .d..t...... ./ 2026-06-25 03:40:52.471906 | 2026-06-25 03:40:52.472366 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-25 03:40:52.524817 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:40:52.532924 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:40:52.556954 | 2026-06-25 03:40:52.557086 | PLAY RECAP 2026-06-25 03:40:52.557297 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-25 03:40:52.557353 | 2026-06-25 03:40:52.799272 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-25 03:40:52.800301 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-25 03:40:53.802712 | 2026-06-25 03:40:53.802943 | PLAY [Base post] 2026-06-25 03:40:53.820811 | 2026-06-25 03:40:53.821202 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-25 03:40:54.436663 | debian-bookworm | changed 2026-06-25 03:40:54.464977 | 2026-06-25 03:40:54.465138 | PLAY RECAP 2026-06-25 03:40:54.465207 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-25 03:40:54.465316 | 2026-06-25 03:40:54.612441 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-25 03:40:54.613380 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-25 03:40:55.459206 | 2026-06-25 03:40:55.459433 | PLAY [Base post-logs] 2026-06-25 03:40:55.471407 | 2026-06-25 03:40:55.471575 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-25 03:40:55.964299 | localhost | changed 2026-06-25 03:40:55.975616 | 2026-06-25 03:40:55.975808 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-25 03:40:56.012862 | localhost | ok 2026-06-25 03:40:56.018985 | 2026-06-25 03:40:56.019119 | TASK [Set zuul-log-path fact] 2026-06-25 03:40:56.049137 | localhost | ok 2026-06-25 03:40:56.064883 | 2026-06-25 03:40:56.065095 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-25 03:40:56.115266 | localhost | ok 2026-06-25 03:40:56.124496 | 2026-06-25 03:40:56.124696 | TASK [upload-logs : Create log directories] 2026-06-25 03:40:56.711439 | localhost | changed 2026-06-25 03:40:56.714893 | 2026-06-25 03:40:56.715021 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-25 03:40:57.350915 | localhost -> localhost | ok: Runtime: 0:00:00.018003 2026-06-25 03:40:57.356687 | 2026-06-25 03:40:57.356837 | TASK [upload-logs : Upload logs to log server] 2026-06-25 03:40:57.995308 | localhost | Output suppressed because no_log was given 2026-06-25 03:40:57.998127 | 2026-06-25 03:40:57.998307 | LOOP [upload-logs : Compress console log and json output] 2026-06-25 03:40:58.060409 | localhost | skipping: Conditional result was False 2026-06-25 03:40:58.067187 | localhost | skipping: Conditional result was False 2026-06-25 03:40:58.077788 | 2026-06-25 03:40:58.078158 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-25 03:40:58.134502 | localhost | skipping: Conditional result was False 2026-06-25 03:40:58.134983 | 2026-06-25 03:40:58.141029 | localhost | skipping: Conditional result was False 2026-06-25 03:40:58.147918 | 2026-06-25 03:40:58.148079 | LOOP [upload-logs : Upload console log and json output]