2025-07-31 09:52:02.712144 | Job console starting 2025-07-31 09:52:02.727090 | Updating git repos 2025-07-31 09:52:02.747054 | Cloning repos into workspace 2025-07-31 09:52:02.782943 | Restoring repo states 2025-07-31 09:52:02.792505 | Merging changes 2025-07-31 09:52:03.275271 | Checking out repos 2025-07-31 09:52:03.346879 | Preparing playbooks 2025-07-31 09:52:04.031296 | Running Ansible setup 2025-07-31 09:52:08.419761 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-07-31 09:52:09.179255 | 2025-07-31 09:52:09.179454 | PLAY [Base pre] 2025-07-31 09:52:09.195512 | 2025-07-31 09:52:09.195658 | TASK [Setup log path fact] 2025-07-31 09:52:09.214534 | debian-bookworm | ok 2025-07-31 09:52:09.230379 | 2025-07-31 09:52:09.230573 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-07-31 09:52:09.260093 | debian-bookworm | ok 2025-07-31 09:52:09.269745 | 2025-07-31 09:52:09.269863 | TASK [emit-job-header : Print job information] 2025-07-31 09:52:09.309220 | # Job Information 2025-07-31 09:52:09.309436 | Ansible Version: 2.16.14 2025-07-31 09:52:09.309476 | Job: python-black 2025-07-31 09:52:09.309510 | Pipeline: check 2025-07-31 09:52:09.309534 | Executor: 521e9411259a 2025-07-31 09:52:09.309555 | Triggered by: https://github.com/osism/zuul-jobs/pull/166 2025-07-31 09:52:09.309577 | Event ID: fc47f4a0-6df3-11f0-9df7-9b1c44ff0897 2025-07-31 09:52:09.314523 | 2025-07-31 09:52:09.314631 | LOOP [emit-job-header : Print node information] 2025-07-31 09:52:09.418007 | debian-bookworm | ok: 2025-07-31 09:52:09.418204 | debian-bookworm | # Node Information 2025-07-31 09:52:09.418238 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-07-31 09:52:09.418263 | debian-bookworm | Hostname: debian 2025-07-31 09:52:09.418286 | debian-bookworm | Username: zuul 2025-07-31 09:52:09.418306 | debian-bookworm | Distro: Debian 12.11 2025-07-31 09:52:09.418330 | debian-bookworm | Provider: regiocloud-a 2025-07-31 09:52:09.418350 | debian-bookworm | Region: 2025-07-31 09:52:09.418372 | debian-bookworm | Label: debian-bookworm 2025-07-31 09:52:09.418430 | debian-bookworm | Product Name: OpenStack Nova 2025-07-31 09:52:09.418452 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe4c:d47b 2025-07-31 09:52:09.430868 | 2025-07-31 09:52:09.431000 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-07-31 09:52:09.946520 | debian-bookworm -> localhost | changed 2025-07-31 09:52:09.956634 | 2025-07-31 09:52:09.956773 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-07-31 09:52:11.070340 | debian-bookworm -> localhost | changed 2025-07-31 09:52:11.081421 | 2025-07-31 09:52:11.081547 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-07-31 09:52:11.459616 | debian-bookworm -> localhost | ok 2025-07-31 09:52:11.466099 | 2025-07-31 09:52:11.466217 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-07-31 09:52:11.515047 | debian-bookworm | ok 2025-07-31 09:52:11.534640 | debian-bookworm | included: /var/lib/zuul/builds/06f36dd759e046258a8792397f4e7d63/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-07-31 09:52:11.541172 | 2025-07-31 09:52:11.541280 | TASK [add-build-sshkey : Create Temp SSH key] 2025-07-31 09:52:12.987541 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-07-31 09:52:12.987785 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/06f36dd759e046258a8792397f4e7d63/work/06f36dd759e046258a8792397f4e7d63_id_rsa 2025-07-31 09:52:12.987824 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/06f36dd759e046258a8792397f4e7d63/work/06f36dd759e046258a8792397f4e7d63_id_rsa.pub 2025-07-31 09:52:12.987849 | debian-bookworm -> localhost | The key fingerprint is: 2025-07-31 09:52:12.987873 | debian-bookworm -> localhost | SHA256:vM3y0mMh4789/xz8p4b3AOZcdoxj1LLXJSREW82cm5U zuul-build-sshkey 2025-07-31 09:52:12.987895 | debian-bookworm -> localhost | The key's randomart image is: 2025-07-31 09:52:12.987928 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-07-31 09:52:12.987950 | debian-bookworm -> localhost | | o+ o+o| 2025-07-31 09:52:12.987970 | debian-bookworm -> localhost | | = E=| 2025-07-31 09:52:12.987990 | debian-bookworm -> localhost | | . + *| 2025-07-31 09:52:12.988009 | debian-bookworm -> localhost | | . . Oo| 2025-07-31 09:52:12.988028 | debian-bookworm -> localhost | | S o B =| 2025-07-31 09:52:12.988053 | debian-bookworm -> localhost | | o+.+ =.+ | 2025-07-31 09:52:12.988073 | debian-bookworm -> localhost | | .o+o.o..o | 2025-07-31 09:52:12.988092 | debian-bookworm -> localhost | | oo+.o oo+| 2025-07-31 09:52:12.988111 | debian-bookworm -> localhost | | ++o.=o+*| 2025-07-31 09:52:12.988131 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-07-31 09:52:12.988181 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.952172 2025-07-31 09:52:12.994701 | 2025-07-31 09:52:12.994819 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-07-31 09:52:13.023753 | debian-bookworm | ok 2025-07-31 09:52:13.033128 | debian-bookworm | included: /var/lib/zuul/builds/06f36dd759e046258a8792397f4e7d63/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-07-31 09:52:13.042243 | 2025-07-31 09:52:13.042362 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-07-31 09:52:13.066014 | debian-bookworm | skipping: Conditional result was False 2025-07-31 09:52:13.072076 | 2025-07-31 09:52:13.072179 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-07-31 09:52:13.736334 | debian-bookworm | changed 2025-07-31 09:52:13.745819 | 2025-07-31 09:52:13.745970 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-07-31 09:52:14.017171 | debian-bookworm | ok 2025-07-31 09:52:14.027481 | 2025-07-31 09:52:14.027639 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-07-31 09:52:14.726224 | debian-bookworm | changed 2025-07-31 09:52:14.736982 | 2025-07-31 09:52:14.737137 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-07-31 09:52:15.400415 | debian-bookworm | changed 2025-07-31 09:52:15.408489 | 2025-07-31 09:52:15.408630 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-07-31 09:52:15.432816 | debian-bookworm | skipping: Conditional result was False 2025-07-31 09:52:15.443697 | 2025-07-31 09:52:15.443864 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-07-31 09:52:15.878038 | debian-bookworm -> localhost | changed 2025-07-31 09:52:15.889455 | 2025-07-31 09:52:15.889593 | TASK [add-build-sshkey : Add back temp key] 2025-07-31 09:52:16.219287 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/06f36dd759e046258a8792397f4e7d63/work/06f36dd759e046258a8792397f4e7d63_id_rsa (zuul-build-sshkey) 2025-07-31 09:52:16.219587 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.011091 2025-07-31 09:52:16.227702 | 2025-07-31 09:52:16.227811 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-07-31 09:52:16.658732 | debian-bookworm | ok 2025-07-31 09:52:16.667653 | 2025-07-31 09:52:16.667805 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-07-31 09:52:16.698755 | debian-bookworm | skipping: Conditional result was False 2025-07-31 09:52:16.710731 | 2025-07-31 09:52:16.710878 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-07-31 09:52:17.134144 | debian-bookworm | ok 2025-07-31 09:52:17.145591 | 2025-07-31 09:52:17.145731 | TASK [validate-host : Define zuul_info_dir fact] 2025-07-31 09:52:17.190927 | debian-bookworm | ok 2025-07-31 09:52:17.198757 | 2025-07-31 09:52:17.198911 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-07-31 09:52:17.497405 | debian-bookworm -> localhost | ok 2025-07-31 09:52:17.504797 | 2025-07-31 09:52:17.504919 | TASK [validate-host : Collect information about the host] 2025-07-31 09:52:18.625669 | debian-bookworm | ok 2025-07-31 09:52:18.643845 | 2025-07-31 09:52:18.643990 | TASK [validate-host : Sanitize hostname] 2025-07-31 09:52:18.753773 | debian-bookworm | ok 2025-07-31 09:52:18.814527 | 2025-07-31 09:52:18.814663 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-07-31 09:52:19.397456 | debian-bookworm -> localhost | changed 2025-07-31 09:52:19.404858 | 2025-07-31 09:52:19.404990 | TASK [validate-host : Collect information about zuul worker] 2025-07-31 09:52:19.817899 | debian-bookworm | ok 2025-07-31 09:52:19.822752 | 2025-07-31 09:52:19.822909 | TASK [validate-host : Write out all zuul information for each host] 2025-07-31 09:52:20.344592 | debian-bookworm -> localhost | changed 2025-07-31 09:52:20.354549 | 2025-07-31 09:52:20.354668 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-07-31 09:52:20.625018 | debian-bookworm | ok 2025-07-31 09:52:20.630856 | 2025-07-31 09:52:20.630965 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-07-31 09:52:21.465026 | debian-bookworm | changed: 2025-07-31 09:52:21.465232 | debian-bookworm | cd+++++++++ src/ 2025-07-31 09:52:21.465268 | debian-bookworm | cd+++++++++ src/github.com/ 2025-07-31 09:52:21.465292 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-07-31 09:52:21.465314 | debian-bookworm | cd+++++++++ src/github.com/osism/zuul-jobs/ 2025-07-31 09:52:21.465335 | debian-bookworm | localhost | ok: Runtime: 0:00:00.004242 2025-07-31 09:52:44.910077 | 2025-07-31 09:52:44.910190 | TASK [upload-logs : Upload logs to log server] 2025-07-31 09:52:45.530976 | localhost | Output suppressed because no_log was given 2025-07-31 09:52:45.533792 | 2025-07-31 09:52:45.533937 | LOOP [upload-logs : Compress console log and json output] 2025-07-31 09:52:45.588177 | localhost | skipping: Conditional result was False 2025-07-31 09:52:45.595071 | localhost | skipping: Conditional result was False 2025-07-31 09:52:45.604713 | 2025-07-31 09:52:45.604837 | LOOP [upload-logs : Upload compressed console log and json output] 2025-07-31 09:52:45.650805 | localhost | skipping: Conditional result was False 2025-07-31 09:52:45.651215 | 2025-07-31 09:52:45.655438 | localhost | skipping: Conditional result was False 2025-07-31 09:52:45.661459 | 2025-07-31 09:52:45.661579 | LOOP [upload-logs : Upload console log and json output]