2026-05-05 08:35:48.620338 | Job console starting 2026-05-05 08:35:48.633428 | Updating git repos 2026-05-05 08:35:48.651067 | Cloning repos into workspace 2026-05-05 08:35:48.701877 | Restoring repo states 2026-05-05 08:35:48.714273 | Merging changes 2026-05-05 08:35:49.213049 | Checking out repos 2026-05-05 08:35:49.279360 | Preparing playbooks 2026-05-05 08:35:50.669320 | Running Ansible setup 2026-05-05 08:35:55.624846 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-05 08:35:56.403037 | 2026-05-05 08:35:56.403177 | PLAY [Base pre] 2026-05-05 08:35:56.422001 | 2026-05-05 08:35:56.422124 | TASK [Setup log path fact] 2026-05-05 08:35:56.441289 | debian-bookworm | ok 2026-05-05 08:35:56.457995 | 2026-05-05 08:35:56.458122 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 08:35:56.496695 | debian-bookworm | ok 2026-05-05 08:35:56.505892 | 2026-05-05 08:35:56.505989 | TASK [emit-job-header : Print job information] 2026-05-05 08:35:56.564353 | # Job Information 2026-05-05 08:35:56.564500 | Ansible Version: 2.16.14 2026-05-05 08:35:56.564529 | Job: python-black 2026-05-05 08:35:56.564555 | Pipeline: check 2026-05-05 08:35:56.564583 | Executor: 521e9411259a 2026-05-05 08:35:56.564600 | Triggered by: https://github.com/osism/zuul-jobs/pull/183 2026-05-05 08:35:56.564618 | Event ID: df9bceb0-485b-11f1-8dd4-5d6b55db8466 2026-05-05 08:35:56.568742 | 2026-05-05 08:35:56.568828 | LOOP [emit-job-header : Print node information] 2026-05-05 08:35:56.785691 | debian-bookworm | ok: 2026-05-05 08:35:56.785900 | debian-bookworm | # Node Information 2026-05-05 08:35:56.785934 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-05 08:35:56.785959 | debian-bookworm | Hostname: debian 2026-05-05 08:35:56.785980 | debian-bookworm | Username: zuul 2026-05-05 08:35:56.786001 | debian-bookworm | Distro: Debian 12.13 2026-05-05 08:35:56.786023 | debian-bookworm | Provider: regiocloud-a 2026-05-05 08:35:56.786043 | debian-bookworm | Region: 2026-05-05 08:35:56.786064 | debian-bookworm | Label: debian-bookworm 2026-05-05 08:35:56.786083 | debian-bookworm | Product Name: OpenStack Nova 2026-05-05 08:35:56.786103 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe7e:95f4 2026-05-05 08:35:56.798998 | 2026-05-05 08:35:56.799102 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-05 08:35:57.393997 | debian-bookworm -> localhost | changed 2026-05-05 08:35:57.399687 | 2026-05-05 08:35:57.399772 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-05 08:35:58.530682 | debian-bookworm -> localhost | changed 2026-05-05 08:35:58.541799 | 2026-05-05 08:35:58.541909 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-05 08:35:58.796680 | debian-bookworm -> localhost | ok 2026-05-05 08:35:58.803137 | 2026-05-05 08:35:58.803236 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-05 08:35:58.823046 | debian-bookworm | ok 2026-05-05 08:35:58.837878 | debian-bookworm | included: /var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-05 08:35:58.843835 | 2026-05-05 08:35:58.843926 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-05 08:36:00.339067 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-05 08:36:00.339321 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/work/3088120b851f47e29b815748d17469ad_id_rsa 2026-05-05 08:36:00.339368 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/work/3088120b851f47e29b815748d17469ad_id_rsa.pub 2026-05-05 08:36:00.339400 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-05 08:36:00.339429 | debian-bookworm -> localhost | SHA256:+R5SnZ0FbPG2y5WlavI7JV5aoS9Mivczm+IzT7A1mXg zuul-build-sshkey 2026-05-05 08:36:00.339457 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-05 08:36:00.339496 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-05 08:36:00.339523 | debian-bookworm -> localhost | | .o. | 2026-05-05 08:36:00.339550 | debian-bookworm -> localhost | | oo | 2026-05-05 08:36:00.339586 | debian-bookworm -> localhost | | . =| 2026-05-05 08:36:00.339613 | debian-bookworm -> localhost | | . o =.=+| 2026-05-05 08:36:00.339638 | debian-bookworm -> localhost | | S + E.++.| 2026-05-05 08:36:00.339667 | debian-bookworm -> localhost | | o =+o= o| 2026-05-05 08:36:00.339693 | debian-bookworm -> localhost | | ..==+B o | 2026-05-05 08:36:00.339718 | debian-bookworm -> localhost | | .o**O.. | 2026-05-05 08:36:00.339744 | debian-bookworm -> localhost | | oo*BB | 2026-05-05 08:36:00.339769 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-05 08:36:00.339834 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.023255 2026-05-05 08:36:00.347533 | 2026-05-05 08:36:00.347680 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-05 08:36:00.372002 | debian-bookworm | ok 2026-05-05 08:36:00.381381 | debian-bookworm | included: /var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-05 08:36:00.390139 | 2026-05-05 08:36:00.390235 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-05 08:36:00.403611 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:36:00.409169 | 2026-05-05 08:36:00.409258 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-05 08:36:01.052187 | debian-bookworm | changed 2026-05-05 08:36:01.057526 | 2026-05-05 08:36:01.057625 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-05 08:36:01.315790 | debian-bookworm | ok 2026-05-05 08:36:01.329194 | 2026-05-05 08:36:01.329319 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-05 08:36:01.937399 | debian-bookworm | changed 2026-05-05 08:36:01.944035 | 2026-05-05 08:36:01.944131 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-05 08:36:02.587338 | debian-bookworm | changed 2026-05-05 08:36:02.596858 | 2026-05-05 08:36:02.597012 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-05 08:36:02.621205 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:36:02.627500 | 2026-05-05 08:36:02.627605 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-05 08:36:03.025468 | debian-bookworm -> localhost | changed 2026-05-05 08:36:03.039007 | 2026-05-05 08:36:03.039112 | TASK [add-build-sshkey : Add back temp key] 2026-05-05 08:36:03.478827 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/work/3088120b851f47e29b815748d17469ad_id_rsa (zuul-build-sshkey) 2026-05-05 08:36:03.479104 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025982 2026-05-05 08:36:03.488018 | 2026-05-05 08:36:03.488118 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-05 08:36:03.946592 | debian-bookworm | ok 2026-05-05 08:36:03.954897 | 2026-05-05 08:36:03.955000 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-05 08:36:03.991473 | debian-bookworm | skipping: Conditional result was False 2026-05-05 08:36:04.002046 | 2026-05-05 08:36:04.002150 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-05 08:36:04.461380 | debian-bookworm | ok 2026-05-05 08:36:04.476566 | 2026-05-05 08:36:04.476718 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-05 08:36:04.515888 | debian-bookworm | ok 2026-05-05 08:36:04.525196 | 2026-05-05 08:36:04.525338 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-05 08:36:04.937566 | debian-bookworm -> localhost | ok 2026-05-05 08:36:04.944325 | 2026-05-05 08:36:04.944435 | TASK [validate-host : Collect information about the host] 2026-05-05 08:36:06.275530 | debian-bookworm | ok 2026-05-05 08:36:06.297266 | 2026-05-05 08:36:06.297429 | TASK [validate-host : Sanitize hostname] 2026-05-05 08:36:06.430026 | debian-bookworm | ok 2026-05-05 08:36:06.525008 | 2026-05-05 08:36:06.525167 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-05 08:36:07.335249 | debian-bookworm -> localhost | changed 2026-05-05 08:36:07.342215 | 2026-05-05 08:36:07.342360 | TASK [validate-host : Collect information about zuul worker] 2026-05-05 08:36:07.783051 | debian-bookworm | ok 2026-05-05 08:36:07.788139 | 2026-05-05 08:36:07.788266 | TASK [validate-host : Write out all zuul information for each host] 2026-05-05 08:36:08.494225 | debian-bookworm -> localhost | changed 2026-05-05 08:36:08.511537 | 2026-05-05 08:36:08.511733 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-05 08:36:08.794916 | debian-bookworm | ok 2026-05-05 08:36:08.803107 | 2026-05-05 08:36:08.803255 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-05 08:36:09.770129 | debian-bookworm | changed: 2026-05-05 08:36:09.770360 | debian-bookworm | cd+++++++++ src/ 2026-05-05 08:36:09.770396 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-05 08:36:09.770421 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-05 08:36:09.770444 | debian-bookworm | cd+++++++++ src/github.com/osism/zuul-jobs/ 2026-05-05 08:36:09.770465 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/work/logs" 2026-05-05 08:36:36.265001 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/work/artifacts" 2026-05-05 08:36:36.498995 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/3088120b851f47e29b815748d17469ad/work/docs" 2026-05-05 08:36:36.528452 | 2026-05-05 08:36:36.528595 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-05 08:36:37.292431 | debian-bookworm | changed: 2026-05-05 08:36:37.292625 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-05 08:36:37.292659 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-05 08:36:37.292693 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-05 08:36:37.292741 | debian-bookworm | changed: All items complete 2026-05-05 08:36:37.292768 | 2026-05-05 08:36:38.130511 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 08:36:38.681924 | debian-bookworm | changed: .d..t...... ./ 2026-05-05 08:36:38.721055 | 2026-05-05 08:36:38.721160 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-05 08:36:39.574217 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008166 2026-05-05 08:36:40.005180 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.007032 2026-05-05 08:36:40.022118 | 2026-05-05 08:36:40.022257 | PLAY RECAP 2026-05-05 08:36:40.022315 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-05 08:36:40.022340 | 2026-05-05 08:36:40.186393 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-05 08:36:40.187523 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 08:36:41.262014 | 2026-05-05 08:36:41.262288 | PLAY [Base post] 2026-05-05 08:36:41.302145 | 2026-05-05 08:36:41.302317 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-05 08:36:42.300941 | debian-bookworm | changed 2026-05-05 08:36:42.317502 | 2026-05-05 08:36:42.317672 | PLAY RECAP 2026-05-05 08:36:42.317742 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-05 08:36:42.317804 | 2026-05-05 08:36:42.582655 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-05 08:36:42.583528 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-05 08:36:43.947896 | 2026-05-05 08:36:43.948087 | PLAY [Base post-logs] 2026-05-05 08:36:43.962216 | 2026-05-05 08:36:43.962396 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-05 08:36:44.950669 | localhost | changed 2026-05-05 08:36:44.967626 | 2026-05-05 08:36:44.967824 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-05 08:36:45.010201 | localhost | ok 2026-05-05 08:36:45.020199 | 2026-05-05 08:36:45.020402 | TASK [Set zuul-log-path fact] 2026-05-05 08:36:45.052553 | localhost | ok 2026-05-05 08:36:45.069226 | 2026-05-05 08:36:45.069395 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-05 08:36:45.112067 | localhost | ok 2026-05-05 08:36:45.121386 | 2026-05-05 08:36:45.121693 | TASK [upload-logs : Create log directories] 2026-05-05 08:36:45.733441 | localhost | changed 2026-05-05 08:36:45.748332 | 2026-05-05 08:36:45.748555 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-05 08:36:46.340291 | localhost -> localhost | ok: Runtime: 0:00:00.013909 2026-05-05 08:36:46.345965 | 2026-05-05 08:36:46.346105 | TASK [upload-logs : Upload logs to log server] 2026-05-05 08:36:46.961892 | localhost | Output suppressed because no_log was given 2026-05-05 08:36:46.964086 | 2026-05-05 08:36:46.964203 | LOOP [upload-logs : Compress console log and json output] 2026-05-05 08:36:47.016014 | localhost | skipping: Conditional result was False 2026-05-05 08:36:47.022648 | localhost | skipping: Conditional result was False 2026-05-05 08:36:47.032829 | 2026-05-05 08:36:47.032967 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-05 08:36:47.083536 | localhost | skipping: Conditional result was False 2026-05-05 08:36:47.083846 | 2026-05-05 08:36:47.089063 | localhost | skipping: Conditional result was False 2026-05-05 08:36:47.100630 | 2026-05-05 08:36:47.100757 | LOOP [upload-logs : Upload console log and json output]