2025-08-23 18:14:31.513817 | Job console starting 2025-08-23 18:14:31.525232 | Updating git repos 2025-08-23 18:14:31.542127 | Cloning repos into workspace 2025-08-23 18:14:31.581099 | Restoring repo states 2025-08-23 18:14:31.585355 | Merging changes 2025-08-23 18:14:32.037991 | Checking out repos 2025-08-23 18:14:32.067321 | Preparing playbooks 2025-08-23 18:14:32.617419 | Running Ansible setup 2025-08-23 18:14:37.251854 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-23 18:14:38.084499 | 2025-08-23 18:14:38.084650 | PLAY [Base pre] 2025-08-23 18:14:38.100228 | 2025-08-23 18:14:38.100359 | TASK [Setup log path fact] 2025-08-23 18:14:38.129615 | debian-bookworm | ok 2025-08-23 18:14:38.144158 | 2025-08-23 18:14:38.144284 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-23 18:14:38.186381 | debian-bookworm | ok 2025-08-23 18:14:38.197082 | 2025-08-23 18:14:38.197202 | TASK [emit-job-header : Print job information] 2025-08-23 18:14:38.237190 | # Job Information 2025-08-23 18:14:38.237375 | Ansible Version: 2.16.14 2025-08-23 18:14:38.237412 | Job: python-black 2025-08-23 18:14:38.237481 | Pipeline: check 2025-08-23 18:14:38.237509 | Executor: 521e9411259a 2025-08-23 18:14:38.237531 | Triggered by: https://github.com/osism/testbed/pull/2739 2025-08-23 18:14:38.237553 | Event ID: e900a9d0-804c-11f0-850e-e44566ddd9de 2025-08-23 18:14:38.242389 | 2025-08-23 18:14:38.242521 | LOOP [emit-job-header : Print node information] 2025-08-23 18:14:38.384397 | debian-bookworm | ok: 2025-08-23 18:14:38.384854 | debian-bookworm | # Node Information 2025-08-23 18:14:38.384916 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-23 18:14:38.384948 | debian-bookworm | Hostname: debian 2025-08-23 18:14:38.384977 | debian-bookworm | Username: zuul 2025-08-23 18:14:38.385004 | debian-bookworm | Distro: Debian 12.11 2025-08-23 18:14:38.385036 | debian-bookworm | Provider: regiocloud-a 2025-08-23 18:14:38.385064 | debian-bookworm | Region: 2025-08-23 18:14:38.385092 | debian-bookworm | Label: debian-bookworm 2025-08-23 18:14:38.385118 | debian-bookworm | Product Name: OpenStack Nova 2025-08-23 18:14:38.385144 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fed4:712f 2025-08-23 18:14:38.415541 | 2025-08-23 18:14:38.415687 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-23 18:14:38.927106 | debian-bookworm -> localhost | changed 2025-08-23 18:14:38.934536 | 2025-08-23 18:14:38.934655 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-23 18:14:40.032949 | debian-bookworm -> localhost | changed 2025-08-23 18:14:40.054318 | 2025-08-23 18:14:40.054570 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-23 18:14:40.333018 | debian-bookworm -> localhost | ok 2025-08-23 18:14:40.339511 | 2025-08-23 18:14:40.339634 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-23 18:14:40.377792 | debian-bookworm | ok 2025-08-23 18:14:40.413747 | debian-bookworm | included: /var/lib/zuul/builds/d5167641175e4c5e875826f748468823/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-23 18:14:40.426026 | 2025-08-23 18:14:40.426165 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-23 18:14:41.884219 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-23 18:14:41.884485 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d5167641175e4c5e875826f748468823/work/d5167641175e4c5e875826f748468823_id_rsa 2025-08-23 18:14:41.884528 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d5167641175e4c5e875826f748468823/work/d5167641175e4c5e875826f748468823_id_rsa.pub 2025-08-23 18:14:41.884555 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-23 18:14:41.884579 | debian-bookworm -> localhost | SHA256:MGi8voJn3VmWCjQ9v3hgIZqLLFE+AiQi/8AGLSDnNL0 zuul-build-sshkey 2025-08-23 18:14:41.884600 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-23 18:14:41.884633 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-23 18:14:41.884655 | debian-bookworm -> localhost | |+.+. | 2025-08-23 18:14:41.884676 | debian-bookworm -> localhost | |B=.o.. | 2025-08-23 18:14:41.884696 | debian-bookworm -> localhost | |==. +oo | 2025-08-23 18:14:41.884714 | debian-bookworm -> localhost | |. *.E.+o | 2025-08-23 18:14:41.884733 | debian-bookworm -> localhost | |.+ *.o +S. | 2025-08-23 18:14:41.884759 | debian-bookworm -> localhost | |o =.o o = | 2025-08-23 18:14:41.884781 | debian-bookworm -> localhost | |.= +.+ B . | 2025-08-23 18:14:41.884802 | debian-bookworm -> localhost | |+.= ..= o | 2025-08-23 18:14:41.884824 | debian-bookworm -> localhost | |.o .. . | 2025-08-23 18:14:41.884845 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-23 18:14:41.884902 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.994637 2025-08-23 18:14:41.891514 | 2025-08-23 18:14:41.891638 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-23 18:14:41.920596 | debian-bookworm | ok 2025-08-23 18:14:41.931519 | debian-bookworm | included: /var/lib/zuul/builds/d5167641175e4c5e875826f748468823/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-23 18:14:41.940960 | 2025-08-23 18:14:41.941081 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-23 18:14:41.965198 | debian-bookworm | skipping: Conditional result was False 2025-08-23 18:14:41.971340 | 2025-08-23 18:14:41.971492 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-23 18:14:42.606623 | debian-bookworm | changed 2025-08-23 18:14:42.612256 | 2025-08-23 18:14:42.612357 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-23 18:14:42.930188 | debian-bookworm | ok 2025-08-23 18:14:42.938064 | 2025-08-23 18:14:42.938185 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-23 18:14:43.624198 | debian-bookworm | changed 2025-08-23 18:14:43.632943 | 2025-08-23 18:14:43.633070 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-23 18:14:44.299607 | debian-bookworm | changed 2025-08-23 18:14:44.306900 | 2025-08-23 18:14:44.307031 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-23 18:14:44.341162 | debian-bookworm | skipping: Conditional result was False 2025-08-23 18:14:44.351216 | 2025-08-23 18:14:44.351346 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-23 18:14:44.790154 | debian-bookworm -> localhost | changed 2025-08-23 18:14:44.801551 | 2025-08-23 18:14:44.801669 | TASK [add-build-sshkey : Add back temp key] 2025-08-23 18:14:45.123044 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d5167641175e4c5e875826f748468823/work/d5167641175e4c5e875826f748468823_id_rsa (zuul-build-sshkey) 2025-08-23 18:14:45.123293 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011896 2025-08-23 18:14:45.131705 | 2025-08-23 18:14:45.131820 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-23 18:14:45.536720 | debian-bookworm | ok 2025-08-23 18:14:45.542178 | 2025-08-23 18:14:45.542295 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-23 18:14:45.576026 | debian-bookworm | skipping: Conditional result was False 2025-08-23 18:14:45.589120 | 2025-08-23 18:14:45.589244 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-23 18:14:46.051657 | debian-bookworm | ok 2025-08-23 18:14:46.066599 | 2025-08-23 18:14:46.066747 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-23 18:14:46.110232 | debian-bookworm | ok 2025-08-23 18:14:46.117611 | 2025-08-23 18:14:46.117758 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-23 18:14:46.483328 | debian-bookworm -> localhost | ok 2025-08-23 18:14:46.490547 | 2025-08-23 18:14:46.490692 | TASK [validate-host : Collect information about the host] 2025-08-23 18:14:47.696356 | debian-bookworm | ok 2025-08-23 18:14:47.725751 | 2025-08-23 18:14:47.726473 | TASK [validate-host : Sanitize hostname] 2025-08-23 18:14:47.844618 | debian-bookworm | ok 2025-08-23 18:14:47.895794 | 2025-08-23 18:14:47.895937 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-23 18:14:48.498173 | debian-bookworm -> localhost | changed 2025-08-23 18:14:48.504274 | 2025-08-23 18:14:48.504397 | TASK [validate-host : Collect information about zuul worker] 2025-08-23 18:14:48.910931 | debian-bookworm | ok 2025-08-23 18:14:48.916684 | 2025-08-23 18:14:48.916811 | TASK [validate-host : Write out all zuul information for each host] 2025-08-23 18:14:49.447700 | debian-bookworm -> localhost | changed 2025-08-23 18:14:49.466899 | 2025-08-23 18:14:49.467060 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-23 18:14:49.788218 | debian-bookworm | ok 2025-08-23 18:14:49.796940 | 2025-08-23 18:14:49.797069 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-23 18:14:50.974993 | debian-bookworm | changed: 2025-08-23 18:14:50.975288 | debian-bookworm | cd+++++++++ src/ 2025-08-23 18:14:50.975345 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-23 18:14:50.975384 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-23 18:14:50.975420 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-08-23 18:14:50.975505 | debian-bookworm | localhost | ok: Runtime: 0:00:00.007184 2025-08-23 18:15:16.284559 | 2025-08-23 18:15:16.284722 | TASK [upload-logs : Upload logs to log server] 2025-08-23 18:15:16.867727 | localhost | Output suppressed because no_log was given 2025-08-23 18:15:16.871932 | 2025-08-23 18:15:16.872093 | LOOP [upload-logs : Compress console log and json output] 2025-08-23 18:15:16.933176 | localhost | skipping: Conditional result was False 2025-08-23 18:15:16.938303 | localhost | skipping: Conditional result was False 2025-08-23 18:15:16.945938 | 2025-08-23 18:15:16.946193 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-23 18:15:16.991750 | localhost | skipping: Conditional result was False 2025-08-23 18:15:16.992072 | 2025-08-23 18:15:16.996582 | localhost | skipping: Conditional result was False 2025-08-23 18:15:17.008036 | 2025-08-23 18:15:17.008164 | LOOP [upload-logs : Upload console log and json output]