2026-05-11 22:11:03.670285 | Job console starting 2026-05-11 22:11:03.765752 | Updating git repos 2026-05-11 22:11:03.813253 | Cloning repos into workspace 2026-05-11 22:11:03.918570 | Restoring repo states 2026-05-11 22:11:03.941874 | Merging changes 2026-05-11 22:11:04.570643 | Checking out repos 2026-05-11 22:11:04.647589 | Preparing playbooks 2026-05-11 22:11:05.528661 | Running Ansible setup 2026-05-11 22:11:12.406930 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-11 22:11:13.956366 | 2026-05-11 22:11:13.956484 | PLAY [Base pre] 2026-05-11 22:11:13.986747 | 2026-05-11 22:11:13.992090 | TASK [Setup log path fact] 2026-05-11 22:11:14.061575 | debian-bookworm | ok 2026-05-11 22:11:14.097385 | 2026-05-11 22:11:14.097510 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 22:11:14.242267 | debian-bookworm | ok 2026-05-11 22:11:14.250435 | 2026-05-11 22:11:14.250534 | TASK [emit-job-header : Print job information] 2026-05-11 22:11:14.394229 | # Job Information 2026-05-11 22:11:14.394378 | Ansible Version: 2.16.14 2026-05-11 22:11:14.394407 | Job: python-black 2026-05-11 22:11:14.394435 | Pipeline: check 2026-05-11 22:11:14.394455 | Executor: 521e9411259a 2026-05-11 22:11:14.394473 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1194 2026-05-11 22:11:14.394492 | Event ID: 446fcc90-4d86-11f1-9153-4ba9dd2375ed 2026-05-11 22:11:14.399063 | 2026-05-11 22:11:14.399148 | LOOP [emit-job-header : Print node information] 2026-05-11 22:11:14.730700 | debian-bookworm | ok: 2026-05-11 22:11:14.730940 | debian-bookworm | # Node Information 2026-05-11 22:11:14.730988 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-11 22:11:14.731129 | debian-bookworm | Hostname: debian 2026-05-11 22:11:14.731167 | debian-bookworm | Username: zuul 2026-05-11 22:11:14.731196 | debian-bookworm | Distro: Debian 12.13 2026-05-11 22:11:14.731238 | debian-bookworm | Provider: regiocloud-a 2026-05-11 22:11:14.731266 | debian-bookworm | Region: 2026-05-11 22:11:14.733044 | debian-bookworm | Label: debian-bookworm 2026-05-11 22:11:14.733118 | debian-bookworm | Product Name: OpenStack Nova 2026-05-11 22:11:14.733147 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fee3:4639 2026-05-11 22:11:14.754327 | 2026-05-11 22:11:14.754427 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-11 22:11:16.480824 | debian-bookworm -> localhost | changed 2026-05-11 22:11:16.488342 | 2026-05-11 22:11:16.488541 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-11 22:11:20.328979 | debian-bookworm -> localhost | changed 2026-05-11 22:11:20.348339 | 2026-05-11 22:11:20.348691 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-11 22:11:21.181009 | debian-bookworm -> localhost | ok 2026-05-11 22:11:21.185975 | 2026-05-11 22:11:21.186075 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-11 22:11:21.265409 | debian-bookworm | ok 2026-05-11 22:11:21.296902 | debian-bookworm | included: /var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-11 22:11:21.312584 | 2026-05-11 22:11:21.312684 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-11 22:11:24.769303 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-11 22:11:24.769495 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/work/bab6e8ae677547a2bce2b3e0dc86fba6_id_rsa 2026-05-11 22:11:24.769527 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/work/bab6e8ae677547a2bce2b3e0dc86fba6_id_rsa.pub 2026-05-11 22:11:24.769549 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-11 22:11:24.769569 | debian-bookworm -> localhost | SHA256:E/LgREjdfiDZ6UXQk26jx3vLBNEE69AghAiRnMGsY+0 zuul-build-sshkey 2026-05-11 22:11:24.769588 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-11 22:11:24.769616 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-11 22:11:24.769635 | debian-bookworm -> localhost | |*=oo.=++o=oo. | 2026-05-11 22:11:24.769654 | debian-bookworm -> localhost | |.=. o.+.=o=+ | 2026-05-11 22:11:24.769671 | debian-bookworm -> localhost | |. . ++o++.. | 2026-05-11 22:11:24.769688 | debian-bookworm -> localhost | |o. . o +o+=. | 2026-05-11 22:11:24.769704 | debian-bookworm -> localhost | |... . S=o. | 2026-05-11 22:11:24.769729 | debian-bookworm -> localhost | | E ..o. | 2026-05-11 22:11:24.769746 | debian-bookworm -> localhost | | . .. | 2026-05-11 22:11:24.769762 | debian-bookworm -> localhost | | .o. | 2026-05-11 22:11:24.769779 | debian-bookworm -> localhost | | .o. | 2026-05-11 22:11:24.769796 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-11 22:11:24.769844 | debian-bookworm -> localhost | ok: Runtime: 0:00:02.081355 2026-05-11 22:11:24.775023 | 2026-05-11 22:11:24.775108 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-11 22:11:24.824071 | debian-bookworm | ok 2026-05-11 22:11:24.834619 | debian-bookworm | included: /var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-11 22:11:24.864855 | 2026-05-11 22:11:24.864949 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-11 22:11:24.918066 | debian-bookworm | skipping: Conditional result was False 2026-05-11 22:11:24.926539 | 2026-05-11 22:11:24.926641 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-11 22:11:26.140847 | debian-bookworm | changed 2026-05-11 22:11:26.151689 | 2026-05-11 22:11:26.151781 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-11 22:11:26.533236 | debian-bookworm | ok 2026-05-11 22:11:26.538050 | 2026-05-11 22:11:26.538146 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-11 22:11:27.479814 | debian-bookworm | changed 2026-05-11 22:11:27.501125 | 2026-05-11 22:11:27.501242 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-11 22:11:28.363750 | debian-bookworm | changed 2026-05-11 22:11:28.368363 | 2026-05-11 22:11:28.368445 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-11 22:11:28.434940 | debian-bookworm | skipping: Conditional result was False 2026-05-11 22:11:28.440044 | 2026-05-11 22:11:28.440135 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-11 22:11:29.649051 | debian-bookworm -> localhost | changed 2026-05-11 22:11:29.663215 | 2026-05-11 22:11:29.663316 | TASK [add-build-sshkey : Add back temp key] 2026-05-11 22:11:30.819169 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/work/bab6e8ae677547a2bce2b3e0dc86fba6_id_rsa (zuul-build-sshkey) 2026-05-11 22:11:30.819366 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.025035 2026-05-11 22:11:30.831989 | 2026-05-11 22:11:30.832082 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-11 22:11:31.705765 | debian-bookworm | ok 2026-05-11 22:11:31.710378 | 2026-05-11 22:11:31.710471 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-11 22:11:31.748104 | debian-bookworm | skipping: Conditional result was False 2026-05-11 22:11:31.792460 | 2026-05-11 22:11:31.792563 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-11 22:11:32.722481 | debian-bookworm | ok 2026-05-11 22:11:32.733818 | 2026-05-11 22:11:32.733908 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-11 22:11:32.802311 | debian-bookworm | ok 2026-05-11 22:11:32.806505 | 2026-05-11 22:11:32.806712 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-11 22:11:33.973256 | debian-bookworm -> localhost | ok 2026-05-11 22:11:33.988859 | 2026-05-11 22:11:33.988967 | TASK [validate-host : Collect information about the host] 2026-05-11 22:11:36.151141 | debian-bookworm | ok 2026-05-11 22:11:36.174209 | 2026-05-11 22:11:36.174413 | TASK [validate-host : Sanitize hostname] 2026-05-11 22:11:36.401958 | debian-bookworm | ok 2026-05-11 22:11:36.487772 | 2026-05-11 22:11:36.487876 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-11 22:11:37.599172 | debian-bookworm -> localhost | changed 2026-05-11 22:11:37.603885 | 2026-05-11 22:11:37.603964 | TASK [validate-host : Collect information about zuul worker] 2026-05-11 22:11:38.185396 | debian-bookworm | ok 2026-05-11 22:11:38.189472 | 2026-05-11 22:11:38.189569 | TASK [validate-host : Write out all zuul information for each host] 2026-05-11 22:11:39.049881 | debian-bookworm -> localhost | changed 2026-05-11 22:11:39.066216 | 2026-05-11 22:11:39.066324 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-11 22:11:39.371225 | debian-bookworm | ok 2026-05-11 22:11:39.380049 | 2026-05-11 22:11:39.380140 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-11 22:11:40.856391 | debian-bookworm | changed: 2026-05-11 22:11:40.856544 | debian-bookworm | cd+++++++++ src/ 2026-05-11 22:11:40.856571 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-11 22:11:40.856591 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-11 22:11:40.856608 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-11 22:11:40.856624 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/work/logs" 2026-05-11 22:12:14.673586 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/work/artifacts" 2026-05-11 22:12:15.197914 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/bab6e8ae677547a2bce2b3e0dc86fba6/work/docs" 2026-05-11 22:12:15.213855 | 2026-05-11 22:12:15.213955 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-11 22:12:16.427838 | debian-bookworm | changed: 2026-05-11 22:12:16.428000 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-11 22:12:16.428028 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-11 22:12:16.428059 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-11 22:12:16.428099 | debian-bookworm | changed: All items complete 2026-05-11 22:12:16.428121 | 2026-05-11 22:12:17.384464 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 22:12:18.628694 | debian-bookworm | changed: .d..t...... ./ 2026-05-11 22:12:18.659360 | 2026-05-11 22:12:18.659461 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-11 22:12:20.033886 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.015296 2026-05-11 22:12:20.672388 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.027130 2026-05-11 22:12:20.684687 | 2026-05-11 22:12:20.684766 | PLAY RECAP 2026-05-11 22:12:20.684813 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-11 22:12:20.684838 | 2026-05-11 22:12:20.874507 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-11 22:12:20.875224 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 22:12:22.173017 | 2026-05-11 22:12:22.173176 | PLAY [Base post] 2026-05-11 22:12:22.223704 | 2026-05-11 22:12:22.223828 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-11 22:12:23.021011 | debian-bookworm | changed 2026-05-11 22:12:23.029895 | 2026-05-11 22:12:23.029988 | PLAY RECAP 2026-05-11 22:12:23.030045 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-11 22:12:23.030105 | 2026-05-11 22:12:23.256095 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-11 22:12:23.256891 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-11 22:12:25.184238 | 2026-05-11 22:12:25.184377 | PLAY [Base post-logs] 2026-05-11 22:12:25.225536 | 2026-05-11 22:12:25.225675 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-11 22:12:26.109541 | localhost | changed 2026-05-11 22:12:26.119569 | 2026-05-11 22:12:26.119713 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-11 22:12:26.170354 | localhost | ok 2026-05-11 22:12:26.173295 | 2026-05-11 22:12:26.173383 | TASK [Set zuul-log-path fact] 2026-05-11 22:12:26.208191 | localhost | ok 2026-05-11 22:12:26.220810 | 2026-05-11 22:12:26.220913 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-11 22:12:26.267415 | localhost | ok 2026-05-11 22:12:26.270296 | 2026-05-11 22:12:26.270385 | TASK [upload-logs : Create log directories] 2026-05-11 22:12:27.425271 | localhost | changed 2026-05-11 22:12:27.427864 | 2026-05-11 22:12:27.427947 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-11 22:12:29.258803 | localhost -> localhost | ok: Runtime: 0:00:00.033749 2026-05-11 22:12:29.262488 | 2026-05-11 22:12:29.262578 | TASK [upload-logs : Upload logs to log server] 2026-05-11 22:12:30.699755 | localhost | Output suppressed because no_log was given 2026-05-11 22:12:30.701208 | 2026-05-11 22:12:30.701282 | LOOP [upload-logs : Compress console log and json output] 2026-05-11 22:12:30.798947 | localhost | skipping: Conditional result was False 2026-05-11 22:12:30.810639 | localhost | skipping: Conditional result was False 2026-05-11 22:12:30.822880 | 2026-05-11 22:12:30.822972 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-11 22:12:30.957786 | localhost | skipping: Conditional result was False 2026-05-11 22:12:30.958033 | 2026-05-11 22:12:30.969358 | localhost | skipping: Conditional result was False 2026-05-11 22:12:30.979101 | 2026-05-11 22:12:30.979221 | LOOP [upload-logs : Upload console log and json output]