freebsd-arm64-dmgk at 636c6e350dfc89c164f3e26aee85b241faf06ae0 :: Running /tmp/workdir-host-freebsd-arm64-dmgk/go/src/make.bash with args ["/tmp/workdir-host-freebsd-arm64-dmgk/go/src/make.bash" "-force"] and env ["HOME=/usr/home/builder" "VENDOR=unknown" "LOGNAME=builder" "PAGER=less" "OSTYPE=FreeBSD" "MACHTYPE=unknown" "PATH=/sbin:/bin:/usr/sbin:/usr/bin:/usr/local/sbin:/usr/local/bin://bin" "EDITOR=vi" "HOST=aarch64.localdomain" "OLDPWD=/" "PWD=/usr/home/builder" "GROUP=builder" "USER=builder" "HOSTTYPE=FreeBSD" "BLOCKSIZE=K" "RC_PID=22" "SHLVL=1" "WORKDIR=/tmp/workdir-host-freebsd-arm64-dmgk" "GOROOT_BOOTSTRAP=/tmp/workdir-host-freebsd-arm64-dmgk/go1.4" "GO_BUILDER_NAME=freebsd-arm64-dmgk" "GOBIN=" "TMPDIR=/tmp/workdir-host-freebsd-arm64-dmgk/tmp" "GOCACHE=/tmp/workdir-host-freebsd-arm64-dmgk/gocache"] in dir /tmp/workdir-host-freebsd-arm64-dmgk/go/src Building Go cmd/dist using /tmp/workdir-host-freebsd-arm64-dmgk/go1.4. (go1.20.6 freebsd/arm64) Building Go toolchain1 using /tmp/workdir-host-freebsd-arm64-dmgk/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 freebsd/arm64. --- Installed Go for freebsd/arm64 in /tmp/workdir-host-freebsd-arm64-dmgk/go Installed commands in /tmp/workdir-host-freebsd-arm64-dmgk/go/bin *** You need to add /tmp/workdir-host-freebsd-arm64-dmgk/go/bin to your PATH. ##### Test execution environment. # GOARCH: arm64 # CPU: # GOOS: freebsd # OS Version: FreeBSD 12.3-RELEASE FreeBSD 12.3-RELEASE r371126 GENERIC arm64 ##### Testing packages. ok archive/tar 0.210s ok archive/zip 0.733s ok bufio 0.066s ok bytes 0.402s ok cmp 0.009s ok compress/bzip2 0.068s ok compress/flate 2.940s ok compress/gzip 3.913s ok compress/lzw 0.128s ok compress/zlib 0.791s ok container/heap 0.011s ok container/list 0.006s ok container/ring 0.007s ok context 0.027s ok crypto 0.008s ok crypto/aes 0.029s ok crypto/cipher 0.015s ok crypto/des 0.012s ok crypto/dsa 0.008s ok crypto/ecdh 0.070s ok crypto/ecdsa 0.048s ok crypto/ed25519 0.160s ok crypto/elliptic 0.016s ok crypto/hmac 0.006s ok crypto/internal/alias 0.006s ok crypto/internal/bigmod 0.284s ok crypto/internal/boring 0.005s ok crypto/internal/boring/bcache 0.195s ok crypto/internal/edwards25519 0.273s ok crypto/internal/edwards25519/field 0.038s ok crypto/internal/nistec 0.263s ok crypto/internal/nistec/fiat 0.006s [no tests to run] ok crypto/md5 0.008s ok crypto/rand 0.081s ok crypto/rc4 0.045s ok crypto/rsa 0.690s ok crypto/sha1 0.009s ok crypto/sha256 0.007s ok crypto/sha512 0.011s ok crypto/subtle 0.502s ok crypto/tls 0.855s ok crypto/x509 0.668s ok database/sql 0.548s ok database/sql/driver 0.010s ok debug/buildinfo 0.018s ok debug/dwarf 0.038s ok debug/elf 0.319s ok debug/gosym 0.032s ok debug/macho 0.011s ok debug/pe 0.028s ok debug/plan9obj 0.007s ok embed 0.010s [no tests to run] ok embed/internal/embedtest 0.007s ok encoding/ascii85 0.008s ok encoding/asn1 0.009s ok encoding/base32 0.034s ok encoding/base64 0.010s ok encoding/binary 0.009s ok encoding/csv 0.016s ok encoding/gob 3.287s ok encoding/hex 0.011s ok encoding/json 0.365s ok encoding/pem 0.787s ok encoding/xml 0.091s ok errors 0.014s ok expvar 0.009s ok flag 0.083s ok fmt 0.130s ok go/ast 0.009s ok go/build 3.016s ok go/build/constraint 0.008s ok go/constant 0.009s ok go/doc 0.104s ok go/doc/comment 1.393s ok go/format 0.013s ok go/importer 0.572s ok go/internal/gccgoimporter 0.018s ok go/internal/gcimporter 1.538s ok go/internal/srcimporter 9.856s ok go/parser 0.344s ok go/printer 0.285s ok go/scanner 0.011s ok go/token 0.024s ok go/types 15.334s ok go/version 0.007s ok hash 0.011s ok hash/adler32 0.011s ok hash/crc32 0.013s ok hash/crc64 0.007s ok hash/fnv 0.006s ok hash/maphash 0.107s ok html 0.007s ok html/template 0.106s ok image 0.070s ok image/color 0.029s ok image/draw 0.121s ok image/gif 1.016s ok image/jpeg 0.261s ok image/png 0.443s ok index/suffixarray 0.301s ok internal/abi 0.069s ok internal/buildcfg 0.008s ok internal/coverage/cformat 0.010s ok internal/coverage/cmerge 0.006s ok internal/coverage/pods 0.007s ok internal/coverage/slicereader 0.006s ok internal/coverage/slicewriter 0.006s ok internal/coverage/test 0.014s ok internal/cpu 0.009s ok internal/dag 0.007s ok internal/diff 0.007s ok internal/fmtsort 0.007s ok internal/fuzz 0.013s ok internal/godebug 0.519s ok internal/godebugs 0.006s ok internal/gover 0.006s ok internal/intern 0.152s ok internal/itoa 0.007s ok internal/platform 2.862s ok internal/poll 0.041s ok internal/profile 0.007s ok internal/reflectlite 0.007s ok internal/safefilepath 0.009s ok internal/saferio 0.047s ok internal/singleflight 0.027s ok internal/testenv 0.222s ok internal/trace 0.103s ok internal/trace/v2 9.171s ok internal/types/errors 0.699s ok internal/unsafeheader 0.006s ok internal/xcoff 0.027s ok internal/zstd 0.761s ok io 0.034s ok io/fs 0.448s ok io/ioutil 0.009s ok log 0.010s ok log/slog 0.046s ok log/slog/internal/benchmarks 0.009s ok log/slog/internal/buffer 0.009s ok log/syslog 1.288s ok maps 0.006s ok math 0.011s ok math/big 2.330s ok math/bits 0.012s ok math/cmplx 0.010s ok math/rand 0.315s ok math/rand/v2 0.478s ok mime 0.011s ok mime/multipart 1.036s ok mime/quotedprintable 0.027s ok net 9.110s ok net/http 15.144s ok net/http/cgi 0.273s ok net/http/cookiejar 0.013s ok net/http/fcgi 0.227s ok net/http/httptest 0.068s ok net/http/httptrace 0.011s ok net/http/httputil 0.649s ok net/http/internal 0.007s ok net/http/internal/ascii 0.007s ok net/http/pprof 4.123s ok net/internal/socktest 0.007s ok net/mail 0.011s ok net/netip 0.492s ok net/rpc 0.035s ok net/rpc/jsonrpc 0.013s ok net/smtp 0.021s ok net/textproto 0.027s ok net/url 0.015s ok os 0.683s ok os/exec 0.449s ok os/exec/internal/fdtest 0.006s ok os/signal 3.587s ok os/user 0.008s ok path 0.007s ok path/filepath 0.091s ok plugin 0.008s ok reflect 2.101s ok regexp 0.803s ok regexp/syntax 1.315s ok runtime 70.213s ok runtime/cgo 0.011s ok runtime/coverage 0.008s ok runtime/debug 0.175s ok runtime/internal/atomic 0.063s ok runtime/internal/math 0.006s ok runtime/internal/sys 0.008s ok runtime/internal/wasitest 0.008s ok runtime/metrics 0.009s ok runtime/pprof 7.906s ok runtime/trace 0.124s ok slices 0.076s ok sort 0.060s ok strconv 0.441s ok strings 2.535s ok sync 0.488s ok sync/atomic 3.287s ok syscall 0.273s ok testing 1.065s ok testing/fstest 0.013s ok testing/iotest 0.007s ok testing/quick 0.063s ok testing/slogtest 0.010s ok text/scanner 0.009s ok text/tabwriter 0.009s ok text/template 0.044s ok text/template/parse 0.012s ok time 2.027s ok unicode 0.010s ok unicode/utf16 0.006s ok unicode/utf8 0.010s ok cmd/addr2line 1.244s ok cmd/api 0.732s ok cmd/asm/internal/asm 0.805s ok cmd/asm/internal/lex 0.015s ok cmd/cgo/internal/swig 0.019s ok cmd/cgo/internal/test 1.329s ok cmd/cgo/internal/testcarchive 0.250s ok cmd/cgo/internal/testcshared 0.429s ok cmd/cgo/internal/testerrors 23.428s ok cmd/cgo/internal/testfortran 0.025s ok cmd/cgo/internal/testgodefs 1.250s ok cmd/cgo/internal/testlife 1.362s ok cmd/cgo/internal/testnocgo 0.007s ok cmd/cgo/internal/testplugin 0.010s ok cmd/cgo/internal/testsanitizers 0.006s [no tests to run] ok cmd/cgo/internal/testshared 0.009s ok cmd/cgo/internal/testso 2.703s ok cmd/cgo/internal/teststdio 3.223s ok cmd/cgo/internal/testtls 0.008s ok cmd/compile/internal/abt 0.011s ok cmd/compile/internal/amd64 0.009s ok cmd/compile/internal/base 0.010s ok cmd/compile/internal/compare 0.007s ok cmd/compile/internal/devirtualize 0.012s ok cmd/compile/internal/dwarfgen 0.770s ok cmd/compile/internal/importer 1.202s ok cmd/compile/internal/inline/inlheur 2.280s ok cmd/compile/internal/ir 0.007s ok cmd/compile/internal/logopt 0.062s ok cmd/compile/internal/loopvar 0.010s ok cmd/compile/internal/noder 0.010s ok cmd/compile/internal/reflectdata 0.009s [no tests to run] ok cmd/compile/internal/ssa 19.259s ok cmd/compile/internal/syntax 0.067s ok cmd/compile/internal/test 33.609s ok cmd/compile/internal/typecheck 0.706s ok cmd/compile/internal/types 0.010s ok cmd/compile/internal/types2 7.550s ok cmd/covdata 0.019s ok cmd/cover 4.351s ok cmd/dist 0.013s ok cmd/distpack 0.009s ok cmd/doc 0.556s ok cmd/fix 5.912s ok cmd/go 148.573s ok cmd/go/internal/auth 0.010s ok cmd/go/internal/cache 0.239s ok cmd/go/internal/cfg 0.008s [no tests to run] ok cmd/go/internal/envcmd 0.067s ok cmd/go/internal/fsys 0.051s ok cmd/go/internal/generate 0.019s ok cmd/go/internal/gover 0.009s ok cmd/go/internal/imports 0.016s ok cmd/go/internal/load 0.015s ok cmd/go/internal/lockedfile 0.559s ok cmd/go/internal/lockedfile/internal/filelock 0.048s ok cmd/go/internal/modfetch 0.019s ok cmd/go/internal/modfetch/codehost 1.911s ok cmd/go/internal/modfetch/zip_sum_test 0.012s ok cmd/go/internal/modindex 0.270s ok cmd/go/internal/modload 0.013s ok cmd/go/internal/mvs 0.019s ok cmd/go/internal/par 0.025s ok cmd/go/internal/str 0.018s ok cmd/go/internal/test 0.028s ok cmd/go/internal/toolchain 0.014s ok cmd/go/internal/vcs 0.018s ok cmd/go/internal/vcweb 0.017s ok cmd/go/internal/vcweb/vcstest 3.482s ok cmd/go/internal/web 0.021s ok cmd/go/internal/work 0.104s ok cmd/gofmt 0.045s ok cmd/internal/archive 0.836s ok cmd/internal/buildid 0.252s ok cmd/internal/bootstrap_test 0.005s ok cmd/internal/cov 0.629s ok cmd/internal/dwarf 0.007s ok cmd/internal/edit 0.005s ok cmd/internal/goobj 0.006s ok cmd/internal/moddeps 3.728s ok cmd/internal/notsha256 0.006s ok cmd/internal/obj 1.199s ok cmd/internal/obj/arm64 0.465s ok cmd/internal/obj/ppc64 0.106s ok cmd/internal/obj/riscv 0.056s ok cmd/internal/obj/s390x 0.006s ok cmd/internal/obj/x86 6.428s ok cmd/internal/objabi 0.067s ok cmd/internal/pkgpath 0.029s ok cmd/internal/pkgpattern 0.007s ok cmd/internal/quoted 0.006s ok cmd/internal/src 0.006s ok cmd/internal/test2json 0.187s ok cmd/link 36.036s ok cmd/link/internal/benchmark 0.015s ok cmd/link/internal/ld 29.608s ok cmd/link/internal/loader 0.026s ok cmd/nm 4.030s ok cmd/objdump 6.741s ok cmd/pack 0.999s ok cmd/pprof 1.899s ok cmd/trace 0.013s ok cmd/trace/v2 0.081s ok cmd/vet 24.434s ##### os/user with tag osusergo ok os/user 0.012s ##### hash/maphash purego implementation ok hash/maphash 0.102s ##### GOMAXPROCS=2 runtime -cpu=1,2,4 -quick --- FAIL: TestRuntimeLockMetricsAndProfile (0.03s) metrics_test.go:967: NumCPU 2 metrics_test.go:968: GOMAXPROCS 4 --- FAIL: TestRuntimeLockMetricsAndProfile/runtime.semrelease (0.00s) metrics_test.go:1056: lock contention growth in runtime/pprof's view (0.000013s) metrics_test.go:1057: lock contention growth in runtime/metrics' view (0.000013s) metrics_test.go:1095: stack [runtime.unlock runtime.semrelease1 runtime_test.TestRuntimeLockMetricsAndProfile.func6.1 runtime_test.(*contentionWorker).run] has samples totaling n=0 value=0 metrics_test.go:1095: stack [runtime.unlock runtime.semacquire1 runtime.semacquire runtime_test.TestRuntimeLockMetricsAndProfile.func6.1 runtime_test.(*contentionWorker).run] has samples totaling n=0 value=0 metrics_test.go:1098: profile: PeriodType: contentions count Period: 1 Time: 2023-11-29 19:47:19.065803831 +0000 UTC Samples: contentions/count delay/nanoseconds 400 51432545: 1 2 3 1 12384: 4 5 6 2 3384: 7 8 9 10 6 1 408: 11 12 13 14 15 -400 -51432545: 16 17 18 -2 -3384: 19 20 21 22 23 Locations 1: 0x20348 M=1 runtime.unlock /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/lock_futex.go:121 s=120 2: 0x340f1f M=1 runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/metrics_test.go:1150 s=1133 3: 0x34205f M=1 runtime_test.(*contentionWorker).run /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/metrics_test.go:1287 s=1283 4: 0x5bc8c M=1 runtime.unlock /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/lock_futex.go:121 s=120 runtime.parkunlock_c /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:4018 s=4017 5: 0x5be07 M=1 runtime.park_m /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:4039 s=4023 6: 0x906e3 M=1 runtime.mcall /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/asm_arm64.s:192 s=166 7: 0x5848c M=1 runtime.unlock /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/lock_futex.go:121 s=120 runtime.stopm /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:2797 s=2782 8: 0x5a437 M=1 runtime.findRunnable /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:3528 s=3155 9: 0x5b877 M=1 runtime.schedule /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:3884 s=3855 10: 0x5cb07 M=1 runtime.goexit0 /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:4255 s=4195 11: 0x67cb8 M=1 runtime.unlock /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/lock_futex.go:121 s=120 runtime.semrelease1 /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/sema.go:198 s=175 12: 0x8ea9b M=1 sync.runtime_Semrelease /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/sema.go:72 s=71 13: 0x9b9df M=1 sync.(*WaitGroup).Add /tmp/workdir-host-freebsd-arm64-dmgk/go/src/sync/waitgroup.go:81 s=43 14: 0x341d0b M=1 sync.(*WaitGroup).Done /tmp/workdir-host-freebsd-arm64-dmgk/go/src/sync/waitgroup.go:87 s=86 runtime_test.TestRuntimeLockMetricsAndProfile.func4.1.1.1 /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/metrics_test.go:1030 s=1029 15: 0x34204f M=1 runtime_test.(*contentionWorker).run /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/metrics_test.go:1285 s=1283 16: 0x20348 M=2 runtime.unlock /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/lock_futex.go:121 s=120 17: 0x340f1f M=2 runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/metrics_test.go:1150 s=1133 18: 0x34205f M=2 runtime_test.(*contentionWorker).run /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/metrics_test.go:1287 s=1283 19: 0x5848c M=2 runtime.unlock /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/lock_futex.go:121 s=120 runtime.stopm /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:2797 s=2782 20: 0x5a437 M=2 runtime.findRunnable /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:3528 s=3155 21: 0x5b877 M=2 runtime.schedule /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:3884 s=3855 22: 0x5cb07 M=2 runtime.goexit0 /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/proc.go:4255 s=4195 23: 0x906e3 M=2 runtime.mcall /tmp/workdir-host-freebsd-arm64-dmgk/go/src/runtime/asm_arm64.s:192 s=166 Mappings 1: 0x0/0x0/0x0 [FN] 2: 0x0/0x0/0x0 [FN] metrics_test.go:1100: have stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] metrics_test.go:1100: have stack [runtime.unlock runtime.parkunlock_c runtime.park_m runtime.mcall] metrics_test.go:1100: have stack [runtime.unlock runtime.stopm runtime.findRunnable runtime.schedule runtime.goexit0 runtime.mcall] metrics_test.go:1100: have stack [runtime.unlock runtime.semrelease1 sync.runtime_Semrelease sync.(*WaitGroup).Add sync.(*WaitGroup).Done runtime_test.TestRuntimeLockMetricsAndProfile.func4.1.1.1 runtime_test.(*contentionWorker).run] metrics_test.go:1100: have stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] metrics_test.go:1100: have stack [runtime.unlock runtime.stopm runtime.findRunnable runtime.schedule runtime.goexit0 runtime.mcall] metrics_test.go:1103: want stack [runtime.unlock runtime.semrelease1 runtime_test.TestRuntimeLockMetricsAndProfile.func6.1 runtime_test.(*contentionWorker).run] metrics_test.go:1103: want stack [runtime.unlock runtime.semacquire1 runtime.semacquire runtime_test.TestRuntimeLockMetricsAndProfile.func6.1 runtime_test.(*contentionWorker).run] metrics_test.go:1271: finished test early (9999534 tries remaining) FAIL FAIL runtime 122.434s FAIL 2023/11/29 19:48:02 Failed: exit status 1 go tool dist: FAILED