2026-08-26 03:21:38.296993 | Job console starting 2026-08-26 03:21:38.343206 | Updating git repos 2026-08-26 03:21:38.391598 | Cloning repos into workspace 2026-08-26 03:21:38.569694 | Restoring repo states 2026-08-26 03:21:38.592620 | Merging changes 2026-08-26 03:21:38.592646 | Checking out repos 2026-08-26 03:21:38.804425 | Preparing playbooks 2026-08-26 03:21:39.828400 | Running Ansible setup 2026-08-26 03:21:45.319802 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-08-26 03:21:46.475008 | 2026-08-26 03:21:46.475151 | PLAY [Base pre] 2026-08-26 03:21:46.495125 | 2026-08-26 03:21:46.495247 | TASK [Setup log path fact] 2026-08-26 03:21:46.537251 | debian-bookworm | ok 2026-08-26 03:21:46.551734 | 2026-08-26 03:21:46.551858 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-26 03:21:46.579515 | debian-bookworm | ok 2026-08-26 03:21:46.591716 | 2026-08-26 03:21:46.591820 | TASK [emit-job-header : Print job information] 2026-08-26 03:21:46.640268 | # Job Information 2026-08-26 03:21:46.640415 | Ansible Version: 2.16.14 2026-08-26 03:21:46.640444 | Job: python-black 2026-08-26 03:21:46.640471 | Pipeline: periodic-daily 2026-08-26 03:21:46.640490 | Executor: 521e9411259a 2026-08-26 03:21:46.640507 | Triggered by: https://github.com/osism/testbed 2026-08-26 03:21:46.640524 | Event ID: d3b41e1ac77947f6a45681b7df621c4c 2026-08-26 03:21:46.645917 | 2026-08-26 03:21:46.646469 | LOOP [emit-job-header : Print node information] 2026-08-26 03:21:46.783192 | debian-bookworm | ok: 2026-08-26 03:21:46.783556 | debian-bookworm | # Node Information 2026-08-26 03:21:46.783605 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-08-26 03:21:46.783627 | debian-bookworm | Hostname: debian 2026-08-26 03:21:46.783646 | debian-bookworm | Username: zuul 2026-08-26 03:21:46.783664 | debian-bookworm | Distro: Debian 12.15 2026-08-26 03:21:46.783684 | debian-bookworm | Provider: regiocloud-a 2026-08-26 03:21:46.783701 | debian-bookworm | Region: 2026-08-26 03:21:46.783719 | debian-bookworm | Label: debian-bookworm 2026-08-26 03:21:46.783735 | debian-bookworm | Product Name: OpenStack Nova 2026-08-26 03:21:46.783751 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe79:b1a4 2026-08-26 03:21:46.801568 | 2026-08-26 03:21:46.801676 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-26 03:21:47.899102 | debian-bookworm -> localhost | changed 2026-08-26 03:21:47.905216 | 2026-08-26 03:21:47.905306 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-26 03:21:49.374920 | debian-bookworm -> localhost | changed 2026-08-26 03:21:49.389606 | 2026-08-26 03:21:49.390966 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-26 03:21:49.934555 | debian-bookworm -> localhost | ok 2026-08-26 03:21:49.941013 | 2026-08-26 03:21:49.941139 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-26 03:21:49.973726 | debian-bookworm | ok 2026-08-26 03:21:50.002823 | debian-bookworm | included: /var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-26 03:21:50.013797 | 2026-08-26 03:21:50.013909 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-26 03:21:51.237579 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-08-26 03:21:51.237793 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/work/7d720d8823df47b9acb336eacb514eec_id_rsa 2026-08-26 03:21:51.237832 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/work/7d720d8823df47b9acb336eacb514eec_id_rsa.pub 2026-08-26 03:21:51.237860 | debian-bookworm -> localhost | The key fingerprint is: 2026-08-26 03:21:51.237883 | debian-bookworm -> localhost | SHA256:6Iyh0HDPtG3lMyM6FcHxzqjKZOKbUpqNwuWVnIsY7wg zuul-build-sshkey 2026-08-26 03:21:51.237907 | debian-bookworm -> localhost | The key's randomart image is: 2026-08-26 03:21:51.237938 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-08-26 03:21:51.237960 | debian-bookworm -> localhost | | .o. | 2026-08-26 03:21:51.237982 | debian-bookworm -> localhost | | .o | 2026-08-26 03:21:51.238002 | debian-bookworm -> localhost | |. . . . o | 2026-08-26 03:21:51.238034 | debian-bookworm -> localhost | | + + o O | 2026-08-26 03:21:51.238056 | debian-bookworm -> localhost | |. . * X S | 2026-08-26 03:21:51.238081 | debian-bookworm -> localhost | | o.o # . + | 2026-08-26 03:21:51.238103 | debian-bookworm -> localhost | |E*X B + | 2026-08-26 03:21:51.238125 | debian-bookworm -> localhost | |OB+= o | 2026-08-26 03:21:51.238146 | debian-bookworm -> localhost | |o==. | 2026-08-26 03:21:51.238167 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-08-26 03:21:51.238216 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.641086 2026-08-26 03:21:51.245259 | 2026-08-26 03:21:51.245354 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-26 03:21:51.273358 | debian-bookworm | ok 2026-08-26 03:21:51.289776 | debian-bookworm | included: /var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-26 03:21:51.302101 | 2026-08-26 03:21:51.302191 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-26 03:21:51.315976 | debian-bookworm | skipping: Conditional result was False 2026-08-26 03:21:51.323201 | 2026-08-26 03:21:51.323313 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-26 03:21:51.952725 | debian-bookworm | changed 2026-08-26 03:21:51.957214 | 2026-08-26 03:21:51.957293 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-26 03:21:52.239531 | debian-bookworm | ok 2026-08-26 03:21:52.244443 | 2026-08-26 03:21:52.244534 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-26 03:21:52.909171 | debian-bookworm | changed 2026-08-26 03:21:52.927411 | 2026-08-26 03:21:52.927523 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-26 03:21:53.631506 | debian-bookworm | changed 2026-08-26 03:21:53.636937 | 2026-08-26 03:21:53.637041 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-26 03:21:53.670362 | debian-bookworm | skipping: Conditional result was False 2026-08-26 03:21:53.676365 | 2026-08-26 03:21:53.676463 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-26 03:21:54.436049 | debian-bookworm -> localhost | changed 2026-08-26 03:21:54.446602 | 2026-08-26 03:21:54.446705 | TASK [add-build-sshkey : Add back temp key] 2026-08-26 03:21:54.844337 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/work/7d720d8823df47b9acb336eacb514eec_id_rsa (zuul-build-sshkey) 2026-08-26 03:21:54.844567 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.013283 2026-08-26 03:21:54.852519 | 2026-08-26 03:21:54.852630 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-26 03:21:55.275347 | debian-bookworm | ok 2026-08-26 03:21:55.280610 | 2026-08-26 03:21:55.280701 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-26 03:21:55.309986 | debian-bookworm | skipping: Conditional result was False 2026-08-26 03:21:55.320656 | 2026-08-26 03:21:55.320773 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-26 03:21:55.755422 | debian-bookworm | ok 2026-08-26 03:21:55.776297 | 2026-08-26 03:21:55.776426 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-26 03:21:55.825617 | debian-bookworm | ok 2026-08-26 03:21:55.831667 | 2026-08-26 03:21:55.831772 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-26 03:21:56.173875 | debian-bookworm -> localhost | ok 2026-08-26 03:21:56.180556 | 2026-08-26 03:21:56.180661 | TASK [validate-host : Collect information about the host] 2026-08-26 03:21:57.485520 | debian-bookworm | ok 2026-08-26 03:21:57.497075 | 2026-08-26 03:21:57.497177 | TASK [validate-host : Sanitize hostname] 2026-08-26 03:21:57.648839 | debian-bookworm | ok 2026-08-26 03:21:57.656584 | 2026-08-26 03:21:57.656729 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-26 03:21:58.239129 | debian-bookworm -> localhost | changed 2026-08-26 03:21:58.245197 | 2026-08-26 03:21:58.245297 | TASK [validate-host : Collect information about zuul worker] 2026-08-26 03:21:58.707272 | debian-bookworm | ok 2026-08-26 03:21:58.711805 | 2026-08-26 03:21:58.711894 | TASK [validate-host : Write out all zuul information for each host] 2026-08-26 03:21:59.247094 | debian-bookworm -> localhost | changed 2026-08-26 03:21:59.260038 | 2026-08-26 03:21:59.260143 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-08-26 03:21:59.546356 | debian-bookworm | ok 2026-08-26 03:21:59.552973 | 2026-08-26 03:21:59.553104 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-08-26 03:22:01.350956 | debian-bookworm | changed: 2026-08-26 03:22:01.351201 | debian-bookworm | cd+++++++++ src/ 2026-08-26 03:22:01.351237 | debian-bookworm | cd+++++++++ src/github.com/ 2026-08-26 03:22:01.351262 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-08-26 03:22:01.351285 | debian-bookworm | cd+++++++++ src/github.com/osism/testbed/ 2026-08-26 03:22:01.351306 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/work/logs" 2026-08-26 03:23:04.133303 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/work/artifacts" 2026-08-26 03:23:04.472678 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/7d720d8823df47b9acb336eacb514eec/work/docs" 2026-08-26 03:23:04.498154 | 2026-08-26 03:23:04.498324 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-26 03:23:05.380753 | debian-bookworm | changed: 2026-08-26 03:23:05.381073 | debian-bookworm | .d..t...... ./ 2026-08-26 03:23:05.381125 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-08-26 03:23:05.381150 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-08-26 03:23:05.381171 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-08-26 03:23:05.381192 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-08-26 03:23:05.381220 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-08-26 03:23:05.381280 | debian-bookworm | changed: All items complete 2026-08-26 03:23:05.381315 | 2026-08-26 03:23:06.007234 | debian-bookworm | changed: .d..t...... ./ 2026-08-26 03:23:06.592820 | debian-bookworm | changed: .d..t...... ./ 2026-08-26 03:23:06.614409 | 2026-08-26 03:23:06.614592 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-26 03:23:06.643096 | debian-bookworm | skipping: Conditional result was False 2026-08-26 03:23:06.646664 | debian-bookworm | skipping: Conditional result was False 2026-08-26 03:23:06.666585 | 2026-08-26 03:23:06.666748 | PLAY RECAP 2026-08-26 03:23:06.666945 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-26 03:23:06.666990 | 2026-08-26 03:23:06.849703 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-08-26 03:23:06.850684 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-26 03:23:07.667551 | 2026-08-26 03:23:07.667806 | PLAY [Base post] 2026-08-26 03:23:07.683206 | 2026-08-26 03:23:07.683376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-08-26 03:23:08.262625 | debian-bookworm | changed 2026-08-26 03:23:08.270611 | 2026-08-26 03:23:08.270748 | PLAY RECAP 2026-08-26 03:23:08.270877 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-26 03:23:08.270947 | 2026-08-26 03:23:08.422938 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-08-26 03:23:08.424539 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-08-26 03:23:09.340055 | 2026-08-26 03:23:09.340254 | PLAY [Base post-logs] 2026-08-26 03:23:09.351975 | 2026-08-26 03:23:09.352125 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-26 03:23:09.896946 | localhost | changed 2026-08-26 03:23:09.916503 | 2026-08-26 03:23:09.916805 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-26 03:23:09.957106 | localhost | ok 2026-08-26 03:23:09.964501 | 2026-08-26 03:23:09.964678 | TASK [Set zuul-log-path fact] 2026-08-26 03:23:09.982783 | localhost | ok 2026-08-26 03:23:09.992132 | 2026-08-26 03:23:09.992285 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-26 03:23:10.025134 | localhost | ok 2026-08-26 03:23:10.032274 | 2026-08-26 03:23:10.032522 | TASK [upload-logs : Create log directories] 2026-08-26 03:23:10.590230 | localhost | changed 2026-08-26 03:23:10.594402 | 2026-08-26 03:23:10.594556 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-26 03:23:11.277471 | localhost -> localhost | ok: Runtime: 0:00:00.025570 2026-08-26 03:23:11.285290 | 2026-08-26 03:23:11.285451 | TASK [upload-logs : Upload logs to log server] 2026-08-26 03:23:11.960796 | localhost | Output suppressed because no_log was given 2026-08-26 03:23:11.965380 | 2026-08-26 03:23:11.965554 | LOOP [upload-logs : Compress console log and json output] 2026-08-26 03:23:12.041185 | localhost | skipping: Conditional result was False 2026-08-26 03:23:12.050917 | localhost | skipping: Conditional result was False 2026-08-26 03:23:12.065005 | 2026-08-26 03:23:12.065391 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-26 03:23:12.132956 | localhost | skipping: Conditional result was False 2026-08-26 03:23:12.133284 | 2026-08-26 03:23:12.138019 | localhost | skipping: Conditional result was False 2026-08-26 03:23:12.150650 | 2026-08-26 03:23:12.151082 | LOOP [upload-logs : Upload console log and json output]