2026-05-29 03:19:17.380197 | Job console starting 2026-05-29 03:19:17.388355 | Updating git repos 2026-05-29 03:19:17.423750 | Cloning repos into workspace 2026-05-29 03:19:17.466403 | Restoring repo states 2026-05-29 03:19:17.472866 | Merging changes 2026-05-29 03:19:17.472885 | Checking out repos 2026-05-29 03:19:17.540636 | Preparing playbooks 2026-05-29 03:19:18.196612 | Running Ansible setup 2026-05-29 03:19:22.294906 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-29 03:19:23.356870 | 2026-05-29 03:19:23.357044 | PLAY [Base pre] 2026-05-29 03:19:23.377505 | 2026-05-29 03:19:23.377662 | TASK [Setup log path fact] 2026-05-29 03:19:23.409153 | debian-bookworm | ok 2026-05-29 03:19:23.427602 | 2026-05-29 03:19:23.427773 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 03:19:23.457641 | debian-bookworm | ok 2026-05-29 03:19:23.468433 | 2026-05-29 03:19:23.468552 | TASK [emit-job-header : Print job information] 2026-05-29 03:19:23.514639 | # Job Information 2026-05-29 03:19:23.515998 | Ansible Version: 2.16.14 2026-05-29 03:19:23.516080 | Job: python-black 2026-05-29 03:19:23.516141 | Pipeline: periodic-daily 2026-05-29 03:19:23.516182 | Executor: 521e9411259a 2026-05-29 03:19:23.516217 | Triggered by: https://github.com/osism/testbed 2026-05-29 03:19:23.516255 | Event ID: 32f1de61ea224afdbe9b819197b43dd0 2026-05-29 03:19:23.526174 | 2026-05-29 03:19:23.526393 | LOOP [emit-job-header : Print node information] 2026-05-29 03:19:23.649292 | debian-bookworm | ok: 2026-05-29 03:19:23.649525 | debian-bookworm | # Node Information 2026-05-29 03:19:23.649563 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-29 03:19:23.649589 | debian-bookworm | Hostname: debian 2026-05-29 03:19:23.649611 | debian-bookworm | Username: zuul 2026-05-29 03:19:23.649632 | debian-bookworm | Distro: Debian 12.14 2026-05-29 03:19:23.649657 | debian-bookworm | Provider: regiocloud-a 2026-05-29 03:19:23.649678 | debian-bookworm | Region: 2026-05-29 03:19:23.649699 | debian-bookworm | Label: debian-bookworm 2026-05-29 03:19:23.649718 | debian-bookworm | Product Name: OpenStack Nova 2026-05-29 03:19:23.649738 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe76:144a 2026-05-29 03:19:23.668915 | 2026-05-29 03:19:23.669060 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-29 03:19:24.399232 | debian-bookworm -> localhost | changed 2026-05-29 03:19:24.418055 | 2026-05-29 03:19:24.418202 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-29 03:19:25.722705 | debian-bookworm -> localhost | changed 2026-05-29 03:19:25.739098 | 2026-05-29 03:19:25.739257 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-29 03:19:26.072624 | debian-bookworm -> localhost | ok 2026-05-29 03:19:26.089715 | 2026-05-29 03:19:26.090143 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-29 03:19:26.131573 | debian-bookworm | ok 2026-05-29 03:19:26.150653 | debian-bookworm | included: /var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-29 03:19:26.157516 | 2026-05-29 03:19:26.157630 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-29 03:19:27.349637 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-29 03:19:27.350526 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/work/590e7dd659e446bab8668b9da70681a9_id_rsa 2026-05-29 03:19:27.350604 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/work/590e7dd659e446bab8668b9da70681a9_id_rsa.pub 2026-05-29 03:19:27.350643 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-29 03:19:27.350676 | debian-bookworm -> localhost | SHA256:nAl4GLhym2PJAGZUKyKzbn3wj+SPODz7VuW1ZqVCqUo zuul-build-sshkey 2026-05-29 03:19:27.350721 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-29 03:19:27.350784 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-29 03:19:27.351014 | debian-bookworm -> localhost | |...o. | 2026-05-29 03:19:27.351066 | debian-bookworm -> localhost | |.o. .+ | 2026-05-29 03:19:27.351097 | debian-bookworm -> localhost | |B. oo o . | 2026-05-29 03:19:27.351138 | debian-bookworm -> localhost | |=o+ . o * . . | 2026-05-29 03:19:27.351167 | debian-bookworm -> localhost | |.= = S . + | 2026-05-29 03:19:27.351199 | debian-bookworm -> localhost | |. O oE o o = | 2026-05-29 03:19:27.351226 | debian-bookworm -> localhost | | +.o.+o + | 2026-05-29 03:19:27.351252 | debian-bookworm -> localhost | |. +=o+ | 2026-05-29 03:19:27.351280 | debian-bookworm -> localhost | | o*=.o | 2026-05-29 03:19:27.351333 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-29 03:19:27.351415 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.629583 2026-05-29 03:19:27.361566 | 2026-05-29 03:19:27.361849 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-29 03:19:27.409386 | debian-bookworm | ok 2026-05-29 03:19:27.420124 | debian-bookworm | included: /var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-29 03:19:27.433371 | 2026-05-29 03:19:27.433677 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-29 03:19:27.465186 | debian-bookworm | skipping: Conditional result was False 2026-05-29 03:19:27.472005 | 2026-05-29 03:19:27.472303 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-29 03:19:28.207503 | debian-bookworm | changed 2026-05-29 03:19:28.270598 | 2026-05-29 03:19:28.271549 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-29 03:19:28.527321 | debian-bookworm | ok 2026-05-29 03:19:28.536596 | 2026-05-29 03:19:28.536730 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-29 03:19:29.235386 | debian-bookworm | changed 2026-05-29 03:19:29.246915 | 2026-05-29 03:19:29.247213 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-29 03:19:29.884516 | debian-bookworm | changed 2026-05-29 03:19:29.892845 | 2026-05-29 03:19:29.892983 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-29 03:19:29.928121 | debian-bookworm | skipping: Conditional result was False 2026-05-29 03:19:29.960963 | 2026-05-29 03:19:29.961143 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-29 03:19:30.574214 | debian-bookworm -> localhost | changed 2026-05-29 03:19:30.601027 | 2026-05-29 03:19:30.601221 | TASK [add-build-sshkey : Add back temp key] 2026-05-29 03:19:31.068365 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/work/590e7dd659e446bab8668b9da70681a9_id_rsa (zuul-build-sshkey) 2026-05-29 03:19:31.068830 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025180 2026-05-29 03:19:31.087880 | 2026-05-29 03:19:31.088015 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-29 03:19:31.536075 | debian-bookworm | ok 2026-05-29 03:19:31.545295 | 2026-05-29 03:19:31.545443 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-29 03:19:31.581381 | debian-bookworm | skipping: Conditional result was False 2026-05-29 03:19:31.606650 | 2026-05-29 03:19:31.606788 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-29 03:19:32.045084 | debian-bookworm | ok 2026-05-29 03:19:32.058855 | 2026-05-29 03:19:32.058987 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-29 03:19:32.119425 | debian-bookworm | ok 2026-05-29 03:19:32.129939 | 2026-05-29 03:19:32.130104 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-29 03:19:32.591355 | debian-bookworm -> localhost | ok 2026-05-29 03:19:32.604967 | 2026-05-29 03:19:32.605149 | TASK [validate-host : Collect information about the host] 2026-05-29 03:19:33.830951 | debian-bookworm | ok 2026-05-29 03:19:33.845251 | 2026-05-29 03:19:33.845462 | TASK [validate-host : Sanitize hostname] 2026-05-29 03:19:34.032504 | debian-bookworm | ok 2026-05-29 03:19:34.129112 | 2026-05-29 03:19:34.129316 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-29 03:19:34.983547 | debian-bookworm -> localhost | changed 2026-05-29 03:19:34.989759 | 2026-05-29 03:19:34.989896 | TASK [validate-host : Collect information about zuul worker] 2026-05-29 03:19:35.405088 | debian-bookworm | ok 2026-05-29 03:19:35.412931 | 2026-05-29 03:19:35.413137 | TASK [validate-host : Write out all zuul information for each host] 2026-05-29 03:19:36.001853 | debian-bookworm -> localhost | changed 2026-05-29 03:19:36.013885 | 2026-05-29 03:19:36.014022 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-29 03:19:36.268986 | debian-bookworm | ok 2026-05-29 03:19:36.279295 | 2026-05-29 03:19:36.279434 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-29 03:19:37.640228 | debian-bookworm | changed: 2026-05-29 03:19:37.641280 | debian-bookworm | cd+++++++++ src/ 2026-05-29 03:19:37.641368 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-29 03:19:37.641398 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-29 03:19:37.641423 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-05-29 03:19:37.641445 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/work/logs" 2026-05-29 03:20:02.942745 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/work/artifacts" 2026-05-29 03:20:03.488097 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/590e7dd659e446bab8668b9da70681a9/work/docs" 2026-05-29 03:20:03.511548 | 2026-05-29 03:20:03.511682 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-29 03:20:04.323609 | debian-bookworm | changed: 2026-05-29 03:20:04.323814 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-29 03:20:04.323852 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-29 03:20:04.323882 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-29 03:20:04.323930 | debian-bookworm | changed: All items complete 2026-05-29 03:20:04.323959 | 2026-05-29 03:20:05.069072 | debian-bookworm | changed: .d..t...... ./ 2026-05-29 03:20:05.783536 | debian-bookworm | changed: .d..t...... ./ 2026-05-29 03:20:05.799523 | 2026-05-29 03:20:05.799632 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-29 03:20:05.835295 | debian-bookworm | skipping: Conditional result was False 2026-05-29 03:20:05.840735 | debian-bookworm | skipping: Conditional result was False 2026-05-29 03:20:05.849337 | 2026-05-29 03:20:05.849406 | PLAY RECAP 2026-05-29 03:20:05.849456 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-29 03:20:05.849484 | 2026-05-29 03:20:06.014653 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-29 03:20:06.015664 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 03:20:06.787960 | 2026-05-29 03:20:06.788086 | PLAY [Base post] 2026-05-29 03:20:06.801021 | 2026-05-29 03:20:06.801138 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-29 03:20:07.289068 | debian-bookworm | changed 2026-05-29 03:20:07.298984 | 2026-05-29 03:20:07.299161 | PLAY RECAP 2026-05-29 03:20:07.299227 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-29 03:20:07.299290 | 2026-05-29 03:20:07.422036 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-29 03:20:07.422656 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-29 03:20:08.193294 | 2026-05-29 03:20:08.193415 | PLAY [Base post-logs] 2026-05-29 03:20:08.205542 | 2026-05-29 03:20:08.205687 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-29 03:20:08.604943 | localhost | changed 2026-05-29 03:20:08.612892 | 2026-05-29 03:20:08.612990 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-29 03:20:08.649161 | localhost | ok 2026-05-29 03:20:08.651635 | 2026-05-29 03:20:08.651703 | TASK [Set zuul-log-path fact] 2026-05-29 03:20:08.665432 | localhost | ok 2026-05-29 03:20:08.671699 | 2026-05-29 03:20:08.671804 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-29 03:20:08.695377 | localhost | ok 2026-05-29 03:20:08.697810 | 2026-05-29 03:20:08.697882 | TASK [upload-logs : Create log directories] 2026-05-29 03:20:09.167346 | localhost | changed 2026-05-29 03:20:09.173669 | 2026-05-29 03:20:09.173803 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-29 03:20:09.627628 | localhost -> localhost | ok: Runtime: 0:00:00.007258 2026-05-29 03:20:09.631243 | 2026-05-29 03:20:09.631335 | TASK [upload-logs : Upload logs to log server] 2026-05-29 03:20:10.151788 | localhost | Output suppressed because no_log was given 2026-05-29 03:20:10.153400 | 2026-05-29 03:20:10.153481 | LOOP [upload-logs : Compress console log and json output] 2026-05-29 03:20:10.215198 | localhost | skipping: Conditional result was False 2026-05-29 03:20:10.224871 | localhost | skipping: Conditional result was False 2026-05-29 03:20:10.230111 | 2026-05-29 03:20:10.230206 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-29 03:20:10.268935 | localhost | skipping: Conditional result was False 2026-05-29 03:20:10.269152 | 2026-05-29 03:20:10.277836 | localhost | skipping: Conditional result was False 2026-05-29 03:20:10.289093 | 2026-05-29 03:20:10.289299 | LOOP [upload-logs : Upload console log and json output]