2026-07-21 03:17:13.862458 | Job console starting 2026-07-21 03:17:13.873424 | Updating git repos 2026-07-21 03:17:13.905903 | Cloning repos into workspace 2026-07-21 03:17:13.949162 | Restoring repo states 2026-07-21 03:17:13.964988 | Merging changes 2026-07-21 03:17:13.965013 | Checking out repos 2026-07-21 03:17:13.993175 | Preparing playbooks 2026-07-21 03:17:14.883334 | Running Ansible setup 2026-07-21 03:17:19.231194 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-07-21 03:17:20.081270 | 2026-07-21 03:17:20.081465 | PLAY [Base pre] 2026-07-21 03:17:20.109667 | 2026-07-21 03:17:20.109872 | TASK [Setup log path fact] 2026-07-21 03:17:20.130386 | debian-bookworm | ok 2026-07-21 03:17:20.150350 | 2026-07-21 03:17:20.150536 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-21 03:17:20.211728 | debian-bookworm | ok 2026-07-21 03:17:20.230986 | 2026-07-21 03:17:20.231145 | TASK [emit-job-header : Print job information] 2026-07-21 03:17:20.280402 | # Job Information 2026-07-21 03:17:20.282534 | Ansible Version: 2.16.14 2026-07-21 03:17:20.282607 | Job: python-black 2026-07-21 03:17:20.282645 | Pipeline: periodic-daily 2026-07-21 03:17:20.282670 | Executor: 521e9411259a 2026-07-21 03:17:20.282691 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-07-21 03:17:20.282715 | Event ID: 4a8f54c47d90498a8322e691873a30ab 2026-07-21 03:17:20.295463 | 2026-07-21 03:17:20.295604 | LOOP [emit-job-header : Print node information] 2026-07-21 03:17:20.433920 | debian-bookworm | ok: 2026-07-21 03:17:20.434180 | debian-bookworm | # Node Information 2026-07-21 03:17:20.434215 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-07-21 03:17:20.434240 | debian-bookworm | Hostname: debian 2026-07-21 03:17:20.434262 | debian-bookworm | Username: zuul 2026-07-21 03:17:20.434282 | debian-bookworm | Distro: Debian 12.15 2026-07-21 03:17:20.434306 | debian-bookworm | Provider: regiocloud-a 2026-07-21 03:17:20.434326 | debian-bookworm | Region: 2026-07-21 03:17:20.434348 | debian-bookworm | Label: debian-bookworm 2026-07-21 03:17:20.434368 | debian-bookworm | Product Name: OpenStack Nova 2026-07-21 03:17:20.434388 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe69:a9c2 2026-07-21 03:17:20.446230 | 2026-07-21 03:17:20.446408 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-07-21 03:17:21.024665 | debian-bookworm -> localhost | changed 2026-07-21 03:17:21.043318 | 2026-07-21 03:17:21.043494 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-07-21 03:17:22.460743 | debian-bookworm -> localhost | changed 2026-07-21 03:17:22.472818 | 2026-07-21 03:17:22.472963 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-07-21 03:17:22.755979 | debian-bookworm -> localhost | ok 2026-07-21 03:17:22.762716 | 2026-07-21 03:17:22.762891 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-07-21 03:17:22.805861 | debian-bookworm | ok 2026-07-21 03:17:22.825634 | debian-bookworm | included: /var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-07-21 03:17:22.832739 | 2026-07-21 03:17:22.832898 | TASK [add-build-sshkey : Create Temp SSH key] 2026-07-21 03:17:24.304467 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-07-21 03:17:24.304761 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/work/fcc1f6d907ea4148ae25e260aeaa3d90_id_rsa 2026-07-21 03:17:24.304817 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/work/fcc1f6d907ea4148ae25e260aeaa3d90_id_rsa.pub 2026-07-21 03:17:24.304844 | debian-bookworm -> localhost | The key fingerprint is: 2026-07-21 03:17:24.304869 | debian-bookworm -> localhost | SHA256:KA8GqOVvPJN18Y26nTZv4bYgb5hB8hxRRAJa38OZ+eA zuul-build-sshkey 2026-07-21 03:17:24.304891 | debian-bookworm -> localhost | The key's randomart image is: 2026-07-21 03:17:24.304925 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-07-21 03:17:24.304946 | debian-bookworm -> localhost | | o..++ | 2026-07-21 03:17:24.304967 | debian-bookworm -> localhost | | . o ..+ + | 2026-07-21 03:17:24.304987 | debian-bookworm -> localhost | |. o . o.O | 2026-07-21 03:17:24.305006 | debian-bookworm -> localhost | |.o . ..o+ * | 2026-07-21 03:17:24.305025 | debian-bookworm -> localhost | |. . + o=S.E o | 2026-07-21 03:17:24.305051 | debian-bookworm -> localhost | | + * .+. . | 2026-07-21 03:17:24.305071 | debian-bookworm -> localhost | | B . o+.. . | 2026-07-21 03:17:24.305091 | debian-bookworm -> localhost | | . o o=+o+ | 2026-07-21 03:17:24.305111 | debian-bookworm -> localhost | | .o+=o. | 2026-07-21 03:17:24.305130 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-07-21 03:17:24.305190 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.877712 2026-07-21 03:17:24.313108 | 2026-07-21 03:17:24.313250 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-07-21 03:17:24.360927 | debian-bookworm | ok 2026-07-21 03:17:24.373107 | debian-bookworm | included: /var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-07-21 03:17:24.381749 | 2026-07-21 03:17:24.381911 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-07-21 03:17:24.406878 | debian-bookworm | skipping: Conditional result was False 2026-07-21 03:17:24.415728 | 2026-07-21 03:17:24.415874 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-07-21 03:17:25.147452 | debian-bookworm | changed 2026-07-21 03:17:25.161242 | 2026-07-21 03:17:25.161953 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-07-21 03:17:25.439924 | debian-bookworm | ok 2026-07-21 03:17:25.454413 | 2026-07-21 03:17:25.454572 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-07-21 03:17:26.209152 | debian-bookworm | changed 2026-07-21 03:17:26.223198 | 2026-07-21 03:17:26.223356 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-07-21 03:17:27.015927 | debian-bookworm | changed 2026-07-21 03:17:27.027483 | 2026-07-21 03:17:27.027637 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-07-21 03:17:27.066668 | debian-bookworm | skipping: Conditional result was False 2026-07-21 03:17:27.074329 | 2026-07-21 03:17:27.074480 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-07-21 03:17:27.830363 | debian-bookworm -> localhost | changed 2026-07-21 03:17:27.845457 | 2026-07-21 03:17:27.845615 | TASK [add-build-sshkey : Add back temp key] 2026-07-21 03:17:28.578336 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/work/fcc1f6d907ea4148ae25e260aeaa3d90_id_rsa (zuul-build-sshkey) 2026-07-21 03:17:28.578707 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031101 2026-07-21 03:17:28.593883 | 2026-07-21 03:17:28.594043 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-07-21 03:17:29.198030 | debian-bookworm | ok 2026-07-21 03:17:29.207259 | 2026-07-21 03:17:29.207363 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-07-21 03:17:29.272700 | debian-bookworm | skipping: Conditional result was False 2026-07-21 03:17:29.284960 | 2026-07-21 03:17:29.285067 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-07-21 03:17:29.858155 | debian-bookworm | ok 2026-07-21 03:17:29.870252 | 2026-07-21 03:17:29.870367 | TASK [validate-host : Define zuul_info_dir fact] 2026-07-21 03:17:29.898496 | debian-bookworm | ok 2026-07-21 03:17:29.908523 | 2026-07-21 03:17:29.908629 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-07-21 03:17:30.357780 | debian-bookworm -> localhost | ok 2026-07-21 03:17:30.368943 | 2026-07-21 03:17:30.369046 | TASK [validate-host : Collect information about the host] 2026-07-21 03:17:31.554887 | debian-bookworm | ok 2026-07-21 03:17:31.567649 | 2026-07-21 03:17:31.567756 | TASK [validate-host : Sanitize hostname] 2026-07-21 03:17:31.734443 | debian-bookworm | ok 2026-07-21 03:17:31.747162 | 2026-07-21 03:17:31.747274 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-07-21 03:17:32.538296 | debian-bookworm -> localhost | changed 2026-07-21 03:17:32.543889 | 2026-07-21 03:17:32.543991 | TASK [validate-host : Collect information about zuul worker] 2026-07-21 03:17:33.092680 | debian-bookworm | ok 2026-07-21 03:17:33.097719 | 2026-07-21 03:17:33.097839 | TASK [validate-host : Write out all zuul information for each host] 2026-07-21 03:17:34.155633 | debian-bookworm -> localhost | changed 2026-07-21 03:17:34.166420 | 2026-07-21 03:17:34.166519 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-07-21 03:17:34.426310 | debian-bookworm | ok 2026-07-21 03:17:34.434516 | 2026-07-21 03:17:34.434628 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-07-21 03:17:36.029493 | debian-bookworm | changed: 2026-07-21 03:17:36.029702 | debian-bookworm | cd+++++++++ src/ 2026-07-21 03:17:36.029768 | debian-bookworm | cd+++++++++ src/github.com/ 2026-07-21 03:17:36.029798 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-07-21 03:17:36.029843 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-07-21 03:17:36.029883 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/work/logs" 2026-07-21 03:18:38.385852 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/work/artifacts" 2026-07-21 03:18:38.717866 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/fcc1f6d907ea4148ae25e260aeaa3d90/work/docs" 2026-07-21 03:18:38.732506 | 2026-07-21 03:18:38.732654 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-07-21 03:18:39.542559 | debian-bookworm | changed: 2026-07-21 03:18:39.546723 | debian-bookworm | .d..t...... ./ 2026-07-21 03:18:39.546823 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-07-21 03:18:39.546872 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-07-21 03:18:39.546896 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-07-21 03:18:39.546919 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-07-21 03:18:39.546940 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-07-21 03:18:39.546998 | debian-bookworm | changed: All items complete 2026-07-21 03:18:39.547027 | 2026-07-21 03:18:40.177000 | debian-bookworm | changed: .d..t...... ./ 2026-07-21 03:18:40.825875 | debian-bookworm | changed: .d..t...... ./ 2026-07-21 03:18:40.860881 | 2026-07-21 03:18:40.861466 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-07-21 03:18:40.912106 | debian-bookworm | skipping: Conditional result was False 2026-07-21 03:18:40.919058 | debian-bookworm | skipping: Conditional result was False 2026-07-21 03:18:40.945352 | 2026-07-21 03:18:40.945490 | PLAY RECAP 2026-07-21 03:18:40.946519 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-07-21 03:18:40.947012 | 2026-07-21 03:18:41.216029 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-07-21 03:18:41.217894 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-21 03:18:42.757198 | 2026-07-21 03:18:42.757417 | PLAY [Base post] 2026-07-21 03:18:42.810815 | 2026-07-21 03:18:42.811090 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-07-21 03:18:43.585357 | debian-bookworm | changed 2026-07-21 03:18:43.604264 | 2026-07-21 03:18:43.604422 | PLAY RECAP 2026-07-21 03:18:43.604985 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-07-21 03:18:43.605096 | 2026-07-21 03:18:43.961225 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-07-21 03:18:43.962123 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-07-21 03:18:44.919483 | 2026-07-21 03:18:44.919702 | PLAY [Base post-logs] 2026-07-21 03:18:44.930753 | 2026-07-21 03:18:44.930921 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-07-21 03:18:45.620080 | localhost | changed 2026-07-21 03:18:45.646668 | 2026-07-21 03:18:45.646938 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-07-21 03:18:45.713895 | localhost | ok 2026-07-21 03:18:45.717826 | 2026-07-21 03:18:45.717982 | TASK [Set zuul-log-path fact] 2026-07-21 03:18:45.755737 | localhost | ok 2026-07-21 03:18:45.769532 | 2026-07-21 03:18:45.769679 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-07-21 03:18:45.806473 | localhost | ok 2026-07-21 03:18:45.810592 | 2026-07-21 03:18:45.810785 | TASK [upload-logs : Create log directories] 2026-07-21 03:18:46.627521 | localhost | changed 2026-07-21 03:18:46.631120 | 2026-07-21 03:18:46.631248 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-07-21 03:18:47.279471 | localhost -> localhost | ok: Runtime: 0:00:00.011249 2026-07-21 03:18:47.284628 | 2026-07-21 03:18:47.284799 | TASK [upload-logs : Upload logs to log server] 2026-07-21 03:18:48.117732 | localhost | Output suppressed because no_log was given 2026-07-21 03:18:48.120173 | 2026-07-21 03:18:48.120302 | LOOP [upload-logs : Compress console log and json output] 2026-07-21 03:18:48.188836 | localhost | skipping: Conditional result was False 2026-07-21 03:18:48.200471 | localhost | skipping: Conditional result was False 2026-07-21 03:18:48.210071 | 2026-07-21 03:18:48.210248 | LOOP [upload-logs : Upload compressed console log and json output] 2026-07-21 03:18:48.271778 | localhost | skipping: Conditional result was False 2026-07-21 03:18:48.272113 | 2026-07-21 03:18:48.277641 | localhost | skipping: Conditional result was False 2026-07-21 03:18:48.289350 | 2026-07-21 03:18:48.289525 | LOOP [upload-logs : Upload console log and json output]