2026-09-11 03:15:49.858921 | Job console starting 2026-09-11 03:15:49.870750 | Updating git repos 2026-09-11 03:15:49.908145 | Cloning repos into workspace 2026-09-11 03:15:49.933772 | Restoring repo states 2026-09-11 03:15:49.939124 | Merging changes 2026-09-11 03:15:49.939140 | Checking out repos 2026-09-11 03:15:49.970012 | Preparing playbooks 2026-09-11 03:15:50.779313 | Running Ansible setup 2026-09-11 03:15:55.328333 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-11 03:15:56.122352 | 2026-09-11 03:15:56.122498 | PLAY [Base pre] 2026-09-11 03:15:56.140106 | 2026-09-11 03:15:56.140229 | TASK [Setup log path fact] 2026-09-11 03:15:56.169474 | debian-bookworm | ok 2026-09-11 03:15:56.186478 | 2026-09-11 03:15:56.186602 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-11 03:15:56.222848 | debian-bookworm | ok 2026-09-11 03:15:56.239737 | 2026-09-11 03:15:56.239876 | TASK [emit-job-header : Print job information] 2026-09-11 03:15:56.281618 | # Job Information 2026-09-11 03:15:56.281837 | Ansible Version: 2.16.14 2026-09-11 03:15:56.281939 | Job: python-black 2026-09-11 03:15:56.281989 | Pipeline: periodic-daily 2026-09-11 03:15:56.282023 | Executor: 521e9411259a 2026-09-11 03:15:56.282054 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2026-09-11 03:15:56.282087 | Event ID: d6dc6e90ec4d493990f1ceb185bbd451 2026-09-11 03:15:56.289077 | 2026-09-11 03:15:56.289201 | LOOP [emit-job-header : Print node information] 2026-09-11 03:15:56.393470 | debian-bookworm | ok: 2026-09-11 03:15:56.393747 | debian-bookworm | # Node Information 2026-09-11 03:15:56.393784 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-11 03:15:56.393811 | debian-bookworm | Hostname: debian 2026-09-11 03:15:56.393832 | debian-bookworm | Username: zuul 2026-09-11 03:15:56.393853 | debian-bookworm | Distro: Debian 12.15 2026-09-11 03:15:56.393923 | debian-bookworm | Provider: regiocloud-a 2026-09-11 03:15:56.393950 | debian-bookworm | Region: 2026-09-11 03:15:56.393972 | debian-bookworm | Label: debian-bookworm 2026-09-11 03:15:56.393993 | debian-bookworm | Product Name: OpenStack Nova 2026-09-11 03:15:56.394013 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee6:b3d5 2026-09-11 03:15:56.421341 | 2026-09-11 03:15:56.421488 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-11 03:15:56.875459 | debian-bookworm -> localhost | changed 2026-09-11 03:15:56.884365 | 2026-09-11 03:15:56.884529 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-11 03:15:57.992880 | debian-bookworm -> localhost | changed 2026-09-11 03:15:58.014437 | 2026-09-11 03:15:58.014578 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-11 03:15:58.329024 | debian-bookworm -> localhost | ok 2026-09-11 03:15:58.342733 | 2026-09-11 03:15:58.342942 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-11 03:15:58.377843 | debian-bookworm | ok 2026-09-11 03:15:58.396344 | debian-bookworm | included: /var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-11 03:15:58.402967 | 2026-09-11 03:15:58.403069 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-11 03:15:59.366113 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-11 03:15:59.366397 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/work/1d2d523387ee42638605f703345dfc09_id_rsa 2026-09-11 03:15:59.366437 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/work/1d2d523387ee42638605f703345dfc09_id_rsa.pub 2026-09-11 03:15:59.366464 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-11 03:15:59.366488 | debian-bookworm -> localhost | SHA256:n06BBNlmtyx4msMMFEPAyRrsVq6g9xaUjYjfY0uWMWc zuul-build-sshkey 2026-09-11 03:15:59.366512 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-11 03:15:59.366545 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-11 03:15:59.366567 | debian-bookworm -> localhost | |.o.++..o | 2026-09-11 03:15:59.366590 | debian-bookworm -> localhost | |..+.....+ . | 2026-09-11 03:15:59.366610 | debian-bookworm -> localhost | |.+oo + +.o . | 2026-09-11 03:15:59.366631 | debian-bookworm -> localhost | |+o..B E.o.o | 2026-09-11 03:15:59.366651 | debian-bookworm -> localhost | |+..o X +S.. | 2026-09-11 03:15:59.366677 | debian-bookworm -> localhost | |..o O * . o | 2026-09-11 03:15:59.366698 | debian-bookworm -> localhost | | . = + . + | 2026-09-11 03:15:59.366718 | debian-bookworm -> localhost | | + o | 2026-09-11 03:15:59.366739 | debian-bookworm -> localhost | | . . | 2026-09-11 03:15:59.366758 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-11 03:15:59.366815 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.409580 2026-09-11 03:15:59.373711 | 2026-09-11 03:15:59.373824 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-11 03:15:59.403107 | debian-bookworm | ok 2026-09-11 03:15:59.413697 | debian-bookworm | included: /var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-11 03:15:59.422017 | 2026-09-11 03:15:59.422116 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-11 03:15:59.439826 | debian-bookworm | skipping: Conditional result was False 2026-09-11 03:15:59.447154 | 2026-09-11 03:15:59.447282 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-11 03:16:00.156588 | debian-bookworm | changed 2026-09-11 03:16:00.163939 | 2026-09-11 03:16:00.164061 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-11 03:16:00.467139 | debian-bookworm | ok 2026-09-11 03:16:00.473196 | 2026-09-11 03:16:00.473310 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-11 03:16:01.214825 | debian-bookworm | changed 2026-09-11 03:16:01.221982 | 2026-09-11 03:16:01.222098 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-11 03:16:01.881403 | debian-bookworm | changed 2026-09-11 03:16:01.890206 | 2026-09-11 03:16:01.890323 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-11 03:16:01.915962 | debian-bookworm | skipping: Conditional result was False 2026-09-11 03:16:01.930162 | 2026-09-11 03:16:01.930314 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-11 03:16:02.401870 | debian-bookworm -> localhost | changed 2026-09-11 03:16:02.418388 | 2026-09-11 03:16:02.418520 | TASK [add-build-sshkey : Add back temp key] 2026-09-11 03:16:02.783546 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/work/1d2d523387ee42638605f703345dfc09_id_rsa (zuul-build-sshkey) 2026-09-11 03:16:02.783833 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.017819 2026-09-11 03:16:02.793057 | 2026-09-11 03:16:02.793172 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-11 03:16:03.238390 | debian-bookworm | ok 2026-09-11 03:16:03.247333 | 2026-09-11 03:16:03.247469 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-11 03:16:03.273131 | debian-bookworm | skipping: Conditional result was False 2026-09-11 03:16:03.292031 | 2026-09-11 03:16:03.292304 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-11 03:16:03.774678 | debian-bookworm | ok 2026-09-11 03:16:03.799754 | 2026-09-11 03:16:03.800004 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-11 03:16:03.842308 | debian-bookworm | ok 2026-09-11 03:16:03.848274 | 2026-09-11 03:16:03.848392 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-11 03:16:04.138730 | debian-bookworm -> localhost | ok 2026-09-11 03:16:04.146823 | 2026-09-11 03:16:04.146951 | TASK [validate-host : Collect information about the host] 2026-09-11 03:16:05.385108 | debian-bookworm | ok 2026-09-11 03:16:05.401184 | 2026-09-11 03:16:05.401319 | TASK [validate-host : Sanitize hostname] 2026-09-11 03:16:05.562010 | debian-bookworm | ok 2026-09-11 03:16:05.566669 | 2026-09-11 03:16:05.566774 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-11 03:16:06.323045 | debian-bookworm -> localhost | changed 2026-09-11 03:16:06.336694 | 2026-09-11 03:16:06.337002 | TASK [validate-host : Collect information about zuul worker] 2026-09-11 03:16:06.776735 | debian-bookworm | ok 2026-09-11 03:16:06.784374 | 2026-09-11 03:16:06.784518 | TASK [validate-host : Write out all zuul information for each host] 2026-09-11 03:16:07.339835 | debian-bookworm -> localhost | changed 2026-09-11 03:16:07.354687 | 2026-09-11 03:16:07.354802 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-11 03:16:07.652976 | debian-bookworm | ok 2026-09-11 03:16:07.663594 | 2026-09-11 03:16:07.663728 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-11 03:16:08.487310 | debian-bookworm | changed: 2026-09-11 03:16:08.487555 | debian-bookworm | cd+++++++++ src/ 2026-09-11 03:16:08.487594 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-11 03:16:08.487625 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-11 03:16:08.487661 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2026-09-11 03:16:08.487695 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/work/logs" 2026-09-11 03:17:12.559146 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/work/artifacts" 2026-09-11 03:17:12.881736 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/1d2d523387ee42638605f703345dfc09/work/docs" 2026-09-11 03:17:12.900867 | 2026-09-11 03:17:12.900969 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-11 03:17:13.934343 | debian-bookworm | changed: 2026-09-11 03:17:13.934526 | debian-bookworm | .d..t...... ./ 2026-09-11 03:17:13.934562 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-11 03:17:13.934632 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-11 03:17:13.934659 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-11 03:17:13.934680 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-11 03:17:13.934701 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-11 03:17:13.934738 | debian-bookworm | changed: All items complete 2026-09-11 03:17:13.934764 | 2026-09-11 03:17:14.614472 | debian-bookworm | changed: .d..t...... ./ 2026-09-11 03:17:15.276421 | debian-bookworm | changed: .d..t...... ./ 2026-09-11 03:17:15.296388 | 2026-09-11 03:17:15.296487 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-11 03:17:15.365506 | debian-bookworm | skipping: Conditional result was False 2026-09-11 03:17:15.369094 | debian-bookworm | skipping: Conditional result was False 2026-09-11 03:17:15.383515 | 2026-09-11 03:17:15.383581 | PLAY RECAP 2026-09-11 03:17:15.383632 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-11 03:17:15.383657 | 2026-09-11 03:17:15.587590 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-11 03:17:15.588330 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-11 03:17:16.496782 | 2026-09-11 03:17:16.496932 | PLAY [Base post] 2026-09-11 03:17:16.514301 | 2026-09-11 03:17:16.514425 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-11 03:17:17.177445 | debian-bookworm | changed 2026-09-11 03:17:17.190684 | 2026-09-11 03:17:17.190797 | PLAY RECAP 2026-09-11 03:17:17.190885 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-11 03:17:17.190950 | 2026-09-11 03:17:17.464076 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-11 03:17:17.464708 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-11 03:17:18.761674 | 2026-09-11 03:17:18.761788 | PLAY [Base post-logs] 2026-09-11 03:17:18.772480 | 2026-09-11 03:17:18.772577 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-11 03:17:19.503829 | localhost | changed 2026-09-11 03:17:19.517319 | 2026-09-11 03:17:19.517439 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-11 03:17:19.568512 | localhost | ok 2026-09-11 03:17:19.571821 | 2026-09-11 03:17:19.571938 | TASK [Set zuul-log-path fact] 2026-09-11 03:17:19.606466 | localhost | ok 2026-09-11 03:17:19.615590 | 2026-09-11 03:17:19.615692 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-11 03:17:19.670506 | localhost | ok 2026-09-11 03:17:19.673814 | 2026-09-11 03:17:19.673914 | TASK [upload-logs : Create log directories] 2026-09-11 03:17:20.305060 | localhost | changed 2026-09-11 03:17:20.308636 | 2026-09-11 03:17:20.308720 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-11 03:17:20.720069 | localhost -> localhost | ok: Runtime: 0:00:00.002683 2026-09-11 03:17:20.723684 | 2026-09-11 03:17:20.723779 | TASK [upload-logs : Upload logs to log server] 2026-09-11 03:17:21.185693 | localhost | Output suppressed because no_log was given 2026-09-11 03:17:21.187835 | 2026-09-11 03:17:21.187987 | LOOP [upload-logs : Compress console log and json output] 2026-09-11 03:17:21.229001 | localhost | skipping: Conditional result was False 2026-09-11 03:17:21.234697 | localhost | skipping: Conditional result was False 2026-09-11 03:17:21.244204 | 2026-09-11 03:17:21.244289 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-11 03:17:21.293251 | localhost | skipping: Conditional result was False 2026-09-11 03:17:21.293605 | 2026-09-11 03:17:21.298017 | localhost | skipping: Conditional result was False 2026-09-11 03:17:21.310346 | 2026-09-11 03:17:21.310447 | LOOP [upload-logs : Upload console log and json output]