openbsd-386-72 at 8b48290895d362417124bcc18f0e6b6476ddc99e :: 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.161s ok archive/zip 0.389s ok bufio 0.067s ok bytes 0.319s ok cmp 0.015s ok compress/bzip2 0.094s ok compress/flate 0.673s ok compress/gzip 1.387s ok compress/lzw 0.076s ok compress/zlib 0.430s ok container/heap 0.016s ok container/list 0.015s ok container/ring 0.016s ok context 0.302s ok crypto 1.036s ok crypto/aes 0.031s ok crypto/cipher 0.019s ok crypto/des 0.027s ok crypto/dsa 0.018s ok crypto/ecdh 0.256s ok crypto/ecdsa 0.176s ok crypto/ed25519 0.267s ok crypto/elliptic 0.057s ok crypto/hmac 0.028s ok crypto/internal/alias 0.015s ok crypto/internal/bigmod 0.054s ok crypto/internal/boring 0.015s ok crypto/internal/boring/bcache 0.165s ok crypto/internal/edwards25519 0.413s ok crypto/internal/edwards25519/field 0.068s ok crypto/internal/hpke 0.020s ok crypto/internal/mlkem768 0.253s ok crypto/internal/nistec 0.922s ok crypto/internal/nistec/fiat 0.015s [no tests to run] ok crypto/md5 0.018s ok crypto/rand 0.113s ok crypto/rc4 0.055s ok crypto/rsa 0.873s ok crypto/sha1 0.018s ok crypto/sha256 0.018s ok crypto/sha512 0.021s ok crypto/subtle 0.252s ok crypto/tls 1.781s ok crypto/x509 2.016s ok database/sql 0.745s ok database/sql/driver 0.015s ok debug/buildinfo 0.023s ok debug/dwarf 0.058s ok debug/elf 0.198s ok debug/gosym 0.060s ok debug/macho 0.026s ok debug/pe 0.070s ok debug/plan9obj 0.017s ok embed 0.016s [no tests to run] ok embed/internal/embedtest 0.017s ok encoding/ascii85 0.016s ok encoding/asn1 0.019s ok encoding/base32 0.037s ok encoding/base64 0.018s ok encoding/binary 0.023s ok encoding/csv 0.027s ok encoding/gob 3.290s ok encoding/hex 0.018s ok encoding/json 0.427s ok encoding/pem 1.025s ok encoding/xml 0.087s ok errors 0.019s ok expvar 0.019s ok flag 0.071s ok fmt 0.102s ok go/ast 0.021s ok go/build 3.154s ok go/build/constraint 0.018s ok go/constant 0.019s ok go/doc 0.102s ok go/doc/comment 1.386s ok go/format 0.020s ok go/importer 0.513s ok go/internal/gccgoimporter 0.034s ok go/internal/gcimporter 3.453s ok go/internal/srcimporter 15.973s ok go/parser 0.452s ok go/printer 0.179s ok go/scanner 0.017s ok go/token 0.028s ok go/types 21.523s ok go/version 0.015s ok hash 0.016s ok hash/adler32 0.022s ok hash/crc32 0.020s ok hash/crc64 0.016s ok hash/fnv 0.015s ok hash/maphash 0.063s ok html 0.017s ok html/template 0.085s ok image 0.078s ok image/color 0.026s ok image/draw 0.083s ok image/gif 0.158s ok image/jpeg 0.194s ok image/png 0.275s ok index/suffixarray 0.170s ok internal/abi 0.163s ok internal/buildcfg 0.015s ok internal/chacha8rand 0.015s ok internal/concurrent 0.066s ok internal/coverage/cfile 1.423s ok internal/coverage/cformat 0.016s 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.024s ok internal/cpu 0.037s ok internal/dag 0.016s ok internal/diff 0.022s ok internal/fmtsort 0.016s ok internal/fuzz 0.025s ok internal/godebug 1.003s ok internal/godebugs 5.960s ok internal/gover 0.015s ok internal/itoa 0.015s ok internal/platform 1.433s ok internal/poll 0.070s ok internal/profile 0.015s ok internal/reflectlite 0.017s ok internal/runtime/atomic 0.051s ok internal/saferio 0.107s ok internal/singleflight 0.089s ok internal/sysinfo 0.015s ok internal/testenv 0.371s --- FAIL: TestTraceStressStartStop (29.67s) --- FAIL: TestTraceStressStartStop/Default (29.66s) 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 (0.16s) --- FAIL: TestTraceWaitOnPipe/Default (0.16s) 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=0x16441f07 m=3 sigcode=0 goroutine 0 gp=0x6c008b48 m=3 mp=0x6c042a08 [idle]: runtime.thrsleep(0x6c042d04, 0x3, 0x0, 0x0, 0x6c042d04) runtime/sys_openbsd1.go:17 +0x21 fp=0x246d92f0 sp=0x246d92e0 pc=0x809efb1 runtime.semasleep(0xffffffffffffffff) runtime/os_openbsd.go:118 +0xe2 fp=0x246d932c sp=0x246d92f0 pc=0x807df72 runtime.notesleep(0x6c042acc) runtime/lock_sema.go:183 +0x82 fp=0x246d9348 sp=0x246d932c pc=0x8055cb2 runtime.mPark(...) runtime/proc.go:1866 runtime.stopm() runtime/proc.go:2885 +0x97 fp=0x246d935c sp=0x246d9348 pc=0x80874b7 runtime.findRunnable() runtime/proc.go:3622 +0xc45 fp=0x246d9428 sp=0x246d935c pc=0x8088cd5 runtime.schedule() runtime/proc.go:3995 +0xb1 fp=0x246d9444 sp=0x246d9428 pc=0x808a1d1 runtime.park_m(0x6c008fc8) runtime/proc.go:4102 +0x1c7 fp=0x246d946c sp=0x246d9444 pc=0x808a5e7 runtime.mcall(0x40000) runtime/asm_386.s:329 +0x40 fp=0x246d9474 sp=0x246d946c pc=0x80bdc10 goroutine 1 gp=0x6c008128 m=nil [chan receive]: runtime.gopark(0x8754fa4, 0x6c0249f4, 0xe, 0x7, 0x2) runtime/proc.go:424 +0xfa fp=0x6c0d57f4 sp=0x6c0d57e0 pc=0x80b862a runtime.chanrecv(0x6c0249c0, 0x0, 0x1) runtime/chan.go:639 +0x3bd fp=0x6c0d5830 sp=0x6c0d57f4 pc=0x8050e4d runtime.chanrecv1(0x6c0249c0, 0x0) runtime/chan.go:489 +0x1c fp=0x6c0d5844 sp=0x6c0d5830 pc=0x8050a5c cmd/go/internal/modload.loadFromRoots({0x880bd20, 0x8b78960}, {{{0x0, 0x0}, 0x6c0da120, 0x0, 0x0, {0x0, 0x0}, 0x0, ...}, ...}) cmd/go/internal/modload/load.go:1161 +0x29f fp=0x6c0d5a34 sp=0x6c0d5844 pc=0x84c8edf cmd/go/internal/modload.ImportFromFiles({0x880bd20, 0x8b78960}, {0x6c01c028, 0x1, 0x1}) cmd/go/internal/modload/load.go:770 +0x196 fp=0x6c0d5afc sp=0x6c0d5a34 pc=0x84c7df6 cmd/go/internal/load.GoFilesPackage({0x880bd20, 0x8b78960}, {0x0, 0x0, 0x1, 0x0, 0x0, 0x0}, {0x6c01c028, 0x1, ...}) cmd/go/internal/load/pkg.go:3161 +0x65b fp=0x6c0d5be0 sp=0x6c0d5afc pc=0x851d23b cmd/go/internal/run.runRun({0x880bd20, 0x8b78960}, 0x8b63280, {0x6c01c028, 0x1, 0x1}) cmd/go/internal/run/run.go:113 +0xb49 fp=0x6c0d5c94 sp=0x6c0d5be0 pc=0x85d97c9 main.invoke(0x8b63280, {0x6c01c020, 0x2, 0x2}) cmd/go/main.go:299 +0x8b8 fp=0x6c0d5e68 sp=0x6c0d5c94 pc=0x8622a68 main.main() cmd/go/main.go:213 +0x1018 fp=0x6c0d5fac sp=0x6c0d5e68 pc=0x8621dc8 runtime.main() runtime/proc.go:272 +0x288 fp=0x6c0d5ff0 sp=0x6c0d5fac pc=0x8082c68 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c0d5ff4 sp=0x6c0d5ff0 pc=0x80befa1 goroutine 2 gp=0x6c008488 m=nil [force gc (idle)]: runtime.gopark(0x8755110, 0x8b6a2b8, 0x11, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x6c03efdc sp=0x6c03efc8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.forcegchelper() runtime/proc.go:337 +0xc7 fp=0x6c03eff0 sp=0x6c03efdc pc=0x8082fc7 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c03eff4 sp=0x6c03eff0 pc=0x80befa1 created by runtime.init.6 in goroutine 1 runtime/proc.go:325 +0x1d goroutine 3 gp=0x6c0085a8 m=nil [GC sweep wait]: runtime.gopark(0x8755110, 0x8b6a790, 0xc, 0x9, 0x1) runtime/proc.go:424 +0xfa fp=0x6c03f7cc sp=0x6c03f7b8 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.bgsweep(0x6c024040) runtime/mgcsweep.go:277 +0x8f fp=0x6c03f7e8 sp=0x6c03f7cc pc=0x806efcf runtime.gcenable.gowrap1() runtime/mgc.go:203 +0x21 fp=0x6c03f7f0 sp=0x6c03f7e8 pc=0x8060a41 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c03f7f4 sp=0x6c03f7f0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:203 +0x71 goroutine 4 gp=0x6c0086c8 m=nil [GC scavenge wait]: runtime.gopark(0x8755110, 0x8b6b240, 0xd, 0xa, 0x2) runtime/proc.go:424 +0xfa fp=0x6c03ffb8 sp=0x6c03ffa4 pc=0x80b862a runtime.goparkunlock(...) runtime/proc.go:430 runtime.(*scavengerState).park(0x8b6b240) runtime/mgcscavenge.go:425 +0x54 fp=0x6c03ffcc sp=0x6c03ffb8 pc=0x806c614 runtime.bgscavenge(0x6c024040) runtime/mgcscavenge.go:653 +0x45 fp=0x6c03ffe8 sp=0x6c03ffcc pc=0x806cc45 runtime.gcenable.gowrap2() runtime/mgc.go:204 +0x21 fp=0x6c03fff0 sp=0x6c03ffe8 pc=0x8060a01 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c03fff4 sp=0x6c03fff0 pc=0x80befa1 created by runtime.gcenable in goroutine 1 runtime/mgc.go:204 +0xb1 goroutine 5 gp=0x6c008c68 m=nil [finalizer wait]: runtime.gopark(0x8754fc4, 0x8b78a30, 0x10, 0xa, 0x1) runtime/proc.go:424 +0xfa fp=0x6c03e798 sp=0x6c03e784 pc=0x80b862a runtime.runfinq() runtime/mfinal.go:193 +0xf0 fp=0x6c03e7f0 sp=0x6c03e798 pc=0x805fba0 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c03e7f4 sp=0x6c03e7f0 pc=0x80befa1 created by runtime.createfing in goroutine 1 runtime/mfinal.go:163 +0x5a goroutine 6 gp=0x6c008fc8 m=nil [chan receive]: runtime.gopark(0x8754fa4, 0x6c024274, 0xe, 0x7, 0x2) runtime/proc.go:424 +0xfa fp=0x6c040790 sp=0x6c04077c pc=0x80b862a runtime.chanrecv(0x6c024240, 0x0, 0x1) runtime/chan.go:639 +0x3bd fp=0x6c0407cc sp=0x6c040790 pc=0x8050e4d runtime.chanrecv1(0x6c024240, 0x0) runtime/chan.go:489 +0x1c fp=0x6c0407e0 sp=0x6c0407cc pc=0x8050a5c runtime.unique_runtime_registerUniqueMapCleanup.func1(...) runtime/mgc.go:1732 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() runtime/mgc.go:1735 +0x34 fp=0x6c0407f0 sp=0x6c0407e0 pc=0x80641e4 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c0407f4 sp=0x6c0407f0 pc=0x80befa1 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 runtime/mgc.go:1730 +0x96 goroutine 7 gp=0x6c009208 m=nil [runnable]: syscall.syscall(0x80d96c0, 0x6c1e6da0, 0x10000, 0x0) runtime/sys_openbsd3.go:28 +0x20 fp=0x6c02d6e4 sp=0x6c02d6d4 pc=0x80bc390 syscall.Open({0x6c159a70, 0x18}, 0x10000, 0x0) syscall/zsyscall_openbsd_386.go:1109 +0x85 fp=0x6c02d714 sp=0x6c02d6e4 pc=0x80d7a25 os.open(...) os/file_open_unix.go:15 os.openDirNolog.func1() os/file_unix.go:309 +0x4a fp=0x6c02d740 sp=0x6c02d714 pc=0x814090a os.ignoringEINTR(...) os/file_posix.go:251 os.openDirNolog({0x6c159a70, 0x18}) os/file_unix.go:308 +0x6f fp=0x6c02d784 sp=0x6c02d740 pc=0x814079f os.openDir({0x6c159a70, 0x18}) os/file.go:399 +0x37 fp=0x6c02d79c sp=0x6c02d784 pc=0x813e6f7 os.ReadDir({0x6c159a70, 0x18}) os/dir.go:119 +0x5d fp=0x6c02d7e0 sp=0x6c02d79c pc=0x813a7bd cmd/go/internal/fsys.readDir({0x6c159a70, 0x18}) cmd/go/internal/fsys/fsys.go:307 +0x27 fp=0x6c02d82c sp=0x6c02d7e0 pc=0x8205127 cmd/go/internal/fsys.ReadDir({0x6c159a70, 0x18}) cmd/go/internal/fsys/fsys.go:340 +0x2d7 fp=0x6c02d9d4 sp=0x6c02d82c pc=0x82056a7 cmd/go/internal/modindex.dirHash({0x6c159a58, 0x13}, {0x6c159a70, 0x18}) cmd/go/internal/modindex/read.go:89 +0x1ba fp=0x6c02da9c sp=0x6c02d9d4 pc=0x84881ba cmd/go/internal/modindex.openIndexPackage.func1() cmd/go/internal/modindex/read.go:207 +0x90 fp=0x6c02db98 sp=0x6c02da9c pc=0x8488d90 cmd/go/internal/par.(*ErrCache[...]).Do.func1() cmd/go/internal/par/work.go:119 +0x16 fp=0x6c02dba8 sp=0x6c02db98 pc=0x8492806 cmd/go/internal/par.(*Cache[...]).Do(0x880dc20, {{0x6c159a58, 0x13}, {0x6c159a70, 0x18}}, 0x6c02dc10) cmd/go/internal/par/work.go:160 +0x106 fp=0x6c02dbe8 sp=0x6c02dba8 pc=0x8492a36 cmd/go/internal/par.(*ErrCache[...]).Do(0x880d2a0, {{0x6c159a58, 0x13}, {0x6c159a70, 0x18}}, 0x6c02dc58) cmd/go/internal/par/work.go:118 +0x52 fp=0x6c02dc20 sp=0x6c02dbe8 pc=0x84927c2 cmd/go/internal/modindex.openIndexPackage({0x6c159a58, 0x13}, {0x6c159a70, 0x18}) cmd/go/internal/modindex/read.go:205 +0x89 fp=0x6c02dc70 sp=0x6c02dc20 pc=0x8488cd9 cmd/go/internal/modindex.GetPackage({0x6c159a58, 0x13}, {0x6c159a70, 0x18}) cmd/go/internal/modindex/read.go:142 +0x164 fp=0x6c02dc9c sp=0x6c02dc70 pc=0x8488654 cmd/go/internal/modindex.IsStandardPackage({0x6c02001f, 0xf}, {0x86fa972, 0x2}, {0x29bec8e8, 0x4}) cmd/go/internal/modindex/read.go:673 +0x211 fp=0x6c02dd04 sp=0x6c02dc9c pc=0x848d2c1 cmd/go/internal/modload.importFromModules({0x880bd20, 0x8b78960}, {0x29bec8e8, 0x4}, 0x6c0a01b0, 0x0, 0x0) cmd/go/internal/modload/import.go:301 +0x458 fp=0x6c02df08 sp=0x6c02dd04 pc=0x84ab918 cmd/go/internal/modload.(*loader).load(0x6c071a40, {0x880bd20, 0x8b78960}, 0x6c0f95e0) cmd/go/internal/modload/load.go:1852 +0xa3 fp=0x6c02dfa0 sp=0x6c02df08 pc=0x84cd533 cmd/go/internal/modload.(*loader).pkg.func1.1() cmd/go/internal/modload/load.go:1665 +0x2f fp=0x6c02dfb4 sp=0x6c02dfa0 pc=0x84cc42f cmd/go/internal/par.(*Queue).Add.func1() cmd/go/internal/par/queue.go:58 +0x53 fp=0x6c02dff0 sp=0x6c02dfb4 pc=0x820a483 runtime.goexit({}) runtime/asm_386.s:1393 +0x1 fp=0x6c02dff4 sp=0x6c02dff0 pc=0x80befa1 created by cmd/go/internal/par.(*Queue).Add in goroutine 1 cmd/go/internal/par/queue.go:56 +0x190 eax 0x58 ebx 0x6c042d04 ecx 0x0 edx 0x246d92f0 edi 0x1ac esi 0x6c008b48 ebp 0x246d9298 esp 0x246d9280 eip 0x16441f07 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 FAIL FAIL internal/trace 180.069s ok internal/trace/internal/oldtrace 0.328s FAIL go tool dist: Failed: exit status 1