openbsd-386-72 at f2bcab5fb3436144c750e48934b4829111d407d6 :: 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.207s ok archive/zip 0.462s ok bufio 0.084s ok bytes 0.458s ok cmp 0.016s ok compress/bzip2 0.093s ok compress/flate 0.838s ok compress/gzip 1.711s ok compress/lzw 0.106s ok compress/zlib 0.600s ok container/heap 0.017s ok container/list 0.016s ok container/ring 0.016s ok context 0.300s ok crypto 1.257s ok crypto/aes 0.036s ok crypto/cipher 0.020s ok crypto/des 0.028s ok crypto/dsa 0.018s ok crypto/ecdh 0.302s ok crypto/ecdsa 0.209s ok crypto/ed25519 0.338s ok crypto/elliptic 0.068s ok crypto/hmac 0.033s ok crypto/internal/alias 0.015s ok crypto/internal/bigmod 0.067s ok crypto/internal/boring 0.015s ok crypto/internal/boring/bcache 0.209s ok crypto/internal/edwards25519 0.535s ok crypto/internal/edwards25519/field 0.083s ok crypto/internal/hpke 0.021s ok crypto/internal/mlkem768 0.323s ok crypto/internal/nistec 1.094s ok crypto/internal/nistec/fiat 0.015s [no tests to run] ok crypto/md5 0.019s ok crypto/rand 0.128s ok crypto/rc4 0.057s ok crypto/rsa 1.101s ok crypto/sha1 0.019s ok crypto/sha256 0.019s ok crypto/sha512 0.024s ok crypto/subtle 0.290s ok crypto/tls 2.348s ok crypto/x509 2.581s ok database/sql 0.800s ok database/sql/driver 0.015s ok debug/buildinfo 0.025s ok debug/dwarf 0.062s ok debug/elf 0.216s ok debug/gosym 0.058s ok debug/macho 0.028s ok debug/pe 0.067s 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.019s ok encoding/base32 0.039s ok encoding/base64 0.019s ok encoding/binary 0.025s ok encoding/csv 0.029s ok encoding/gob 4.663s ok encoding/hex 0.018s ok encoding/json 0.525s ok encoding/pem 1.110s ok encoding/xml 0.096s ok errors 0.025s ok expvar 0.019s ok flag 0.070s ok fmt 0.107s ok go/ast 0.022s ok go/build 3.935s ok go/build/constraint 0.018s ok go/constant 0.019s ok go/doc 0.105s ok go/doc/comment 1.857s ok go/format 0.022s ok go/importer 0.624s ok go/internal/gccgoimporter 0.037s ok go/internal/gcimporter 4.068s ok go/internal/srcimporter 17.225s ok go/parser 0.496s ok go/printer 0.211s ok go/scanner 0.017s ok go/token 0.032s ok go/types 25.721s 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.015s ok hash/maphash 0.068s ok html 0.018s ok html/template 0.100s ok image 0.088s ok image/color 0.030s ok image/draw 0.101s ok image/gif 0.208s ok image/jpeg 0.223s ok image/png 0.344s ok index/suffixarray 0.219s ok internal/abi 0.155s ok internal/buildcfg 0.015s ok internal/chacha8rand 0.016s ok internal/concurrent 0.077s ok internal/coverage/cfile 1.637s ok internal/coverage/cformat 0.015s ok internal/coverage/cmerge 0.015s ok internal/coverage/pods 0.020s ok internal/coverage/slicereader 0.015s ok internal/coverage/slicewriter 0.015s ok internal/coverage/test 0.024s ok internal/cpu 0.038s ok internal/dag 0.016s ok internal/diff 0.022s ok internal/fmtsort 0.016s ok internal/fuzz 0.023s ok internal/godebug 1.101s ok internal/godebugs 8.681s ok internal/gover 0.015s ok internal/itoa 0.015s ok internal/platform 1.744s ok internal/poll 0.067s ok internal/profile 0.018s ok internal/reflectlite 0.019s ok internal/runtime/atomic 0.055s ok internal/saferio 0.108s ok internal/singleflight 0.072s ok internal/sysinfo 0.019s ok internal/testenv 0.427s --- FAIL: TestTraceStress (125.64s) --- FAIL: TestTraceStress/Default (125.64s) exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go trace_test.go:579: exit status 1 --- FAIL: TestTraceStressStartStop (12.39s) --- FAIL: TestTraceStressStartStop/Default (12.39s) 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: exit status 1 --- FAIL: TestTraceManyStartStop (0.57s) --- FAIL: TestTraceManyStartStop/Default (0.57s) 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=0x2a45a593 m=3 sigcode=0 goroutine 0 gp=0x4d408b48 m=3 mp=0x4d442a08 [idle]: runtime.kevent(0x3d, 0x0, 0x0, 0x412f2714, 0x40, 0x0) runtime/sys_openbsd2.go:219 +0x21 fp=0x412f26c0 sp=0x412f26b0 pc=0x809f591 runtime.netpoll(0xffffffffffffffff) runtime/netpoll_kqueue.go:107 +0xe5 fp=0x412f2d1c sp=0x412f26c0 pc=0x807d675 runtime.findRunnable() runtime/proc.go:3580 +0x9ff fp=0x412f2de8 sp=0x412f2d1c pc=0x8088a8f runtime.schedule() runtime/proc.go:3995 +0xb1 fp=0x412f2e04 sp=0x412f2de8 pc=0x808a1d1 runtime.park_m(0x4d4086c8) runtime/proc.go:4102 +0x1c7 fp=0x412f2e2c sp=0x412f2e04 pc=0x808a5e7 runtime.mcall(0x40000) runtime/asm_386.s:329 +0x40 fp=0x412f2e34 sp=0x412f2e2c pc=0x80bdc10 goroutine 1 gp=0x4d408128 m=nil [semacquire]: runtime.gopark(0x8755110, 0x8b74220, 0x12, 0x5, 0x4) runtime/proc.go:424 +0xfa fp=0x4d4d7ae4 sp=0x4d4d7ad0 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.semacquire1(0x4d6b2248, 0x0, 0x1, 0x0, 0x12) runtime/sema.go:178 +0x285 fp=0x4d4d7b14 sp=0x4d4d7ae4 pc=0x8095af5 sync.runtime_Semacquire(0x4d6b2248) runtime/sema.go:71 +0x35 fp=0x4d4d7b2c sp=0x4d4d7b14 pc=0x80b9ad5 sync.(*WaitGroup).Wait(0x4d6b2240) sync/waitgroup.go:118 +0x5f fp=0x4d4d7b48 sp=0x4d4d7b2c pc=0x80cc1af cmd/go/internal/work.(*Builder).Do(0x4d478230, {0x880bd20, 0x8b78960}, 0x4d6f9e08) cmd/go/internal/work/exec.go:230 +0x42b fp=0x4d4d7be0 sp=0x4d4d7b48 pc=0x858870b cmd/go/internal/run.runRun({0x880bd20, 0x8b78960}, 0x8b63280, {0x4d41c028, 0x1, 0x1}) cmd/go/internal/run/run.go:174 +0x7ff fp=0x4d4d7c94 sp=0x4d4d7be0 pc=0x85d947f main.invoke(0x8b63280, {0x4d41c020, 0x2, 0x2}) cmd/go/main.go:299 +0x8b8 fp=0x4d4d7e68 sp=0x4d4d7c94 pc=0x8622a68 main.main() cmd/go/main.go:213 +0x1018 fp=0x4d4d7fac sp=0x4d4d7e68 pc=0x8621dc8 runtime.main() runtime/proc.go:272 +0x288 fp=0x4d4d7ff0 sp=0x4d4d7fac pc=0x8082c68 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d4d7ff4 sp=0x4d4d7ff0 pc=0x80befa1 goroutine 2 gp=0x4d408488 m=nil [force gc (idle)]: runtime.gopark(0x8755110, 0x8b6a2b8, 0x11, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x4d43efdc sp=0x4d43efc8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.forcegchelper() runtime/proc.go:337 +0xc7 fp=0x4d43eff0 sp=0x4d43efdc pc=0x8082fc7 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d43eff4 sp=0x4d43eff0 pc=0x80befa1 created by runtime.init.6 in goroutine 1 runtime/proc.go:325 +0x1d goroutine 3 gp=0x4d4085a8 m=nil [GC sweep wait]: runtime.gopark(0x8755110, 0x8b6a790, 0xc, 0x9, 0x1) runtime/proc.go:424 +0xfa fp=0x4d43f7cc sp=0x4d43f7b8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.bgsweep(0x4d424040) runtime/mgcsweep.go:317 +0xec fp=0x4d43f7e8 sp=0x4d43f7cc pc=0x806f02c runtime.gcenable.gowrap1() runtime/mgc.go:203 +0x21 fp=0x4d43f7f0 sp=0x4d43f7e8 pc=0x8060a41 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d43f7f4 sp=0x4d43f7f0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:203 +0x71 goroutine 4 gp=0x4d4086c8 m=nil [GC scavenge wait]: runtime.gopark(0x8755110, 0x8b6b240, 0xd, 0xa, 0x2) runtime/proc.go:424 +0xfa fp=0x4d43ffb8 sp=0x4d43ffa4 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.(*scavengerState).park(0x8b6b240) runtime/mgcscavenge.go:425 +0x54 fp=0x4d43ffcc sp=0x4d43ffb8 pc=0x806c614 runtime.bgscavenge(0x4d424040) runtime/mgcscavenge.go:658 +0x69 fp=0x4d43ffe8 sp=0x4d43ffcc pc=0x806cc69 runtime.gcenable.gowrap2() runtime/mgc.go:204 +0x21 fp=0x4d43fff0 sp=0x4d43ffe8 pc=0x8060a01 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d43fff4 sp=0x4d43fff0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x4d408c68 m=nil [finalizer wait]: runtime.gopark(0x8754fc4, 0x8b78a30, 0x10, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x4d43e798 sp=0x4d43e784 pc=0x80b862a runtime.runfinq() runtime/mfinal.go:193 +0xf0 fp=0x4d43e7f0 sp=0x4d43e798 pc=0x805fba0 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d43e7f4 sp=0x4d43e7f0 pc=0x80befa1 created by runtime.createfing in goroutine 1 runtime/mfinal.go:163 +0x5a goroutine 6 gp=0x4d408fc8 m=nil [chan receive]: runtime.gopark(0x8754fa4, 0x4d424274, 0xe, 0x7, 0x2) runtime/proc.go:424 +0xfa fp=0x4d440790 sp=0x4d44077c pc=0x80b862a runtime.chanrecv(0x4d424240, 0x0, 0x1) runtime/chan.go:639 +0x3bd fp=0x4d4407cc sp=0x4d440790 pc=0x8050e4d runtime.chanrecv1(0x4d424240, 0x0) runtime/chan.go:489 +0x1c fp=0x4d4407e0 sp=0x4d4407cc pc=0x8050a5c runtime.unique_runtime_registerUniqueMapCleanup.func1(...) runtime/mgc.go:1732 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() runtime/mgc.go:1735 +0x34 fp=0x4d4407f0 sp=0x4d4407e0 pc=0x80641e4 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d4407f4 sp=0x4d4407f0 pc=0x80befa1 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 runtime/mgc.go:1730 +0x96 goroutine 8 gp=0x4d409208 m=nil [chan receive]: runtime.gopark(0x8754fa4, 0x4d6fd574, 0xe, 0x7, 0x2) runtime/proc.go:424 +0xfa fp=0x4d4d5a40 sp=0x4d4d5a2c pc=0x80b862a runtime.chanrecv(0x4d6fd540, 0x4d4d5ac8, 0x1) runtime/chan.go:639 +0x3bd fp=0x4d4d5a7c sp=0x4d4d5a40 pc=0x8050e4d runtime.chanrecv1(0x4d6fd540, 0x4d4d5ac8) runtime/chan.go:489 +0x1c fp=0x4d4d5a90 sp=0x4d4d5a7c pc=0x8050a5c os/exec.(*Cmd).awaitGoroutines(0x4d4041a8, 0x0) os/exec/exec.go:957 +0x1e3 fp=0x4d4d5afc sp=0x4d4d5a90 pc=0x8175db3 os/exec.(*Cmd).Wait(0x4d4041a8) os/exec/exec.go:924 +0x14e fp=0x4d4d5b34 sp=0x4d4d5afc pc=0x8175aae os/exec.(*Cmd).Run(0x4d4041a8) os/exec/exec.go:610 +0x3f fp=0x4d4d5b44 sp=0x4d4d5b34 pc=0x817472f cmd/go/internal/work.(*Shell).runOut(0x4d7bb380, {0x8804de4, 0x1}, {0x4d7bb800, 0x1, 0x1}, {0x4d4d5d7c, 0x8, 0x8}) cmd/go/internal/work/shell.go:637 +0x706 fp=0x4d4d5c50 sp=0x4d4d5b44 pc=0x85bdd06 cmd/go/internal/work.(*Shell).run(0x4d7bb380, {0x8804de4, 0x1}, {0x8708bc4, 0x16}, {0x4d7bb800, 0x1, 0x1}, {0x4d4d5d7c, 0x8, ...}) cmd/go/internal/work/shell.go:564 +0x62 fp=0x4d4d5ca8 sp=0x4d4d5c50 pc=0x85bd472 cmd/go/internal/work.gcToolchain.ld({}, 0x4d478230, 0x4d6cb088, {0x4d62af80, 0x3c}, {0x4d425980, 0x37}, {0x4d59a960, 0x30}) cmd/go/internal/work/gc.go:686 +0x1065 fp=0x4d4d5dd4 sp=0x4d4d5ca8 pc=0x85aee75 cmd/go/internal/work.(*gcToolchain).ld(0x8b78960, 0x4d478230, 0x4d6cb088, {0x4d62af80, 0x3c}, {0x4d425980, 0x37}, {0x4d59a960, 0x30}) :1 +0x62 fp=0x4d4d5e00 sp=0x4d4d5dd4 pc=0x85c04a2 cmd/go/internal/work.(*Builder).link(0x4d478230, {0x880bd20, 0x8b78960}, 0x4d6cb088) cmd/go/internal/work/exec.go:1481 +0x353 fp=0x4d4d5e84 sp=0x4d4d5e00 pc=0x8595ea3 cmd/go/internal/work.ActorFunc.Act(0x8754e1c, 0x4d478230, {0x880bd20, 0x8b78960}, 0x4d6cb088) cmd/go/internal/work/action.go:76 +0x36 fp=0x4d4d5ea0 sp=0x4d4d5e84 pc=0x8578f36 cmd/go/internal/work.(*Builder).Do.func3({0x880bd20, 0x8b78960}, 0x4d6cb088) cmd/go/internal/work/exec.go:152 +0x801 fp=0x4d4d5f88 sp=0x4d4d5ea0 pc=0x8589251 cmd/go/internal/work.(*Builder).Do.func4() cmd/go/internal/work/exec.go:221 +0xae fp=0x4d4d5ff0 sp=0x4d4d5f88 pc=0x85888ee runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d4d5ff4 sp=0x4d4d5ff0 pc=0x80befa1 created by cmd/go/internal/work.(*Builder).Do in goroutine 1 cmd/go/internal/work/exec.go:207 +0x38c goroutine 13 gp=0x4d6e0ea8 m=nil [GC worker (idle)]: runtime.gopark(0x8754fd0, 0x4d6a5a40, 0x1a, 0xa, 0x0) runtime/proc.go:424 +0xfa fp=0x4d440f8c sp=0x4d440f78 pc=0x80b862a runtime.gcBgMarkWorker(0x4d62bac0) runtime/mgc.go:1363 +0xeb fp=0x4d440fe8 sp=0x4d440f8c pc=0x806337b runtime.gcBgMarkStartWorkers.gowrap1() runtime/mgc.go:1279 +0x21 fp=0x4d440ff0 sp=0x4d440fe8 pc=0x8063271 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d440ff4 sp=0x4d440ff0 pc=0x80befa1 created by runtime.gcBgMarkStartWorkers in goroutine 8 runtime/mgc.go:1279 +0x114 goroutine 17 gp=0x4d6e0fc8 m=nil [IO wait]: runtime.gopark(0x8755104, 0x1700e998, 0x2, 0x2, 0x5) runtime/proc.go:424 +0xfa fp=0x4d441588 sp=0x4d441574 pc=0x80b862a runtime.netpollblock(0x1700e988, 0x72, 0x0) runtime/netpoll.go:575 +0xe4 fp=0x4d4415a0 sp=0x4d441588 pc=0x807ce54 internal/poll.runtime_pollWait(0x1700e988, 0x72) runtime/netpoll.go:351 +0x4c fp=0x4d4415b4 sp=0x4d4415a0 pc=0x80b79ec internal/poll.(*pollDesc).wait(0x4d6fd4d8, 0x72, 0x1) internal/poll/fd_poll_runtime.go:84 +0x31 fp=0x4d4415c8 sp=0x4d4415b4 pc=0x8133e21 internal/poll.(*pollDesc).waitRead(...) internal/poll/fd_poll_runtime.go:89 internal/poll.(*FD).Read(0x4d6fd4c0, {0x4d4a9400, 0x200, 0x200}) internal/poll/fd_unix.go:165 +0x1f6 fp=0x4d441610 sp=0x4d4415c8 pc=0x8134ca6 os.(*File).read(...) os/file_posix.go:29 os.(*File).Read(0x4d7bb860, {0x4d4a9400, 0x200, 0x200}) os/file.go:124 +0x6a fp=0x4d441634 sp=0x4d441610 pc=0x813d76a os.(*fileWithoutWriteTo).Read(0x4d7bb888, {0x4d4a9400, 0x200, 0x200}) :1 +0x3c fp=0x4d441654 sp=0x4d441634 pc=0x8145c1c bytes.(*Buffer).ReadFrom(0x4d6d2348, {0x88089f0, 0x4d7bb888}) bytes/buffer.go:211 +0x8f fp=0x4d441690 sp=0x4d441654 pc=0x816f52f io.copyBuffer({0x8808af0, 0x4d6d2348}, {0x88089f0, 0x4d7bb888}, {0x0, 0x0, 0x0}) io/io.go:415 +0x146 fp=0x4d4416dc sp=0x4d441690 pc=0x812c7a6 io.Copy(...) io/io.go:388 os.genericWriteTo(0x4d7bb860, {0x8808af0, 0x4d6d2348}) os/file.go:275 +0x6f fp=0x4d441710 sp=0x4d4416dc pc=0x813e00f os.(*File).WriteTo(0x4d7bb860, {0x8808af0, 0x4d6d2348}) os/file.go:253 +0x61 fp=0x4d441730 sp=0x4d441710 pc=0x813df41 io.copyBuffer({0x8808af0, 0x4d6d2348}, {0x8808980, 0x4d7bb860}, {0x0, 0x0, 0x0}) io/io.go:411 +0x186 fp=0x4d44177c sp=0x4d441730 pc=0x812c7e6 io.Copy(...) io/io.go:388 os/exec.(*Cmd).writerDescriptor.func1() os/exec/exec.go:580 +0x4f fp=0x4d4417b8 sp=0x4d44177c pc=0x81746af os/exec.(*Cmd).Start.func2(0x4d412720) os/exec/exec.go:733 +0x2b fp=0x4d4417e8 sp=0x4d4417b8 pc=0x817522b os/exec.(*Cmd).Start.gowrap1() os/exec/exec.go:745 +0x23 fp=0x4d4417f0 sp=0x4d4417e8 pc=0x81751e3 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x4d4417f4 sp=0x4d4417f0 pc=0x80befa1 created by os/exec.(*Cmd).Start in goroutine 8 os/exec/exec.go:732 +0x910 eax 0x4 ebx 0x40 ecx 0x0 edx 0x412f26c0 edi 0x79c esi 0x4d408b48 ebp 0x412f2668 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 FAIL FAIL internal/trace 180.071s ok internal/trace/internal/oldtrace 0.422s FAIL go tool dist: Failed: exit status 1