2026-05-07 03:08:49.561763 | Job console starting 2026-05-07 03:08:49.575358 | Updating git repos 2026-05-07 03:08:49.608380 | Cloning repos into workspace 2026-05-07 03:08:49.762775 | Restoring repo states 2026-05-07 03:08:49.777208 | Merging changes 2026-05-07 03:08:49.777230 | Checking out repos 2026-05-07 03:08:49.956061 | Preparing playbooks 2026-05-07 03:08:51.427069 | Running Ansible setup 2026-05-07 03:08:57.013181 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-07 03:08:58.440727 | 2026-05-07 03:08:58.440854 | PLAY [Base pre] 2026-05-07 03:08:58.456308 | 2026-05-07 03:08:58.456420 | TASK [Setup log path fact] 2026-05-07 03:08:58.474205 | debian-bookworm | ok 2026-05-07 03:08:58.505685 | 2026-05-07 03:08:58.505878 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-07 03:08:58.571178 | debian-bookworm | ok 2026-05-07 03:08:58.605813 | 2026-05-07 03:08:58.605932 | TASK [emit-job-header : Print job information] 2026-05-07 03:08:58.667929 | # Job Information 2026-05-07 03:08:58.668083 | Ansible Version: 2.16.14 2026-05-07 03:08:58.668116 | Job: python-black 2026-05-07 03:08:58.668146 | Pipeline: periodic-daily 2026-05-07 03:08:58.668168 | Executor: 521e9411259a 2026-05-07 03:08:58.668189 | Triggered by: https://github.com/osism/testbed 2026-05-07 03:08:58.668210 | Event ID: eefb09ff71274dadb597c8443c9a1ddb 2026-05-07 03:08:58.673028 | 2026-05-07 03:08:58.674335 | LOOP [emit-job-header : Print node information] 2026-05-07 03:08:58.856849 | debian-bookworm | ok: 2026-05-07 03:08:58.857099 | debian-bookworm | # Node Information 2026-05-07 03:08:58.857136 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-07 03:08:58.857162 | debian-bookworm | Hostname: debian 2026-05-07 03:08:58.857184 | debian-bookworm | Username: zuul 2026-05-07 03:08:58.857204 | debian-bookworm | Distro: Debian 12.13 2026-05-07 03:08:58.857233 | debian-bookworm | Provider: regiocloud-a 2026-05-07 03:08:58.857259 | debian-bookworm | Region: 2026-05-07 03:08:58.857280 | debian-bookworm | Label: debian-bookworm 2026-05-07 03:08:58.857299 | debian-bookworm | Product Name: OpenStack Nova 2026-05-07 03:08:58.857318 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:febd:b76a 2026-05-07 03:08:58.874793 | 2026-05-07 03:08:58.879060 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-07 03:08:59.789824 | debian-bookworm -> localhost | changed 2026-05-07 03:08:59.798053 | 2026-05-07 03:08:59.798204 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-07 03:09:02.237338 | debian-bookworm -> localhost | changed 2026-05-07 03:09:02.276361 | 2026-05-07 03:09:02.276982 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-07 03:09:03.350111 | debian-bookworm -> localhost | ok 2026-05-07 03:09:03.357217 | 2026-05-07 03:09:03.357328 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-07 03:09:03.392231 | debian-bookworm | ok 2026-05-07 03:09:03.442348 | debian-bookworm | included: /var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-07 03:09:03.456964 | 2026-05-07 03:09:03.457069 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-07 03:09:06.146563 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-07 03:09:06.147105 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/work/c27ddbb8262845f1956eab34309fc6ef_id_rsa 2026-05-07 03:09:06.147201 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/work/c27ddbb8262845f1956eab34309fc6ef_id_rsa.pub 2026-05-07 03:09:06.147228 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-07 03:09:06.147248 | debian-bookworm -> localhost | SHA256:wL2UQvZnHvfc3yxzr9la2oaCqVndoia/tB8A+TIb1ZY zuul-build-sshkey 2026-05-07 03:09:06.147267 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-07 03:09:06.147291 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-07 03:09:06.147310 | debian-bookworm -> localhost | | o | 2026-05-07 03:09:06.147329 | debian-bookworm -> localhost | | + o o . . | 2026-05-07 03:09:06.147346 | debian-bookworm -> localhost | | + B = E | 2026-05-07 03:09:06.147362 | debian-bookworm -> localhost | | + O + o . | 2026-05-07 03:09:06.147379 | debian-bookworm -> localhost | | S + o .| 2026-05-07 03:09:06.147427 | debian-bookworm -> localhost | | = o . .o| 2026-05-07 03:09:06.147480 | debian-bookworm -> localhost | | . oo+ +.*| 2026-05-07 03:09:06.147502 | debian-bookworm -> localhost | | .++o.o.Xo| 2026-05-07 03:09:06.147520 | debian-bookworm -> localhost | | o==o..=++| 2026-05-07 03:09:06.147538 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-07 03:09:06.147599 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.225095 2026-05-07 03:09:06.163317 | 2026-05-07 03:09:06.163407 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-07 03:09:06.222172 | debian-bookworm | ok 2026-05-07 03:09:06.257604 | debian-bookworm | included: /var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-07 03:09:06.308884 | 2026-05-07 03:09:06.308994 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-07 03:09:06.372023 | debian-bookworm | skipping: Conditional result was False 2026-05-07 03:09:06.381228 | 2026-05-07 03:09:06.381325 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-07 03:09:07.228972 | debian-bookworm | changed 2026-05-07 03:09:07.238369 | 2026-05-07 03:09:07.238451 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-07 03:09:07.554582 | debian-bookworm | ok 2026-05-07 03:09:07.559382 | 2026-05-07 03:09:07.559463 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-07 03:09:08.313996 | debian-bookworm | changed 2026-05-07 03:09:08.324220 | 2026-05-07 03:09:08.324330 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-07 03:09:09.067797 | debian-bookworm | changed 2026-05-07 03:09:09.072778 | 2026-05-07 03:09:09.072858 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-07 03:09:09.105578 | debian-bookworm | skipping: Conditional result was False 2026-05-07 03:09:09.110967 | 2026-05-07 03:09:09.111056 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-07 03:09:10.105712 | debian-bookworm -> localhost | changed 2026-05-07 03:09:10.115366 | 2026-05-07 03:09:10.115464 | TASK [add-build-sshkey : Add back temp key] 2026-05-07 03:09:10.656864 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/work/c27ddbb8262845f1956eab34309fc6ef_id_rsa (zuul-build-sshkey) 2026-05-07 03:09:10.657069 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.015718 2026-05-07 03:09:10.664512 | 2026-05-07 03:09:10.664610 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-07 03:09:11.144051 | debian-bookworm | ok 2026-05-07 03:09:11.149044 | 2026-05-07 03:09:11.149142 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-07 03:09:11.192286 | debian-bookworm | skipping: Conditional result was False 2026-05-07 03:09:11.202703 | 2026-05-07 03:09:11.202812 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-07 03:09:11.820535 | debian-bookworm | ok 2026-05-07 03:09:11.828977 | 2026-05-07 03:09:11.829064 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-07 03:09:11.895885 | debian-bookworm | ok 2026-05-07 03:09:11.899947 | 2026-05-07 03:09:11.900026 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-07 03:09:12.337936 | debian-bookworm -> localhost | ok 2026-05-07 03:09:12.343235 | 2026-05-07 03:09:12.343322 | TASK [validate-host : Collect information about the host] 2026-05-07 03:09:13.630462 | debian-bookworm | ok 2026-05-07 03:09:13.649137 | 2026-05-07 03:09:13.649235 | TASK [validate-host : Sanitize hostname] 2026-05-07 03:09:13.804544 | debian-bookworm | ok 2026-05-07 03:09:13.858004 | 2026-05-07 03:09:13.858097 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-07 03:09:15.204472 | debian-bookworm -> localhost | changed 2026-05-07 03:09:15.208972 | 2026-05-07 03:09:15.209055 | TASK [validate-host : Collect information about zuul worker] 2026-05-07 03:09:15.732036 | debian-bookworm | ok 2026-05-07 03:09:15.736736 | 2026-05-07 03:09:15.736831 | TASK [validate-host : Write out all zuul information for each host] 2026-05-07 03:09:17.043646 | debian-bookworm -> localhost | changed 2026-05-07 03:09:17.051351 | 2026-05-07 03:09:17.051443 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-07 03:09:17.340907 | debian-bookworm | ok 2026-05-07 03:09:17.345403 | 2026-05-07 03:09:17.345524 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-07 03:09:18.868070 | debian-bookworm | changed: 2026-05-07 03:09:18.868213 | debian-bookworm | cd+++++++++ src/ 2026-05-07 03:09:18.868242 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-07 03:09:18.868261 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-07 03:09:18.868278 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-05-07 03:09:18.868295 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/work/logs" 2026-05-07 03:09:48.345627 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/work/artifacts" 2026-05-07 03:09:48.670781 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/c27ddbb8262845f1956eab34309fc6ef/work/docs" 2026-05-07 03:09:48.683151 | 2026-05-07 03:09:48.683262 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-07 03:09:49.674204 | debian-bookworm | changed: 2026-05-07 03:09:49.674402 | debian-bookworm | .d..t...... ./ 2026-05-07 03:09:49.674438 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-07 03:09:49.674468 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-07 03:09:49.674495 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-07 03:09:49.674534 | debian-bookworm | changed: All items complete 2026-05-07 03:09:49.674562 | 2026-05-07 03:09:50.220051 | debian-bookworm | changed: .d..t...... ./ 2026-05-07 03:09:51.108726 | debian-bookworm | changed: .d..t...... ./ 2026-05-07 03:09:51.139409 | 2026-05-07 03:09:51.139541 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-07 03:09:51.199388 | debian-bookworm | skipping: Conditional result was False 2026-05-07 03:09:51.210816 | debian-bookworm | skipping: Conditional result was False 2026-05-07 03:09:51.225971 | 2026-05-07 03:09:51.226043 | PLAY RECAP 2026-05-07 03:09:51.226088 | debian-bookworm | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-05-07 03:09:51.226116 | 2026-05-07 03:09:51.443181 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-07 03:09:51.443839 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-07 03:09:52.571717 | 2026-05-07 03:09:52.571838 | PLAY [Base post] 2026-05-07 03:09:52.582183 | 2026-05-07 03:09:52.582295 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-07 03:09:53.125278 | debian-bookworm | changed 2026-05-07 03:09:53.133603 | 2026-05-07 03:09:53.133705 | PLAY RECAP 2026-05-07 03:09:53.133766 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-07 03:09:53.133826 | 2026-05-07 03:09:53.230036 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-07 03:09:53.230666 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-07 03:09:54.090467 | 2026-05-07 03:09:54.090610 | PLAY [Base post-logs] 2026-05-07 03:09:54.125002 | 2026-05-07 03:09:54.125120 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-07 03:09:55.345822 | localhost | changed 2026-05-07 03:09:55.353681 | 2026-05-07 03:09:55.353801 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-07 03:09:55.379926 | localhost | ok 2026-05-07 03:09:55.382259 | 2026-05-07 03:09:55.382331 | TASK [Set zuul-log-path fact] 2026-05-07 03:09:55.406363 | localhost | ok 2026-05-07 03:09:55.415828 | 2026-05-07 03:09:55.416093 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-07 03:09:55.468262 | localhost | ok 2026-05-07 03:09:55.470653 | 2026-05-07 03:09:55.470734 | TASK [upload-logs : Create log directories] 2026-05-07 03:09:56.312760 | localhost | changed 2026-05-07 03:09:56.320471 | 2026-05-07 03:09:56.320589 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-07 03:09:57.173546 | localhost -> localhost | ok: Runtime: 0:00:00.021091 2026-05-07 03:09:57.176549 | 2026-05-07 03:09:57.176748 | TASK [upload-logs : Upload logs to log server] 2026-05-07 03:09:58.085252 | localhost | Output suppressed because no_log was given 2026-05-07 03:09:58.087069 | 2026-05-07 03:09:58.087358 | LOOP [upload-logs : Compress console log and json output] 2026-05-07 03:09:58.126371 | localhost | skipping: Conditional result was False 2026-05-07 03:09:58.131810 | localhost | skipping: Conditional result was False 2026-05-07 03:09:58.142629 | 2026-05-07 03:09:58.142716 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-07 03:09:58.190587 | localhost | skipping: Conditional result was False 2026-05-07 03:09:58.190814 | 2026-05-07 03:09:58.196309 | localhost | skipping: Conditional result was False 2026-05-07 03:09:58.207415 | 2026-05-07 03:09:58.207507 | LOOP [upload-logs : Upload console log and json output]