2026-09-29 05:34:21.876862 | Job console starting 2026-09-29 05:34:21.885584 | Updating git repos 2026-09-29 05:34:21.950527 | Cloning repos into workspace 2026-09-29 05:34:22.058149 | Restoring repo states 2026-09-29 05:34:22.072391 | Merging changes 2026-09-29 05:34:23.010284 | Checking out repos 2026-09-29 05:34:23.252438 | Preparing playbooks 2026-09-29 05:34:24.650081 | Running Ansible setup 2026-09-29 05:34:30.024662 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-29 05:34:30.738587 | 2026-09-29 05:34:30.738707 | PLAY [Base pre] 2026-09-29 05:34:30.776470 | 2026-09-29 05:34:30.776577 | TASK [Setup log path fact] 2026-09-29 05:34:30.819524 | debian-bookworm | ok 2026-09-29 05:34:30.846707 | 2026-09-29 05:34:30.846824 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-29 05:34:30.911693 | debian-bookworm | ok 2026-09-29 05:34:30.929285 | 2026-09-29 05:34:30.929400 | TASK [emit-job-header : Print job information] 2026-09-29 05:34:31.017084 | # Job Information 2026-09-29 05:34:31.017230 | Ansible Version: 2.16.14 2026-09-29 05:34:31.017258 | Job: python-black 2026-09-29 05:34:31.017285 | Pipeline: check 2026-09-29 05:34:31.017304 | Executor: 521e9411259a 2026-09-29 05:34:31.017321 | Triggered by: https://github.com/osism/python-osism/pull/2734 2026-09-29 05:34:31.017339 | Event ID: 6659c040-bbc7-11f1-91bb-1118de1a6d3d 2026-09-29 05:34:31.021449 | 2026-09-29 05:34:31.021537 | LOOP [emit-job-header : Print node information] 2026-09-29 05:34:31.257493 | debian-bookworm | ok: 2026-09-29 05:34:31.257686 | debian-bookworm | # Node Information 2026-09-29 05:34:31.257718 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-29 05:34:31.257739 | debian-bookworm | Hostname: debian 2026-09-29 05:34:31.257757 | debian-bookworm | Username: zuul 2026-09-29 05:34:31.257774 | debian-bookworm | Distro: Debian 12.15 2026-09-29 05:34:31.257795 | debian-bookworm | Provider: regiocloud-a 2026-09-29 05:34:31.257813 | debian-bookworm | Region: 2026-09-29 05:34:31.257830 | debian-bookworm | Label: debian-bookworm 2026-09-29 05:34:31.257846 | debian-bookworm | Product Name: OpenStack Nova 2026-09-29 05:34:31.257862 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:feef:263c 2026-09-29 05:34:31.266137 | 2026-09-29 05:34:31.266233 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-29 05:34:32.165689 | debian-bookworm -> localhost | changed 2026-09-29 05:34:32.177629 | 2026-09-29 05:34:32.177722 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-29 05:34:33.848380 | debian-bookworm -> localhost | changed 2026-09-29 05:34:33.868506 | 2026-09-29 05:34:33.868602 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-29 05:34:34.305279 | debian-bookworm -> localhost | ok 2026-09-29 05:34:34.310228 | 2026-09-29 05:34:34.310312 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-29 05:34:34.341385 | debian-bookworm | ok 2026-09-29 05:34:34.369485 | debian-bookworm | included: /var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-29 05:34:34.388412 | 2026-09-29 05:34:34.388506 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-29 05:34:37.512955 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-29 05:34:37.513134 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/work/5f1d75c821444e37a87f57679e2e7a42_id_rsa 2026-09-29 05:34:37.513167 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/work/5f1d75c821444e37a87f57679e2e7a42_id_rsa.pub 2026-09-29 05:34:37.513189 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-29 05:34:37.513210 | debian-bookworm -> localhost | SHA256:0p/0aRy9fbxr2RM2gCgYaJKbhvD11lNSVBSB0mKlMwc zuul-build-sshkey 2026-09-29 05:34:37.513229 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-29 05:34:37.513257 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-29 05:34:37.513275 | debian-bookworm -> localhost | | . . E=++=o | 2026-09-29 05:34:37.513293 | debian-bookworm -> localhost | |.o o.. =o+ | 2026-09-29 05:34:37.513310 | debian-bookworm -> localhost | |o.=. .oo+=o . | 2026-09-29 05:34:37.513326 | debian-bookworm -> localhost | |.+. .ooo= . o | 2026-09-29 05:34:37.513343 | debian-bookworm -> localhost | |. .. S.. . o | 2026-09-29 05:34:37.513363 | debian-bookworm -> localhost | | . o + o B | 2026-09-29 05:34:37.513381 | debian-bookworm -> localhost | | o = o O| 2026-09-29 05:34:37.513398 | debian-bookworm -> localhost | | . ++| 2026-09-29 05:34:37.513416 | debian-bookworm -> localhost | | .oo| 2026-09-29 05:34:37.513433 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-29 05:34:37.513473 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.356859 2026-09-29 05:34:37.518497 | 2026-09-29 05:34:37.518579 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-29 05:34:37.544581 | debian-bookworm | ok 2026-09-29 05:34:37.558472 | debian-bookworm | included: /var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-29 05:34:37.564870 | 2026-09-29 05:34:37.564936 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-29 05:34:37.594281 | debian-bookworm | skipping: Conditional result was False 2026-09-29 05:34:37.598751 | 2026-09-29 05:34:37.598820 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-29 05:34:38.344121 | debian-bookworm | changed 2026-09-29 05:34:38.348537 | 2026-09-29 05:34:38.348611 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-29 05:34:38.618110 | debian-bookworm | ok 2026-09-29 05:34:38.623591 | 2026-09-29 05:34:38.623681 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-29 05:34:39.301736 | debian-bookworm | changed 2026-09-29 05:34:39.306945 | 2026-09-29 05:34:39.307043 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-29 05:34:39.996454 | debian-bookworm | changed 2026-09-29 05:34:40.009336 | 2026-09-29 05:34:40.009450 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-29 05:34:40.043926 | debian-bookworm | skipping: Conditional result was False 2026-09-29 05:34:40.054478 | 2026-09-29 05:34:40.054762 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-29 05:34:40.685943 | debian-bookworm -> localhost | changed 2026-09-29 05:34:40.696535 | 2026-09-29 05:34:40.696634 | TASK [add-build-sshkey : Add back temp key] 2026-09-29 05:34:41.336465 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/work/5f1d75c821444e37a87f57679e2e7a42_id_rsa (zuul-build-sshkey) 2026-09-29 05:34:41.336671 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012193 2026-09-29 05:34:41.344100 | 2026-09-29 05:34:41.344198 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-29 05:34:41.864861 | debian-bookworm | ok 2026-09-29 05:34:41.870675 | 2026-09-29 05:34:41.870759 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-29 05:34:41.917266 | debian-bookworm | skipping: Conditional result was False 2026-09-29 05:34:41.933616 | 2026-09-29 05:34:41.933903 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-29 05:34:42.458859 | debian-bookworm | ok 2026-09-29 05:34:42.470998 | 2026-09-29 05:34:42.471087 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-29 05:34:42.503605 | debian-bookworm | ok 2026-09-29 05:34:42.507700 | 2026-09-29 05:34:42.507770 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-29 05:34:43.157077 | debian-bookworm -> localhost | ok 2026-09-29 05:34:43.162215 | 2026-09-29 05:34:43.162288 | TASK [validate-host : Collect information about the host] 2026-09-29 05:34:44.512767 | debian-bookworm | ok 2026-09-29 05:34:44.543268 | 2026-09-29 05:34:44.543382 | TASK [validate-host : Sanitize hostname] 2026-09-29 05:34:44.651788 | debian-bookworm | ok 2026-09-29 05:34:44.660138 | 2026-09-29 05:34:44.660230 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-29 05:34:45.680382 | debian-bookworm -> localhost | changed 2026-09-29 05:34:45.688117 | 2026-09-29 05:34:45.688225 | TASK [validate-host : Collect information about zuul worker] 2026-09-29 05:34:46.299429 | debian-bookworm | ok 2026-09-29 05:34:46.312576 | 2026-09-29 05:34:46.312696 | TASK [validate-host : Write out all zuul information for each host] 2026-09-29 05:34:47.186447 | debian-bookworm -> localhost | changed 2026-09-29 05:34:47.193903 | 2026-09-29 05:34:47.194003 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-29 05:34:47.508875 | debian-bookworm | ok 2026-09-29 05:34:47.515111 | 2026-09-29 05:34:47.515214 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-29 05:34:50.099219 | debian-bookworm | changed: 2026-09-29 05:34:50.099405 | debian-bookworm | cd+++++++++ src/ 2026-09-29 05:34:50.099440 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-29 05:34:50.099465 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-29 05:34:50.100165 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-09-29 05:34:50.100213 | debian-bookworm | Containerfile 2026-09-29 05:34:50.100353 | debian-bookworm | Containerfile 2026-09-29 05:34:50.113813 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/work/logs" 2026-09-29 05:36:10.126912 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/work/artifacts" 2026-09-29 05:36:10.389023 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/5f1d75c821444e37a87f57679e2e7a42/work/docs" 2026-09-29 05:36:10.407080 | 2026-09-29 05:36:10.407214 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-29 05:36:11.204724 | debian-bookworm | changed: 2026-09-29 05:36:11.205655 | debian-bookworm | .d..t...... ./ 2026-09-29 05:36:11.205732 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-29 05:36:11.205760 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-29 05:36:11.205792 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-29 05:36:11.205817 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-29 05:36:11.205838 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-29 05:36:11.205896 | debian-bookworm | changed: All items complete 2026-09-29 05:36:11.205931 | 2026-09-29 05:36:11.782272 | debian-bookworm | changed: .d..t...... ./ 2026-09-29 05:36:12.343702 | debian-bookworm | changed: .d..t...... ./ 2026-09-29 05:36:12.357820 | 2026-09-29 05:36:12.357941 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-29 05:36:12.693741 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.008857 2026-09-29 05:36:12.958551 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.009428 2026-09-29 05:36:12.976061 | 2026-09-29 05:36:12.976184 | PLAY RECAP 2026-09-29 05:36:12.976263 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-09-29 05:36:12.976301 | 2026-09-29 05:36:13.120673 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-29 05:36:13.121757 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-29 05:36:13.891900 | 2026-09-29 05:36:13.892067 | PLAY [Base post] 2026-09-29 05:36:13.905389 | 2026-09-29 05:36:13.905514 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-29 05:36:14.495656 | debian-bookworm | changed 2026-09-29 05:36:14.505037 | 2026-09-29 05:36:14.505152 | PLAY RECAP 2026-09-29 05:36:14.505232 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-29 05:36:14.505311 | 2026-09-29 05:36:14.632528 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-29 05:36:14.633363 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-29 05:36:15.523321 | 2026-09-29 05:36:15.523488 | PLAY [Base post-logs] 2026-09-29 05:36:15.534526 | 2026-09-29 05:36:15.534789 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-29 05:36:16.007053 | localhost | changed 2026-09-29 05:36:16.017171 | 2026-09-29 05:36:16.017314 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-29 05:36:16.042766 | localhost | ok 2026-09-29 05:36:16.045810 | 2026-09-29 05:36:16.045906 | TASK [Set zuul-log-path fact] 2026-09-29 05:36:16.062892 | localhost | ok 2026-09-29 05:36:16.077388 | 2026-09-29 05:36:16.077521 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-29 05:36:16.104231 | localhost | ok 2026-09-29 05:36:16.111343 | 2026-09-29 05:36:16.111512 | TASK [upload-logs : Create log directories] 2026-09-29 05:36:16.665024 | localhost | changed 2026-09-29 05:36:16.670935 | 2026-09-29 05:36:16.671130 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-29 05:36:17.231082 | localhost -> localhost | ok: Runtime: 0:00:00.018780 2026-09-29 05:36:17.235364 | 2026-09-29 05:36:17.235471 | TASK [upload-logs : Upload logs to log server] 2026-09-29 05:36:17.767603 | localhost | Output suppressed because no_log was given 2026-09-29 05:36:17.771651 | 2026-09-29 05:36:17.771864 | LOOP [upload-logs : Compress console log and json output] 2026-09-29 05:36:17.826277 | localhost | skipping: Conditional result was False 2026-09-29 05:36:17.831170 | localhost | skipping: Conditional result was False 2026-09-29 05:36:17.842927 | 2026-09-29 05:36:17.843126 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-29 05:36:17.889383 | localhost | skipping: Conditional result was False 2026-09-29 05:36:17.890049 | 2026-09-29 05:36:17.893307 | localhost | skipping: Conditional result was False 2026-09-29 05:36:17.907326 | 2026-09-29 05:36:17.907575 | LOOP [upload-logs : Upload console log and json output]