openbsd-386-72 at d102c2ed01b0aaa1278371fb1c1d2f0cd6a71f0d :: 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.209s ok archive/zip 0.465s ok bufio 0.085s ok bytes 0.465s ok cmp 0.016s ok compress/bzip2 0.097s ok compress/flate 0.862s ok compress/gzip 1.732s ok compress/lzw 0.111s ok compress/zlib 0.614s ok container/heap 0.018s ok container/list 0.017s ok container/ring 0.017s ok context 0.294s ok crypto 1.243s ok crypto/aes 0.041s ok crypto/cipher 2.310s ok crypto/des 0.032s ok crypto/dsa 0.022s ok crypto/ecdh 0.305s ok crypto/ecdsa 0.210s ok crypto/ed25519 0.343s ok crypto/elliptic 0.066s ok crypto/hmac 0.033s ok crypto/internal/alias 0.015s ok crypto/internal/bigmod 0.068s ok crypto/internal/boring 0.015s ok crypto/internal/boring/bcache 0.229s ok crypto/internal/edwards25519 0.531s ok crypto/internal/edwards25519/field 0.084s ok crypto/internal/hpke 0.028s ok crypto/internal/mlkem768 0.325s ok crypto/internal/nistec 1.089s ok crypto/internal/nistec/fiat 0.015s [no tests to run] ok crypto/md5 0.019s ok crypto/rand 0.135s ok crypto/rc4 0.066s ok crypto/rsa 1.123s ok crypto/sha1 0.019s ok crypto/sha256 0.019s ok crypto/sha512 0.024s ok crypto/subtle 0.285s ok crypto/tls 2.343s ok crypto/x509 2.726s ok database/sql 0.799s ok database/sql/driver 0.016s ok debug/buildinfo 0.094s ok debug/dwarf 0.063s ok debug/elf 0.222s ok debug/gosym 0.061s ok debug/macho 0.027s ok debug/pe 0.069s ok debug/plan9obj 0.018s ok embed 0.017s [no tests to run] ok embed/internal/embedtest 0.018s ok encoding/ascii85 0.017s ok encoding/asn1 0.020s ok encoding/base32 0.040s ok encoding/base64 0.019s ok encoding/binary 0.026s ok encoding/csv 0.029s ok encoding/gob 4.403s ok encoding/hex 0.018s ok encoding/json 0.471s ok encoding/pem 1.097s ok encoding/xml 0.101s ok errors 0.020s ok expvar 0.020s ok flag 0.072s ok fmt 0.108s ok go/ast 0.022s ok go/build 4.058s ok go/build/constraint 0.020s ok go/constant 0.020s ok go/doc 0.113s ok go/doc/comment 1.871s ok go/format 0.021s ok go/importer 0.620s ok go/internal/gccgoimporter 0.038s ok go/internal/gcimporter 4.071s ok go/internal/srcimporter 17.465s ok go/parser 0.503s ok go/printer 0.218s ok go/scanner 0.017s ok go/token 0.032s ok go/types 26.650s ok go/version 0.016s ok hash 0.017s ok hash/adler32 0.023s ok hash/crc32 0.021s ok hash/crc64 0.017s ok hash/fnv 0.017s ok hash/maphash 0.067s ok html 0.018s ok html/template 0.107s ok image 0.093s ok image/color 0.031s ok image/draw 0.104s ok image/gif 0.223s ok image/jpeg 0.227s ok image/png 0.348s ok index/suffixarray 0.221s ok internal/abi 0.162s ok internal/buildcfg 0.016s ok internal/chacha8rand 0.016s ok internal/concurrent 0.079s ok internal/coverage/cfile 1.682s ok internal/coverage/cformat 0.019s ok internal/coverage/cmerge 0.015s ok internal/coverage/pods 0.019s ok internal/coverage/slicereader 0.015s ok internal/coverage/slicewriter 0.015s ok internal/coverage/test 0.025s ok internal/cpu 0.037s ok internal/dag 0.016s ok internal/diff 0.023s ok internal/fmtsort 0.016s ok internal/fuzz 0.023s ok internal/godebug 1.177s ok internal/godebugs 8.707s ok internal/gover 0.016s ok internal/itoa 0.015s ok internal/pkgbits 0.016s ok internal/platform 1.792s ok internal/poll 0.079s ok internal/profile 0.016s ok internal/reflectlite 0.019s ok internal/runtime/atomic 0.054s ok internal/runtime/math 0.015s ok internal/runtime/sys 0.015s ok internal/saferio 0.109s ok internal/singleflight 0.089s ok internal/sysinfo 0.015s ok internal/testenv 0.423s --- FAIL: TestTraceStressStartStop (114.78s) --- FAIL: TestTraceStressStartStop/Default (114.78s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go trace_test.go:616: signal: killed --- FAIL: TestTraceIterPull (1.30s) --- FAIL: TestTraceIterPull/Default (1.30s) exec.go:213: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/iter-pull.go trace_test.go:614: stderr: SIGQUIT: quit trace_test.go:616: signal: killed panic: test timed out after 3m0s goroutine 157 gp=0x638078c8 m=3 mp=0x6383ca08 [running]: panic({0x823a580, 0x657c8628}) /tmp/workdir/go/src/runtime/panic.go:804 +0x138 fp=0x63835f84 sp=0x63835f30 pc=0x80b63a8 testing.(*M).startAlarm.func1() /tmp/workdir/go/src/testing/testing.go:2456 +0x37f fp=0x63835ff0 sp=0x63835f84 pc=0x8142caf runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x63835ff4 sp=0x63835ff0 pc=0x80bcdf1 created by time.goFunc /tmp/workdir/go/src/time/sleep.go:215 +0x29 goroutine 1 gp=0x63806128 m=nil [chan receive]: runtime.gopark(0x8280c7c, 0x6692a274, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x63866d24 sp=0x63866d10 pc=0x80b675a runtime.chanrecv(0x6692a240, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x63866d60 sp=0x63866d24 pc=0x804db8d runtime.chanrecv1(0x6692a240, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x63866d74 sp=0x63866d60 pc=0x804d79c testing.runFuzzTests({0x82bf300, 0x83fe080}, {0x83e6840, 0x1, 0x1}, {0xc1ae5ac097951868, 0x29e8f92f76, 0x83f1e80}) /tmp/workdir/go/src/testing/fuzz.go:528 +0x7f3 fp=0x63866e08 sp=0x63866d74 pc=0x8136c13 testing.(*M).Run(0x63858050) /tmp/workdir/go/src/testing/testing.go:2115 +0x685 fp=0x63866f54 sp=0x63866e08 pc=0x8140215 main.main() _testmain.go:99 +0xdf fp=0x63866fac sp=0x63866f54 pc=0x821e8ff runtime.main() /tmp/workdir/go/src/runtime/proc.go:283 +0x288 fp=0x63866ff0 sp=0x63866fac pc=0x80814b8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x63866ff4 sp=0x63866ff0 pc=0x80bcdf1 goroutine 2 gp=0x63806488 m=nil [force gc (idle), 2 minutes]: runtime.gopark(0x8280de8, 0x83f1b38, 0x11, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x63838fdc sp=0x63838fc8 pc=0x80b675a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.forcegchelper() /tmp/workdir/go/src/runtime/proc.go:348 +0xc7 fp=0x63838ff0 sp=0x63838fdc pc=0x8081817 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x63838ff4 sp=0x63838ff0 pc=0x80bcdf1 created by runtime.init.6 in goroutine 1 /tmp/workdir/go/src/runtime/proc.go:336 +0x1d goroutine 3 gp=0x638065a8 m=nil [GC sweep wait]: runtime.gopark(0x8280de8, 0x83f1ce0, 0xc, 0x9, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x638397cc sp=0x638397b8 pc=0x80b675a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.bgsweep(0x6381e040) /tmp/workdir/go/src/runtime/mgcsweep.go:317 +0xec fp=0x638397e8 sp=0x638397cc pc=0x806b42c runtime.gcenable.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:203 +0x21 fp=0x638397f0 sp=0x638397e8 pc=0x805cf01 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x638397f4 sp=0x638397f0 pc=0x80bcdf1 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:203 +0x71 goroutine 4 gp=0x638066c8 m=nil [runnable]: runtime.gopark(0x8280de8, 0x83f1fa0, 0x13, 0xe, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x63839f68 sp=0x63839f54 pc=0x80b675a runtime.goparkunlock(...) /tmp/workdir/go/src/runtime/proc.go:441 runtime.(*scavengerState).sleep(0x83f1fa0, 0x412e938800000000) /tmp/workdir/go/src/runtime/mgcscavenge.go:504 +0x149 fp=0x63839fcc sp=0x63839f68 pc=0x8068c79 runtime.bgscavenge(0x6381e040) /tmp/workdir/go/src/runtime/mgcscavenge.go:662 +0xa0 fp=0x63839fe8 sp=0x63839fcc pc=0x8069100 runtime.gcenable.gowrap2() /tmp/workdir/go/src/runtime/mgc.go:204 +0x21 fp=0x63839ff0 sp=0x63839fe8 pc=0x805cec1 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x63839ff4 sp=0x63839ff0 pc=0x80bcdf1 created by runtime.gcenable in goroutine 1 /tmp/workdir/go/src/runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x63806c68 m=nil [finalizer wait]: runtime.gopark(0x8280c9c, 0x83fe0d0, 0x10, 0xa, 0x1) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6383a798 sp=0x6383a784 pc=0x80b675a runtime.runfinq() /tmp/workdir/go/src/runtime/mfinal.go:193 +0xf0 fp=0x6383a7f0 sp=0x6383a798 pc=0x805c060 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6383a7f4 sp=0x6383a7f0 pc=0x80bcdf1 created by runtime.createfing in goroutine 1 /tmp/workdir/go/src/runtime/mfinal.go:163 +0x5a goroutine 149 gp=0x63806fc8 m=nil [chan receive]: runtime.gopark(0x8280c7c, 0x6692a934, 0xe, 0x7, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x63865d30 sp=0x63865d1c pc=0x80b675a runtime.chanrecv(0x6692a900, 0x0, 0x1) /tmp/workdir/go/src/runtime/chan.go:639 +0x3bd fp=0x63865d6c sp=0x63865d30 pc=0x804db8d runtime.chanrecv1(0x6692a900, 0x0) /tmp/workdir/go/src/runtime/chan.go:489 +0x1c fp=0x63865d80 sp=0x63865d6c pc=0x804d79c testing.(*F).Fuzz.func1({0x82bdce0, 0x639e8008}, {{0x0, 0x0}, {0x639cc570, 0x29}, {0x0, 0x0, 0x0}, {0x657c82d0, ...}, ...}) /tmp/workdir/go/src/testing/fuzz.go:337 +0x502 fp=0x63865df8 sp=0x63865d80 pc=0x8135c22 testing.(*F).Fuzz(0x639e8008, {0x8237600, 0x8280bf8}) /tmp/workdir/go/src/testing/fuzz.go:402 +0xbb1 fp=0x63865f74 sp=0x63865df8 pc=0x8135181 internal/trace_test.FuzzReader(0x639e8008) /tmp/workdir/go/src/internal/trace/reader_test.go:55 +0x2f fp=0x63865f84 sp=0x63865f74 pc=0x82135df testing.fRunner(0x639e8008, 0x8280a8c) /tmp/workdir/go/src/testing/fuzz.go:724 +0xe4 fp=0x63865fe4 sp=0x63865f84 pc=0x8137684 testing.runFuzzTests.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:527 +0x28 fp=0x63865ff0 sp=0x63865fe4 pc=0x8136d58 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x63865ff4 sp=0x63865ff0 pc=0x80bcdf1 created by testing.runFuzzTests in goroutine 1 /tmp/workdir/go/src/testing/fuzz.go:527 +0x7d9 goroutine 10 gp=0x63807688 m=nil [GC worker (idle), 2 minutes]: runtime.gopark(0x8280cac, 0x63818c48, 0x1a, 0xa, 0x0) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x6383878c sp=0x63838778 pc=0x80b675a runtime.gcBgMarkWorker(0x6381fd80) /tmp/workdir/go/src/runtime/mgc.go:1363 +0xeb fp=0x638387e8 sp=0x6383878c pc=0x805f82b runtime.gcBgMarkStartWorkers.gowrap1() /tmp/workdir/go/src/runtime/mgc.go:1279 +0x21 fp=0x638387f0 sp=0x638387e8 pc=0x805f721 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x638387f4 sp=0x638387f0 pc=0x80bcdf1 created by runtime.gcBgMarkStartWorkers in goroutine 9 /tmp/workdir/go/src/runtime/mgc.go:1279 +0x114 goroutine 156 gp=0x63807328 m=nil [runnable]: runtime.goschedguarded(...) /tmp/workdir/go/src/runtime/proc.go:372 runtime.memclrNoHeapPointersChunked(0x1e00000, 0x66948000) /tmp/workdir/go/src/runtime/malloc.go:1371 +0x5e fp=0x638a35d0 sp=0x638a35bc pc=0x8053c9e runtime.mallocgc(0x1e00000, 0x8239640, 0x1) /tmp/workdir/go/src/runtime/malloc.go:1265 +0x7cd fp=0x638a3648 sp=0x638a35d0 pc=0x80b24fd runtime.newobject(0x8239640) /tmp/workdir/go/src/runtime/malloc.go:1386 +0x26 fp=0x638a365c sp=0x638a3648 pc=0x8053cf6 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(0x63876f00) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:476 +0x4f2 fp=0x638a39b8 sp=0x638a365c pc=0x81d1392 internal/trace/internal/oldtrace.(*parser).parse(0x63876f00) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:212 +0x1d0 fp=0x638a3a5c sp=0x638a39b8 pc=0x81d08c0 internal/trace/internal/oldtrace.Parse({0x82bdd10, 0x63d08b40}, 0xb) /tmp/workdir/go/src/internal/trace/internal/oldtrace/parser.go:176 +0x96 fp=0x638a3ad8 sp=0x638a3a5c pc=0x81d06a6 internal/trace.NewReader({0x82bdd30, 0x64674768}) /tmp/workdir/go/src/internal/trace/reader.go:43 +0x19e fp=0x638a3bb0 sp=0x638a3ad8 pc=0x81f5aee internal/trace_test.FuzzReader.func1(0x63d0eea8, {0x69876138, 0x17, 0x18}) /tmp/workdir/go/src/internal/trace/reader_test.go:56 +0x8a fp=0x638a3c58 sp=0x638a3bb0 pc=0x821d4ca runtime.call16(0x651b25c0, 0x8280bf8, 0x6b864780, 0x10, 0x10, 0x10, 0x638a3d00) /tmp/workdir/go/src/runtime/asm_386.s:574 +0x2a fp=0x638a3c6c sp=0x638a3c58 pc=0x80bbd8a reflect.Value.call({0x8237600, 0x8280bf8, 0x13}, {0x826b23e, 0x4}, {0x64674750, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:581 +0x97c fp=0x638a3eb0 sp=0x638a3c6c pc=0x81061cc reflect.Value.Call({0x8237600, 0x8280bf8, 0x13}, {0x64674750, 0x2, 0x2}) /tmp/workdir/go/src/reflect/value.go:365 +0x79 fp=0x638a3eec sp=0x638a3eb0 pc=0x81057b9 testing.(*F).Fuzz.func1.1(0x63d0eea8) /tmp/workdir/go/src/testing/fuzz.go:335 +0x2bc fp=0x638a3f84 sp=0x638a3eec pc=0x81360ac testing.tRunner(0x63d0eea8, 0x63a14320) /tmp/workdir/go/src/testing/testing.go:1764 +0x113 fp=0x638a3fe4 sp=0x638a3f84 pc=0x813e803 testing.(*F).Fuzz.func1.gowrap1() /tmp/workdir/go/src/testing/fuzz.go:322 +0x28 fp=0x638a3ff0 sp=0x638a3fe4 pc=0x8135dd8 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x638a3ff4 sp=0x638a3ff0 pc=0x80bcdf1 created by testing.(*F).Fuzz.func1 in goroutine 149 /tmp/workdir/go/src/testing/fuzz.go:322 +0x4e8 goroutine 145 gp=0x63d0f208 m=nil [runnable]: runtime.gopark(0x8280c7c, 0x6692af74, 0xf, 0x6, 0x2) /tmp/workdir/go/src/runtime/proc.go:435 +0xfa fp=0x63867ee8 sp=0x63867ed4 pc=0x80b675a runtime.chansend(0x6692af40, 0x63867fd4, 0x1, 0x8168377) /tmp/workdir/go/src/runtime/chan.go:270 +0x299 fp=0x63867f20 sp=0x63867ee8 pc=0x804cf29 runtime.chansend1(0x6692af40, 0x63867fd4) /tmp/workdir/go/src/runtime/chan.go:156 +0x24 fp=0x63867f38 sp=0x63867f20 pc=0x804cc84 os/exec.(*Cmd).watchCtx(0x638945b8, 0x6692af40) /tmp/workdir/go/src/os/exec/exec.go:873 +0x497 fp=0x63867fe4 sp=0x63867f38 pc=0x8168377 os/exec.(*Cmd).Start.gowrap2() /tmp/workdir/go/src/os/exec/exec.go:775 +0x28 fp=0x63867ff0 sp=0x63867fe4 pc=0x8167c98 runtime.goexit({}) /tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x63867ff4 sp=0x63867ff0 pc=0x80bcdf1 created by os/exec.(*Cmd).Start in goroutine 142 /tmp/workdir/go/src/os/exec/exec.go:775 +0x87b FAIL internal/trace 180.832s ok internal/trace/internal/oldtrace 2.318s ok internal/types/errors 5.906s FAIL go tool dist: Failed: exit status 1