openbsd-386-72 at 73186ba00251b3ed8baaab36e4f5278c7681155b :: 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.203s ok archive/zip 0.479s ok bufio 0.085s ok bytes 0.464s ok cmp 0.015s ok compress/bzip2 0.094s ok compress/flate 0.854s ok compress/gzip 1.717s ok compress/lzw 0.109s ok compress/zlib 0.582s ok container/heap 0.017s ok container/list 0.016s ok container/ring 0.016s ok context 0.302s ok crypto 1.246s ok crypto/aes 0.035s ok crypto/cipher 0.020s ok crypto/des 0.028s ok crypto/dsa 0.018s ok crypto/ecdh 0.303s ok crypto/ecdsa 0.209s ok crypto/ed25519 0.338s ok crypto/elliptic 0.067s ok crypto/hmac 0.032s ok crypto/internal/alias 0.016s ok crypto/internal/bigmod 0.067s ok crypto/internal/boring 0.015s ok crypto/internal/boring/bcache 0.216s ok crypto/internal/edwards25519 0.529s ok crypto/internal/edwards25519/field 0.083s ok crypto/internal/hpke 0.020s ok crypto/internal/mlkem768 0.323s ok crypto/internal/nistec 1.091s ok crypto/internal/nistec/fiat 0.014s [no tests to run] ok crypto/md5 0.025s ok crypto/rand 0.128s ok crypto/rc4 0.056s ok crypto/rsa 1.113s ok crypto/sha1 0.019s ok crypto/sha256 0.019s ok crypto/sha512 0.023s ok crypto/subtle 0.287s ok crypto/tls 2.365s ok crypto/x509 2.554s ok database/sql 0.793s ok database/sql/driver 0.015s ok debug/buildinfo 0.025s ok debug/dwarf 0.062s ok debug/elf 0.237s ok debug/gosym 0.058s ok debug/macho 0.027s ok debug/pe 0.071s ok debug/plan9obj 0.017s ok embed 0.017s [no tests to run] ok embed/internal/embedtest 0.017s ok encoding/ascii85 0.016s 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.348s ok encoding/hex 0.018s ok encoding/json 0.469s ok encoding/pem 1.088s ok encoding/xml 0.096s ok errors 0.019s ok expvar 0.019s ok flag 0.069s ok fmt 0.105s ok go/ast 0.021s ok go/build 3.930s ok go/build/constraint 0.018s ok go/constant 0.020s ok go/doc 0.108s ok go/doc/comment 1.846s ok go/format 0.021s ok go/importer 0.617s ok go/internal/gccgoimporter 0.036s ok go/internal/gcimporter 4.084s ok go/internal/srcimporter 17.329s ok go/parser 0.497s ok go/printer 0.214s ok go/scanner 0.017s ok go/token 0.031s ok go/types 25.891s ok go/version 0.015s ok hash 0.017s ok hash/adler32 0.022s ok hash/crc32 0.019s ok hash/crc64 0.016s ok hash/fnv 0.015s ok hash/maphash 0.067s ok html 0.017s ok html/template 0.098s ok image 0.089s ok image/color 0.030s ok image/draw 0.101s ok image/gif 0.215s ok image/jpeg 0.226s ok image/png 0.345s ok index/suffixarray 0.220s ok internal/abi 0.159s ok internal/buildcfg 0.015s ok internal/chacha8rand 0.016s ok internal/concurrent 0.078s ok internal/coverage/cfile 1.687s 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.026s ok internal/cpu 0.037s ok internal/dag 0.016s ok internal/diff 0.022s ok internal/fmtsort 0.016s ok internal/fuzz 0.023s ok internal/godebug 1.108s ok internal/godebugs 8.713s ok internal/gover 0.015s ok internal/itoa 0.015s ok internal/platform 1.754s ok internal/poll 0.065s ok internal/profile 0.015s ok internal/reflectlite 0.018s ok internal/runtime/atomic 0.053s ok internal/saferio 0.108s ok internal/singleflight 0.081s ok internal/sysinfo 0.015s ok internal/testenv 0.412s --- FAIL: TestTraceStressStartStop (24.61s) --- FAIL: TestTraceStressStartStop/Default (24.61s) 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 (1.19s) --- FAIL: TestTraceManyStartStop/Default (1.19s) exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go trace_test.go:579: signal: killed --- 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=0x66c06ea8 m=0 mp=0x83ec680 [running]: panic({0x8238020, 0x687ec030}) /tmp/workdir/go/src/runtime/panic.go:804 +0x138 fp=0x66c35f84 sp=0x66c35f30 pc=0x80b61f8 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2373 +0x386 fp=0x66c35ff0 sp=0x66c35f84 pc=0x8142456 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c35ff4 sp=0x66c35ff0 pc=0x80bcc21 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:215 +0x29 goroutine 1 gp=0x66c06128 m=nil [chan receive]: runtime.gopark(0x827dacc, 0x66cffa34, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c86d24 sp=0x66c86d10 pc=0x80b65aa runtime.chanrecv(0x66cffa00, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x66c86d60 sp=0x66c86d24 pc=0x804db5d runtime.chanrecv1(0x66cffa00, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x66c86d74 sp=0x66c86d60 pc=0x804d76c testing.runFuzzTests({0x82bb9e0, 0x83f80a0}, {0x83e0880, 0x1, 0x1}, {0xc19b770a9e1b829a, 0x29e8eeac50, 0x83ebea0}) /tmp/workdir/go/src/testing/fuzz.go:528 +0x7e3 fp=0x66c86e08 sp=0x66c86d74 pc=0x81364a3 testing.(*M).Run(0x66c58050) /tmp/workdir/go/src/testing/testing.go:2035 +0x687 fp=0x66c86f54 sp=0x66c86e08 pc=0x813fa27 main.main() _testmain.go:99 +0xdf fp=0x66c86fac sp=0x66c86f54 pc=0x821bbcf runtime.main() /tmp/workdir/go/src/runtime/proc.go:272 +0x288 fp=0x66c86ff0 sp=0x66c86fac pc=0x80812a8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c86ff4 sp=0x66c86ff0 pc=0x80bcc21 goroutine 2 gp=0x66c06488 m=nil [force gc (idle), 3 minutes]: runtime.gopark(0x827dc38, 0x83ebb58, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c38fdc sp=0x66c38fc8 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:337 +0xc7 fp=0x66c38ff0 sp=0x66c38fdc pc=0x8081607 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c38ff4 sp=0x66c38ff0 pc=0x80bcc21 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:325 +0x1d goroutine 3 gp=0x66c065a8 m=nil [runnable]: runtime.gopark(0x827dc38, 0x83ebcf0, 0xc, 0x9, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c397cc sp=0x66c397b8 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.bgsweep(0x66c1e040) /tmp/workdir/go/src/runtime/mgcsweep.go:317 +0xec fp=0x66c397e8 sp=0x66c397cc pc=0x806b4ec runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:203 +0x21 fp=0x66c397f0 sp=0x66c397e8 pc=0x805cf61 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c397f4 sp=0x66c397f0 pc=0x80bcc21 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:203 +0x71 goroutine 4 gp=0x66c066c8 m=nil [sleep]: runtime.gopark(0x827dc38, 0x83ebfc0, 0x13, 0xe, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c39f68 sp=0x66c39f54 pc=0x80b65aa runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:430 runtime.(*scavengerState).sleep(0x83ebfc0, 0x4130982000000000) /tmp/workdir/go/src/runtime/mgcscavenge.go:504 +0x149 fp=0x66c39fcc sp=0x66c39f68 pc=0x8068cd9 runtime.bgscavenge(0x66c1e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:662 +0xa0 fp=0x66c39fe8 sp=0x66c39fcc pc=0x8069160 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x66c39ff0 sp=0x66c39fe8 pc=0x805cf21 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c39ff4 sp=0x66c39ff0 pc=0x80bcc21 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x66c06c68 m=nil [finalizer wait]: runtime.gopark(0x827daec, 0x83f80f4, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c3a798 sp=0x66c3a784 pc=0x80b65aa runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:193 +0xf0 fp=0x66c3a7f0 sp=0x66c3a798 pc=0x805c0c0 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c3a7f4 sp=0x66c3a7f0 pc=0x80bcc21 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:163 +0x5a goroutine 143 gp=0x66c06d88 m=nil [runnable]: runtime.gopark(0x827dacc, 0x68b74074, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c85d30 sp=0x66c85d1c pc=0x80b65aa runtime.chanrecv(0x68b74040, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x66c85d6c sp=0x66c85d30 pc=0x804db5d runtime.chanrecv1(0x68b74040, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x66c85d80 sp=0x66c85d6c pc=0x804d76c testing.(*F).Fuzz.func1({0x82ba3d0, 0x68b68008}, {{0x0, 0x0}, {0x68ad5590, 0x29}, {0x0, 0x0, 0x0}, {0x687ecd30, ...}, ...}) /tmp/workdir/go/src/testing/fuzz.go:337 +0x4f9 fp=0x66c85df8 sp=0x66c85d80 pc=0x81354b9 testing.(*F).Fuzz(0x68b68008, {0x8235220, 0x827da4c}) /tmp/workdir/go/src/testing/fuzz.go:402 +0xbb1 fp=0x66c85f74 sp=0x66c85df8 pc=0x8134a11 internal/trace_test.FuzzReader(0x68b68008) /tmp/workdir/go/src/internal/trace/reader_test.go:55 +0x2f fp=0x66c85f84 sp=0x66c85f74 pc=0x8210dff testing.fRunner(0x68b68008, 0x827d8e0) /tmp/workdir/go/src/testing/fuzz.go:724 +0xe4 fp=0x66c85fe4 sp=0x66c85f84 pc=0x8136f14 testing.runFuzzTests.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:527 +0x28 fp=0x66c85ff0 sp=0x66c85fe4 pc=0x81365e8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c85ff4 sp=0x66c85ff0 pc=0x80bcc21 created by testing.runFuzzTests in goroutine 1 /tmp/workdir/go/src/testing/fuzz.go:527 +0x7c9 goroutine 10 gp=0x66c070e8 m=nil [GC worker (idle)]: runtime.gopark(0x827dafc, 0x66c18c48, 0x1a, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:424 +0xfa fp=0x66c3878c sp=0x66c38778 pc=0x80b65aa runtime.gcBgMarkWorker(0x66c1fd80) /tmp/workdir/go/src/runtime/mgc.go:1363 +0xeb fp=0x66c387e8 sp=0x66c3878c pc=0x805f89b runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1279 +0x21 fp=0x66c387f0 sp=0x66c387e8 pc=0x805f791 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x66c387f4 sp=0x66c387f0 pc=0x80bcc21 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1279 +0x114 FAIL internal/trace 180.156s ok internal/trace/internal/oldtrace 2.087s FAIL go tool dist: Failed: exit status 1