openbsd-386-72 at adf21a9b56f3498385152d701ddd0e8679fe57ed :: 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.207s ok archive/zip 0.484s ok bufio 0.086s ok bytes 0.405s ok cmp 0.017s ok compress/bzip2 0.090s ok compress/flate 0.832s ok compress/gzip 1.864s ok compress/lzw 0.108s ok compress/zlib 0.587s ok container/heap 0.017s ok container/list 0.016s ok container/ring 0.018s ok context 0.296s ok crypto 1.272s ok crypto/aes 0.023s ok crypto/cipher 8.897s ok crypto/des 0.032s ok crypto/dsa 0.018s ok crypto/ecdh 0.330s ok crypto/ecdsa 0.190s ok crypto/ed25519 0.338s ok crypto/elliptic 0.047s ? 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.187s ? 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.036s 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.016s ok crypto/internal/fips140/ecdsa 0.169s ? crypto/internal/fips140/ed25519 [no test files] ok crypto/internal/fips140/edwards25519 0.523s ok crypto/internal/fips140/edwards25519/field 0.081s ? crypto/internal/fips140/hkdf [no test files] ? crypto/internal/fips140/hmac [no test files] ok crypto/internal/fips140/mlkem 0.236s ok crypto/internal/fips140/nistec 0.161s 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.343s ? 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] ok crypto/internal/fips140/subtle 0.015s ? crypto/internal/fips140/tls12 [no test files] ? crypto/internal/fips140/tls13 [no test files] ok crypto/internal/fips140deps 0.189s ? 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 4.127s ok crypto/internal/hpke 0.024s ? crypto/internal/impl [no test files] ? crypto/internal/randutil [no test files] ok crypto/internal/sysrand 0.398s ? crypto/internal/sysrand/internal/seccomp [no test files] ok crypto/md5 0.022s ok crypto/mlkem 0.110s ok crypto/pbkdf2 0.036s ok crypto/rand 0.790s ok crypto/rc4 0.055s ok crypto/rsa 1.338s ok crypto/sha1 0.022s ok crypto/sha256 0.022s ok crypto/sha3 4.335s ok crypto/sha512 0.026s ok crypto/subtle 0.197s ok crypto/tls 21.844s ? crypto/tls/internal/fips140tls [no test files] ok crypto/x509 2.678s ? crypto/x509/pkix [no test files] ok database/sql 0.814s ok database/sql/driver 0.015s ok debug/buildinfo 0.160s ok debug/dwarf 0.063s ok debug/elf 0.202s ok debug/gosym 0.059s ok debug/macho 0.027s ok debug/pe 0.072s ok debug/plan9obj 0.017s ok embed 0.017s [no tests to run] ok embed/internal/embedtest 0.017s ? encoding [no test files] ok encoding/ascii85 0.017s ok encoding/asn1 0.019s ok encoding/base32 0.039s ok encoding/base64 0.019s ok encoding/binary 0.025s ok encoding/csv 0.028s ok encoding/gob 4.424s ok encoding/hex 0.019s ok encoding/json 0.462s ok encoding/pem 1.099s ok encoding/xml 0.104s ok errors 0.019s ok expvar 0.020s ok flag 0.071s ok fmt 0.107s ok go/ast 0.022s ok go/build 6.088s ok go/build/constraint 0.085s ok go/constant 0.020s ok go/doc 0.100s ok go/doc/comment 1.577s ok go/format 0.021s ok go/importer 0.654s ok go/internal/gccgoimporter 0.037s ok go/internal/gcimporter 4.771s ok go/internal/srcimporter 17.665s ok go/parser 0.510s ok go/printer 0.198s ok go/scanner 0.018s ok go/token 0.030s ok go/types 27.207s ok go/version 0.015s ok hash 0.017s ok hash/adler32 0.022s ok hash/crc32 0.021s ok hash/crc64 0.017s ok hash/fnv 0.021s ok hash/maphash 2.304s ok html 0.019s ok html/template 0.108s ok image 0.090s ok image/color 0.029s ? image/color/palette [no test files] ok image/draw 0.100s ok image/gif 0.219s ? image/internal/imageutil [no test files] ok image/jpeg 0.228s ok image/png 0.342s ok index/suffixarray 0.220s ok internal/abi 0.173s ? internal/asan [no test files] ? internal/bisect [no test files] ok internal/buildcfg 0.017s ? internal/bytealg [no test files] ? internal/byteorder [no test files] ? internal/cfg [no test files] ok internal/chacha8rand 0.019s ok internal/copyright 0.352s ? internal/coverage [no test files] ? internal/coverage/calloc [no test files] ok internal/coverage/cfile 1.771s ok internal/coverage/cformat 0.016s ok internal/coverage/cmerge 0.016s ? 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.015s 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.038s ok internal/dag 0.016s ok internal/diff 0.023s ? internal/exportdata [no test files] ? internal/filepathlite [no test files] ok internal/fmtsort 0.017s ok internal/fuzz 0.025s ? internal/goarch [no test files] ok internal/godebug 1.201s ok internal/godebugs 8.889s ? 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.015s ok internal/platform 1.923s ok internal/poll 0.073s ok internal/profile 0.015s ? internal/profilerecord [no test files] ? internal/race [no test files] ok internal/reflectlite 0.018s ok internal/routebsd 0.019s ok internal/runtime/atomic 0.055s ? internal/runtime/exithook [no test files] ok internal/runtime/maps 0.022s ok internal/runtime/math 0.015s ok internal/runtime/sys 0.015s ok internal/runtime/wasitest 0.022s ok internal/saferio 0.108s ok internal/singleflight 0.125s ? internal/stringslite [no test files] ok internal/sync 1.221s ok internal/synctest 1.405s ? 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.458s ? internal/testlog [no test files] ? internal/testpty [no test files] --- FAIL: TestTraceStress (122.22s) --- FAIL: TestTraceStress/Default (122.22s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go trace_test.go:615: exit status 1 --- FAIL: TestTraceStressStartStop (12.27s) --- FAIL: TestTraceStressStartStop/Default (12.27s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go trace_test.go:615: context deadline exceeded --- FAIL: TestTraceWaitOnPipe (0.21s) --- FAIL: TestTraceWaitOnPipe/Default (0.21s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go trace_test.go:613: stderr: SIGQUIT: quit PC=0x2c8f2003 m=1 sigcode=0 goroutine 0 gp=0x6cc08368 m=1 mp=0x6cc42008 [idle]: runtime.usleep(0x14) runtime/sys_openbsd2.go:140 +0x19 fp=0x33fffc54 sp=0x33fffc44 pc=0x80a83b9 runtime.sysmon() runtime/proc.go:6150 +0xa6 fp=0x33fffcb0 sp=0x33fffc54 pc=0x8096dd6 runtime.mstart1() runtime/proc.go:1878 +0x72 fp=0x33fffcc0 sp=0x33fffcb0 pc=0x808de62 runtime.mstart0() runtime/proc.go:1831 +0x4b fp=0x33fffccc sp=0x33fffcc0 pc=0x808dddb runtime.mstart() runtime/asm_386.s:275 +0x5 fp=0x33fffcd0 sp=0x33fffccc pc=0x80c4835 goroutine 1 gp=0x6cc08128 m=nil [sync.WaitGroup.Wait]: runtime.gopark(0x87c38fc, 0x8c453e0, 0x18, 0x5, 0x4) runtime/proc.go:458 +0xfa fp=0x6cc2dac8 sp=0x6cc2dab4 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:464 runtime.semacquire1(0x6ce3c978, 0x0, 0x1, 0x0, 0x18) runtime/sema.go:188 +0x27b fp=0x6cc2daf8 sp=0x6cc2dac8 pc=0x809e3bb sync.runtime_SemacquireWaitGroup(0x6ce3c978) runtime/sema.go:110 +0x35 fp=0x6cc2db10 sp=0x6cc2daf8 pc=0x80c0c55 sync.(*WaitGroup).Wait(0x6ce3c970) sync/waitgroup.go:141 +0x5f fp=0x6cc2db2c sp=0x6cc2db10 pc=0x80d005f cmd/go/internal/work.(*Builder).Do(0x6cc03ab0, {0x888b150, 0x8c4b3e0}, 0x6cf07048) cmd/go/internal/work/exec.go:234 +0x415 fp=0x6cc2dbc4 sp=0x6cc2db2c pc=0x853c295 cmd/go/internal/run.runRun({0x888b150, 0x8c4b3e0}, 0x8c35880, {0x6cc1c0e8, 0x1, 0x1}) cmd/go/internal/run/run.go:172 +0x840 fp=0x6cc2dc90 sp=0x6cc2dbc4 pc=0x85917e0 main.invoke(0x8c35880, {0x6cc1c0e0, 0x2, 0x2}) cmd/go/main.go:341 +0x8ba fp=0x6cc2de64 sp=0x6cc2dc90 pc=0x85dfe8a main.main() cmd/go/main.go:220 +0x1031 fp=0x6cc2dfac sp=0x6cc2de64 pc=0x85deef1 runtime.main() runtime/proc.go:283 +0x288 fp=0x6cc2dff0 sp=0x6cc2dfac pc=0x808acd8 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc2dff4 sp=0x6cc2dff0 pc=0x80c5c11 goroutine 2 gp=0x6cc08488 m=nil [force gc (idle)]: runtime.gopark(0x87c38fc, 0x8c3ca98, 0x11, 0xa, 0x1) runtime/proc.go:458 +0xfa fp=0x6cc3e7dc sp=0x6cc3e7c8 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:464 runtime.forcegchelper() runtime/proc.go:371 +0xc7 fp=0x6cc3e7f0 sp=0x6cc3e7dc pc=0x808b037 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc3e7f4 sp=0x6cc3e7f0 pc=0x80c5c11 created by runtime.init.6 in goroutine 1 runtime/proc.go:359 +0x1d goroutine 3 gp=0x6cc085a8 m=nil [GC sweep wait]: runtime.gopark(0x87c38fc, 0x8c3ce70, 0xc, 0x9, 0x1) runtime/proc.go:458 +0xfa fp=0x6cc3efcc sp=0x6cc3efb8 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:464 runtime.bgsweep(0x6cc24040) runtime/mgcsweep.go:276 +0x8f fp=0x6cc3efe8 sp=0x6cc3efcc pc=0x807691f runtime.gcenable.gowrap1() runtime/mgc.go:203 +0x21 fp=0x6cc3eff0 sp=0x6cc3efe8 pc=0x8068161 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc3eff4 sp=0x6cc3eff0 pc=0x80c5c11 created by runtime.gcenable in goroutine 1 runtime/mgc.go:203 +0x71 goroutine 4 gp=0x6cc086c8 m=nil [GC scavenge wait]: runtime.gopark(0x87c38fc, 0x8c3dbe0, 0xd, 0xa, 0x2) runtime/proc.go:458 +0xfa fp=0x6cc3f7b8 sp=0x6cc3f7a4 pc=0x80bf61a runtime.goparkunlock(...) runtime/proc.go:464 runtime.(*scavengerState).park(0x8c3dbe0) runtime/mgcscavenge.go:425 +0x54 fp=0x6cc3f7cc sp=0x6cc3f7b8 pc=0x8073fe4 runtime.bgscavenge(0x6cc24040) runtime/mgcscavenge.go:653 +0x45 fp=0x6cc3f7e8 sp=0x6cc3f7cc pc=0x8074615 runtime.gcenable.gowrap2() runtime/mgc.go:204 +0x21 fp=0x6cc3f7f0 sp=0x6cc3f7e8 pc=0x8068121 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc3f7f4 sp=0x6cc3f7f0 pc=0x80c5c11 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x6cc08c68 m=nil [finalizer wait]: runtime.gopark(0x87c37ac, 0x8c4b4d0, 0x10, 0xa, 0x1) runtime/proc.go:458 +0xfa fp=0x6cc3ff98 sp=0x6cc3ff84 pc=0x80bf61a runtime.runFinalizersAndCleanups() runtime/mfinal.go:197 +0xed fp=0x6cc3fff0 sp=0x6cc3ff98 pc=0x806720d runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc3fff4 sp=0x6cc3fff0 pc=0x80c5c11 created by runtime.createfing in goroutine 1 runtime/mfinal.go:167 +0x5a goroutine 6 gp=0x6cc08fc8 m=nil [chan receive]: runtime.gopark(0x87c378c, 0x6cc24234, 0xe, 0x7, 0x2) runtime/proc.go:458 +0xfa fp=0x6cc4078c sp=0x6cc40778 pc=0x80bf61a runtime.chanrecv(0x6cc24200, 0x0, 0x1) runtime/chan.go:664 +0x41a fp=0x6cc407cc sp=0x6cc4078c pc=0x8059a9a runtime.chanrecv1(0x6cc24200, 0x0) runtime/chan.go:506 +0x1c fp=0x6cc407e0 sp=0x6cc407cc pc=0x805964c runtime.unique_runtime_registerUniqueMapCleanup.func2(...) runtime/mgc.go:1804 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() runtime/mgc.go:1807 +0x34 fp=0x6cc407f0 sp=0x6cc407e0 pc=0x806ba54 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc407f4 sp=0x6cc407f0 pc=0x80c5c11 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 runtime/mgc.go:1802 +0x66 goroutine 8 gp=0x6cc09208 m=nil [runnable]: syscall.syscall6(0x80e0320, 0x5c6f, 0x6cccd4c4, 0x0, 0x6ce23400, 0x0, 0x0) runtime/sys_openbsd3.go:54 +0x20 fp=0x6cccd470 sp=0x6cccd460 pc=0x80c3230 syscall.wait4(0x5c6f, 0x6cccd4c4, 0x0, 0x6ce23400) syscall/zsyscall_openbsd_386.go:43 +0x5d fp=0x6cccd4a8 sp=0x6cccd470 pc=0x80dd02d syscall.Wait4(0x5c6f, 0x6cccd4e8, 0x0, 0x6ce23400) syscall/syscall_bsd.go:144 +0x3b fp=0x6cccd4cc sp=0x6cccd4a8 pc=0x80da6ab os.(*Process).pidWait.func1(...) os/exec_unix.go:64 os.ignoringEINTR2[...](...) os/file_posix.go:266 os.(*Process).pidWait(0x6cef70b0) os/exec_unix.go:63 +0x9e fp=0x6cccd500 sp=0x6cccd4cc pc=0x814345e os.(*Process).wait(0x6cef70b0) os/exec_unix.go:28 +0x22 fp=0x6cccd514 sp=0x6cccd500 pc=0x8143382 os.(*Process).Wait(...) os/exec.go:340 os/exec.(*Cmd).Wait(0x6cf07118) os/exec/exec.go:922 +0x36 fp=0x6cccd54c sp=0x6cccd514 pc=0x817d656 os/exec.(*Cmd).Run(0x6cf07118) os/exec/exec.go:626 +0x3f fp=0x6cccd55c sp=0x6cccd54c pc=0x817c44f cmd/go/internal/work.(*Builder).toolID.func1() cmd/go/internal/work/buildid.go:163 +0x236 fp=0x6cccd5e8 sp=0x6cccd55c pc=0x8536866 cmd/internal/par.(*Cache[...]).Do(0x888ce00, {0x8766b79, 0x7}, 0x6cccd640) cmd/internal/par/work.go:160 +0x11f fp=0x6cccd624 sp=0x6cccd5e8 pc=0x84dcf2f cmd/go/internal/work.(*Builder).toolID(0x6cc03ab0, {0x8766b79, 0x7}) cmd/go/internal/work/buildid.go:147 +0x54 fp=0x6cccd650 sp=0x6cccd624 pc=0x8536614 cmd/go/internal/work.(*Builder).buildActionID(0x6cc03ab0, 0x6cf02348) cmd/go/internal/work/exec.go:344 +0x12f0 fp=0x6cccd854 sp=0x6cccd650 pc=0x853e4f0 cmd/go/internal/work.(*Builder).build(0x6cc03ab0, {0x888b150, 0x8c4b3e0}, 0x6cf02348) cmd/go/internal/work/exec.go:484 +0x2a2 fp=0x6cccde7c sp=0x6cccd854 pc=0x853fc92 cmd/go/internal/work.(*buildActor).Act(0x6cedcdc0, 0x6cc03ab0, {0x888b150, 0x8c4b3e0}, 0x6cf02348) cmd/go/internal/work/action.go:468 +0x33 fp=0x6cccde98 sp=0x6cccde7c pc=0x852dc53 cmd/go/internal/work.(*Builder).Do.func3({0x888b150, 0x8c4b3e0}, 0x6cf02348) cmd/go/internal/work/exec.go:153 +0x912 fp=0x6cccdf84 sp=0x6cccde98 pc=0x853cee2 cmd/go/internal/work.(*Builder).Do.func4() cmd/go/internal/work/exec.go:225 +0xaf fp=0x6cccdff0 sp=0x6cccdf84 pc=0x853c46f runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cccdff4 sp=0x6cccdff0 pc=0x80c5c11 created by cmd/go/internal/work.(*Builder).Do in goroutine 1 cmd/go/internal/work/exec.go:211 +0x376 goroutine 9 gp=0x6cee1568 m=nil [runnable]: os/exec.(*Cmd).Start.gowrap1() os/exec/exec.go:748 fp=0x6cc40ff0 sp=0x6cc40fec pc=0x817ce80 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc40ff4 sp=0x6cc40ff0 pc=0x80c5c11 created by os/exec.(*Cmd).Start in goroutine 8 os/exec/exec.go:748 +0x8b6 goroutine 10 gp=0x6cee1688 m=nil [runnable]: os/exec.(*Cmd).Start.gowrap1() os/exec/exec.go:748 fp=0x6cc417f0 sp=0x6cc417ec pc=0x817ce80 runtime.goexit({}) runtime/asm_386.s:1386 +0x1 fp=0x6cc417f4 sp=0x6cc417f0 pc=0x80c5c11 created by os/exec.(*Cmd).Start in goroutine 8 os/exec/exec.go:748 +0x8b6 eax 0x4 ebx 0x4c8772b8 ecx 0x0 edx 0x0 edi 0x98 esi 0x1275ff04 ebp 0x33fffbc0 esp 0x33fffbac eip 0x2c8f2003 eflags 0x247 cs 0x2b fs 0x5b gs 0x63 trace_test.go:615: exit status 2 --- FAIL: TestTraceIterPull (0.00s) --- FAIL: TestTraceIterPull/Default (0.00s) trace_test.go:615: context deadline exceeded panic: test timed out after 3m0s running tests: TestTraceV1 (0s) TestTraceV1/stress_1_11_good (0s) goroutine 139 gp=0x5b7547e8 m=0 mp=0x842d8e0 [running]: panic({0x8254e20, 0x5b422428}) /tmp/workdir/go/src/runtime/panic.go:802 +0x135 fp=0x5b44df84 sp=0x5b44df30 pc=0x80bc3e5 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2494 +0x36a fp=0x5b44dff0 sp=0x5b44df84 pc=0x814ad9a runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b44dff4 sp=0x5b44dff0 pc=0x80c28e1 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:215 +0x29 goroutine 1 gp=0x5b406128 m=nil [chan receive, 1 minutes]: runtime.gopark(0x82a2f14, 0x5b41f134, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b44ac34 sp=0x5b44ac20 pc=0x80bc82a runtime.chanrecv(0x5b41f100, 0x5b44acaf, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x41a fp=0x5b44ac74 sp=0x5b44ac34 pc=0x80556aa runtime.chanrecv1(0x5b41f100, 0x5b44acaf) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x5b44ac88 sp=0x5b44ac74 pc=0x805525c testing.(*T).Run(0x5b406d88, {0x828dee1, 0xb}, 0x82a2d78) /tmp/workdir/go/src/testing/testing.go:1866 +0x481 fp=0x5b44ad10 sp=0x5b44ac88 pc=0x81477f1 testing.runTests.func1(0x5b406d88) /tmp/workdir/go/src/testing/testing.go:2289 +0x3f fp=0x5b44ad30 sp=0x5b44ad10 pc=0x81499bf testing.tRunner(0x5b406d88, 0x5b44adf4) /tmp/workdir/go/src/testing/testing.go:1799 +0x10e fp=0x5b44ad90 sp=0x5b44ad30 pc=0x814683e testing.runTests(0x5b4100e0, {0x8425160, 0x1a, 0x1a}, {0xc1f76d9789018cdc, 0x29e8f11df6, 0x842d100}) /tmp/workdir/go/src/testing/testing.go:2287 +0x3e4 fp=0x5b44ae08 sp=0x5b44ad90 pc=0x81498d4 testing.(*M).Run(0x5b464140) /tmp/workdir/go/src/testing/testing.go:2152 +0x634 fp=0x5b44af54 sp=0x5b44ae08 pc=0x8148274 main.main() _testmain.go:99 +0xdf fp=0x5b44afac sp=0x5b44af54 pc=0x822771f runtime.main() /tmp/workdir/go/src/runtime/proc.go:283 +0x288 fp=0x5b44aff0 sp=0x5b44afac pc=0x8088878 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b44aff4 sp=0x5b44aff0 pc=0x80c28e1 goroutine 2 gp=0x5b406488 m=nil [force gc (idle), 3 minutes]: runtime.gopark(0x82a3080, 0x842cd80, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b4387dc sp=0x5b4387c8 pc=0x80bc82a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:464 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:371 +0xc7 fp=0x5b4387f0 sp=0x5b4387dc pc=0x8088bd7 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b4387f4 sp=0x5b4387f0 pc=0x80c28e1 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:359 +0x1d goroutine 3 gp=0x5b4065a8 m=nil [runnable]: runtime.goschedIfBusy() /tmp/workdir/go/src/runtime/proc.go:410 +0x36 fp=0x5b44cfcc sp=0x5b44cfc0 pc=0x8088c86 runtime.bgsweep(0x5b41e040) /tmp/workdir/go/src/runtime/mgcsweep.go:300 +0x141 fp=0x5b44cfe8 sp=0x5b44cfcc pc=0x8072351 runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:203 +0x21 fp=0x5b44cff0 sp=0x5b44cfe8 pc=0x8063b41 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b44cff4 sp=0x5b44cff0 pc=0x80c28e1 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:203 +0x71 goroutine 4 gp=0x5b4066c8 m=nil [runnable]: runtime.gopark(0x82a3080, 0x842d220, 0x13, 0xe, 0x2) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b446f68 sp=0x5b446f54 pc=0x80bc82a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:464 runtime.(*scavengerState).sleep(0x842d220, 0x412e89f400000000) /tmp/workdir/go/src/runtime/mgcscavenge.go:504 +0x149 fp=0x5b446fcc sp=0x5b446f68 pc=0x806fb69 runtime.bgscavenge(0x5b41e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:662 +0xa0 fp=0x5b446fe8 sp=0x5b446fcc pc=0x806fff0 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x5b446ff0 sp=0x5b446fe8 pc=0x8063b01 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b446ff4 sp=0x5b446ff0 pc=0x80c28e1 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x5b406c68 m=nil [finalizer wait, 3 minutes]: runtime.gopark(0x82a2f34, 0x8439410, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b439f98 sp=0x5b439f84 pc=0x80bc82a runtime.runFinalizersAndCleanups() /tmp/workdir/go/src/runtime/mfinal.go:197 +0xed fp=0x5b439ff0 sp=0x5b439f98 pc=0x8062bed runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b439ff4 sp=0x5b439ff0 pc=0x80c28e1 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:167 +0x5a goroutine 12 gp=0x5b406fc8 m=nil [chan receive, 3 minutes]: runtime.gopark(0x82a2f14, 0x5b41e1f4, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b449d34 sp=0x5b449d20 pc=0x80bc82a runtime.chanrecv(0x5b41e1c0, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x41a fp=0x5b449d74 sp=0x5b449d34 pc=0x80556aa runtime.chanrecv1(0x5b41e1c0, 0x0) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x5b449d88 sp=0x5b449d74 pc=0x805525c testing.(*T).Parallel(0x5b4070e8) /tmp/workdir/go/src/testing/testing.go:1580 +0x267 fp=0x5b449e08 sp=0x5b449d88 pc=0x81464f7 internal/trace_test.TestMMU(0x5b4070e8) /tmp/workdir/go/src/internal/trace/gc_test.go:28 +0x2c fp=0x5b449f84 sp=0x5b449e08 pc=0x8219b4c testing.tRunner(0x5b4070e8, 0x82a2d2c) /tmp/workdir/go/src/testing/testing.go:1799 +0x10e fp=0x5b449fe4 sp=0x5b449f84 pc=0x814683e testing.(*T).Run.gowrap1() /tmp/workdir/go/src/testing/testing.go:1858 +0x28 fp=0x5b449ff0 sp=0x5b449fe4 pc=0x8147938 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b449ff4 sp=0x5b449ff0 pc=0x80c28e1 created by testing.(*T).Run in goroutine 1 /tmp/workdir/go/src/testing/testing.go:1858 +0x462 goroutine 10 gp=0x5b407688 m=nil [GC worker (idle)]: runtime.gopark(0x82a2f44, 0x5b418c48, 0x1b, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b448f8c sp=0x5b448f78 pc=0x80bc82a runtime.gcBgMarkWorker(0x5b41fd00) /tmp/workdir/go/src/runtime/mgc.go:1431 +0xeb fp=0x5b448fe8 sp=0x5b448f8c pc=0x80665cb runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1347 +0x21 fp=0x5b448ff0 sp=0x5b448fe8 pc=0x80664c1 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b448ff4 sp=0x5b448ff0 pc=0x80c28e1 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1347 +0x114 goroutine 134 gp=0x5b407328 m=nil [chan receive]: runtime.gopark(0x82a2f14, 0x5b41e5b4, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:458 +0xfa fp=0x5b44be5c sp=0x5b44be48 pc=0x80bc82a runtime.chanrecv(0x5b41e580, 0x5b44bed7, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x41a fp=0x5b44be9c sp=0x5b44be5c pc=0x80556aa runtime.chanrecv1(0x5b41e580, 0x5b44bed7) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x5b44beb0 sp=0x5b44be9c pc=0x805525c testing.(*T).Run(0x5b754008, {0x5b41b30a, 0x10}, 0x5b5b42d0) /tmp/workdir/go/src/testing/testing.go:1866 +0x481 fp=0x5b44bf38 sp=0x5b44beb0 pc=0x81477f1 internal/trace_test.TestTraceV1(0x5b754008) /tmp/workdir/go/src/internal/trace/tracev1_test.go:28 +0xe5 fp=0x5b44bf84 sp=0x5b44bf38 pc=0x8221c15 testing.tRunner(0x5b754008, 0x82a2d78) /tmp/workdir/go/src/testing/testing.go:1799 +0x10e fp=0x5b44bfe4 sp=0x5b44bf84 pc=0x814683e testing.(*T).Run.gowrap1() /tmp/workdir/go/src/testing/testing.go:1858 +0x28 fp=0x5b44bff0 sp=0x5b44bfe4 pc=0x8147938 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b44bff4 sp=0x5b44bff0 pc=0x80c28e1 created by testing.(*T).Run in goroutine 1 /tmp/workdir/go/src/testing/testing.go:1858 +0x462 goroutine 138 gp=0x5b407b08 m=nil [runnable]: runtime.asyncPreempt2() /tmp/workdir/go/src/runtime/preempt.go:308 +0x38 fp=0x5b4912a0 sp=0x5b491294 pc=0x80874b8 runtime.asyncPreempt() /tmp/workdir/go/src/runtime/preempt_386.s:27 +0x52 fp=0x5b491344 sp=0x5b4912a0 pc=0x80c34e2 internal/trace/internal/tracev1.(*parser).readRawEvent(0x5b47fb80, 0x0, 0x5b4914a0) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:754 +0x1261 fp=0x5b491424 sp=0x5b491344 pc=0x81db631 internal/trace/internal/tracev1.(*parser).loadBatch(0x5b47fb80, 0x2, {0x0, 0x0, 0x0}) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:844 +0x1d3 fp=0x5b4914d4 sp=0x5b491424 pc=0x81db943 internal/trace/internal/tracev1.(*parser).parseEventBatches(0x5b47fb80) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:414 +0x102e fp=0x5b491868 sp=0x5b4914d4 pc=0x81d9b8e internal/trace/internal/tracev1.(*parser).parse(0x5b47fb80) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:211 +0x1d0 fp=0x5b49190c sp=0x5b491868 pc=0x81d85b0 internal/trace/internal/tracev1.Parse({0x82e39d0, 0x5b614060}, 0xb) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:175 +0x96 fp=0x5b491988 sp=0x5b49190c pc=0x81d8386 internal/trace.NewReader({0x82e3980, 0x5b4223e8}) /tmp/workdir/go/src/internal/trace/reader.go:50 +0x19e fp=0x5b491a60 sp=0x5b491988 pc=0x81f9e6e internal/trace_test.TestTraceV1.func1(0x5b5b6248) /tmp/workdir/go/src/internal/trace/tracev1_test.go:35 +0x193 fp=0x5b491f84 sp=0x5b491a60 pc=0x8221f13 testing.tRunner(0x5b5b6248, 0x5b5b42d0) /tmp/workdir/go/src/testing/testing.go:1799 +0x10e fp=0x5b491fe4 sp=0x5b491f84 pc=0x814683e testing.(*T).Run.gowrap1() /tmp/workdir/go/src/testing/testing.go:1858 +0x28 fp=0x5b491ff0 sp=0x5b491fe4 pc=0x8147938 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1386 +0x1 fp=0x5b491ff4 sp=0x5b491ff0 pc=0x80c28e1 created by testing.(*T).Run in goroutine 134 /tmp/workdir/go/src/testing/testing.go:1858 +0x462 FAIL internal/trace 180.151s ? internal/trace/internal/testgen [no test files] FAIL go tool dist: Failed: exit status 1