2026-09-09 03:04:45.273050 | Job console starting 2026-09-09 03:04:45.281136 | Updating git repos 2026-09-09 03:04:45.305439 | Cloning repos into workspace 2026-09-09 03:04:45.359222 | Restoring repo states 2026-09-09 03:04:45.364234 | Merging changes 2026-09-09 03:04:45.364247 | Checking out repos 2026-09-09 03:04:45.421604 | Preparing playbooks 2026-09-09 03:04:46.355288 | Running Ansible setup 2026-09-09 03:04:52.142185 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-09 03:04:52.891615 | 2026-09-09 03:04:52.892148 | PLAY [Base pre] 2026-09-09 03:04:52.921930 | 2026-09-09 03:04:52.922077 | TASK [Setup log path fact] 2026-09-09 03:04:52.952313 | debian-bookworm | ok 2026-09-09 03:04:52.970507 | 2026-09-09 03:04:52.970652 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-09 03:04:53.004793 | debian-bookworm | ok 2026-09-09 03:04:53.024911 | 2026-09-09 03:04:53.025049 | TASK [emit-job-header : Print job information] 2026-09-09 03:04:53.062084 | # Job Information 2026-09-09 03:04:53.062262 | Ansible Version: 2.16.14 2026-09-09 03:04:53.062298 | Job: python-black 2026-09-09 03:04:53.062345 | Pipeline: periodic-daily 2026-09-09 03:04:53.062369 | Executor: 521e9411259a 2026-09-09 03:04:53.062390 | Triggered by: https://github.com/osism/metalbox 2026-09-09 03:04:53.062412 | Event ID: 072358d6dbbf4972b0e9296c4b1fd83a 2026-09-09 03:04:53.077686 | 2026-09-09 03:04:53.077834 | LOOP [emit-job-header : Print node information] 2026-09-09 03:04:53.205473 | debian-bookworm | ok: 2026-09-09 03:04:53.205705 | debian-bookworm | # Node Information 2026-09-09 03:04:53.205741 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-09 03:04:53.205766 | debian-bookworm | Hostname: debian 2026-09-09 03:04:53.205788 | debian-bookworm | Username: zuul 2026-09-09 03:04:53.205808 | debian-bookworm | Distro: Debian 12.15 2026-09-09 03:04:53.205832 | debian-bookworm | Provider: regiocloud-a 2026-09-09 03:04:53.205853 | debian-bookworm | Region: 2026-09-09 03:04:53.205874 | debian-bookworm | Label: debian-bookworm 2026-09-09 03:04:53.205894 | debian-bookworm | Product Name: OpenStack Nova 2026-09-09 03:04:53.205913 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef8:66a 2026-09-09 03:04:53.216536 | 2026-09-09 03:04:53.216663 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-09 03:04:53.945086 | debian-bookworm -> localhost | changed 2026-09-09 03:04:53.952868 | 2026-09-09 03:04:53.952997 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-09 03:04:55.238452 | debian-bookworm -> localhost | changed 2026-09-09 03:04:55.252779 | 2026-09-09 03:04:55.252966 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-09 03:04:55.722783 | debian-bookworm -> localhost | ok 2026-09-09 03:04:55.729994 | 2026-09-09 03:04:55.730124 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-09 03:04:55.786552 | debian-bookworm | ok 2026-09-09 03:04:55.824762 | debian-bookworm | included: /var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-09 03:04:55.833723 | 2026-09-09 03:04:55.833850 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-09 03:04:59.502768 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-09 03:04:59.503787 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/work/8bc51ef98b8d455db62cf8649f21032e_id_rsa 2026-09-09 03:04:59.503846 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/work/8bc51ef98b8d455db62cf8649f21032e_id_rsa.pub 2026-09-09 03:04:59.503870 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-09 03:04:59.503891 | debian-bookworm -> localhost | SHA256:5KchQzwnFXYwf1X7J13RKbRXaa9c9hlDe0d6MhRk2PU zuul-build-sshkey 2026-09-09 03:04:59.503910 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-09 03:04:59.503936 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-09 03:04:59.503956 | debian-bookworm -> localhost | | *o. .===B| 2026-09-09 03:04:59.503975 | debian-bookworm -> localhost | | . o + .++=B| 2026-09-09 03:04:59.503992 | debian-bookworm -> localhost | | = o . .o+=E| 2026-09-09 03:04:59.504008 | debian-bookworm -> localhost | | . * . ==X| 2026-09-09 03:04:59.504024 | debian-bookworm -> localhost | | o S . ..B@| 2026-09-09 03:04:59.504045 | debian-bookworm -> localhost | | o + ooo| 2026-09-09 03:04:59.504062 | debian-bookworm -> localhost | | . | 2026-09-09 03:04:59.504079 | debian-bookworm -> localhost | | | 2026-09-09 03:04:59.504096 | debian-bookworm -> localhost | | | 2026-09-09 03:04:59.504112 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-09 03:04:59.504156 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.906382 2026-09-09 03:04:59.516390 | 2026-09-09 03:04:59.516467 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-09 03:04:59.544155 | debian-bookworm | ok 2026-09-09 03:04:59.553663 | debian-bookworm | included: /var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-09 03:04:59.575958 | 2026-09-09 03:04:59.576454 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-09 03:04:59.591998 | debian-bookworm | skipping: Conditional result was False 2026-09-09 03:04:59.598488 | 2026-09-09 03:04:59.598588 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-09 03:05:00.383208 | debian-bookworm | changed 2026-09-09 03:05:00.388525 | 2026-09-09 03:05:00.388617 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-09 03:05:00.694183 | debian-bookworm | ok 2026-09-09 03:05:00.707627 | 2026-09-09 03:05:00.707731 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-09 03:05:01.483846 | debian-bookworm | changed 2026-09-09 03:05:01.488189 | 2026-09-09 03:05:01.488260 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-09 03:05:02.177796 | debian-bookworm | changed 2026-09-09 03:05:02.183080 | 2026-09-09 03:05:02.183166 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-09 03:05:02.211211 | debian-bookworm | skipping: Conditional result was False 2026-09-09 03:05:02.217201 | 2026-09-09 03:05:02.217326 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-09 03:05:02.833874 | debian-bookworm -> localhost | changed 2026-09-09 03:05:02.843984 | 2026-09-09 03:05:02.844072 | TASK [add-build-sshkey : Add back temp key] 2026-09-09 03:05:03.239171 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/work/8bc51ef98b8d455db62cf8649f21032e_id_rsa (zuul-build-sshkey) 2026-09-09 03:05:03.239392 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.016394 2026-09-09 03:05:03.246445 | 2026-09-09 03:05:03.246528 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-09 03:05:03.774381 | debian-bookworm | ok 2026-09-09 03:05:03.778476 | 2026-09-09 03:05:03.778551 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-09 03:05:03.801040 | debian-bookworm | skipping: Conditional result was False 2026-09-09 03:05:03.809447 | 2026-09-09 03:05:03.809528 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-09 03:05:04.222493 | debian-bookworm | ok 2026-09-09 03:05:04.234254 | 2026-09-09 03:05:04.234364 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-09 03:05:04.263663 | debian-bookworm | ok 2026-09-09 03:05:04.268450 | 2026-09-09 03:05:04.268531 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-09 03:05:04.707816 | debian-bookworm -> localhost | ok 2026-09-09 03:05:04.713805 | 2026-09-09 03:05:04.713892 | TASK [validate-host : Collect information about the host] 2026-09-09 03:05:05.931790 | debian-bookworm | ok 2026-09-09 03:05:05.948716 | 2026-09-09 03:05:05.948808 | TASK [validate-host : Sanitize hostname] 2026-09-09 03:05:06.207211 | debian-bookworm | ok 2026-09-09 03:05:06.220046 | 2026-09-09 03:05:06.220141 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-09 03:05:07.052687 | debian-bookworm -> localhost | changed 2026-09-09 03:05:07.057357 | 2026-09-09 03:05:07.057445 | TASK [validate-host : Collect information about zuul worker] 2026-09-09 03:05:07.578201 | debian-bookworm | ok 2026-09-09 03:05:07.582040 | 2026-09-09 03:05:07.582121 | TASK [validate-host : Write out all zuul information for each host] 2026-09-09 03:05:08.194672 | debian-bookworm -> localhost | changed 2026-09-09 03:05:08.209606 | 2026-09-09 03:05:08.209692 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-09 03:05:08.523138 | debian-bookworm | ok 2026-09-09 03:05:08.527495 | 2026-09-09 03:05:08.527565 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-09 03:05:09.418128 | debian-bookworm | changed: 2026-09-09 03:05:09.418316 | debian-bookworm | cd+++++++++ src/ 2026-09-09 03:05:09.418358 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-09 03:05:09.418385 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-09 03:05:09.418408 | debian-bookworm | cd+++++++++ src/github.com/osism/metalbox/ 2026-09-09 03:05:09.418430 | debian-bookworm | Containerfile 2026-09-09 03:05:09.464962 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/work/logs" 2026-09-09 03:06:13.603481 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/work/artifacts" 2026-09-09 03:06:13.919357 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/8bc51ef98b8d455db62cf8649f21032e/work/docs" 2026-09-09 03:06:13.935538 | 2026-09-09 03:06:13.935765 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-09 03:06:14.712788 | debian-bookworm | changed: 2026-09-09 03:06:14.713034 | debian-bookworm | .d..t...... ./ 2026-09-09 03:06:14.713071 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-09 03:06:14.713095 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-09 03:06:14.713117 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-09 03:06:14.713140 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-09 03:06:14.713162 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-09 03:06:14.713204 | debian-bookworm | changed: All items complete 2026-09-09 03:06:14.713272 | 2026-09-09 03:06:15.290088 | debian-bookworm | changed: .d..t...... ./ 2026-09-09 03:06:15.908735 | debian-bookworm | changed: .d..t...... ./ 2026-09-09 03:06:15.930250 | 2026-09-09 03:06:15.930378 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-09 03:06:15.956400 | debian-bookworm | skipping: Conditional result was False 2026-09-09 03:06:15.962307 | debian-bookworm | skipping: Conditional result was False 2026-09-09 03:06:15.973748 | 2026-09-09 03:06:15.973830 | PLAY RECAP 2026-09-09 03:06:15.973882 | debian-bookworm | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-09-09 03:06:15.973907 | 2026-09-09 03:06:16.100356 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-09 03:06:16.101159 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-09 03:06:16.834280 | 2026-09-09 03:06:16.834442 | PLAY [Base post] 2026-09-09 03:06:16.847642 | 2026-09-09 03:06:16.847770 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-09 03:06:17.471101 | debian-bookworm | changed 2026-09-09 03:06:17.478123 | 2026-09-09 03:06:17.478245 | PLAY RECAP 2026-09-09 03:06:17.478311 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-09 03:06:17.478373 | 2026-09-09 03:06:17.605157 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-09 03:06:17.606064 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-09 03:06:18.463294 | 2026-09-09 03:06:18.463460 | PLAY [Base post-logs] 2026-09-09 03:06:18.476869 | 2026-09-09 03:06:18.477016 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-09 03:06:19.069950 | localhost | changed 2026-09-09 03:06:19.084329 | 2026-09-09 03:06:19.084568 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-09 03:06:19.111592 | localhost | ok 2026-09-09 03:06:19.116535 | 2026-09-09 03:06:19.116646 | TASK [Set zuul-log-path fact] 2026-09-09 03:06:19.146626 | localhost | ok 2026-09-09 03:06:19.157928 | 2026-09-09 03:06:19.158048 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-09 03:06:19.183014 | localhost | ok 2026-09-09 03:06:19.186120 | 2026-09-09 03:06:19.188136 | TASK [upload-logs : Create log directories] 2026-09-09 03:06:20.057464 | localhost | changed 2026-09-09 03:06:20.071198 | 2026-09-09 03:06:20.072959 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-09 03:06:21.504877 | localhost -> localhost | ok: Runtime: 0:00:00.031971 2026-09-09 03:06:21.543169 | 2026-09-09 03:06:21.544042 | TASK [upload-logs : Upload logs to log server] 2026-09-09 03:06:22.404825 | localhost | Output suppressed because no_log was given 2026-09-09 03:06:22.406575 | 2026-09-09 03:06:22.406667 | LOOP [upload-logs : Compress console log and json output] 2026-09-09 03:06:22.468022 | localhost | skipping: Conditional result was False 2026-09-09 03:06:22.473580 | localhost | skipping: Conditional result was False 2026-09-09 03:06:22.485329 | 2026-09-09 03:06:22.485612 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-09 03:06:22.568399 | localhost | skipping: Conditional result was False 2026-09-09 03:06:22.568639 | 2026-09-09 03:06:22.573120 | localhost | skipping: Conditional result was False 2026-09-09 03:06:22.583743 | 2026-09-09 03:06:22.583832 | LOOP [upload-logs : Upload console log and json output]