2025-06-24 17:39:42.578214 | Job console starting 2025-06-24 17:39:42.592953 | Updating git repos 2025-06-24 17:39:42.634386 | Cloning repos into workspace 2025-06-24 17:39:42.670627 | Restoring repo states 2025-06-24 17:39:42.677282 | Merging changes 2025-06-24 17:39:43.182225 | Checking out repos 2025-06-24 17:39:43.217421 | Preparing playbooks 2025-06-24 17:39:43.829129 | Running Ansible setup 2025-06-24 17:39:48.437552 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-06-24 17:39:49.189598 | 2025-06-24 17:39:49.189776 | PLAY [Base pre] 2025-06-24 17:39:49.206340 | 2025-06-24 17:39:49.206493 | TASK [Setup log path fact] 2025-06-24 17:39:49.248076 | debian-bookworm | ok 2025-06-24 17:39:49.263327 | 2025-06-24 17:39:49.263481 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-06-24 17:39:49.293454 | debian-bookworm | ok 2025-06-24 17:39:49.303468 | 2025-06-24 17:39:49.303592 | TASK [emit-job-header : Print job information] 2025-06-24 17:39:49.353015 | # Job Information 2025-06-24 17:39:49.353247 | Ansible Version: 2.16.14 2025-06-24 17:39:49.353286 | Job: python-black 2025-06-24 17:39:49.353319 | Pipeline: check 2025-06-24 17:39:49.353342 | Executor: 521e9411259a 2025-06-24 17:39:49.353362 | Triggered by: https://github.com/osism/testbed/pull/2669 2025-06-24 17:39:49.353385 | Event ID: 30b52080-5122-11f0-8a00-531f957249a2 2025-06-24 17:39:49.358568 | 2025-06-24 17:39:49.358698 | LOOP [emit-job-header : Print node information] 2025-06-24 17:39:49.472705 | debian-bookworm | ok: 2025-06-24 17:39:49.472894 | debian-bookworm | # Node Information 2025-06-24 17:39:49.472928 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-06-24 17:39:49.472954 | debian-bookworm | Hostname: debian 2025-06-24 17:39:49.472975 | debian-bookworm | Username: zuul 2025-06-24 17:39:49.472996 | debian-bookworm | Distro: Debian 12.11 2025-06-24 17:39:49.473019 | debian-bookworm | Provider: regiocloud-a 2025-06-24 17:39:49.473040 | debian-bookworm | Region: 2025-06-24 17:39:49.473062 | debian-bookworm | Label: debian-bookworm 2025-06-24 17:39:49.473081 | debian-bookworm | Product Name: OpenStack Nova 2025-06-24 17:39:49.473100 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febf:ccd0 2025-06-24 17:39:49.486492 | 2025-06-24 17:39:49.486633 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-06-24 17:39:49.999537 | debian-bookworm -> localhost | changed 2025-06-24 17:39:50.007104 | 2025-06-24 17:39:50.007248 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-06-24 17:39:51.153710 | debian-bookworm -> localhost | changed 2025-06-24 17:39:51.164928 | 2025-06-24 17:39:51.165063 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-06-24 17:39:51.490591 | debian-bookworm -> localhost | ok 2025-06-24 17:39:51.497018 | 2025-06-24 17:39:51.497194 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-06-24 17:39:51.526655 | debian-bookworm | ok 2025-06-24 17:39:51.545068 | debian-bookworm | included: /var/lib/zuul/builds/d84db95decc2414596088499366777dd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-06-24 17:39:51.551613 | 2025-06-24 17:39:51.551737 | TASK [add-build-sshkey : Create Temp SSH key] 2025-06-24 17:39:53.113954 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-06-24 17:39:53.114196 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/d84db95decc2414596088499366777dd/work/d84db95decc2414596088499366777dd_id_rsa 2025-06-24 17:39:53.114239 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/d84db95decc2414596088499366777dd/work/d84db95decc2414596088499366777dd_id_rsa.pub 2025-06-24 17:39:53.114267 | debian-bookworm -> localhost | The key fingerprint is: 2025-06-24 17:39:53.114290 | debian-bookworm -> localhost | SHA256:okcGLXE+HleOKyWyLPHJrpgckRbplEcZDgvSEay3C30 zuul-build-sshkey 2025-06-24 17:39:53.114313 | debian-bookworm -> localhost | The key's randomart image is: 2025-06-24 17:39:53.114346 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-06-24 17:39:53.114369 | debian-bookworm -> localhost | |o+o=+ . . | 2025-06-24 17:39:53.114391 | debian-bookworm -> localhost | |o X. = + | 2025-06-24 17:39:53.114412 | debian-bookworm -> localhost | | B.o+ * + . | 2025-06-24 17:39:53.114432 | debian-bookworm -> localhost | |+ == B * . | 2025-06-24 17:39:53.114451 | debian-bookworm -> localhost | | B..* * S | 2025-06-24 17:39:53.114481 | debian-bookworm -> localhost | |o +oE+ o | 2025-06-24 17:39:53.114503 | debian-bookworm -> localhost | | o oo . | 2025-06-24 17:39:53.114524 | debian-bookworm -> localhost | |.oo. . | 2025-06-24 17:39:53.114544 | debian-bookworm -> localhost | |o.. | 2025-06-24 17:39:53.114565 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-06-24 17:39:53.114623 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.022904 2025-06-24 17:39:53.121226 | 2025-06-24 17:39:53.121329 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-06-24 17:39:53.149771 | debian-bookworm | ok 2025-06-24 17:39:53.159288 | debian-bookworm | included: /var/lib/zuul/builds/d84db95decc2414596088499366777dd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-06-24 17:39:53.168581 | 2025-06-24 17:39:53.168688 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-06-24 17:39:53.192644 | debian-bookworm | skipping: Conditional result was False 2025-06-24 17:39:53.198442 | 2025-06-24 17:39:53.198544 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-06-24 17:39:53.800650 | debian-bookworm | changed 2025-06-24 17:39:53.809095 | 2025-06-24 17:39:53.809283 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-06-24 17:39:54.046357 | debian-bookworm | ok 2025-06-24 17:39:54.052007 | 2025-06-24 17:39:54.052120 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-06-24 17:39:54.663920 | debian-bookworm | changed 2025-06-24 17:39:54.675759 | 2025-06-24 17:39:54.675949 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-06-24 17:39:55.306912 | debian-bookworm | changed 2025-06-24 17:39:55.313675 | 2025-06-24 17:39:55.313802 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-06-24 17:39:55.347936 | debian-bookworm | skipping: Conditional result was False 2025-06-24 17:39:55.355221 | 2025-06-24 17:39:55.355343 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-06-24 17:39:55.815294 | debian-bookworm -> localhost | changed 2025-06-24 17:39:55.826475 | 2025-06-24 17:39:55.826594 | TASK [add-build-sshkey : Add back temp key] 2025-06-24 17:39:56.201403 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/d84db95decc2414596088499366777dd/work/d84db95decc2414596088499366777dd_id_rsa (zuul-build-sshkey) 2025-06-24 17:39:56.201662 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014480 2025-06-24 17:39:56.210339 | 2025-06-24 17:39:56.210480 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-06-24 17:39:56.594093 | debian-bookworm | ok 2025-06-24 17:39:56.601598 | 2025-06-24 17:39:56.601723 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-06-24 17:39:56.625532 | debian-bookworm | skipping: Conditional result was False 2025-06-24 17:39:56.636243 | 2025-06-24 17:39:56.636366 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-06-24 17:39:57.101614 | debian-bookworm | ok 2025-06-24 17:39:57.112853 | 2025-06-24 17:39:57.112998 | TASK [validate-host : Define zuul_info_dir fact] 2025-06-24 17:39:57.142229 | debian-bookworm | ok 2025-06-24 17:39:57.147473 | 2025-06-24 17:39:57.147592 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-06-24 17:39:57.426026 | debian-bookworm -> localhost | ok 2025-06-24 17:39:57.434716 | 2025-06-24 17:39:57.434897 | TASK [validate-host : Collect information about the host] 2025-06-24 17:39:58.529543 | debian-bookworm | ok 2025-06-24 17:39:58.546998 | 2025-06-24 17:39:58.547167 | TASK [validate-host : Sanitize hostname] 2025-06-24 17:39:58.648759 | debian-bookworm | ok 2025-06-24 17:39:58.698515 | 2025-06-24 17:39:58.698650 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-06-24 17:39:59.393741 | debian-bookworm -> localhost | changed 2025-06-24 17:39:59.401641 | 2025-06-24 17:39:59.401788 | TASK [validate-host : Collect information about zuul worker] 2025-06-24 17:39:59.807487 | debian-bookworm | ok 2025-06-24 17:39:59.812944 | 2025-06-24 17:39:59.813062 | TASK [validate-host : Write out all zuul information for each host] 2025-06-24 17:40:00.403676 | debian-bookworm -> localhost | changed 2025-06-24 17:40:00.413695 | 2025-06-24 17:40:00.413827 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-06-24 17:40:00.666063 | debian-bookworm | ok 2025-06-24 17:40:00.673174 | 2025-06-24 17:40:00.673322 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-06-24 17:40:01.795283 | debian-bookworm | changed: 2025-06-24 17:40:01.795494 | debian-bookworm | cd+++++++++ src/ 2025-06-24 17:40:01.795530 | debian-bookworm | cd+++++++++ src/github.com/ 2025-06-24 17:40:01.795554 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-06-24 17:40:01.795575 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-06-24 17:40:01.795594 | debian-bookworm | localhost | ok: Runtime: 0:00:00.009992 2025-06-24 17:40:25.746146 | 2025-06-24 17:40:25.746270 | TASK [upload-logs : Upload logs to log server] 2025-06-24 17:40:26.451827 | localhost | Output suppressed because no_log was given 2025-06-24 17:40:26.453978 | 2025-06-24 17:40:26.454095 | LOOP [upload-logs : Compress console log and json output] 2025-06-24 17:40:26.507743 | localhost | skipping: Conditional result was False 2025-06-24 17:40:26.513444 | localhost | skipping: Conditional result was False 2025-06-24 17:40:26.521373 | 2025-06-24 17:40:26.521518 | LOOP [upload-logs : Upload compressed console log and json output] 2025-06-24 17:40:26.572707 | localhost | skipping: Conditional result was False 2025-06-24 17:40:26.573501 | 2025-06-24 17:40:26.577162 | localhost | skipping: Conditional result was False 2025-06-24 17:40:26.588130 | 2025-06-24 17:40:26.588256 | LOOP [upload-logs : Upload console log and json output]