windows-amd64-longtest at cab120218382c78fb4263566a38df78aa3653f72 :: 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=8.3s" "GO_STAGE0_DL_DELAY=100ms" "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.254s ok bufio 0.063s ok bytes 1.470s ok compress/bzip2 0.078s ok compress/flate 7.717s ok compress/gzip 0.115s ok compress/lzw 0.075s ok compress/zlib 0.702s ok container/heap 0.023s ok container/list 0.021s ok container/ring 0.025s ok context 0.223s ok crypto 0.023s ok crypto/aes 0.043s ok crypto/cipher 0.133s ok crypto/des 0.024s ok crypto/dsa 17.307s ok crypto/ecdsa 2.751s ok crypto/ed25519 0.073s ok crypto/elliptic 2.371s ok crypto/hmac 0.023s ok crypto/internal/subtle 0.023s ok crypto/md5 0.021s ok crypto/rand 0.166s ok crypto/rc4 0.045s ok crypto/rsa 0.360s ok crypto/sha1 0.021s ok crypto/sha256 0.024s ok crypto/sha512 0.021s ok crypto/subtle 0.027s ok crypto/tls 2.502s ok crypto/x509 3.071s ok database/sql 1.345s ok database/sql/driver 0.030s ok debug/dwarf 0.034s ok debug/elf 0.033s ok debug/gosym 1.953s ok debug/macho 0.028s ok debug/pe 4.163s ok debug/plan9obj 0.023s ok embed/internal/embedtest 0.024s ok encoding/ascii85 0.025s ok encoding/asn1 0.023s ok encoding/base32 0.036s ok encoding/base64 0.026s ok encoding/binary 0.023s ok encoding/csv 0.032s ok encoding/gob 0.040s ok encoding/hex 0.024s ok encoding/json 1.061s ok encoding/pem 0.029s ok encoding/xml 0.034s ok errors 0.026s ok expvar 0.023s ok flag 0.102s ok fmt 0.094s ok go/ast 0.027s ok go/build 0.996s ok go/constant 0.025s ok go/doc 0.062s ok go/format 0.028s ok go/importer 0.136s ok go/internal/gccgoimporter 0.044s ok go/internal/gcimporter 1.604s ok go/internal/srcimporter 4.858s ok go/parser 0.044s ok go/printer 0.278s ok go/scanner 0.026s ok go/token 0.041s ok go/types 9.766s ok hash 0.024s ok hash/adler32 0.029s ok hash/crc32 0.023s ok hash/crc64 0.023s ok hash/fnv 0.023s ok hash/maphash 123.973s ok html 0.021s ok html/template 0.304s ok image 0.190s ok image/color 0.039s ok image/draw 0.060s ok image/gif 0.243s ok image/jpeg 0.185s ok image/png 0.516s ok index/suffixarray 51.378s ok internal/cpu 0.024s ok internal/fmtsort 0.024s ok internal/poll 2.240s ok internal/profile 0.027s ok internal/reflectlite 0.057s ok internal/singleflight 0.040s ok internal/syscall/windows 0.043s ok internal/syscall/windows/registry 0.024s ok internal/trace 1.000s ok internal/unsafeheader 0.021s ok internal/xcoff 0.030s ok io 0.057s ok io/fs 0.025s ok io/ioutil 0.026s ok log 0.022s ok math 0.026s ok math/big 2.989s ok math/bits 0.026s ok math/cmplx 0.022s ok math/rand 4.082s ok mime 0.041s ok mime/multipart 0.272s ok mime/quotedprintable 0.110s ok net 55.640s panic: test timed out after 15m0s goroutine 9941 [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(0xc00015a180, 0xe7d745, 0x1b, 0xea1de0, 0x9f2701) 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, 0x11865c0, 0x218, 0x218, 0xbff60eb16c64d224, 0xd18c5aabb5, 0x118c1a0, 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 45 [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, 0xea1560) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 46 [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, 0xea15d8) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 47 [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, 0xea15d0) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 53 [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, 0xea1678) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 58 [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, 0xea1620) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 4269 [chan receive, 14 minutes]: testing.(*T).Parallel(0xc00018c600) C:/workdir/go/src/testing/testing.go:1060 +0x119 net/http_test.TestConsumingBodyOnNextConn(0xc00018c600) C:/workdir/go/src/net/http/serve_test.go:165 +0x6a testing.tRunner(0xc00018c600, 0xea1d70) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 9783 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x27d71f7e6d8, 0x72, 0xf1c500) C:/workdir/go/src/runtime/netpoll.go:222 +0x65 internal/poll.(*pollDesc).wait(0xc000127338, 0x72, 0x1132500, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x4c internal/poll.execIO(0xc000127198, 0xc0003dfbd8, 0xf0, 0xc0000cc000, 0x9c11ce) C:/workdir/go/src/internal/poll/fd_windows.go:175 +0x113 internal/poll.(*FD).acceptOne(0xc000127180, 0x504, 0xc0000cc000, 0x2, 0x2, 0xc000127198, 0x11d7ae8, 0x0, 0x9541a5, 0x27) C:/workdir/go/src/internal/poll/fd_windows.go:810 +0x9c internal/poll.(*FD).Accept(0xc000127180, 0xc0003dfd88, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, 0x0, ...) C:/workdir/go/src/internal/poll/fd_windows.go:844 +0x168 net.(*netFD).accept(0xc000127180, 0xca57b8, 0xc0004b004c, 0xc000418090) C:/workdir/go/src/net/fd_windows.go:139 +0x85 net.(*TCPListener).accept(0xc000736030, 0x0, 0xc000074278, 0xf29ec8) C:/workdir/go/src/net/tcpsock_posix.go:139 +0x39 net.(*TCPListener).Accept(0xc000736030, 0xc0003dfe70, 0x18, 0xc0002bcc00, 0xc4a358) C:/workdir/go/src/net/tcpsock.go:261 +0x6b net/http.(*Server).Serve(0xc000180000, 0xf23f30, 0xc000736030, 0x0, 0x0) C:/workdir/go/src/net/http/server.go:2981 +0x29c net/http/httptest.(*Server).goServe.func1(0xc0004b0000) 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 9782 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x27d71fd5cb0, 0x72, 0xf1c500) C:/workdir/go/src/runtime/netpoll.go:222 +0x65 internal/poll.(*pollDesc).wait(0xc00012a438, 0x72, 0x1132500, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x4c internal/poll.execIO(0xc00012a298, 0xea1160, 0x1, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:175 +0x113 internal/poll.(*FD).Read(0xc00012a280, 0xc0011e3659, 0x1, 0x1, 0x0, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:441 +0x2f6 net.(*netFD).Read(0xc00012a280, 0xc0011e3659, 0x1, 0x1, 0x4, 0x0, 0x0) C:/workdir/go/src/net/fd_posix.go:55 +0x56 net.(*conn).Read(0xc0000a0008, 0xc0011e3659, 0x1, 0x1, 0x0, 0x0, 0x0) C:/workdir/go/src/net/net.go:183 +0x98 net/http.(*readWriteCloserBody).Read(0xc000410030, 0xc0011e3659, 0x1, 0x1, 0x4, 0x0, 0x0) C:/workdir/go/src/net/http/transport.go:2371 +0x107 io.ReadAtLeast(0x27d72951be8, 0xc000410030, 0xc0011e3655, 0x5, 0x5, 0x5, 0x0, 0xa671e0, 0x10c5588) C:/workdir/go/src/io/io.go:328 +0x8e io.ReadFull(...) C:/workdir/go/src/io/io.go:347 net/http_test.TestDisableKeepAliveUpgrade(0xc00015a180) C:/workdir/go/src/net/http/serve_test.go:6499 +0x455 testing.tRunner(0xc00015a180, 0xea1de0) C:/workdir/go/src/testing/testing.go:1194 +0xef created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1239 +0x2b3 goroutine 9707 [IO wait, 14 minutes]: internal/poll.runtime_pollWait(0x27d71f128d8, 0x72, 0xf1c500) C:/workdir/go/src/runtime/netpoll.go:222 +0x65 internal/poll.(*pollDesc).wait(0xc0000881b8, 0x72, 0x1132500, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_poll_runtime.go:87 +0x4c internal/poll.execIO(0xc000088018, 0xea1160, 0x1, 0xc000088000, 0xea1188) C:/workdir/go/src/internal/poll/fd_windows.go:175 +0x113 internal/poll.(*FD).Read(0xc000088000, 0xc000590000, 0x8000, 0x8000, 0x0, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:441 +0x2f6 net.(*netFD).Read(0xc000088000, 0xc000590000, 0x8000, 0x8000, 0xc0000aa000, 0xc000590000, 0x4) C:/workdir/go/src/net/fd_posix.go:55 +0x56 net.(*conn).Read(0xc0000aa000, 0xc000590000, 0x8000, 0x8000, 0x0, 0x0, 0x0) C:/workdir/go/src/net/net.go:183 +0x98 io.copyBuffer(0xf1bb60, 0xc00023a0b0, 0x27d71e94008, 0xc0000aa000, 0xc000590000, 0x8000, 0x8000, 0x9f274d, 0x5ff7bfde, 0xc0004b004c) C:/workdir/go/src/io/io.go:423 +0x12c io.Copy(...) C:/workdir/go/src/io/io.go:382 net.genericReadFrom(0xf1b0a0, 0xc0000aa000, 0x27d71e94008, 0xc0000aa000, 0x0, 0x0, 0xdfd300) C:/workdir/go/src/net/net.go:655 +0xa5 net.(*TCPConn).readFrom(0xc0000aa000, 0x27d71e94008, 0xc0000aa000, 0x9c4507, 0xc000555a00, 0x95c611) C:/workdir/go/src/net/tcpsock_posix.go:54 +0xaa net.(*TCPConn).ReadFrom(0xc0000aa000, 0x27d71e94008, 0xc0000aa000, 0x27d723e4988, 0xc0000aa000, 0xc0000aa001) C:/workdir/go/src/net/tcpsock.go:103 +0x54 io.copyBuffer(0xf1b0a0, 0xc0000aa000, 0x27d71e94008, 0xc0000aa000, 0x0, 0x0, 0x0, 0x0, 0xc000088000, 0xea1178) C:/workdir/go/src/io/io.go:409 +0x357 io.Copy(...) C:/workdir/go/src/io/io.go:382 net/http_test.TestDisableKeepAliveUpgrade.func1(0xf24110, 0xc000320000, 0xc000173600) C:/workdir/go/src/net/http/serve_test.go:6469 +0x365 net/http.HandlerFunc.ServeHTTP(0xea1dd8, 0xf24110, 0xc000320000, 0xc000173600) C:/workdir/go/src/net/http/server.go:2069 +0x4b net/http.serverHandler.ServeHTTP(0xc000180000, 0xf24110, 0xc000320000, 0xc000173600) C:/workdir/go/src/net/http/server.go:2887 +0xaa net/http.(*conn).serve(0xc000100000, 0xf24a80, 0xc000186140) 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 FAIL net/http 900.036s FAIL go tool dist: Failed: exit status 1