plan9-386-0intro at e3ec1ca17e339e21ca50fbdb375b4294783bc385 :: Running /tmp/workdir-gnot/go/src/make.rc with args ["/tmp/workdir-gnot/go/src/make.rc" "-force"] and env ["home=/usr/glenda" "path=.\x00/bin" "*=" "type=host-plan9-386-0intro" "rcname=gobuildlet" "pid=198" "fn#term%=fn term% {$*}\n" "fn#newworkdir=fn newworkdir {if(test -d $workdir){for(i in `{du -a $workdir|awk '{print $2}'})chmod u+w $i;rm -rf $workdir};mkdir $workdir}\n" "status=" "fn#cd=fn cd {builtin cd $* && awd}\n" "GO_TEST_TIMEOUT_SCALE=2" "workdir=/tmp/workdir-gnot" "objtype=386" "fn#sigexit=" "cflag=" "0=/bin/gobuildlet" "WORKDIR=/tmp/workdir-gnot" "GOROOT_BOOTSTRAP=/tmp/workdir-gnot/go1.4" "GO_BUILDER_NAME=plan9-386-0intro" "GOBIN=" "PWD=/tmp/workdir-gnot/go/src"] in dir /tmp/workdir-gnot/go/src Building Go cmd/dist using /tmp/workdir-gnot/go1.4. (go1.20.6 plan9/386) Building Go toolchain1 using /tmp/workdir-gnot/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 plan9/386. --- Installed Go for plan9/386 in /tmp/workdir-gnot/go Installed commands in /tmp/workdir-gnot/go/bin *** You need to bind /tmp/workdir-gnot/go/bin before /bin. ##### Test execution environment. # GOARCH: 386 # CPU: Intel Core Processor (Broadwell) # GOOS: plan9 # OS Version: 2000 ##### Testing packages. ok archive/tar 0.132s ok archive/zip 0.316s ok bufio 0.056s ok bytes 0.227s ok cmp 0.015s ok compress/bzip2 0.051s ok compress/flate 0.694s ok compress/gzip 2.082s ok compress/lzw 0.072s ok compress/zlib 0.383s ok container/heap 0.014s ok container/list 0.013s ok container/ring 0.014s ok context 0.202s ok crypto 2.080s ok crypto/aes 0.031s ok crypto/cipher 0.040s ok crypto/des 0.023s ok crypto/dsa 0.015s ok crypto/ecdh 0.270s ok crypto/ecdsa 0.181s ok crypto/ed25519 0.304s ok crypto/elliptic 0.070s ok crypto/hmac 0.016s ok crypto/internal/alias 0.013s ok crypto/internal/bigmod 0.057s ok crypto/internal/boring 0.013s ok crypto/internal/boring/bcache 0.183s ok crypto/internal/edwards25519 0.533s ok crypto/internal/edwards25519/field 0.085s ok crypto/internal/nistec 1.020s ok crypto/internal/nistec/fiat 0.012s [no tests to run] ok crypto/md5 0.022s ok crypto/rand 0.036s ok crypto/rc4 0.056s ok crypto/rsa 1.031s ok crypto/sha1 0.021s ok crypto/sha256 0.017s ok crypto/sha512 0.016s ok crypto/subtle 0.239s ok crypto/tls 18.928s ok crypto/x509 2.233s ok database/sql 0.651s ok database/sql/driver 0.014s ok debug/buildinfo 0.021s ok debug/dwarf 0.068s ok debug/elf 0.146s ok debug/gosym 0.040s ok debug/macho 0.019s ok debug/pe 0.082s ok debug/plan9obj 0.014s ok embed 0.014s [no tests to run] ok embed/internal/embedtest 0.016s ok encoding/ascii85 0.015s ok encoding/asn1 0.023s ok encoding/base32 0.025s ok encoding/base64 0.016s ok encoding/binary 0.020s ok encoding/csv 0.022s ok encoding/gob 2.867s ok encoding/hex 0.016s ok encoding/json 0.232s ok encoding/pem 0.477s ok encoding/xml 0.052s ok errors 0.020s ok expvar 0.018s ok flag 0.057s ok fmt 0.057s ok go/ast 0.036s ok go/build 5.406s ok go/build/constraint 0.017s ok go/constant 0.021s ok go/doc 0.062s ok go/doc/comment 2.128s ok go/format 0.020s ok go/importer 0.920s ok go/internal/gccgoimporter 0.041s ok go/internal/gcimporter 6.038s ok go/internal/srcimporter 4.189s ok go/parser 0.143s ok go/printer 0.160s ok go/scanner 0.021s ok go/token 0.028s ok go/types 35.495s ok go/version 0.017s ok hash 0.017s ok hash/adler32 0.020s ok hash/crc32 0.018s ok hash/crc64 0.015s ok hash/fnv 0.015s ok hash/maphash 0.055s ok html 0.020s ok html/template 0.089s ok image 0.056s ok image/color 0.027s ok image/draw 0.075s ok image/gif 0.127s ok image/jpeg 0.143s ok image/png 0.248s ok index/suffixarray 0.157s ok internal/abi 0.227s ok internal/buildcfg 0.056s ok internal/chacha8rand 0.038s ok internal/coverage/cformat 0.033s ok internal/coverage/cmerge 0.032s ok internal/coverage/pods 0.060s ok internal/coverage/slicereader 0.038s ok internal/coverage/slicewriter 0.031s ok internal/coverage/test 0.052s ok internal/cpu 0.035s ok internal/dag 0.032s ok internal/diff 0.042s ok internal/fmtsort 0.035s ok internal/fuzz 0.040s ok internal/godebug 1.285s ok internal/godebugs 8.589s ok internal/gover 0.039s ok internal/intern 0.111s ok internal/itoa 0.034s ok internal/platform 1.650s ok internal/poll 0.056s ok internal/profile 0.032s ok internal/reflectlite 0.042s ok internal/runtime/atomic 0.067s ok internal/saferio 0.062s ok internal/singleflight 0.084s ok internal/sysinfo 0.032s ok internal/testenv 0.429s ok internal/trace 0.089s ok internal/trace/v2 53.286s ok internal/trace/v2/internal/oldtrace 0.150s ok internal/types/errors 1.544s ok internal/unsafeheader 0.031s ok internal/xcoff 0.067s ok internal/zstd 0.069s ok io 0.220s ok io/fs 0.400s ok io/ioutil 2.526s ok log 0.041s ok log/slog 0.067s ok log/slog/internal/benchmarks 0.035s ok log/slog/internal/buffer 0.032s ok maps 0.033s ok math 0.041s ok math/big 1.205s ok math/bits 0.051s ok math/cmplx 0.040s ok math/rand 0.399s ok math/rand/v2 0.544s ok mime 0.042s ok mime/multipart 2.110s ok mime/quotedprintable 0.044s ok net 18.492s ok net/http 158.755s ok net/http/cgi 0.438s ok net/http/cookiejar 0.261s ok net/http/fcgi 0.266s ok net/http/httptest 3.639s ok net/http/httptrace 0.040s ok net/http/httputil 15.331s ok net/http/internal 0.238s ok net/http/internal/ascii 0.032s ok net/http/pprof 4.190s ok net/internal/cgotest 0.040s ok net/mail 0.039s ok net/netip 0.731s ok net/rpc 3.388s ok net/rpc/jsonrpc 0.058s ok net/smtp 1.794s ok net/textproto 0.048s ok net/url 0.042s ok os 3.803s ok os/exec 0.826s ok os/exec/internal/fdtest 0.033s signal.test 22325: suicide: sys: floating point in note handler pc=0x00071ae0 panic: test timed out after 6m0s running tests: TestStress (6m0s) goroutine 11 gp=0x807568 m=2 mp=0x84c508 [running]: panic({0x1130e0, 0x8d22a0}) /tmp/workdir-gnot/go/src/runtime/panic.go:778 +0x139 fp=0x8bbf84 sp=0x8bbf30 pc=0x3ab89 testing.(*M).startAlarm.func1() /tmp/workdir-gnot/go/src/testing/testing.go:2366 +0x38b fp=0x8bbff0 sp=0x8bbf84 pc=0xc7eeb runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x8bbff4 sp=0x8bbff0 pc=0x6f871 created by time.goFunc /tmp/workdir-gnot/go/src/time/sleep.go:214 +0x2e goroutine 1 gp=0x806248 m=nil [chan receive]: runtime.gopark(0x13939c, 0x8325f4, 0xe, 0x7, 0x2) /tmp/workdir-gnot/go/src/runtime/proc.go:402 +0x100 fp=0x848c7c sp=0x848c68 pc=0x3df50 runtime.chanrecv(0x8325c0, 0x848cef, 0x1) /tmp/workdir-gnot/go/src/runtime/chan.go:629 +0x3c7 fp=0x848cb8 sp=0x848c7c pc=0x5fc7 runtime.chanrecv1(0x8325c0, 0x848cef) /tmp/workdir-gnot/go/src/runtime/chan.go:479 +0x1c fp=0x848ccc sp=0x848cb8 pc=0x5bfc testing.(*T).Run(0x88e008, {0x12fadf, 0xa}, 0x1392a8) /tmp/workdir-gnot/go/src/testing/testing.go:1750 +0x3d7 fp=0x848d30 sp=0x848ccc pc=0xc4a37 testing.runTests.func1(0x88e008) /tmp/workdir-gnot/go/src/testing/testing.go:2161 +0x44 fp=0x848d50 sp=0x848d30 pc=0xc6ab4 testing.tRunner(0x88e008, 0x848df4) /tmp/workdir-gnot/go/src/testing/testing.go:1689 +0x124 fp=0x848d9c sp=0x848d50 pc=0xc3b74 testing.runTests(0x81a050, {0x216540, 0x3, 0x3}, {0xc17b30c0cd190d47, 0x53d1c3a123, 0x218660}) /tmp/workdir-gnot/go/src/testing/testing.go:2159 +0x396 fp=0x848e08 sp=0x848d9c pc=0xc69c6 testing.(*M).Run(0x86e050) /tmp/workdir-gnot/go/src/testing/testing.go:2027 +0x6d7 fp=0x848f68 sp=0x848e08 pc=0xc5377 main.main() _testmain.go:53 +0x140 fp=0x848fa4 sp=0x848f68 pc=0x103460 runtime.main() /tmp/workdir-gnot/go/src/runtime/proc.go:271 +0x2e1 fp=0x848ff0 sp=0x848fa4 pc=0x3da61 runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x848ff4 sp=0x848ff0 pc=0x6f871 goroutine 2 gp=0x8065a8 m=nil [force gc (idle)]: runtime.gopark(0x1394fc, 0x2183f8, 0x11, 0xa, 0x1) /tmp/workdir-gnot/go/src/runtime/proc.go:402 +0x100 fp=0x849fd4 sp=0x849fc0 pc=0x3df50 runtime.goparkunlock(...) /tmp/workdir-gnot/go/src/runtime/proc.go:408 runtime.forcegchelper() /tmp/workdir-gnot/go/src/runtime/proc.go:326 +0xc9 fp=0x849ff0 sp=0x849fd4 pc=0x3dd49 runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x849ff4 sp=0x849ff0 pc=0x6f871 created by runtime.init.5 in goroutine 1 /tmp/workdir-gnot/go/src/runtime/proc.go:314 +0x22 goroutine 3 gp=0x8066c8 m=nil [GC sweep wait]: runtime.gopark(0x1394fc, 0x218560, 0xc, 0x9, 0x1) /tmp/workdir-gnot/go/src/runtime/proc.go:402 +0x100 fp=0x84afcc sp=0x84afb8 pc=0x3df50 runtime.goparkunlock(...) /tmp/workdir-gnot/go/src/runtime/proc.go:408 runtime.bgsweep(0x8321c0) /tmp/workdir-gnot/go/src/runtime/mgcsweep.go:278 +0x99 fp=0x84afe8 sp=0x84afcc pc=0x27429 runtime.gcenable.gowrap1() /tmp/workdir-gnot/go/src/runtime/mgc.go:203 +0x26 fp=0x84aff0 sp=0x84afe8 pc=0x189b6 runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x84aff4 sp=0x84aff0 pc=0x6f871 created by runtime.gcenable in goroutine 1 /tmp/workdir-gnot/go/src/runtime/mgc.go:203 +0x76 goroutine 4 gp=0x8067e8 m=nil [GC scavenge wait]: runtime.gopark(0x1394fc, 0x218780, 0xd, 0xa, 0x2) /tmp/workdir-gnot/go/src/runtime/proc.go:402 +0x100 fp=0x84bfb8 sp=0x84bfa4 pc=0x3df50 runtime.goparkunlock(...) /tmp/workdir-gnot/go/src/runtime/proc.go:408 runtime.(*scavengerState).park(0x218780) /tmp/workdir-gnot/go/src/runtime/mgcscavenge.go:425 +0x5e fp=0x84bfcc sp=0x84bfb8 pc=0x2493e runtime.bgscavenge(0x8321c0) /tmp/workdir-gnot/go/src/runtime/mgcscavenge.go:653 +0x4a fp=0x84bfe8 sp=0x84bfcc pc=0x24fda runtime.gcenable.gowrap2() /tmp/workdir-gnot/go/src/runtime/mgc.go:204 +0x26 fp=0x84bff0 sp=0x84bfe8 pc=0x18976 runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x84bff4 sp=0x84bff0 pc=0x6f871 created by runtime.gcenable in goroutine 1 /tmp/workdir-gnot/go/src/runtime/mgc.go:204 +0xb6 goroutine 5 gp=0x806b48 m=nil [finalizer wait]: runtime.gopark(0x1393b8, 0x244b78, 0x10, 0xa, 0x1) /tmp/workdir-gnot/go/src/runtime/proc.go:402 +0x100 fp=0x844f98 sp=0x844f84 pc=0x3df50 runtime.runfinq() /tmp/workdir-gnot/go/src/runtime/mfinal.go:194 +0xfa fp=0x844ff0 sp=0x844f98 pc=0x17aaa runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x844ff4 sp=0x844ff0 pc=0x6f871 created by runtime.createfing in goroutine 1 /tmp/workdir-gnot/go/src/runtime/mfinal.go:164 +0x5f goroutine 8 gp=0x806c68 m=nil [chan receive]: runtime.gopark(0x13939c, 0x832674, 0xe, 0x7, 0x2) /tmp/workdir-gnot/go/src/runtime/proc.go:402 +0x100 fp=0x845f1c sp=0x845f08 pc=0x3df50 runtime.chanrecv(0x832640, 0x0, 0x1) /tmp/workdir-gnot/go/src/runtime/chan.go:629 +0x3c7 fp=0x845f58 sp=0x845f1c pc=0x5fc7 runtime.chanrecv1(0x832640, 0x0) /tmp/workdir-gnot/go/src/runtime/chan.go:479 +0x1c fp=0x845f6c sp=0x845f58 pc=0x5bfc os/signal.TestStress(0x88e208) /tmp/workdir-gnot/go/src/os/signal/signal_plan9_test.go:100 +0x1be fp=0x845f98 sp=0x845f6c pc=0xcf2be testing.tRunner(0x88e208, 0x1392a8) /tmp/workdir-gnot/go/src/testing/testing.go:1689 +0x124 fp=0x845fe4 sp=0x845f98 pc=0xc3b74 testing.(*T).Run.gowrap1() /tmp/workdir-gnot/go/src/testing/testing.go:1742 +0x2d fp=0x845ff0 sp=0x845fe4 pc=0xc4b7d runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x845ff4 sp=0x845ff0 pc=0x6f871 created by testing.(*T).Run in goroutine 1 /tmp/workdir-gnot/go/src/testing/testing.go:1742 +0x3b8 goroutine 7 gp=0x806d88 m=0 mp=0x218c20 [syscall]: runtime.notetsleepg(0x24d098, 0xffffffffffffffff) /tmp/workdir-gnot/go/src/runtime/lock_sema.go:296 +0x30 fp=0x846fcc sp=0x846fa8 pc=0xb9c0 os/signal.signal_recv() /tmp/workdir-gnot/go/src/runtime/sigqueue_plan9.go:110 +0x66 fp=0x846fe0 sp=0x846fcc pc=0x6d4d6 os/signal.loop() /tmp/workdir-gnot/go/src/os/signal/signal_plan9.go:27 +0x19 fp=0x846ff0 sp=0x846fe0 pc=0xceb19 runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x846ff4 sp=0x846ff0 pc=0x6f871 created by os/signal.Notify.func1.1 in goroutine 6 /tmp/workdir-gnot/go/src/os/signal/signal.go:151 +0x26 goroutine 10 gp=0x807448 m=3 mp=0x84ca08 [syscall]: syscall.Syscall6(0x33, 0x3, 0x8baf9c, 0x5, 0xffffffff, 0xffffffff, 0x0) /tmp/workdir-gnot/go/src/syscall/asm_plan9_386.s:64 +0xb fp=0x8baee0 sp=0x8bae48 pc=0x7bdeb syscall.Pwrite(0x3, {0x8baf9c, 0x5, 0x20}, 0xffffffffffffffff) /tmp/workdir-gnot/go/src/syscall/zsyscall_plan9_386.go:235 +0x64 fp=0x8baf18 sp=0x8baee0 pc=0x7bbd4 syscall.Write(...) /tmp/workdir-gnot/go/src/syscall/syscall_plan9.go:182 os.(*File).write(0x8d2278, {0x8baf9c, 0x5, 0x20}) /tmp/workdir-gnot/go/src/os/file_plan9.go:325 +0xe0 fp=0x8baf50 sp=0x8baf18 pc=0x90fe0 os.(*File).Write(0x8d2278, {0x8baf9c, 0x5, 0x20}) /tmp/workdir-gnot/go/src/os/file.go:189 +0x66 fp=0x8baf74 sp=0x8baf50 pc=0x8f856 os/signal.postNote(0x5735, {0x12ed1d, 0x5}) /tmp/workdir-gnot/go/src/os/signal/signal_plan9_test.go:165 +0x131 fp=0x8bafd0 sp=0x8baf74 pc=0xcfcb1 os/signal.TestStress.func2() /tmp/workdir-gnot/go/src/os/signal/signal_plan9_test.go:91 +0x5c fp=0x8baff0 sp=0x8bafd0 pc=0xcf38c runtime.goexit({}) /tmp/workdir-gnot/go/src/runtime/asm_386.s:1393 +0x1 fp=0x8baff4 sp=0x8baff0 pc=0x6f871 created by os/signal.TestStress in goroutine 8 /tmp/workdir-gnot/go/src/os/signal/signal_plan9_test.go:84 +0x176 FAIL os/signal 360.210s ok os/user 0.031s ok path 0.033s FAIL go tool dist: Failed: exit status: 'go 22272: 1'