2026-09-29 03:04:49.343183 | Job console starting 2026-09-29 03:04:49.351995 | Updating git repos 2026-09-29 03:04:49.397686 | Cloning repos into workspace 2026-09-29 03:04:49.488197 | Restoring repo states 2026-09-29 03:04:49.510468 | Merging changes 2026-09-29 03:04:49.510483 | Checking out repos 2026-09-29 03:04:49.586639 | Preparing playbooks 2026-09-29 03:04:50.897240 | Running Ansible setup 2026-09-29 03:04:55.907530 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-29 03:04:56.541989 | 2026-09-29 03:04:56.542096 | PLAY [Base pre] 2026-09-29 03:04:56.558155 | 2026-09-29 03:04:56.558237 | TASK [Setup log path fact] 2026-09-29 03:04:56.575352 | debian-bookworm | ok 2026-09-29 03:04:56.589521 | 2026-09-29 03:04:56.589637 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-29 03:04:56.616109 | debian-bookworm | ok 2026-09-29 03:04:56.624278 | 2026-09-29 03:04:56.624362 | TASK [emit-job-header : Print job information] 2026-09-29 03:04:56.661814 | # Job Information 2026-09-29 03:04:56.661945 | Ansible Version: 2.16.14 2026-09-29 03:04:56.661973 | Job: python-black 2026-09-29 03:04:56.661999 | Pipeline: periodic-daily 2026-09-29 03:04:56.662018 | Executor: 521e9411259a 2026-09-29 03:04:56.662035 | Triggered by: https://github.com/osism/openstack-sandbox-manager 2026-09-29 03:04:56.662053 | Event ID: c912e1b9c1bd4449889a282f471e9be2 2026-09-29 03:04:56.666120 | 2026-09-29 03:04:56.666196 | LOOP [emit-job-header : Print node information] 2026-09-29 03:04:56.751054 | debian-bookworm | ok: 2026-09-29 03:04:56.751283 | debian-bookworm | # Node Information 2026-09-29 03:04:56.751317 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-29 03:04:56.751339 | debian-bookworm | Hostname: debian 2026-09-29 03:04:56.751358 | debian-bookworm | Username: zuul 2026-09-29 03:04:56.751375 | debian-bookworm | Distro: Debian 12.15 2026-09-29 03:04:56.751400 | debian-bookworm | Provider: regiocloud-a 2026-09-29 03:04:56.751422 | debian-bookworm | Region: 2026-09-29 03:04:56.751580 | debian-bookworm | Label: debian-bookworm 2026-09-29 03:04:56.751614 | debian-bookworm | Product Name: OpenStack Nova 2026-09-29 03:04:56.751634 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe98:d844 2026-09-29 03:04:56.768890 | 2026-09-29 03:04:56.768973 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-29 03:04:57.205407 | debian-bookworm -> localhost | changed 2026-09-29 03:04:57.210992 | 2026-09-29 03:04:57.211073 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-29 03:04:58.344416 | debian-bookworm -> localhost | changed 2026-09-29 03:04:58.353424 | 2026-09-29 03:04:58.353514 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-29 03:04:58.575306 | debian-bookworm -> localhost | ok 2026-09-29 03:04:58.580445 | 2026-09-29 03:04:58.580531 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-29 03:04:58.620463 | debian-bookworm | ok 2026-09-29 03:04:58.633544 | debian-bookworm | included: /var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-29 03:04:58.638330 | 2026-09-29 03:04:58.638399 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-29 03:05:00.019263 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-29 03:05:00.019457 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/work/040ac1586366428a815bada2a5d98d47_id_rsa 2026-09-29 03:05:00.019489 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/work/040ac1586366428a815bada2a5d98d47_id_rsa.pub 2026-09-29 03:05:00.019510 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-29 03:05:00.019530 | debian-bookworm -> localhost | SHA256:9GIeGQDeZnPraVpxIioekCw0M2o370Ag2qVyFyRPAPs zuul-build-sshkey 2026-09-29 03:05:00.019548 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-29 03:05:00.019576 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-29 03:05:00.019595 | debian-bookworm -> localhost | |..o.+.. | 2026-09-29 03:05:00.019613 | debian-bookworm -> localhost | | . * . . | 2026-09-29 03:05:00.019630 | debian-bookworm -> localhost | |o* = = + | 2026-09-29 03:05:00.019646 | debian-bookworm -> localhost | |*+=o + + = | 2026-09-29 03:05:00.019662 | debian-bookworm -> localhost | |BoE+. . S o | 2026-09-29 03:05:00.019681 | debian-bookworm -> localhost | |o+o.o. = B | 2026-09-29 03:05:00.019698 | debian-bookworm -> localhost | | o... * | 2026-09-29 03:05:00.019726 | debian-bookworm -> localhost | | . oo + | 2026-09-29 03:05:00.019742 | debian-bookworm -> localhost | | . .. | 2026-09-29 03:05:00.019759 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-29 03:05:00.019806 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.820341 2026-09-29 03:05:00.025505 | 2026-09-29 03:05:00.025597 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-29 03:05:00.062692 | debian-bookworm | ok 2026-09-29 03:05:00.072642 | debian-bookworm | included: /var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-29 03:05:00.083491 | 2026-09-29 03:05:00.083570 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-29 03:05:00.107199 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:05:00.118006 | 2026-09-29 03:05:00.118087 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-29 03:05:01.048405 | debian-bookworm | changed 2026-09-29 03:05:01.057493 | 2026-09-29 03:05:01.057634 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-29 03:05:01.310857 | debian-bookworm | ok 2026-09-29 03:05:01.315339 | 2026-09-29 03:05:01.315414 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-29 03:05:01.991651 | debian-bookworm | changed 2026-09-29 03:05:01.998252 | 2026-09-29 03:05:01.998345 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-29 03:05:02.696613 | debian-bookworm | changed 2026-09-29 03:05:02.707311 | 2026-09-29 03:05:02.707405 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-29 03:05:02.722113 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:05:02.728982 | 2026-09-29 03:05:02.731759 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-29 03:05:03.202168 | debian-bookworm -> localhost | changed 2026-09-29 03:05:03.217742 | 2026-09-29 03:05:03.217860 | TASK [add-build-sshkey : Add back temp key] 2026-09-29 03:05:03.528179 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/work/040ac1586366428a815bada2a5d98d47_id_rsa (zuul-build-sshkey) 2026-09-29 03:05:03.528427 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.008798 2026-09-29 03:05:03.536183 | 2026-09-29 03:05:03.536276 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-29 03:05:03.978650 | debian-bookworm | ok 2026-09-29 03:05:03.984175 | 2026-09-29 03:05:03.984264 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-29 03:05:04.008982 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:05:04.018779 | 2026-09-29 03:05:04.018862 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-29 03:05:04.440405 | debian-bookworm | ok 2026-09-29 03:05:04.462904 | 2026-09-29 03:05:04.463014 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-29 03:05:04.491495 | debian-bookworm | ok 2026-09-29 03:05:04.496379 | 2026-09-29 03:05:04.496470 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-29 03:05:04.884272 | debian-bookworm -> localhost | ok 2026-09-29 03:05:04.891603 | 2026-09-29 03:05:04.891687 | TASK [validate-host : Collect information about the host] 2026-09-29 03:05:06.061893 | debian-bookworm | ok 2026-09-29 03:05:06.073090 | 2026-09-29 03:05:06.073181 | TASK [validate-host : Sanitize hostname] 2026-09-29 03:05:06.187935 | debian-bookworm | ok 2026-09-29 03:05:06.197751 | 2026-09-29 03:05:06.197915 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-29 03:05:06.819143 | debian-bookworm -> localhost | changed 2026-09-29 03:05:06.824676 | 2026-09-29 03:05:06.824774 | TASK [validate-host : Collect information about zuul worker] 2026-09-29 03:05:07.268826 | debian-bookworm | ok 2026-09-29 03:05:07.273418 | 2026-09-29 03:05:07.273511 | TASK [validate-host : Write out all zuul information for each host] 2026-09-29 03:05:08.259777 | debian-bookworm -> localhost | changed 2026-09-29 03:05:08.270347 | 2026-09-29 03:05:08.270436 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-29 03:05:08.551799 | debian-bookworm | ok 2026-09-29 03:05:08.560259 | 2026-09-29 03:05:08.560351 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-29 03:05:09.849534 | debian-bookworm | changed: 2026-09-29 03:05:09.849766 | debian-bookworm | cd+++++++++ src/ 2026-09-29 03:05:09.849802 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-29 03:05:09.849825 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-29 03:05:09.849846 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-sandbox-manager/ 2026-09-29 03:05:09.849866 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/work/logs" 2026-09-29 03:06:17.729010 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/work/artifacts" 2026-09-29 03:06:18.026145 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/040ac1586366428a815bada2a5d98d47/work/docs" 2026-09-29 03:06:18.042443 | 2026-09-29 03:06:18.042535 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-29 03:06:18.953659 | debian-bookworm | changed: 2026-09-29 03:06:18.953838 | debian-bookworm | .d..t...... ./ 2026-09-29 03:06:18.953871 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-29 03:06:18.953895 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-29 03:06:18.953917 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-29 03:06:18.954043 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-29 03:06:18.954072 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-29 03:06:18.954113 | debian-bookworm | changed: All items complete 2026-09-29 03:06:18.954140 | 2026-09-29 03:06:19.732010 | debian-bookworm | changed: .d..t...... ./ 2026-09-29 03:06:20.461063 | debian-bookworm | changed: .d..t...... ./ 2026-09-29 03:06:20.473883 | 2026-09-29 03:06:20.473983 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-29 03:06:20.498647 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:06:20.511833 | debian-bookworm | skipping: Conditional result was False 2026-09-29 03:06:20.519948 | 2026-09-29 03:06:20.520047 | PLAY RECAP 2026-09-29 03:06:20.520103 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 4 rescued: 0 ignored: 0 2026-09-29 03:06:20.520132 | 2026-09-29 03:06:20.621225 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-29 03:06:20.622040 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-29 03:06:21.653590 | 2026-09-29 03:06:21.653730 | PLAY [Base post] 2026-09-29 03:06:21.691155 | 2026-09-29 03:06:21.691284 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-29 03:06:22.364034 | debian-bookworm | changed 2026-09-29 03:06:22.371914 | 2026-09-29 03:06:22.372012 | PLAY RECAP 2026-09-29 03:06:22.372070 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-29 03:06:22.372130 | 2026-09-29 03:06:22.611624 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-29 03:06:22.612366 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-29 03:06:23.610780 | 2026-09-29 03:06:23.610895 | PLAY [Base post-logs] 2026-09-29 03:06:23.620222 | 2026-09-29 03:06:23.620311 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-29 03:06:24.219291 | localhost | changed 2026-09-29 03:06:24.227135 | 2026-09-29 03:06:24.227237 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-29 03:06:24.274084 | localhost | ok 2026-09-29 03:06:24.276420 | 2026-09-29 03:06:24.276509 | TASK [Set zuul-log-path fact] 2026-09-29 03:06:24.301481 | localhost | ok 2026-09-29 03:06:24.322812 | 2026-09-29 03:06:24.323049 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-29 03:06:24.389885 | localhost | ok 2026-09-29 03:06:24.393200 | 2026-09-29 03:06:24.393282 | TASK [upload-logs : Create log directories] 2026-09-29 03:06:25.133242 | localhost | changed 2026-09-29 03:06:25.136036 | 2026-09-29 03:06:25.136124 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-29 03:06:25.730712 | localhost -> localhost | ok: Runtime: 0:00:00.004406 2026-09-29 03:06:25.734252 | 2026-09-29 03:06:25.734340 | TASK [upload-logs : Upload logs to log server] 2026-09-29 03:06:26.305389 | localhost | Output suppressed because no_log was given 2026-09-29 03:06:26.307324 | 2026-09-29 03:06:26.307419 | LOOP [upload-logs : Compress console log and json output] 2026-09-29 03:06:26.376699 | localhost | skipping: Conditional result was False 2026-09-29 03:06:26.382396 | localhost | skipping: Conditional result was False 2026-09-29 03:06:26.393798 | 2026-09-29 03:06:26.393912 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-29 03:06:26.473228 | localhost | skipping: Conditional result was False 2026-09-29 03:06:26.473439 | 2026-09-29 03:06:26.478265 | localhost | skipping: Conditional result was False 2026-09-29 03:06:26.489795 | 2026-09-29 03:06:26.489905 | LOOP [upload-logs : Upload console log and json output]