openbsd-386-72 at c0d96f30e88b7ed541830a9d5a172f199d05399c :: 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.456s ok bufio 0.087s ok bytes 0.455s ok cmp 0.016s ok compress/bzip2 0.088s ok compress/flate 0.919s ok compress/gzip 1.854s ok compress/lzw 0.110s ok compress/zlib 0.587s ok container/heap 0.018s ok container/list 0.016s ok container/ring 0.017s ok context 0.299s ok crypto 1.282s ok crypto/aes 0.020s ok crypto/cipher 8.557s ok crypto/des 0.032s ok crypto/dsa 0.018s ok crypto/ecdh 0.329s ok crypto/ecdsa 0.191s ok crypto/ed25519 0.347s ok crypto/elliptic 0.058s ? crypto/fips140 [no test files] ok crypto/hkdf 0.023s ok crypto/hmac 0.039s ok crypto/internal/boring 0.015s ? crypto/internal/boring/bbig [no test files] ok crypto/internal/boring/bcache 0.193s ? 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.532s ok crypto/internal/fips140/edwards25519/field 0.083s ? crypto/internal/fips140/hkdf [no test files] ? crypto/internal/fips140/hmac [no test files] ok crypto/internal/fips140/mlkem 0.231s 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 5.204s ok crypto/internal/hpke 0.025s ? crypto/internal/impl [no test files] ? crypto/internal/randutil [no test files] ok crypto/internal/sysrand 0.393s ? crypto/internal/sysrand/internal/seccomp [no test files] ok crypto/md5 0.020s ok crypto/mlkem 0.110s ok crypto/pbkdf2 0.036s ok crypto/rand 0.789s ok crypto/rc4 0.066s ok crypto/rsa 1.280s ok crypto/sha1 0.019s ok crypto/sha256 0.020s ok crypto/sha3 4.345s ok crypto/sha512 0.024s ok crypto/subtle 0.202s ok crypto/tls 16.769s ? crypto/tls/internal/fips140tls [no test files] ok crypto/x509 2.651s ? crypto/x509/pkix [no test files] ok database/sql 0.793s ok database/sql/driver 0.015s ok debug/buildinfo 0.088s ok debug/dwarf 0.060s ok debug/elf 0.205s ok debug/gosym 0.061s ok debug/macho 0.026s ok debug/pe 0.070s 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.020s ok encoding/base32 0.039s ok encoding/base64 0.019s ok encoding/binary 0.026s ok encoding/csv 0.028s ok encoding/gob 4.426s ok encoding/hex 0.019s ok encoding/json 0.509s ok encoding/pem 1.095s ok encoding/xml 0.098s ok errors 0.020s ok expvar 0.020s ok flag 0.073s ok fmt 0.107s ok go/ast 0.022s ok go/ast/internal/tests 0.016s ok go/build 6.128s ok go/build/constraint 0.082s ok go/constant 0.020s ok go/doc 0.107s ok go/doc/comment 1.603s ok go/format 0.022s ok go/importer 0.664s ok go/internal/gccgoimporter 0.037s ok go/internal/gcimporter 4.762s ok go/internal/srcimporter 17.497s ok go/parser 0.491s ok go/printer 0.210s ok go/scanner 0.018s ok go/token 0.031s ok go/types 27.565s 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.293s ok html 0.018s ok html/template 0.098s ok image 0.090s ok image/color 0.030s ? image/color/palette [no test files] ok image/draw 0.100s ok image/gif 0.223s ? image/internal/imageutil [no test files] ok image/jpeg 0.225s ok image/png 0.337s ok index/suffixarray 0.219s ok internal/abi 0.171s ? 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.357s ? internal/coverage [no test files] ? internal/coverage/calloc [no test files] ok internal/coverage/cfile 1.809s ok internal/coverage/cformat 0.018s 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.020s ? internal/coverage/rtcov [no test files] ok internal/coverage/slicereader 0.016s ok internal/coverage/slicewriter 0.016s ? 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.204s ok internal/godebugs 8.802s ? internal/goexperiment [no test files] ? internal/goos [no test files] ? internal/goroot [no test files] ok internal/gover 0.015s ? 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.923s ok internal/poll 0.065s ok internal/profile 0.015s ? internal/profilerecord [no test files] ? internal/race [no test files] ok internal/reflectlite 0.018s ok internal/routebsd 0.022s 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.015s ok internal/saferio 0.108s ok internal/singleflight 0.131s ? internal/stringslite [no test files] ok internal/sync 1.161s ok internal/synctest 1.427s ? 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.469s ? internal/testlog [no test files] ? internal/testpty [no test files] --- FAIL: TestTraceStress (123.37s) --- FAIL: TestTraceStress/Default (123.37s) 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 (11.99s) --- FAIL: TestTraceStressStartStop/Default (11.99s) 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: signal: killed --- FAIL: TestTraceManyStartStop (0.19s) --- FAIL: TestTraceManyStartStop/Default (0.19s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go trace_test.go:613: stderr: SIGQUIT: quit PC=0x3a5b95a3 m=3 sigcode=0 goroutine 0 gp=0x6d408b48 m=3 mp=0x6d443008 [idle]: runtime.thrsleep(0x6d443314, 0x3, 0x0, 0x0, 0x6d443314) runtime/sys_openbsd1.go:17 +0x21 fp=0x4eec4598 sp=0x4eec4588 pc=0x80a85d1 runtime.semasleep(0xffffffffffffffff) runtime/os_openbsd.go:118 +0xe0 fp=0x4eec45d4 sp=0x4eec4598 pc=0x8085ed0 runtime.notesleep(0x6d4430cc) runtime/lock_sema.go:62 +0x7e fp=0x4eec45f0 sp=0x4eec45d4 pc=0x805eace runtime.mPark(...) runtime/proc.go:1887 runtime.stopm() runtime/proc.go:2907 +0x96 fp=0x4eec4604 sp=0x4eec45f0 pc=0x808f5c6 runtime.startlockedm(0x6d408128) runtime/proc.go:3190 +0x63 fp=0x4eec4614 sp=0x4eec4604 pc=0x808feb3 runtime.schedule() runtime/proc.go:4063 +0x71 fp=0x4eec4630 sp=0x4eec4614 pc=0x80922b1 runtime.park_m(0x6d4085a8) runtime/proc.go:4141 +0x227 fp=0x4eec465c sp=0x4eec4630 pc=0x8092767 runtime.mcall(0x40000) runtime/asm_386.s:329 +0x40 fp=0x4eec4664 sp=0x4eec465c pc=0x80c4590 goroutine 1 gp=0x6d408128 m=nil [chan receive]: runtime.gopark(0x87bb1f4, 0x6d424474, 0xe, 0x7, 0x2) runtime/proc.go:435 +0xfa fp=0x6d4cf7c0 sp=0x6d4cf7ac pc=0x80bfada runtime.chanrecv(0x6d424440, 0x0, 0x1) runtime/chan.go:664 +0x41a fp=0x6d4cf800 sp=0x6d4cf7c0 pc=0x8059fea runtime.chanrecv1(0x6d424440, 0x0) runtime/chan.go:506 +0x1c fp=0x6d4cf814 sp=0x6d4cf800 pc=0x8059b9c cmd/go/internal/modload.loadFromRoots({0x88821e0, 0x8c3e380}, {{{0x0, 0x0}, 0x6d4d40e0, 0x0, 0x0, {0x0, 0x0}, 0x0, ...}, ...}) cmd/go/internal/modload/load.go:1163 +0x400 fp=0x6d4cfa28 sp=0x6d4cf814 pc=0x8474f10 cmd/go/internal/modload.ImportFromFiles({0x88821e0, 0x8c3e380}, {0x6d41c118, 0x1, 0x1}) cmd/go/internal/modload/load.go:772 +0x186 fp=0x6d4cfaf0 sp=0x6d4cfa28 pc=0x8473ce6 cmd/go/internal/load.GoFilesPackage({0x88821e0, 0x8c3e380}, {0x0, 0x0, 0x1, 0x0, 0x0, 0x0}, {0x6d41c118, 0x1, ...}) cmd/go/internal/load/pkg.go:3249 +0x677 fp=0x6d4cfbdc sp=0x6d4cfaf0 pc=0x84cd2f7 cmd/go/internal/run.runRun({0x88821e0, 0x8c3e380}, 0x8c286c0, {0x6d41c118, 0x1, 0x1}) cmd/go/internal/run/run.go:110 +0xb52 fp=0x6d4cfc90 sp=0x6d4cfbdc pc=0x858d452 main.invoke(0x8c286c0, {0x6d41c110, 0x2, 0x2}) cmd/go/main.go:341 +0x8ba fp=0x6d4cfe64 sp=0x6d4cfc90 pc=0x85dad0a main.main() cmd/go/main.go:220 +0x1031 fp=0x6d4cffac sp=0x6d4cfe64 pc=0x85d9d71 runtime.main() runtime/proc.go:283 +0x288 fp=0x6d4cfff0 sp=0x6d4cffac pc=0x808acd8 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d4cfff4 sp=0x6d4cfff0 pc=0x80c5921 goroutine 2 gp=0x6d408488 m=nil [force gc (idle)]: runtime.gopark(0x87bb364, 0x8c2f7d0, 0x11, 0xa, 0x1) runtime/proc.go:435 +0xfa fp=0x6d43e7dc sp=0x6d43e7c8 pc=0x80bfada runtime.goparkunlock(...) runtime/proc.go:441 runtime.forcegchelper() runtime/proc.go:348 +0xc7 fp=0x6d43e7f0 sp=0x6d43e7dc pc=0x808b037 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d43e7f4 sp=0x6d43e7f0 pc=0x80c5921 created by runtime.init.6 in goroutine 1 runtime/proc.go:336 +0x1d goroutine 3 gp=0x6d4085a8 m=nil [GC sweep wait]: runtime.gopark(0x87bb364, 0x8c2fba0, 0xc, 0x9, 0x1) runtime/proc.go:435 +0xfa fp=0x6d43efcc sp=0x6d43efb8 pc=0x80bfada runtime.goparkunlock(...) runtime/proc.go:441 runtime.bgsweep(0x6d424040) runtime/mgcsweep.go:276 +0x8f fp=0x6d43efe8 sp=0x6d43efcc pc=0x8076d8f runtime.gcenable.gowrap1() runtime/mgc.go:204 +0x21 fp=0x6d43eff0 sp=0x6d43efe8 pc=0x80685e1 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6d43eff4 sp=0x6d43eff0 pc=0x80c5921 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0x71 goroutine 4 gp=0x6d4086c8 m=nil [GC scavenge wait]: runtime.gopark trace_test.go:615: signal: killed --- FAIL: TestTraceWaitOnPipe (0.00s) --- FAIL: TestTraceWaitOnPipe/Default (0.00s) trace_test.go:615: context deadline exceeded --- 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 (2s) TestTraceV1/http_1_19_good (2s) goroutine 134 gp=0x577390e8 m=0 mp=0x8422e00 [running]: panic({0x824eb80, 0x57422278}) /tmp/workdir/go/src/runtime/panic.go:818 +0x138 fp=0x57643f84 sp=0x57643f30 pc=0x80bc728 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2490 +0x37c fp=0x57643ff0 sp=0x57643f84 pc=0x81497fc runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x57643ff4 sp=0x57643ff0 pc=0x80c2471 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:215 +0x29 goroutine 1 gp=0x57406128 m=nil [chan receive, 1 minutes]: runtime.gopark(0x829bce8, 0x5741efb4, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x5744ac34 sp=0x5744ac20 pc=0x80bcb6a runtime.chanrecv(0x5741ef80, 0x5744acaf, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x41a fp=0x5744ac74 sp=0x5744ac34 pc=0x80556da runtime.chanrecv1(0x5741ef80, 0x5744acaf) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x5744ac88 sp=0x5744ac74 pc=0x805528c testing.(*T).Run(0x57406d88, {0x828710e, 0xb}, 0x829bb4c) /tmp/workdir/go/src/testing/testing.go:1865 +0x481 fp=0x5744ad10 sp=0x5744ac88 pc=0x8146241 testing.runTests.func1(0x57406d88) /tmp/workdir/go/src/testing/testing.go:2285 +0x3f fp=0x5744ad30 sp=0x5744ad10 pc=0x814840f testing.tRunner(0x57406d88, 0x5744adf4) /tmp/workdir/go/src/testing/testing.go:1798 +0x10e fp=0x5744ad90 sp=0x5744ad30 pc=0x814528e testing.runTests(0x57410080, {0x841a100, 0x1a, 0x1a}, {0xc1e3fe192e074772, 0x29e8ef10cb, 0x8422040}) /tmp/workdir/go/src/testing/testing.go:2283 +0x3e4 fp=0x5744ae08 sp=0x5744ad90 pc=0x8148324 testing.(*M).Run(0x57464140) /tmp/workdir/go/src/testing/testing.go:2148 +0x634 fp=0x5744af54 sp=0x5744ae08 pc=0x8146cc4 main.main() _testmain.go:99 +0xdf fp=0x5744afac sp=0x5744af54 pc=0x822225f runtime.main() /tmp/workdir/go/src/runtime/proc.go:283 +0x288 fp=0x5744aff0 sp=0x5744afac pc=0x8088658 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5744aff4 sp=0x5744aff0 pc=0x80c2471 goroutine 2 gp=0x57406488 m=nil [force gc (idle), 3 minutes]: runtime.gopark(0x829be54, 0x8421cb8, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x574387dc sp=0x574387c8 pc=0x80bcb6a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:348 +0xc7 fp=0x574387f0 sp=0x574387dc pc=0x80889b7 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x574387f4 sp=0x574387f0 pc=0x80c2471 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:336 +0x1d goroutine 3 gp=0x574065a8 m=nil [runnable]: runtime.gopark(0x829be54, 0x8421e50, 0xc, 0x9, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x5744dfcc sp=0x5744dfb8 pc=0x80bcb6a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.bgsweep(0x5741e040) /tmp/workdir/go/src/runtime/mgcsweep.go:316 +0xec fp=0x5744dfe8 sp=0x5744dfcc pc=0x80722fc runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x5744dff0 sp=0x5744dfe8 pc=0x8063b51 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5744dff4 sp=0x5744dff0 pc=0x80c2471 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0x71 goroutine 4 gp=0x574066c8 m=nil [runnable]: runtime.gopark(0x829be54, 0x8422160, 0xd, 0xa, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x57447fb8 sp=0x57447fa4 pc=0x80bcb6a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.(*scavengerState).park(0x8422160) /tmp/workdir/go/src/runtime/mgcscavenge.go:425 +0x54 fp=0x57447fcc sp=0x57447fb8 pc=0x806f954 runtime.bgscavenge(0x5741e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:658 +0x69 fp=0x57447fe8 sp=0x57447fcc pc=0x806ffa9 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:205 +0x21 fp=0x57447ff0 sp=0x57447fe8 pc=0x8063b11 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x57447ff4 sp=0x57447ff0 pc=0x80c2471 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:205 +0xb1 goroutine 5 gp=0x57406c68 m=nil [finalizer wait, 3 minutes]: runtime.gopark(0x829bd08, 0x842e5d0, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x57439f98 sp=0x57439f84 pc=0x80bcb6a runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:196 +0xed fp=0x57439ff0 sp=0x57439f98 pc=0x8062bfd runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x57439ff4 sp=0x57439ff0 pc=0x80c2471 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:166 +0x5a goroutine 12 gp=0x57406fc8 m=nil [chan receive, 3 minutes]: runtime.gopark(0x829bce8, 0x5741e1f4, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x57449d34 sp=0x57449d20 pc=0x80bcb6a runtime.chanrecv(0x5741e1c0, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x41a fp=0x57449d74 sp=0x57449d34 pc=0x80556da runtime.chanrecv1(0x5741e1c0, 0x0) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x57449d88 sp=0x57449d74 pc=0x805528c testing.(*T).Parallel(0x574070e8) /tmp/workdir/go/src/testing/testing.go:1579 +0x267 fp=0x57449e08 sp=0x57449d88 pc=0x8144f47 internal/trace_test.TestMMU(0x574070e8) /tmp/workdir/go/src/internal/trace/gc_test.go:28 +0x2c fp=0x57449f84 sp=0x57449e08 pc=0x821471c testing.tRunner(0x574070e8, 0x829bb00) /tmp/workdir/go/src/testing/testing.go:1798 +0x10e fp=0x57449fe4 sp=0x57449f84 pc=0x814528e testing.(*T).Run.gowrap1() /tmp/workdir/go/src/testing/testing.go:1857 +0x28 fp=0x57449ff0 sp=0x57449fe4 pc=0x8146388 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x57449ff4 sp=0x57449ff0 pc=0x80c2471 created by testing.(*T).Run in goroutine 1 /tmp/workdir/go/src/testing/testing.go:1857 +0x462 goroutine 10 gp=0x57407688 m=nil [GC worker (idle), 1 minutes]: runtime.gopark(0x829bd18, 0x57418c48, 0x1b, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x57448f8c sp=0x57448f78 pc=0x80bcb6a runtime.gcBgMarkWorker(0x5741fd00) /tmp/workdir/go/src/runtime/mgc.go:1432 +0xeb fp=0x57448fe8 sp=0x57448f8c pc=0x80665db runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1348 +0x21 fp=0x57448ff0 sp=0x57448fe8 pc=0x80664d1 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x57448ff4 sp=0x57448ff0 pc=0x80c2471 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1348 +0x114 goroutine 131 gp=0x57407328 m=nil [chan receive, 1 minutes]: runtime.gopark(0x829bce8, 0x5741fab4, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x5744be5c sp=0x5744be48 pc=0x80bcb6a runtime.chanrecv(0x5741fa80, 0x5744bed7, 0x1) /tmp/workdir/go/src/runtime/chan.go:664 +0x41a fp=0x5744be9c sp=0x5744be5c pc=0x80556da runtime.chanrecv1(0x5741fa80, 0x5744bed7) /tmp/workdir/go/src/runtime/chan.go:506 +0x1c fp=0x5744beb0 sp=0x5744be9c pc=0x805528c testing.(*T).Run(0x575a1448, {0x5741a91a, 0xe}, 0x575a2ca8) /tmp/workdir/go/src/testing/testing.go:1865 +0x481 fp=0x5744bf38 sp=0x5744beb0 pc=0x8146241 internal/trace_test.TestTraceV1(0x575a1448) /tmp/workdir/go/src/internal/trace/tracev1_test.go:28 +0xe5 fp=0x5744bf84 sp=0x5744bf38 pc=0x821c755 testing.tRunner(0x575a1448, 0x829bb4c) /tmp/workdir/go/src/testing/testing.go:1798 +0x10e fp=0x5744bfe4 sp=0x5744bf84 pc=0x814528e testing.(*T).Run.gowrap1() /tmp/workdir/go/src/testing/testing.go:1857 +0x28 fp=0x5744bff0 sp=0x5744bfe4 pc=0x8146388 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5744bff4 sp=0x5744bff0 pc=0x80c2471 created by testing.(*T).Run in goroutine 1 /tmp/workdir/go/src/testing/testing.go:1857 +0x462 goroutine 133 gp=0x57738488 m=nil [runnable]: runtime.goschedguarded(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.memclrNoHeapPointersChunked(0x100000, 0x5783a000) /tmp/workdir/go/src/runtime/malloc.go:1696 +0x5e fp=0x57491328 sp=0x57491314 pc=0x805cfae runtime.mallocgcLarge(0x100000, 0x824ed80, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1597 +0x20b fp=0x5749135c sp=0x57491328 pc=0x805cc5b runtime.mallocgc(0x100000, 0x824ed80, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1060 +0x11e fp=0x57491378 sp=0x5749135c pc=0x80bb14e runtime.makeslice(0x824ed80, 0x20000, 0x20000) /tmp/workdir/go/src/runtime/slice.go:116 +0x44 fp=0x5749138c sp=0x57491378 pc=0x80be834 internal/trace/internal/tracev1.(*parser).allocateStack(...) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:1507 internal/trace/internal/tracev1.(*parser).parseEvent(0x57644b00, 0x574914c4, 0x5749147c) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:940 +0x4a3 fp=0x57491448 sp=0x5749138c pc=0x81d66b3 internal/trace/internal/tracev1.(*parser).loadBatch(0x57644b00, 0x0, {0x5765a000, 0x0, 0x1c7}) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:862 +0x28c fp=0x574914f8 sp=0x57491448 pc=0x81d5e6c internal/trace/internal/tracev1.(*parser).parseEventBatches(0x57644b00) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:414 +0xfb5 fp=0x57491868 sp=0x574914f8 pc=0x81d4015 internal/trace/internal/tracev1.(*parser).parse(0x57644b00) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:211 +0x1d0 fp=0x5749190c sp=0x57491868 pc=0x81d2ab0 internal/trace/internal/tracev1.Parse({0x82dc260, 0x57608720}, 0x13) /tmp/workdir/go/src/internal/trace/internal/tracev1/parser.go:175 +0x96 fp=0x57491988 sp=0x5749190c pc=0x81d2886 internal/trace.NewReader({0x82dc220, 0x57422c38}) /tmp/workdir/go/src/internal/trace/reader.go:49 +0x19e fp=0x57491a60 sp=0x57491988 pc=0x81f432e internal/trace_test.TestTraceV1.func1(0x5763bd48) /tmp/workdir/go/src/internal/trace/tracev1_test.go:35 +0x193 fp=0x57491f84 sp=0x57491a60 pc=0x821ca53 testing.tRunner(0x5763bd48, 0x575a2ca8) /tmp/workdir/go/src/testing/testing.go:1798 +0x10e fp=0x57491fe4 sp=0x57491f84 pc=0x814528e testing.(*T).Run.gowrap1() /tmp/workdir/go/src/testing/testing.go:1857 +0x28 fp=0x57491ff0 sp=0x57491fe4 pc=0x8146388 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x57491ff4 sp=0x57491ff0 pc=0x80c2471 created by testing.(*T).Run in goroutine 131 /tmp/workdir/go/src/testing/testing.go:1857 +0x462 goroutine 119 gp=0x577387e8 m=nil [runnable]: runtime.gopark(0x829bce8, 0x5741e7f4, 0xf, 0x6, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x5763eee4 sp=0x5763eed0 pc=0x80bcb6a runtime.chansend(0x5741e7c0, 0x5763efd4, 0x1, 0x816c927) /tmp/workdir/go/src/runtime/chan.go:283 +0x2fb fp=0x5763ef20 sp=0x5763eee4 pc=0x80549ab runtime.chansend1(0x5741e7c0, 0x5763efd4) /tmp/workdir/go/src/runtime/chan.go:161 +0x24 fp=0x5763ef38 sp=0x5763ef20 pc=0x80546a4 os/exec.(*Cmd).watchCtx(0x5748a278, 0x5741e7c0) /tmp/workdir/go/src/os/exec/exec.go:873 +0x497 fp=0x5763efe4 sp=0x5763ef38 pc=0x816c927 os/exec.(*Cmd).Start.gowrap2() /tmp/workdir/go/src/os/exec/exec.go:775 +0x28 fp=0x5763eff0 sp=0x5763efe4 pc=0x816c248 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5763eff4 sp=0x5763eff0 pc=0x80c2471 created by os/exec.(*Cmd).Start in goroutine 116 /tmp/workdir/go/src/os/exec/exec.go:775 +0x87b FAIL internal/trace 183.034s ? internal/trace/internal/testgen [no test files] FAIL go tool dist: Failed: exit status 1