2025-10-21 16:15:05.912076 | Job console starting 2025-10-21 16:15:05.927715 | Updating git repos 2025-10-21 16:15:05.970036 | Cloning repos into workspace 2025-10-21 16:15:06.016102 | Restoring repo states 2025-10-21 16:15:06.028210 | Merging changes 2025-10-21 16:15:06.576079 | Checking out repos 2025-10-21 16:15:06.643260 | Preparing playbooks 2025-10-21 16:15:07.228974 | Running Ansible setup 2025-10-21 16:15:11.585672 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2025-10-21 16:15:12.317740 | 2025-10-21 16:15:12.317892 | PLAY [Base pre] 2025-10-21 16:15:12.333610 | 2025-10-21 16:15:12.333735 | TASK [Setup log path fact] 2025-10-21 16:15:12.380397 | debian-bookworm | ok 2025-10-21 16:15:12.419604 | 2025-10-21 16:15:12.419780 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-21 16:15:12.459858 | debian-bookworm | ok 2025-10-21 16:15:12.469785 | 2025-10-21 16:15:12.469899 | TASK [emit-job-header : Print job information] 2025-10-21 16:15:12.509519 | # Job Information 2025-10-21 16:15:12.509697 | Ansible Version: 2.16.14 2025-10-21 16:15:12.509733 | Job: python-black 2025-10-21 16:15:12.509767 | Pipeline: check 2025-10-21 16:15:12.509790 | Executor: 521e9411259a 2025-10-21 16:15:12.509811 | Triggered by: https://github.com/osism/python-osism/pull/1768 2025-10-21 16:15:12.509833 | Event ID: e5267450-ae98-11f0-8eed-d8e80a7aebb3 2025-10-21 16:15:12.514766 | 2025-10-21 16:15:12.514918 | LOOP [emit-job-header : Print node information] 2025-10-21 16:15:12.630626 | debian-bookworm | ok: 2025-10-21 16:15:12.630864 | debian-bookworm | # Node Information 2025-10-21 16:15:12.630922 | debian-bookworm | Inventory Hostname: debian-bookworm 2025-10-21 16:15:12.630963 | debian-bookworm | Hostname: debian 2025-10-21 16:15:12.631000 | debian-bookworm | Username: zuul 2025-10-21 16:15:12.631035 | debian-bookworm | Distro: Debian 12.12 2025-10-21 16:15:12.631075 | debian-bookworm | Provider: regiocloud-a 2025-10-21 16:15:12.631110 | debian-bookworm | Region: 2025-10-21 16:15:12.631145 | debian-bookworm | Label: debian-bookworm 2025-10-21 16:15:12.631178 | debian-bookworm | Product Name: OpenStack Nova 2025-10-21 16:15:12.631210 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe05:ea0f 2025-10-21 16:15:12.652193 | 2025-10-21 16:15:12.652348 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-21 16:15:13.103198 | debian-bookworm -> localhost | changed 2025-10-21 16:15:13.115777 | 2025-10-21 16:15:13.116017 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-21 16:15:14.228150 | debian-bookworm -> localhost | changed 2025-10-21 16:15:14.239870 | 2025-10-21 16:15:14.239990 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-21 16:15:14.507702 | debian-bookworm -> localhost | ok 2025-10-21 16:15:14.514227 | 2025-10-21 16:15:14.514346 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-21 16:15:14.533750 | debian-bookworm | ok 2025-10-21 16:15:14.552273 | debian-bookworm | included: /var/lib/zuul/builds/14c2b5fb6815445cad69e2229301becb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-21 16:15:14.559171 | 2025-10-21 16:15:14.559273 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-21 16:15:15.706674 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2025-10-21 16:15:15.706975 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/14c2b5fb6815445cad69e2229301becb/work/14c2b5fb6815445cad69e2229301becb_id_rsa 2025-10-21 16:15:15.707028 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/14c2b5fb6815445cad69e2229301becb/work/14c2b5fb6815445cad69e2229301becb_id_rsa.pub 2025-10-21 16:15:15.707057 | debian-bookworm -> localhost | The key fingerprint is: 2025-10-21 16:15:15.707082 | debian-bookworm -> localhost | SHA256:oNL+VJt7cqKT+mk2e4E8ClqXst3ZL7/dunwWBKqBC+Y zuul-build-sshkey 2025-10-21 16:15:15.707106 | debian-bookworm -> localhost | The key's randomart image is: 2025-10-21 16:15:15.707146 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2025-10-21 16:15:15.707169 | debian-bookworm -> localhost | | | 2025-10-21 16:15:15.707192 | debian-bookworm -> localhost | | . | 2025-10-21 16:15:15.707212 | debian-bookworm -> localhost | | .. . . | 2025-10-21 16:15:15.707233 | debian-bookworm -> localhost | | .o.... . . | 2025-10-21 16:15:15.707253 | debian-bookworm -> localhost | | .oo+ oSo . | 2025-10-21 16:15:15.707280 | debian-bookworm -> localhost | | +oE =.oo . | 2025-10-21 16:15:15.707301 | debian-bookworm -> localhost | | o *.o.=o. . | 2025-10-21 16:15:15.707322 | debian-bookworm -> localhost | |. . ooO.*.oo .o | 2025-10-21 16:15:15.707343 | debian-bookworm -> localhost | | .=**.O+.==. | 2025-10-21 16:15:15.707364 | debian-bookworm -> localhost | +----[SHA256]-----+ 2025-10-21 16:15:15.707439 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.625623 2025-10-21 16:15:15.714020 | 2025-10-21 16:15:15.714130 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-21 16:15:15.739495 | debian-bookworm | ok 2025-10-21 16:15:15.748992 | debian-bookworm | included: /var/lib/zuul/builds/14c2b5fb6815445cad69e2229301becb/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-21 16:15:15.757991 | 2025-10-21 16:15:15.758088 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-21 16:15:15.771728 | debian-bookworm | skipping: Conditional result was False 2025-10-21 16:15:15.778610 | 2025-10-21 16:15:15.778712 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-21 16:15:16.421573 | debian-bookworm | changed 2025-10-21 16:15:16.433673 | 2025-10-21 16:15:16.433838 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-21 16:15:16.724495 | debian-bookworm | ok 2025-10-21 16:15:16.734182 | 2025-10-21 16:15:16.734335 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-21 16:15:17.492513 | debian-bookworm | changed 2025-10-21 16:15:17.504648 | 2025-10-21 16:15:17.504799 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-21 16:15:18.277543 | debian-bookworm | changed 2025-10-21 16:15:18.287528 | 2025-10-21 16:15:18.287676 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-21 16:15:18.312037 | debian-bookworm | skipping: Conditional result was False 2025-10-21 16:15:18.326217 | 2025-10-21 16:15:18.326412 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-21 16:15:18.796903 | debian-bookworm -> localhost | changed 2025-10-21 16:15:18.812955 | 2025-10-21 16:15:18.813157 | TASK [add-build-sshkey : Add back temp key] 2025-10-21 16:15:19.158643 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/14c2b5fb6815445cad69e2229301becb/work/14c2b5fb6815445cad69e2229301becb_id_rsa (zuul-build-sshkey) 2025-10-21 16:15:19.159176 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014447 2025-10-21 16:15:19.170490 | 2025-10-21 16:15:19.170611 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-21 16:15:19.575469 | debian-bookworm | ok 2025-10-21 16:15:19.584175 | 2025-10-21 16:15:19.584314 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-21 16:15:19.618890 | debian-bookworm | skipping: Conditional result was False 2025-10-21 16:15:19.637063 | 2025-10-21 16:15:19.637181 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-21 16:15:20.101191 | debian-bookworm | ok 2025-10-21 16:15:20.117831 | 2025-10-21 16:15:20.117966 | TASK [validate-host : Define zuul_info_dir fact] 2025-10-21 16:15:20.163321 | debian-bookworm | ok 2025-10-21 16:15:20.174363 | 2025-10-21 16:15:20.174557 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2025-10-21 16:15:20.496075 | debian-bookworm -> localhost | ok 2025-10-21 16:15:20.503171 | 2025-10-21 16:15:20.503292 | TASK [validate-host : Collect information about the host] 2025-10-21 16:15:21.663770 | debian-bookworm | ok 2025-10-21 16:15:21.675256 | 2025-10-21 16:15:21.675376 | TASK [validate-host : Sanitize hostname] 2025-10-21 16:15:21.786215 | debian-bookworm | ok 2025-10-21 16:15:21.829185 | 2025-10-21 16:15:21.829367 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2025-10-21 16:15:22.627524 | debian-bookworm -> localhost | changed 2025-10-21 16:15:22.633826 | 2025-10-21 16:15:22.633948 | TASK [validate-host : Collect information about zuul worker] 2025-10-21 16:15:23.070768 | debian-bookworm | ok 2025-10-21 16:15:23.075985 | 2025-10-21 16:15:23.076101 | TASK [validate-host : Write out all zuul information for each host] 2025-10-21 16:15:23.766663 | debian-bookworm -> localhost | changed 2025-10-21 16:15:23.784905 | 2025-10-21 16:15:23.785070 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2025-10-21 16:15:24.108687 | debian-bookworm | ok 2025-10-21 16:15:24.114503 | 2025-10-21 16:15:24.114621 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2025-10-21 16:15:25.360774 | debian-bookworm | changed: 2025-10-21 16:15:25.360986 | debian-bookworm | cd+++++++++ src/ 2025-10-21 16:15:25.361022 | debian-bookworm | cd+++++++++ src/github.com/ 2025-10-21 16:15:25.361046 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2025-10-21 16:15:25.361067 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2025-10-21 16:15:25.361087 | debian-bookworm | Containerfile 2025-10-21 16:15:25.361197 | debian-bookworm | Containerfile 2025-10-21 16:15:25.369896 | debian-bookworm | localhost | ok: Runtime: 0:00:00.020183 2025-10-21 16:15:56.240008 | 2025-10-21 16:15:56.240086 | TASK [upload-logs : Upload logs to log server] 2025-10-21 16:15:56.720219 | localhost | Output suppressed because no_log was given 2025-10-21 16:15:56.721586 | 2025-10-21 16:15:56.721658 | LOOP [upload-logs : Compress console log and json output] 2025-10-21 16:15:56.801882 | localhost | skipping: Conditional result was False 2025-10-21 16:15:56.808180 | localhost | skipping: Conditional result was False 2025-10-21 16:15:56.816672 | 2025-10-21 16:15:56.816768 | LOOP [upload-logs : Upload compressed console log and json output] 2025-10-21 16:15:56.900300 | localhost | skipping: Conditional result was False 2025-10-21 16:15:56.900549 | 2025-10-21 16:15:56.908535 | localhost | skipping: Conditional result was False 2025-10-21 16:15:56.912456 | 2025-10-21 16:15:56.912541 | LOOP [upload-logs : Upload console log and json output]