2025-08-15 17:03:04.649642 | Job console starting 2025-08-15 17:03:04.662270 | Updating git repos 2025-08-15 17:03:04.690567 | Cloning repos into workspace 2025-08-15 17:03:04.769530 | Restoring repo states 2025-08-15 17:03:04.776954 | Merging changes 2025-08-15 17:03:05.284748 | Checking out repos 2025-08-15 17:03:05.354086 | Preparing playbooks 2025-08-15 17:03:06.080146 | Running Ansible setup 2025-08-15 17:03:10.358029 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-08-15 17:03:11.257774 | 2025-08-15 17:03:11.257930 | PLAY [Base pre] 2025-08-15 17:03:11.277069 | 2025-08-15 17:03:11.277214 | TASK [Setup log path fact] 2025-08-15 17:03:11.307009 | debian-bookworm | ok 2025-08-15 17:03:11.323034 | 2025-08-15 17:03:11.323185 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-08-15 17:03:11.352803 | debian-bookworm | ok 2025-08-15 17:03:11.369590 | 2025-08-15 17:03:11.369708 | TASK [emit-job-header : Print job information] 2025-08-15 17:03:11.438755 | # Job Information 2025-08-15 17:03:11.439214 | Ansible Version: 2.16.14 2025-08-15 17:03:11.439298 | Job: python-black 2025-08-15 17:03:11.439369 | Pipeline: check 2025-08-15 17:03:11.439418 | Executor: 521e9411259a 2025-08-15 17:03:11.439462 | Triggered by: https://github.com/osism/testbed/pull/2739 2025-08-15 17:03:11.439510 | Event ID: b05c4520-79f9-11f0-8d54-676042732d90 2025-08-15 17:03:11.447061 | 2025-08-15 17:03:11.447199 | LOOP [emit-job-header : Print node information] 2025-08-15 17:03:11.552498 | debian-bookworm | ok: 2025-08-15 17:03:11.552698 | debian-bookworm | # Node Information 2025-08-15 17:03:11.552734 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-08-15 17:03:11.552760 | debian-bookworm | Hostname: debian 2025-08-15 17:03:11.552782 | debian-bookworm | Username: zuul 2025-08-15 17:03:11.552803 | debian-bookworm | Distro: Debian 12.11 2025-08-15 17:03:11.552826 | debian-bookworm | Provider: regiocloud-a 2025-08-15 17:03:11.552848 | debian-bookworm | Region: 2025-08-15 17:03:11.552869 | debian-bookworm | Label: debian-bookworm 2025-08-15 17:03:11.552888 | debian-bookworm | Product Name: OpenStack Nova 2025-08-15 17:03:11.552908 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe3d:6e6b 2025-08-15 17:03:11.564223 | 2025-08-15 17:03:11.564360 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-08-15 17:03:12.084988 | debian-bookworm -> localhost | changed 2025-08-15 17:03:12.092407 | 2025-08-15 17:03:12.092547 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-08-15 17:03:13.205984 | debian-bookworm -> localhost | changed 2025-08-15 17:03:13.218318 | 2025-08-15 17:03:13.218449 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-08-15 17:03:13.489174 | debian-bookworm -> localhost | ok 2025-08-15 17:03:13.495528 | 2025-08-15 17:03:13.495648 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-08-15 17:03:13.523887 | debian-bookworm | ok 2025-08-15 17:03:13.541798 | debian-bookworm | included: /var/lib/zuul/builds/ed7f1571038f494eb19a2baa35cb1c8a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-08-15 17:03:13.557661 | 2025-08-15 17:03:13.557796 | TASK [add-build-sshkey : Create Temp SSH key] 2025-08-15 17:03:16.451881 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-08-15 17:03:16.452339 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/ed7f1571038f494eb19a2baa35cb1c8a/work/ed7f1571038f494eb19a2baa35cb1c8a_id_rsa 2025-08-15 17:03:16.452427 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/ed7f1571038f494eb19a2baa35cb1c8a/work/ed7f1571038f494eb19a2baa35cb1c8a_id_rsa.pub 2025-08-15 17:03:16.452486 | debian-bookworm -> localhost | The key fingerprint is: 2025-08-15 17:03:16.452540 | debian-bookworm -> localhost | SHA256:QCne8O1LjCJYZTQWHc8SVitBnufwWrL/g6Wb3nXtn98 zuul-build-sshkey 2025-08-15 17:03:16.452591 | debian-bookworm -> localhost | The key's randomart image is: 2025-08-15 17:03:16.452660 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-08-15 17:03:16.452711 | debian-bookworm -> localhost | | .=+B+. | 2025-08-15 17:03:16.452759 | debian-bookworm -> localhost | | .==o* . | 2025-08-15 17:03:16.452805 | debian-bookworm -> localhost | | + =B.= | 2025-08-15 17:03:16.452850 | debian-bookworm -> localhost | | . . oO. | 2025-08-15 17:03:16.452897 | debian-bookworm -> localhost | | o .+S | 2025-08-15 17:03:16.452953 | debian-bookworm -> localhost | |. . . .=+ . . | 2025-08-15 17:03:16.452999 | debian-bookworm -> localhost | | . .o. = . . .| 2025-08-15 17:03:16.453064 | debian-bookworm -> localhost | | .+o.. . .o| 2025-08-15 17:03:16.453112 | debian-bookworm -> localhost | | .=oo. .E| 2025-08-15 17:03:16.453159 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-08-15 17:03:16.453265 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.281644 2025-08-15 17:03:16.466054 | 2025-08-15 17:03:16.466212 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-08-15 17:03:16.496808 | debian-bookworm | ok 2025-08-15 17:03:16.507853 | debian-bookworm | included: /var/lib/zuul/builds/ed7f1571038f494eb19a2baa35cb1c8a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-08-15 17:03:16.516923 | 2025-08-15 17:03:16.517158 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-08-15 17:03:16.551309 | debian-bookworm | skipping: Conditional result was False 2025-08-15 17:03:16.557127 | 2025-08-15 17:03:16.557235 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-08-15 17:03:17.246307 | debian-bookworm | changed 2025-08-15 17:03:17.255673 | 2025-08-15 17:03:17.255810 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-08-15 17:03:17.532587 | debian-bookworm | ok 2025-08-15 17:03:17.547792 | 2025-08-15 17:03:17.547929 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-08-15 17:03:18.195641 | debian-bookworm | changed 2025-08-15 17:03:18.202683 | 2025-08-15 17:03:18.202807 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-08-15 17:03:18.843561 | debian-bookworm | changed 2025-08-15 17:03:18.849133 | 2025-08-15 17:03:18.849242 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-08-15 17:03:18.872777 | debian-bookworm | skipping: Conditional result was False 2025-08-15 17:03:18.878868 | 2025-08-15 17:03:18.878975 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-08-15 17:03:19.312765 | debian-bookworm -> localhost | changed 2025-08-15 17:03:19.323819 | 2025-08-15 17:03:19.323950 | TASK [add-build-sshkey : Add back temp key] 2025-08-15 17:03:19.652120 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/ed7f1571038f494eb19a2baa35cb1c8a/work/ed7f1571038f494eb19a2baa35cb1c8a_id_rsa (zuul-build-sshkey) 2025-08-15 17:03:19.652386 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.019698 2025-08-15 17:03:19.660764 | 2025-08-15 17:03:19.660885 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-08-15 17:03:20.074791 | debian-bookworm | ok 2025-08-15 17:03:20.082425 | 2025-08-15 17:03:20.082565 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-08-15 17:03:20.117075 | debian-bookworm | skipping: Conditional result was False 2025-08-15 17:03:20.137558 | 2025-08-15 17:03:20.137697 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-08-15 17:03:20.593858 | debian-bookworm | ok 2025-08-15 17:03:20.608781 | 2025-08-15 17:03:20.608917 | TASK [validate-host : Define zuul_info_dir fact] 2025-08-15 17:03:20.648177 | debian-bookworm | ok 2025-08-15 17:03:20.653234 | 2025-08-15 17:03:20.653345 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-08-15 17:03:20.979242 | debian-bookworm -> localhost | ok 2025-08-15 17:03:20.985921 | 2025-08-15 17:03:20.986060 | TASK [validate-host : Collect information about the host] 2025-08-15 17:03:22.143701 | debian-bookworm | ok 2025-08-15 17:03:22.159219 | 2025-08-15 17:03:22.159369 | TASK [validate-host : Sanitize hostname] 2025-08-15 17:03:22.260892 | debian-bookworm | ok 2025-08-15 17:03:22.308602 | 2025-08-15 17:03:22.308729 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-08-15 17:03:22.923309 | debian-bookworm -> localhost | changed 2025-08-15 17:03:22.929322 | 2025-08-15 17:03:22.929440 | TASK [validate-host : Collect information about zuul worker] 2025-08-15 17:03:23.356907 | debian-bookworm | ok 2025-08-15 17:03:23.365040 | 2025-08-15 17:03:23.365186 | TASK [validate-host : Write out all zuul information for each host] 2025-08-15 17:03:24.012979 | debian-bookworm -> localhost | changed 2025-08-15 17:03:24.022485 | 2025-08-15 17:03:24.022599 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-08-15 17:03:24.297054 | debian-bookworm | ok 2025-08-15 17:03:24.302751 | 2025-08-15 17:03:24.302912 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-08-15 17:03:25.376734 | debian-bookworm | changed: 2025-08-15 17:03:25.376952 | debian-bookworm | cd+++++++++ src/ 2025-08-15 17:03:25.376987 | debian-bookworm | cd+++++++++ src/github.com/ 2025-08-15 17:03:25.377051 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-08-15 17:03:25.377077 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-08-15 17:03:25.377098 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006068 2025-08-15 17:03:49.785449 | 2025-08-15 17:03:49.785604 | TASK [upload-logs : Upload logs to log server] 2025-08-15 17:03:50.455911 | localhost | Output suppressed because no_log was given 2025-08-15 17:03:50.457916 | 2025-08-15 17:03:50.458042 | LOOP [upload-logs : Compress console log and json output] 2025-08-15 17:03:50.523262 | localhost | skipping: Conditional result was False 2025-08-15 17:03:50.528806 | localhost | skipping: Conditional result was False 2025-08-15 17:03:50.541593 | 2025-08-15 17:03:50.541855 | LOOP [upload-logs : Upload compressed console log and json output] 2025-08-15 17:03:50.603065 | localhost | skipping: Conditional result was False 2025-08-15 17:03:50.603658 | 2025-08-15 17:03:50.612184 | localhost | skipping: Conditional result was False 2025-08-15 17:03:50.620398 | 2025-08-15 17:03:50.620635 | LOOP [upload-logs : Upload console log and json output]