2026-07-09 03:18:57.347056 | Job console starting 2026-07-09 03:18:57.358506 | Updating git repos 2026-07-09 03:18:57.386605 | Cloning repos into workspace 2026-07-09 03:18:57.413046 | Restoring repo states 2026-07-09 03:18:57.420593 | Merging changes 2026-07-09 03:18:57.420614 | Checking out repos 2026-07-09 03:18:57.453362 | Preparing playbooks 2026-07-09 03:18:58.693869 | Running Ansible setup 2026-07-09 03:19:06.494844 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-09 03:19:08.094915 | 2026-07-09 03:19:08.095063 | PLAY [Base pre] 2026-07-09 03:19:08.119526 | 2026-07-09 03:19:08.119656 | TASK [Setup log path fact] 2026-07-09 03:19:08.157849 | debian-bookworm | ok 2026-07-09 03:19:08.196372 | 2026-07-09 03:19:08.196513 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-09 03:19:08.280524 | debian-bookworm | ok 2026-07-09 03:19:08.303238 | 2026-07-09 03:19:08.303361 | TASK [emit-job-header : Print job information] 2026-07-09 03:19:08.388471 | # Job Information 2026-07-09 03:19:08.388632 | Ansible Version: 2.16.14 2026-07-09 03:19:08.388666 | Job: python-black 2026-07-09 03:19:08.388698 | Pipeline: periodic-daily 2026-07-09 03:19:08.388720 | Executor: 521e9411259a 2026-07-09 03:19:08.388741 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-07-09 03:19:08.388762 | Event ID: 0496361fb5c44d1292387e03bfe242cf 2026-07-09 03:19:08.394027 | 2026-07-09 03:19:08.394128 | LOOP [emit-job-header : Print node information] 2026-07-09 03:19:08.621212 | debian-bookworm | ok: 2026-07-09 03:19:08.621838 | debian-bookworm | # Node Information 2026-07-09 03:19:08.621904 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-09 03:19:08.621932 | debian-bookworm | Hostname: debian 2026-07-09 03:19:08.621956 | debian-bookworm | Username: zuul 2026-07-09 03:19:08.621990 | debian-bookworm | Distro: Debian 12.14 2026-07-09 03:19:08.622015 | debian-bookworm | Provider: regiocloud-a 2026-07-09 03:19:08.622036 | debian-bookworm | Region: 2026-07-09 03:19:08.622058 | debian-bookworm | Label: debian-bookworm 2026-07-09 03:19:08.622077 | debian-bookworm | Product Name: OpenStack Nova 2026-07-09 03:19:08.622096 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea3:3055 2026-07-09 03:19:08.639154 | 2026-07-09 03:19:08.639261 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-09 03:19:09.448168 | debian-bookworm -> localhost | changed 2026-07-09 03:19:09.461236 | 2026-07-09 03:19:09.461344 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-09 03:19:10.790208 | debian-bookworm -> localhost | changed 2026-07-09 03:19:10.810418 | 2026-07-09 03:19:10.810560 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-09 03:19:11.093159 | debian-bookworm -> localhost | ok 2026-07-09 03:19:11.099119 | 2026-07-09 03:19:11.099227 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-09 03:19:11.128504 | debian-bookworm | ok 2026-07-09 03:19:11.145890 | debian-bookworm | included: /var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-09 03:19:11.162183 | 2026-07-09 03:19:11.162290 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-09 03:19:12.387536 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-09 03:19:12.388028 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/work/a2fb3bfa0e9e4d4fa99cf77a610d2573_id_rsa 2026-07-09 03:19:12.388324 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/work/a2fb3bfa0e9e4d4fa99cf77a610d2573_id_rsa.pub 2026-07-09 03:19:12.388365 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-09 03:19:12.388389 | debian-bookworm -> localhost | SHA256:U4qS5GQQcJPQC4o5kvGPSVtySWv5Rc563Kxa9PZHXEg zuul-build-sshkey 2026-07-09 03:19:12.388492 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-09 03:19:12.388525 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-09 03:19:12.388564 | debian-bookworm -> localhost | |o+=o | 2026-07-09 03:19:12.388741 | debian-bookworm -> localhost | |o.oo. . E | 2026-07-09 03:19:12.388766 | debian-bookworm -> localhost | |o* o++ + . . . | 2026-07-09 03:19:12.388855 | debian-bookworm -> localhost | |B ==B. .+o . .| 2026-07-09 03:19:12.388883 | debian-bookworm -> localhost | |.o X+..+So . . | 2026-07-09 03:19:12.388903 | debian-bookworm -> localhost | | + ..o.ooo o | 2026-07-09 03:19:12.388922 | debian-bookworm -> localhost | | ...o . | 2026-07-09 03:19:12.388952 | debian-bookworm -> localhost | | ... . . | 2026-07-09 03:19:12.389491 | debian-bookworm -> localhost | | .. .. | 2026-07-09 03:19:12.389525 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-09 03:19:12.389580 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.747860 2026-07-09 03:19:12.403075 | 2026-07-09 03:19:12.403153 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-09 03:19:12.429996 | debian-bookworm | ok 2026-07-09 03:19:12.438809 | debian-bookworm | included: /var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-09 03:19:12.447058 | 2026-07-09 03:19:12.447144 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-09 03:19:12.470672 | debian-bookworm | skipping: Conditional result was False 2026-07-09 03:19:12.475593 | 2026-07-09 03:19:12.475715 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-09 03:19:13.207257 | debian-bookworm | changed 2026-07-09 03:19:13.212375 | 2026-07-09 03:19:13.212506 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-09 03:19:13.455994 | debian-bookworm | ok 2026-07-09 03:19:13.460718 | 2026-07-09 03:19:13.460809 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-09 03:19:14.208922 | debian-bookworm | changed 2026-07-09 03:19:14.216080 | 2026-07-09 03:19:14.216193 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-09 03:19:14.917637 | debian-bookworm | changed 2026-07-09 03:19:14.923674 | 2026-07-09 03:19:14.923772 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-09 03:19:14.946672 | debian-bookworm | skipping: Conditional result was False 2026-07-09 03:19:14.956404 | 2026-07-09 03:19:14.956514 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-09 03:19:15.508018 | debian-bookworm -> localhost | changed 2026-07-09 03:19:15.529001 | 2026-07-09 03:19:15.529134 | TASK [add-build-sshkey : Add back temp key] 2026-07-09 03:19:16.009562 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/work/a2fb3bfa0e9e4d4fa99cf77a610d2573_id_rsa (zuul-build-sshkey) 2026-07-09 03:19:16.009771 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.020271 2026-07-09 03:19:16.021168 | 2026-07-09 03:19:16.021259 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-09 03:19:16.696435 | debian-bookworm | ok 2026-07-09 03:19:16.700932 | 2026-07-09 03:19:16.701045 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-09 03:19:16.744186 | debian-bookworm | skipping: Conditional result was False 2026-07-09 03:19:16.753664 | 2026-07-09 03:19:16.753761 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-09 03:19:17.319413 | debian-bookworm | ok 2026-07-09 03:19:17.330221 | 2026-07-09 03:19:17.330328 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-09 03:19:17.355635 | debian-bookworm | ok 2026-07-09 03:19:17.363203 | 2026-07-09 03:19:17.363313 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-09 03:19:17.619331 | debian-bookworm -> localhost | ok 2026-07-09 03:19:17.624834 | 2026-07-09 03:19:17.624924 | TASK [validate-host : Collect information about the host] 2026-07-09 03:19:18.746146 | debian-bookworm | ok 2026-07-09 03:19:18.771720 | 2026-07-09 03:19:18.771841 | TASK [validate-host : Sanitize hostname] 2026-07-09 03:19:18.971772 | debian-bookworm | ok 2026-07-09 03:19:18.979824 | 2026-07-09 03:19:18.979948 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-09 03:19:19.557986 | debian-bookworm -> localhost | changed 2026-07-09 03:19:19.562586 | 2026-07-09 03:19:19.562671 | TASK [validate-host : Collect information about zuul worker] 2026-07-09 03:19:19.947624 | debian-bookworm | ok 2026-07-09 03:19:19.952431 | 2026-07-09 03:19:19.952543 | TASK [validate-host : Write out all zuul information for each host] 2026-07-09 03:19:20.857434 | debian-bookworm -> localhost | changed 2026-07-09 03:19:20.868267 | 2026-07-09 03:19:20.868383 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-09 03:19:21.178309 | debian-bookworm | ok 2026-07-09 03:19:21.183660 | 2026-07-09 03:19:21.183756 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-09 03:19:22.196195 | debian-bookworm | changed: 2026-07-09 03:19:22.197046 | debian-bookworm | cd+++++++++ src/ 2026-07-09 03:19:22.197131 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-09 03:19:22.197157 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-09 03:19:22.197180 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-07-09 03:19:22.197202 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/work/logs" 2026-07-09 03:20:30.242312 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/work/artifacts" 2026-07-09 03:20:30.498593 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/a2fb3bfa0e9e4d4fa99cf77a610d2573/work/docs" 2026-07-09 03:20:30.514623 | 2026-07-09 03:20:30.514763 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-09 03:20:31.417750 | debian-bookworm | changed: 2026-07-09 03:20:31.417990 | debian-bookworm | .d..t...... ./ 2026-07-09 03:20:31.418046 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-09 03:20:31.418070 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-09 03:20:31.418092 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-09 03:20:31.418113 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-09 03:20:31.418133 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-09 03:20:31.418175 | debian-bookworm | changed: All items complete 2026-07-09 03:20:31.418197 | 2026-07-09 03:20:32.042270 | debian-bookworm | changed: .d..t...... ./ 2026-07-09 03:20:32.615222 | debian-bookworm | changed: .d..t...... ./ 2026-07-09 03:20:32.647969 | 2026-07-09 03:20:32.648205 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-09 03:20:32.688423 | debian-bookworm | skipping: Conditional result was False 2026-07-09 03:20:32.691957 | debian-bookworm | skipping: Conditional result was False 2026-07-09 03:20:32.709074 | 2026-07-09 03:20:32.709237 | PLAY RECAP 2026-07-09 03:20:32.709319 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-07-09 03:20:32.709358 | 2026-07-09 03:20:32.848316 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-09 03:20:32.849230 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-09 03:20:33.726269 | 2026-07-09 03:20:33.726444 | PLAY [Base post] 2026-07-09 03:20:33.741023 | 2026-07-09 03:20:33.741167 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-09 03:20:34.374138 | debian-bookworm | changed 2026-07-09 03:20:34.390662 | 2026-07-09 03:20:34.390817 | PLAY RECAP 2026-07-09 03:20:34.390917 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-09 03:20:34.390983 | 2026-07-09 03:20:34.548277 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-09 03:20:34.550726 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-09 03:20:35.474423 | 2026-07-09 03:20:35.474618 | PLAY [Base post-logs] 2026-07-09 03:20:35.489813 | 2026-07-09 03:20:35.489978 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-09 03:20:36.207647 | localhost | changed 2026-07-09 03:20:36.227876 | 2026-07-09 03:20:36.228134 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-09 03:20:36.289463 | localhost | ok 2026-07-09 03:20:36.294820 | 2026-07-09 03:20:36.295181 | TASK [Set zuul-log-path fact] 2026-07-09 03:20:36.323789 | localhost | ok 2026-07-09 03:20:36.340116 | 2026-07-09 03:20:36.340264 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-09 03:20:36.378176 | localhost | ok 2026-07-09 03:20:36.381442 | 2026-07-09 03:20:36.381552 | TASK [upload-logs : Create log directories] 2026-07-09 03:20:37.484784 | localhost | changed 2026-07-09 03:20:37.489593 | 2026-07-09 03:20:37.489765 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-09 03:20:38.763072 | localhost -> localhost | ok: Runtime: 0:00:00.031851 2026-07-09 03:20:38.767653 | 2026-07-09 03:20:38.767847 | TASK [upload-logs : Upload logs to log server] 2026-07-09 03:20:39.671123 | localhost | Output suppressed because no_log was given 2026-07-09 03:20:39.672766 | 2026-07-09 03:20:39.672849 | LOOP [upload-logs : Compress console log and json output] 2026-07-09 03:20:39.715170 | localhost | skipping: Conditional result was False 2026-07-09 03:20:39.719789 | localhost | skipping: Conditional result was False 2026-07-09 03:20:39.729140 | 2026-07-09 03:20:39.729240 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-09 03:20:39.833611 | localhost | skipping: Conditional result was False 2026-07-09 03:20:39.833860 | 2026-07-09 03:20:39.838664 | localhost | skipping: Conditional result was False 2026-07-09 03:20:39.846072 | 2026-07-09 03:20:39.846740 | LOOP [upload-logs : Upload console log and json output]