linux-s390x-ibm-race at 1c15291fa0efaeb14a76d0b0bcd8390665c9b97d :: Running /data/golang/workdir/go/src/make.bash with args ["/data/golang/workdir/go/src/make.bash"] and env ["LANG=en_US.UTF-8" "PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin" "HOME=/root" "LOGNAME=root" "USER=root" "SHELL=/bin/sh" "INVOCATION_ID=c684c3b95c284ad29565d27a56902bb7" "JOURNAL_STREAM=9:235600713" "META_BUILDLET_BINARY_URL=https://storage.googleapis.com/go-builder-data/buildlet.linux-s390x" "GO_STAGE0_NET_DELAY=0s" "GO_STAGE0_DL_DELAY=100ms" "WORKDIR=/data/golang/workdir" "GOROOT_BOOTSTRAP=/data/golang/workdir/go1.4" "GO_BUILDER_NAME=linux-s390x-ibm-race" "GO_BUILDER_FLAKY_NET=1" "GO_TEST_TIMEOUT_SCALE=5" "GOBIN=" "TMPDIR=/data/golang/workdir/tmp" "GOCACHE=/data/golang/workdir/gocache" "GOPLSCACHE=/data/golang/workdir/goplscache" "PWD=/data/golang/workdir/go/src"] in dir /data/golang/workdir/go/src Building Go cmd/dist using /data/golang/workdir/go1.4. (go1.20.6 linux/s390x) Building Go toolchain1 using /data/golang/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 linux/s390x. --- Installed Go for linux/s390x in /data/golang/workdir/go Installed commands in /data/golang/workdir/go/bin *** You need to add /data/golang/workdir/go/bin to your PATH. :: Running /data/golang/workdir/go/bin/go with args ["/data/golang/workdir/go/bin/go" "install" "-race" "std"] and env ["LANG=en_US.UTF-8" "PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin" "HOME=/root" "LOGNAME=root" "USER=root" "SHELL=/bin/sh" "INVOCATION_ID=c684c3b95c284ad29565d27a56902bb7" "JOURNAL_STREAM=9:235600713" "META_BUILDLET_BINARY_URL=https://storage.googleapis.com/go-builder-data/buildlet.linux-s390x" "GO_STAGE0_NET_DELAY=0s" "GO_STAGE0_DL_DELAY=100ms" "WORKDIR=/data/golang/workdir" "GOROOT_BOOTSTRAP=/data/golang/workdir/go1.4" "GO_BUILDER_NAME=linux-s390x-ibm-race" "GO_BUILDER_FLAKY_NET=1" "GO_TEST_TIMEOUT_SCALE=5" "GOBIN=" "TMPDIR=/data/golang/workdir/tmp" "GOCACHE=/data/golang/workdir/gocache" "GOPLSCACHE=/data/golang/workdir/goplscache" "PWD=/data/golang/workdir/go/bin"] in dir /data/golang/workdir/go/bin ##### Test execution environment. # GOARCH: s390x # CPU: # GOOS: linux # OS Version: Linux 4.18.0-372.19.1.el8_6.s390x #1 SMP Mon Jul 18 11:11:32 EDT 2022 s390x ##### Testing packages. ok archive/tar 2.111s ok archive/zip 4.758s ok bufio 1.658s ok bytes 3.077s ok cmp 1.016s ok compress/bzip2 1.833s ok compress/flate 19.325s ok compress/gzip 25.086s ok compress/lzw 2.482s ok compress/zlib 10.451s ok container/heap 1.035s ok container/list 1.030s ok container/ring 1.037s ok context 1.253s ok crypto 1.255s ok crypto/aes 1.085s ok crypto/cipher 1.140s ok crypto/des 1.141s ok crypto/dsa 1.201s ok crypto/ecdh 1.989s ok crypto/ecdsa 1.733s ok crypto/ed25519 2.867s ok crypto/elliptic 1.049s ok crypto/hmac 1.048s ok crypto/internal/alias 1.010s ok crypto/internal/bigmod 7.916s ok crypto/internal/boring 1.023s ok crypto/internal/boring/bcache 1.694s ok crypto/internal/edwards25519 3.894s ok crypto/internal/edwards25519/field 1.434s ok crypto/internal/nistec 3.025s ok crypto/internal/nistec/fiat 1.043s [no tests to run] ok crypto/md5 1.528s ok crypto/rand 1.570s ok crypto/rc4 1.922s ok crypto/rsa 5.150s ok crypto/sha1 1.056s ok crypto/sha256 1.075s ok crypto/sha512 1.027s ok crypto/subtle 2.281s ok crypto/tls 8.678s ok crypto/x509 3.958s ok database/sql 1.734s ok database/sql/driver 1.027s ok debug/buildinfo 1.030s ok debug/dwarf 1.146s ok debug/elf 3.095s ok debug/gosym 1.200s ok debug/macho 1.027s ok debug/pe 1.092s ok debug/plan9obj 1.021s ok embed 1.016s [no tests to run] ok embed/internal/embedtest 1.412s ok encoding/ascii85 1.055s ok encoding/asn1 1.019s ok encoding/base32 1.075s ok encoding/base64 1.070s ok encoding/binary 1.301s ok encoding/csv 1.368s ok encoding/gob 33.851s ok encoding/hex 1.302s ok encoding/json 5.883s ok encoding/pem 3.365s ok encoding/xml 1.308s ok errors 1.059s ok expvar 1.059s ok flag 3.217s ok fmt 1.598s ok go/ast 1.049s ok go/build 8.460s ok go/build/constraint 1.203s ok go/constant 1.205s ok go/doc 1.381s ok go/doc/comment 5.585s ok go/format 1.246s ok go/importer 2.961s ok go/internal/gccgoimporter 1.401s ok go/internal/gcimporter 22.182s ok go/internal/srcimporter 29.523s ok go/parser 3.260s ok go/printer 3.706s ok go/scanner 1.073s ok go/token 1.202s ok go/types 100.136s ok go/version 1.031s ok hash 1.022s ok hash/adler32 1.048s ok hash/crc32 1.079s ok hash/crc64 1.042s ok hash/fnv 1.014s ok hash/maphash 1.089s ok html 1.135s ok html/template 1.376s ok image 2.557s ok image/color 1.121s ok image/draw 2.573s ok image/gif 4.686s ok image/jpeg 4.391s ok image/png 7.037s ok index/suffixarray 5.267s ok internal/abi 1.553s ok internal/buildcfg 1.057s ok internal/coverage/cformat 1.024s ok internal/coverage/cmerge 1.121s ok internal/coverage/pods 1.471s ok internal/coverage/slicereader 1.038s ok internal/coverage/slicewriter 1.024s ok internal/coverage/test 1.068s ok internal/cpu 2.205s ok internal/dag 1.016s ok internal/diff 1.021s ok internal/fmtsort 1.107s ok internal/fuzz 1.113s ok internal/godebug 14.626s ok internal/godebugs 1.015s ok internal/gover 1.014s ok internal/intern 5.236s ok internal/itoa 1.383s ok internal/platform 2.715s ok internal/poll 1.831s ok internal/profile 1.105s ok internal/reflectlite 1.133s ok internal/safefilepath 1.132s ok internal/saferio 2.245s ok internal/singleflight 1.108s ok internal/testenv 2.552s ok internal/trace 1.776s ok internal/trace/v2 39.509s ok internal/types/errors 3.517s ok internal/unsafeheader 1.059s ok internal/xcoff 1.108s ok internal/zstd 1.059s ok io 1.124s ok io/fs 1.238s ok io/ioutil 1.073s ok log 1.048s ok log/slog 1.141s ok log/slog/internal/benchmarks 1.018s ok log/slog/internal/buffer 1.012s ok log/syslog 2.613s ok maps 1.021s ok math 1.061s ok math/big 9.759s ok math/bits 1.135s ok math/cmplx 1.030s ok math/rand 6.420s ok math/rand/v2 4.663s ok mime 1.039s ok mime/multipart 13.904s ok mime/quotedprintable 1.836s ok net 64.295s ok net/http 27.865s ok net/http/cgi 31.257s ok net/http/cookiejar 1.222s ok net/http/fcgi 1.224s ok net/http/httptest 1.629s ok net/http/httptrace 1.081s ok net/http/httputil 2.161s ok net/http/internal 1.198s ok net/http/internal/ascii 1.113s ok net/http/pprof 6.577s ok net/internal/socktest 1.214s ok net/mail 1.026s ok net/netip 1.743s ok net/rpc 1.068s ok net/rpc/jsonrpc 1.025s ok net/smtp 1.297s ok net/textproto 1.973s ok net/url 1.608s ok os 10.854s ok os/exec 11.786s ok os/exec/internal/fdtest 1.090s ok os/signal 25.174s ok os/user 1.180s ok path 1.781s ok path/filepath 1.047s ok plugin 1.035s ok reflect 6.897s ok regexp 8.510s ok regexp/syntax 14.390s --- FAIL: TestRuntimeLockMetricsAndProfile (38.23s) --- FAIL: TestRuntimeLockMetricsAndProfile/runtime.lock (0.29s) --- FAIL: TestRuntimeLockMetricsAndProfile/runtime.lock/sample-1 (0.18s) metrics_test.go:1048: lock contention growth in runtime/pprof's view (0.003057s) metrics_test.go:1049: lock contention growth in runtime/metrics' view (0.003058s) metrics_test.go:1077: stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] has samples totaling n=1 value=3057498 metrics_test.go:1156: mutex profile reported contention count different from the known true count (1 != 100) --- FAIL: TestRuntimeLockMetricsAndProfile/runtime.lock/sample-2 (0.11s) metrics_test.go:1038: no increase in mutex profile metrics_test.go:1041: no increase in /sync/mutex/wait/total:seconds metric metrics_test.go:1048: lock contention growth in runtime/pprof's view (0.000000s) metrics_test.go:1049: lock contention growth in runtime/metrics' view (0.000000s) metrics_test.go:1077: stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] has samples totaling n=0 value=0 metrics_test.go:1079: profile: PeriodType: contentions count Period: 1 Time: 2023-11-19 10:46:01.391826072 -0500 EST Samples: contentions/count delay/nanoseconds 1 3057498: 1 2 3 -1 -3057498: 4 5 6 Locations 1: 0x5c41e M=1 runtime.unlock /data/golang/workdir/go/src/runtime/lock_futex.go:117 s=116 2: 0x667471 M=1 runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 /data/golang/workdir/go/src/runtime/metrics_test.go:1129 s=1112 3: 0x6693bb M=1 runtime_test.(*contentionWorker).run /data/golang/workdir/go/src/runtime/metrics_test.go:1247 s=1243 4: 0x5c41e M=7 runtime.unlock /data/golang/workdir/go/src/runtime/lock_futex.go:117 s=116 5: 0x667471 M=7 runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 /data/golang/workdir/go/src/runtime/metrics_test.go:1129 s=1112 6: 0x6693bb M=7 runtime_test.(*contentionWorker).run /data/golang/workdir/go/src/runtime/metrics_test.go:1247 s=1243 Mappings 1: 0x10000/0x739000/0x0 /data/golang/workdir/tmp/go-build3226486922/b016/runtime.test [FN] 2: 0x3ffbcc80000/0x3ffbce1a000/0x0 /usr/lib64/libc-2.28.so 488fd2a4642f972046dd77bad3705e6625911a3b 3: 0x3ffbce80000/0x3ffbce9c000/0x0 /usr/lib64/libpthread-2.28.so f024c1621e82621ea41079a98b3b3a63a6fef9c7 4: 0x3ffbcf00000/0x3ffbcf16000/0x0 /usr/lib64/libresolv-2.28.so be64c7c2cdbf7a338ec667565ed4835cff61a844 5: 0x3ffbd000000/0x3ffbd028000/0x0 /usr/lib64/ld-2.28.so 369cc3a745b7185a5225a79b03b95e960a8174bc 6: 0x3ffbd07e000/0x3ffbd080000/0x0 [vdso] 7: 0x10000/0x739000/0x0 /data/golang/workdir/tmp/go-build3226486922/b016/runtime.test [FN] 8: 0x3ffbcc80000/0x3ffbce1a000/0x0 /usr/lib64/libc-2.28.so 488fd2a4642f972046dd77bad3705e6625911a3b 9: 0x3ffbce80000/0x3ffbce9c000/0x0 /usr/lib64/libpthread-2.28.so f024c1621e82621ea41079a98b3b3a63a6fef9c7 10: 0x3ffbcf00000/0x3ffbcf16000/0x0 /usr/lib64/libresolv-2.28.so be64c7c2cdbf7a338ec667565ed4835cff61a844 11: 0x3ffbd000000/0x3ffbd028000/0x0 /usr/lib64/ld-2.28.so 369cc3a745b7185a5225a79b03b95e960a8174bc 12: 0x3ffbd07e000/0x3ffbd080000/0x0 [vdso] metrics_test.go:1081: have stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] metrics_test.go:1081: have stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] metrics_test.go:1083: want stack [runtime.unlock runtime_test.TestRuntimeLockMetricsAndProfile.func5.1 runtime_test.(*contentionWorker).run] metrics_test.go:1183: runtime/metrics reported less than the known minimum contention duration (2.500000 * 0.000000s < 0.001000s) metrics_test.go:1186: mutex profile reported contention count too different from the expected count (0 far from 100) FAIL FAIL runtime 135.674s ok runtime/cgo 1.355s ok runtime/coverage 1.301s FAIL go tool dist: Failed: exit status 1