openbsd-386-72 at 4256ec16616827b8ef5a19f74599aa44ffe6111a :: Running /tmp/workdir/go/src/make.bash with args ["/tmp/workdir/go/src/make.bash" "-force"] and env ["HOME=/" "PATH=/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/bin" "INRC=1" "_=/bin/sh" "WORKDIR=/tmp/workdir" "GOROOT_BOOTSTRAP=/tmp/workdir/go1.4" "GO_BUILDER_NAME=openbsd-386-72" "GOBIN=" "TMPDIR=/tmp/workdir/tmp" "GOCACHE=/tmp/workdir/gocache" "GOPLSCACHE=/tmp/workdir/goplscache" "PWD=/tmp/workdir/go/src"] in dir /tmp/workdir/go/src Building Go cmd/dist using /tmp/workdir/go1.4. (go1.20.6 openbsd/386) 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 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.212s ok archive/zip 0.470s ok bufio 0.085s ok bytes 0.473s ok cmp 0.016s ok compress/bzip2 0.095s ok compress/flate 0.846s ok compress/gzip 1.800s ok compress/lzw 0.107s ok compress/zlib 0.608s ok container/heap 0.017s ok container/list 0.015s ok container/ring 0.017s ok context 0.300s ok crypto 1.208s ok crypto/aes 0.036s ok crypto/cipher 0.020s ok crypto/des 0.028s ok crypto/dsa 0.018s ok crypto/ecdh 0.304s ok crypto/ecdsa 0.208s ok crypto/ed25519 0.338s ok crypto/elliptic 0.065s ok crypto/hmac 0.016s ok crypto/internal/alias 0.015s ok crypto/internal/bigmod 0.068s ok crypto/internal/boring 0.015s ok crypto/internal/boring/bcache 0.217s ok crypto/internal/edwards25519 0.535s ok crypto/internal/edwards25519/field 0.083s ok crypto/internal/hpke 0.020s ok crypto/internal/mlkem768 0.322s ok crypto/internal/nistec 1.087s ok crypto/internal/nistec/fiat 0.014s [no tests to run] ok crypto/md5 0.018s ok crypto/rand 0.126s ok crypto/rc4 0.057s ok crypto/rsa 1.130s ok crypto/sha1 0.019s ok crypto/sha256 0.018s ok crypto/sha512 0.021s ok crypto/subtle 0.285s ok crypto/tls 2.435s ok crypto/x509 2.606s ok database/sql 0.785s ok database/sql/driver 0.016s ok debug/buildinfo 0.025s ok debug/dwarf 0.061s ok debug/elf 0.223s ok debug/gosym 0.057s ok debug/macho 0.027s ok debug/pe 0.074s ok debug/plan9obj 0.018s ok embed 0.017s [no tests to run] ok embed/internal/embedtest 0.017s ok encoding/ascii85 0.017s ok encoding/asn1 0.019s ok encoding/base32 0.038s ok encoding/base64 0.019s ok encoding/binary 0.025s ok encoding/csv 0.028s ok encoding/gob 4.533s ok encoding/hex 0.018s ok encoding/json 0.467s ok encoding/pem 1.094s ok encoding/xml 0.096s ok errors 0.025s ok expvar 0.019s ok flag 0.070s ok fmt 0.106s ok go/ast 0.022s ok go/build 3.941s ok go/build/constraint 0.018s ok go/constant 0.020s ok go/doc 0.102s ok go/doc/comment 1.830s ok go/format 0.021s ok go/importer 0.621s ok go/internal/gccgoimporter 0.036s ok go/internal/gcimporter 4.064s ok go/internal/srcimporter 17.291s ok go/parser 0.500s ok go/printer 0.219s ok go/scanner 0.018s ok go/token 0.031s ok go/types 26.029s ok go/version 0.016s ok hash 0.017s ok hash/adler32 0.028s ok hash/crc32 0.020s ok hash/crc64 0.016s ok hash/fnv 0.016s ok hash/maphash 0.067s ok html 0.017s ok html/template 0.097s ok image 0.090s ok image/color 0.030s ok image/draw 0.102s ok image/gif 0.219s ok image/jpeg 0.225s ok image/png 0.344s ok index/suffixarray 0.219s ok internal/abi 0.167s ok internal/buildcfg 0.016s ok internal/chacha8rand 0.016s ok internal/concurrent 0.079s ok internal/coverage/cfile 0.016s ok internal/coverage/cformat 0.016s ok internal/coverage/cmerge 0.015s ok internal/coverage/pods 0.024s ok internal/coverage/slicereader 0.015s ok internal/coverage/slicewriter 0.015s ok internal/coverage/test 0.024s ok internal/cpu 0.036s ok internal/dag 0.016s ok internal/diff 0.023s ok internal/fmtsort 0.016s ok internal/fuzz 0.029s ok internal/godebug 1.170s ok internal/godebugs 8.663s ok internal/gover 0.016s ok internal/itoa 0.015s ok internal/platform 1.820s ok internal/poll 0.068s ok internal/profile 0.015s ok internal/reflectlite 0.018s ok internal/runtime/atomic 0.053s ok internal/saferio 0.106s ok internal/singleflight 0.086s ok internal/sysinfo 0.015s ok internal/testenv 0.424s --- FAIL: TestTraceStressStartStop (18.90s) --- FAIL: TestTraceStressStartStop/Default (18.90s) exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go trace_test.go:579: signal: killed --- FAIL: TestTraceManyStartStop (0.86s) --- FAIL: TestTraceManyStartStop/Default (0.86s) exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go trace_test.go:577: stderr: SIGQUIT: quit PC=0x1f9d5ec7 m=3 sigcode=0 goroutine 0 gp=0x5bc08b48 m=3 mp=0x5bc42a08 [idle]: runtime.kevent(0x3d, 0x0, 0x0, 0x26eb3094, 0x40, 0x0) runtime/sys_openbsd2.go:219 +0x21 fp=0x26eb3040 sp=0x26eb3030 pc=0x809f591 runtime.netpoll(0xffffffffffffffff) runtime/netpoll_kqueue.go:107 +0xe5 fp=0x26eb369c sp=0x26eb3040 pc=0x807d675 runtime.findRunnable() runtime/proc.go:3580 +0x9ff fp=0x26eb3768 sp=0x26eb369c pc=0x8088a8f runtime.schedule() runtime/proc.go:3995 +0xb1 fp=0x26eb3784 sp=0x26eb3768 pc=0x808a1d1 runtime.park_m(0x5bc086c8) runtime/proc.go:4102 +0x1c7 fp=0x26eb37ac sp=0x26eb3784 pc=0x808a5e7 runtime.mcall(0x40000) runtime/asm_386.s:329 +0x40 fp=0x26eb37b4 sp=0x26eb37ac pc=0x80bdc10 goroutine 1 gp=0x5bc08128 m=nil [semacquire]: runtime.gopark(0x8753bdc, 0x8b725e0, 0x12, 0x5, 0x4) runtime/proc.go:424 +0xfa fp=0x5bcd7ae4 sp=0x5bcd7ad0 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.semacquire1(0x5bec21f8, 0x0, 0x1, 0x0, 0x12) runtime/sema.go:178 +0x285 fp=0x5bcd7b14 sp=0x5bcd7ae4 pc=0x8095af5 sync.runtime_Semacquire(0x5bec21f8) runtime/sema.go:71 +0x35 fp=0x5bcd7b2c sp=0x5bcd7b14 pc=0x80b9ad5 sync.(*WaitGroup).Wait(0x5bec21f0) sync/waitgroup.go:118 +0x5f fp=0x5bcd7b48 sp=0x5bcd7b2c pc=0x80cc3df cmd/go/internal/work.(*Builder).Do(0x5bc78230, {0x880aae0, 0x8b778e0}, 0x5bef9988) cmd/go/internal/work/exec.go:230 +0x42b fp=0x5bcd7be0 sp=0x5bcd7b48 pc=0x858bffb cmd/go/internal/run.runRun({0x880aae0, 0x8b778e0}, 0x8b62260, {0x5bc1c028, 0x1, 0x1}) cmd/go/internal/run/run.go:174 +0x7ff fp=0x5bcd7c94 sp=0x5bcd7be0 pc=0x85d898f main.invoke(0x8b62260, {0x5bc1c020, 0x2, 0x2}) cmd/go/main.go:297 +0x8b8 fp=0x5bcd7e68 sp=0x5bcd7c94 pc=0x8621f68 main.main() cmd/go/main.go:211 +0x1013 fp=0x5bcd7fac sp=0x5bcd7e68 pc=0x86212c3 runtime.main() runtime/proc.go:272 +0x288 fp=0x5bcd7ff0 sp=0x5bcd7fac pc=0x8082c68 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bcd7ff4 sp=0x5bcd7ff0 pc=0x80befa1 goroutine 2 gp=0x5bc08488 m=nil [force gc (idle)]: runtime.gopark(0x8753bdc, 0x8b69258, 0x11, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x5bc3efdc sp=0x5bc3efc8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.forcegchelper() runtime/proc.go:337 +0xc7 fp=0x5bc3eff0 sp=0x5bc3efdc pc=0x8082fc7 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc3eff4 sp=0x5bc3eff0 pc=0x80befa1 created by runtime.init.6 in goroutine 1 runtime/proc.go:325 +0x1d goroutine 3 gp=0x5bc085a8 m=nil [GC sweep wait]: runtime.gopark(0x8753bdc, 0x8b69730, 0xc, 0x9, 0x1) runtime/proc.go:424 +0xfa fp=0x5bc3f7cc sp=0x5bc3f7b8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.bgsweep(0x5bc24040) runtime/mgcsweep.go:317 +0xec fp=0x5bc3f7e8 sp=0x5bc3f7cc pc=0x806f02c runtime.gcenable.gowrap1() runtime/mgc.go:203 +0x21 fp=0x5bc3f7f0 sp=0x5bc3f7e8 pc=0x8060a41 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc3f7f4 sp=0x5bc3f7f0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:203 +0x71 goroutine 4 gp=0x5bc086c8 m=nil [GC scavenge wait]: runtime.gopark(0x8753bdc, 0x8b6a1c0, 0xd, 0xa, 0x2) runtime/proc.go:424 +0xfa fp=0x5bc3ffb8 sp=0x5bc3ffa4 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.(*scavengerState).park(0x8b6a1c0) runtime/mgcscavenge.go:425 +0x54 fp=0x5bc3ffcc sp=0x5bc3ffb8 pc=0x806c614 runtime.bgscavenge(0x5bc24040) runtime/mgcscavenge.go:658 +0x69 fp=0x5bc3ffe8 sp=0x5bc3ffcc pc=0x806cc69 runtime.gcenable.gowrap2() runtime/mgc.go:204 +0x21 fp=0x5bc3fff0 sp=0x5bc3ffe8 pc=0x8060a01 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc3fff4 sp=0x5bc3fff0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x5bc08c68 m=nil [finalizer wait]: runtime.gopark(0x8753a90, 0x8b779ac, 0x10, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x5bc3e798 sp=0x5bc3e784 pc=0x80b862a runtime.runfinq() runtime/mfinal.go:193 +0xf0 fp=0x5bc3e7f0 sp=0x5bc3e798 pc=0x805fba0 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc3e7f4 sp=0x5bc3e7f0 pc=0x80befa1 created by runtime.createfing in goroutine 1 runtime/mfinal.go:163 +0x5a goroutine 6 gp=0x5bc08fc8 m=nil [chan receive]: runtime.gopark(0x8753a70, 0x5bc24274, 0xe, 0x7, 0x2) runtime/proc.go:424 +0xfa fp=0x5bc40790 sp=0x5bc4077c pc=0x80b862a runtime.chanrecv(0x5bc24240, 0x0, 0x1) runtime/chan.go:639 +0x3bd fp=0x5bc407cc sp=0x5bc40790 pc=0x8050e4d runtime.chanrecv1(0x5bc24240, 0x0) runtime/chan.go:489 +0x1c fp=0x5bc407e0 sp=0x5bc407cc pc=0x8050a5c runtime.unique_runtime_registerUniqueMapCleanup.func1(...) runtime/mgc.go:1732 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() runtime/mgc.go:1735 +0x34 fp=0x5bc407f0 sp=0x5bc407e0 pc=0x80641e4 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc407f4 sp=0x5bc407f0 pc=0x80befa1 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 runtime/mgc.go:1730 +0x96 goroutine 8 gp=0x5bc09208 m=0 mp=0x8b6b180 [syscall]: syscall.syscall6(0x80d9680, 0xdf55, 0x5bcd5ac0, 0x0, 0x5c07b090, 0x0, 0x0) runtime/sys_openbsd3.go:54 +0x20 fp=0x5bcd5a6c sp=0x5bcd5a5c pc=0x80bc3d0 syscall.wait4(0xdf55, 0x5bcd5ac0, 0x0, 0x5c07b090) syscall/zsyscall_openbsd_386.go:43 +0x61 fp=0x5bcd5aa4 sp=0x5bcd5a6c pc=0x80d6201 syscall.Wait4(0xdf55, 0x5bcd5ae4, 0x0, 0x5c07b090) syscall/syscall_bsd.go:144 +0x3b fp=0x5bcd5ac8 sp=0x5bcd5aa4 pc=0x80d38cb os.(*Process).wait(0x5bd9b8f0) os/exec_unix.go:63 +0x12c fp=0x5bcd5afc sp=0x5bcd5ac8 pc=0x813cb9c os.(*Process).Wait(...) os/exec.go:135 os/exec.(*Cmd).Wait(0x5bc041a8) os/exec/exec.go:901 +0x36 fp=0x5bcd5b34 sp=0x5bcd5afc pc=0x81758d6 os/exec.(*Cmd).Run(0x5bc041a8) os/exec/exec.go:608 +0x3f fp=0x5bcd5b44 sp=0x5bcd5b34 pc=0x817465f cmd/go/internal/work.(*Shell).runOut(0x5bfd12e8, {0x8803bd0, 0x1}, {0x5bfd1770, 0x1, 0x1}, {0x5bcd5d7c, 0x8, 0x8}) cmd/go/internal/work/shell.go:637 +0x706 fp=0x5bcd5c50 sp=0x5bcd5b44 pc=0x85c15a6 cmd/go/internal/work.(*Shell).run(0x5bfd12e8, {0x8803bd0, 0x1}, {0x8707941, 0x16}, {0x5bfd1770, 0x1, 0x1}, {0x5bcd5d7c, 0x8, ...}) cmd/go/internal/work/shell.go:564 +0x62 fp=0x5bcd5ca8 sp=0x5bcd5c50 pc=0x85c0d12 cmd/go/internal/work.gcToolchain.ld({}, 0x5bc78230, 0x5becec08, {0x5be36dc0, 0x3b}, {0x5bc25680, 0x36}, {0x5bd9a720, 0x2f}) cmd/go/internal/work/gc.go:683 +0x1065 fp=0x5bcd5dd4 sp=0x5bcd5ca8 pc=0x85b2715 cmd/go/internal/work.(*gcToolchain).ld(0x8b778e0, 0x5bc78230, 0x5becec08, {0x5be36dc0, 0x3b}, {0x5bc25680, 0x36}, {0x5bd9a720, 0x2f}) :1 +0x62 fp=0x5bcd5e00 sp=0x5bcd5dd4 pc=0x85c3d42 cmd/go/internal/work.(*Builder).link(0x5bc78230, {0x880aae0, 0x8b778e0}, 0x5becec08) cmd/go/internal/work/exec.go:1480 +0x353 fp=0x5bcd5e84 sp=0x5bcd5e00 pc=0x8599743 cmd/go/internal/work.ActorFunc.Act(0x87538e8, 0x5bc78230, {0x880aae0, 0x8b778e0}, 0x5becec08) cmd/go/internal/work/action.go:76 +0x36 fp=0x5bcd5ea0 sp=0x5bcd5e84 pc=0x857c856 cmd/go/internal/work.(*Builder).Do.func3({0x880aae0, 0x8b778e0}, 0x5becec08) cmd/go/internal/work/exec.go:152 +0x801 fp=0x5bcd5f88 sp=0x5bcd5ea0 pc=0x858cb41 cmd/go/internal/work.(*Builder).Do.func4() cmd/go/internal/work/exec.go:221 +0xae fp=0x5bcd5ff0 sp=0x5bcd5f88 pc=0x858c1de runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bcd5ff4 sp=0x5bcd5ff0 pc=0x80befa1 created by cmd/go/internal/work.(*Builder).Do in goroutine 1 cmd/go/internal/work/exec.go:207 +0x38c goroutine 13 gp=0x5bedcfc8 m=nil [GC worker (idle)]: runtime.gopark(0x8753a9c, 0x5bea3a10, 0x1a, 0xa, 0x0) runtime/proc.go:424 +0xfa fp=0x5bc40f8c sp=0x5bc40f78 pc=0x80b862a runtime.gcBgMarkWorker(0x5be37900) runtime/mgc.go:1363 +0xeb fp=0x5bc40fe8 sp=0x5bc40f8c pc=0x806337b runtime.gcBgMarkStartWorkers.gowrap1() runtime/mgc.go:1279 +0x21 fp=0x5bc40ff0 sp=0x5bc40fe8 pc=0x8063271 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc40ff4 sp=0x5bc40ff0 pc=0x80befa1 created by runtime.gcBgMarkStartWorkers in goroutine 8 runtime/mgc.go:1279 +0x114 goroutine 17 gp=0x5bedd0e8 m=nil [IO wait]: runtime.gopark(0x8753bd0, 0x49026998, 0x2, 0x2, 0x5) runtime/proc.go:424 +0xfa fp=0x5bc41588 sp=0x5bc41574 pc=0x80b862a runtime.netpollblock(0x49026988, 0x72, 0x0) runtime/netpoll.go:575 +0xe4 fp=0x5bc415a0 sp=0x5bc41588 pc=0x807ce54 internal/poll.runtime_pollWait(0x49026988, 0x72) runtime/netpoll.go:351 +0x4c fp=0x5bc415b4 sp=0x5bc415a0 pc=0x80b79ec internal/poll.(*pollDesc).wait(0x5befd3d8, 0x72, 0x1) internal/poll/fd_poll_runtime.go:84 +0x31 fp=0x5bc415c8 sp=0x5bc415b4 pc=0x8133ff1 internal/poll.(*pollDesc).waitRead(...) internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x5befd3c0, {0x5bca9400, 0x200, 0x200}) internal/poll/fd_unix.go:165 +0x1f6 fp=0x5bc41610 sp=0x5bc415c8 pc=0x8134e76 os.(*File).read(...) os/file_posix.go:29 os.(*File).Read(0x5bfd17d0, {0x5bca9400, 0x200, 0x200}) os/file.go:124 +0x6a fp=0x5bc41634 sp=0x5bc41610 pc=0x813d71a os.(*fileWithoutWriteTo).Read(0x5bfd17f8, {0x5bca9400, 0x200, 0x200}) :1 +0x3c fp=0x5bc41654 sp=0x5bc41634 pc=0x8145b7c bytes.(*Buffer).ReadFrom(0x5bed2348, {0x88077b0, 0x5bfd17f8}) bytes/buffer.go:211 +0x8f fp=0x5bc41690 sp=0x5bc41654 pc=0x816f45f io.copyBuffer({0x88078b0, 0x5bed2348}, {0x88077b0, 0x5bfd17f8}, {0x0, 0x0, 0x0}) io/io.go:415 +0x146 fp=0x5bc416dc sp=0x5bc41690 pc=0x812c976 io.Copy(...) io/io.go:388 os.genericWriteTo(0x5bfd17d0, {0x88078b0, 0x5bed2348}) os/file.go:275 +0x6f fp=0x5bc41710 sp=0x5bc416dc pc=0x813dfbf os.(*File).WriteTo(0x5bfd17d0, {0x88078b0, 0x5bed2348}) os/file.go:253 +0x61 fp=0x5bc41730 sp=0x5bc41710 pc=0x813def1 io.copyBuffer({0x88078b0, 0x5bed2348}, {0x8807740, 0x5bfd17d0}, {0x0, 0x0, 0x0}) io/io.go:411 +0x186 fp=0x5bc4177c sp=0x5bc41730 pc=0x812c9b6 io.Copy(...) io/io.go:388 os/exec.(*Cmd).writerDescriptor.func1() os/exec/exec.go:578 +0x4f fp=0x5bc417b8 sp=0x5bc4177c pc=0x81745df os/exec.(*Cmd).Start.func2(0x5bc12720) os/exec/exec.go:728 +0x2b fp=0x5bc417e8 sp=0x5bc417b8 pc=0x817516b os/exec.(*Cmd).Start.gowrap1() os/exec/exec.go:740 +0x23 fp=0x5bc417f0 sp=0x5bc417e8 pc=0x8175123 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x5bc417f4 sp=0x5bc417f0 pc=0x80befa1 created by os/exec.(*Cmd).Start in goroutine 8 os/exec/exec.go:727 +0x924 eax 0x4 ebx 0x40 ecx 0x0 edx 0x26eb3040 edi 0x79c esi 0x5bc08b48 ebp 0x26eb2fe8 esp 0x26eb2fcc eip 0x1f9d5ec7 eflags 0x203 cs 0x2b fs 0x5b gs 0x63 trace_test.go:579: exit status 2 --- FAIL: TestTraceWaitOnPipe (0.00s) --- FAIL: TestTraceWaitOnPipe/Default (0.00s) trace_test.go:579: context deadline exceeded --- FAIL: TestTraceIterPull (0.00s) --- FAIL: TestTraceIterPull/Default (0.00s) trace_test.go:579: context deadline exceeded panic: test timed out after 3m0s goroutine 151 gp=0x5e406fc8 m=3 mp=0x5e43ca08 [running]: panic({0x8236ee0, 0x6007d038}) /tmp/workdir/go/src/runtime/panic.go:804 +0x138 fp=0x5e435f84 sp=0x5e435f30 pc=0x80b61f8 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2373 +0x386 fp=0x5e435ff0 sp=0x5e435f84 pc=0x81421b6 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e435ff4 sp=0x5e435ff0 pc=0x80bcc21 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:214 +0x29 goroutine 1 gp=0x5e406128 m=nil [chan receive]: runtime.gopark(0x827c900, 0x5e41f534, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e486d24 sp=0x5e486d10 pc=0x80b65aa runtime.chanrecv(0x5e41f500, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x5e486d60 sp=0x5e486d24 pc=0x804db5d runtime.chanrecv1(0x5e41f500, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x5e486d74 sp=0x5e486d60 pc=0x804d76c testing.runFuzzTests({0x82ba7c0, 0x83f7040}, {0x83df880, 0x1, 0x1}, {0xc191eed1855bb343, 0x29e8ef08c9, 0x83eae40}) /tmp/workdir/go/src/testing/fuzz.go:528 +0x7e3 fp=0x5e486e08 sp=0x5e486d74 pc=0x8136203 testing.(*M).Run(0x5e458050) /tmp/workdir/go/src/testing/testing.go:2035 +0x687 fp=0x5e486f54 sp=0x5e486e08 pc=0x813f787 main.main() _testmain.go:99 +0xdf fp=0x5e486fac sp=0x5e486f54 pc=0x821b85f runtime.main() /tmp/workdir/go/src/runtime/proc.go:272 +0x288 fp=0x5e486ff0 sp=0x5e486fac pc=0x80812a8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e486ff4 sp=0x5e486ff0 pc=0x80bcc21 goroutine 2 gp=0x5e406488 m=nil [force gc (idle), 3 minutes]: runtime.gopark(0x827ca6c, 0x83eab08, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e438fdc sp=0x5e438fc8 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:337 +0xc7 fp=0x5e438ff0 sp=0x5e438fdc pc=0x8081607 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e438ff4 sp=0x5e438ff0 pc=0x80bcc21 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:325 +0x1d goroutine 3 gp=0x5e4065a8 m=nil [GC sweep wait]: runtime.gopark(0x827ca6c, 0x83eaca0, 0xc, 0x9, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e4397cc sp=0x5e4397b8 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.bgsweep(0x5e41e040) /tmp/workdir/go/src/runtime/mgcsweep.go:317 +0xec fp=0x5e4397e8 sp=0x5e4397cc pc=0x806b4ec runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:203 +0x21 fp=0x5e4397f0 sp=0x5e4397e8 pc=0x805cf61 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e4397f4 sp=0x5e4397f0 pc=0x80bcc21 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:203 +0x71 goroutine 4 gp=0x5e4066c8 m=nil [GC scavenge wait]: runtime.gopark(0x827ca6c, 0x83eaf60, 0xd, 0xa, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e439fb8 sp=0x5e439fa4 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.(*scavengerState).park(0x83eaf60) /tmp/workdir/go/src/runtime/mgcscavenge.go:425 +0x54 fp=0x5e439fcc sp=0x5e439fb8 pc=0x8068ad4 runtime.bgscavenge(0x5e41e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:658 +0x69 fp=0x5e439fe8 sp=0x5e439fcc pc=0x8069129 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x5e439ff0 sp=0x5e439fe8 pc=0x805cf21 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e439ff4 sp=0x5e439ff0 pc=0x80bcc21 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x5e406c68 m=nil [finalizer wait, 2 minutes]: runtime.gopark(0x827c920, 0x83f7094, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e43a798 sp=0x5e43a784 pc=0x80b65aa runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:193 +0xf0 fp=0x5e43a7f0 sp=0x5e43a798 pc=0x805c0c0 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e43a7f4 sp=0x5e43a7f0 pc=0x80bcc21 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:163 +0x5a goroutine 143 gp=0x5e406d88 m=nil [chan receive]: runtime.gopark(0x827c900, 0x5e41fbf4, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e485d30 sp=0x5e485d1c pc=0x80b65aa runtime.chanrecv(0x5e41fbc0, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x5e485d6c sp=0x5e485d30 pc=0x804db5d runtime.chanrecv1(0x5e41fbc0, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x5e485d80 sp=0x5e485d6c pc=0x804d76c testing.(*F).Fuzz.func1({0x82b91b0, 0x60582008}, {{0x0, 0x0}, {0x6053faa0, 0x29}, {0x0, 0x0, 0x0}, {0x6007cce0, ...}, ...}) /tmp/workdir/go/src/testing/fuzz.go:337 +0x4f9 fp=0x5e485df8 sp=0x5e485d80 pc=0x8135219 testing.(*F).Fuzz(0x60582008, {0x8234120, 0x827c880}) /tmp/workdir/go/src/testing/fuzz.go:402 +0xbb1 fp=0x5e485f74 sp=0x5e485df8 pc=0x8134771 internal/trace_test.FuzzReader(0x60582008) /tmp/workdir/go/src/internal/trace/reader_test.go:55 +0x2f fp=0x5e485f84 sp=0x5e485f74 pc=0x8210a8f testing.fRunner(0x60582008, 0x827c714) /tmp/workdir/go/src/testing/fuzz.go:724 +0xe4 fp=0x5e485fe4 sp=0x5e485f84 pc=0x8136c74 testing.runFuzzTests.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:527 +0x28 fp=0x5e485ff0 sp=0x5e485fe4 pc=0x8136348 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e485ff4 sp=0x5e485ff0 pc=0x80bcc21 created by testing.runFuzzTests in goroutine 1 /tmp/workdir/go/src/testing/fuzz.go:527 +0x7c9 goroutine 10 gp=0x5e4070e8 m=nil [GC worker (idle), 1 minutes]: runtime.gopark(0x827c930, 0x5e418c48, 0x1a, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e43878c sp=0x5e438778 pc=0x80b65aa runtime.gcBgMarkWorker(0x5e41fd80) /tmp/workdir/go/src/runtime/mgc.go:1363 +0xeb fp=0x5e4387e8 sp=0x5e43878c pc=0x805f89b runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1279 +0x21 fp=0x5e4387f0 sp=0x5e4387e8 pc=0x805f791 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e4387f4 sp=0x5e4387f0 pc=0x80bcc21 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1279 +0x114 goroutine 150 gp=0x5e406ea8 m=nil [runnable]: runtime.goschedguarded(...) /tmp/workdir/go/src/runtime/proc.go:361 runtime.memclrNoHeapPointersChunked(0x1e00000, 0x64340000) /tmp/workdir/go/src/runtime/malloc.go:1371 +0x5e fp=0x5e4835b8 sp=0x5e4835a4 pc=0x8053cbe runtime.mallocgc(0x1e00000, 0x8236160, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1265 +0x7cb fp=0x5e483630 sp=0x5e4835b8 pc=0x80b234b runtime.newobject(0x8236160) /tmp/workdir/go/src/runtime/malloc.go:1386 +0x26 fp=0x5e483644 sp=0x5e483630 pc=0x8053d16 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(0x60581380) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:476 +0x4f2 fp=0x5e4839a4 sp=0x5e483644 pc=0x81d0582 internal/trace/internal/oldtrace.(*parser).parse(0x60581380) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:212 +0x1d0 fp=0x5e483a48 sp=0x5e4839a4 pc=0x81cfab0 internal/trace/internal/oldtrace.Parse({0x82b91e0, 0x5ea56cc0}, 0xb) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:176 +0x96 fp=0x5e483ac4 sp=0x5e483a48 pc=0x81cf896 internal/trace.NewReader({0x82b9200, 0x602e9008}) /tmp/workdir/go/src/internal/trace/reader.go:43 +0x19e fp=0x5e483b9c sp=0x5e483ac4 pc=0x81f4c9e internal/trace_test.FuzzReader.func1(0x5eb83b08, {0x6053cc48, 0x17, 0x18}) /tmp/workdir/go/src/internal/trace/reader_test.go:56 +0x8a fp=0x5e483c54 sp=0x5e483b9c pc=0x821a42a runtime.call16(0x6052ecc0, 0x827c880, 0x6053b970, 0x10, 0x10, 0x10, 0x5e483d00) /tmp/workdir/go/src/runtime/asm_386.s:574 +0x2a fp=0x5e483c68 sp=0x5e483c54 pc=0x80bbbba reflect.Value.call({0x8234120, 0x827c880, 0x13}, {0x826709a, 0x4}, {0x602e8ff0, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:581 +0x9db fp=0x5e483eb0 sp=0x5e483c68 pc=0x810512b reflect.Value.Call({0x8234120, 0x827c880, 0x13}, {0x602e8ff0, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:365 +0x79 fp=0x5e483eec sp=0x5e483eb0 pc=0x81046b9 testing.(*F).Fuzz.func1.1(0x5eb83b08) /tmp/workdir/go/src/testing/fuzz.go:335 +0x2bc fp=0x5e483f84 sp=0x5e483eec pc=0x81356ac testing.tRunner(0x5eb83b08, 0x5e62e550) /tmp/workdir/go/src/testing/testing.go:1690 +0x113 fp=0x5e483fe4 sp=0x5e483f84 pc=0x813ddf3 testing.(*F).Fuzz.func1.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:322 +0x28 fp=0x5e483ff0 sp=0x5e483fe4 pc=0x81353d8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e483ff4 sp=0x5e483ff0 pc=0x80bcc21 created by testing.(*F).Fuzz.func1 in goroutine 143 /tmp/workdir/go/src/testing/fuzz.go:322 +0x4df goroutine 131 gp=0x5e9005a8 m=nil [runnable]: runtime.gopark(0x827ca9c, 0x0, 0x9, 0x3, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x5e488e4c sp=0x5e488e38 pc=0x80b65aa runtime.selectgo(0x5e488f94, 0x5e488f58, 0x0, 0x1, 0x1, 0x1) /tmp/workdir/go/src/runtime/select.go:335 +0xb6f fp=0x5e488f38 sp=0x5e488e4c pc=0x809372f os/exec.(*Cmd).watchCtx(0x5e4a81a8, 0x5e41ecc0) /tmp/workdir/go/src/os/exec/exec.go:798 +0x268 fp=0x5e488fe4 sp=0x5e488f38 pc=0x8167718 os/exec.(*Cmd).Start.gowrap2() /tmp/workdir/go/src/os/exec/exec.go:754 +0x28 fp=0x5e488ff0 sp=0x5e488fe4 pc=0x8167268 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e488ff4 sp=0x5e488ff0 pc=0x80bcc21 created by os/exec.(*Cmd).Start in goroutine 128 /tmp/workdir/go/src/os/exec/exec.go:754 +0x8e9 FAIL internal/trace 180.789s ok internal/trace/internal/oldtrace 1.751s FAIL go tool dist: Failed: exit status 1