2025-12-04 10:29:21.687213 | Job console starting 2025-12-04 10:29:21.709663 | Updating git repos 2025-12-04 10:29:21.885337 | Cloning repos into workspace 2025-12-04 10:29:22.010121 | Restoring repo states 2025-12-04 10:29:22.036187 | Merging changes 2025-12-04 10:29:22.778419 | Checking out repos 2025-12-04 10:29:22.892943 | Preparing playbooks 2025-12-04 10:29:24.179754 | Running Ansible setup 2025-12-04 10:29:29.624405 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-12-04 10:29:30.721161 | 2025-12-04 10:29:30.721411 | PLAY [Base pre] 2025-12-04 10:29:30.744153 | 2025-12-04 10:29:30.744327 | TASK [Setup log path fact] 2025-12-04 10:29:30.765477 | debian-bookworm | ok 2025-12-04 10:29:30.785321 | 2025-12-04 10:29:30.785498 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-04 10:29:30.817110 | debian-bookworm | ok 2025-12-04 10:29:30.829841 | 2025-12-04 10:29:30.829992 | TASK [emit-job-header : Print job information] 2025-12-04 10:29:30.911902 | # Job Information 2025-12-04 10:29:30.912128 | Ansible Version: 2.16.14 2025-12-04 10:29:30.912166 | Job: python-black 2025-12-04 10:29:30.912197 | Pipeline: check 2025-12-04 10:29:30.912220 | Executor: 521e9411259a 2025-12-04 10:29:30.912241 | Triggered by: https://github.com/osism/python-osism/pull/1883 2025-12-04 10:29:30.912264 | Event ID: 129f9670-d0fc-11f0-8ca2-af40703446c0 2025-12-04 10:29:30.917555 | 2025-12-04 10:29:30.917692 | LOOP [emit-job-header : Print node information] 2025-12-04 10:29:31.186592 | debian-bookworm | ok: 2025-12-04 10:29:31.186965 | debian-bookworm | # Node Information 2025-12-04 10:29:31.187013 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-12-04 10:29:31.187040 | debian-bookworm | Hostname: debian 2025-12-04 10:29:31.187063 | debian-bookworm | Username: zuul 2025-12-04 10:29:31.187084 | debian-bookworm | Distro: Debian 12.12 2025-12-04 10:29:31.187107 | debian-bookworm | Provider: regiocloud-a 2025-12-04 10:29:31.187128 | debian-bookworm | Region: 2025-12-04 10:29:31.187150 | debian-bookworm | Label: debian-bookworm 2025-12-04 10:29:31.187291 | debian-bookworm | Product Name: OpenStack Nova 2025-12-04 10:29:31.187432 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe55:9fdc 2025-12-04 10:29:31.213130 | 2025-12-04 10:29:31.213302 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-04 10:29:32.410945 | debian-bookworm -> localhost | changed 2025-12-04 10:29:32.420196 | 2025-12-04 10:29:32.420351 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-04 10:29:34.707484 | debian-bookworm -> localhost | changed 2025-12-04 10:29:34.723293 | 2025-12-04 10:29:34.723442 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-04 10:29:35.405490 | debian-bookworm -> localhost | ok 2025-12-04 10:29:35.416329 | 2025-12-04 10:29:35.416468 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-04 10:29:35.467499 | debian-bookworm | ok 2025-12-04 10:29:35.504905 | debian-bookworm | included: /var/lib/zuul/builds/2330c649da334b469a41fe6fe4212014/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-04 10:29:35.511579 | 2025-12-04 10:29:35.511750 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-04 10:29:38.393823 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-12-04 10:29:38.394019 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/2330c649da334b469a41fe6fe4212014/work/2330c649da334b469a41fe6fe4212014_id_rsa 2025-12-04 10:29:38.394057 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/2330c649da334b469a41fe6fe4212014/work/2330c649da334b469a41fe6fe4212014_id_rsa.pub 2025-12-04 10:29:38.394083 | debian-bookworm -> localhost | The key fingerprint is: 2025-12-04 10:29:38.394106 | debian-bookworm -> localhost | SHA256:zyp4FZwD8Q/o7GW81j9DinlPG8GpEW5lX7sGGzFem20 zuul-build-sshkey 2025-12-04 10:29:38.394128 | debian-bookworm -> localhost | The key's randomart image is: 2025-12-04 10:29:38.394160 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-12-04 10:29:38.394181 | debian-bookworm -> localhost | | .. | 2025-12-04 10:29:38.394203 | debian-bookworm -> localhost | | .o | 2025-12-04 10:29:38.394223 | debian-bookworm -> localhost | | .oo.. oo ..| 2025-12-04 10:29:38.394243 | debian-bookworm -> localhost | | o .=+ =.o+.=| 2025-12-04 10:29:38.394263 | debian-bookworm -> localhost | | o So= ++.+E| 2025-12-04 10:29:38.394283 | debian-bookworm -> localhost | | . o.* o..+..| 2025-12-04 10:29:38.394303 | debian-bookworm -> localhost | | ...oo=oo. o | 2025-12-04 10:29:38.394323 | debian-bookworm -> localhost | | . o.o.oooo. | 2025-12-04 10:29:38.394343 | debian-bookworm -> localhost | | . ... .+o | 2025-12-04 10:29:38.394364 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-12-04 10:29:38.394408 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.080231 2025-12-04 10:29:38.405606 | 2025-12-04 10:29:38.405733 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-04 10:29:38.434199 | debian-bookworm | ok 2025-12-04 10:29:38.443097 | debian-bookworm | included: /var/lib/zuul/builds/2330c649da334b469a41fe6fe4212014/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-04 10:29:38.451910 | 2025-12-04 10:29:38.452000 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-04 10:29:38.475047 | debian-bookworm | skipping: Conditional result was False 2025-12-04 10:29:38.481026 | 2025-12-04 10:29:38.481124 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-04 10:29:39.087263 | debian-bookworm | changed 2025-12-04 10:29:39.096309 | 2025-12-04 10:29:39.096422 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-04 10:29:39.342257 | debian-bookworm | ok 2025-12-04 10:29:39.347574 | 2025-12-04 10:29:39.347670 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-04 10:29:39.995660 | debian-bookworm | changed 2025-12-04 10:29:40.006926 | 2025-12-04 10:29:40.007053 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-04 10:29:40.679796 | debian-bookworm | changed 2025-12-04 10:29:40.688519 | 2025-12-04 10:29:40.697329 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-04 10:29:40.747231 | debian-bookworm | skipping: Conditional result was False 2025-12-04 10:29:40.753937 | 2025-12-04 10:29:40.754066 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-04 10:29:41.497076 | debian-bookworm -> localhost | changed 2025-12-04 10:29:41.517400 | 2025-12-04 10:29:41.517561 | TASK [add-build-sshkey : Add back temp key] 2025-12-04 10:29:42.161829 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/2330c649da334b469a41fe6fe4212014/work/2330c649da334b469a41fe6fe4212014_id_rsa (zuul-build-sshkey) 2025-12-04 10:29:42.162174 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.018692 2025-12-04 10:29:42.179100 | 2025-12-04 10:29:42.179240 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-04 10:29:42.721904 | debian-bookworm | ok 2025-12-04 10:29:42.730608 | 2025-12-04 10:29:42.730765 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-04 10:29:42.778876 | debian-bookworm | skipping: Conditional result was False 2025-12-04 10:29:42.794964 | 2025-12-04 10:29:42.795119 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-04 10:29:43.437939 | debian-bookworm | ok 2025-12-04 10:29:43.452249 | 2025-12-04 10:29:43.452391 | TASK [validate-host : Define zuul_info_dir fact] 2025-12-04 10:29:43.489254 | debian-bookworm | ok 2025-12-04 10:29:43.496042 | 2025-12-04 10:29:43.496168 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-12-04 10:29:43.803698 | debian-bookworm -> localhost | ok 2025-12-04 10:29:43.810463 | 2025-12-04 10:29:43.812024 | TASK [validate-host : Collect information about the host] 2025-12-04 10:29:45.019632 | debian-bookworm | ok 2025-12-04 10:29:45.042908 | 2025-12-04 10:29:45.043054 | TASK [validate-host : Sanitize hostname] 2025-12-04 10:29:45.250589 | debian-bookworm | ok 2025-12-04 10:29:45.301476 | 2025-12-04 10:29:45.301617 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-12-04 10:29:45.987807 | debian-bookworm -> localhost | changed 2025-12-04 10:29:45.993826 | 2025-12-04 10:29:45.993960 | TASK [validate-host : Collect information about zuul worker] 2025-12-04 10:29:46.503970 | debian-bookworm | ok 2025-12-04 10:29:46.511269 | 2025-12-04 10:29:46.511415 | TASK [validate-host : Write out all zuul information for each host] 2025-12-04 10:29:47.346588 | debian-bookworm -> localhost | changed 2025-12-04 10:29:47.356789 | 2025-12-04 10:29:47.356923 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-12-04 10:29:47.628670 | debian-bookworm | ok 2025-12-04 10:29:47.636344 | 2025-12-04 10:29:47.636474 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-12-04 10:29:48.968602 | debian-bookworm | changed: 2025-12-04 10:29:48.968860 | debian-bookworm | cd+++++++++ src/ 2025-12-04 10:29:48.968898 | debian-bookworm | cd+++++++++ src/github.com/ 2025-12-04 10:29:48.968923 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-12-04 10:29:48.968945 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-12-04 10:29:48.968964 | debian-bookworm | Containerfile 2025-12-04 10:29:48.969095 | debian-bookworm | Containerfile 2025-12-04 10:29:49.032378 | debian-bookworm | localhost | ok: Runtime: 0:00:00.008909 2025-12-04 10:30:17.540624 | 2025-12-04 10:30:17.540810 | TASK [upload-logs : Upload logs to log server] 2025-12-04 10:30:18.181303 | localhost | Output suppressed because no_log was given 2025-12-04 10:30:18.184540 | 2025-12-04 10:30:18.184711 | LOOP [upload-logs : Compress console log and json output] 2025-12-04 10:30:18.248219 | localhost | skipping: Conditional result was False 2025-12-04 10:30:18.259095 | localhost | skipping: Conditional result was False 2025-12-04 10:30:18.266493 | 2025-12-04 10:30:18.266798 | LOOP [upload-logs : Upload compressed console log and json output] 2025-12-04 10:30:18.330922 | localhost | skipping: Conditional result was False 2025-12-04 10:30:18.331375 | 2025-12-04 10:30:18.338538 | localhost | skipping: Conditional result was False 2025-12-04 10:30:18.352050 | 2025-12-04 10:30:18.352274 | LOOP [upload-logs : Upload console log and json output]