2026-05-18 16:55:09.725892 | Job console starting 2026-05-18 16:55:09.751620 | Updating git repos 2026-05-18 16:55:09.799759 | Cloning repos into workspace 2026-05-18 16:55:09.885417 | Restoring repo states 2026-05-18 16:55:09.904418 | Merging changes 2026-05-18 16:55:10.639140 | Checking out repos 2026-05-18 16:55:10.695877 | Preparing playbooks 2026-05-18 16:55:12.432320 | Running Ansible setup 2026-05-18 16:55:18.595327 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-18 16:55:20.320167 | 2026-05-18 16:55:20.320289 | PLAY [Base pre] 2026-05-18 16:55:20.339294 | 2026-05-18 16:55:20.339407 | TASK [Setup log path fact] 2026-05-18 16:55:20.408144 | debian-bookworm | ok 2026-05-18 16:55:20.447652 | 2026-05-18 16:55:20.447785 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 16:55:20.493396 | debian-bookworm | ok 2026-05-18 16:55:20.501850 | 2026-05-18 16:55:20.501946 | TASK [emit-job-header : Print job information] 2026-05-18 16:55:20.606824 | # Job Information 2026-05-18 16:55:20.607030 | Ansible Version: 2.16.14 2026-05-18 16:55:20.607060 | Job: python-black 2026-05-18 16:55:20.607086 | Pipeline: check 2026-05-18 16:55:20.607105 | Executor: 521e9411259a 2026-05-18 16:55:20.607122 | Triggered by: https://github.com/osism/openstack-image-manager/pull/1194 2026-05-18 16:55:20.607140 | Event ID: 4e354c60-52da-11f1-8b17-ccfbba37ca4d 2026-05-18 16:55:20.611323 | 2026-05-18 16:55:20.611420 | LOOP [emit-job-header : Print node information] 2026-05-18 16:55:20.789402 | debian-bookworm | ok: 2026-05-18 16:55:20.789588 | debian-bookworm | # Node Information 2026-05-18 16:55:20.789619 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-18 16:55:20.789641 | debian-bookworm | Hostname: debian 2026-05-18 16:55:20.789659 | debian-bookworm | Username: zuul 2026-05-18 16:55:20.789676 | debian-bookworm | Distro: Debian 12.14 2026-05-18 16:55:20.789696 | debian-bookworm | Provider: regiocloud-a 2026-05-18 16:55:20.789713 | debian-bookworm | Region: 2026-05-18 16:55:20.789730 | debian-bookworm | Label: debian-bookworm 2026-05-18 16:55:20.789746 | debian-bookworm | Product Name: OpenStack Nova 2026-05-18 16:55:20.789762 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fef0:706a 2026-05-18 16:55:20.817944 | 2026-05-18 16:55:20.818065 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-18 16:55:21.643897 | debian-bookworm -> localhost | changed 2026-05-18 16:55:21.653032 | 2026-05-18 16:55:21.653152 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-18 16:55:23.089649 | debian-bookworm -> localhost | changed 2026-05-18 16:55:23.099904 | 2026-05-18 16:55:23.100010 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-18 16:55:23.967774 | debian-bookworm -> localhost | ok 2026-05-18 16:55:23.991897 | 2026-05-18 16:55:23.992140 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-18 16:55:24.042614 | debian-bookworm | ok 2026-05-18 16:55:24.075968 | debian-bookworm | included: /var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-18 16:55:24.082074 | 2026-05-18 16:55:24.082168 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-18 16:55:26.327998 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-18 16:55:26.328155 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/work/634160d786274b65b7545a484c2e6058_id_rsa 2026-05-18 16:55:26.328184 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/work/634160d786274b65b7545a484c2e6058_id_rsa.pub 2026-05-18 16:55:26.328205 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-18 16:55:26.328224 | debian-bookworm -> localhost | SHA256:wBZRGEu5wrwFh2/EsWyivj61E6fDopjdban72rQdlpg zuul-build-sshkey 2026-05-18 16:55:26.328242 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-18 16:55:26.328268 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-18 16:55:26.328286 | debian-bookworm -> localhost | | o*B. | 2026-05-18 16:55:26.328303 | debian-bookworm -> localhost | | o+*+ | 2026-05-18 16:55:26.328320 | debian-bookworm -> localhost | | o.=O. | 2026-05-18 16:55:26.328336 | debian-bookworm -> localhost | | .++=. | 2026-05-18 16:55:26.328351 | debian-bookworm -> localhost | | . = S | 2026-05-18 16:55:26.328373 | debian-bookworm -> localhost | | . + .o . | 2026-05-18 16:55:26.328391 | debian-bookworm -> localhost | | .o =E.+ | 2026-05-18 16:55:26.328407 | debian-bookworm -> localhost | |.ooo*+o+ . | 2026-05-18 16:55:26.328424 | debian-bookworm -> localhost | |+o+o=O= . | 2026-05-18 16:55:26.328456 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-18 16:55:26.328500 | debian-bookworm -> localhost | ok: Runtime: 0:00:01.585213 2026-05-18 16:55:26.334588 | 2026-05-18 16:55:26.334676 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-18 16:55:26.372605 | debian-bookworm | ok 2026-05-18 16:55:26.384282 | debian-bookworm | included: /var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-18 16:55:26.400266 | 2026-05-18 16:55:26.400366 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-18 16:55:26.436122 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:55:26.444717 | 2026-05-18 16:55:26.444818 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-18 16:55:27.054521 | debian-bookworm | changed 2026-05-18 16:55:27.058957 | 2026-05-18 16:55:27.059045 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-18 16:55:27.292170 | debian-bookworm | ok 2026-05-18 16:55:27.302197 | 2026-05-18 16:55:27.302312 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-18 16:55:27.901274 | debian-bookworm | changed 2026-05-18 16:55:27.913037 | 2026-05-18 16:55:27.913152 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-18 16:55:28.554964 | debian-bookworm | changed 2026-05-18 16:55:28.564738 | 2026-05-18 16:55:28.564837 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-18 16:55:28.601699 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:55:28.608121 | 2026-05-18 16:55:28.608232 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-18 16:55:29.452225 | debian-bookworm -> localhost | changed 2026-05-18 16:55:29.465892 | 2026-05-18 16:55:29.466004 | TASK [add-build-sshkey : Add back temp key] 2026-05-18 16:55:30.126459 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/work/634160d786274b65b7545a484c2e6058_id_rsa (zuul-build-sshkey) 2026-05-18 16:55:30.126671 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.048346 2026-05-18 16:55:30.134224 | 2026-05-18 16:55:30.134326 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-18 16:55:30.663692 | debian-bookworm | ok 2026-05-18 16:55:30.674931 | 2026-05-18 16:55:30.675059 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-18 16:55:30.722682 | debian-bookworm | skipping: Conditional result was False 2026-05-18 16:55:30.732784 | 2026-05-18 16:55:30.732891 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-18 16:55:31.330239 | debian-bookworm | ok 2026-05-18 16:55:31.346671 | 2026-05-18 16:55:31.346797 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-18 16:55:31.378005 | debian-bookworm | ok 2026-05-18 16:55:31.383303 | 2026-05-18 16:55:31.383409 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-18 16:55:32.141480 | debian-bookworm -> localhost | ok 2026-05-18 16:55:32.148253 | 2026-05-18 16:55:32.148356 | TASK [validate-host : Collect information about the host] 2026-05-18 16:55:33.728832 | debian-bookworm | ok 2026-05-18 16:55:33.752507 | 2026-05-18 16:55:33.752614 | TASK [validate-host : Sanitize hostname] 2026-05-18 16:55:33.873857 | debian-bookworm | ok 2026-05-18 16:55:33.939676 | 2026-05-18 16:55:33.939786 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-18 16:55:35.202060 | debian-bookworm -> localhost | changed 2026-05-18 16:55:35.207521 | 2026-05-18 16:55:35.207622 | TASK [validate-host : Collect information about zuul worker] 2026-05-18 16:55:35.608068 | debian-bookworm | ok 2026-05-18 16:55:35.612580 | 2026-05-18 16:55:35.612679 | TASK [validate-host : Write out all zuul information for each host] 2026-05-18 16:55:36.072435 | debian-bookworm -> localhost | changed 2026-05-18 16:55:36.083692 | 2026-05-18 16:55:36.083802 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-18 16:55:36.412355 | debian-bookworm | ok 2026-05-18 16:55:36.418848 | 2026-05-18 16:55:36.418961 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-18 16:55:37.453556 | debian-bookworm | changed: 2026-05-18 16:55:37.453738 | debian-bookworm | cd+++++++++ src/ 2026-05-18 16:55:37.453774 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-18 16:55:37.453798 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-18 16:55:37.453820 | debian-bookworm | cd+++++++++ src/github.com/osism/openstack-image-manager/ 2026-05-18 16:55:37.453841 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/work/logs" 2026-05-18 16:56:06.931669 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/work/artifacts" 2026-05-18 16:56:07.411710 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/634160d786274b65b7545a484c2e6058/work/docs" 2026-05-18 16:56:07.421152 | 2026-05-18 16:56:07.421311 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-18 16:56:08.822699 | debian-bookworm | changed: 2026-05-18 16:56:08.823176 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-18 16:56:08.823222 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-18 16:56:08.823249 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-18 16:56:08.823291 | debian-bookworm | changed: All items complete 2026-05-18 16:56:08.823314 | 2026-05-18 16:56:09.675321 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 16:56:10.314323 | debian-bookworm | changed: .d..t...... ./ 2026-05-18 16:56:10.335878 | 2026-05-18 16:56:10.335996 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-18 16:56:11.005288 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.016620 2026-05-18 16:56:11.659043 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.018028 2026-05-18 16:56:11.666082 | 2026-05-18 16:56:11.666153 | PLAY RECAP 2026-05-18 16:56:11.666193 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-18 16:56:11.666214 | 2026-05-18 16:56:11.927741 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-18 16:56:11.928354 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 16:56:13.302421 | 2026-05-18 16:56:13.303258 | PLAY [Base post] 2026-05-18 16:56:13.345849 | 2026-05-18 16:56:13.347408 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-18 16:56:14.274454 | debian-bookworm | changed 2026-05-18 16:56:14.279757 | 2026-05-18 16:56:14.279843 | PLAY RECAP 2026-05-18 16:56:14.279892 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-18 16:56:14.279948 | 2026-05-18 16:56:14.413081 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-18 16:56:14.413730 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-18 16:56:15.325793 | 2026-05-18 16:56:15.325937 | PLAY [Base post-logs] 2026-05-18 16:56:15.335612 | 2026-05-18 16:56:15.335745 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-18 16:56:15.846298 | localhost | changed 2026-05-18 16:56:15.854214 | 2026-05-18 16:56:15.854332 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-18 16:56:15.900897 | localhost | ok 2026-05-18 16:56:15.903727 | 2026-05-18 16:56:15.903803 | TASK [Set zuul-log-path fact] 2026-05-18 16:56:15.918877 | localhost | ok 2026-05-18 16:56:15.925094 | 2026-05-18 16:56:15.925196 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-18 16:56:15.962457 | localhost | ok 2026-05-18 16:56:15.964854 | 2026-05-18 16:56:15.964932 | TASK [upload-logs : Create log directories] 2026-05-18 16:56:16.985376 | localhost | changed 2026-05-18 16:56:16.987587 | 2026-05-18 16:56:16.987686 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-18 16:56:17.823343 | localhost -> localhost | ok: Runtime: 0:00:00.030942 2026-05-18 16:56:17.830055 | 2026-05-18 16:56:17.830143 | TASK [upload-logs : Upload logs to log server] 2026-05-18 16:56:18.623652 | localhost | Output suppressed because no_log was given 2026-05-18 16:56:18.625275 | 2026-05-18 16:56:18.625361 | LOOP [upload-logs : Compress console log and json output] 2026-05-18 16:56:18.707938 | localhost | skipping: Conditional result was False 2026-05-18 16:56:18.712975 | localhost | skipping: Conditional result was False 2026-05-18 16:56:18.715763 | 2026-05-18 16:56:18.715860 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-18 16:56:18.789798 | localhost | skipping: Conditional result was False 2026-05-18 16:56:18.790040 | 2026-05-18 16:56:18.801990 | localhost | skipping: Conditional result was False 2026-05-18 16:56:18.813228 | 2026-05-18 16:56:18.813345 | LOOP [upload-logs : Upload console log and json output]