2026-09-06 16:49:52.760690 | Job console starting 2026-09-06 16:49:52.781256 | Updating git repos 2026-09-06 16:49:52.826211 | Cloning repos into workspace 2026-09-06 16:49:52.876001 | Restoring repo states 2026-09-06 16:49:52.887746 | Merging changes 2026-09-06 16:49:53.528230 | Checking out repos 2026-09-06 16:49:53.569527 | Preparing playbooks 2026-09-06 16:49:54.823767 | Running Ansible setup 2026-09-06 16:49:59.374068 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-09-06 16:50:00.183822 | 2026-09-06 16:50:00.183956 | PLAY [Base pre] 2026-09-06 16:50:00.206663 | 2026-09-06 16:50:00.206768 | TASK [Setup log path fact] 2026-09-06 16:50:00.224184 | debian-bookworm | ok 2026-09-06 16:50:00.249439 | 2026-09-06 16:50:00.249560 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-06 16:50:00.276846 | debian-bookworm | ok 2026-09-06 16:50:00.286134 | 2026-09-06 16:50:00.286223 | TASK [emit-job-header : Print job information] 2026-09-06 16:50:00.334011 | # Job Information 2026-09-06 16:50:00.334159 | Ansible Version: 2.16.14 2026-09-06 16:50:00.334196 | Job: python-black 2026-09-06 16:50:00.334229 | Pipeline: check 2026-09-06 16:50:00.334253 | Executor: 521e9411259a 2026-09-06 16:50:00.334274 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1271 2026-09-06 16:50:00.334296 | Event ID: f59a9d40-aa12-11f1-97e5-964876b1aec2 2026-09-06 16:50:00.339057 | 2026-09-06 16:50:00.339150 | LOOP [emit-job-header : Print node information] 2026-09-06 16:50:00.499642 | debian-bookworm | ok: 2026-09-06 16:50:00.499792 | debian-bookworm | # Node Information 2026-09-06 16:50:00.499825 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-09-06 16:50:00.499850 | debian-bookworm | Hostname: debian 2026-09-06 16:50:00.499871 | debian-bookworm | Username: zuul 2026-09-06 16:50:00.499891 | debian-bookworm | Distro: Debian 12.15 2026-09-06 16:50:00.499943 | debian-bookworm | Provider: regiocloud-a 2026-09-06 16:50:00.499966 | debian-bookworm | Region: 2026-09-06 16:50:00.499987 | debian-bookworm | Label: debian-bookworm 2026-09-06 16:50:00.500007 | debian-bookworm | Product Name: OpenStack Nova 2026-09-06 16:50:00.500028 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe22:a1ee 2026-09-06 16:50:00.516342 | 2026-09-06 16:50:00.516444 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-09-06 16:50:01.181730 | debian-bookworm -> localhost | changed 2026-09-06 16:50:01.188488 | 2026-09-06 16:50:01.188583 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-09-06 16:50:02.737996 | debian-bookworm -> localhost | changed 2026-09-06 16:50:02.760175 | 2026-09-06 16:50:02.760284 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-09-06 16:50:03.207243 | debian-bookworm -> localhost | ok 2026-09-06 16:50:03.213018 | 2026-09-06 16:50:03.213116 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-09-06 16:50:03.252162 | debian-bookworm | ok 2026-09-06 16:50:03.274603 | debian-bookworm | included: /var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-09-06 16:50:03.282502 | 2026-09-06 16:50:03.282592 | TASK [add-build-sshkey : Create Temp SSH key] 2026-09-06 16:50:04.823387 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-09-06 16:50:04.823552 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/work/f53e2737f51c4fabb6749a1215fbc10c_id_rsa 2026-09-06 16:50:04.823583 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/work/f53e2737f51c4fabb6749a1215fbc10c_id_rsa.pub 2026-09-06 16:50:04.823606 | debian-bookworm -> localhost | The key fingerprint is: 2026-09-06 16:50:04.823627 | debian-bookworm -> localhost | SHA256:xbATNmyUOEMCbMqmV4MLLYY344SB2KdBAZFeUl751Ic zuul-build-sshkey 2026-09-06 16:50:04.823647 | debian-bookworm -> localhost | The key's randomart image is: 2026-09-06 16:50:04.823673 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-09-06 16:50:04.823691 | debian-bookworm -> localhost | |=B=+.oo+B.. | 2026-09-06 16:50:04.823709 | debian-bookworm -> localhost | |=.*.oo+o+E . | 2026-09-06 16:50:04.823725 | debian-bookworm -> localhost | |+*o* o+o + | 2026-09-06 16:50:04.823741 | debian-bookworm -> localhost | |*=O o . o | 2026-09-06 16:50:04.823757 | debian-bookworm -> localhost | |+* = . S | 2026-09-06 16:50:04.823776 | debian-bookworm -> localhost | |. + | 2026-09-06 16:50:04.823793 | debian-bookworm -> localhost | | . | 2026-09-06 16:50:04.823808 | debian-bookworm -> localhost | | | 2026-09-06 16:50:04.823825 | debian-bookworm -> localhost | | | 2026-09-06 16:50:04.823842 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-09-06 16:50:04.823905 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.019129 2026-09-06 16:50:04.828966 | 2026-09-06 16:50:04.829042 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-09-06 16:50:04.855674 | debian-bookworm | ok 2026-09-06 16:50:04.867469 | debian-bookworm | included: /var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-09-06 16:50:04.873807 | 2026-09-06 16:50:04.873882 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-09-06 16:50:04.914239 | debian-bookworm | skipping: Conditional result was False 2026-09-06 16:50:04.918720 | 2026-09-06 16:50:04.918791 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-09-06 16:50:05.637195 | debian-bookworm | changed 2026-09-06 16:50:05.645014 | 2026-09-06 16:50:05.645122 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-09-06 16:50:05.895097 | debian-bookworm | ok 2026-09-06 16:50:05.900570 | 2026-09-06 16:50:05.900650 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-09-06 16:50:06.506244 | debian-bookworm | changed 2026-09-06 16:50:06.515582 | 2026-09-06 16:50:06.515738 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-09-06 16:50:07.163964 | debian-bookworm | changed 2026-09-06 16:50:07.171975 | 2026-09-06 16:50:07.172087 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-09-06 16:50:07.195787 | debian-bookworm | skipping: Conditional result was False 2026-09-06 16:50:07.204874 | 2026-09-06 16:50:07.204960 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-09-06 16:50:07.592104 | debian-bookworm -> localhost | changed 2026-09-06 16:50:07.605139 | 2026-09-06 16:50:07.605240 | TASK [add-build-sshkey : Add back temp key] 2026-09-06 16:50:07.998021 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/work/f53e2737f51c4fabb6749a1215fbc10c_id_rsa (zuul-build-sshkey) 2026-09-06 16:50:07.998228 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.031461 2026-09-06 16:50:08.006344 | 2026-09-06 16:50:08.006437 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-09-06 16:50:08.411394 | debian-bookworm | ok 2026-09-06 16:50:08.416707 | 2026-09-06 16:50:08.416829 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-09-06 16:50:08.439870 | debian-bookworm | skipping: Conditional result was False 2026-09-06 16:50:08.450914 | 2026-09-06 16:50:08.451012 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-09-06 16:50:08.884026 | debian-bookworm | ok 2026-09-06 16:50:08.894741 | 2026-09-06 16:50:08.894897 | TASK [validate-host : Define zuul_info_dir fact] 2026-09-06 16:50:08.923046 | debian-bookworm | ok 2026-09-06 16:50:08.928187 | 2026-09-06 16:50:08.928269 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-09-06 16:50:09.183180 | debian-bookworm -> localhost | ok 2026-09-06 16:50:09.190879 | 2026-09-06 16:50:09.190994 | TASK [validate-host : Collect information about the host] 2026-09-06 16:50:10.258725 | debian-bookworm | ok 2026-09-06 16:50:10.269613 | 2026-09-06 16:50:10.269704 | TASK [validate-host : Sanitize hostname] 2026-09-06 16:50:10.374489 | debian-bookworm | ok 2026-09-06 16:50:10.379053 | 2026-09-06 16:50:10.379138 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-09-06 16:50:10.897068 | debian-bookworm -> localhost | changed 2026-09-06 16:50:10.902412 | 2026-09-06 16:50:10.902504 | TASK [validate-host : Collect information about zuul worker] 2026-09-06 16:50:11.302727 | debian-bookworm | ok 2026-09-06 16:50:11.307279 | 2026-09-06 16:50:11.307369 | TASK [validate-host : Write out all zuul information for each host] 2026-09-06 16:50:11.957947 | debian-bookworm -> localhost | changed 2026-09-06 16:50:11.967086 | 2026-09-06 16:50:11.967181 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-09-06 16:50:12.235111 | debian-bookworm | ok 2026-09-06 16:50:12.240591 | 2026-09-06 16:50:12.240677 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-09-06 16:50:13.227656 | debian-bookworm | changed: 2026-09-06 16:50:13.227822 | debian-bookworm | cd+++++++++ src/ 2026-09-06 16:50:13.227872 | debian-bookworm | cd+++++++++ src/github.com/ 2026-09-06 16:50:13.227897 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-09-06 16:50:13.227919 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-09-06 16:50:13.227940 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/work/logs" 2026-09-06 16:51:16.246437 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/work/artifacts" 2026-09-06 16:51:16.521461 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/f53e2737f51c4fabb6749a1215fbc10c/work/docs" 2026-09-06 16:51:16.532643 | 2026-09-06 16:51:16.532773 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-09-06 16:51:17.289256 | debian-bookworm | changed: 2026-09-06 16:51:17.289623 | debian-bookworm | .d..t...... ./ 2026-09-06 16:51:17.289692 | debian-bookworm | >f+++++++++ net-debug.post.txt 2026-09-06 16:51:17.289734 | debian-bookworm | >f+++++++++ net-debug.pre.txt 2026-09-06 16:51:17.289770 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-09-06 16:51:17.289807 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-09-06 16:51:17.289841 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-09-06 16:51:17.289909 | debian-bookworm | changed: All items complete 2026-09-06 16:51:17.289953 | 2026-09-06 16:51:17.820124 | debian-bookworm | changed: .d..t...... ./ 2026-09-06 16:51:18.363650 | debian-bookworm | changed: .d..t...... ./ 2026-09-06 16:51:18.388491 | 2026-09-06 16:51:18.388648 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-09-06 16:51:18.736648 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.006786 2026-09-06 16:51:18.991558 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.005490 2026-09-06 16:51:19.002381 | 2026-09-06 16:51:19.002540 | PLAY RECAP 2026-09-06 16:51:19.002598 | debian-bookworm | ok: 8 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-09-06 16:51:19.002623 | 2026-09-06 16:51:19.132931 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-09-06 16:51:19.133857 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-06 16:51:19.871994 | 2026-09-06 16:51:19.872143 | PLAY [Base post] 2026-09-06 16:51:19.885367 | 2026-09-06 16:51:19.885503 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-09-06 16:51:20.440421 | debian-bookworm | changed 2026-09-06 16:51:20.446811 | 2026-09-06 16:51:20.446949 | PLAY RECAP 2026-09-06 16:51:20.447014 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-09-06 16:51:20.447076 | 2026-09-06 16:51:20.567309 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-09-06 16:51:20.568481 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-09-06 16:51:21.360899 | 2026-09-06 16:51:21.361059 | PLAY [Base post-logs] 2026-09-06 16:51:21.371579 | 2026-09-06 16:51:21.371715 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-09-06 16:51:21.812964 | localhost | changed 2026-09-06 16:51:21.824991 | 2026-09-06 16:51:21.825150 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-09-06 16:51:21.851089 | localhost | ok 2026-09-06 16:51:21.854119 | 2026-09-06 16:51:21.854279 | TASK [Set zuul-log-path fact] 2026-09-06 16:51:21.869305 | localhost | ok 2026-09-06 16:51:21.877071 | 2026-09-06 16:51:21.877180 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-09-06 16:51:21.902019 | localhost | ok 2026-09-06 16:51:21.905100 | 2026-09-06 16:51:21.905206 | TASK [upload-logs : Create log directories] 2026-09-06 16:51:22.435329 | localhost | changed 2026-09-06 16:51:22.438710 | 2026-09-06 16:51:22.438884 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-09-06 16:51:22.941318 | localhost -> localhost | ok: Runtime: 0:00:00.006742 2026-09-06 16:51:22.945695 | 2026-09-06 16:51:22.945810 | TASK [upload-logs : Upload logs to log server] 2026-09-06 16:51:23.540805 | localhost | Output suppressed because no_log was given 2026-09-06 16:51:23.544200 | 2026-09-06 16:51:23.544380 | LOOP [upload-logs : Compress console log and json output] 2026-09-06 16:51:23.595429 | localhost | skipping: Conditional result was False 2026-09-06 16:51:23.601126 | localhost | skipping: Conditional result was False 2026-09-06 16:51:23.617958 | 2026-09-06 16:51:23.618096 | LOOP [upload-logs : Upload compressed console log and json output] 2026-09-06 16:51:23.677994 | localhost | skipping: Conditional result was False 2026-09-06 16:51:23.678685 | 2026-09-06 16:51:23.682467 | localhost | skipping: Conditional result was False 2026-09-06 16:51:23.696014 | 2026-09-06 16:51:23.696135 | LOOP [upload-logs : Upload console log and json output]