2026-05-20 03:13:38.960650 | Job console starting 2026-05-20 03:13:38.973411 | Updating git repos 2026-05-20 03:13:39.011424 | Cloning repos into workspace 2026-05-20 03:13:39.072046 | Restoring repo states 2026-05-20 03:13:39.092242 | Merging changes 2026-05-20 03:13:39.092256 | Checking out repos 2026-05-20 03:13:39.181230 | Preparing playbooks 2026-05-20 03:13:40.521770 | Running Ansible setup 2026-05-20 03:13:46.539359 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-20 03:13:47.774611 | 2026-05-20 03:13:47.774726 | PLAY [Base pre] 2026-05-20 03:13:47.812854 | 2026-05-20 03:13:47.812968 | TASK [Setup log path fact] 2026-05-20 03:13:47.849315 | debian-bookworm | ok 2026-05-20 03:13:47.872177 | 2026-05-20 03:13:47.872295 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-20 03:13:47.914079 | debian-bookworm | ok 2026-05-20 03:13:47.922811 | 2026-05-20 03:13:47.922959 | TASK [emit-job-header : Print job information] 2026-05-20 03:13:47.960465 | # Job Information 2026-05-20 03:13:47.960613 | Ansible Version: 2.16.14 2026-05-20 03:13:47.960642 | Job: python-black 2026-05-20 03:13:47.960670 | Pipeline: periodic-daily 2026-05-20 03:13:47.960689 | Executor: 521e9411259a 2026-05-20 03:13:47.960706 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-05-20 03:13:47.960725 | Event ID: b0b3f87e9d4e4c5aa9e466325b1adb49 2026-05-20 03:13:47.964939 | 2026-05-20 03:13:47.965026 | LOOP [emit-job-header : Print node information] 2026-05-20 03:13:48.093461 | debian-bookworm | ok: 2026-05-20 03:13:48.093731 | debian-bookworm | # Node Information 2026-05-20 03:13:48.093776 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-20 03:13:48.093803 | debian-bookworm | Hostname: debian 2026-05-20 03:13:48.093825 | debian-bookworm | Username: zuul 2026-05-20 03:13:48.093847 | debian-bookworm | Distro: Debian 12.14 2026-05-20 03:13:48.093871 | debian-bookworm | Provider: regiocloud-a 2026-05-20 03:13:48.093892 | debian-bookworm | Region: 2026-05-20 03:13:48.093913 | debian-bookworm | Label: debian-bookworm 2026-05-20 03:13:48.093933 | debian-bookworm | Product Name: OpenStack Nova 2026-05-20 03:13:48.093952 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe1e:1269 2026-05-20 03:13:48.120444 | 2026-05-20 03:13:48.122200 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-20 03:13:48.814702 | debian-bookworm -> localhost | changed 2026-05-20 03:13:48.820795 | 2026-05-20 03:13:48.820887 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-20 03:13:50.626941 | debian-bookworm -> localhost | changed 2026-05-20 03:13:50.635589 | 2026-05-20 03:13:50.635685 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-20 03:13:51.155539 | debian-bookworm -> localhost | ok 2026-05-20 03:13:51.161444 | 2026-05-20 03:13:51.161564 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-20 03:13:51.181785 | debian-bookworm | ok 2026-05-20 03:13:51.215804 | debian-bookworm | included: /var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-20 03:13:51.222173 | 2026-05-20 03:13:51.222276 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-20 03:13:52.814548 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-20 03:13:52.814740 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/work/3c35e6bc361e4fa385673cb9d4736724_id_rsa 2026-05-20 03:13:52.814778 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/work/3c35e6bc361e4fa385673cb9d4736724_id_rsa.pub 2026-05-20 03:13:52.814804 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-20 03:13:52.814828 | debian-bookworm -> localhost | SHA256:2o0+sIRvEegFcdxW2/vHE6KnF0sTYjQtXNqvSNUUXt0 zuul-build-sshkey 2026-05-20 03:13:52.814863 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-20 03:13:52.814898 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-20 03:13:52.814920 | debian-bookworm -> localhost | | .o.. .o o. o=| 2026-05-20 03:13:52.814942 | debian-bookworm -> localhost | | ... o Bo.+ E| 2026-05-20 03:13:52.814962 | debian-bookworm -> localhost | | o . o.+o o | 2026-05-20 03:13:52.814981 | debian-bookworm -> localhost | | . o o.o. | 2026-05-20 03:13:52.815001 | debian-bookworm -> localhost | | . o .S ..o..o | 2026-05-20 03:13:52.815027 | debian-bookworm -> localhost | | o +o o. o=o..| 2026-05-20 03:13:52.815048 | debian-bookworm -> localhost | | o.+o .o.o=.o| 2026-05-20 03:13:52.815067 | debian-bookworm -> localhost | | +.. oo ..| 2026-05-20 03:13:52.815087 | debian-bookworm -> localhost | | . .. .. | 2026-05-20 03:13:52.815134 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-20 03:13:52.815192 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.648351 2026-05-20 03:13:52.821351 | 2026-05-20 03:13:52.821473 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-20 03:13:52.855332 | debian-bookworm | ok 2026-05-20 03:13:52.868290 | debian-bookworm | included: /var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-20 03:13:52.903780 | 2026-05-20 03:13:52.903899 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-20 03:13:52.934196 | debian-bookworm | skipping: Conditional result was False 2026-05-20 03:13:52.940127 | 2026-05-20 03:13:52.940223 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-20 03:13:53.699504 | debian-bookworm | changed 2026-05-20 03:13:53.704811 | 2026-05-20 03:13:53.704907 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-20 03:13:53.987342 | debian-bookworm | ok 2026-05-20 03:13:53.993402 | 2026-05-20 03:13:53.993510 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-20 03:13:54.634410 | debian-bookworm | changed 2026-05-20 03:13:54.643813 | 2026-05-20 03:13:54.643925 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-20 03:13:55.379954 | debian-bookworm | changed 2026-05-20 03:13:55.386855 | 2026-05-20 03:13:55.386969 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-20 03:13:55.426888 | debian-bookworm | skipping: Conditional result was False 2026-05-20 03:13:55.433379 | 2026-05-20 03:13:55.433492 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-20 03:13:56.009200 | debian-bookworm -> localhost | changed 2026-05-20 03:13:56.019821 | 2026-05-20 03:13:56.019930 | TASK [add-build-sshkey : Add back temp key] 2026-05-20 03:13:56.459804 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/work/3c35e6bc361e4fa385673cb9d4736724_id_rsa (zuul-build-sshkey) 2026-05-20 03:13:56.460018 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.010158 2026-05-20 03:13:56.467809 | 2026-05-20 03:13:56.467906 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-20 03:13:56.911557 | debian-bookworm | ok 2026-05-20 03:13:56.919227 | 2026-05-20 03:13:56.919369 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-20 03:13:56.952557 | debian-bookworm | skipping: Conditional result was False 2026-05-20 03:13:56.965505 | 2026-05-20 03:13:56.965627 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-20 03:13:57.502299 | debian-bookworm | ok 2026-05-20 03:13:57.511465 | 2026-05-20 03:13:57.511550 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-20 03:13:57.565667 | debian-bookworm | ok 2026-05-20 03:13:57.583331 | 2026-05-20 03:13:57.583426 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-20 03:13:58.085484 | debian-bookworm -> localhost | ok 2026-05-20 03:13:58.090757 | 2026-05-20 03:13:58.090850 | TASK [validate-host : Collect information about the host] 2026-05-20 03:13:59.354317 | debian-bookworm | ok 2026-05-20 03:13:59.369638 | 2026-05-20 03:13:59.369729 | TASK [validate-host : Sanitize hostname] 2026-05-20 03:13:59.494487 | debian-bookworm | ok 2026-05-20 03:13:59.575879 | 2026-05-20 03:13:59.576019 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-20 03:14:00.626632 | debian-bookworm -> localhost | changed 2026-05-20 03:14:00.633190 | 2026-05-20 03:14:00.633289 | TASK [validate-host : Collect information about zuul worker] 2026-05-20 03:14:01.245218 | debian-bookworm | ok 2026-05-20 03:14:01.249871 | 2026-05-20 03:14:01.249959 | TASK [validate-host : Write out all zuul information for each host] 2026-05-20 03:14:02.589006 | debian-bookworm -> localhost | changed 2026-05-20 03:14:02.599515 | 2026-05-20 03:14:02.599639 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-20 03:14:02.928258 | debian-bookworm | ok 2026-05-20 03:14:02.938126 | 2026-05-20 03:14:02.938246 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-20 03:14:03.955814 | debian-bookworm | changed: 2026-05-20 03:14:03.955964 | debian-bookworm | cd+++++++++ src/ 2026-05-20 03:14:03.955993 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-20 03:14:03.956012 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-20 03:14:03.956030 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-05-20 03:14:03.956047 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/work/logs" 2026-05-20 03:14:30.828748 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/work/artifacts" 2026-05-20 03:14:31.363948 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3c35e6bc361e4fa385673cb9d4736724/work/docs" 2026-05-20 03:14:31.395500 | 2026-05-20 03:14:31.395632 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-20 03:14:32.557671 | debian-bookworm | changed: 2026-05-20 03:14:32.557859 | debian-bookworm | .d..t...... ./ 2026-05-20 03:14:32.557887 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-20 03:14:32.557912 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-20 03:14:32.557934 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-20 03:14:32.557966 | debian-bookworm | changed: All items complete 2026-05-20 03:14:32.557988 | 2026-05-20 03:14:33.714405 | debian-bookworm | changed: .d..t...... ./ 2026-05-20 03:14:34.873084 | debian-bookworm | changed: .d..t...... ./ 2026-05-20 03:14:34.901496 | 2026-05-20 03:14:34.901635 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-20 03:14:34.991585 | debian-bookworm | skipping: Conditional result was False 2026-05-20 03:14:35.012112 | debian-bookworm | skipping: Conditional result was False 2026-05-20 03:14:35.031870 | 2026-05-20 03:14:35.031973 | PLAY RECAP 2026-05-20 03:14:35.032026 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-20 03:14:35.032050 | 2026-05-20 03:14:35.256923 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-20 03:14:35.257811 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-20 03:14:36.369310 | 2026-05-20 03:14:36.369463 | PLAY [Base post] 2026-05-20 03:14:36.402714 | 2026-05-20 03:14:36.402899 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-20 03:14:37.060786 | debian-bookworm | changed 2026-05-20 03:14:37.074957 | 2026-05-20 03:14:37.075060 | PLAY RECAP 2026-05-20 03:14:37.075121 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-20 03:14:37.075173 | 2026-05-20 03:14:37.255380 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-20 03:14:37.256003 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-20 03:14:38.419558 | 2026-05-20 03:14:38.419711 | PLAY [Base post-logs] 2026-05-20 03:14:38.444410 | 2026-05-20 03:14:38.444553 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-20 03:14:39.143793 | localhost | changed 2026-05-20 03:14:39.165606 | 2026-05-20 03:14:39.165764 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-20 03:14:39.190714 | localhost | ok 2026-05-20 03:14:39.193902 | 2026-05-20 03:14:39.193993 | TASK [Set zuul-log-path fact] 2026-05-20 03:14:39.208609 | localhost | ok 2026-05-20 03:14:39.216460 | 2026-05-20 03:14:39.216552 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-20 03:14:39.250945 | localhost | ok 2026-05-20 03:14:39.253750 | 2026-05-20 03:14:39.253838 | TASK [upload-logs : Create log directories] 2026-05-20 03:14:39.873149 | localhost | changed 2026-05-20 03:14:39.876491 | 2026-05-20 03:14:39.876588 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-20 03:14:40.944355 | localhost -> localhost | ok: Runtime: 0:00:00.021188 2026-05-20 03:14:40.957223 | 2026-05-20 03:14:40.957540 | TASK [upload-logs : Upload logs to log server] 2026-05-20 03:14:41.724180 | localhost | Output suppressed because no_log was given 2026-05-20 03:14:41.725778 | 2026-05-20 03:14:41.725861 | LOOP [upload-logs : Compress console log and json output] 2026-05-20 03:14:41.811297 | localhost | skipping: Conditional result was False 2026-05-20 03:14:41.820911 | localhost | skipping: Conditional result was False 2026-05-20 03:14:41.825893 | 2026-05-20 03:14:41.825991 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-20 03:14:41.897957 | localhost | skipping: Conditional result was False 2026-05-20 03:14:41.898318 | 2026-05-20 03:14:41.906481 | localhost | skipping: Conditional result was False 2026-05-20 03:14:41.917010 | 2026-05-20 03:14:41.917611 | LOOP [upload-logs : Upload console log and json output]