2025-10-27 16:02:46.812906 | Job console starting 2025-10-27 16:02:46.829049 | Updating git repos 2025-10-27 16:02:46.866262 | Cloning repos into workspace 2025-10-27 16:02:46.927887 | Restoring repo states 2025-10-27 16:02:46.944560 | Merging changes 2025-10-27 16:02:47.469685 | Checking out repos 2025-10-27 16:02:47.505978 | Preparing playbooks 2025-10-27 16:02:48.252570 | Running Ansible setup 2025-10-27 16:02:52.713308 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-27 16:02:53.456417 | 2025-10-27 16:02:53.456586 | PLAY [Base pre] 2025-10-27 16:02:53.473321 | 2025-10-27 16:02:53.473471 | TASK [Setup log path fact] 2025-10-27 16:02:53.502918 | debian-bookworm | ok 2025-10-27 16:02:53.518296 | 2025-10-27 16:02:53.518457 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-27 16:02:53.548244 | debian-bookworm | ok 2025-10-27 16:02:53.557993 | 2025-10-27 16:02:53.558104 | TASK [emit-job-header : Print job information] 2025-10-27 16:02:53.597768 | # Job Information 2025-10-27 16:02:53.597947 | Ansible Version: 2.16.14 2025-10-27 16:02:53.597982 | Job: python-black 2025-10-27 16:02:53.598015 | Pipeline: check 2025-10-27 16:02:53.598037 | Executor: 521e9411259a 2025-10-27 16:02:53.598058 | Triggered by: https://github.com/osism/testbed/pull/2777 2025-10-27 16:02:53.598079 | Event ID: 5b4847d0-b34e-11f0-95fe-4ed18e25c7e9 2025-10-27 16:02:53.603016 | 2025-10-27 16:02:53.603134 | LOOP [emit-job-header : Print node information] 2025-10-27 16:02:53.706705 | debian-bookworm | ok: 2025-10-27 16:02:53.706974 | debian-bookworm | # Node Information 2025-10-27 16:02:53.707012 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-27 16:02:53.707037 | debian-bookworm | Hostname: debian 2025-10-27 16:02:53.707058 | debian-bookworm | Username: zuul 2025-10-27 16:02:53.707078 | debian-bookworm | Distro: Debian 12.12 2025-10-27 16:02:53.707107 | debian-bookworm | Provider: regiocloud-a 2025-10-27 16:02:53.707132 | debian-bookworm | Region: 2025-10-27 16:02:53.707152 | debian-bookworm | Label: debian-bookworm 2025-10-27 16:02:53.707172 | debian-bookworm | Product Name: OpenStack Nova 2025-10-27 16:02:53.707192 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe04:7230 2025-10-27 16:02:53.740609 | 2025-10-27 16:02:53.740747 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-27 16:02:54.220702 | debian-bookworm -> localhost | changed 2025-10-27 16:02:54.230382 | 2025-10-27 16:02:54.230515 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-27 16:02:55.289634 | debian-bookworm -> localhost | changed 2025-10-27 16:02:55.300857 | 2025-10-27 16:02:55.300985 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-27 16:02:55.571211 | debian-bookworm -> localhost | ok 2025-10-27 16:02:55.577543 | 2025-10-27 16:02:55.577664 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-27 16:02:55.606081 | debian-bookworm | ok 2025-10-27 16:02:55.624161 | debian-bookworm | included: /var/lib/zuul/builds/439f60424b844800935bea9b5db3bbfe/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-27 16:02:55.630411 | 2025-10-27 16:02:55.630520 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-27 16:02:56.526213 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-27 16:02:56.526673 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/439f60424b844800935bea9b5db3bbfe/work/439f60424b844800935bea9b5db3bbfe_id_rsa 2025-10-27 16:02:56.526770 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/439f60424b844800935bea9b5db3bbfe/work/439f60424b844800935bea9b5db3bbfe_id_rsa.pub 2025-10-27 16:02:56.526897 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-27 16:02:56.526966 | debian-bookworm -> localhost | SHA256:AcKTqvgQeUoNfVOjofN+nO8Ctl+KrsI3LWGwk62BbQU zuul-build-sshkey 2025-10-27 16:02:56.527024 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-27 16:02:56.527100 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-27 16:02:56.527157 | debian-bookworm -> localhost | | ...ooo | 2025-10-27 16:02:56.527212 | debian-bookworm -> localhost | | .E.=+o.. | 2025-10-27 16:02:56.527296 | debian-bookworm -> localhost | | .o=.o. . | 2025-10-27 16:02:56.527350 | debian-bookworm -> localhost | |ooo.+ . | 2025-10-27 16:02:56.527402 | debian-bookworm -> localhost | |+*=. . S | 2025-10-27 16:02:56.527465 | debian-bookworm -> localhost | |*=o+.o. . | 2025-10-27 16:02:56.527518 | debian-bookworm -> localhost | |.+= +.o+ . | 2025-10-27 16:02:56.527570 | debian-bookworm -> localhost | | +.+ oo.+ | 2025-10-27 16:02:56.527623 | debian-bookworm -> localhost | | o.=o.ooo | 2025-10-27 16:02:56.527673 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-27 16:02:56.527899 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.269551 2025-10-27 16:02:56.539442 | 2025-10-27 16:02:56.539612 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-27 16:02:56.569109 | debian-bookworm | ok 2025-10-27 16:02:56.578574 | debian-bookworm | included: /var/lib/zuul/builds/439f60424b844800935bea9b5db3bbfe/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-27 16:02:56.595150 | 2025-10-27 16:02:56.595333 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-27 16:02:56.619484 | debian-bookworm | skipping: Conditional result was False 2025-10-27 16:02:56.628076 | 2025-10-27 16:02:56.628201 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-27 16:02:57.277641 | debian-bookworm | changed 2025-10-27 16:02:57.286313 | 2025-10-27 16:02:57.286465 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-27 16:02:57.567329 | debian-bookworm | ok 2025-10-27 16:02:57.578476 | 2025-10-27 16:02:57.578605 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-27 16:02:58.235483 | debian-bookworm | changed 2025-10-27 16:02:58.245467 | 2025-10-27 16:02:58.245600 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-27 16:02:58.893062 | debian-bookworm | changed 2025-10-27 16:02:58.902189 | 2025-10-27 16:02:58.902455 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-27 16:02:58.937113 | debian-bookworm | skipping: Conditional result was False 2025-10-27 16:02:58.949087 | 2025-10-27 16:02:58.949270 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-27 16:02:59.403547 | debian-bookworm -> localhost | changed 2025-10-27 16:02:59.414760 | 2025-10-27 16:02:59.414904 | TASK [add-build-sshkey : Add back temp key] 2025-10-27 16:02:59.754192 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/439f60424b844800935bea9b5db3bbfe/work/439f60424b844800935bea9b5db3bbfe_id_rsa (zuul-build-sshkey) 2025-10-27 16:02:59.754680 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.023438 2025-10-27 16:02:59.767800 | 2025-10-27 16:02:59.767922 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-27 16:03:00.196502 | debian-bookworm | ok 2025-10-27 16:03:00.203111 | 2025-10-27 16:03:00.203273 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-27 16:03:00.237500 | debian-bookworm | skipping: Conditional result was False 2025-10-27 16:03:00.255780 | 2025-10-27 16:03:00.255917 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-27 16:03:00.702754 | debian-bookworm | ok 2025-10-27 16:03:00.713610 | 2025-10-27 16:03:00.713728 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-27 16:03:00.742587 | debian-bookworm | ok 2025-10-27 16:03:00.747676 | 2025-10-27 16:03:00.747783 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-27 16:03:01.055452 | debian-bookworm -> localhost | ok 2025-10-27 16:03:01.061978 | 2025-10-27 16:03:01.062083 | TASK [validate-host : Collect information about the host] 2025-10-27 16:03:02.251893 | debian-bookworm | ok 2025-10-27 16:03:02.262318 | 2025-10-27 16:03:02.262425 | TASK [validate-host : Sanitize hostname] 2025-10-27 16:03:02.371544 | debian-bookworm | ok 2025-10-27 16:03:02.421773 | 2025-10-27 16:03:02.421908 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-27 16:03:02.966046 | debian-bookworm -> localhost | changed 2025-10-27 16:03:02.971872 | 2025-10-27 16:03:02.971991 | TASK [validate-host : Collect information about zuul worker] 2025-10-27 16:03:03.381788 | debian-bookworm | ok 2025-10-27 16:03:03.388326 | 2025-10-27 16:03:03.388454 | TASK [validate-host : Write out all zuul information for each host] 2025-10-27 16:03:03.912435 | debian-bookworm -> localhost | changed 2025-10-27 16:03:03.923199 | 2025-10-27 16:03:03.923360 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-27 16:03:04.197605 | debian-bookworm | ok 2025-10-27 16:03:04.203802 | 2025-10-27 16:03:04.203920 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-27 16:03:05.388700 | debian-bookworm | changed: 2025-10-27 16:03:05.388950 | debian-bookworm | cd+++++++++ src/ 2025-10-27 16:03:05.389001 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-27 16:03:05.389038 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-27 16:03:05.389070 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-27 16:03:05.389101 | debian-bookworm | localhost | ok: Runtime: 0:00:00.025134 2025-10-27 16:03:30.951935 | 2025-10-27 16:03:30.952043 | TASK [upload-logs : Upload logs to log server] 2025-10-27 16:03:31.487442 | localhost | Output suppressed because no_log was given 2025-10-27 16:03:31.489305 | 2025-10-27 16:03:31.489411 | LOOP [upload-logs : Compress console log and json output] 2025-10-27 16:03:31.552107 | localhost | skipping: Conditional result was False 2025-10-27 16:03:31.558080 | localhost | skipping: Conditional result was False 2025-10-27 16:03:31.567759 | 2025-10-27 16:03:31.567871 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-27 16:03:31.614198 | localhost | skipping: Conditional result was False 2025-10-27 16:03:31.614528 | 2025-10-27 16:03:31.619310 | localhost | skipping: Conditional result was False 2025-10-27 16:03:31.628536 | 2025-10-27 16:03:31.628647 | LOOP [upload-logs : Upload console log and json output]