2025-09-18 16:38:40.445144 | Job console starting 2025-09-18 16:38:40.455455 | Updating git repos 2025-09-18 16:38:40.490412 | Cloning repos into workspace 2025-09-18 16:38:40.562970 | Restoring repo states 2025-09-18 16:38:40.569436 | Merging changes 2025-09-18 16:38:41.064479 | Checking out repos 2025-09-18 16:38:41.108195 | Preparing playbooks 2025-09-18 16:38:41.805737 | Running Ansible setup 2025-09-18 16:38:46.300126 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-09-18 16:38:47.057681 | 2025-09-18 16:38:47.057845 | PLAY [Base pre] 2025-09-18 16:38:47.073607 | 2025-09-18 16:38:47.073746 | TASK [Setup log path fact] 2025-09-18 16:38:47.102490 | debian-bookworm | ok 2025-09-18 16:38:47.118044 | 2025-09-18 16:38:47.118200 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-09-18 16:38:47.147886 | debian-bookworm | ok 2025-09-18 16:38:47.157649 | 2025-09-18 16:38:47.157767 | TASK [emit-job-header : Print job information] 2025-09-18 16:38:47.214393 | # Job Information 2025-09-18 16:38:47.214573 | Ansible Version: 2.16.14 2025-09-18 16:38:47.214607 | Job: python-black 2025-09-18 16:38:47.214639 | Pipeline: check 2025-09-18 16:38:47.214661 | Executor: 521e9411259a 2025-09-18 16:38:47.214682 | Triggered by: https://github.com/osism/testbed/pull/2766 2025-09-18 16:38:47.214704 | Event ID: e94c4c30-94ad-11f0-9096-4f280063e2a0 2025-09-18 16:38:47.219661 | 2025-09-18 16:38:47.219768 | LOOP [emit-job-header : Print node information] 2025-09-18 16:38:47.343830 | debian-bookworm | ok: 2025-09-18 16:38:47.344076 | debian-bookworm | # Node Information 2025-09-18 16:38:47.344113 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-09-18 16:38:47.344139 | debian-bookworm | Hostname: debian 2025-09-18 16:38:47.344162 | debian-bookworm | Username: zuul 2025-09-18 16:38:47.344185 | debian-bookworm | Distro: Debian 12.12 2025-09-18 16:38:47.344219 | debian-bookworm | Provider: regiocloud-a 2025-09-18 16:38:47.344254 | debian-bookworm | Region: 2025-09-18 16:38:47.344277 | debian-bookworm | Label: debian-bookworm 2025-09-18 16:38:47.344298 | debian-bookworm | Product Name: OpenStack Nova 2025-09-18 16:38:47.344318 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe61:bb19 2025-09-18 16:38:47.362646 | 2025-09-18 16:38:47.362775 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-09-18 16:38:48.079132 | debian-bookworm -> localhost | changed 2025-09-18 16:38:48.090402 | 2025-09-18 16:38:48.090656 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-09-18 16:38:49.402243 | debian-bookworm -> localhost | changed 2025-09-18 16:38:49.414582 | 2025-09-18 16:38:49.414710 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-09-18 16:38:49.864031 | debian-bookworm -> localhost | ok 2025-09-18 16:38:49.870479 | 2025-09-18 16:38:49.870597 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-09-18 16:38:49.907766 | debian-bookworm | ok 2025-09-18 16:38:49.932674 | debian-bookworm | included: /var/lib/zuul/builds/b2762b439002474e99e818401a65282d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-09-18 16:38:49.938987 | 2025-09-18 16:38:49.939161 | TASK [add-build-sshkey : Create Temp SSH key] 2025-09-18 16:38:51.981648 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-09-18 16:38:51.982012 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/b2762b439002474e99e818401a65282d/work/b2762b439002474e99e818401a65282d_id_rsa 2025-09-18 16:38:51.982102 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/b2762b439002474e99e818401a65282d/work/b2762b439002474e99e818401a65282d_id_rsa.pub 2025-09-18 16:38:51.982163 | debian-bookworm -> localhost | The key fingerprint is: 2025-09-18 16:38:51.982221 | debian-bookworm -> localhost | SHA256:WfnnmbyYKE0ouUDLd/1yvlVCefPAwR70yOQaVG4EYgo zuul-build-sshkey 2025-09-18 16:38:51.982273 | debian-bookworm -> localhost | The key's randomart image is: 2025-09-18 16:38:51.982340 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-09-18 16:38:51.982412 | debian-bookworm -> localhost | | E o o=* | 2025-09-18 16:38:51.982465 | debian-bookworm -> localhost | | . o + Bo= | 2025-09-18 16:38:51.982513 | debian-bookworm -> localhost | | . o ..@oo| 2025-09-18 16:38:51.982560 | debian-bookworm -> localhost | | . o . =.oo| 2025-09-18 16:38:51.982606 | debian-bookworm -> localhost | | o . .So o o o| 2025-09-18 16:38:51.982667 | debian-bookworm -> localhost | | + + o o + = | 2025-09-18 16:38:51.982716 | debian-bookworm -> localhost | | o + o . * | 2025-09-18 16:38:51.982763 | debian-bookworm -> localhost | | . . o.o+ . | 2025-09-18 16:38:51.982812 | debian-bookworm -> localhost | | ..+=.. | 2025-09-18 16:38:51.982895 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-09-18 16:38:51.983003 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.476270 2025-09-18 16:38:51.993727 | 2025-09-18 16:38:51.993855 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-09-18 16:38:52.023074 | debian-bookworm | ok 2025-09-18 16:38:52.032974 | debian-bookworm | included: /var/lib/zuul/builds/b2762b439002474e99e818401a65282d/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-09-18 16:38:52.041454 | 2025-09-18 16:38:52.041581 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-09-18 16:38:52.064383 | debian-bookworm | skipping: Conditional result was False 2025-09-18 16:38:52.070969 | 2025-09-18 16:38:52.071064 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-09-18 16:38:52.677062 | debian-bookworm | changed 2025-09-18 16:38:52.683435 | 2025-09-18 16:38:52.683531 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-09-18 16:38:52.952395 | debian-bookworm | ok 2025-09-18 16:38:52.958115 | 2025-09-18 16:38:52.958207 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-09-18 16:38:53.636993 | debian-bookworm | changed 2025-09-18 16:38:53.645393 | 2025-09-18 16:38:53.645499 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-09-18 16:38:54.356493 | debian-bookworm | changed 2025-09-18 16:38:54.367131 | 2025-09-18 16:38:54.367225 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-09-18 16:38:54.410217 | debian-bookworm | skipping: Conditional result was False 2025-09-18 16:38:54.421315 | 2025-09-18 16:38:54.421481 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-09-18 16:38:54.873764 | debian-bookworm -> localhost | changed 2025-09-18 16:38:54.884073 | 2025-09-18 16:38:54.884166 | TASK [add-build-sshkey : Add back temp key] 2025-09-18 16:38:55.234511 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/b2762b439002474e99e818401a65282d/work/b2762b439002474e99e818401a65282d_id_rsa (zuul-build-sshkey) 2025-09-18 16:38:55.234723 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.026621 2025-09-18 16:38:55.244159 | 2025-09-18 16:38:55.244253 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-09-18 16:38:55.642632 | debian-bookworm | ok 2025-09-18 16:38:55.648563 | 2025-09-18 16:38:55.648655 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-09-18 16:38:55.702592 | debian-bookworm | skipping: Conditional result was False 2025-09-18 16:38:55.732879 | 2025-09-18 16:38:55.732987 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-09-18 16:38:56.404441 | debian-bookworm | ok 2025-09-18 16:38:56.422404 | 2025-09-18 16:38:56.422520 | TASK [validate-host : Define zuul_info_dir fact] 2025-09-18 16:38:56.460566 | debian-bookworm | ok 2025-09-18 16:38:56.465292 | 2025-09-18 16:38:56.465387 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-09-18 16:38:56.776682 | debian-bookworm -> localhost | ok 2025-09-18 16:38:56.782612 | 2025-09-18 16:38:56.782694 | TASK [validate-host : Collect information about the host] 2025-09-18 16:38:57.813752 | debian-bookworm | ok 2025-09-18 16:38:57.825212 | 2025-09-18 16:38:57.825336 | TASK [validate-host : Sanitize hostname] 2025-09-18 16:38:58.078637 | debian-bookworm | ok 2025-09-18 16:38:58.157743 | 2025-09-18 16:38:58.157880 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-09-18 16:38:58.933747 | debian-bookworm -> localhost | changed 2025-09-18 16:38:58.939777 | 2025-09-18 16:38:58.939893 | TASK [validate-host : Collect information about zuul worker] 2025-09-18 16:38:59.395705 | debian-bookworm | ok 2025-09-18 16:38:59.404835 | 2025-09-18 16:38:59.404967 | TASK [validate-host : Write out all zuul information for each host] 2025-09-18 16:38:59.980099 | debian-bookworm -> localhost | changed 2025-09-18 16:38:59.999519 | 2025-09-18 16:38:59.999657 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-09-18 16:39:00.283731 | debian-bookworm | ok 2025-09-18 16:39:00.289588 | 2025-09-18 16:39:00.289698 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-09-18 16:39:01.569207 | debian-bookworm | changed: 2025-09-18 16:39:01.569452 | debian-bookworm | cd+++++++++ src/ 2025-09-18 16:39:01.569491 | debian-bookworm | cd+++++++++ src/github.com/ 2025-09-18 16:39:01.569515 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-09-18 16:39:01.569537 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-09-18 16:39:01.569558 | debian-bookworm | localhost | ok: Runtime: 0:00:00.004094 2025-09-18 16:39:27.197339 | 2025-09-18 16:39:27.197493 | TASK [upload-logs : Upload logs to log server] 2025-09-18 16:39:27.732126 | localhost | Output suppressed because no_log was given 2025-09-18 16:39:27.736232 | 2025-09-18 16:39:27.736572 | LOOP [upload-logs : Compress console log and json output] 2025-09-18 16:39:27.788010 | localhost | skipping: Conditional result was False 2025-09-18 16:39:27.796175 | localhost | skipping: Conditional result was False 2025-09-18 16:39:27.806819 | 2025-09-18 16:39:27.806967 | LOOP [upload-logs : Upload compressed console log and json output] 2025-09-18 16:39:27.877555 | localhost | skipping: Conditional result was False 2025-09-18 16:39:27.878000 | 2025-09-18 16:39:27.882802 | localhost | skipping: Conditional result was False 2025-09-18 16:39:27.895692 | 2025-09-18 16:39:27.895814 | LOOP [upload-logs : Upload console log and json output]