2025-10-23 17:07:47.871595 | Job console starting 2025-10-23 17:07:47.926763 | Updating git repos 2025-10-23 17:07:47.968480 | Cloning repos into workspace 2025-10-23 17:07:48.020212 | Restoring repo states 2025-10-23 17:07:48.025430 | Merging changes 2025-10-23 17:07:48.578307 | Checking out repos 2025-10-23 17:07:48.653955 | Preparing playbooks 2025-10-23 17:07:49.252702 | Running Ansible setup 2025-10-23 17:07:53.681834 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-23 17:07:54.524408 | 2025-10-23 17:07:54.524563 | PLAY [Base pre] 2025-10-23 17:07:54.545204 | 2025-10-23 17:07:54.545348 | TASK [Setup log path fact] 2025-10-23 17:07:54.575247 | debian-bookworm | ok 2025-10-23 17:07:54.590079 | 2025-10-23 17:07:54.590217 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-23 17:07:54.630785 | debian-bookworm | ok 2025-10-23 17:07:54.640770 | 2025-10-23 17:07:54.640898 | TASK [emit-job-header : Print job information] 2025-10-23 17:07:54.698577 | # Job Information 2025-10-23 17:07:54.698807 | Ansible Version: 2.16.14 2025-10-23 17:07:54.698865 | Job: python-black 2025-10-23 17:07:54.698899 | Pipeline: check 2025-10-23 17:07:54.698923 | Executor: 521e9411259a 2025-10-23 17:07:54.698944 | Triggered by: https://github.com/osism/testbed/pull/2777 2025-10-23 17:07:54.698965 | Event ID: c704f690-b032-11f0-9b7c-d43f9c0b832a 2025-10-23 17:07:54.704167 | 2025-10-23 17:07:54.704280 | LOOP [emit-job-header : Print node information] 2025-10-23 17:07:54.816682 | debian-bookworm | ok: 2025-10-23 17:07:54.816891 | debian-bookworm | # Node Information 2025-10-23 17:07:54.816927 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-23 17:07:54.816952 | debian-bookworm | Hostname: debian 2025-10-23 17:07:54.816974 | debian-bookworm | Username: zuul 2025-10-23 17:07:54.816995 | debian-bookworm | Distro: Debian 12.12 2025-10-23 17:07:54.817058 | debian-bookworm | Provider: regiocloud-a 2025-10-23 17:07:54.817084 | debian-bookworm | Region: 2025-10-23 17:07:54.817105 | debian-bookworm | Label: debian-bookworm 2025-10-23 17:07:54.817125 | debian-bookworm | Product Name: OpenStack Nova 2025-10-23 17:07:54.817145 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe58:8919 2025-10-23 17:07:54.832056 | 2025-10-23 17:07:54.832211 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-23 17:07:55.450341 | debian-bookworm -> localhost | changed 2025-10-23 17:07:55.460502 | 2025-10-23 17:07:55.460642 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-23 17:07:56.498468 | debian-bookworm -> localhost | changed 2025-10-23 17:07:56.510703 | 2025-10-23 17:07:56.510818 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-23 17:07:56.847589 | debian-bookworm -> localhost | ok 2025-10-23 17:07:56.864521 | 2025-10-23 17:07:56.864791 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-23 17:07:56.895814 | debian-bookworm | ok 2025-10-23 17:07:56.924563 | debian-bookworm | included: /var/lib/zuul/builds/723269b3f7344e15b583eb6ad9a0accd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-23 17:07:56.930661 | 2025-10-23 17:07:56.930765 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-23 17:07:59.884436 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-23 17:07:59.884867 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/723269b3f7344e15b583eb6ad9a0accd/work/723269b3f7344e15b583eb6ad9a0accd_id_rsa 2025-10-23 17:07:59.884965 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/723269b3f7344e15b583eb6ad9a0accd/work/723269b3f7344e15b583eb6ad9a0accd_id_rsa.pub 2025-10-23 17:07:59.885048 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-23 17:07:59.885113 | debian-bookworm -> localhost | SHA256:Gz+VYFv/nzk+mgxPC0eaBORsBlJnYMSKqjVPnx/MGcQ zuul-build-sshkey 2025-10-23 17:07:59.885169 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-23 17:07:59.885241 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-23 17:07:59.885307 | debian-bookworm -> localhost | | .+*.+ | 2025-10-23 17:07:59.885363 | debian-bookworm -> localhost | | ooB | 2025-10-23 17:07:59.885414 | debian-bookworm -> localhost | | . . E*o . | 2025-10-23 17:07:59.885464 | debian-bookworm -> localhost | | . . .o..+ o | 2025-10-23 17:07:59.885512 | debian-bookworm -> localhost | | . S ..o.. | 2025-10-23 17:07:59.885578 | debian-bookworm -> localhost | | .o . o *..+ . | 2025-10-23 17:07:59.885634 | debian-bookworm -> localhost | |.. + . .* o= o .| 2025-10-23 17:07:59.885689 | debian-bookworm -> localhost | |. . o . .B oo+| 2025-10-23 17:07:59.885740 | debian-bookworm -> localhost | | .. *o++| 2025-10-23 17:07:59.885792 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-23 17:07:59.885912 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.468875 2025-10-23 17:07:59.896825 | 2025-10-23 17:07:59.896934 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-23 17:07:59.915942 | debian-bookworm | ok 2025-10-23 17:07:59.925697 | debian-bookworm | included: /var/lib/zuul/builds/723269b3f7344e15b583eb6ad9a0accd/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-23 17:07:59.934135 | 2025-10-23 17:07:59.934215 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-23 17:07:59.956706 | debian-bookworm | skipping: Conditional result was False 2025-10-23 17:07:59.962999 | 2025-10-23 17:07:59.963099 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-23 17:08:00.617304 | debian-bookworm | changed 2025-10-23 17:08:00.622370 | 2025-10-23 17:08:00.622451 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-23 17:08:00.882742 | debian-bookworm | ok 2025-10-23 17:08:00.887891 | 2025-10-23 17:08:00.887974 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-23 17:08:01.518286 | debian-bookworm | changed 2025-10-23 17:08:01.525587 | 2025-10-23 17:08:01.525726 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-23 17:08:02.139207 | debian-bookworm | changed 2025-10-23 17:08:02.144561 | 2025-10-23 17:08:02.144638 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-23 17:08:02.167153 | debian-bookworm | skipping: Conditional result was False 2025-10-23 17:08:02.174099 | 2025-10-23 17:08:02.174191 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-23 17:08:02.572179 | debian-bookworm -> localhost | changed 2025-10-23 17:08:02.591035 | 2025-10-23 17:08:02.591165 | TASK [add-build-sshkey : Add back temp key] 2025-10-23 17:08:02.907274 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/723269b3f7344e15b583eb6ad9a0accd/work/723269b3f7344e15b583eb6ad9a0accd_id_rsa (zuul-build-sshkey) 2025-10-23 17:08:02.907476 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016486 2025-10-23 17:08:02.914664 | 2025-10-23 17:08:02.914759 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-23 17:08:03.289534 | debian-bookworm | ok 2025-10-23 17:08:03.296145 | 2025-10-23 17:08:03.296244 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-23 17:08:03.320792 | debian-bookworm | skipping: Conditional result was False 2025-10-23 17:08:03.334815 | 2025-10-23 17:08:03.334938 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-23 17:08:03.754132 | debian-bookworm | ok 2025-10-23 17:08:03.769008 | 2025-10-23 17:08:03.769147 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-23 17:08:03.826440 | debian-bookworm | ok 2025-10-23 17:08:03.846181 | 2025-10-23 17:08:03.846279 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-23 17:08:04.101115 | debian-bookworm -> localhost | ok 2025-10-23 17:08:04.107227 | 2025-10-23 17:08:04.107315 | TASK [validate-host : Collect information about the host] 2025-10-23 17:08:05.215416 | debian-bookworm | ok 2025-10-23 17:08:05.227769 | 2025-10-23 17:08:05.227860 | TASK [validate-host : Sanitize hostname] 2025-10-23 17:08:05.336692 | debian-bookworm | ok 2025-10-23 17:08:05.364035 | 2025-10-23 17:08:05.364134 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-23 17:08:05.905769 | debian-bookworm -> localhost | changed 2025-10-23 17:08:05.911037 | 2025-10-23 17:08:05.911121 | TASK [validate-host : Collect information about zuul worker] 2025-10-23 17:08:06.317843 | debian-bookworm | ok 2025-10-23 17:08:06.329563 | 2025-10-23 17:08:06.329839 | TASK [validate-host : Write out all zuul information for each host] 2025-10-23 17:08:06.919195 | debian-bookworm -> localhost | changed 2025-10-23 17:08:06.938758 | 2025-10-23 17:08:06.938914 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-23 17:08:07.215867 | debian-bookworm | ok 2025-10-23 17:08:07.222375 | 2025-10-23 17:08:07.222495 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-23 17:08:08.365677 | debian-bookworm | changed: 2025-10-23 17:08:08.365866 | debian-bookworm | cd+++++++++ src/ 2025-10-23 17:08:08.365901 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-23 17:08:08.365926 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-23 17:08:08.365948 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2025-10-23 17:08:08.365968 | debian-bookworm | localhost | ok: Runtime: 0:00:00.006910 2025-10-23 17:08:33.550962 | 2025-10-23 17:08:33.551120 | TASK [upload-logs : Upload logs to log server] 2025-10-23 17:08:34.157703 | localhost | Output suppressed because no_log was given 2025-10-23 17:08:34.159617 | 2025-10-23 17:08:34.159721 | LOOP [upload-logs : Compress console log and json output] 2025-10-23 17:08:34.221265 | localhost | skipping: Conditional result was False 2025-10-23 17:08:34.231125 | localhost | skipping: Conditional result was False 2025-10-23 17:08:34.240028 | 2025-10-23 17:08:34.240406 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-23 17:08:34.293358 | localhost | skipping: Conditional result was False 2025-10-23 17:08:34.293728 | 2025-10-23 17:08:34.297985 | localhost | skipping: Conditional result was False 2025-10-23 17:08:34.301973 | 2025-10-23 17:08:34.302111 | LOOP [upload-logs : Upload console log and json output]