windows-386-2008 at a3ffb8a1ac0edc57fff7dd8d79e3ff9129ed6708 :: 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-2008R2-V" "ComSpec=C:\\Windows\\system32\\cmd.exe" "FP_NO_HOST_CHECK=NO" "GooGetRoot=C:\\ProgramData\\GooGet" "GOROOT_BOOTSTRAP=C:\\workdir\\go1.4" "HOMEDRIVE=C:" "HOMEPATH=\\Users\\gopher" "LOCALAPPDATA=C:\\Users\\gopher\\AppData\\Local" "LOGONSERVER=\\\\SERVER-2008R2-V" "NUMBER_OF_PROCESSORS=4" "OS=Windows_NT" "PATH=C:\\Windows\\system32;C:\\Windows;C:\\Windows\\System32\\Wbem;C:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;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:\\Windows\\System32\\WindowsPowerShell\\v1.0\\;C:\\Program Files\\Google\\Compute Engine\\sysprep;C:\\godep\\gcc32\\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:\\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" "TMP=C:\\Users\\gopher\\AppData\\Local\\Temp" "USERDOMAIN=SERVER-2008R2-V" "USERDOMAIN_ROAMINGPROFILE=SERVER-2008R2-V" "USERNAME=gopher" "USERPROFILE=C:\\Users\\gopher" "windir=C:\\Windows" "windows_tracing_flags=3" "windows_tracing_logfile=C:\\BVTBin\\Tests\\installpackage\\csilogfile.log" "GO_STAGE0_NET_DELAY=36.3s" "GO_STAGE0_DL_DELAY=0s" "WORKDIR=C:\\workdir" "GO_BUILDER_NAME=windows-386-2008" "GOARCH=386" "GOHOSTARCH=386" "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/386. --- Installed Go for windows/386 in C:\workdir\go Installed commands in C:\workdir\go\bin ##### Testing packages. ok archive/tar 0.076s ok archive/zip 0.278s ok bufio 0.142s ok bytes 2.215s ok compress/bzip2 0.241s ok compress/flate 0.639s ok compress/gzip 0.340s ok compress/lzw 0.093s ok compress/zlib 0.681s ok container/heap 0.054s ok container/list 0.041s ok container/ring 0.032s ok context 1.008s ok crypto 0.033s ok crypto/aes 0.065s ok crypto/cipher 0.042s ok crypto/des 0.064s ok crypto/dsa 0.058s ok crypto/ecdsa 0.375s ok crypto/ed25519 0.437s ok crypto/elliptic 0.062s ok crypto/hmac 0.035s ok crypto/internal/subtle 0.039s ok crypto/md5 0.035s ok crypto/rand 0.076s ok crypto/rc4 0.137s ok crypto/rsa 0.188s ok crypto/sha1 0.034s ok crypto/sha256 0.062s ok crypto/sha512 0.038s ok crypto/subtle 0.207s ok crypto/tls 1.241s ok crypto/x509 1.333s ok database/sql 0.592s ok database/sql/driver 0.041s ok debug/dwarf 0.039s ok debug/elf 0.049s ok debug/gosym 0.029s ok debug/macho 0.037s ok debug/pe 5.283s ok debug/plan9obj 0.107s ok encoding/ascii85 0.080s ok encoding/asn1 0.032s ok encoding/base32 0.063s ok encoding/base64 0.050s ok encoding/binary 0.031s ok encoding/csv 0.035s ok encoding/gob 0.044s ok encoding/hex 0.043s ok encoding/json 0.099s ok encoding/pem 0.053s ok encoding/xml 0.057s ok errors 0.036s ok expvar 0.038s ok flag 0.034s ok fmt 0.134s ok go/ast 0.048s ok go/build 0.308s ok go/constant 0.104s ok go/doc 0.094s ok go/format 0.050s ok go/importer 0.154s ok go/internal/gccgoimporter 0.068s ok go/internal/gcimporter 1.883s ok go/internal/srcimporter 2.899s ok go/parser 0.050s ok go/printer 0.324s ok go/scanner 0.036s ok go/token 0.052s ok go/types 1.497s ok hash 0.042s ok hash/adler32 0.052s ok hash/crc32 0.033s ok hash/crc64 0.068s ok hash/fnv 0.033s ok hash/maphash 0.156s ok html 0.064s ok html/template 0.097s ok image 0.093s ok image/color 0.091s ok image/draw 0.095s ok image/gif 0.343s ok image/jpeg 0.370s ok image/png 0.102s ok index/suffixarray 0.309s ok internal/cpu 0.034s ok internal/fmtsort 0.068s ok internal/poll 0.222s ok internal/reflectlite 0.073s ok internal/singleflight 0.061s ok internal/syscall/windows 0.082s ok internal/syscall/windows/registry 0.042s ok internal/trace 0.146s ok internal/xcoff 0.057s ok io 0.068s ok io/ioutil 0.036s ok log 0.043s ok math 0.037s ok math/big 1.321s ok math/bits 0.038s ok math/cmplx 0.058s ok math/rand 0.570s ok mime 0.050s ok mime/multipart 0.210s ok mime/quotedprintable 0.069s ok net 9.050s ok net/http 8.023s ok net/http/cgi 0.303s ok net/http/cookiejar 0.045s ok net/http/fcgi 0.107s ok net/http/httptest 2.109s ok net/http/httptrace 0.038s ok net/http/httputil 0.086s ok net/http/internal 0.038s ok net/http/pprof 2.210s ok net/internal/socktest 0.033s ok net/mail 0.347s ok net/rpc 0.064s ok net/rpc/jsonrpc 0.047s ok net/smtp 0.067s ok net/textproto 0.105s ok net/url 0.040s ok os 2.618s ok os/exec 3.971s ok os/signal 1.898s ok os/user 0.047s ok path 0.049s ok path/filepath 0.913s ok plugin 0.036s ok reflect 0.158s ok regexp 0.170s ok regexp/syntax 0.374s ok runtime 28.644s ok runtime/debug 0.088s ok runtime/internal/atomic 0.077s ok runtime/internal/math 0.048s ok runtime/internal/sys 0.032s ok runtime/pprof 7.014s ok runtime/pprof/internal/profile 0.038s runtime: VirtualFree of 0 bytes failed with errno=487 fatal error: runtime: failed to decommit pages runtime stack: runtime.throw(0x581de7, 0x21) C:/workdir/go/src/runtime/panic.go:1106 +0x64 runtime.sysUnused(0x33ffe000, 0x2000) C:/workdir/go/src/runtime/mem_windows.go:55 +0x14b runtime.(*pageAlloc).scavengeRangeLocked(0x69d5c4, 0xcf, 0x1ff, 0x1) C:/workdir/go/src/runtime/mgcscavenge.go:491 +0x88 runtime.(*pageAlloc).scavengeOne(0x69d5c4, 0x1000, 0x698c00, 0x4395ee) C:/workdir/go/src/runtime/mgcscavenge.go:415 +0x2fa runtime.bgscavenge.func2() C:/workdir/go/src/runtime/mgcscavenge.go:260 +0xb2 runtime.systemstack(0x0) C:/workdir/go/src/runtime/asm_386.s:395 +0x53 runtime.mstart() C:/workdir/go/src/runtime/proc.go:1069 goroutine 4 [running]: runtime.systemstack_switch() C:/workdir/go/src/runtime/asm_386.s:356 fp=0x12429f7c sp=0x12429f78 pc=0x45b8c0 runtime.bgscavenge(0x124100c0) C:/workdir/go/src/runtime/mgcscavenge.go:247 +0x120 fp=0x12429fe8 sp=0x12429f7c pc=0x41dda0 runtime.goexit() C:/workdir/go/src/runtime/asm_386.s:1337 +0x1 fp=0x12429fec sp=0x12429fe8 pc=0x45d1d1 created by runtime.gcenable C:/workdir/go/src/runtime/mgc.go:215 +0x6b goroutine 1 [chan receive]: testing.(*T).Run(0x1259a1e0, 0x57f1d9, 0x18, 0x586bec, 0x301) C:/workdir/go/src/testing/testing.go:1006 +0x2cc testing.runTests.func1(0x124e6000) C:/workdir/go/src/testing/testing.go:1247 +0x5a testing.tRunner(0x124e6000, 0x124aff0c) C:/workdir/go/src/testing/testing.go:954 +0xb4 testing.runTests(0x12492110, 0x696900, 0x9, 0x9, 0x0) C:/workdir/go/src/testing/testing.go:1245 +0x22d testing.(*M).Run(0x124b8040, 0x0) C:/workdir/go/src/testing/testing.go:1162 +0x114 main.main() _testmain.go:66 +0x104 goroutine 58 [semacquire]: internal/trace.parseEvents(0x3f3, 0x12b80000, 0x1ac8, 0x1e00, 0x1248b940, 0x1e00, 0x1248b940, 0x0, 0x0, 0x41031455, ...) C:/workdir/go/src/internal/trace/parser.go:396 +0x895 internal/trace.parse(0x5ae340, 0x1248bf80, 0x0, 0x0, 0x3f0000, 0x21, 0x0, 0x408ecc, 0x6a3920, 0x0, ...) C:/workdir/go/src/internal/trace/parser.go:107 +0x6d internal/trace.Parse(0x5ae340, 0x1248bf80, 0x0, 0x0, 0x12580330, 0x0, 0x42af83, 0x125801c0, 0x42f0b3, 0x74e8183e) C:/workdir/go/src/internal/trace/parser.go:90 +0x3d runtime/trace_test.parseTrace(0x1259a1e0, 0x5ae340, 0x1248bf80, 0x18, 0x3b41, 0x4800, 0x1248b940) C:/workdir/go/src/runtime/trace/trace_test.go:142 +0x43 runtime/trace_test.TestTraceStressStartStop(0x1259a1e0) C:/workdir/go/src/runtime/trace/trace_test.go:493 +0xcd testing.tRunner(0x1259a1e0, 0x586bec) C:/workdir/go/src/testing/testing.go:954 +0xb4 created by testing.(*T).Run C:/workdir/go/src/testing/testing.go:1005 +0x2ad goroutine 40 [runnable, locked to thread]: runtime.Gosched(...) C:/workdir/go/src/runtime/proc.go:269 runtime/trace_test.TestTraceStressStartStop.func1.5(0x124108c0) C:/workdir/go/src/runtime/trace/trace_test.go:395 +0x2b created by runtime/trace_test.TestTraceStressStartStop.func1 C:/workdir/go/src/runtime/trace/trace_test.go:388 +0x160 goroutine 24 [select (no cases)]: runtime/trace_test.TestTraceSymbolize.func1() C:/workdir/go/src/runtime/trace/trace_stack_test.go:41 +0x17 created by runtime/trace_test.TestTraceSymbolize C:/workdir/go/src/runtime/trace/trace_stack_test.go:40 +0x10a goroutine 25 [chan send (nil chan)]: runtime/trace_test.TestTraceSymbolize.func2() C:/workdir/go/src/runtime/trace/trace_stack_test.go:45 +0x2b created by runtime/trace_test.TestTraceSymbolize C:/workdir/go/src/runtime/trace/trace_stack_test.go:43 +0x120 goroutine 26 [chan receive (nil chan)]: runtime/trace_test.TestTraceSymbolize.func3() C:/workdir/go/src/runtime/trace/trace_stack_test.go:49 +0x29 created by runtime/trace_test.TestTraceSymbolize C:/workdir/go/src/runtime/trace/trace_stack_test.go:47 +0x136 goroutine 62 [syscall, locked to thread]: syscall.Syscall6(0x74e83fd5, 0x5, 0x120, 0x1240ab04, 0x1, 0x12525f48, 0x0, 0x0, 0x0, 0x0, ...) C:/workdir/go/src/runtime/syscall_windows.go:201 +0xbb syscall.ReadFile(0x120, 0x1240ab04, 0x1, 0x1, 0x12525f48, 0x0, 0x0, 0x1) C:/workdir/go/src/syscall/zsyscall_windows.go:313 +0x97 syscall.Read(0x120, 0x1240ab04, 0x1, 0x1, 0x44fdc9, 0x790000, 0x43) C:/workdir/go/src/syscall/syscall_windows.go:325 +0x55 internal/poll.(*FD).Read(0x124b0c60, 0x1240ab04, 0x1, 0x1, 0x0, 0x0, 0x0) C:/workdir/go/src/internal/poll/fd_windows.go:513 +0x254 os.(*File).read(...) C:/workdir/go/src/os/file_windows.go:213 os.(*File).Read(0x12488428, 0x1240ab04, 0x1, 0x1, 0x586a0c, 0xffffffff, 0x0) C:/workdir/go/src/os/file.go:116 +0x58 runtime/trace_test.TestTraceStressStartStop.func1.4(0x12488428, 0x124108c0, 0x1240aaf0) C:/workdir/go/src/runtime/trace/trace_test.go:382 +0x3f created by runtime/trace_test.TestTraceStressStartStop.func1 C:/workdir/go/src/runtime/trace/trace_test.go:380 +0x12e goroutine 61 [chan receive]: runtime/trace_test.TestTraceStressStartStop.func1.2(0x124108c0, 0x1240aaf0) C:/workdir/go/src/runtime/trace/trace_test.go:366 +0x29 created by runtime/trace_test.TestTraceStressStartStop.func1 C:/workdir/go/src/runtime/trace/trace_test.go:365 +0xb1 goroutine 59 [runnable]: runtime/trace_test.TestTraceStressStartStop.func1(0x12410840, 0x1259a1e0) C:/workdir/go/src/runtime/trace/trace_test.go:409 +0x18b created by runtime/trace_test.TestTraceStressStartStop C:/workdir/go/src/runtime/trace/trace_test.go:356 +0x9f FAIL runtime/trace 0.816s ok sort 0.085s FAIL 2019/11/12 21:05:31 Failed: exit status 1