[I2023-08-21T16:25:35.161954Z 10027 0 sink.go:276] SinkServer: warm-up started [I2023-08-21T16:25:35.161998Z 10027 0 sink.go:346] SinkServer: starting HTTP server... [I2023-08-21T16:25:35.162977Z 10027 0 sink.go:281] SinkServer: warm-up ended [I2023-08-21T16:25:35.163123Z 10027 0 cmd_stream.go:473] rdb-stream: starting the test command - ["/b/s/w/ir/x/w/cipd_tool/fuchsia/shac/git_revision%3A7fafae0f3d68b0a57a4532f7e2bd0fed7a2a1bf3/shac" "check" "--all" "--root" "/b/s/w/ir/x/w/fuchsia-infra-recipes" "--json-output" "/b/s/w/ir/x/t/tmpkv3srclmjson"] - recipe_style_guide (success in 1.455s) - black (error in 1.749s): wait: command failed with exit code 1: [cipd ensure -ensure-file shac.ensure -root /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools] Can't resolve $HOME: exit status 2[P1 16:25:35.216 auth.go:1044 W] Disabling auth disk token cache. Not configured. [P1 16:25:35.262 action_plan.go:163 I] In root: [P1 16:25:35.262 action_plan.go:169 I] to install: [P1 16:25:35.262 action_plan.go:171 I] fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC [P1 16:25:35.262 action_plan.go:171 I] infra/3pp/go/github.com/bufbuild/buf/linux-amd64:k-3C0JS-yOpeKUfuN72alqwz50OnVK6qPcLs3Rpb50QC [P1 16:25:35.263 instancecache.go:188 I] Using temporary instance cache at /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools/.cipd/tmp/dl_1273282356 [P1 16:25:35.264 client.go:1838 I] [fetch 1/2] Resolving fetch URL for fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC [P1 16:25:35.331 storage.go:283 I] [fetch 1/2] Connecting... [P1 16:25:35.504 storage.go:245 I] [fetch 1/2] Fetching: 0.0/22.3 MB ( 0%!)(MISSING) [P1 16:25:36.008 storage.go:245 I] [fetch 1/2] Fetching: 13.3/22.3 MB ( 60%!,(MISSING) 21.77 MB/s) [P1 16:25:36.189 storage.go:245 I] [fetch 1/2] Fetching: 22.3/22.3 MB (100%!,(MISSING) 22.85 MB/s) [P1 16:25:36.190 client.go:1832 I] [fetch 1/2] Fetched fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC in 0.9s [P1 16:25:36.190 client.go:1838 I] [fetch 2/2] Resolving fetch URL for infra/3pp/go/github.com/bufbuild/buf/linux-amd64:k-3C0JS-yOpeKUfuN72alqwz50OnVK6qPcLs3Rpb50QC [P1 16:25:36.190 deployer.go:303 I] [unzip 1/2] Deploying fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC into /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools(/) [P1 16:25:36.201 storage.go:283 I] [fetch 2/2] Connecting... [P1 16:25:36.279 reader.go:400 I] [unzip 1/2] Extracting (3 files left): 0.0/22.3 MB ( 0%!)(MISSING) [P1 16:25:36.280 storage.go:245 I] [fetch 2/2] Fetching: 0.0/35.4 MB ( 0%!)(MISSING) [P1 16:25:36.501 reader.go:424 I] [unzip 1/2] Extracting (0 files left): 22.3/22.3 MB (100%!,(MISSING) 100.64 MB/s) [P1 16:25:36.501 deployer.go:388 I] [unzip 1/2] Moving files to their final destination... [P1 16:25:36.517 deployer.go:403 I] [unzip 1/2] Cleaning up... [P1 16:25:36.518 deployer.go:409 I] [unzip 1/2] Deployed fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC in 0.3s [P1 16:25:36.781 storage.go:245 I] [fetch 2/2] Fetching: 25.8/35.4 MB ( 73%!,(MISSING) 33.06 MB/s) [P1 16:25:36.932 storage.go:245 I] [fetch 2/2] Fetching: 35.4/35.4 MB (100%!,(MISSING) 34.53 MB/s) [P1 16:25:36.935 client.go:1832 I] [fetch 2/2] Fetched infra/3pp/go/github.com/bufbuild/buf/linux-amd64:k-3C0JS-yOpeKUfuN72alqwz50OnVK6qPcLs3Rpb50QC in 0.7s [P1 16:25:36.946 client.go:1913 E] [unzip 2/2] Failed to install infra/3pp/go/github.com/bufbuild/buf/linux-amd64: writing to instance cache: lstat /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools/.cipd/tmp/dl_1273282356/ydG06gcweyld: no such file or directory Errors: failed to install infra/3pp/go/github.com/bufbuild/buf/linux-amd64: writing to instance cache: lstat /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools/.cipd/tmp/dl_1273282356/ydG06gcweyld: no such file or directory [W2023-08-21T16:25:36.964054Z 10027 0 encoding.go:226] prpc: responding with InvalidArgument error (HTTP 400): failure_reason: primary_error_message: exceeds the maximum size of 1024 bytes Traceback (most recent call last): //shac.star:6:31: in black //shac.star:72:34: in _install_tools shac: wait: command failed with exit code 1: [cipd ensure -ensure-file shac.ensure -root /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools] Can't resolve $HOME: exit status 2[P1 16:25:35.216 auth.go:1044 W] Disabling auth disk token cache. Not configured. [P1 16:25:35.262 action_plan.go:163 I] In root: [P1 16:25:35.262 action_plan.go:169 I] to install: [P1 16:25:35.262 action_plan.go:171 I] fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC [P1 16:25:35.262 action_plan.go:171 I] infra/3pp/go/github.com/bufbuild/buf/linux-amd64:k-3C0JS-yOpeKUfuN72alqwz50OnVK6qPcLs3Rpb50QC [P1 16:25:35.263 instancecache.go:188 I] Using temporary instance cache at /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools/.cipd/tmp/dl_1273282356 [P1 16:25:35.264 client.go:1838 I] [fetch 1/2] Resolving fetch URL for fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC [P1 16:25:35.331 storage.go:283 I] [fetch 1/2] Connecting... [P1 16:25:35.504 storage.go:245 I] [fetch 1/2] Fetching: 0.0/22.3 MB ( 0%!)(MISSING) [P1 16:25:36.008 storage.go:245 I] [fetch 1/2] Fetching: 13.3/22.3 MB ( 60%!,(MISSING) 21.77 MB/s) [P1 16:25:36.189 storage.go:245 I] [fetch 1/2] Fetching: 22.3/22.3 MB (100%!,(MISSING) 22.85 MB/s) [P1 16:25:36.190 client.go:1832 I] [fetch 1/2] Fetched fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC in 0.9s [P1 16:25:36.190 client.go:1838 I] [fetch 2/2] Resolving fetch URL for infra/3pp/go/github.com/bufbuild/buf/linux-amd64:k-3C0JS-yOpeKUfuN72alqwz50OnVK6qPcLs3Rpb50QC [P1 16:25:36.190 deployer.go:303 I] [unzip 1/2] Deploying fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC into /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools(/) [P1 16:25:36.201 storage.go:283 I] [fetch 2/2] Connecting... [P1 16:25:36.279 reader.go:400 I] [unzip 1/2] Extracting (3 files left): 0.0/22.3 MB ( 0%!)(MISSING) [P1 16:25:36.280 storage.go:245 I] [fetch 2/2] Fetching: 0.0/35.4 MB ( 0%!)(MISSING) [P1 16:25:36.501 reader.go:424 I] [unzip 1/2] Extracting (0 files left): 22.3/22.3 MB (100%!,(MISSING) 100.64 MB/s) [P1 16:25:36.501 deployer.go:388 I] [unzip 1/2] Moving files to their final destination... [P1 16:25:36.517 deployer.go:403 I] [unzip 1/2] Cleaning up... [P1 16:25:36.518 deployer.go:409 I] [unzip 1/2] Deployed fuchsia/third_party/black/linux-amd64:s_X-nogKNUNktvkcWSj47M89wBvcUM0vI8oeiRdjTzYC in 0.3s [P1 16:25:36.781 storage.go:245 I] [fetch 2/2] Fetching: 25.8/35.4 MB ( 73%!,(MISSING) 33.06 MB/s) [P1 16:25:36.932 storage.go:245 I] [fetch 2/2] Fetching: 35.4/35.4 MB (100%!,(MISSING) 34.53 MB/s) [P1 16:25:36.935 client.go:1832 I] [fetch 2/2] Fetched infra/3pp/go/github.com/bufbuild/buf/linux-amd64:k-3C0JS-yOpeKUfuN72alqwz50OnVK6qPcLs3Rpb50QC in 0.7s [P1 16:25:36.946 client.go:1913 E] [unzip 2/2] Failed to install infra/3pp/go/github.com/bufbuild/buf/linux-amd64: writing to instance cache: lstat /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools/.cipd/tmp/dl_1273282356/ydG06gcweyld: no such file or directory Errors: failed to install infra/3pp/go/github.com/bufbuild/buf/linux-amd64: writing to instance cache: lstat /b/s/w/ir/x/w/fuchsia-infra-recipes/.tools/.cipd/tmp/dl_1273282356/ydG06gcweyld: no such file or directory [I2023-08-21T16:25:36.965406Z 10027 0 cmd_stream.go:469] rdb-stream: the test process terminated [I2023-08-21T16:25:36.965467Z 10027 0 sink.go:371] SinkServer: shutdown started [I2023-08-21T16:25:36.965507Z 10027 0 sink.go:349] SinkServer: HTTP server stopped with "http: Server closed" [I2023-08-21T16:25:36.965524Z 10027 0 sink_server.go:95] SinkServer: draining TestResult channel started [I2023-08-21T16:25:36.965547Z 10027 0 sink_server.go:97] SinkServer: draining TestResult channel ended [I2023-08-21T16:25:36.965554Z 10027 0 sink_server.go:99] SinkServer: draining Artifact channel started [I2023-08-21T16:25:36.965593Z 10027 0 sink_server.go:101] SinkServer: draining Artifact channel ended [I2023-08-21T16:25:36.965643Z 10027 0 sink.go:374] SinkServer: shutdown completed successfully [I2023-08-21T16:25:36.965664Z 10027 0 cmd_stream.go:401] rdb-stream: exiting with 1