2026-09-07 03:16:54.990733 | Job console starting 2026-09-07 03:16:55.003416 | Updating git repos 2026-09-07 03:16:55.025726 | Cloning repos into workspace 2026-09-07 03:16:55.047400 | Restoring repo states 2026-09-07 03:16:55.051474 | Merging changes 2026-09-07 03:16:55.051488 | Checking out repos 2026-09-07 03:16:55.089994 | Preparing playbooks 2026-09-07 03:16:56.006389 | Running Ansible setup 2026-09-07 03:17:00.495176 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-07 03:17:01.338044 | 2026-09-07 03:17:01.338172 | PLAY [Base pre] 2026-09-07 03:17:01.357304 | 2026-09-07 03:17:01.357415 | TASK [Setup log path fact] 2026-09-07 03:17:01.389304 | debian-bookworm | ok 2026-09-07 03:17:01.408137 | 2026-09-07 03:17:01.408298 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 03:17:01.437784 | debian-bookworm | ok 2026-09-07 03:17:01.448116 | 2026-09-07 03:17:01.448207 | TASK [emit-job-header : Print job information] 2026-09-07 03:17:01.476242 | # Job Information 2026-09-07 03:17:01.476419 | Ansible Version: 2.16.14 2026-09-07 03:17:01.476455 | Job: python-black 2026-09-07 03:17:01.476491 | Pipeline: periodic-daily 2026-09-07 03:17:01.476535 | Executor: 521e9411259a 2026-09-07 03:17:01.476558 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-09-07 03:17:01.476580 | Event ID: a777f2a8ddcd415bb9711a0d30185cf2 2026-09-07 03:17:01.485417 | 2026-09-07 03:17:01.485519 | LOOP [emit-job-header : Print node information] 2026-09-07 03:17:01.633126 | debian-bookworm | ok: 2026-09-07 03:17:01.633342 | debian-bookworm | # Node Information 2026-09-07 03:17:01.633379 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-07 03:17:01.633404 | debian-bookworm | Hostname: debian 2026-09-07 03:17:01.633425 | debian-bookworm | Username: zuul 2026-09-07 03:17:01.633446 | debian-bookworm | Distro: Debian 12.15 2026-09-07 03:17:01.633469 | debian-bookworm | Provider: regiocloud-a 2026-09-07 03:17:01.633490 | debian-bookworm | Region: 2026-09-07 03:17:01.633511 | debian-bookworm | Label: debian-bookworm 2026-09-07 03:17:01.633531 | debian-bookworm | Product Name: OpenStack Nova 2026-09-07 03:17:01.633550 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe51:2d2c 2026-09-07 03:17:01.653844 | 2026-09-07 03:17:01.653972 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-07 03:17:02.244018 | debian-bookworm -> localhost | changed 2026-09-07 03:17:02.250548 | 2026-09-07 03:17:02.250639 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-07 03:17:03.786351 | debian-bookworm -> localhost | changed 2026-09-07 03:17:03.797868 | 2026-09-07 03:17:03.797975 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-07 03:17:04.077220 | debian-bookworm -> localhost | ok 2026-09-07 03:17:04.083451 | 2026-09-07 03:17:04.083544 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-07 03:17:04.102324 | debian-bookworm | ok 2026-09-07 03:17:04.117074 | debian-bookworm | included: /var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-07 03:17:04.123138 | 2026-09-07 03:17:04.123221 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-07 03:17:05.568349 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-07 03:17:05.568793 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/work/89ad21b9db7a44cfb892b696db1fb115_id_rsa 2026-09-07 03:17:05.568966 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/work/89ad21b9db7a44cfb892b696db1fb115_id_rsa.pub 2026-09-07 03:17:05.569002 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-07 03:17:05.569124 | debian-bookworm -> localhost | SHA256:lDhJfggeYdsaINSwDLIcxlsmEpX5IrEzWqsC4h7nkyY zuul-build-sshkey 2026-09-07 03:17:05.569157 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-07 03:17:05.569329 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-07 03:17:05.569371 | debian-bookworm -> localhost | |BB=o=.. | 2026-09-07 03:17:05.569505 | debian-bookworm -> localhost | |O==B B + . | 2026-09-07 03:17:05.569652 | debian-bookworm -> localhost | |o*=.+ B + | 2026-09-07 03:17:05.569770 | debian-bookworm -> localhost | |=.o .o + | 2026-09-07 03:17:05.569806 | debian-bookworm -> localhost | |.= o. S | 2026-09-07 03:17:05.569930 | debian-bookworm -> localhost | |+ . | 2026-09-07 03:17:05.570062 | debian-bookworm -> localhost | |+o .. | 2026-09-07 03:17:05.570100 | debian-bookworm -> localhost | |oE++ | 2026-09-07 03:17:05.570215 | debian-bookworm -> localhost | |o.o.. | 2026-09-07 03:17:05.570240 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-07 03:17:05.570983 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.989227 2026-09-07 03:17:05.583668 | 2026-09-07 03:17:05.589680 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-07 03:17:05.618157 | debian-bookworm | ok 2026-09-07 03:17:05.630940 | debian-bookworm | included: /var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-07 03:17:05.646381 | 2026-09-07 03:17:05.646541 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-07 03:17:05.673087 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:17:05.681531 | 2026-09-07 03:17:05.681625 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-07 03:17:06.460008 | debian-bookworm | changed 2026-09-07 03:17:06.468303 | 2026-09-07 03:17:06.468433 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-07 03:17:06.739297 | debian-bookworm | ok 2026-09-07 03:17:06.750589 | 2026-09-07 03:17:06.750720 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-07 03:17:07.465483 | debian-bookworm | changed 2026-09-07 03:17:07.473865 | 2026-09-07 03:17:07.474018 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-07 03:17:08.135386 | debian-bookworm | changed 2026-09-07 03:17:08.141348 | 2026-09-07 03:17:08.141461 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-07 03:17:08.165194 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:17:08.171157 | 2026-09-07 03:17:08.171262 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-07 03:17:08.670314 | debian-bookworm -> localhost | changed 2026-09-07 03:17:08.683187 | 2026-09-07 03:17:08.683323 | TASK [add-build-sshkey : Add back temp key] 2026-09-07 03:17:09.026545 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/work/89ad21b9db7a44cfb892b696db1fb115_id_rsa (zuul-build-sshkey) 2026-09-07 03:17:09.026791 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011344 2026-09-07 03:17:09.034748 | 2026-09-07 03:17:09.034883 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-07 03:17:09.448149 | debian-bookworm | ok 2026-09-07 03:17:09.453692 | 2026-09-07 03:17:09.453801 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-07 03:17:09.477485 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:17:09.492287 | 2026-09-07 03:17:09.492412 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-07 03:17:09.965685 | debian-bookworm | ok 2026-09-07 03:17:09.984849 | 2026-09-07 03:17:09.985029 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-07 03:17:10.014372 | debian-bookworm | ok 2026-09-07 03:17:10.019640 | 2026-09-07 03:17:10.019740 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-07 03:17:10.305962 | debian-bookworm -> localhost | ok 2026-09-07 03:17:10.312847 | 2026-09-07 03:17:10.312981 | TASK [validate-host : Collect information about the host] 2026-09-07 03:17:11.492547 | debian-bookworm | ok 2026-09-07 03:17:11.504759 | 2026-09-07 03:17:11.504853 | TASK [validate-host : Sanitize hostname] 2026-09-07 03:17:11.618904 | debian-bookworm | ok 2026-09-07 03:17:11.623757 | 2026-09-07 03:17:11.623840 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-07 03:17:12.576261 | debian-bookworm -> localhost | changed 2026-09-07 03:17:12.581608 | 2026-09-07 03:17:12.581707 | TASK [validate-host : Collect information about zuul worker] 2026-09-07 03:17:13.016633 | debian-bookworm | ok 2026-09-07 03:17:13.021199 | 2026-09-07 03:17:13.021288 | TASK [validate-host : Write out all zuul information for each host] 2026-09-07 03:17:13.579727 | debian-bookworm -> localhost | changed 2026-09-07 03:17:13.590328 | 2026-09-07 03:17:13.590421 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-07 03:17:13.916628 | debian-bookworm | ok 2026-09-07 03:17:13.922296 | 2026-09-07 03:17:13.922381 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-07 03:17:14.675845 | debian-bookworm | changed: 2026-09-07 03:17:14.676011 | debian-bookworm | cd+++++++++ src/ 2026-09-07 03:17:14.676046 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-07 03:17:14.676070 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-07 03:17:14.676091 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-09-07 03:17:14.676111 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/work/logs" 2026-09-07 03:18:18.211599 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/work/artifacts" 2026-09-07 03:18:18.475941 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/89ad21b9db7a44cfb892b696db1fb115/work/docs" 2026-09-07 03:18:18.485208 | 2026-09-07 03:18:18.485353 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-07 03:18:19.154095 | debian-bookworm | changed: 2026-09-07 03:18:19.154339 | debian-bookworm | .d..t...... ./ 2026-09-07 03:18:19.154391 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-07 03:18:19.154428 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-07 03:18:19.154462 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-07 03:18:19.154494 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-07 03:18:19.154524 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-07 03:18:19.154581 | debian-bookworm | changed: All items complete 2026-09-07 03:18:19.154615 | 2026-09-07 03:18:19.641577 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 03:18:20.184106 | debian-bookworm | changed: .d..t...... ./ 2026-09-07 03:18:20.200438 | 2026-09-07 03:18:20.200543 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-07 03:18:20.227624 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:18:20.233317 | debian-bookworm | skipping: Conditional result was False 2026-09-07 03:18:20.244530 | 2026-09-07 03:18:20.244598 | PLAY RECAP 2026-09-07 03:18:20.244645 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-07 03:18:20.244669 | 2026-09-07 03:18:20.457745 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-07 03:18:20.458460 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 03:18:21.170318 | 2026-09-07 03:18:21.170437 | PLAY [Base post] 2026-09-07 03:18:21.189373 | 2026-09-07 03:18:21.189482 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-07 03:18:21.739030 | debian-bookworm | changed 2026-09-07 03:18:21.751117 | 2026-09-07 03:18:21.751222 | PLAY RECAP 2026-09-07 03:18:21.751284 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-07 03:18:21.751345 | 2026-09-07 03:18:21.898396 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-07 03:18:21.899140 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-07 03:18:22.637281 | 2026-09-07 03:18:22.637410 | PLAY [Base post-logs] 2026-09-07 03:18:22.660001 | 2026-09-07 03:18:22.660147 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-07 03:18:23.117607 | localhost | changed 2026-09-07 03:18:23.126469 | 2026-09-07 03:18:23.126579 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-07 03:18:23.150691 | localhost | ok 2026-09-07 03:18:23.153448 | 2026-09-07 03:18:23.153534 | TASK [Set zuul-log-path fact] 2026-09-07 03:18:23.178821 | localhost | ok 2026-09-07 03:18:23.190358 | 2026-09-07 03:18:23.190471 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-07 03:18:23.214821 | localhost | ok 2026-09-07 03:18:23.217536 | 2026-09-07 03:18:23.217620 | TASK [upload-logs : Create log directories] 2026-09-07 03:18:23.680685 | localhost | changed 2026-09-07 03:18:23.684993 | 2026-09-07 03:18:23.685116 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-07 03:18:24.098459 | localhost -> localhost | ok: Runtime: 0:00:00.004952 2026-09-07 03:18:24.101863 | 2026-09-07 03:18:24.101955 | TASK [upload-logs : Upload logs to log server] 2026-09-07 03:18:24.553645 | localhost | Output suppressed because no_log was given 2026-09-07 03:18:24.555254 | 2026-09-07 03:18:24.555342 | LOOP [upload-logs : Compress console log and json output] 2026-09-07 03:18:24.594942 | localhost | skipping: Conditional result was False 2026-09-07 03:18:24.602156 | localhost | skipping: Conditional result was False 2026-09-07 03:18:24.610366 | 2026-09-07 03:18:24.610454 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-07 03:18:24.672355 | localhost | skipping: Conditional result was False 2026-09-07 03:18:24.672566 | 2026-09-07 03:18:24.681787 | localhost | skipping: Conditional result was False 2026-09-07 03:18:24.685430 | 2026-09-07 03:18:24.685519 | LOOP [upload-logs : Upload console log and json output]