linux-amd64-longtest at 59bfc18e3441d9cd0b1b2f302935403bbf52ac8b :: 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-rn1a46544" "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.971s ok bufio 0.049s ok bytes 1.588s ok compress/bzip2 0.059s ok compress/flate 7.465s ok compress/gzip 0.046s ok compress/lzw 0.065s ok compress/zlib 0.642s ok container/heap 0.004s ok container/list 0.003s ok container/ring 0.004s ok context 0.011s ok crypto 0.007s ok crypto/aes 0.021s ok crypto/cipher 0.224s ok crypto/des 0.007s ok crypto/dsa 11.297s ok crypto/ecdsa 2.700s ok crypto/ed25519 0.055s ok crypto/elliptic 2.338s ok crypto/hmac 0.003s ok crypto/internal/subtle 0.002s ok crypto/md5 0.003s ok crypto/rand 0.187s ok crypto/rc4 0.027s ok crypto/rsa 0.356s ok crypto/sha1 0.016s ok crypto/sha256 0.003s ok crypto/sha512 0.004s ok crypto/subtle 0.005s ok crypto/tls 1.790s ok crypto/x509 2.291s ok database/sql 1.309s ok database/sql/driver 0.002s ok debug/dwarf 0.015s ok debug/elf 0.013s ok debug/gosym 0.160s ok debug/macho 0.005s ok debug/pe 0.005s ok debug/plan9obj 0.002s ok embed/internal/embedtest 0.004s ok encoding/ascii85 0.005s ok encoding/asn1 0.004s ok encoding/base32 0.017s ok encoding/base64 0.004s ok encoding/binary 0.004s ok encoding/csv 0.005s ok encoding/gob 0.019s ok encoding/hex 0.004s ok encoding/json 1.058s ok encoding/pem 0.011s ok encoding/xml 0.015s ok errors 0.004s ok expvar 0.005s ok flag 0.017s ok fmt 0.096s ok go/ast 0.004s ok go/build 0.807s ok go/build/constraint 0.004s ok go/constant 0.005s ok go/doc 0.032s ok go/format 0.007s ok go/importer 0.069s ok go/internal/gccgoimporter 0.009s ok go/internal/gcimporter 1.402s ok go/internal/srcimporter 4.163s ok go/parser 0.024s ok go/printer 0.216s ok go/scanner 0.004s ok go/token 0.013s ok go/types 9.744s ok hash 0.004s ok hash/adler32 0.008s ok hash/crc32 0.006s ok hash/crc64 0.003s ok hash/fnv 0.002s ok hash/maphash 112.009s ok html 0.009s ok html/template 0.280s ok image 0.176s ok image/color 0.022s ok image/draw 0.039s ok image/gif 0.229s ok image/jpeg 0.169s ok image/png 0.321s ok index/suffixarray 49.139s ok internal/cpu 0.008s ok internal/fmtsort 0.003s ok internal/poll 1.324s ok internal/profile 0.006s ok internal/reflectlite 0.035s ok internal/singleflight 0.020s ok internal/trace 0.872s ok internal/unsafeheader 0.003s ok internal/xcoff 0.006s ok io 0.026s ok io/fs 0.006s ok io/ioutil 0.005s ok log 0.004s ok log/syslog 1.219s ok math 0.006s ok math/big 2.715s ok math/bits 0.005s ok math/cmplx 0.004s ok math/rand 4.161s ok mime 0.006s ok mime/multipart 0.241s ok mime/quotedprintable 0.092s ok net 27.105s panic: test timed out after 15m0s goroutine 9810 [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(0xc000496300, 0x92af1b, 0x1b, 0x94f2d0, 0x494801) /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, 0xc33180, 0x218, 0x218, 0xbff657f8e824784e, 0xd18c3e43dc, 0xc387c0, 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(0xc000230f00) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestCmdGoNoHTTPServer(0xc000230f00) /workdir/go/src/net/http/http_test.go:81 +0x45 testing.tRunner(0xc000230f00, 0x94ea50) /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(0xc000231080) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestOmitHTTP2(0xc000231080) /workdir/go/src/net/http/http_test.go:115 +0x6a testing.tRunner(0xc000231080, 0x94eac8) /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(0xc000231200) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestOmitHTTP2Vet(0xc000231200) /workdir/go/src/net/http/http_test.go:127 +0x45 testing.tRunner(0xc000231200, 0x94eac0) /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(0xc000231e00) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestRequestWriteTransport(0xc000231e00) /workdir/go/src/net/http/requestwrite_test.go:668 +0x45 testing.tRunner(0xc000231e00, 0x94eb68) /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(0xc000286780) /workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestReadResponseCloseInMiddle(0xc000286780) /workdir/go/src/net/http/response_test.go:647 +0x45 testing.tRunner(0xc000286780, 0x94eb10) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 4268 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc00084a780) /workdir/go/src/testing/testing.go:1060 +0x119 net/http_test.TestConsumingBodyOnNextConn(0xc00084a780) /workdir/go/src/net/http/serve_test.go:165 +0x5b testing.tRunner(0xc00084a780, 0x94f260) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 9706 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x7f478debd358, 0x72, 0xffffffffffffffff) /workdir/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(*pollDesc).wait(0xc00060a298, 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(0xc00060a280, 0xc00032c144, 0x1, 0x1, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/fd_unix.go:166 +0x1d5 net.(*netFD).Read(0xc00060a280, 0xc00032c144, 0x1, 0x1, 0x4, 0x0, 0x0) /workdir/go/src/net/fd_posix.go:55 +0x4f net.(*conn).Read(0xc000386038, 0xc00032c144, 0x1, 0x1, 0x0, 0x0, 0x0) /workdir/go/src/net/net.go:183 +0x91 net/http.(*readWriteCloserBody).Read(0xc0000aa168, 0xc00032c144, 0x1, 0x1, 0x4, 0x0, 0x0) /workdir/go/src/net/http/transport.go:2371 +0x102 io.ReadAtLeast(0x7f478ef43888, 0xc0000aa168, 0xc00032c140, 0x5, 0x5, 0x5, 0x0, 0x22, 0x439) /workdir/go/src/io/io.go:328 +0x87 io.ReadFull(...) /workdir/go/src/io/io.go:347 net/http_test.TestDisableKeepAliveUpgrade(0xc000496300) /workdir/go/src/net/http/serve_test.go:6499 +0x455 testing.tRunner(0xc000496300, 0x94f2d0) /workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run /workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 9776 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x7f4794095ac8, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(*pollDesc).wait(0xc000268018, 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, 0xc000268000, 0x400000, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/splice_linux.go:96 +0x1a5 internal/poll.Splice(0xc000268000, 0xc000268000, 0x4000000000000000, 0x4, 0xc00039a001, 0x0, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/splice_linux.go:42 +0x131 net.splice(0xc000268000, 0x9c6ac0, 0xc00039a020, 0xc000384000, 0x40c2bf, 0xc000012000, 0x8aa6e0) /workdir/go/src/net/splice_linux.go:39 +0xab net.(*TCPConn).readFrom(0xc00039a020, 0x9c6ac0, 0xc00039a020, 0xc0000e99f0, 0xc0000e9a00, 0x40d6ca) /workdir/go/src/net/tcpsock_posix.go:48 +0x46 net.(*TCPConn).ReadFrom(0xc00039a020, 0x9c6ac0, 0xc00039a020, 0x7f478de0b4e0, 0xc00039a020, 0xc00039a001) /workdir/go/src/net/tcpsock.go:103 +0x4d io.copyBuffer(0x9c6ae0, 0xc00039a020, 0x9c6ac0, 0xc00039a020, 0x0, 0x0, 0x0, 0x0, 0xc000268000, 0x94e628) /workdir/go/src/io/io.go:409 +0x357 io.Copy(...) /workdir/go/src/io/io.go:382 net/http_test.TestDisableKeepAliveUpgrade.func1(0x9cfc40, 0xc0000d40e0, 0xc000902100) /workdir/go/src/net/http/serve_test.go:6469 +0x365 net/http.HandlerFunc.ServeHTTP(0x94f2c8, 0x9cfc40, 0xc0000d40e0, 0xc000902100) /workdir/go/src/net/http/server.go:2069 +0x44 net/http.serverHandler.ServeHTTP(0xc000326000, 0x9cfc40, 0xc0000d40e0, 0xc000902100) /workdir/go/src/net/http/server.go:2887 +0xa3 net/http.(*conn).serve(0xc000148280, 0x9d0640, 0xc000168140) /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 9707 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x7f47940bcf10, 0x72, 0x0) /workdir/go/src/runtime/netpoll.go:222 +0x55 internal/poll.(*pollDesc).wait(0xc00060a198, 0x72, 0x0, 0x0, 0x91be71) /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(0xc00060a180, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0) /workdir/go/src/internal/poll/fd_unix.go:401 +0x212 net.(*netFD).accept(0xc00060a180, 0x755511, 0xc000454a5c, 0xc0003f6240) /workdir/go/src/net/fd_unix.go:172 +0x45 net.(*TCPListener).accept(0xc0000aa078, 0x0, 0xc00027fe20, 0x9d5688) /workdir/go/src/net/tcpsock_posix.go:139 +0x32 net.(*TCPListener).Accept(0xc0000aa078, 0xc00027fe70, 0x18, 0xc000320a80, 0x6fae7b) /workdir/go/src/net/tcpsock.go:261 +0x65 net/http.(*Server).Serve(0xc000326000, 0x9cfa30, 0xc0000aa078, 0x0, 0x0) /workdir/go/src/net/http/server.go:2981 +0x285 net/http/httptest.(*Server).goServe.func1(0xc000454a10) /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 FAIL net/http 900.010s FAIL go tool dist: Failed: exit status 1