2025-12-09 18:24:46.690356 | Job console starting 2025-12-09 18:24:46.721088 | Updating git repos 2025-12-09 18:24:46.759993 | Cloning repos into workspace 2025-12-09 18:24:46.825470 | Restoring repo states 2025-12-09 18:24:46.837899 | Merging changes 2025-12-09 18:24:47.440837 | Checking out repos 2025-12-09 18:24:47.494924 | Preparing playbooks 2025-12-09 18:24:48.409342 | Running Ansible setup 2025-12-09 18:24:53.120226 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-09 18:24:53.961208 | 2025-12-09 18:24:53.961392 | PLAY [Base pre] 2025-12-09 18:24:53.979142 | 2025-12-09 18:24:53.979332 | TASK [Setup log path fact] 2025-12-09 18:24:54.021747 | debian-bookworm | ok 2025-12-09 18:24:54.058278 | 2025-12-09 18:24:54.058469 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-09 18:24:54.089318 | debian-bookworm | ok 2025-12-09 18:24:54.100913 | 2025-12-09 18:24:54.101057 | TASK [emit-job-header : Print job information] 2025-12-09 18:24:54.149672 | # Job Information 2025-12-09 18:24:54.149874 | Ansible Version: 2.16.14 2025-12-09 18:24:54.149910 | Job: python-black 2025-12-09 18:24:54.149942 | Pipeline: check 2025-12-09 18:24:54.149965 | Executor: 521e9411259a 2025-12-09 18:24:54.149986 | Triggered by: https://github.com/osism/testbed/pull/2804 2025-12-09 18:24:54.150009 | Event ID: 521f0f90-d52c-11f0-9713-4d1f9be8f222 2025-12-09 18:24:54.155283 | 2025-12-09 18:24:54.155428 | LOOP [emit-job-header : Print node information] 2025-12-09 18:24:54.285812 | debian-bookworm | ok: 2025-12-09 18:24:54.286079 | debian-bookworm | # Node Information 2025-12-09 18:24:54.286115 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-09 18:24:54.286140 | debian-bookworm | Hostname: debian 2025-12-09 18:24:54.286161 | debian-bookworm | Username: zuul 2025-12-09 18:24:54.286224 | debian-bookworm | Distro: Debian 12.12 2025-12-09 18:24:54.286249 | debian-bookworm | Provider: regiocloud-a 2025-12-09 18:24:54.286270 | debian-bookworm | Region: 2025-12-09 18:24:54.286290 | debian-bookworm | Label: debian-bookworm 2025-12-09 18:24:54.286323 | debian-bookworm | Product Name: OpenStack Nova 2025-12-09 18:24:54.286344 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe08:a6c6 2025-12-09 18:24:54.305099 | 2025-12-09 18:24:54.305280 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-09 18:24:54.867287 | debian-bookworm -> localhost | changed 2025-12-09 18:24:54.875002 | 2025-12-09 18:24:54.875148 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-09 18:24:56.051781 | debian-bookworm -> localhost | changed 2025-12-09 18:24:56.065932 | 2025-12-09 18:24:56.066073 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-09 18:24:56.344351 | debian-bookworm -> localhost | ok 2025-12-09 18:24:56.350901 | 2025-12-09 18:24:56.351036 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-09 18:24:56.381753 | debian-bookworm | ok 2025-12-09 18:24:56.401385 | debian-bookworm | included: /var/lib/zuul/builds/c6e875c8f47e48aa96e8826045487ff9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-09 18:24:56.408622 | 2025-12-09 18:24:56.408756 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-09 18:24:57.451415 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-09 18:24:57.451648 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c6e875c8f47e48aa96e8826045487ff9/work/c6e875c8f47e48aa96e8826045487ff9_id_rsa 2025-12-09 18:24:57.451687 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c6e875c8f47e48aa96e8826045487ff9/work/c6e875c8f47e48aa96e8826045487ff9_id_rsa.pub 2025-12-09 18:24:57.451714 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-09 18:24:57.451740 | debian-bookworm -> localhost | SHA256:z+TPQpTeSRkMyEYWgO1IYEHTUo2MrIb13r0iRq2Gk7w zuul-build-sshkey 2025-12-09 18:24:57.451762 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-09 18:24:57.451794 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-09 18:24:57.451815 | debian-bookworm -> localhost | | o*B.=.++o.o | 2025-12-09 18:24:57.451837 | debian-bookworm -> localhost | | .=.* o.+ o | 2025-12-09 18:24:57.451857 | debian-bookworm -> localhost | |.o + o . . o | 2025-12-09 18:24:57.451877 | debian-bookworm -> localhost | |o. o . o o | 2025-12-09 18:24:57.451896 | debian-bookworm -> localhost | |. . o .So.o . | 2025-12-09 18:24:57.451923 | debian-bookworm -> localhost | | o o .=o o | 2025-12-09 18:24:57.451943 | debian-bookworm -> localhost | | . + . o+ | 2025-12-09 18:24:57.451962 | debian-bookworm -> localhost | | = = . . .o | 2025-12-09 18:24:57.451982 | debian-bookworm -> localhost | | E= . . .o | 2025-12-09 18:24:57.452002 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-09 18:24:57.452053 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.428511 2025-12-09 18:24:57.459019 | 2025-12-09 18:24:57.459149 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-09 18:24:57.501206 | debian-bookworm | ok 2025-12-09 18:24:57.530158 | debian-bookworm | included: /var/lib/zuul/builds/c6e875c8f47e48aa96e8826045487ff9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-09 18:24:57.541265 | 2025-12-09 18:24:57.541391 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-09 18:24:57.565755 | debian-bookworm | skipping: Conditional result was False 2025-12-09 18:24:57.578603 | 2025-12-09 18:24:57.578768 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-09 18:24:58.226109 | debian-bookworm | changed 2025-12-09 18:24:58.232023 | 2025-12-09 18:24:58.232142 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-09 18:24:58.501586 | debian-bookworm | ok 2025-12-09 18:24:58.507526 | 2025-12-09 18:24:58.507657 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-09 18:24:59.193778 | debian-bookworm | changed 2025-12-09 18:24:59.213713 | 2025-12-09 18:24:59.213934 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-09 18:24:59.927512 | debian-bookworm | changed 2025-12-09 18:24:59.936353 | 2025-12-09 18:24:59.936498 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-09 18:24:59.970818 | debian-bookworm | skipping: Conditional result was False 2025-12-09 18:24:59.983885 | 2025-12-09 18:24:59.984062 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-09 18:25:00.655234 | debian-bookworm -> localhost | changed 2025-12-09 18:25:00.671545 | 2025-12-09 18:25:00.671680 | TASK [add-build-sshkey : Add back temp key] 2025-12-09 18:25:01.020238 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c6e875c8f47e48aa96e8826045487ff9/work/c6e875c8f47e48aa96e8826045487ff9_id_rsa (zuul-build-sshkey) 2025-12-09 18:25:01.020499 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011309 2025-12-09 18:25:01.031740 | 2025-12-09 18:25:01.031920 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-09 18:25:01.489350 | debian-bookworm | ok 2025-12-09 18:25:01.506455 | 2025-12-09 18:25:01.506656 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-09 18:25:01.542233 | debian-bookworm | skipping: Conditional result was False 2025-12-09 18:25:01.555265 | 2025-12-09 18:25:01.555403 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-09 18:25:02.076335 | debian-bookworm | ok 2025-12-09 18:25:02.087496 | 2025-12-09 18:25:02.087627 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-09 18:25:02.116772 | debian-bookworm | ok 2025-12-09 18:25:02.122117 | 2025-12-09 18:25:02.122278 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-09 18:25:02.427542 | debian-bookworm -> localhost | ok 2025-12-09 18:25:02.434800 | 2025-12-09 18:25:02.435116 | TASK [validate-host : Collect information about the host] 2025-12-09 18:25:03.606276 | debian-bookworm | ok 2025-12-09 18:25:03.617484 | 2025-12-09 18:25:03.617620 | TASK [validate-host : Sanitize hostname] 2025-12-09 18:25:03.740682 | debian-bookworm | ok 2025-12-09 18:25:03.789469 | 2025-12-09 18:25:03.789617 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-09 18:25:04.439335 | debian-bookworm -> localhost | changed 2025-12-09 18:25:04.449519 | 2025-12-09 18:25:04.449652 | TASK [validate-host : Collect information about zuul worker] 2025-12-09 18:25:04.907650 | debian-bookworm | ok 2025-12-09 18:25:04.916400 | 2025-12-09 18:25:04.916582 | TASK [validate-host : Write out all zuul information for each host] 2025-12-09 18:25:05.537654 | debian-bookworm -> localhost | changed 2025-12-09 18:25:05.555997 | 2025-12-09 18:25:05.556154 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-09 18:25:05.822874 | debian-bookworm | ok 2025-12-09 18:25:05.830371 | 2025-12-09 18:25:05.830519 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-09 18:25:07.001908 | debian-bookworm | changed: 2025-12-09 18:25:07.002330 | debian-bookworm | cd+++++++++ src/ 2025-12-09 18:25:07.002405 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-09 18:25:07.002450 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-09 18:25:07.002488 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-12-09 18:25:07.002524 | debian-bookworm | localhost | ok: Runtime: 0:00:00.009359 2025-12-09 18:25:32.425243 | 2025-12-09 18:25:32.425415 | TASK [upload-logs : Upload logs to log server] 2025-12-09 18:25:33.023431 | localhost | Output suppressed because no_log was given 2025-12-09 18:25:33.025388 | 2025-12-09 18:25:33.025497 | LOOP [upload-logs : Compress console log and json output] 2025-12-09 18:25:33.106035 | localhost | skipping: Conditional result was False 2025-12-09 18:25:33.111243 | localhost | skipping: Conditional result was False 2025-12-09 18:25:33.114570 | 2025-12-09 18:25:33.114685 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-09 18:25:33.179070 | localhost | skipping: Conditional result was False 2025-12-09 18:25:33.179408 | 2025-12-09 18:25:33.188643 | localhost | skipping: Conditional result was False 2025-12-09 18:25:33.208973 | 2025-12-09 18:25:33.209231 | LOOP [upload-logs : Upload console log and json output]