[I2024-03-21T20:00:18.585036Z 229788 0 sink.go:276] SinkServer: warm-up started [I2024-03-21T20:00:18.585099Z 229788 0 sink.go:346] SinkServer: starting HTTP server... [I2024-03-21T20:00:18.586179Z 229788 0 sink.go:281] SinkServer: warm-up ended [I2024-03-21T20:00:18.586498Z 229788 0 cmd_stream.go:473] rdb-stream: starting the test command - ["/home/swarming/.swarming/w/ir/cache/tools/bin/result_adapter" "go" "-v=false" "--" "/home/swarming/.swarming/w/ir/x/w/goroot/bin/go" "tool" "dist" "test" "-json" "-race" "archive/zip" "compress/flate" "crypto/dsa" "crypto/internal/edwards25519" "crypto/internal/edwards25519/field" "crypto/internal/nistec" "crypto/rsa" "crypto/subtle" "crypto/tls" "database/sql" "encoding/gob" "encoding/xml" "go/build" "go/internal/gcimporter" "go/internal/srcimporter" "go/parser" "go/types" "hash/maphash" "image/gif" "image/jpeg" "index/suffixarray" "internal/abi" "internal/chacha8rand" "internal/cpu" "internal/godebugs" "internal/intern" "internal/poll" "internal/reflectlite" "internal/trace" "internal/trace/v2" "io" "log/syslog" "math" "math/big" "math/bits" "math/rand" "math/rand/v2" "net" "net/http" "net/http/httptrace" "net/http/pprof" "net/netip" "os" "os/signal" "reflect" "regexp" "runtime" "runtime/coverage" "runtime/internal/atomic" "runtime/pprof" "sort" "strconv" "strings" "sync" "sync/atomic" "syscall" "testing" "time" "cmd/api" "cmd/cgo/internal/test" "cmd/cgo/internal/testcarchive" "cmd/cgo/internal/testcshared" "cmd/cgo/internal/testerrors" "cmd/cgo/internal/testfortran" "cmd/cgo/internal/testplugin" "cmd/cgo/internal/testsanitizers" "cmd/cgo/internal/testshared" "compress/lzw" "container/list" "crypto/ecdsa" "crypto/elliptic" "crypto/rand" "crypto/sha256" "crypto/sha512" "encoding/ascii85" "encoding/binary" "encoding/pem" "expvar" "go/internal/gccgoimporter" "hash" "hash/crc32" "hash/crc64" "internal/coverage/cformat" "internal/saferio" "internal/singleflight" "math/cmplx" "mime" "net/http/cgi" "net/http/fcgi" "net/http/httptest" "net/http/internal" "net/mail" "net/url" "plugin" "runtime/internal/math" "slices" "testing/iotest" "testing/quick" "text/template" "unicode" "cmd/compile/internal/abt" "cmd/compile/internal/base" "cmd/compile/internal/inline/inlheur" "cmd/compile/internal/ir" "cmd/compile/internal/reflectdata" "cmd/covdata" "cmd/cover" "cmd/distpack" "cmd/go/internal/mvs" "cmd/go/internal/test" "cmd/go/internal/work" "cmd/internal/dwarf" "cmd/internal/objabi" "cmd/internal/pkgpattern" "cmd/internal/test2json" "cmd/link/internal/loader" "cmd/relnote" "cmd/trace"] go tool dist: Failed: exit status 1 ok archive/zip 4.231s ok compress/flate 8.369s ok crypto/dsa 1.024s ok crypto/internal/edwards25519 2.253s ok crypto/internal/edwards25519/field 1.099s ok crypto/internal/nistec 2.463s ok crypto/rsa 2.826s ok crypto/subtle 1.586s ok crypto/tls 6.234s ok database/sql 1.736s ok encoding/gob 39.802s ok encoding/xml 1.350s ok go/build 13.925s ok go/internal/gcimporter 28.237s ok go/internal/srcimporter 34.854s ok go/parser 3.018s ok go/types 104.147s ok hash/maphash 1.028s ok image/gif 2.915s ok image/jpeg 3.556s ok index/suffixarray 2.671s ok internal/abi 2.042s ok internal/chacha8rand 1.036s ok internal/cpu 3.144s ok internal/godebugs 128.968s ok internal/intern 2.028s ok internal/poll 1.710s ok internal/reflectlite 1.027s ok internal/trace 1.494s ok internal/trace/v2 33.823s ok io 1.125s ok log/syslog 2.303s ok math 1.065s ok math/big 6.923s ok math/bits 1.059s ok math/rand 5.772s ok math/rand/v2 3.973s ok net 45.035s --- FAIL: TestBidiStreamReverseProxy (0.01s) --- FAIL: TestBidiStreamReverseProxy/h2 (1.23s) testing.go:1398: race detected during execution of test --- FAIL: TestCancelRequestMidBody (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestCancelRequestWithChannelBeforeDo_Context (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestChunkedResponseHeaders (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClient (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestClientAltersCookiesOnRedirect (0.04s) testing.go:1398: race detected during execution of test --- FAIL: TestClientCallsCloseOnlyOnce (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientCopyHeadersOnRedirect (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientCopyHostOnRedirect (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientDoCanceledVsTimeout (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientHead (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientHeadContentLength (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientInsecureTransport (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirect308NoGetBody (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirectEatsBody_h1 (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirectNoLocation (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirectTypes (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirectUseResponse (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirects (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientRedirectsContext (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientTimeout (0.03s) testing.go:1398: race detected during execution of test --- FAIL: TestClientTimeoutCancel (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientTimeoutDoesNotExpire (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestClientTimeout_Headers (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientWithCorrectTLSServerName (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientWithIncorrectTLSServerName (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientWriteShutdown (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestClientWrites (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestCloseIdleConnections (0.00s) --- FAIL: TestCloseIdleConnections/h2 (0.42s) testing.go:1398: race detected during execution of test --- FAIL: TestConcurrentReadWriteReqBody (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestConnectRequest (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestContentTypeWithVariousSources (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestDeleteRedirects (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestDirectoryIfNotModified (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestDisableKeepAliveUpgrade (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestDontCacheBrokenHTTP2Conn (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestEmptyPasswordAuth (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerDirWithRootFile (0.00s) --- FAIL: TestFileServerDirWithRootFile/h2 (0.44s) --- FAIL: TestFileServerDirWithRootFile/h2/FileServer (0.24s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerEscapesNames (0.01s) --- FAIL: TestFileServerEscapesNames/h2 (0.27s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerImplicitLeadingSlash (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerMethods (0.01s) --- FAIL: TestFileServerMethods/h2 (0.28s) fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file fs_test.go:1544: https://127.0.0.1:35271/file testing.go:1398: race detected during execution of test --- FAIL: TestFileServerNamesEscape (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerNotDirError (0.00s) --- FAIL: TestFileServerNotDirError/h2 (0.29s) --- FAIL: TestFileServerNotDirError/h2/FS (0.22s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerSortsNames (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestFileServerZeroByte (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestHTTPSClientDetectsHTTPServer (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestHijackAfterCloseNotifier (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestHostHandlers (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestIdentityTransferEncoding (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestIdleConnH2Crash (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestJarCalls (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestMaxInt64ForMultipartFormMaxMemoryOverflow (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestNewClientServerTest (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestNoSniffExpectRequestBody (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestOmitHTTP2Vet (6.29s) testing.go:1398: race detected during execution of test --- FAIL: TestPostRedirects (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestProbeZeroLengthBody (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestReadResponseCloseInMiddle (4.81s) testing.go:1398: race detected during execution of test --- FAIL: TestRedirectCookiesJar (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestRequestLimit (0.02s) --- FAIL: TestRequestLimit/h1 (4.22s) testing.go:1398: race detected during execution of test --- FAIL: TestRequestLimit/h2 (5.54s) testing.go:1398: race detected during execution of test --- FAIL: TestRequestRedirect (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestResponseBodyReadAfterClose (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestResponseControllerFlush (0.01s) --- FAIL: TestResponseControllerFlush/h2 (0.19s) testing.go:1398: race detected during execution of test --- FAIL: TestResponseControllerSetFutureWriteDeadline (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestResponseSetsTLSConnectionState (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeContent (0.03s) testing.go:1398: race detected during execution of test --- FAIL: TestServeContentErrorMessages (0.02s) testing.go:1398: race detected during execution of test --- FAIL: TestServeDirWithoutTrailingSlash (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeFileContentType (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeFileFromCWD (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeFileMimeType (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeFileNotModified (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestServeFileRejectsInvalidSuffixLengths (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeFileWithContentEncoding (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestServeIndexHtml (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServeIndexHtmlFS (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServerShutdownStateNew (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestServerUndeclaredTrailers (0.02s) testing.go:1398: race detected during execution of test --- FAIL: TestServerWriteTimeout (0.00s) --- FAIL: TestServerWriteTimeout/h2 (1.00s) serve_test.go:850: Server.Config.WriteTimeout = 5ms clientserver_test.go:210: server log: http: TLS handshake error from 127.0.0.1:42436: write tcp 127.0.0.1:39061->127.0.0.1:42436: i/o timeout serve_test.go:881: Get error, retrying: Get "https://127.0.0.1:39061": EOF serve_test.go:850: Server.Config.WriteTimeout = 10ms clientserver_test.go:210: server log: http: TLS handshake error from 127.0.0.1:56718: write tcp 127.0.0.1:38195->127.0.0.1:56718: i/o timeout serve_test.go:881: Get error, retrying: Get "https://127.0.0.1:38195": EOF serve_test.go:850: Server.Config.WriteTimeout = 20ms clientserver_test.go:210: server log: http: TLS handshake error from 127.0.0.1:41478: write tcp 127.0.0.1:37981->127.0.0.1:41478: i/o timeout serve_test.go:881: Get error, retrying: Get "https://127.0.0.1:37981": EOF serve_test.go:850: Server.Config.WriteTimeout = 40ms clientserver_test.go:210: server log: http: TLS handshake error from 127.0.0.1:34582: write tcp 127.0.0.1:44871->127.0.0.1:34582: i/o timeout serve_test.go:881: Get error, retrying: Get "https://127.0.0.1:44871": EOF serve_test.go:850: Server.Config.WriteTimeout = 80ms testing.go:1398: race detected during execution of test --- FAIL: TestStreamingGet (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestTLSServer (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestTLSServerClosesConnection (0.00s) --- FAIL: TestTLSServerClosesConnection/https1 (4.31s) transport_test.go:3539: successes = 20 of 20 testing.go:1398: race detected during execution of test --- FAIL: TestTrailersClientToServer (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestTrailersServerToClientFlush (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportBodyReadError (0.01s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportConnectionCloseOnResponse (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportDiscardsUnneededConns (0.02s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportGCRequest (0.00s) --- FAIL: TestTransportGCRequest/h2 (0.67s) --- FAIL: TestTransportGCRequest/h2/Body (0.44s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportGzip (0.01s) --- FAIL: TestTransportGzip/h1 (4.16s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportRejectsInvalidHeaders (0.03s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportUserAgent (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestTransportUsesTLSConfigServerName (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestWrappedResponseController (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestWriteHeader0 (0.00s) testing.go:1398: race detected during execution of test --- FAIL: TestWriteHeaderNoCodeCheck (0.00s) testing.go:1398: race detected during execution of test FAIL FAIL net/http 12.299s ok net/http/httptrace 1.025s ok net/http/pprof 6.259s ok net/netip 1.587s ok os 7.912s ok os/signal 19.054s ok reflect 1.983s ok regexp 8.117s ok runtime 78.548s ok runtime/coverage 1.020s ok runtime/internal/atomic 1.105s ok runtime/pprof 26.962s ok sort 1.762s ok strconv 4.996s ok strings 4.060s ok sync 1.721s ok sync/atomic 4.305s ok syscall 10.896s ok testing 4.040s ok time 13.148s ok cmd/api 41.146s ok cmd/cgo/internal/test 3.874s ok cmd/cgo/internal/testcarchive 58.202s ok cmd/cgo/internal/testcshared 32.509s ok cmd/cgo/internal/testerrors 35.348s ok cmd/cgo/internal/testfortran 6.844s ok cmd/cgo/internal/testplugin 68.217s ok cmd/cgo/internal/testsanitizers 39.195s ok cmd/cgo/internal/testshared 85.962s ok compress/lzw 3.153s ok container/list 1.095s ok crypto/ecdsa 1.558s ok crypto/elliptic 1.169s ok crypto/rand 1.883s ok crypto/sha256 1.048s ok crypto/sha512 1.065s ok encoding/ascii85 1.175s ok encoding/binary 1.074s ok encoding/pem 8.445s ok expvar 1.057s ok go/internal/gccgoimporter 1.194s ok hash 1.081s ok hash/crc32 1.248s ok hash/crc64 1.061s ok internal/coverage/cformat 1.084s ok internal/saferio 1.488s ok internal/singleflight 1.233s ok math/cmplx 1.058s ok mime 1.134s ok net/http/cgi 28.502s ok net/http/fcgi 1.337s ok net/http/httptest 1.516s ok net/http/internal 6.193s ok net/mail 1.177s ok net/url 1.080s ok plugin 1.045s ok runtime/internal/math 1.054s ok slices 2.037s ok testing/iotest 1.052s ok testing/quick 1.374s ok text/template 1.143s ok unicode 1.046s ok cmd/compile/internal/abt 1.091s ok cmd/compile/internal/base 1.027s ok cmd/compile/internal/inline/inlheur 1.648s ok cmd/compile/internal/ir 1.022s ok cmd/compile/internal/reflectdata 1.024s [no tests to run] ok cmd/covdata 1.035s ok cmd/cover 21.513s ok cmd/distpack 1.018s ok cmd/go/internal/mvs 1.069s ok cmd/go/internal/test 1.090s ok cmd/go/internal/work 1.262s ok cmd/internal/dwarf 1.110s ok cmd/internal/objabi 1.132s ok cmd/internal/pkgpattern 1.062s ok cmd/internal/test2json 2.486s ok cmd/link/internal/loader 1.096s ok cmd/relnote 1.071s ok cmd/trace 1.100s Warning: no '=' in invocation-link-artifacts pair: "", ignoring [I2024-03-21T20:03:18.765633Z 229788 0 cmd_stream.go:469] rdb-stream: the test process terminated [I2024-03-21T20:03:18.765784Z 229788 0 sink.go:371] SinkServer: shutdown started [I2024-03-21T20:03:18.765846Z 229788 0 sink.go:349] SinkServer: HTTP server stopped with "http: Server closed" [I2024-03-21T20:03:18.765873Z 229788 0 sink_server.go:95] SinkServer: draining TestResult channel started [I2024-03-21T20:03:19.589449Z 229788 0 sink_server.go:97] SinkServer: draining TestResult channel ended [I2024-03-21T20:03:19.589521Z 229788 0 sink_server.go:99] SinkServer: draining Artifact channel started [I2024-03-21T20:03:19.686089Z 229788 0 sink_server.go:101] SinkServer: draining Artifact channel ended [I2024-03-21T20:03:19.686151Z 229788 0 sink.go:374] SinkServer: shutdown completed successfully [I2024-03-21T20:03:19.686168Z 229788 0 cmd_stream.go:401] rdb-stream: exiting with 1