netbsd-amd64-9_0-n2 at 766f89b5c625f8c57492cf5645576d9e6f450cc2 :: Running /tmp/workdir/go/src/make.bash with args ["/tmp/workdir/go/src/make.bash"] and env ["PWD=/" "HOME=/" "PATH=/usr/pkg/bin:/usr/pkg/sbin:/sbin:/usr/sbin:/bin:/usr/bin:/usr/pkg/sbin:/usr/pkg/bin:/usr/X11R7/bin:/usr/local/sbin:/usr/local/bin" "_rc_original_stdout_fd=7" "GOROOT_BOOTSTRAP=/tmp/workdir/go1.4" "_rc_original_stderr_fd=8" "RC_PID= 8" "_rc_pid=2" "_rc_postprocessor_fd=9" "WORKDIR=/tmp/workdir" "GO_BUILDER_NAME=netbsd-amd64-9_0-n2" "GOBIN=" "TMPDIR=/tmp/workdir/tmp" "GOCACHE=/tmp/workdir/gocache"] in dir /tmp/workdir/go/src Building Go cmd/dist using /tmp/workdir/go1.4. (devel +2da6b33 Thu Nov 30 02:28:53 2017 +0000 netbsd/amd64) Building Go toolchain1 using /tmp/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 netbsd/amd64. --- Installed Go for netbsd/amd64 in /tmp/workdir/go Installed commands in /tmp/workdir/go/bin ##### Testing packages. ok archive/tar 0.020s ok archive/zip 0.971s ok bufio 0.126s ok bytes 0.943s ok compress/bzip2 0.100s ok compress/flate 2.702s ok compress/gzip 0.192s ok compress/lzw 0.145s ok compress/zlib 1.376s ok constraints 0.082s ok container/heap 0.006s ok container/list 0.005s ok container/ring 0.007s ok context 0.109s ok crypto 0.003s ok crypto/aes 0.026s ok crypto/cipher 0.010s ok crypto/des 0.007s ok crypto/dsa 0.005s ok crypto/ecdsa 0.006s ok crypto/ed25519 0.088s ok crypto/ed25519/internal/edwards25519 3.349s ok crypto/ed25519/internal/edwards25519/field 2.044s ok crypto/elliptic 0.028s ok crypto/elliptic/internal/fiat 0.009s [no tests to run] ok crypto/elliptic/internal/nistec 0.608s ok crypto/hmac 0.004s ok crypto/internal/subtle 0.003s ok crypto/md5 0.035s ok crypto/rand 0.013s ok crypto/rc4 0.038s ok crypto/rsa 0.093s ok crypto/sha1 0.023s ok crypto/sha256 0.022s ok crypto/sha512 0.021s ok crypto/subtle 0.006s ok crypto/tls 0.420s ok crypto/x509 0.388s ok database/sql 0.842s ok database/sql/driver 0.004s ok debug/buildinfo 0.004s ok debug/dwarf 0.013s ok debug/elf 0.022s ok debug/gosym 0.018s ok debug/macho 0.007s ok debug/pe 0.006s ok debug/plan9obj 0.016s ok embed 0.004s [no tests to run] ok embed/internal/embedtest 0.003s ok encoding/ascii85 0.005s ok encoding/asn1 0.004s ok encoding/base32 0.165s ok encoding/base64 0.004s ok encoding/binary 0.003s ok encoding/csv 0.007s ok encoding/gob 0.015s ok encoding/hex 0.004s ok encoding/json 0.297s ok encoding/pem 0.018s ok encoding/xml 0.028s ok errors 0.005s ok expvar 0.006s ok flag 0.022s ok fmt 0.084s ok go/ast 0.005s ok go/build 0.990s ok go/build/constraint 0.021s ok go/constant 0.005s ok go/doc 0.065s ok go/format 0.008s ok go/importer 0.246s ok go/internal/gccgoimporter 0.009s ok go/internal/gcimporter 7.896s ok go/internal/srcimporter 6.325s ok go/parser 0.057s ok go/printer 0.429s ok go/scanner 0.004s ok go/token 0.010s ok go/types 6.623s ok hash 0.004s ok hash/adler32 0.006s ok hash/crc32 0.005s ok hash/crc64 0.003s ok hash/fnv 0.003s ok hash/maphash 0.063s ok html 0.012s ok html/template 0.166s ok image 0.037s ok image/color 0.019s ok image/draw 0.076s ok image/gif 1.938s ok image/jpeg 0.204s ok image/png 0.095s ok index/suffixarray 0.196s ok internal/abi 0.032s ok internal/buildcfg 0.003s ok internal/cpu 0.007s ok internal/execabs 0.014s ok internal/fmtsort 0.003s ok internal/fuzz 0.004s ok internal/godebug 0.004s ok internal/intern 3.714s ok internal/itoa 0.010s ok internal/poll 0.034s ok internal/profile 0.003s ok internal/reflectlite 0.076s ok internal/singleflight 0.015s ok internal/trace 0.080s ok internal/unsafeheader 0.005s ok internal/xcoff 0.043s ok io 0.361s ok io/fs 0.009s ok io/ioutil 0.005s ok log 0.016s ok log/syslog 1.297s ok math 0.006s ok math/big 1.093s ok math/bits 0.004s ok math/cmplx 0.006s ok math/rand 0.320s ok mime 0.020s ok mime/multipart 0.136s ok mime/quotedprintable 0.020s ok net 35.466s ok net/http 8.748s ok net/http/cgi 0.056s ok net/http/cookiejar 0.007s ok net/http/fcgi 0.113s ok net/http/httptest 0.020s ok net/http/httptrace 0.005s ok net/http/httputil 0.717s ok net/http/internal 0.004s ok net/http/internal/ascii 0.006s ok net/http/pprof 5.352s ok net/internal/socktest 0.004s ok net/mail 0.009s ok net/netip 0.597s ok net/rpc 0.029s ok net/rpc/jsonrpc 0.008s ok net/smtp 0.010s ok net/textproto 0.021s ok net/url 0.009s ok os 1.072s ok os/exec 1.034s ok os/exec/internal/fdtest 0.024s ok os/signal 2.315s ok os/user 0.005s ok path 0.013s ok path/filepath 0.023s ok plugin 0.023s ok reflect 1.801s ok regexp 0.117s ok regexp/syntax 0.393s ok runtime 49.291s ok runtime/cgo 0.004s ok runtime/debug 0.155s ok runtime/internal/atomic 0.040s ok runtime/internal/math 0.006s ok runtime/internal/sys 0.003s ok runtime/metrics 0.004s ok runtime/pprof 8.656s ok runtime/trace 0.957s ok sort 0.051s ok strconv 0.457s ok strings 0.731s ok sync 1.295s ok sync/atomic 2.316s ok syscall 0.143s ok testing 3.370s ok testing/fstest 0.005s ok testing/iotest 0.009s ok testing/quick 0.029s ok text/scanner 0.003s ok text/tabwriter 0.004s ok text/template 0.057s ok text/template/parse 0.011s ok time 2.642s ok unicode 0.006s ok unicode/utf16 0.003s ok unicode/utf8 0.005s ok cmd/addr2line 2.639s ok cmd/api 18.062s ok cmd/asm/internal/asm 0.752s ok cmd/asm/internal/lex 0.004s ok cmd/compile/internal/amd64 0.004s ok cmd/compile/internal/dwarfgen 0.395s ok cmd/compile/internal/importer 10.754s ok cmd/compile/internal/ir 0.003s panic: test timed out after 3m0s goroutine 47 [running]: panic({0x514de0, 0xc0000aa850}) /tmp/workdir/go/src/runtime/panic.go:941 +0x397 fp=0xc00009bf98 sp=0xc00009bed8 pc=0x432957 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2000 +0x8e fp=0xc00009bfe0 sp=0xc00009bf98 pc=0x4bf26e runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc00009bfe8 sp=0xc00009bfe0 pc=0x462981 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:176 +0x32 goroutine 1 [chan receive]: runtime.gopark(0xc00011f9e8?, 0x1?, 0x20?, 0x68?, 0x4bb4a0?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc00011f9b0 sp=0xc00011f990 pc=0x4358d6 runtime.chanrecv(0xc000054150, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:577 +0x56c fp=0xc00011fa40 sp=0xc00011f9b0 pc=0x405f2c runtime.chanrecv1(0xc0000601e0?, 0xc0000541a8?) /tmp/workdir/go/src/runtime/chan.go:440 +0x18 fp=0xc00011fa68 sp=0xc00011fa40 pc=0x405998 testing.tRunner.func1() /tmp/workdir/go/src/testing/testing.go:1376 +0x4a5 fp=0xc00011fbd0 sp=0xc00011fa68 pc=0x4bbb05 testing.tRunner(0xc000116340, 0xc00011fcd8) /tmp/workdir/go/src/testing/testing.go:1416 +0x13b fp=0xc00011fc20 sp=0xc00011fbd0 pc=0x4bb5db testing.runTests(0xc00007e280?, {0x61aac0, 0x2, 0x2}, {0x7f7ff7ebf108?, 0x40?, 0x6242e0?}) /tmp/workdir/go/src/testing/testing.go:1808 +0x457 fp=0xc00011fd08 sp=0xc00011fc20 pc=0x4be137 testing.(*M).Run(0xc00007e280) /tmp/workdir/go/src/testing/testing.go:1690 +0x5d9 fp=0xc00011ff00 sp=0xc00011fd08 pc=0x4bcc19 main.main() _testmain.go:49 +0x1aa fp=0xc00011ff80 sp=0xc00011ff00 pc=0x50628a runtime.main() /tmp/workdir/go/src/runtime/proc.go:255 +0x227 fp=0xc00011ffe0 sp=0xc00011ff80 pc=0x435507 runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc00011ffe8 sp=0xc00011ffe0 pc=0x462981 goroutine 2 [force gc (idle)]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000034fb0 sp=0xc000034f90 pc=0x4358d6 runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:306 +0xad fp=0xc000034fe0 sp=0xc000034fb0 pc=0x43576d runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc000034fe8 sp=0xc000034fe0 pc=0x462981 created by runtime.init.6 /tmp/workdir/go/src/runtime/proc.go:294 +0x25 goroutine 3 [GC sweep wait]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000035790 sp=0xc000035770 pc=0x4358d6 runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.bgsweep(0x0?) /tmp/workdir/go/src/runtime/mgcsweep.go:278 +0x8e fp=0xc0000357c8 sp=0xc000035790 pc=0x421a4e runtime.gcenable.func1() /tmp/workdir/go/src/runtime/mgc.go:177 +0x26 fp=0xc0000357e0 sp=0xc0000357c8 pc=0x417766 runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc0000357e8 sp=0xc0000357e0 pc=0x462981 created by runtime.gcenable /tmp/workdir/go/src/runtime/mgc.go:177 +0x6b goroutine 4 [GC scavenge wait]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000035f30 sp=0xc000035f10 pc=0x4358d6 runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.bgscavenge(0x0?) /tmp/workdir/go/src/runtime/mgcscavenge.go:271 +0xec fp=0xc000035fc8 sp=0xc000035f30 pc=0x41f70c runtime.gcenable.func2() /tmp/workdir/go/src/runtime/mgc.go:178 +0x26 fp=0xc000035fe0 sp=0xc000035fc8 pc=0x417706 runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc000035fe8 sp=0xc000035fe0 pc=0x462981 created by runtime.gcenable /tmp/workdir/go/src/runtime/mgc.go:178 +0xaa goroutine 5 [finalizer wait]: runtime.gopark(0x0?, 0xc000034670?, 0x70?, 0x47?, 0x4423d1?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000034630 sp=0xc000034610 pc=0x4358d6 runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:177 +0xb3 fp=0xc0000347e0 sp=0xc000034630 pc=0x4167f3 runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc0000347e8 sp=0xc0000347e0 pc=0x462981 created by runtime.createfing /tmp/workdir/go/src/runtime/mfinal.go:157 +0x45 goroutine 7 [chan receive]: runtime.gopark(0xc000066bf0?, 0xc000066c58?, 0x85?, 0xc8?, 0xc000066c80?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000066bd8 sp=0xc000066bb8 pc=0x4358d6 runtime.chanrecv(0xc0000542a0, 0xc000066cd7, 0x1) /tmp/workdir/go/src/runtime/chan.go:577 +0x56c fp=0xc000066c68 sp=0xc000066bd8 pc=0x405f2c runtime.chanrecv1(0xc00006a3c0?, 0x1?) /tmp/workdir/go/src/runtime/chan.go:440 +0x18 fp=0xc000066c90 sp=0xc000066c68 pc=0x405998 testing.(*T).Run(0xc000116820, {0x538256?, 0xc000066d84?}, 0xc000020200) /tmp/workdir/go/src/testing/testing.go:1458 +0x37a fp=0xc000066d30 sp=0xc000066c90 pc=0x4bc39a cmd/compile/internal/logopt.TestLogOpt(0xc000116820) /tmp/workdir/go/src/cmd/compile/internal/logopt/logopt_test.go:133 +0x385 fp=0xc000066f70 sp=0xc000066d30 pc=0x5044c5 testing.tRunner(0xc000116820, 0x545328) /tmp/workdir/go/src/testing/testing.go:1410 +0x102 fp=0xc000066fc0 sp=0xc000066f70 pc=0x4bb5a2 testing.(*T).Run.func1() /tmp/workdir/go/src/testing/testing.go:1457 +0x2a fp=0xc000066fe0 sp=0xc000066fc0 pc=0x4bc44a runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc000066fe8 sp=0xc000066fe0 pc=0x462981 created by testing.(*T).Run /tmp/workdir/go/src/testing/testing.go:1457 +0x35f goroutine 11 [chan receive]: runtime.gopark(0xc000067bd0?, 0xc000067c38?, 0x85?, 0xc8?, 0xc000067c60?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000067bb8 sp=0xc000067b98 pc=0x4358d6 runtime.chanrecv(0xc0000ce2a0, 0xc000067cb7, 0x1) /tmp/workdir/go/src/runtime/chan.go:577 +0x56c fp=0xc000067c48 sp=0xc000067bb8 pc=0x405f2c runtime.chanrecv1(0xc00006a3c0?, 0x1?) /tmp/workdir/go/src/runtime/chan.go:440 +0x18 fp=0xc000067c70 sp=0xc000067c48 pc=0x405998 testing.(*T).Run(0xc000116ea0, {0x538372?, 0xc000067d64?}, 0xc0000ca3c0) /tmp/workdir/go/src/testing/testing.go:1458 +0x37a fp=0xc000067d10 sp=0xc000067c70 pc=0x4bc39a cmd/compile/internal/logopt.TestLogOpt.func3(0xc000116ea0) /tmp/workdir/go/src/cmd/compile/internal/logopt/logopt_test.go:163 +0x4cd fp=0xc000067f70 sp=0xc000067d10 pc=0x504eed testing.tRunner(0xc000116ea0, 0xc000020200) /tmp/workdir/go/src/testing/testing.go:1410 +0x102 fp=0xc000067fc0 sp=0xc000067f70 pc=0x4bb5a2 testing.(*T).Run.func1() /tmp/workdir/go/src/testing/testing.go:1457 +0x2a fp=0xc000067fe0 sp=0xc000067fc0 pc=0x4bc44a runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc000067fe8 sp=0xc000067fe0 pc=0x462981 created by testing.(*T).Run /tmp/workdir/go/src/testing/testing.go:1457 +0x35f goroutine 45 [syscall]: syscall.Syscall6(0x1c1, 0x43e5, 0xc000062b4c, 0x0, 0xc0000e6360, 0x0, 0x0) /tmp/workdir/go/src/syscall/asm_unix_amd64.s:39 +0x5 fp=0xc000062ab0 sp=0xc000062aa8 pc=0x46d445 syscall.wait4(0xc000062b50?, 0x40cbe7?, 0x90?, 0x5325a0?) /tmp/workdir/go/src/syscall/zsyscall_netbsd_amd64.go:34 +0x4e fp=0xc000062b28 sp=0xc000062ab0 pc=0x46b8ce syscall.Wait4(0x203000?, 0xc000062b84, 0x7?, 0x7?) /tmp/workdir/go/src/syscall/syscall_bsd.go:144 +0x2b fp=0xc000062b60 sp=0xc000062b28 pc=0x46b62b os.(*Process).wait(0xc0000b0660) /tmp/workdir/go/src/os/exec_unix.go:43 +0x77 fp=0xc000062bb8 sp=0xc000062b60 pc=0x481f77 os.(*Process).Wait(...) /tmp/workdir/go/src/os/exec.go:132 os/exec.(*Cmd).Wait(0xc0000d91e0) /tmp/workdir/go/src/os/exec/exec.go:507 +0x54 fp=0xc000062c30 sp=0xc000062bb8 pc=0x4c79b4 os/exec.(*Cmd).Run(0x5?) /tmp/workdir/go/src/os/exec/exec.go:341 +0x39 fp=0xc000062c50 sp=0xc000062c30 pc=0x4c6b19 os/exec.(*Cmd).CombinedOutput(0xc0000d91e0) /tmp/workdir/go/src/os/exec/exec.go:567 +0x96 fp=0xc000062c78 sp=0xc000062c50 pc=0x4c7cd6 cmd/compile/internal/logopt.testCopy(0xc000083040, {0xc00001c150, 0x25}, {0x538372, 0x4}, {0x53810f, 0x2}, {0xc00001c300, 0x2d}, {0xc00001c360, ...}) /tmp/workdir/go/src/cmd/compile/internal/logopt/logopt_test.go:255 +0x3be fp=0xc000062d38 sp=0xc000062c78 pc=0x505f7e cmd/compile/internal/logopt.TestLogOpt.func3.1(0xc000083040) /tmp/workdir/go/src/cmd/compile/internal/logopt/logopt_test.go:168 +0xb5 fp=0xc000062f70 sp=0xc000062d38 pc=0x504ff5 testing.tRunner(0xc000083040, 0xc0000ca3c0) /tmp/workdir/go/src/testing/testing.go:1410 +0x102 fp=0xc000062fc0 sp=0xc000062f70 pc=0x4bb5a2 testing.(*T).Run.func1() /tmp/workdir/go/src/testing/testing.go:1457 +0x2a fp=0xc000062fe0 sp=0xc000062fc0 pc=0x4bc44a runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc000062fe8 sp=0xc000062fe0 pc=0x462981 created by testing.(*T).Run /tmp/workdir/go/src/testing/testing.go:1457 +0x35f goroutine 46 [IO wait]: runtime.gopark(0xc000082ea0?, 0xc000022000?, 0x70?, 0x5?, 0x46d419?) /tmp/workdir/go/src/runtime/proc.go:366 +0xd6 fp=0xc000030500 sp=0xc0000304e0 pc=0x4358d6 runtime.netpollblock(0xc000160c00?, 0x200?, 0x0?) /tmp/workdir/go/src/runtime/netpoll.go:453 +0xf3 fp=0xc000030538 sp=0xc000030500 pc=0x42f193 internal/poll.runtime_pollWait(0x7f7fd10d8888, 0x72) /tmp/workdir/go/src/runtime/netpoll.go:233 +0x89 fp=0xc000030558 sp=0xc000030538 pc=0x45dde9 internal/poll.(*pollDesc).wait(0xc0000b8c00?, 0xc000160c00?, 0x1) /tmp/workdir/go/src/internal/poll/fd_poll_runtime.go:83 +0x32 fp=0xc000030580 sp=0xc000030558 pc=0x47e972 internal/poll.(*pollDesc).waitRead(...) /tmp/workdir/go/src/internal/poll/fd_poll_runtime.go:88 internal/poll.(*FD).Read(0xc0000b8c00, {0xc000160c00, 0x200, 0x200}) /tmp/workdir/go/src/internal/poll/fd_unix.go:167 +0x25a fp=0xc000030600 sp=0xc000030580 pc=0x47f0fa os.(*File).read(...) /tmp/workdir/go/src/os/file_posix.go:31 os.(*File).Read(0xc0000b6100, {0xc000160c00?, 0xc0000306a0?, 0xc0000306a0?}) /tmp/workdir/go/src/os/file.go:119 +0x5e fp=0xc000030658 sp=0xc000030600 pc=0x48243e bytes.(*Buffer).ReadFrom(0xc0000a03c0, {0x568760, 0xc0000b6100}) /tmp/workdir/go/src/bytes/buffer.go:204 +0x98 fp=0xc0000306b0 sp=0xc000030658 pc=0x4abf98 io.copyBuffer({0x568620, 0xc0000a03c0}, {0x568760, 0xc0000b6100}, {0x0, 0x0, 0x0}) /tmp/workdir/go/src/io/io.go:412 +0x14b fp=0xc000030730 sp=0xc0000306b0 pc=0x47c82b io.Copy(...) /tmp/workdir/go/src/io/io.go:385 os/exec.(*Cmd).writerDescriptor.func1() /tmp/workdir/go/src/os/exec/exec.go:311 +0x3a fp=0xc000030790 sp=0xc000030730 pc=0x4c6a9a os/exec.(*Cmd).Start.func1(0xc0000ca320?) /tmp/workdir/go/src/os/exec/exec.go:441 +0x25 fp=0xc0000307c8 sp=0xc000030790 pc=0x4c78c5 os/exec.(*Cmd).Start.func3() /tmp/workdir/go/src/os/exec/exec.go:442 +0x2a fp=0xc0000307e0 sp=0xc0000307c8 pc=0x4c786a runtime.goexit() /tmp/workdir/go/src/runtime/asm_amd64.s:1571 +0x1 fp=0xc0000307e8 sp=0xc0000307e0 pc=0x462981 created by os/exec.(*Cmd).Start /tmp/workdir/go/src/os/exec/exec.go:440 +0x715 FAIL cmd/compile/internal/logopt 180.115s FAIL go tool dist: Failed: exit status 1