windows-amd64-longtest at d92f8add32f79efe7e46af55172d4c703a778938 :: Running C:\workdir\go\src\make.bat with args ["C:\\workdir\\go\\src\\make.bat"] and env ["GOBUILDEXIT=1" "=C:=C:\\golang" "ALLUSERSPROFILE=C:\\ProgramData" "APPDATA=C:\\Users\\gopher\\AppData\\Roaming" "CommonProgramFiles=C:\\Program Files\\Common Files" "CommonProgramFiles(x86)=C:\\Program Files (x86)\\Common Files" "CommonProgramW6432=C:\\Program Files\\Common Files" "COMPUTERNAME=SERVER-2016-V7-" "ComSpec=C:\\Windows\\system32\\cmd.exe" "GooGetRoot=C:\\ProgramData\\GooGet" "GOROOT_BOOTSTRAP=C:\\workdir\\go1.4" "HOMEDRIVE=C:" "HOMEPATH=\\Users\\gopher" "LOCALAPPDATA=C:\\Users\\gopher\\AppData\\Local" "LOGONSERVER=\\\\SERVER-2016-V7-" "NUMBER_OF_PROCESSORS=16" "OS=Windows_NT" "PATH=C:\\Windows\\system32;C:\\Windows;C:\\Windows\\System32\\Wbem;C:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\ProgramData\\GooGet;C:\\Program Files\\Google\\Compute Engine\\metadata_scripts;C:\\Program Files (x86)\\Google\\Cloud SDK\\google-cloud-sdk\\bin;C:\\Program Files\\Google\\Compute Engine\\sysprep;C:\\Users\\gopher\\AppData\\Local\\Microsoft\\WindowsApps;;C:\\godep\\gcc64\\bin" "PATHEXT=.COM;.EXE;.BAT;.CMD;.VBS;.VBE;.JS;.JSE;.WSF;.WSH;.MSC" "PROCESSOR_ARCHITECTURE=AMD64" "PROCESSOR_IDENTIFIER=Intel64 Family 6 Model 63 Stepping 0, GenuineIntel" "PROCESSOR_LEVEL=6" "PROCESSOR_REVISION=3f00" "ProgramData=C:\\ProgramData" "ProgramFiles=C:\\Program Files" "ProgramFiles(x86)=C:\\Program Files (x86)" "ProgramW6432=C:\\Program Files" "PROMPT=$P$G" "PSModulePath=C:\\Program Files (x86)\\WindowsPowerShell\\Modules;C:\\Windows\\system32\\WindowsPowerShell\\v1.0\\Modules;C:\\Program Files (x86)\\Google\\Cloud SDK\\google-cloud-sdk\\platform\\PowerShell" "PUBLIC=C:\\Users\\Public" "SESSIONNAME=Console" "SystemDrive=C:" "SystemRoot=C:\\Windows" "TEMP=C:\\Users\\gopher\\AppData\\Local\\Temp\\1" "TMP=C:\\Users\\gopher\\AppData\\Local\\Temp\\1" "USERDOMAIN=SERVER-2016-V7-" "USERDOMAIN_ROAMINGPROFILE=SERVER-2016-V7-" "USERNAME=gopher" "USERPROFILE=C:\\Users\\gopher" "windir=C:\\Windows" "GO_STAGE0_NET_DELAY=10.3s" "GO_STAGE0_DL_DELAY=300ms" "WORKDIR=C:\\workdir" "GO_BUILDER_NAME=windows-amd64-longtest" "GO_TEST_SHORT=0" "GO_TEST_TIMEOUT_SCALE=5" "GOBIN="] in dir C:\workdir\go\src Building Go cmd/dist using C:\workdir\go1.4 Building Go toolchain1 using C:\workdir\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 windows/amd64. --- Installed Go for windows/amd64 in C:\workdir\go Installed commands in C:\workdir\go\bin ##### Testing packages. ok archive/tar 0.042s ok archive/zip 25.439s ok bufio 0.063s ok bytes 1.472s ok compress/bzip2 0.076s ok compress/flate 7.781s ok compress/gzip 0.118s ok compress/lzw 0.074s ok compress/zlib 0.705s ok container/heap 0.023s ok container/list 0.021s ok container/ring 0.024s ok context 0.222s ok crypto 0.022s ok crypto/aes 0.045s ok crypto/cipher 0.173s ok crypto/des 0.024s ok crypto/dsa 11.814s ok crypto/ecdsa 2.776s ok crypto/ed25519 0.075s ok crypto/elliptic 2.377s ok crypto/hmac 0.023s ok crypto/internal/subtle 0.023s ok crypto/md5 0.023s ok crypto/rand 0.159s ok crypto/rc4 0.048s ok crypto/rsa 0.397s ok crypto/sha1 0.025s ok crypto/sha256 0.024s ok crypto/sha512 0.022s ok crypto/subtle 0.023s ok crypto/tls 2.545s ok crypto/x509 2.932s ok database/sql 1.347s ok database/sql/driver 0.030s ok debug/dwarf 0.038s ok debug/elf 0.032s ok debug/gosym 1.971s ok debug/macho 0.028s ok debug/pe 4.217s ok debug/plan9obj 0.024s ok embed/internal/embedtest 0.024s ok encoding/ascii85 0.025s ok encoding/asn1 0.023s ok encoding/base32 0.034s ok encoding/base64 0.028s ok encoding/binary 0.023s ok encoding/csv 0.028s ok encoding/gob 0.036s ok encoding/hex 0.025s ok encoding/json 1.095s ok encoding/pem 0.030s ok encoding/xml 0.033s ok errors 0.023s ok expvar 0.024s ok flag 0.100s ok fmt 0.098s ok go/ast 0.025s ok go/build 0.993s ok go/constant 0.028s ok go/doc 0.060s ok go/format 0.029s ok go/importer 0.130s ok go/internal/gccgoimporter 0.044s ok go/internal/gcimporter 1.649s ok go/internal/srcimporter 4.764s ok go/parser 0.042s ok go/printer 0.274s ok go/scanner 0.026s ok go/token 0.038s ok go/types 10.257s ok hash 0.022s ok hash/adler32 0.027s ok hash/crc32 0.024s ok hash/crc64 0.022s ok hash/fnv 0.021s ok hash/maphash 125.798s ok html 0.021s ok html/template 0.304s ok image 0.190s ok image/color 0.041s ok image/draw 0.059s ok image/gif 0.241s ok image/jpeg 0.183s ok image/png 0.511s ok index/suffixarray 51.237s ok internal/cpu 0.024s ok internal/fmtsort 0.023s ok internal/poll 2.333s ok internal/profile 0.024s ok internal/reflectlite 0.054s ok internal/singleflight 0.041s ok internal/syscall/windows 0.043s ok internal/syscall/windows/registry 0.024s ok internal/trace 1.004s ok internal/unsafeheader 0.023s ok internal/xcoff 0.033s ok io 0.057s ok io/fs 0.022s ok io/ioutil 0.030s ok log 0.022s ok math 0.025s ok math/big 2.979s ok math/bits 0.023s ok math/cmplx 0.022s ok math/rand 4.097s ok mime 0.038s ok mime/multipart 0.274s ok mime/quotedprintable 0.105s ok net 55.668s panic: test timed out after 15m0s goroutine 9844 [running]: testing.(*M).startAlarm.func1() C:/workdir/go/src/testing/testing.go:1701 +0xe6 created by time.goFunc C:/workdir/go/src/time/sleep.go:169 +0x4b goroutine 1 [chan receive, 14 minutes]: testing.(*T).Run(0xc000515200, 0x16dd745, 0x1b, 0x1701de0, 0x1252701) C:/workdir/go/src/testing/testing.go:1240 +0x2da testing.runTests.func1(0xc000056f00) C:/workdir/go/src/testing/testing.go:1512 +0x7f testing.tRunner(0xc000056f00, 0xc000125d68) C:/workdir/go/src/testing/testing.go:1194 +0xef testing.runTests(0xc0000040d8, 0x19e65c0, 0x218, 0x218, 0xbff60eb1b1d05f08, 0xd18c78ce8d, 0x19ec1a0, 0x20) C:/workdir/go/src/testing/testing.go:1510 +0x310 testing.(*M).Run(0xc00002c100, 0x0) C:/workdir/go/src/testing/testing.go:1418 +0x1f5 net/http_test.TestMain(0xc00002c100) C:/workdir/go/src/net/http/main_test.go:23 +0x32 main.main() _testmain.go:1183 +0x166 goroutine 30 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000195680) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestCmdGoNoHTTPServer(0xc000195680) C:/workdir/go/src/net/http/http_test.go:81 +0x52 testing.tRunner(0xc000195680, 0x1701560) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 31 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000195800) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestOmitHTTP2(0xc000195800) C:/workdir/go/src/net/http/http_test.go:115 +0x77 testing.tRunner(0xc000195800, 0x17015d8) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 32 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000195980) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestOmitHTTP2Vet(0xc000195980) C:/workdir/go/src/net/http/http_test.go:127 +0x47 testing.tRunner(0xc000195980, 0x17015d0) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 38 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000210600) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestRequestWriteTransport(0xc000210600) C:/workdir/go/src/net/http/requestwrite_test.go:668 +0x52 testing.tRunner(0xc000210600, 0x1701678) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 43 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc000210f00) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http.TestReadResponseCloseInMiddle(0xc000210f00) C:/workdir/go/src/net/http/response_test.go:647 +0x52 testing.tRunner(0xc000210f00, 0x1701620) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 4100 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc0000e7800) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http_test.TestConsumingBodyOnNextConn(0xc0000e7800) C:/workdir/go/src/net/http/serve_test.go:165 +0x6a testing.tRunner(0xc0000e7800, 0x1701d70) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 9712 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x289eb1a5290, 0x72, 0x177c500) C:/workdir/go/src/runtime/netpoll.go:222 +0x65 internal/poll.(*pollDesc).wait(0xc00021ae38, 0x72, 0x1992500, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x4c internal/poll.execIO(0xc00021ac98, 0xc00029fbd8, 0xf0, 0xc000104000, 0x12211ce) C:/workdir/go/src/internal/poll/fd_windows.go:175 +0x113 internal/poll.(*FD).acceptOne(0xc00021ac80, 0x450, 0xc000104000, 0x2, 0x2, 0xc00021ac98, 0x8, 0x0, 0x11b41a5, 0xc00029fe08) C:/workdir/go/src/internal/poll/fd_windows.go:810 +0x9c internal/poll.(*FD).Accept(0xc00021ac80, 0xc00029fd88, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) C:/workdir/go/src/internal/poll/fd_windows.go:844 +0x168 net.(*netFD).accept(0xc00021ac80, 0x15057b8, 0xc0005178cc, 0xc00094a870) C:/workdir/go/src/net/fd_windows.go:139 +0x85 net.(*TCPListener).accept(0xc000274078, 0x0, 0xc000256368, 0x1789ec8) C:/workdir/go/src/net/tcpsock_posix.go:139 +0x39 net.(*TCPListener).Accept(0xc000274078, 0xc00029fe70, 0x18, 0xc000252780, 0x14aa358) C:/workdir/go/src/net/tcpsock.go:261 +0x6b net/http.(*Server).Serve(0xc0001200e0, 0x1783f30, 0xc000274078, 0x0, 0x0) C:/workdir/go/src/net/http/server.go:2981 +0x29c net/http/httptest.(*Server).goServe.func1(0xc000517880) C:/workdir/go/src/net/http/httptest/server.go:308 +0x75 created by net/http/httptest.(*Server).goServe C:/workdir/go/src/net/http/httptest/server.go:306 +0x67 goroutine 9828 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x289eb081270, 0x72, 0x177c500) C:/workdir/go/src/runtime/netpoll.go:222 +0x65 internal/poll.(*pollDesc).wait(0xc000a76938, 0x72, 0x1992500, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x4c internal/poll.execIO(0xc000a76798, 0x1701160, 0x1, 0xc000a76780, 0x1701188) C:/workdir/go/src/internal/poll/fd_windows.go:175 +0x113 internal/poll.(*FD).Read(0xc000a76780, 0xc0000be000, 0x8000, 0x8000, 0x0, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:441 +0x2f6 net.(*netFD).Read(0xc000a76780, 0xc0000be000, 0x8000, 0x8000, 0xc0003ac028, 0xc0000be000, 0x4) C:/workdir/go/src/net/fd_posix.go:55 +0x56 net.(*conn).Read(0xc0003ac028, 0xc0000be000, 0x8000, 0x8000, 0x0, 0x0, 0x0) C:/workdir/go/src/net/net.go:183 +0x98 io.copyBuffer(0x177bb60, 0xc00059c020, 0x289eacd0008, 0xc0003ac028, 0xc0000be000, 0x8000, 0x8000, 0x125274d, 0x5ff7bfdf, 0xc0005178cc) C:/workdir/go/src/io/io.go:423 +0x12c io.Copy(...) C:/workdir/go/src/io/io.go:382 net.genericReadFrom(0x177b0a0, 0xc0003ac028, 0x289eacd0008, 0xc0003ac028, 0x0, 0x0, 0x165d300) C:/workdir/go/src/net/net.go:655 +0xa5 net.(*TCPConn).readFrom(0xc0003ac028, 0x289eacd0008, 0xc0003ac028, 0xc0007559f0, 0xc000755a00, 0x11bc611) C:/workdir/go/src/net/tcpsock_posix.go:54 +0xaa net.(*TCPConn).ReadFrom(0xc0003ac028, 0x289eacd0008, 0xc0003ac028, 0x289eb5701e8, 0xc0003ac028, 0xc0003ac001) C:/workdir/go/src/net/tcpsock.go:103 +0x54 io.copyBuffer(0x177b0a0, 0xc0003ac028, 0x289eacd0008, 0xc0003ac028, 0x0, 0x0, 0x0, 0x0, 0xc000a76780, 0x1701178) C:/workdir/go/src/io/io.go:409 +0x357 io.Copy(...) C:/workdir/go/src/io/io.go:382 net/http_test.TestDisableKeepAliveUpgrade.func1(0x1784110, 0xc000394000, 0xc00039a100) C:/workdir/go/src/net/http/serve_test.go:6469 +0x365 net/http.HandlerFunc.ServeHTTP(0x1701dd8, 0x1784110, 0xc000394000, 0xc00039a100) C:/workdir/go/src/net/http/server.go:2069 +0x4b net/http.serverHandler.ServeHTTP(0xc0001200e0, 0x1784110, 0xc000394000, 0xc00039a100) C:/workdir/go/src/net/http/server.go:2887 +0xaa net/http.(*conn).serve(0xc0001003c0, 0x1784a80, 0xc0003ba0c0) C:/workdir/go/src/net/http/server.go:1952 +0x8cd created by net/http.(*Server).Serve C:/workdir/go/src/net/http/server.go:3013 +0x3b8 goroutine 9711 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x289eb1a4d20, 0x72, 0x177c500) C:/workdir/go/src/runtime/netpoll.go:222 +0x65 internal/poll.(*pollDesc).wait(0xc0002fe6b8, 0x72, 0x1992500, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x4c internal/poll.execIO(0xc0002fe518, 0x1701160, 0x1, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:175 +0x113 internal/poll.(*FD).Read(0xc0002fe500, 0xc0009d8024, 0x1, 0x1, 0x0, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:441 +0x2f6 net.(*netFD).Read(0xc0002fe500, 0xc0009d8024, 0x1, 0x1, 0x4, 0x0, 0x0) C:/workdir/go/src/net/fd_posix.go:55 +0x56 net.(*conn).Read(0xc000840018, 0xc0009d8024, 0x1, 0x1, 0x0, 0x0, 0x0) C:/workdir/go/src/net/net.go:183 +0x98 net/http.(*readWriteCloserBody).Read(0xc000254018, 0xc0009d8024, 0x1, 0x1, 0x4, 0x0, 0x0) C:/workdir/go/src/net/http/transport.go:2371 +0x107 io.ReadAtLeast(0x289eac7af08, 0xc000254018, 0xc0009d8020, 0x5, 0x5, 0x5, 0x0, 0x12c71e0, 0x1925588) C:/workdir/go/src/io/io.go:328 +0x8e io.ReadFull(...) C:/workdir/go/src/io/io.go:347 net/http_test.TestDisableKeepAliveUpgrade(0xc000515200) C:/workdir/go/src/net/http/serve_test.go:6499 +0x455 testing.tRunner(0xc000515200, 0x1701de0) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 FAIL net/http 900.035s FAIL go tool dist: Failed: exit status 1