linux-amd64-longtest at ee4d32249b0ccd1475a20ee9e4c5caf4a39e36c4 :: Running /workdir/go/src/make.bash with args ["/workdir/go/src/make.bash"] and env ["PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin" "HOSTNAME=buildlet-linux-stretch-morecpu-rn65a881d" "DEBIAN_FRONTEND=noninteractive" "HOME=/root" "USER=root" "GO_STAGE0_NET_DELAY=0s" "GO_STAGE0_DL_DELAY=100ms" "WORKDIR=/workdir" "GOROOT_BOOTSTRAP=/workdir/go1.4" "GO_BUILDER_NAME=linux-amd64-longtest" "GO_TEST_SHORT=0" "GOROOT_BOOTSTRAP=/go1.4" "GO_TEST_TIMEOUT_SCALE=5" "GOBIN=" "TMPDIR=/workdir/tmp" "GOCACHE=/workdir/gocache"] in dir /workdir/go/src Building Go cmd/dist using /go1.4. (go1.4 linux/amd64) Building Go toolchain1 using /go1.4. Building Go bootstrap cmd/go (go_bootstrap) using Go toolchain1. Building Go toolchain2 using go_bootstrap and Go toolchain1. Building Go toolchain3 using go_bootstrap and Go toolchain2. Building packages and commands for linux/amd64. --- Installed Go for linux/amd64 in /workdir/go Installed commands in /workdir/go/bin ##### Testing packages. ok archive/tar 0.019s ok archive/zip 24.467s ok bufio 0.042s ok bytes 1.549s ok compress/bzip2 0.060s ok compress/flate 8.257s ok compress/gzip 0.043s ok compress/lzw 0.059s ok compress/zlib 0.619s ok container/heap 0.004s ok container/list 0.002s ok container/ring 0.003s ok context 0.011s ok crypto 0.003s ok crypto/aes 0.023s ok crypto/cipher 0.221s ok crypto/des 0.011s ok crypto/dsa 4.442s ok crypto/ecdsa 2.662s ok crypto/ed25519 0.056s ok crypto/elliptic 2.342s ok crypto/hmac 0.003s ok crypto/internal/subtle 0.002s ok crypto/md5 0.004s ok crypto/rand 0.170s ok crypto/rc4 0.029s ok crypto/rsa 0.452s ok crypto/sha1 0.017s ok crypto/sha256 0.003s ok crypto/sha512 0.003s ok crypto/subtle 0.004s ok crypto/tls 1.866s ok crypto/x509 2.251s ok database/sql 1.307s ok database/sql/driver 0.003s ok debug/dwarf 0.010s ok debug/elf 0.013s ok debug/gosym 0.162s ok debug/macho 0.004s ok debug/pe 0.005s ok debug/plan9obj 0.003s ok embed/internal/embedtest 0.003s ok encoding/ascii85 0.004s ok encoding/asn1 0.004s ok encoding/base32 0.016s ok encoding/base64 0.004s ok encoding/binary 0.003s ok encoding/csv 0.004s ok encoding/gob 0.020s ok encoding/hex 0.008s ok encoding/json 0.988s ok encoding/pem 0.010s ok encoding/xml 0.014s ok errors 0.003s ok expvar 0.003s ok flag 0.018s ok fmt 0.071s ok go/ast 0.005s ok go/build 0.784s ok go/constant 0.007s ok go/doc 0.032s ok go/format 0.014s ok go/importer 0.064s ok go/internal/gccgoimporter 0.010s ok go/internal/gcimporter 1.413s ok go/internal/srcimporter 4.138s ok go/parser 0.019s ok go/printer 0.203s ok go/scanner 0.005s ok go/token 0.012s ok go/types 7.984s ok hash 0.003s ok hash/adler32 0.006s ok hash/crc32 0.006s ok hash/crc64 0.003s ok hash/fnv 0.002s ok hash/maphash 110.623s ok html 0.012s ok html/template 0.258s ok image 0.171s ok image/color 0.020s ok image/draw 0.041s ok image/gif 0.198s ok image/jpeg 0.158s ok image/png 0.299s ok index/suffixarray 47.193s ok internal/cpu 0.008s ok internal/fmtsort 0.003s ok internal/poll 1.416s ok internal/profile 0.003s ok internal/reflectlite 0.033s ok internal/singleflight 0.017s ok internal/trace 0.809s ok internal/unsafeheader 0.002s ok internal/xcoff 0.006s ok io 0.025s ok io/fs 0.007s ok io/ioutil 0.011s ok log 0.005s ok log/syslog 1.214s ok math 0.006s ok math/big 2.639s ok math/bits 0.005s ok math/cmplx 0.003s ok math/rand 3.970s ok mime 0.007s ok mime/multipart 0.233s ok mime/quotedprintable 0.091s ok net 26.354s panic: test timed out after 15m0s goroutine 9776 [running]: testing.(*M).startAlarm.func1() /workdir/go/src/testing/testing.go:1701 +0xe5 created by time.goFunc /workdir/go/src/time/sleep.go:169 +0x45 goroutine 1 [chan receive, 14 minutes]: testing.(*T).Run(0xc0004aec00, 0x929f1b, 0x1b, 0x94e2d0, 0x494701) /workdir/go/src/testing/testing.go:1240 +0x2da testing.runTests.func1(0xc000102780) /workdir/go/src/testing/testing.go:1512 +0x78 testing.tRunner(0xc000102780, 0xc000153d68) /workdir/go/src/testing/testing.go:1194 +0xef testing.runTests(0xc0001360a8, 0xc31180, 0x218, 0x218, 0xbff60da79aeb1956, 0xd18c3d0aee, 0xc367c0, 0x203000) /workdir/go/src/testing/testing.go:1510 +0x2fe testing.(*M).Run(0xc000194000, 0x0) /workdir/go/src/testing/testing.go:1418 +0x1eb net/http_test.TestMain(0xc000194000) /workdir/go/src/net/http/main_test.go:23 +0x2b main.main() _testmain.go:1183 +0x165 goroutine 42 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000232f00) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestCmdGoNoHTTPServer(0xc000232f00) /workdir/go/src/net/http/http_test.go:81 +0x45 testing.tRunner(0xc000232f00, 0x94da50) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 43 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000233080) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestOmitHTTP2(0xc000233080) /workdir/go/src/net/http/http_test.go:115 +0x6a testing.tRunner(0xc000233080, 0x94dac8) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 44 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000233200) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestOmitHTTP2Vet(0xc000233200) /workdir/go/src/net/http/http_test.go:127 +0x45 testing.tRunner(0xc000233200, 0x94dac0) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 50 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000233e00) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestRequestWriteTransport(0xc000233e00) /workdir/go/src/net/http/requestwrite_test.go:668 +0x45 testing.tRunner(0xc000233e00, 0x94db68) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 55 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000288780) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestReadResponseCloseInMiddle(0xc000288780) /workdir/go/src/net/http/response_test.go:647 +0x45 testing.tRunner(0xc000288780, 0x94db10) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 4440 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000880780) /workdir/go/src/testing/testing.go:1060 +0x119 net/http_test.TestConsumingBodyOnNextConn(0xc000880780) /workdir/go/src/net/http/serve_test.go:165 +0x5b testing.tRunner(0xc000880780, 0x94e260) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 9772 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x7fe0ec5d85e0, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(*pollDesc).wait(0xc000438198, 0x72, 0x0, 0x0, 0x91ae71) /workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x45 internal/poll.(*pollDesc).waitRead(...) /workdir/go/src/internal/poll/fd_poll_runtime.go:92 internal/poll.(*FD).Accept(0xc000438180, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/fd_unix.go:401 +0x212 net.(*netFD).accept(0xc000438180, 0x754771, 0xc0004140bc, 0xc0005c89c0) /workdir/go/src/net/fd_unix.go:172 +0x45 net.(*TCPListener).accept(0xc0002c2030, 0x0, 0xc000071e20, 0x9d4548) /workdir/go/src/net/tcpsock_posix.go:139 +0x32 net.(*TCPListener).Accept(0xc0002c2030, 0xc000071e70, 0x18, 0xc00092e900, 0x6fa39b) /workdir/go/src/net/tcpsock.go:261 +0x65 net/http.(*Server).Serve(0xc00077a000, 0x9ce8f0, 0xc0002c2030, 0x0, 0x0) /workdir/go/src/net/http/server.go:2981 +0x285 net/http/httptest.(*Server).goServe.func1(0xc000414070) /workdir/go/src/net/http/httptest/server.go:308 +0x6e created by net/http/httptest.(*Server).goServe /workdir/go/src/net/http/httptest/server.go:306 +0x5c goroutine 9814 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x7fe0ec5d74c0, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(*pollDesc).wait(0xc000030618, 0x72, 0x0, 0x0, 0x400000) /workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x45 internal/poll.(*pollDesc).waitRead(...) /workdir/go/src/internal/poll/fd_poll_runtime.go:92 internal/poll.spliceDrain(0xb, 0xc000030600, 0x400000, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/splice_linux.go:96 +0x1a5 internal/poll.Splice(0xc000030600, 0xc000030600, 0x4000000000000000, 0x4, 0x7fe116a1d801, 0x0, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/splice_linux.go:42 +0x131 net.splice(0xc000030600, 0x9c5980, 0xc000440090, 0xc0002463c0, 0x40c27f, 0xc000012000, 0x8a96e0) /workdir/go/src/net/splice_linux.go:39 +0xab net.(*TCPConn).readFrom(0xc000440090, 0x9c5980, 0xc000440090, 0xc0007639f0, 0xc000763a00, 0x40d68a) /workdir/go/src/net/tcpsock_posix.go:48 +0x46 net.(*TCPConn).ReadFrom(0xc000440090, 0x9c5980, 0xc000440090, 0x7fe0ec6517b0, 0xc000440090, 0xc000440001) /workdir/go/src/net/tcpsock.go:103 +0x4d io.copyBuffer(0x9c59a0, 0xc000440090, 0x9c5980, 0xc000440090, 0x0, 0x0, 0x0, 0x0, 0xc000030600, 0x94d628) /workdir/go/src/io/io.go:409 +0x357 io.Copy(...) /workdir/go/src/io/io.go:382 net/http_test.TestDisableKeepAliveUpgrade.func1(0x9ceb00, 0xc0002740e0, 0xc000386200) /workdir/go/src/net/http/serve_test.go:6469 +0x365 net/http.HandlerFunc.ServeHTTP(0x94e2c8, 0x9ceb00, 0xc0002740e0, 0xc000386200) /workdir/go/src/net/http/server.go:2069 +0x44 net/http.serverHandler.ServeHTTP(0xc00077a000, 0x9ceb00, 0xc0002740e0, 0xc000386200) /workdir/go/src/net/http/server.go:2887 +0xa3 net/http.(*conn).serve(0xc0000fc5a0, 0x9cf500, 0xc0002fc540) /workdir/go/src/net/http/server.go:1952 +0x8cd created by net/http.(*Server).Serve /workdir/go/src/net/http/server.go:3013 +0x39b goroutine 9771 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x7fe1169f69d0, 0x72, 0xffffffffffffffff) /workdir/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(*pollDesc).wait(0xc000438498, 0x72, 0x0, 0x1, 0xffffffffffffffff) /workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x45 internal/poll.(*pollDesc).waitRead(...) /workdir/go/src/internal/poll/fd_poll_runtime.go:92 internal/poll.(*FD).Read(0xc000438480, 0xc0004e20d4, 0x1, 0x1, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/fd_unix.go:166 +0x1d5 net.(*netFD).Read(0xc000438480, 0xc0004e20d4, 0x1, 0x1, 0x4, 0x0, 0x0) /workdir/go/src/net/fd_posix.go:55 +0x4f net.(*conn).Read(0xc000126030, 0xc0004e20d4, 0x1, 0x1, 0x0, 0x0, 0x0) /workdir/go/src/net/net.go:183 +0x91 net/http.(*readWriteCloserBody).Read(0xc000136168, 0xc0004e20d4, 0x1, 0x1, 0x4, 0x0, 0x0) /workdir/go/src/net/http/transport.go:2371 +0x102 io.ReadAtLeast(0x7fe0ecf02ce8, 0xc000136168, 0xc0004e20d0, 0x5, 0x5, 0x5, 0x0, 0x507320, 0xb6d2b8) /workdir/go/src/io/io.go:328 +0x87 io.ReadFull(...) /workdir/go/src/io/io.go:347 net/http_test.TestDisableKeepAliveUpgrade(0xc0004aec00) /workdir/go/src/net/http/serve_test.go:6499 +0x455 testing.tRunner(0xc0004aec00, 0x94e2d0) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 FAIL net/http 900.010s FAIL go tool dist: Failed: exit status 1