2025-10-15 17:44:18.587899 | Job console starting 2025-10-15 17:44:18.596127 | Updating git repos 2025-10-15 17:44:18.629019 | Cloning repos into workspace 2025-10-15 17:44:18.742416 | Restoring repo states 2025-10-15 17:44:18.756488 | Merging changes 2025-10-15 17:44:20.397708 | Checking out repos 2025-10-15 17:44:20.442430 | Preparing playbooks 2025-10-15 17:44:21.249296 | Running Ansible setup 2025-10-15 17:44:25.482113 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-15 17:44:26.291062 | 2025-10-15 17:44:26.291284 | PLAY [Base pre] 2025-10-15 17:44:26.308461 | 2025-10-15 17:44:26.308593 | TASK [Setup log path fact] 2025-10-15 17:44:26.348473 | debian-bookworm | ok 2025-10-15 17:44:26.376728 | 2025-10-15 17:44:26.376891 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-15 17:44:26.431232 | debian-bookworm | ok 2025-10-15 17:44:26.446574 | 2025-10-15 17:44:26.446692 | TASK [emit-job-header : Print job information] 2025-10-15 17:44:26.501505 | # Job Information 2025-10-15 17:44:26.501719 | Ansible Version: 2.16.14 2025-10-15 17:44:26.501756 | Job: python-black 2025-10-15 17:44:26.501789 | Pipeline: check 2025-10-15 17:44:26.501813 | Executor: 521e9411259a 2025-10-15 17:44:26.501837 | Triggered by: https://github.com/osism/testbed/pull/2777 2025-10-15 17:44:26.501860 | Event ID: 8d6bacc0-a9ee-11f0-9e08-135068229f84 2025-10-15 17:44:26.511169 | 2025-10-15 17:44:26.511297 | LOOP [emit-job-header : Print node information] 2025-10-15 17:44:26.618240 | debian-bookworm | ok: 2025-10-15 17:44:26.618481 | debian-bookworm | # Node Information 2025-10-15 17:44:26.618519 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-15 17:44:26.618546 | debian-bookworm | Hostname: debian 2025-10-15 17:44:26.618569 | debian-bookworm | Username: zuul 2025-10-15 17:44:26.618590 | debian-bookworm | Distro: Debian 12.12 2025-10-15 17:44:26.618616 | debian-bookworm | Provider: regiocloud-a 2025-10-15 17:44:26.618638 | debian-bookworm | Region: 2025-10-15 17:44:26.618659 | debian-bookworm | Label: debian-bookworm 2025-10-15 17:44:26.618678 | debian-bookworm | Product Name: OpenStack Nova 2025-10-15 17:44:26.618698 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef6:295d 2025-10-15 17:44:26.639314 | 2025-10-15 17:44:26.639443 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-15 17:44:27.069615 | debian-bookworm -> localhost | changed 2025-10-15 17:44:27.085369 | 2025-10-15 17:44:27.085488 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-15 17:44:28.101028 | debian-bookworm -> localhost | changed 2025-10-15 17:44:28.112261 | 2025-10-15 17:44:28.112363 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-15 17:44:28.311981 | debian-bookworm -> localhost | ok 2025-10-15 17:44:28.318173 | 2025-10-15 17:44:28.318269 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-15 17:44:28.335603 | debian-bookworm | ok 2025-10-15 17:44:28.352711 | debian-bookworm | included: /var/lib/zuul/builds/806109d0de3645988b56d0e114e6eefb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-15 17:44:28.358800 | 2025-10-15 17:44:28.358894 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-15 17:44:29.907456 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-15 17:44:29.907937 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/806109d0de3645988b56d0e114e6eefb/work/806109d0de3645988b56d0e114e6eefb_id_rsa 2025-10-15 17:44:29.908063 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/806109d0de3645988b56d0e114e6eefb/work/806109d0de3645988b56d0e114e6eefb_id_rsa.pub 2025-10-15 17:44:29.908138 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-15 17:44:29.908209 | debian-bookworm -> localhost | SHA256:IXyoA8ZfvAOdhkR9Izyh4JWZiit70PSrpPVN2bFLjEE zuul-build-sshkey 2025-10-15 17:44:29.908275 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-15 17:44:29.908357 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-15 17:44:29.908420 | debian-bookworm -> localhost | | ..+*.. | 2025-10-15 17:44:29.908480 | debian-bookworm -> localhost | | o ++*=oo | 2025-10-15 17:44:29.908538 | debian-bookworm -> localhost | | .=.+ E+o. | 2025-10-15 17:44:29.908597 | debian-bookworm -> localhost | |..oo * + . | 2025-10-15 17:44:29.908654 | debian-bookworm -> localhost | | + .+ + S | 2025-10-15 17:44:29.908718 | debian-bookworm -> localhost | |+ . .. B o | 2025-10-15 17:44:29.908776 | debian-bookworm -> localhost | |.oo .+ = | 2025-10-15 17:44:29.908831 | debian-bookworm -> localhost | |.+...o . . | 2025-10-15 17:44:29.908889 | debian-bookworm -> localhost | |..... . . | 2025-10-15 17:44:29.908948 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-15 17:44:29.909094 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.129750 2025-10-15 17:44:29.922752 | 2025-10-15 17:44:29.922901 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-15 17:44:29.943366 | debian-bookworm | ok 2025-10-15 17:44:29.954041 | debian-bookworm | included: /var/lib/zuul/builds/806109d0de3645988b56d0e114e6eefb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-15 17:44:29.962643 | 2025-10-15 17:44:29.962718 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-15 17:44:29.975452 | debian-bookworm | skipping: Conditional result was False 2025-10-15 17:44:29.986878 | 2025-10-15 17:44:29.987049 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-15 17:44:30.615216 | debian-bookworm | changed 2025-10-15 17:44:30.624536 | 2025-10-15 17:44:30.624660 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-15 17:44:30.866690 | debian-bookworm | ok 2025-10-15 17:44:30.875262 | 2025-10-15 17:44:30.875353 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-15 17:44:31.505878 | debian-bookworm | changed 2025-10-15 17:44:31.515660 | 2025-10-15 17:44:31.515765 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-15 17:44:32.138447 | debian-bookworm | changed 2025-10-15 17:44:32.145553 | 2025-10-15 17:44:32.145659 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-15 17:44:32.170169 | debian-bookworm | skipping: Conditional result was False 2025-10-15 17:44:32.194247 | 2025-10-15 17:44:32.194414 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-15 17:44:32.582518 | debian-bookworm -> localhost | changed 2025-10-15 17:44:32.592641 | 2025-10-15 17:44:32.592729 | TASK [add-build-sshkey : Add back temp key] 2025-10-15 17:44:32.868801 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/806109d0de3645988b56d0e114e6eefb/work/806109d0de3645988b56d0e114e6eefb_id_rsa (zuul-build-sshkey) 2025-10-15 17:44:32.869206 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011904 2025-10-15 17:44:32.882426 | 2025-10-15 17:44:32.882537 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-15 17:44:33.275412 | debian-bookworm | ok 2025-10-15 17:44:33.282190 | 2025-10-15 17:44:33.282297 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-15 17:44:33.317290 | debian-bookworm | skipping: Conditional result was False 2025-10-15 17:44:33.337456 | 2025-10-15 17:44:33.337569 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-15 17:44:33.775954 | debian-bookworm | ok 2025-10-15 17:44:33.799450 | 2025-10-15 17:44:33.799550 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-15 17:44:33.827072 | debian-bookworm | ok 2025-10-15 17:44:33.831783 | 2025-10-15 17:44:33.831862 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-15 17:44:34.076769 | debian-bookworm -> localhost | ok 2025-10-15 17:44:34.082739 | 2025-10-15 17:44:34.082828 | TASK [validate-host : Collect information about the host] 2025-10-15 17:44:35.111042 | debian-bookworm | ok 2025-10-15 17:44:35.120771 | 2025-10-15 17:44:35.120858 | TASK [validate-host : Sanitize hostname] 2025-10-15 17:44:35.207544 | debian-bookworm | ok 2025-10-15 17:44:35.239882 | 2025-10-15 17:44:35.240008 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-15 17:44:35.780241 | debian-bookworm -> localhost | changed 2025-10-15 17:44:35.791555 | 2025-10-15 17:44:35.791638 | TASK [validate-host : Collect information about zuul worker] 2025-10-15 17:44:36.205468 | debian-bookworm | ok 2025-10-15 17:44:36.211444 | 2025-10-15 17:44:36.211548 | TASK [validate-host : Write out all zuul information for each host] 2025-10-15 17:44:36.736468 | debian-bookworm -> localhost | changed 2025-10-15 17:44:36.746362 | 2025-10-15 17:44:36.746462 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-15 17:44:37.007001 | debian-bookworm | ok 2025-10-15 17:44:37.012043 | 2025-10-15 17:44:37.012122 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-15 17:44:38.167818 | debian-bookworm | changed: 2025-10-15 17:44:38.168094 | debian-bookworm | cd+++++++++ src/ 2025-10-15 17:44:38.168139 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-15 17:44:38.168169 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-15 17:44:38.168196 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-15 17:44:38.168221 | debian-bookworm | localhost | ok: Runtime: 0:00:00.008256 2025-10-15 17:45:03.598727 | 2025-10-15 17:45:03.598906 | TASK [upload-logs : Upload logs to log server] 2025-10-15 17:45:04.182768 | localhost | Output suppressed because no_log was given 2025-10-15 17:45:04.184828 | 2025-10-15 17:45:04.184933 | LOOP [upload-logs : Compress console log and json output] 2025-10-15 17:45:04.238092 | localhost | skipping: Conditional result was False 2025-10-15 17:45:04.246080 | localhost | skipping: Conditional result was False 2025-10-15 17:45:04.255820 | 2025-10-15 17:45:04.256101 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-15 17:45:04.321907 | localhost | skipping: Conditional result was False 2025-10-15 17:45:04.322430 | 2025-10-15 17:45:04.323831 | localhost | skipping: Conditional result was False 2025-10-15 17:45:04.331225 | 2025-10-15 17:45:04.331504 | LOOP [upload-logs : Upload console log and json output]