2025-10-12 15:09:02.841313 | Job console starting 2025-10-12 15:09:02.875118 | Updating git repos 2025-10-12 15:09:02.978664 | Cloning repos into workspace 2025-10-12 15:09:03.057410 | Restoring repo states 2025-10-12 15:09:03.075026 | Merging changes 2025-10-12 15:09:03.684993 | Checking out repos 2025-10-12 15:09:03.800712 | Preparing playbooks 2025-10-12 15:09:04.989403 | Running Ansible setup 2025-10-12 15:09:10.871027 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-12 15:09:12.451129 | 2025-10-12 15:09:12.451287 | PLAY [Base pre] 2025-10-12 15:09:12.489324 | 2025-10-12 15:09:12.489461 | TASK [Setup log path fact] 2025-10-12 15:09:12.525830 | debian-bookworm | ok 2025-10-12 15:09:12.552456 | 2025-10-12 15:09:12.552593 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-12 15:09:12.585986 | debian-bookworm | ok 2025-10-12 15:09:12.598119 | 2025-10-12 15:09:12.598220 | TASK [emit-job-header : Print job information] 2025-10-12 15:09:12.635863 | # Job Information 2025-10-12 15:09:12.636028 | Ansible Version: 2.16.14 2025-10-12 15:09:12.636063 | Job: python-black 2025-10-12 15:09:12.636094 | Pipeline: check 2025-10-12 15:09:12.636117 | Executor: 521e9411259a 2025-10-12 15:09:12.636137 | Triggered by: https://github.com/osism/python-osism/pull/1750 2025-10-12 15:09:12.636159 | Event ID: 5e130220-a77d-11f0-9247-13df84bf2db7 2025-10-12 15:09:12.640773 | 2025-10-12 15:09:12.640861 | LOOP [emit-job-header : Print node information] 2025-10-12 15:09:12.758921 | debian-bookworm | ok: 2025-10-12 15:09:12.759077 | debian-bookworm | # Node Information 2025-10-12 15:09:12.759110 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-12 15:09:12.759135 | debian-bookworm | Hostname: debian 2025-10-12 15:09:12.759156 | debian-bookworm | Username: zuul 2025-10-12 15:09:12.759176 | debian-bookworm | Distro: Debian 12.12 2025-10-12 15:09:12.759199 | debian-bookworm | Provider: regiocloud-a 2025-10-12 15:09:12.759220 | debian-bookworm | Region: 2025-10-12 15:09:12.759240 | debian-bookworm | Label: debian-bookworm 2025-10-12 15:09:12.759260 | debian-bookworm | Product Name: OpenStack Nova 2025-10-12 15:09:12.759279 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feed:2d3e 2025-10-12 15:09:12.777075 | 2025-10-12 15:09:12.777186 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-12 15:09:13.523148 | debian-bookworm -> localhost | changed 2025-10-12 15:09:13.528711 | 2025-10-12 15:09:13.528799 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-12 15:09:14.762299 | debian-bookworm -> localhost | changed 2025-10-12 15:09:14.771164 | 2025-10-12 15:09:14.771254 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-12 15:09:15.110466 | debian-bookworm -> localhost | ok 2025-10-12 15:09:15.115487 | 2025-10-12 15:09:15.115573 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-12 15:09:15.142317 | debian-bookworm | ok 2025-10-12 15:09:15.157182 | debian-bookworm | included: /var/lib/zuul/builds/81095a950dd14b22a8936373cf2ef069/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-12 15:09:15.162083 | 2025-10-12 15:09:15.162159 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-12 15:09:16.098730 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-12 15:09:16.098938 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/81095a950dd14b22a8936373cf2ef069/work/81095a950dd14b22a8936373cf2ef069_id_rsa 2025-10-12 15:09:16.098974 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/81095a950dd14b22a8936373cf2ef069/work/81095a950dd14b22a8936373cf2ef069_id_rsa.pub 2025-10-12 15:09:16.098996 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-12 15:09:16.099017 | debian-bookworm -> localhost | SHA256:L1sqWG1a14BB/4xnPbwuM3Zp6Yzw+2pyO9blVDwH4wo zuul-build-sshkey 2025-10-12 15:09:16.099036 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-12 15:09:16.099061 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-12 15:09:16.099079 | debian-bookworm -> localhost | | .. | 2025-10-12 15:09:16.099096 | debian-bookworm -> localhost | | .. o | 2025-10-12 15:09:16.099113 | debian-bookworm -> localhost | | o. . + | 2025-10-12 15:09:16.099135 | debian-bookworm -> localhost | | . E+ o..+| 2025-10-12 15:09:16.099152 | debian-bookworm -> localhost | | .S .+=.+ +| 2025-10-12 15:09:16.099171 | debian-bookworm -> localhost | | . +..oo oo| 2025-10-12 15:09:16.099188 | debian-bookworm -> localhost | | o +..+ ..* | 2025-10-12 15:09:16.099204 | debian-bookworm -> localhost | | . o =.oX+* .| 2025-10-12 15:09:16.099221 | debian-bookworm -> localhost | | .o **&= | 2025-10-12 15:09:16.099237 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-12 15:09:16.099282 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.462397 2025-10-12 15:09:16.104291 | 2025-10-12 15:09:16.104364 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-12 15:09:16.140435 | debian-bookworm | ok 2025-10-12 15:09:16.147563 | debian-bookworm | included: /var/lib/zuul/builds/81095a950dd14b22a8936373cf2ef069/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-12 15:09:16.168530 | 2025-10-12 15:09:16.168599 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-12 15:09:16.197151 | debian-bookworm | skipping: Conditional result was False 2025-10-12 15:09:16.201591 | 2025-10-12 15:09:16.201661 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-12 15:09:16.932823 | debian-bookworm | changed 2025-10-12 15:09:16.937064 | 2025-10-12 15:09:16.937136 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-12 15:09:17.192012 | debian-bookworm | ok 2025-10-12 15:09:17.196322 | 2025-10-12 15:09:17.196397 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-12 15:09:17.888763 | debian-bookworm | changed 2025-10-12 15:09:17.900740 | 2025-10-12 15:09:17.900845 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-12 15:09:18.559087 | debian-bookworm | changed 2025-10-12 15:09:18.565366 | 2025-10-12 15:09:18.565466 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-12 15:09:18.579785 | debian-bookworm | skipping: Conditional result was False 2025-10-12 15:09:18.586797 | 2025-10-12 15:09:18.587163 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-12 15:09:18.990700 | debian-bookworm -> localhost | changed 2025-10-12 15:09:18.999749 | 2025-10-12 15:09:18.999831 | TASK [add-build-sshkey : Add back temp key] 2025-10-12 15:09:19.245416 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/81095a950dd14b22a8936373cf2ef069/work/81095a950dd14b22a8936373cf2ef069_id_rsa (zuul-build-sshkey) 2025-10-12 15:09:19.245591 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012084 2025-10-12 15:09:19.252799 | 2025-10-12 15:09:19.252879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-12 15:09:19.623338 | debian-bookworm | ok 2025-10-12 15:09:19.628081 | 2025-10-12 15:09:19.628169 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-12 15:09:19.662288 | debian-bookworm | skipping: Conditional result was False 2025-10-12 15:09:19.672111 | 2025-10-12 15:09:19.672205 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-12 15:09:20.112645 | debian-bookworm | ok 2025-10-12 15:09:20.128998 | 2025-10-12 15:09:20.129100 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-12 15:09:20.185049 | debian-bookworm | ok 2025-10-12 15:09:20.190094 | 2025-10-12 15:09:20.190185 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-12 15:09:20.591456 | debian-bookworm -> localhost | ok 2025-10-12 15:09:20.597414 | 2025-10-12 15:09:20.597503 | TASK [validate-host : Collect information about the host] 2025-10-12 15:09:21.832641 | debian-bookworm | ok 2025-10-12 15:09:21.842700 | 2025-10-12 15:09:21.842790 | TASK [validate-host : Sanitize hostname] 2025-10-12 15:09:21.933620 | debian-bookworm | ok 2025-10-12 15:09:21.973299 | 2025-10-12 15:09:21.973395 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-12 15:09:22.572952 | debian-bookworm -> localhost | changed 2025-10-12 15:09:22.578364 | 2025-10-12 15:09:22.578463 | TASK [validate-host : Collect information about zuul worker] 2025-10-12 15:09:22.999031 | debian-bookworm | ok 2025-10-12 15:09:23.003552 | 2025-10-12 15:09:23.003646 | TASK [validate-host : Write out all zuul information for each host] 2025-10-12 15:09:23.474532 | debian-bookworm -> localhost | changed 2025-10-12 15:09:23.483219 | 2025-10-12 15:09:23.483307 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-12 15:09:23.741826 | debian-bookworm | ok 2025-10-12 15:09:23.750983 | 2025-10-12 15:09:23.751090 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-12 15:09:24.971673 | debian-bookworm | changed: 2025-10-12 15:09:24.971844 | debian-bookworm | cd+++++++++ src/ 2025-10-12 15:09:24.971878 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-12 15:09:24.971914 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-12 15:09:24.971936 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-10-12 15:09:24.971956 | debian-bookworm | Containerfile 2025-10-12 15:09:24.972067 | debian-bookworm | Containerfile 2025-10-12 15:09:24.998945 | debian-bookworm | localhost | ok: Runtime: 0:00:00.005752 2025-10-12 15:09:50.878376 | 2025-10-12 15:09:50.878583 | TASK [upload-logs : Upload logs to log server] 2025-10-12 15:09:51.370973 | localhost | Output suppressed because no_log was given 2025-10-12 15:09:51.376443 | 2025-10-12 15:09:51.376668 | LOOP [upload-logs : Compress console log and json output] 2025-10-12 15:09:51.432502 | localhost | skipping: Conditional result was False 2025-10-12 15:09:51.436689 | localhost | skipping: Conditional result was False 2025-10-12 15:09:51.448691 | 2025-10-12 15:09:51.448853 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-12 15:09:51.487478 | localhost | skipping: Conditional result was False 2025-10-12 15:09:51.487884 | 2025-10-12 15:09:51.491321 | localhost | skipping: Conditional result was False 2025-10-12 15:09:51.502922 | 2025-10-12 15:09:51.503104 | LOOP [upload-logs : Upload console log and json output]