2025-10-28 03:01:27.517016 | Job console starting 2025-10-28 03:01:27.532855 | Updating git repos 2025-10-28 03:01:27.627186 | Cloning repos into workspace 2025-10-28 03:01:27.749809 | Restoring repo states 2025-10-28 03:01:27.763412 | Merging changes 2025-10-28 03:01:27.763428 | Checking out repos 2025-10-28 03:01:27.883970 | Preparing playbooks 2025-10-28 03:01:29.320427 | Running Ansible setup 2025-10-28 03:01:35.951378 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-28 03:01:37.183643 | 2025-10-28 03:01:37.183757 | PLAY [Base pre] 2025-10-28 03:01:37.207858 | 2025-10-28 03:01:37.207958 | TASK [Setup log path fact] 2025-10-28 03:01:37.234992 | debian-bookworm | ok 2025-10-28 03:01:37.252655 | 2025-10-28 03:01:37.252766 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-28 03:01:37.308174 | debian-bookworm | ok 2025-10-28 03:01:37.316025 | 2025-10-28 03:01:37.316137 | TASK [emit-job-header : Print job information] 2025-10-28 03:01:37.356220 | # Job Information 2025-10-28 03:01:37.356352 | Ansible Version: 2.16.14 2025-10-28 03:01:37.356381 | Job: python-black 2025-10-28 03:01:37.356407 | Pipeline: periodic-daily 2025-10-28 03:01:37.356426 | Executor: 521e9411259a 2025-10-28 03:01:37.356444 | Triggered by: https://github.com/osism/cinder-driver-dm-clone 2025-10-28 03:01:37.356462 | Event ID: 0d4a3265fdf245058aa362a7c1a7e226 2025-10-28 03:01:37.360997 | 2025-10-28 03:01:37.361094 | LOOP [emit-job-header : Print node information] 2025-10-28 03:01:37.549383 | debian-bookworm | ok: 2025-10-28 03:01:37.550309 | debian-bookworm | # Node Information 2025-10-28 03:01:37.550386 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-28 03:01:37.550411 | debian-bookworm | Hostname: debian 2025-10-28 03:01:37.550438 | debian-bookworm | Username: zuul 2025-10-28 03:01:37.550464 | debian-bookworm | Distro: Debian 12.12 2025-10-28 03:01:37.550484 | debian-bookworm | Provider: regiocloud-a 2025-10-28 03:01:37.550502 | debian-bookworm | Region: 2025-10-28 03:01:37.550519 | debian-bookworm | Label: debian-bookworm 2025-10-28 03:01:37.550535 | debian-bookworm | Product Name: OpenStack Nova 2025-10-28 03:01:37.550551 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe0d:c2bc 2025-10-28 03:01:37.569288 | 2025-10-28 03:01:37.569379 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-28 03:01:38.158894 | debian-bookworm -> localhost | changed 2025-10-28 03:01:38.166618 | 2025-10-28 03:01:38.166707 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-28 03:01:39.517895 | debian-bookworm -> localhost | changed 2025-10-28 03:01:39.526498 | 2025-10-28 03:01:39.526585 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-28 03:01:39.989070 | debian-bookworm -> localhost | ok 2025-10-28 03:01:39.993890 | 2025-10-28 03:01:39.993977 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-28 03:01:40.031777 | debian-bookworm | ok 2025-10-28 03:01:40.059342 | debian-bookworm | included: /var/lib/zuul/builds/defd5eabce854aac933c61716ab11181/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-28 03:01:40.080488 | 2025-10-28 03:01:40.080584 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-28 03:01:42.677008 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-28 03:01:42.677220 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/defd5eabce854aac933c61716ab11181/work/defd5eabce854aac933c61716ab11181_id_rsa 2025-10-28 03:01:42.677258 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/defd5eabce854aac933c61716ab11181/work/defd5eabce854aac933c61716ab11181_id_rsa.pub 2025-10-28 03:01:42.677286 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-28 03:01:42.677311 | debian-bookworm -> localhost | SHA256:QVdCOBP6RBb+lpQWwkf8jAyB0dxihmBUfm6Poy2pSQQ zuul-build-sshkey 2025-10-28 03:01:42.677334 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-28 03:01:42.677430 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-28 03:01:42.677474 | debian-bookworm -> localhost | | .+ooBOX*.. | 2025-10-28 03:01:42.677553 | debian-bookworm -> localhost | | . .o*%o++ | 2025-10-28 03:01:42.677598 | debian-bookworm -> localhost | | E oo=B++ | 2025-10-28 03:01:42.677623 | debian-bookworm -> localhost | | . = =o.o | 2025-10-28 03:01:42.677644 | debian-bookworm -> localhost | | . S + | 2025-10-28 03:01:42.677671 | debian-bookworm -> localhost | | . . + | 2025-10-28 03:01:42.677691 | debian-bookworm -> localhost | | . .o . | 2025-10-28 03:01:42.677711 | debian-bookworm -> localhost | | . .oo . | 2025-10-28 03:01:42.677732 | debian-bookworm -> localhost | | o.... | 2025-10-28 03:01:42.677752 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-28 03:01:42.677803 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.629543 2025-10-28 03:01:42.684707 | 2025-10-28 03:01:42.684861 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-28 03:01:42.725360 | debian-bookworm | ok 2025-10-28 03:01:42.748626 | debian-bookworm | included: /var/lib/zuul/builds/defd5eabce854aac933c61716ab11181/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-28 03:01:42.786710 | 2025-10-28 03:01:42.786821 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-28 03:01:42.834149 | debian-bookworm | skipping: Conditional result was False 2025-10-28 03:01:42.840132 | 2025-10-28 03:01:42.840244 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-28 03:01:43.840213 | debian-bookworm | changed 2025-10-28 03:01:43.845235 | 2025-10-28 03:01:43.845321 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-28 03:01:44.198912 | debian-bookworm | ok 2025-10-28 03:01:44.203342 | 2025-10-28 03:01:44.203422 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-28 03:01:45.002333 | debian-bookworm | changed 2025-10-28 03:01:45.009835 | 2025-10-28 03:01:45.009927 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-28 03:01:45.905220 | debian-bookworm | changed 2025-10-28 03:01:45.909583 | 2025-10-28 03:01:45.909663 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-28 03:01:45.943205 | debian-bookworm | skipping: Conditional result was False 2025-10-28 03:01:45.948199 | 2025-10-28 03:01:45.948281 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-28 03:01:46.693286 | debian-bookworm -> localhost | changed 2025-10-28 03:01:46.702892 | 2025-10-28 03:01:46.702984 | TASK [add-build-sshkey : Add back temp key] 2025-10-28 03:01:47.070088 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/defd5eabce854aac933c61716ab11181/work/defd5eabce854aac933c61716ab11181_id_rsa (zuul-build-sshkey) 2025-10-28 03:01:47.070267 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018227 2025-10-28 03:01:47.076469 | 2025-10-28 03:01:47.076548 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-28 03:01:47.547924 | debian-bookworm | ok 2025-10-28 03:01:47.552505 | 2025-10-28 03:01:47.552582 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-28 03:01:47.599488 | debian-bookworm | skipping: Conditional result was False 2025-10-28 03:01:47.609287 | 2025-10-28 03:01:47.609378 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-28 03:01:48.193052 | debian-bookworm | ok 2025-10-28 03:01:48.226690 | 2025-10-28 03:01:48.226789 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-28 03:01:48.277543 | debian-bookworm | ok 2025-10-28 03:01:48.281648 | 2025-10-28 03:01:48.281738 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-28 03:01:48.795151 | debian-bookworm -> localhost | ok 2025-10-28 03:01:48.800235 | 2025-10-28 03:01:48.800312 | TASK [validate-host : Collect information about the host] 2025-10-28 03:01:50.370156 | debian-bookworm | ok 2025-10-28 03:01:50.396309 | 2025-10-28 03:01:50.396423 | TASK [validate-host : Sanitize hostname] 2025-10-28 03:01:50.490271 | debian-bookworm | ok 2025-10-28 03:01:50.547712 | 2025-10-28 03:01:50.547808 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-28 03:01:51.942341 | debian-bookworm -> localhost | changed 2025-10-28 03:01:51.946823 | 2025-10-28 03:01:51.947174 | TASK [validate-host : Collect information about zuul worker] 2025-10-28 03:01:52.573162 | debian-bookworm | ok 2025-10-28 03:01:52.582291 | 2025-10-28 03:01:52.582381 | TASK [validate-host : Write out all zuul information for each host] 2025-10-28 03:01:53.621416 | debian-bookworm -> localhost | changed 2025-10-28 03:01:53.628873 | 2025-10-28 03:01:53.628955 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-28 03:01:53.897740 | debian-bookworm | ok 2025-10-28 03:01:53.902078 | 2025-10-28 03:01:53.902153 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-28 03:01:54.877927 | debian-bookworm | changed: 2025-10-28 03:01:54.878121 | debian-bookworm | cd+++++++++ src/ 2025-10-28 03:01:54.878163 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-28 03:01:54.878192 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-28 03:01:54.878218 | debian-bookworm | cd+++++++++ src/github.com/osism/cinder-driver-dm-clone/ 2025-10-28 03:01:54.878244 | debian-bookworm | localhost | ok: Runtime: 0:00:00.039890 2025-10-28 03:02:24.614279 | 2025-10-28 03:02:24.614357 | TASK [upload-logs : Upload logs to log server] 2025-10-28 03:02:25.475554 | localhost | Output suppressed because no_log was given 2025-10-28 03:02:25.477234 | 2025-10-28 03:02:25.477322 | LOOP [upload-logs : Compress console log and json output] 2025-10-28 03:02:25.551955 | localhost | skipping: Conditional result was False 2025-10-28 03:02:25.564759 | localhost | skipping: Conditional result was False 2025-10-28 03:02:25.574600 | 2025-10-28 03:02:25.574700 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-28 03:02:25.644053 | localhost | skipping: Conditional result was False 2025-10-28 03:02:25.644296 | 2025-10-28 03:02:25.659110 | localhost | skipping: Conditional result was False 2025-10-28 03:02:25.671684 | 2025-10-28 03:02:25.671805 | LOOP [upload-logs : Upload console log and json output]