openbsd-386-72 at 64eed8ef1d7e9ce832f2d207c6c37484c36d80a6 :: Running /tmp/workdir/go/src/make.bash with args ["/tmp/workdir/go/src/make.bash" "-force"] and env ["HOME=/" "INRC=1" "_=/bin/sh" "WORKDIR=/tmp/workdir" "GO_BUILDER_NAME=openbsd-386-72" "GOBIN=" "GOROOT_BOOTSTRAP=" "TMPDIR=/tmp/workdir/tmp" "GOCACHE=/tmp/workdir/gocache" "GOPLSCACHE=/tmp/workdir/goplscache" "PATH=/tmp/workdir/go1.4/go/bin:/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/bin" "PWD=/tmp/workdir/go/src"] in dir /tmp/workdir/go/src WARNING: //go1.4/bin/go does not exist, found /tmp/workdir/go1.4/go/bin/go from env WARNING: set /tmp/workdir/go1.4/go as GOROOT_BOOTSTRAP Building Go cmd/dist using /tmp/workdir/go1.4/go. (go1.22.6 openbsd/386) Building Go toolchain1 using /tmp/workdir/go1.4/go. 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 openbsd/386. --- Installed Go for openbsd/386 in /tmp/workdir/go Installed commands in /tmp/workdir/go/bin *** You need to add /tmp/workdir/go/bin to your PATH. ##### Test execution environment. # GOARCH: 386 # CPU: Intel(R) Xeon(R) CPU @ 2.80GHz # GOOS: openbsd # OS Version: OpenBSD 7.2 OpenBSD 7.2 (GENERIC) #5: Tue Jul 25 16:20:24 CEST 2023 root@syspatch-72-i386.openbsd.org:/usr/src/sys/arch/i386/compile/GENERIC i386 ##### Testing packages. ok archive/tar 0.213s ok archive/zip 0.475s ok bufio 0.084s ok bytes 0.409s ok cmp 0.016s ok compress/bzip2 0.094s ok compress/flate 0.861s ok compress/gzip 1.890s ok compress/lzw 0.108s ok compress/zlib 0.584s ok container/heap 0.023s ok container/list 0.016s ok container/ring 0.017s ok context 0.304s ok crypto 1.254s ok crypto/aes 0.020s ok crypto/cipher 8.796s ok crypto/des 0.032s ok crypto/dsa 0.019s ok crypto/ecdh 0.331s ok crypto/ecdsa 0.192s ok crypto/ed25519 0.346s ok crypto/elliptic 0.058s ? crypto/fips140 [no test files] ok crypto/hkdf 0.017s ok crypto/hmac 0.032s ok crypto/internal/boring 0.015s ? crypto/internal/boring/bbig [no test files] ok crypto/internal/boring/bcache 0.195s ? crypto/internal/boring/sig [no test files] ? crypto/internal/cryptotest [no test files] ? crypto/internal/entropy [no test files] ? crypto/internal/fips140 [no test files] ok crypto/internal/fips140/aes 0.037s ok crypto/internal/fips140/aes/gcm 0.015s [no tests to run] ? crypto/internal/fips140/alias [no test files] ok crypto/internal/fips140/bigmod 0.080s ? crypto/internal/fips140/check [no test files] ? crypto/internal/fips140/check/checktest [no test files] ok crypto/internal/fips140/drbg 0.015s [no tests to run] ok crypto/internal/fips140/ecdh 0.015s ok crypto/internal/fips140/ecdsa 0.169s ? crypto/internal/fips140/ed25519 [no test files] ok crypto/internal/fips140/edwards25519 0.538s ok crypto/internal/fips140/edwards25519/field 0.085s ? crypto/internal/fips140/hkdf [no test files] ? crypto/internal/fips140/hmac [no test files] ok crypto/internal/fips140/mlkem 0.233s ok crypto/internal/fips140/nistec 0.162s ok crypto/internal/fips140/nistec/fiat 0.015s [no tests to run] ? crypto/internal/fips140/pbkdf2 [no test files] ok crypto/internal/fips140/rsa 0.342s ? crypto/internal/fips140/sha256 [no test files] ? crypto/internal/fips140/sha3 [no test files] ? crypto/internal/fips140/sha512 [no test files] ? crypto/internal/fips140/ssh [no test files] ? crypto/internal/fips140/subtle [no test files] ? crypto/internal/fips140/tls12 [no test files] ? crypto/internal/fips140/tls13 [no test files] ok crypto/internal/fips140deps 0.230s ? crypto/internal/fips140deps/byteorder [no test files] ? crypto/internal/fips140deps/cpu [no test files] ? crypto/internal/fips140deps/godebug [no test files] ? crypto/internal/fips140hash [no test files] ? crypto/internal/fips140only [no test files] ok crypto/internal/fips140test 2.593s ok crypto/internal/hpke 0.024s ? crypto/internal/impl [no test files] ? crypto/internal/randutil [no test files] ok crypto/internal/sysrand 0.403s ? crypto/internal/sysrand/internal/seccomp [no test files] ok crypto/md5 0.020s ok crypto/mlkem 0.111s ok crypto/pbkdf2 0.036s ok crypto/rand 0.791s ok crypto/rc4 0.069s ok crypto/rsa 1.375s ok crypto/sha1 0.019s ok crypto/sha256 0.020s ok crypto/sha3 4.341s ok crypto/sha512 0.024s ok crypto/subtle 0.203s ok crypto/tls 11.318s ? crypto/tls/internal/fips140tls [no test files] ok crypto/x509 2.680s ? crypto/x509/pkix [no test files] ok database/sql 0.787s ok database/sql/driver 0.016s ok debug/buildinfo 0.092s ok debug/dwarf 0.061s ok debug/elf 0.211s ok debug/gosym 0.061s ok debug/macho 0.027s ok debug/pe 0.070s ok debug/plan9obj 0.017s ok embed 0.019s [no tests to run] ok embed/internal/embedtest 0.017s ? encoding [no test files] ok encoding/ascii85 0.017s ok encoding/asn1 0.020s ok encoding/base32 0.039s ok encoding/base64 0.020s ok encoding/binary 0.026s ok encoding/csv 0.028s ok encoding/gob 4.466s ok encoding/hex 0.019s ok encoding/json 0.467s ok encoding/pem 1.102s ok encoding/xml 0.098s ok errors 0.019s ok expvar 0.020s ok flag 0.072s ok fmt 0.107s ok go/ast 0.022s ok go/ast/internal/tests 0.020s ok go/build 6.100s ok go/build/constraint 0.078s ok go/constant 0.020s ok go/doc 0.112s ok go/doc/comment 1.598s ok go/format 0.022s ok go/importer 0.653s ok go/internal/gccgoimporter 0.037s ok go/internal/gcimporter 4.793s ok go/internal/srcimporter 18.195s ok go/parser 0.508s ok go/printer 0.216s ok go/scanner 0.018s ok go/token 0.031s ok go/types 27.214s ok go/version 0.016s ok hash 0.018s ok hash/adler32 0.022s ok hash/crc32 0.021s ok hash/crc64 0.017s ok hash/fnv 0.016s ok hash/maphash 2.282s ok html 0.018s ok html/template 0.099s ok image 0.092s ok image/color 0.030s ? image/color/palette [no test files] ok image/draw 0.100s ok image/gif 0.227s ? image/internal/imageutil [no test files] ok image/jpeg 0.226s ok image/png 0.337s ok index/suffixarray 0.222s ok internal/abi 0.170s ? internal/asan [no test files] ? internal/bisect [no test files] ok internal/buildcfg 0.016s ? internal/bytealg [no test files] ? internal/byteorder [no test files] ? internal/cfg [no test files] ok internal/chacha8rand 0.016s ok internal/copyright 0.355s ? internal/coverage [no test files] ? internal/coverage/calloc [no test files] ok internal/coverage/cfile 1.818s ok internal/coverage/cformat 0.016s ok internal/coverage/cmerge 0.015s ? internal/coverage/decodecounter [no test files] ? internal/coverage/decodemeta [no test files] ? internal/coverage/encodecounter [no test files] ? internal/coverage/encodemeta [no test files] ok internal/coverage/pods 0.019s ? internal/coverage/rtcov [no test files] ok internal/coverage/slicereader 0.016s ok internal/coverage/slicewriter 0.015s ? internal/coverage/stringtab [no test files] ok internal/coverage/test 0.024s ? internal/coverage/uleb128 [no test files] ok internal/cpu 0.037s ok internal/dag 0.016s ok internal/diff 0.022s ? internal/exportdata [no test files] ? internal/filepathlite [no test files] ok internal/fmtsort 0.016s ok internal/fuzz 0.023s ? internal/goarch [no test files] ok internal/godebug 1.207s ok internal/godebugs 9.013s ? internal/goexperiment [no test files] ? internal/goos [no test files] ? internal/goroot [no test files] ok internal/gover 0.016s ? internal/goversion [no test files] ok internal/itoa 0.015s ? internal/lazyregexp [no test files] ? internal/lazytemplate [no test files] ? internal/msan [no test files] ? internal/nettrace [no test files] ? internal/obscuretestdata [no test files] ? internal/oserror [no test files] ok internal/pkgbits 0.016s ok internal/platform 1.919s ok internal/poll 0.069s ok internal/profile 0.016s ? internal/profilerecord [no test files] ? internal/race [no test files] ok internal/reflectlite 0.018s ok internal/routebsd 0.017s ok internal/runtime/atomic 0.053s ? internal/runtime/exithook [no test files] ok internal/runtime/maps 0.022s ok internal/runtime/math 0.015s ok internal/runtime/sys 0.016s ok internal/saferio 0.109s ok internal/singleflight 0.132s ? internal/stringslite [no test files] ok internal/sync 1.144s ok internal/synctest 1.372s ? internal/syscall/execenv [no test files] ? internal/syscall/unix [no test files] ok internal/sysinfo 0.016s ? internal/syslist [no test files] ok internal/testenv 0.473s ? internal/testlog [no test files] ? internal/testpty [no test files] --- FAIL: TestTraceStressStartStop (75.31s) --- FAIL: TestTraceStressStartStop/Default (75.31s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go trace_test.go:610: signal: killed --- FAIL: TestTraceWaitOnPipe (1.50s) --- FAIL: TestTraceWaitOnPipe/Default (1.50s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go trace_test.go:608: stderr: SIGQUIT: quit PC=0x182273eb m=3 sigcode=0 goroutine 0 gp=0x6d808b48 m=3 mp=0x6d843008 [idle]: runtime.kevent(0x3e, 0x0, 0x0, 0x40f9d690, 0x40, 0x0) runtime/sys_openbsd2.go:219 +0x21 fp=0x40f9d63c sp=0x40f9d62c pc=0x80a86d1 runtime.netpoll(0xffffffffffffffff) runtime/netpoll_kqueue.go:110 +0xe3 fp=0x40f9dc98 sp=0x40f9d63c pc=0x8085223 runtime.findRunnable() runtime/proc.go:3602 +0xa00 fp=0x40f9dd64 sp=0x40f9dc98 pc=0x8090740 runtime.schedule() runtime/proc.go:4017 +0xb1 fp=0x40f9dd80 sp=0x40f9dd64 pc=0x8091f01 runtime.park_m(0x6d8085a8) runtime/proc.go:4141 +0x227 fp=0x40f9ddac sp=0x40f9dd80 pc=0x8092377 runtime.mcall(0x40000) runtime/asm_386.s:329 +0x40 fp=0x40f9ddb4 sp=0x40f9ddac pc=0x80c40d0 goroutine 1 gp=0x6d808128 m=nil [sync.WaitGroup.Wait]: runtime.gopark(0x87b8070, 0x8c31d60, 0x18, 0x5, 0x4) runtime/proc.go:435 +0xfa fp=0x6d8cfae0 sp=0x6d8cfacc pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:441 runtime.semacquire1(0x6da388c8, 0x0, 0x1, 0x0, 0x18) runtime/sema.go:188 +0x27b fp=0x6d8cfb10 sp=0x6d8cfae0 pc=0x809dd3b sync.runtime_SemacquireWaitGroup(0x6da388c8) runtime/sema.go:110 +0x35 fp=0x6d8cfb28 sp=0x6d8cfb10 pc=0x80c0c55 sync.(*WaitGroup).Wait(0x6da388c0) sync/waitgroup.go:118 +0x5f fp=0x6d8cfb44 sp=0x6d8cfb28 pc=0x80cf9ff cmd/go/internal/work.(*Builder).Do(0x6d872320, {0x887ea70, 0x8c37420}, 0x6daff048) cmd/go/internal/work/exec.go:234 +0x419 fp=0x6d8cfbdc sp=0x6d8cfb44 pc=0x8536ed9 cmd/go/internal/run.runRun({0x887ea70, 0x8c37420}, 0x8c216e0, {0x6d81c118, 0x1, 0x1}) cmd/go/internal/run/run.go:172 +0x80f fp=0x6d8cfc90 sp=0x6d8cfbdc pc=0x858b0ef main.invoke(0x8c216e0, {0x6d81c110, 0x2, 0x2}) cmd/go/main.go:341 +0x8b7 fp=0x6d8cfe64 sp=0x6d8cfc90 pc=0x85d8d17 main.main() cmd/go/main.go:220 +0x1031 fp=0x6d8cffac sp=0x6d8cfe64 pc=0x85d7d81 runtime.main() runtime/proc.go:283 +0x288 fp=0x6d8cfff0 sp=0x6d8cffac pc=0x808a878 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d8cfff4 sp=0x6d8cfff0 pc=0x80c5461 goroutine 2 gp=0x6d808488 m=nil [force gc (idle)]: runtime.gopark(0x87b8070, 0x8c28878, 0x11, 0xa, 0x1) runtime/proc.go:435 +0xfa fp=0x6d83e7dc sp=0x6d83e7c8 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:441 runtime.forcegchelper() runtime/proc.go:348 +0xc7 fp=0x6d83e7f0 sp=0x6d83e7dc pc=0x808abd7 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d83e7f4 sp=0x6d83e7f0 pc=0x80c5461 created by runtime.init.6 in goroutine 1 runtime/proc.go:336 +0x1d goroutine 3 gp=0x6d8085a8 m=nil [GC sweep wait]: runtime.gopark(0x87b8070, 0x8c28c60, 0xc, 0x9, 0x1) runtime/proc.go:435 +0xfa fp=0x6d850fcc sp=0x6d850fb8 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:441 runtime.bgsweep(0x6d824040) runtime/mgcsweep.go:316 +0xec fp=0x6d850fe8 sp=0x6d850fcc pc=0x8076b7c runtime.gcenable.gowrap1() runtime/mgc.go:204 +0x21 fp=0x6d850ff0 sp=0x6d850fe8 pc=0x8068361 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d850ff4 sp=0x6d850ff0 pc=0x80c5461 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0x71 goroutine 4 gp=0x6d8086c8 m=nil [GC scavenge wait]: runtime.gopark(0x87b8070, 0x8c299c0, 0xd, 0xa, 0x2) runtime/proc.go:435 +0xfa fp=0x6d83f7b8 sp=0x6d83f7a4 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:441 runtime.(*scavengerState).park(0x8c299c0) runtime/mgcscavenge.go:425 +0x54 fp=0x6d83f7cc sp=0x6d83f7b8 pc=0x80741d4 runtime.bgscavenge(0x6d824040) runtime/mgcscavenge.go:658 +0x69 fp=0x6d83f7e8 sp=0x6d83f7cc pc=0x8074829 runtime.gcenable.gowrap2() runtime/mgc.go:205 +0x21 fp=0x6d83f7f0 sp=0x6d83f7e8 pc=0x8068321 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d83f7f4 sp=0x6d83f7f0 pc=0x80c5461 created by runtime.gcenable in goroutine 1 runtime/mgc.go:205 +0xb1 goroutine 5 gp=0x6d808c68 m=nil [finalizer wait]: runtime.gopark(0x87b7f20, 0x8c37510, 0x10, 0xa, 0x1) runtime/proc.go:435 +0xfa fp=0x6d84df9c sp=0x6d84df88 pc=0x80bf61a runtime.runfinq() runtime/mfinal.go:196 +0xed fp=0x6d84dff0 sp=0x6d84df9c pc=0x806740d runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d84dff4 sp=0x6d84dff0 pc=0x80c5461 created by runtime.createfing in goroutine 1 runtime/mfinal.go:166 +0x5a goroutine 6 gp=0x6d808fc8 m=nil [chan receive]: runtime.gopark(0x87b7f00, 0x6d824274, 0xe, 0x7, 0x2) runtime/proc.go:435 +0xfa fp=0x6d851f90 sp=0x6d851f7c pc=0x80bf61a runtime.chanrecv(0x6d824240, 0x0, 0x1) runtime/chan.go:664 +0x3f4 fp=0x6d851fcc sp=0x6d851f90 pc=0x8059f34 runtime.chanrecv1(0x6d824240, 0x0) runtime/chan.go:506 +0x1c fp=0x6d851fe0 sp=0x6d851fcc pc=0x8059b0c runtime.unique_runtime_registerUniqueMapCleanup.func2(...) runtime/mgc.go:1805 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() runtime/mgc.go:1808 +0x34 fp=0x6d851ff0 sp=0x6d851fe0 pc=0x806bc64 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d851ff4 sp=0x6d851ff0 pc=0x80c5461 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 runtime/mgc.go:1803 +0x66 goroutine 8 gp=0x6d809208 m=0 mp=0x8c2b4a0 [syscall]: syscall.syscall6(0x80dfa90, 0xba8c, 0x6d8cd4c4, 0x0, 0x6d891590, 0x0, 0x0) runtime/sys_openbsd3.go:54 +0x20 fp=0x6d8cd470 sp=0x6d8cd460 pc=0x80c2a90 syscall.wait4(0xba8c, 0x6d8cd4c4, 0x0, 0x6d891590) syscall/zsyscall_openbsd_386.go:43 +0x5d fp=0x6d8cd4a8 sp=0x6d8cd470 pc=0x80dc79d syscall.Wait4(0xba8c, 0x6d8cd4ec, 0x0, 0x6d891590) syscall/syscall_bsd.go:144 +0x3b fp=0x6d8cd4cc sp=0x6d8cd4a8 pc=0x80d9e7b os.(*Process).pidWait.func1(...) os/exec_unix.go:68 os.ignoringEINTR2[...](...) os/file_posix.go:261 os.(*Process).pidWait(0x6d995c20) os/exec_unix.go:67 +0xb6 fp=0x6d8cd504 sp=0x6d8cd4cc pc=0x8142436 os.(*Process).wait(0x6d995c20) os/exec_unix.go:30 +0x23 fp=0x6d8cd518 sp=0x6d8cd504 pc=0x8142323 os.(*Process).Wait(...) os/exec.go:358 os/exec.(*Cmd).Wait(0x6daff118) os/exec/exec.go:922 +0x36 fp=0x6d8cd550 sp=0x6d8cd518 pc=0x817b7b6 os/exec.(*Cmd).Run(0x6daff118) os/exec/exec.go:626 +0x3f fp=0x6d8cd560 sp=0x6d8cd550 pc=0x817a5af cmd/go/internal/work.(*Shell).runOut(0x6daeadf8, {0x6d800124, 0x22}, {0x0, 0x0, 0x0}, {0x6dad2000, 0xd, 0x10}) cmd/go/internal/work/shell.go:650 +0x703 fp=0x6d8cd670 sp=0x6d8cd560 pc=0x856c9b3 cmd/go/internal/work.gcToolchain.gc({}, 0x6d872320, 0x6d8a9ee8, {0x6d995a10, 0x30}, {0x6d8e7c00, 0x2a4, 0x400}, {0x0, 0x0, ...}, ...) cmd/go/internal/work/gc.go:178 +0x1c15 fp=0x6d8cd7f8 sp=0x6d8cd670 pc=0x8558525 cmd/go/internal/work.(*gcToolchain).gc(0x8c37420, 0x6d872320, 0x6d8a9ee8, {0x6d995a10, 0x30}, {0x6d8e7c00, 0x2a4, 0x400}, {0x0, 0x0, ...}, ...) :1 +0xe6 fp=0x6d8cd860 sp=0x6d8cd7f8 pc=0x856ede6 cmd/go/internal/work.(*Builder).build(0x6d872320, {0x887ea70, 0x8c37420}, 0x6d8a9ee8) cmd/go/internal/work/exec.go:872 +0x323f fp=0x6d8cde80 sp=0x6d8cd860 pc=0x853d6ff cmd/go/internal/work.(*buildActor).Act(0x6daea6c8, 0x6d872320, {0x887ea70, 0x8c37420}, 0x6d8a9ee8) cmd/go/internal/work/action.go:466 +0x33 fp=0x6d8cde9c sp=0x6d8cde80 pc=0x8529333 cmd/go/internal/work.(*Builder).Do.func3({0x887ea70, 0x8c37420}, 0x6d8a9ee8) cmd/go/internal/work/exec.go:153 +0x90d fp=0x6d8cdf84 sp=0x6d8cde9c pc=0x8537b2d cmd/go/internal/work.(*Builder).Do.func4() cmd/go/internal/work/exec.go:225 +0xaf fp=0x6d8cdff0 sp=0x6d8cdf84 pc=0x85370bf runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d8cdff4 sp=0x6d8cdff0 pc=0x80c5461 created by cmd/go/internal/work.(*Builder).Do in goroutine 1 cmd/go/internal/work/exec.go:211 +0x37a goroutine 13 gp=0x6dad17a8 m=nil [GC worker (idle)]: runtime.gopark(0x87b7f2c, 0x6dab1fe0, 0x1b, 0xa, 0x0) runtime/proc.go:435 +0xfa fp=0x6d84ff8c sp=0x6d84ff78 pc=0x80bf61a runtime.gcBgMarkWorker(0x6da42ac0) runtime/mgc.go:1432 +0xeb fp=0x6d84ffe8 sp=0x6d84ff8c pc=0x806adfb runtime.gcBgMarkStartWorkers.gowrap1() runtime/mgc.go:1348 +0x21 fp=0x6d84fff0 sp=0x6d84ffe8 pc=0x806acf1 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d84fff4 sp=0x6d84fff0 pc=0x80c5461 created by runtime.gcBgMarkStartWorkers in goroutine 8 runtime/mgc.go:1348 +0x114 goroutine 14 gp=0x6dad18c8 m=nil [IO wait]: runtime.gopark(0x87b8064, 0x1d5bf220, 0x2, 0x2, 0x5) runtime/proc.go:435 +0xfa fp=0x6d84cda8 sp=0x6d84cd94 pc=0x80bf61a runtime.netpollblock(0x1d5bf210, 0x72, 0x0) runtime/netpoll.go:575 +0xe4 fp=0x6d84cdc0 sp=0x6d84cda8 pc=0x8084a04 internal/poll.runtime_pollWait(0x1d5bf210, 0x72) runtime/netpoll.go:351 +0x4c fp=0x6d84cdd4 sp=0x6d84cdc0 pc=0x80be93c internal/poll.(*pollDesc).wait(0x6db05a58, 0x72, 0x1) internal/poll/fd_poll_runtime.go:84 +0x31 fp=0x6d84cde8 sp=0x6d84cdd4 pc=0x8139831 internal/poll.(*pollDesc).waitRead(...) internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x6db05a40, {0x6d8ad800, 0x200, 0x200}) internal/poll/fd_unix.go:165 +0x1f6 fp=0x6d84ce30 sp=0x6d84cde8 pc=0x813a6b6 os.(*File).read(...) os/file_posix.go:29 os.(*File).Read(0x6daeb788, {0x6d8ad800, 0x200, 0x200}) os/file.go:124 +0x6a fp=0x6d84ce54 sp=0x6d84ce30 pc=0x814316a bytes.(*Buffer).ReadFrom(0x6daf6480, {0x887b5a0, 0x6daeb7c0}) bytes/buffer.go:211 +0x8f fp=0x6d84ce90 sp=0x6d84ce54 pc=0x81752df io.copyBuffer({0x887b6b0, 0x6daf6480}, {0x887b5a0, 0x6daeb7c0}, {0x0, 0x0, 0x0}) io/io.go:415 +0x146 fp=0x6d84cedc sp=0x6d84ce90 pc=0x8132216 io.Copy(...) io/io.go:388 os.genericWriteTo(0x6daeb788, {0x887b6b0, 0x6daf6480}) os/file.go:275 +0x6f fp=0x6d84cf10 sp=0x6d84cedc pc=0x8143a0f os.(*File).WriteTo(0x6daeb788, {0x887b6b0, 0x6daf6480}) os/file.go:253 +0x61 fp=0x6d84cf30 sp=0x6d84cf10 pc=0x8143941 io.copyBuffer({0x887b6b0, 0x6daf6480}, {0x887b520, 0x6daeb788}, {0x0, 0x0, 0x0}) io/io.go:411 +0x186 fp=0x6d84cf7c sp=0x6d84cf30 pc=0x8132256 io.Copy(...) io/io.go:388 os/exec.(*Cmd).writerDescriptor.func1() os/exec/exec.go:596 +0x4f fp=0x6d84cfb8 sp=0x6d84cf7c pc=0x817a52f os/exec.(*Cmd).Start.func2(0x6daec200) os/exec/exec.go:749 +0x2b fp=0x6d84cfe8 sp=0x6d84cfb8 pc=0x817b04b os/exec.(*Cmd).Start.gowrap1() os/exec/exec.go:761 +0x23 fp=0x6d84cff0 sp=0x6d84cfe8 pc=0x817b003 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d84cff4 sp=0x6d84cff0 pc=0x80c5461 created by os/exec.(*Cmd).Start in goroutine 8 os/exec/exec.go:748 +0x8b6 eax 0x4 ebx 0x40 ecx 0x0 edx 0x40f9d63c edi 0x7a0 esi 0x6d808b48 ebp 0x40f9d5e8 esp 0x40f9d5cc eip 0x182273eb eflags 0x203 cs 0x2b fs 0x5b gs 0x63 trace_test.go:610: exit status 2 --- FAIL: TestTraceIterPull (0.00s) --- FAIL: TestTraceIterPull/Default (0.00s) trace_test.go:610: context deadline exceeded panic: test timed out after 3m0s goroutine 154 gp=0x6d8078c8 m=2 mp=0x6d83c808 [running]: panic({0x824e920, 0x8427dec8}) /tmp/workdir/go/src/runtime/panic.go:806 +0x138 fp=0x6dbb5f84 sp=0x6dbb5f30 pc=0x80bc1a8 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2480 +0x37c fp=0x6dbb5ff0 sp=0x6dbb5f84 pc=0x8148d0c runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6dbb5ff4 sp=0x6dbb5ff0 pc=0x80c1ef1 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:215 +0x29 goroutine 1 gp=0x6d806128 m=nil [chan receive]: runtime.gopark(0x829afc8, 0x73fa4e34, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6d84ad18 sp=0x6d84ad04 pc=0x80bc5ea runtime.chanrecv(0x73fa4e00, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x3f4 fp=0x6d84ad54 sp=0x6d84ad18 pc=0x8055634 runtime.chanrecv1(0x73fa4e00, 0x0) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x6d84ad68 sp=0x6d84ad54 pc=0x805520c testing.runFuzzTests({0x82dc460, 0x842c4e0}, {0x8414820, 0x1, 0x1}, {0xc1e06e4d45fd59cd, 0x29e8f2060e, 0x841ffc0}) /tmp/workdir/go/src/testing/fuzz.go:536 +0x879 fp=0x6d84ae08 sp=0x6d84ad68 pc=0x813cb19 testing.(*M).Run(0x6d862050) /tmp/workdir/go/src/testing/testing.go:2139 +0x687 fp=0x6d84af54 sp=0x6d84ae08 pc=0x8146267 main.main() _testmain.go:99 +0xdf fp=0x6d84afac sp=0x6d84af54 pc=0x822159f runtime.main() /tmp/workdir/go/src/runtime/proc.go:283 +0x288 fp=0x6d84aff0 sp=0x6d84afac pc=0x8088138 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d84aff4 sp=0x6d84aff0 pc=0x80c1ef1 goroutine 2 gp=0x6d806488 m=nil [force gc (idle), 3 minutes]: runtime.gopark(0x829b134, 0x841fc50, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6d8387dc sp=0x6d8387c8 pc=0x80bc5ea runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:348 +0xc7 fp=0x6d8387f0 sp=0x6d8387dc pc=0x8088497 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d8387f4 sp=0x6d8387f0 pc=0x80c1ef1 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:336 +0x1d goroutine 3 gp=0x6d8065a8 m=nil [GC sweep wait]: runtime.gopark(0x829b134, 0x841fde0, 0xc, 0x9, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6d846fcc sp=0x6d846fb8 pc=0x80bc5ea runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.bgsweep(0x6d81e040) /tmp/workdir/go/src/runtime/mgcsweep.go:316 +0xec fp=0x6d846fe8 sp=0x6d846fcc pc=0x807209c runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x6d846ff0 sp=0x6d846fe8 pc=0x80638e1 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d846ff4 sp=0x6d846ff0 pc=0x80c1ef1 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0x71 goroutine 4 gp=0x6d8066c8 m=nil [GC scavenge wait]: runtime.gopark(0x829b134, 0x84200e0, 0xd, 0xa, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6dbb7fb8 sp=0x6dbb7fa4 pc=0x80bc5ea runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.(*scavengerState).park(0x84200e0) /tmp/workdir/go/src/runtime/mgcscavenge.go:425 +0x54 fp=0x6dbb7fcc sp=0x6dbb7fb8 pc=0x806f6f4 runtime.bgscavenge(0x6d81e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:658 +0x69 fp=0x6dbb7fe8 sp=0x6dbb7fcc pc=0x806fd49 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:205 +0x21 fp=0x6dbb7ff0 sp=0x6dbb7fe8 pc=0x80638a1 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6dbb7ff4 sp=0x6dbb7ff0 pc=0x80c1ef1 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:205 +0xb1 goroutine 5 gp=0x6d806c68 m=nil [finalizer wait, 2 minutes]: runtime.gopark(0x829afe8, 0x842c550, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6dbb6f9c sp=0x6dbb6f88 pc=0x80bc5ea runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:196 +0xed fp=0x6dbb6ff0 sp=0x6dbb6f9c pc=0x806298d runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6dbb6ff4 sp=0x6dbb6ff0 pc=0x80c1ef1 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:166 +0x5a goroutine 146 gp=0x6d806fc8 m=nil [chan receive]: runtime.gopark(0x829afc8, 0x73fa5534, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6d891d24 sp=0x6d891d10 pc=0x80bc5ea runtime.chanrecv(0x73fa5500, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x3f4 fp=0x6d891d60 sp=0x6d891d24 pc=0x8055634 runtime.chanrecv1(0x73fa5500, 0x0) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x6d891d74 sp=0x6d891d60 pc=0x805520c testing.(*F).Fuzz.func1({0x82dad00, 0x6d964008}, {{0x0, 0x0}, {0x6e5a6780, 0x29}, {0x0, 0x0, 0x0}, {0x8427db58, ...}, ...}) /tmp/workdir/go/src/testing/fuzz.go:342 +0x56d fp=0x6d891df8 sp=0x6d891d74 pc=0x813ba9d testing.(*F).Fuzz(0x6d964008, {0x824abe0, 0x829af4c}) /tmp/workdir/go/src/testing/fuzz.go:407 +0xba8 fp=0x6d891f74 sp=0x6d891df8 pc=0x813af98 internal/trace_test.FuzzReader(0x6d964008) /tmp/workdir/go/src/internal/trace/reader_test.go:55 +0x2f fp=0x6d891f84 sp=0x6d891f74 pc=0x8215f7f testing.fRunner(0x6d964008, 0x829add8) /tmp/workdir/go/src/testing/fuzz.go:735 +0xe4 fp=0x6d891fe4 sp=0x6d891f84 pc=0x813d644 testing.runFuzzTests.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:535 +0x28 fp=0x6d891ff0 sp=0x6d891fe4 pc=0x813cc58 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d891ff4 sp=0x6d891ff0 pc=0x80c1ef1 created by testing.runFuzzTests in goroutine 1 /tmp/workdir/go/src/testing/fuzz.go:535 +0x85f goroutine 10 gp=0x6d807688 m=nil [GC worker (idle)]: runtime.gopark(0x829aff8, 0x6d818c48, 0x1b, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6d848f8c sp=0x6d848f78 pc=0x80bc5ea runtime.gcBgMarkWorker(0x6d81fd00) /tmp/workdir/go/src/runtime/mgc.go:1432 +0xeb fp=0x6d848fe8 sp=0x6d848f8c pc=0x806637b runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1348 +0x21 fp=0x6d848ff0 sp=0x6d848fe8 pc=0x8066271 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d848ff4 sp=0x6d848ff0 pc=0x80c1ef1 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1348 +0x114 goroutine 153 gp=0x6d807328 m=nil [runnable]: runtime.goschedguarded(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.memclrNoHeapPointersChunked(0x1e00000, 0x87380000) /tmp/workdir/go/src/runtime/malloc.go:1696 +0x5e fp=0x6d88f5e8 sp=0x6d88f5d4 pc=0x805cefe runtime.mallocgcLarge(0x1e00000, 0x824cf20, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1597 +0x20b fp=0x6d88f61c sp=0x6d88f5e8 pc=0x805cbab runtime.mallocgc(0x1e00000, 0x824cf20, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1060 +0x11e fp=0x6d88f638 sp=0x6d88f61c pc=0x80babce runtime.newobject(0x824cf20) /tmp/workdir/go/src/runtime/malloc.go:1711 +0x26 fp=0x6d88f64c sp=0x6d88f638 pc=0x805cf56 internal/trace/internal/oldtrace.(*Events).grow(...) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:293 internal/trace/internal/oldtrace.(*Events).append(...) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:302 internal/trace/internal/oldtrace.(*parser).parseEventBatches(0x6d87ee00) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:476 +0x50a fp=0x6d88f9b8 sp=0x6d88f64c pc=0x81d2c2a internal/trace/internal/oldtrace.(*parser).parse(0x6d87ee00) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:212 +0x1d0 fp=0x6d88fa5c sp=0x6d88f9b8 pc=0x81d2170 internal/trace/internal/oldtrace.Parse({0x82dad30, 0x6dd97110}, 0xb) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:176 +0x96 fp=0x6d88fad8 sp=0x6d88fa5c pc=0x81d1f46 internal/trace.NewReader({0x82dad50, 0x6d8651a0}) /tmp/workdir/go/src/internal/trace/reader.go:43 +0x19e fp=0x6d88fbb0 sp=0x6d88fad8 pc=0x81f7eae internal/trace_test.FuzzReader.func1(0x6dd9cc68, {0x7bd9a300, 0x17, 0x18}) /tmp/workdir/go/src/internal/trace/reader_test.go:56 +0x8a fp=0x6d88fc58 sp=0x6d88fbb0 pc=0x822030a runtime.call16(0x6e5971c0, 0x829af4c, 0x73e7f2a0, 0x10, 0x10, 0x10, 0x6d88fd00) /tmp/workdir/go/src/runtime/asm_386.s:574 +0x2a fp=0x6d88fc6c sp=0x6d88fc58 pc=0x80c0e8a reflect.Value.call({0x824abe0, 0x829af4c, 0x13}, {0x8284c36, 0x4}, {0x6d865188, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:581 +0x97c fp=0x6d88feb0 sp=0x6d88fc6c pc=0x810d97c reflect.Value.Call({0x824abe0, 0x829af4c, 0x13}, {0x6d865188, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:365 +0x79 fp=0x6d88feec sp=0x6d88feb0 pc=0x810cf69 testing.(*F).Fuzz.func1.1(0x6dd9cc68) /tmp/workdir/go/src/testing/fuzz.go:340 +0x2c5 fp=0x6d88ff84 sp=0x6d88feec pc=0x813bf35 testing.tRunner(0x6dd9cc68, 0x6ddc2410) /tmp/workdir/go/src/testing/testing.go:1788 +0x113 fp=0x6d88ffe4 sp=0x6d88ff84 pc=0x8144813 testing.(*F).Fuzz.func1.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:327 +0x28 fp=0x6d88fff0 sp=0x6d88ffe4 pc=0x813bc58 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d88fff4 sp=0x6d88fff0 pc=0x80c1ef1 created by testing.(*F).Fuzz.func1 in goroutine 146 /tmp/workdir/go/src/testing/fuzz.go:327 +0x553 goroutine 138 gp=0x6dd9ca28 m=nil [runnable]: runtime.gopark(0x829afc8, 0x73fa48f4, 0xf, 0x6, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6d847ee8 sp=0x6d847ed4 pc=0x80bc5ea runtime.chansend(0x73fa48c0, 0x6d847fd4, 0x1, 0x816bc47) /tmp/workdir/go/src/runtime/chan.go:283 +0x2d0 fp=0x6d847f20 sp=0x6d847ee8 pc=0x8054930 runtime.chansend1(0x73fa48c0, 0x6d847fd4) /tmp/workdir/go/src/runtime/chan.go:161 +0x24 fp=0x6d847f38 sp=0x6d847f20 pc=0x8054654 os/exec.(*Cmd).watchCtx(0x6d88a4e8, 0x73fa48c0) /tmp/workdir/go/src/os/exec/exec.go:873 +0x497 fp=0x6d847fe4 sp=0x6d847f38 pc=0x816bc47 os/exec.(*Cmd).Start.gowrap2() /tmp/workdir/go/src/os/exec/exec.go:775 +0x28 fp=0x6d847ff0 sp=0x6d847fe4 pc=0x816b568 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d847ff4 sp=0x6d847ff0 pc=0x80c1ef1 created by os/exec.(*Cmd).Start in goroutine 135 /tmp/workdir/go/src/os/exec/exec.go:775 +0x87b FAIL internal/trace 182.134s ? internal/trace/event [no test files] FAIL go tool dist: Failed: exit status 1