openbsd-386-72 at ad4d9b046482a3a3fef19388a0d692ff4ebbe730 :: 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.209s ok archive/zip 0.479s ok bufio 0.087s ok bytes 0.442s ok cmp 0.015s ok compress/bzip2 0.091s ok compress/flate 0.844s ok compress/gzip 1.770s ok compress/lzw 0.109s ok compress/zlib 0.585s ok container/heap 0.017s ok container/list 0.015s ok container/ring 0.016s ok context 0.300s ok crypto 1.232s ok crypto/aes 0.036s ok crypto/cipher 0.020s ok crypto/des 0.029s ok crypto/dsa 0.018s ok crypto/ecdh 0.305s ok crypto/ecdsa 0.209s ok crypto/ed25519 0.335s ok crypto/elliptic 0.066s ok crypto/hmac 0.016s ok crypto/internal/alias 0.014s ok crypto/internal/bigmod 0.068s ok crypto/internal/boring 0.015s ok crypto/internal/boring/bcache 0.211s ok crypto/internal/edwards25519 0.531s ok crypto/internal/edwards25519/field 0.083s ok crypto/internal/hpke 0.020s ok crypto/internal/mlkem768 0.323s ok crypto/internal/nistec 1.086s ok crypto/internal/nistec/fiat 0.014s [no tests to run] ok crypto/md5 0.018s ok crypto/rand 0.130s ok crypto/rc4 0.056s ok crypto/rsa 1.104s ok crypto/sha1 0.018s ok crypto/sha256 0.018s ok crypto/sha512 0.021s ok crypto/subtle 0.285s ok crypto/tls 2.441s ok crypto/x509 2.608s ok database/sql 0.797s ok database/sql/driver 0.015s ok debug/buildinfo 0.025s ok debug/dwarf 0.061s ok debug/elf 0.242s ok debug/gosym 0.058s ok debug/macho 0.027s ok debug/pe 0.068s ok debug/plan9obj 0.017s ok embed 0.017s [no tests to run] ok embed/internal/embedtest 0.017s ok encoding/ascii85 0.017s ok encoding/asn1 0.020s ok encoding/base32 0.039s ok encoding/base64 0.019s ok encoding/binary 0.025s ok encoding/csv 0.028s ok encoding/gob 4.439s ok encoding/hex 0.023s ok encoding/json 0.463s ok encoding/pem 1.094s ok encoding/xml 0.096s ok errors 0.019s ok expvar 0.020s ok flag 0.070s ok fmt 0.105s ok go/ast 0.022s ok go/build 3.969s ok go/build/constraint 0.018s ok go/constant 0.020s ok go/doc 0.105s ok go/doc/comment 1.863s ok go/format 0.021s ok go/importer 0.614s ok go/internal/gccgoimporter 0.035s ok go/internal/gcimporter 4.092s ok go/internal/srcimporter 17.238s ok go/parser 0.499s ok go/printer 0.202s ok go/scanner 0.017s ok go/token 0.031s ok go/types 25.887s ok go/version 0.015s ok hash 0.017s ok hash/adler32 0.022s ok hash/crc32 0.020s ok hash/crc64 0.016s ok hash/fnv 0.016s ok hash/maphash 0.067s ok html 0.018s ok html/template 0.098s ok image 0.091s ok image/color 0.029s ok image/draw 0.103s ok image/gif 0.212s ok image/jpeg 0.225s ok image/png 0.346s ok index/suffixarray 0.221s ok internal/abi 0.168s ok internal/buildcfg 0.015s ok internal/chacha8rand 0.016s ok internal/concurrent 0.078s ok internal/coverage/cfile 0.016s ok internal/coverage/cformat 0.015s ok internal/coverage/cmerge 0.015s ok internal/coverage/pods 0.018s ok internal/coverage/slicereader 0.015s ok internal/coverage/slicewriter 0.015s ok internal/coverage/test 0.025s ok internal/cpu 0.036s ok internal/dag 0.016s ok internal/diff 0.022s ok internal/fmtsort 0.016s ok internal/fuzz 0.023s ok internal/godebug 1.091s ok internal/godebugs 8.706s ok internal/gover 0.015s ok internal/itoa 0.015s ok internal/platform 1.839s ok internal/poll 0.068s ok internal/profile 0.016s ok internal/reflectlite 0.018s ok internal/runtime/atomic 0.054s ok internal/saferio 0.106s ok internal/singleflight 0.085s ok internal/sysinfo 0.015s ok internal/testenv 0.423s --- FAIL: TestTraceStressStartStop (45.24s) --- FAIL: TestTraceStressStartStop/Default (45.24s) 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: TestTraceWaitOnPipe (1.25s) --- FAIL: TestTraceWaitOnPipe/Default (1.25s) exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go trace_test.go:577: stderr: SIGQUIT: quit PC=0x4ade9bd3 m=3 sigcode=0 goroutine 0 gp=0x6b008b48 m=3 mp=0x6b042a08 [idle]: runtime.thrsleep(0x6b042d04, 0x3, 0x0, 0x0, 0x6b042d04) runtime/sys_openbsd1.go:17 +0x21 fp=0x2747fde0 sp=0x2747fdd0 pc=0x809efb1 runtime.semasleep(0xffffffffffffffff) runtime/os_openbsd.go:118 +0xe2 fp=0x2747fe1c sp=0x2747fde0 pc=0x807df72 runtime.notesleep(0x6b042acc) runtime/lock_sema.go:183 +0x82 fp=0x2747fe38 sp=0x2747fe1c pc=0x8055cb2 runtime.mPark(...) runtime/proc.go:1866 runtime.stopm() runtime/proc.go:2885 +0x97 fp=0x2747fe4c sp=0x2747fe38 pc=0x80874b7 runtime.findRunnable() runtime/proc.go:3622 +0xc45 fp=0x2747ff18 sp=0x2747fe4c pc=0x8088cd5 runtime.schedule() runtime/proc.go:3995 +0xb1 fp=0x2747ff34 sp=0x2747ff18 pc=0x808a1d1 runtime.park_m(0x6b0085a8) runtime/proc.go:4102 +0x1c7 fp=0x2747ff5c sp=0x2747ff34 pc=0x808a5e7 runtime.mcall(0x40000) runtime/asm_386.s:329 +0x40 fp=0x2747ff64 sp=0x2747ff5c pc=0x80bdc10 goroutine 1 gp=0x6b008128 m=nil [semacquire]: runtime.gopark(0x87539f8, 0x8b6e480, 0x12, 0x5, 0x4) runtime/proc.go:424 +0xfa fp=0x6b0d7ae4 sp=0x6b0d7ad0 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.semacquire1(0x6b2ee0d8, 0x0, 0x1, 0x0, 0x12) runtime/sema.go:178 +0x285 fp=0x6b0d7b14 sp=0x6b0d7ae4 pc=0x8095af5 sync.runtime_Semacquire(0x6b2ee0d8) runtime/sema.go:71 +0x35 fp=0x6b0d7b2c sp=0x6b0d7b14 pc=0x80b9ad5 sync.(*WaitGroup).Wait(0x6b2ee0d0) sync/waitgroup.go:116 +0x5f fp=0x6b0d7b48 sp=0x6b0d7b2c pc=0x80cc3df cmd/go/internal/work.(*Builder).Do(0x6b078230, {0x880a750, 0x8b768c0}, 0x6b2f3b08) cmd/go/internal/work/exec.go:230 +0x42b fp=0x6b0d7be0 sp=0x6b0d7b48 pc=0x858bb9b cmd/go/internal/run.runRun({0x880a750, 0x8b768c0}, 0x8b61240, {0x6b01c028, 0x1, 0x1}) cmd/go/internal/run/run.go:174 +0x7ff fp=0x6b0d7c94 sp=0x6b0d7be0 pc=0x85d853f main.invoke(0x8b61240, {0x6b01c020, 0x2, 0x2}) cmd/go/main.go:297 +0x8b8 fp=0x6b0d7e68 sp=0x6b0d7c94 pc=0x86218b8 main.main() cmd/go/main.go:211 +0x1013 fp=0x6b0d7fac sp=0x6b0d7e68 pc=0x8620c13 runtime.main() runtime/proc.go:272 +0x288 fp=0x6b0d7ff0 sp=0x6b0d7fac pc=0x8082c68 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b0d7ff4 sp=0x6b0d7ff0 pc=0x80befa1 goroutine 2 gp=0x6b008488 m=nil [force gc (idle)]: runtime.gopark(0x87539f8, 0x8b68240, 0x11, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x6b03efdc sp=0x6b03efc8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.forcegchelper() runtime/proc.go:337 +0xc7 fp=0x6b03eff0 sp=0x6b03efdc pc=0x8082fc7 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b03eff4 sp=0x6b03eff0 pc=0x80befa1 created by runtime.init.6 in goroutine 1 runtime/proc.go:325 +0x1d goroutine 3 gp=0x6b0085a8 m=nil [GC sweep wait]: runtime.gopark(0x87539f8, 0x8b68710, 0xc, 0x9, 0x1) runtime/proc.go:424 +0xfa fp=0x6b03f7cc sp=0x6b03f7b8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.bgsweep(0x6b024040) runtime/mgcsweep.go:317 +0xec fp=0x6b03f7e8 sp=0x6b03f7cc pc=0x806f02c runtime.gcenable.gowrap1() runtime/mgc.go:203 +0x21 fp=0x6b03f7f0 sp=0x6b03f7e8 pc=0x8060a41 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b03f7f4 sp=0x6b03f7f0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:203 +0x71 goroutine 4 gp=0x6b0086c8 m=nil [GC scavenge wait]: runtime.gopark(0x87539f8, 0x8b691a0, 0xd, 0xa, 0x2) runtime/proc.go:424 +0xfa fp=0x6b03ffb8 sp=0x6b03ffa4 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.(*scavengerState).park(0x8b691a0) runtime/mgcscavenge.go:425 +0x54 fp=0x6b03ffcc sp=0x6b03ffb8 pc=0x806c614 runtime.bgscavenge(0x6b024040) runtime/mgcscavenge.go:658 +0x69 fp=0x6b03ffe8 sp=0x6b03ffcc pc=0x806cc69 runtime.gcenable.gowrap2() runtime/mgc.go:204 +0x21 fp=0x6b03fff0 sp=0x6b03ffe8 pc=0x8060a01 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b03fff4 sp=0x6b03fff0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x6b008c68 m=nil [finalizer wait]: runtime.gopark(0x87538ac, 0x8b76988, 0x10, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x6b03e798 sp=0x6b03e784 pc=0x80b862a runtime.runfinq() runtime/mfinal.go:193 +0xf0 fp=0x6b03e7f0 sp=0x6b03e798 pc=0x805fba0 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b03e7f4 sp=0x6b03e7f0 pc=0x80befa1 created by runtime.createfing in goroutine 1 runtime/mfinal.go:163 +0x5a goroutine 6 gp=0x6b008fc8 m=nil [chan receive]: runtime.gopark(0x875388c, 0x6b0242b4, 0xe, 0x7, 0x2) runtime/proc.go:424 +0xfa fp=0x6b040790 sp=0x6b04077c pc=0x80b862a runtime.chanrecv(0x6b024280, 0x0, 0x1) runtime/chan.go:639 +0x3bd fp=0x6b0407cc sp=0x6b040790 pc=0x8050e4d runtime.chanrecv1(0x6b024280, 0x0) runtime/chan.go:489 +0x1c fp=0x6b0407e0 sp=0x6b0407cc pc=0x8050a5c runtime.unique_runtime_registerUniqueMapCleanup.func1(...) runtime/mgc.go:1732 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() runtime/mgc.go:1735 +0x34 fp=0x6b0407f0 sp=0x6b0407e0 pc=0x80641e4 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b0407f4 sp=0x6b0407f0 pc=0x80befa1 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 runtime/mgc.go:1730 +0x96 goroutine 8 gp=0x6b009208 m=nil [runnable]: syscall.syscall(0x80d9920, 0x3b, 0x6b22ca4f, 0x1) runtime/sys_openbsd3.go:28 +0x20 fp=0x6b0d5350 sp=0x6b0d5340 pc=0x80bc390 syscall.read(0x3b, {0x6b22ca4f, 0x1, 0x1}) syscall/zsyscall_openbsd_386.go:1192 +0x49 fp=0x6b0d5378 sp=0x6b0d5350 pc=0x80d7f29 syscall.Read(...) syscall/syscall_unix.go:183 internal/poll.ignoringEINTRIO(...) internal/poll/fd_unix.go:745 internal/poll.(*FD).Read(0x6b2feac0, {0x6b22ca4f, 0x1, 0x1}) internal/poll/fd_unix.go:161 +0x229 fp=0x6b0d53c0 sp=0x6b0d5378 pc=0x8134e59 os.(*File).read(...) os/file_posix.go:29 os.(*File).Read(0x6b37ec80, {0x6b22ca4f, 0x1, 0x1}) os/file.go:124 +0x6a fp=0x6b0d53e4 sp=0x6b0d53c0 pc=0x813d6ca io.ReadAtLeast({0x88073c0, 0x6b37ec80}, {0x6b22c9a0, 0xb0, 0xb0}, 0xb0) io/io.go:335 +0x91 fp=0x6b0d5414 sp=0x6b0d53e4 pc=0x812c531 io.ReadFull(...) io/io.go:354 cmd/go/internal/cache.(*DiskCache).get(0x6b012810, {0xf3, 0x62, 0x41, 0x3a, 0x5f, 0xef, 0xe8, 0x42, 0x7, ...}) cmd/go/internal/cache/cache.go:210 +0x1cb fp=0x6b0d5564 sp=0x6b0d5414 pc=0x8223c9b cmd/go/internal/cache.(*DiskCache).Get(0x6b012810, {0xf3, 0x62, 0x41, 0x3a, 0x5f, 0xef, 0xe8, 0x42, 0x7, ...}) cmd/go/internal/cache/cache.go:190 +0xc6 fp=0x6b0d5614 sp=0x6b0d5564 pc=0x8223a76 cmd/go/internal/cache.GetFile({0x880aa98, 0x6b012810}, {0xf3, 0x62, 0x41, 0x3a, 0x5f, 0xef, 0xe8, 0x42, ...}) cmd/go/internal/cache/cache.go:265 +0x57 fp=0x6b0d56d0 sp=0x6b0d5614 pc=0x8224b47 cmd/go/internal/work.(*Builder).useCache(0x6b078230, 0x6b2f20c8, {0xf3, 0x62, 0x41, 0x3a, 0x5f, 0xef, 0xe8, 0x42, ...}, ...) cmd/go/internal/work/buildid.go:553 +0x749 fp=0x6b0d5808 sp=0x6b0d56d0 pc=0x8588959 cmd/go/internal/work.(*Builder).build(0x6b078230, {0x880a750, 0x8b768c0}, 0x6b2f20c8) cmd/go/internal/work/exec.go:474 +0x313 fp=0x6b0d5e84 sp=0x6b0d5808 pc=0x858f373 cmd/go/internal/work.(*buildActor).Act(0x6b2cd2f8, 0x6b078230, {0x880a750, 0x8b768c0}, 0x6b2f20c8) cmd/go/internal/work/action.go:461 +0x33 fp=0x6b0d5ea0 sp=0x6b0d5e84 pc=0x857e623 cmd/go/internal/work.(*Builder).Do.func3({0x880a750, 0x8b768c0}, 0x6b2f20c8) cmd/go/internal/work/exec.go:152 +0x801 fp=0x6b0d5f88 sp=0x6b0d5ea0 pc=0x858c6e1 cmd/go/internal/work.(*Builder).Do.func4() cmd/go/internal/work/exec.go:221 +0xae fp=0x6b0d5ff0 sp=0x6b0d5f88 pc=0x858bd7e runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b0d5ff4 sp=0x6b0d5ff0 pc=0x80befa1 created by cmd/go/internal/work.(*Builder).Do in goroutine 1 cmd/go/internal/work/exec.go:207 +0x38c goroutine 13 gp=0x6b2defc8 m=nil [GC worker (idle)]: runtime.gopark(0x87538b8, 0x6b2ad938, 0x1a, 0xa, 0x0) runtime/proc.go:424 +0xfa fp=0x6b04178c sp=0x6b041778 pc=0x80b862a runtime.gcBgMarkWorker(0x6b235980) runtime/mgc.go:1363 +0xeb fp=0x6b0417e8 sp=0x6b04178c pc=0x806337b runtime.gcBgMarkStartWorkers.gowrap1() runtime/mgc.go:1279 +0x21 fp=0x6b0417f0 sp=0x6b0417e8 pc=0x8063271 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6b0417f4 sp=0x6b0417f0 pc=0x80befa1 created by runtime.gcBgMarkStartWorkers in goroutine 8 runtime/mgc.go:1279 +0x114 eax 0x58 ebx 0x6b042d04 ecx 0x0 edx 0x2747fde0 edi 0x1ac esi 0x6b008b48 ebp 0x2747fd88 esp 0x2747fd70 eip 0x4ade9bd3 eflags 0x206 cs 0x2b fs 0x5b gs 0x63 trace_test.go:579: exit status 2 --- FAIL: TestTraceIterPull (0.00s) --- FAIL: TestTraceIterPull/Default (0.00s) trace_test.go:579: context deadline exceeded panic: test timed out after 3m0s goroutine 154 gp=0x6ed00a28 m=3 mp=0x6e83ca08 [running]: panic({0x8236ec0, 0x703acf58}) /tmp/workdir/go/src/runtime/panic.go:804 +0x138 fp=0x6e835f84 sp=0x6e835f30 pc=0x80b61f8 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2373 +0x386 fp=0x6e835ff0 sp=0x6e835f84 pc=0x8142166 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e835ff4 sp=0x6e835ff0 pc=0x80bcc21 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:214 +0x29 goroutine 1 gp=0x6e806128 m=nil [chan receive]: runtime.gopark(0x827c8e0, 0x6e81ea34, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e886d24 sp=0x6e886d10 pc=0x80b65aa runtime.chanrecv(0x6e81ea00, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x6e886d60 sp=0x6e886d24 pc=0x804db5d runtime.chanrecv1(0x6e81ea00, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x6e886d74 sp=0x6e886d60 pc=0x804d76c testing.runFuzzTests({0x82ba720, 0x83f7040}, {0x83df878, 0x1, 0x1}, {0xc18ff249b52ad916, 0x29e8eecb2a, 0x83eae40}) /tmp/workdir/go/src/testing/fuzz.go:528 +0x7e3 fp=0x6e886e08 sp=0x6e886d74 pc=0x81361b3 testing.(*M).Run(0x6e858050) /tmp/workdir/go/src/testing/testing.go:2035 +0x687 fp=0x6e886f54 sp=0x6e886e08 pc=0x813f737 main.main() _testmain.go:99 +0xdf fp=0x6e886fac sp=0x6e886f54 pc=0x821b7ff runtime.main() /tmp/workdir/go/src/runtime/proc.go:272 +0x288 fp=0x6e886ff0 sp=0x6e886fac pc=0x80812a8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e886ff4 sp=0x6e886ff0 pc=0x80bcc21 goroutine 2 gp=0x6e806488 m=nil [force gc (idle), 3 minutes]: runtime.gopark(0x827ca4c, 0x83eab08, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e838fdc sp=0x6e838fc8 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:337 +0xc7 fp=0x6e838ff0 sp=0x6e838fdc pc=0x8081607 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e838ff4 sp=0x6e838ff0 pc=0x80bcc21 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:325 +0x1d goroutine 3 gp=0x6e8065a8 m=nil [GC sweep wait]: runtime.gopark(0x827ca4c, 0x83eaca0, 0xc, 0x9, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e8397cc sp=0x6e8397b8 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.bgsweep(0x6e81e040) /tmp/workdir/go/src/runtime/mgcsweep.go:317 +0xec fp=0x6e8397e8 sp=0x6e8397cc pc=0x806b4ec runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:203 +0x21 fp=0x6e8397f0 sp=0x6e8397e8 pc=0x805cf61 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e8397f4 sp=0x6e8397f0 pc=0x80bcc21 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:203 +0x71 goroutine 4 gp=0x6e8066c8 m=nil [GC scavenge wait]: runtime.gopark(0x827ca4c, 0x83eaf60, 0xd, 0xa, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e839fb8 sp=0x6e839fa4 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=0x6e839fcc sp=0x6e839fb8 pc=0x8068ad4 runtime.bgscavenge(0x6e81e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:658 +0x69 fp=0x6e839fe8 sp=0x6e839fcc pc=0x8069129 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x6e839ff0 sp=0x6e839fe8 pc=0x805cf21 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e839ff4 sp=0x6e839ff0 pc=0x80bcc21 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x6e806c68 m=nil [finalizer wait, 2 minutes]: runtime.gopark(0x827c900, 0x83f7094, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e83a798 sp=0x6e83a784 pc=0x80b65aa runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:193 +0xf0 fp=0x6e83a7f0 sp=0x6e83a798 pc=0x805c0c0 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e83a7f4 sp=0x6e83a7f0 pc=0x80bcc21 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:163 +0x5a goroutine 146 gp=0x6e806d88 m=nil [chan receive]: runtime.gopark(0x827c8e0, 0x6e81f134, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e885d30 sp=0x6e885d1c pc=0x80b65aa runtime.chanrecv(0x6e81f100, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x6e885d6c sp=0x6e885d30 pc=0x804db5d runtime.chanrecv1(0x6e81f100, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x6e885d80 sp=0x6e885d6c pc=0x804d76c testing.(*F).Fuzz.func1({0x82b9110, 0x6eca2008}, {{0x0, 0x0}, {0x7432e9f0, 0x29}, {0x0, 0x0, 0x0}, {0x703acc00, ...}, ...}) /tmp/workdir/go/src/testing/fuzz.go:337 +0x4f9 fp=0x6e885df8 sp=0x6e885d80 pc=0x81351c9 testing.(*F).Fuzz(0x6eca2008, {0x8234100, 0x827c860}) /tmp/workdir/go/src/testing/fuzz.go:402 +0xbb1 fp=0x6e885f74 sp=0x6e885df8 pc=0x8134721 internal/trace_test.FuzzReader(0x6eca2008) /tmp/workdir/go/src/internal/trace/reader_test.go:55 +0x2f fp=0x6e885f84 sp=0x6e885f74 pc=0x8210a2f testing.fRunner(0x6eca2008, 0x827c6f4) /tmp/workdir/go/src/testing/fuzz.go:724 +0xe4 fp=0x6e885fe4 sp=0x6e885f84 pc=0x8136c24 testing.runFuzzTests.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:527 +0x28 fp=0x6e885ff0 sp=0x6e885fe4 pc=0x81362f8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e885ff4 sp=0x6e885ff0 pc=0x80bcc21 created by testing.runFuzzTests in goroutine 1 /tmp/workdir/go/src/testing/fuzz.go:527 +0x7c9 goroutine 10 gp=0x6e8070e8 m=nil [GC worker (idle), 1 minutes]: runtime.gopark(0x827c910, 0x6e818c48, 0x1a, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x6e83878c sp=0x6e838778 pc=0x80b65aa runtime.gcBgMarkWorker(0x6e81fd80) /tmp/workdir/go/src/runtime/mgc.go:1363 +0xeb fp=0x6e8387e8 sp=0x6e83878c pc=0x805f89b runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1279 +0x21 fp=0x6e8387f0 sp=0x6e8387e8 pc=0x805f791 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e8387f4 sp=0x6e8387f0 pc=0x80bcc21 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1279 +0x114 goroutine 153 gp=0x6e806ea8 m=nil [runnable]: runtime.goschedguarded(...) /tmp/workdir/go/src/runtime/proc.go:361 runtime.memclrNoHeapPointersChunked(0x1e00000, 0x74600000) /tmp/workdir/go/src/runtime/malloc.go:1371 +0x5e fp=0x6e8875b8 sp=0x6e8875a4 pc=0x8053cbe runtime.mallocgc(0x1e00000, 0x8236140, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1265 +0x7cb fp=0x6e887630 sp=0x6e8875b8 pc=0x80b234b runtime.newobject(0x8236140) /tmp/workdir/go/src/runtime/malloc.go:1386 +0x26 fp=0x6e887644 sp=0x6e887630 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(0x6ee2c780) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:476 +0x4f2 fp=0x6e8879a4 sp=0x6e887644 pc=0x81d0522 internal/trace/internal/oldtrace.(*parser).parse(0x6ee2c780) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:212 +0x1d0 fp=0x6e887a48 sp=0x6e8879a4 pc=0x81cfa50 internal/trace/internal/oldtrace.Parse({0x82b9140, 0x6ee56cc0}, 0xb) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:176 +0x96 fp=0x6e887ac4 sp=0x6e887a48 pc=0x81cf836 internal/trace.NewReader({0x82b9160, 0x6f67e3d8}) /tmp/workdir/go/src/internal/trace/reader.go:43 +0x19e fp=0x6e887b9c sp=0x6e887ac4 pc=0x81f4c3e internal/trace_test.FuzzReader.func1(0x6ef85b08, {0x6ed7ab40, 0x17, 0x18}) /tmp/workdir/go/src/internal/trace/reader_test.go:56 +0x8a fp=0x6e887c54 sp=0x6e887b9c pc=0x821a3ca runtime.call16(0x6e85c8e0, 0x827c860, 0x6ee48050, 0x10, 0x10, 0x10, 0x6e887d00) /tmp/workdir/go/src/runtime/asm_386.s:574 +0x2a fp=0x6e887c68 sp=0x6e887c54 pc=0x80bbbba reflect.Value.call({0x8234100, 0x827c860, 0x13}, {0x826707a, 0x4}, {0x6f67e3c0, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:581 +0x9db fp=0x6e887eb0 sp=0x6e887c68 pc=0x81050db reflect.Value.Call({0x8234100, 0x827c860, 0x13}, {0x6f67e3c0, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:365 +0x79 fp=0x6e887eec sp=0x6e887eb0 pc=0x8104669 testing.(*F).Fuzz.func1.1(0x6ef85b08) /tmp/workdir/go/src/testing/fuzz.go:335 +0x2bc fp=0x6e887f84 sp=0x6e887eec pc=0x813565c testing.tRunner(0x6ef85b08, 0x6eac0500) /tmp/workdir/go/src/testing/testing.go:1690 +0x113 fp=0x6e887fe4 sp=0x6e887f84 pc=0x813dda3 testing.(*F).Fuzz.func1.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:322 +0x28 fp=0x6e887ff0 sp=0x6e887fe4 pc=0x8135388 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e887ff4 sp=0x6e887ff0 pc=0x80bcc21 created by testing.(*F).Fuzz.func1 in goroutine 146 /tmp/workdir/go/src/testing/fuzz.go:322 +0x4df FAIL internal/trace 180.765s ok internal/trace/internal/oldtrace 1.423s FAIL go tool dist: Failed: exit status 1