2026-05-28 04:08:20.722185 | Job console starting 2026-05-28 04:08:20.738790 | Updating git repos 2026-05-28 04:08:20.821110 | Cloning repos into workspace 2026-05-28 04:08:20.874804 | Restoring repo states 2026-05-28 04:08:20.911437 | Merging changes 2026-05-28 04:08:21.683448 | Checking out repos 2026-05-28 04:08:21.777211 | Preparing playbooks 2026-05-28 04:08:23.346207 | Running Ansible setup 2026-05-28 04:08:29.098049 | PRE-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/pre.yaml@main] 2026-05-28 04:08:30.321194 | 2026-05-28 04:08:30.321383 | PLAY [Base pre] 2026-05-28 04:08:30.339773 | 2026-05-28 04:08:30.339931 | TASK [Setup log path fact] 2026-05-28 04:08:30.359551 | debian-bookworm | ok 2026-05-28 04:08:30.376486 | 2026-05-28 04:08:30.376653 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 04:08:30.406278 | debian-bookworm | ok 2026-05-28 04:08:30.421457 | 2026-05-28 04:08:30.421593 | TASK [emit-job-header : Print job information] 2026-05-28 04:08:30.500795 | # Job Information 2026-05-28 04:08:30.500987 | Ansible Version: 2.16.14 2026-05-28 04:08:30.501022 | Job: python-black 2026-05-28 04:08:30.501053 | Pipeline: check 2026-05-28 04:08:30.501076 | Executor: 521e9411259a 2026-05-28 04:08:30.501097 | Triggered by: https://github.com/osism/python-osism/pull/2298 2026-05-28 04:08:30.501120 | Event ID: d4451b9c-5a4a-11f1-88b5-c26cb733d888 2026-05-28 04:08:30.506129 | 2026-05-28 04:08:30.506241 | LOOP [emit-job-header : Print node information] 2026-05-28 04:08:30.732857 | debian-bookworm | ok: 2026-05-28 04:08:30.733060 | debian-bookworm | # Node Information 2026-05-28 04:08:30.733096 | debian-bookworm | Inventory Hostname: debian-bookworm 2026-05-28 04:08:30.733123 | debian-bookworm | Hostname: debian 2026-05-28 04:08:30.733145 | debian-bookworm | Username: zuul 2026-05-28 04:08:30.733165 | debian-bookworm | Distro: Debian 12.14 2026-05-28 04:08:30.733189 | debian-bookworm | Provider: regiocloud-a 2026-05-28 04:08:30.733210 | debian-bookworm | Region: 2026-05-28 04:08:30.733230 | debian-bookworm | Label: debian-bookworm 2026-05-28 04:08:30.733249 | debian-bookworm | Product Name: OpenStack Nova 2026-05-28 04:08:30.733268 | debian-bookworm | Interface IP: 2a13:1a81:8000:3124:f816:3eff:fe24:c8ee 2026-05-28 04:08:30.746133 | 2026-05-28 04:08:30.746266 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-28 04:08:31.564894 | debian-bookworm -> localhost | changed 2026-05-28 04:08:31.577745 | 2026-05-28 04:08:31.577883 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-28 04:08:33.525632 | debian-bookworm -> localhost | changed 2026-05-28 04:08:33.534354 | 2026-05-28 04:08:33.534446 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-28 04:08:34.022156 | debian-bookworm -> localhost | ok 2026-05-28 04:08:34.027056 | 2026-05-28 04:08:34.027140 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-28 04:08:34.060166 | debian-bookworm | ok 2026-05-28 04:08:34.088318 | debian-bookworm | included: /var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-28 04:08:34.093359 | 2026-05-28 04:08:34.093435 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-28 04:08:35.265946 | debian-bookworm -> localhost | Generating public/private rsa key pair. 2026-05-28 04:08:35.266113 | debian-bookworm -> localhost | Your identification has been saved in /var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/work/e90d1d87e0754c73a1257233bef5f97a_id_rsa 2026-05-28 04:08:35.266143 | debian-bookworm -> localhost | Your public key has been saved in /var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/work/e90d1d87e0754c73a1257233bef5f97a_id_rsa.pub 2026-05-28 04:08:35.266165 | debian-bookworm -> localhost | The key fingerprint is: 2026-05-28 04:08:35.266184 | debian-bookworm -> localhost | SHA256:/Ws1u0T2D3go/h9CqmrJedJZ1xTokh/7EyXOy9Zv/y8 zuul-build-sshkey 2026-05-28 04:08:35.266203 | debian-bookworm -> localhost | The key's randomart image is: 2026-05-28 04:08:35.266235 | debian-bookworm -> localhost | +---[RSA 3072]----+ 2026-05-28 04:08:35.266255 | debian-bookworm -> localhost | | . | 2026-05-28 04:08:35.266272 | debian-bookworm -> localhost | | . . | 2026-05-28 04:08:35.266299 | debian-bookworm -> localhost | | o . | 2026-05-28 04:08:35.266316 | debian-bookworm -> localhost | | .o o o .| 2026-05-28 04:08:35.266334 | debian-bookworm -> localhost | | S .o.Boo | 2026-05-28 04:08:35.266354 | debian-bookworm -> localhost | | .++=B. | 2026-05-28 04:08:35.266372 | debian-bookworm -> localhost | | . + oo.=+=*.| 2026-05-28 04:08:35.266388 | debian-bookworm -> localhost | | * +o ..=Eo+| 2026-05-28 04:08:35.266404 | debian-bookworm -> localhost | | ..+. .oooo=@| 2026-05-28 04:08:35.266421 | debian-bookworm -> localhost | +----[SHA256]-----+ 2026-05-28 04:08:35.266462 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.406492 2026-05-28 04:08:35.271596 | 2026-05-28 04:08:35.271681 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-28 04:08:35.288105 | debian-bookworm | ok 2026-05-28 04:08:35.295637 | debian-bookworm | included: /var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/trusted/project_1/github.com/osism/openinfra-zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-28 04:08:35.303957 | 2026-05-28 04:08:35.304030 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-28 04:08:35.316539 | debian-bookworm | skipping: Conditional result was False 2026-05-28 04:08:35.321357 | 2026-05-28 04:08:35.321438 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-28 04:08:35.980448 | debian-bookworm | changed 2026-05-28 04:08:35.988138 | 2026-05-28 04:08:35.988240 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-28 04:08:36.265399 | debian-bookworm | ok 2026-05-28 04:08:36.272202 | 2026-05-28 04:08:36.272333 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-28 04:08:36.964126 | debian-bookworm | changed 2026-05-28 04:08:36.983838 | 2026-05-28 04:08:36.983942 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-28 04:08:37.646241 | debian-bookworm | changed 2026-05-28 04:08:37.653606 | 2026-05-28 04:08:37.653936 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-28 04:08:37.679872 | debian-bookworm | skipping: Conditional result was False 2026-05-28 04:08:37.685999 | 2026-05-28 04:08:37.686101 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-28 04:08:38.174101 | debian-bookworm -> localhost | changed 2026-05-28 04:08:38.199561 | 2026-05-28 04:08:38.199702 | TASK [add-build-sshkey : Add back temp key] 2026-05-28 04:08:38.865322 | debian-bookworm -> localhost | Identity added: /var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/work/e90d1d87e0754c73a1257233bef5f97a_id_rsa (zuul-build-sshkey) 2026-05-28 04:08:38.865616 | debian-bookworm -> localhost | ok: Runtime: 0:00:00.012856 2026-05-28 04:08:38.874730 | 2026-05-28 04:08:38.874879 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-28 04:08:39.408144 | debian-bookworm | ok 2026-05-28 04:08:39.416007 | 2026-05-28 04:08:39.416147 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-28 04:08:39.466502 | debian-bookworm | skipping: Conditional result was False 2026-05-28 04:08:39.479522 | 2026-05-28 04:08:39.479710 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-05-28 04:08:40.092078 | debian-bookworm | ok 2026-05-28 04:08:40.112148 | 2026-05-28 04:08:40.112379 | TASK [validate-host : Define zuul_info_dir fact] 2026-05-28 04:08:40.147225 | debian-bookworm | ok 2026-05-28 04:08:40.152944 | 2026-05-28 04:08:40.153089 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-05-28 04:08:40.799568 | debian-bookworm -> localhost | ok 2026-05-28 04:08:40.807500 | 2026-05-28 04:08:40.807737 | TASK [validate-host : Collect information about the host] 2026-05-28 04:08:42.267487 | debian-bookworm | ok 2026-05-28 04:08:42.276719 | 2026-05-28 04:08:42.276803 | TASK [validate-host : Sanitize hostname] 2026-05-28 04:08:42.355416 | debian-bookworm | ok 2026-05-28 04:08:42.384009 | 2026-05-28 04:08:42.384102 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-05-28 04:08:42.913326 | debian-bookworm -> localhost | changed 2026-05-28 04:08:42.917975 | 2026-05-28 04:08:42.918049 | TASK [validate-host : Collect information about zuul worker] 2026-05-28 04:08:43.304046 | debian-bookworm | ok 2026-05-28 04:08:43.309843 | 2026-05-28 04:08:43.309943 | TASK [validate-host : Write out all zuul information for each host] 2026-05-28 04:08:44.046092 | debian-bookworm -> localhost | changed 2026-05-28 04:08:44.056396 | 2026-05-28 04:08:44.056495 | TASK [prepare-workspace-log : Start zuul_console daemon.] 2026-05-28 04:08:44.358574 | debian-bookworm | ok 2026-05-28 04:08:44.372583 | 2026-05-28 04:08:44.372690 | TASK [prepare-workspace-log : Synchronize src repos to workspace directory.] 2026-05-28 04:08:45.605016 | debian-bookworm | changed: 2026-05-28 04:08:45.605200 | debian-bookworm | cd+++++++++ src/ 2026-05-28 04:08:45.605233 | debian-bookworm | cd+++++++++ src/github.com/ 2026-05-28 04:08:45.605253 | debian-bookworm | cd+++++++++ src/github.com/osism/ 2026-05-28 04:08:45.605295 | debian-bookworm | cd+++++++++ src/github.com/osism/python-osism/ 2026-05-28 04:08:45.605313 | debian-bookworm | Containerfile 2026-05-28 04:08:45.605421 | debian-bookworm | Containerfile 2026-05-28 04:08:45.613286 | debian-bookworm | localhost | ok: "/var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/work/logs" 2026-05-28 04:09:16.278359 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/work/artifacts" 2026-05-28 04:09:16.605337 | debian-bookworm -> localhost | changed: "/var/lib/zuul/builds/e90d1d87e0754c73a1257233bef5f97a/work/docs" 2026-05-28 04:09:16.625728 | 2026-05-28 04:09:16.625924 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-28 04:09:17.498023 | debian-bookworm | changed: 2026-05-28 04:09:17.498353 | debian-bookworm | >f+++++++++ sudo-debug.txt 2026-05-28 04:09:17.498401 | debian-bookworm | >f+++++++++ sudo-strace.log 2026-05-28 04:09:17.498548 | debian-bookworm | >f+++++++++ sudo-strace.log.time 2026-05-28 04:09:17.498610 | debian-bookworm | changed: All items complete 2026-05-28 04:09:17.498634 | 2026-05-28 04:09:18.111299 | debian-bookworm | changed: .d..t...... ./ 2026-05-28 04:09:18.744162 | debian-bookworm | changed: .d..t...... ./ 2026-05-28 04:09:18.769386 | 2026-05-28 04:09:18.769582 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-28 04:09:19.340964 | debian-bookworm -> localhost | ok: Item: artifacts Runtime: 0:00:00.012193 2026-05-28 04:09:19.662434 | debian-bookworm -> localhost | ok: Item: docs Runtime: 0:00:00.014086 2026-05-28 04:09:19.685863 | 2026-05-28 04:09:19.685993 | PLAY RECAP 2026-05-28 04:09:19.686074 | debian-bookworm | ok: 4 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-28 04:09:19.686116 | 2026-05-28 04:09:19.821914 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post-fetch.yaml@main] 2026-05-28 04:09:19.824093 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 04:09:20.566206 | 2026-05-28 04:09:20.566380 | PLAY [Base post] 2026-05-28 04:09:20.585836 | 2026-05-28 04:09:20.585992 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-28 04:09:21.211263 | debian-bookworm | changed 2026-05-28 04:09:21.218451 | 2026-05-28 04:09:21.218573 | PLAY RECAP 2026-05-28 04:09:21.218637 | debian-bookworm | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-28 04:09:21.218698 | 2026-05-28 04:09:21.377135 | POST-RUN END RESULT_NORMAL: [trusted : github.com/osism/zuul-config/playbooks/base/post.yaml@main] 2026-05-28 04:09:21.378285 | POST-RUN START: [trusted : github.com/osism/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-28 04:09:22.276622 | 2026-05-28 04:09:22.276883 | PLAY [Base post-logs] 2026-05-28 04:09:22.290341 | 2026-05-28 04:09:22.290530 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-28 04:09:22.838414 | localhost | changed 2026-05-28 04:09:22.851779 | 2026-05-28 04:09:22.852003 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-28 04:09:22.881394 | localhost | ok 2026-05-28 04:09:22.886511 | 2026-05-28 04:09:22.886666 | TASK [Set zuul-log-path fact] 2026-05-28 04:09:22.915924 | localhost | ok 2026-05-28 04:09:22.925928 | 2026-05-28 04:09:22.926076 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-28 04:09:22.965436 | localhost | ok 2026-05-28 04:09:22.972941 | 2026-05-28 04:09:22.973133 | TASK [upload-logs : Create log directories] 2026-05-28 04:09:23.518149 | localhost | changed 2026-05-28 04:09:23.529596 | 2026-05-28 04:09:23.529805 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-28 04:09:24.108362 | localhost -> localhost | ok: Runtime: 0:00:00.008234 2026-05-28 04:09:24.118718 | 2026-05-28 04:09:24.119004 | TASK [upload-logs : Upload logs to log server] 2026-05-28 04:09:24.714613 | localhost | Output suppressed because no_log was given 2026-05-28 04:09:24.719572 | 2026-05-28 04:09:24.719756 | LOOP [upload-logs : Compress console log and json output] 2026-05-28 04:09:24.773046 | localhost | skipping: Conditional result was False 2026-05-28 04:09:24.782147 | localhost | skipping: Conditional result was False 2026-05-28 04:09:24.792925 | 2026-05-28 04:09:24.793151 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-28 04:09:24.843861 | localhost | skipping: Conditional result was False 2026-05-28 04:09:24.844505 | 2026-05-28 04:09:24.848119 | localhost | skipping: Conditional result was False 2026-05-28 04:09:24.855691 | 2026-05-28 04:09:24.855912 | LOOP [upload-logs : Upload console log and json output]