2026-06-25 03:47:22.306411 | Job console starting 2026-06-25 03:47:22.319757 | Updating git repos 2026-06-25 03:47:22.364958 | Cloning repos into workspace 2026-06-25 03:47:22.440133 | Restoring repo states 2026-06-25 03:47:22.452776 | Merging changes 2026-06-25 03:47:22.452807 | Checking out repos 2026-06-25 03:47:22.611009 | Preparing playbooks 2026-06-25 03:47:23.383100 | Running Ansible setup 2026-06-25 03:47:28.213132 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-06-25 03:47:29.070084 | 2026-06-25 03:47:29.070293 | PLAY [Base pre] 2026-06-25 03:47:29.089835 | 2026-06-25 03:47:29.090016 | TASK [Setup log path fact] 2026-06-25 03:47:29.136783 | debian-bookworm | ok 2026-06-25 03:47:29.166268 | 2026-06-25 03:47:29.166516 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-25 03:47:29.230862 | debian-bookworm | ok 2026-06-25 03:47:29.249745 | 2026-06-25 03:47:29.249907 | TASK [emit-job-header : Print job information] 2026-06-25 03:47:29.315296 | # Job Information 2026-06-25 03:47:29.315596 | Ansible Version: 2.16.14 2026-06-25 03:47:29.315633 | Job: python-black 2026-06-25 03:47:29.315668 | Pipeline: periodic-daily 2026-06-25 03:47:29.315692 | Executor: 521e9411259a 2026-06-25 03:47:29.315727 | Triggered by: https://github.com/osism/testbed 2026-06-25 03:47:29.315751 | Event ID: 77cc396744e240e1a150411d9fc989f5 2026-06-25 03:47:29.322560 | 2026-06-25 03:47:29.322702 | LOOP [emit-job-header : Print node information] 2026-06-25 03:47:29.463674 | debian-bookworm | ok: 2026-06-25 03:47:29.464055 | debian-bookworm | # Node Information 2026-06-25 03:47:29.464109 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-06-25 03:47:29.464137 | debian-bookworm | Hostname: debian 2026-06-25 03:47:29.464161 | debian-bookworm | Username: zuul 2026-06-25 03:47:29.464183 | debian-bookworm | Distro: Debian 12.14 2026-06-25 03:47:29.464208 | debian-bookworm | Provider: regiocloud-a 2026-06-25 03:47:29.464230 | debian-bookworm | Region: 2026-06-25 03:47:29.464251 | debian-bookworm | Label: debian-bookworm 2026-06-25 03:47:29.464272 | debian-bookworm | Product Name: OpenStack Nova 2026-06-25 03:47:29.464292 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fea1:a22e 2026-06-25 03:47:29.490411 | 2026-06-25 03:47:29.490629 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-25 03:47:30.052103 | debian-bookworm -> localhost | changed 2026-06-25 03:47:30.064465 | 2026-06-25 03:47:30.064609 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-25 03:47:31.234668 | debian-bookworm -> localhost | changed 2026-06-25 03:47:31.254897 | 2026-06-25 03:47:31.255356 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-25 03:47:31.596598 | debian-bookworm -> localhost | ok 2026-06-25 03:47:31.610386 | 2026-06-25 03:47:31.610603 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-25 03:47:31.655075 | debian-bookworm | ok 2026-06-25 03:47:31.672872 | debian-bookworm | included: /var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-25 03:47:31.679411 | 2026-06-25 03:47:31.679571 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-25 03:47:33.002059 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-06-25 03:47:33.002350 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/work/6d52418e65634d1193151a61c3157e6b_id_rsa 2026-06-25 03:47:33.002391 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/work/6d52418e65634d1193151a61c3157e6b_id_rsa.pub 2026-06-25 03:47:33.002460 | debian-bookworm -> localhost | The key fingerprint is: 2026-06-25 03:47:33.002488 | debian-bookworm -> localhost | SHA256:+pQ6BokJuGfGqxUSg/zHvSgUZa7wQ0YrO7zCzNgS1zk zuul-build-sshkey 2026-06-25 03:47:33.002511 | debian-bookworm -> localhost | The key's randomart image is: 2026-06-25 03:47:33.002546 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-06-25 03:47:33.002570 | debian-bookworm -> localhost | | . o | 2026-06-25 03:47:33.003006 | debian-bookworm -> localhost | |o . = | 2026-06-25 03:47:33.003050 | debian-bookworm -> localhost | |=+ = . | 2026-06-25 03:47:33.003076 | debian-bookworm -> localhost | |+oO.+.. | 2026-06-25 03:47:33.003100 | debian-bookworm -> localhost | |oBoOE+ .S | 2026-06-25 03:47:33.003129 | debian-bookworm -> localhost | |*=@.=..... | 2026-06-25 03:47:33.003152 | debian-bookworm -> localhost | |+Ooo o..o | 2026-06-25 03:47:33.003172 | debian-bookworm -> localhost | |.o. . o+ | 2026-06-25 03:47:33.003194 | debian-bookworm -> localhost | |.. .... | 2026-06-25 03:47:33.003216 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-06-25 03:47:33.003278 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.753445 2026-06-25 03:47:33.017930 | 2026-06-25 03:47:33.018061 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-25 03:47:33.047731 | debian-bookworm | ok 2026-06-25 03:47:33.059159 | debian-bookworm | included: /var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-25 03:47:33.067570 | 2026-06-25 03:47:33.067677 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-25 03:47:33.092049 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:47:33.098749 | 2026-06-25 03:47:33.098893 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-25 03:47:33.928055 | debian-bookworm | changed 2026-06-25 03:47:33.938269 | 2026-06-25 03:47:33.938465 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-25 03:47:34.233806 | debian-bookworm | ok 2026-06-25 03:47:34.242098 | 2026-06-25 03:47:34.242278 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-25 03:47:35.063943 | debian-bookworm | changed 2026-06-25 03:47:35.080046 | 2026-06-25 03:47:35.080613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-25 03:47:35.808792 | debian-bookworm | changed 2026-06-25 03:47:35.815859 | 2026-06-25 03:47:35.816025 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-25 03:47:35.850337 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:47:35.864026 | 2026-06-25 03:47:35.864218 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-25 03:47:36.454732 | debian-bookworm -> localhost | changed 2026-06-25 03:47:36.493282 | 2026-06-25 03:47:36.494090 | TASK [add-build-sshkey : Add back temp key] 2026-06-25 03:47:36.899293 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/work/6d52418e65634d1193151a61c3157e6b_id_rsa (zuul-build-sshkey) 2026-06-25 03:47:36.899851 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.014179 2026-06-25 03:47:36.924555 | 2026-06-25 03:47:36.924784 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-25 03:47:37.422082 | debian-bookworm | ok 2026-06-25 03:47:37.429778 | 2026-06-25 03:47:37.429922 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-25 03:47:37.465804 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:47:37.485181 | 2026-06-25 03:47:37.485321 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-06-25 03:47:37.981914 | debian-bookworm | ok 2026-06-25 03:47:37.999459 | 2026-06-25 03:47:37.999670 | TASK [validate-host : Define zuul_info_dir fact] 2026-06-25 03:47:38.040996 | debian-bookworm | ok 2026-06-25 03:47:38.049282 | 2026-06-25 03:47:38.049404 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-06-25 03:47:38.371989 | debian-bookworm -> localhost | ok 2026-06-25 03:47:38.383700 | 2026-06-25 03:47:38.383867 | TASK [validate-host : Collect information about the host] 2026-06-25 03:47:40.860754 | debian-bookworm | ok 2026-06-25 03:47:40.880906 | 2026-06-25 03:47:40.881105 | TASK [validate-host : Sanitize hostname] 2026-06-25 03:47:41.079070 | debian-bookworm | ok 2026-06-25 03:47:41.084385 | 2026-06-25 03:47:41.084581 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-06-25 03:47:41.896811 | debian-bookworm -> localhost | changed 2026-06-25 03:47:41.904282 | 2026-06-25 03:47:41.904474 | TASK [validate-host : Collect information about zuul worker] 2026-06-25 03:47:42.536914 | debian-bookworm | ok 2026-06-25 03:47:42.551233 | 2026-06-25 03:47:42.551474 | TASK [validate-host : Write out all zuul information for each host] 2026-06-25 03:47:43.203951 | debian-bookworm -> localhost | changed 2026-06-25 03:47:43.224656 | 2026-06-25 03:47:43.224809 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-06-25 03:47:43.556316 | debian-bookworm | ok 2026-06-25 03:47:43.582172 | 2026-06-25 03:47:43.582465 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-06-25 03:47:45.073433 | debian-bookworm | changed: 2026-06-25 03:47:45.073700 | debian-bookworm | cd+++++++++ src/ 2026-06-25 03:47:45.073736 | debian-bookworm | cd+++++++++ src/github.com/ 2026-06-25 03:47:45.073761 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-06-25 03:47:45.073783 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-06-25 03:47:45.073803 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/work/logs" 2026-06-25 03:48:49.313974 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/work/artifacts" 2026-06-25 03:48:49.607662 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/6d52418e65634d1193151a61c3157e6b/work/docs" 2026-06-25 03:48:49.627013 | 2026-06-25 03:48:49.627214 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-25 03:48:50.536930 | debian-bookworm | changed: 2026-06-25 03:48:50.537211 | debian-bookworm | .d..t...... ./ 2026-06-25 03:48:50.537246 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-06-25 03:48:50.537271 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-06-25 03:48:50.537303 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-06-25 03:48:50.537331 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-06-25 03:48:50.537384 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-06-25 03:48:50.537449 | debian-bookworm | changed: All items complete 2026-06-25 03:48:50.537480 | 2026-06-25 03:48:51.142230 | debian-bookworm | changed: .d..t...... ./ 2026-06-25 03:48:51.771686 | debian-bookworm | changed: .d..t...... ./ 2026-06-25 03:48:51.805846 | 2026-06-25 03:48:51.806034 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-25 03:48:51.851471 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:48:51.855681 | debian-bookworm | skipping: Conditional result was False 2026-06-25 03:48:51.867060 | 2026-06-25 03:48:51.867151 | PLAY RECAP 2026-06-25 03:48:51.867206 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-06-25 03:48:51.867230 | 2026-06-25 03:48:52.021609 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-06-25 03:48:52.022632 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-25 03:48:52.884389 | 2026-06-25 03:48:52.884609 | PLAY [Base post] 2026-06-25 03:48:52.900416 | 2026-06-25 03:48:52.900580 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-25 03:48:53.522423 | debian-bookworm | changed 2026-06-25 03:48:53.532766 | 2026-06-25 03:48:53.532917 | PLAY RECAP 2026-06-25 03:48:53.532997 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-25 03:48:53.533071 | 2026-06-25 03:48:53.689757 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-06-25 03:48:53.691325 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-25 03:48:54.590198 | 2026-06-25 03:48:54.590523 | PLAY [Base post-logs] 2026-06-25 03:48:54.615641 | 2026-06-25 03:48:54.615829 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-25 03:48:55.351039 | localhost | changed 2026-06-25 03:48:55.361944 | 2026-06-25 03:48:55.362120 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-25 03:48:55.411864 | localhost | ok 2026-06-25 03:48:55.415413 | 2026-06-25 03:48:55.415540 | TASK [Set zuul-log-path fact] 2026-06-25 03:48:55.431848 | localhost | ok 2026-06-25 03:48:55.440675 | 2026-06-25 03:48:55.440804 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-25 03:48:55.467210 | localhost | ok 2026-06-25 03:48:55.470598 | 2026-06-25 03:48:55.470712 | TASK [upload-logs : Create log directories] 2026-06-25 03:48:55.994500 | localhost | changed 2026-06-25 03:48:55.997553 | 2026-06-25 03:48:55.997667 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-25 03:48:56.551276 | localhost -> localhost | ok: Runtime: 0:00:00.007808 2026-06-25 03:48:56.562204 | 2026-06-25 03:48:56.562436 | TASK [upload-logs : Upload logs to log server] 2026-06-25 03:48:57.248651 | localhost | Output suppressed because no_log was given 2026-06-25 03:48:57.253823 | 2026-06-25 03:48:57.254149 | LOOP [upload-logs : Compress console log and json output] 2026-06-25 03:48:57.306628 | localhost | skipping: Conditional result was False 2026-06-25 03:48:57.325447 | localhost | skipping: Conditional result was False 2026-06-25 03:48:57.340619 | 2026-06-25 03:48:57.340849 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-25 03:48:57.396008 | localhost | skipping: Conditional result was False 2026-06-25 03:48:57.396647 | 2026-06-25 03:48:57.400286 | localhost | skipping: Conditional result was False 2026-06-25 03:48:57.416807 | 2026-06-25 03:48:57.417083 | LOOP [upload-logs : Upload console log and json output]