Use of LUCI is subject to the Google [Terms of Service](
https://policies.google.com/terms) and [Privacy Policy](
https://policies.google.com/privacy)
[I2025-10-17T17:58:44.190076Z 116382 0 sink.go:354] SinkServer: warm-up started
[I2025-10-17T17:58:44.190210Z 116382 0 sink.go:427] SinkServer: starting HTTP server...
[I2025-10-17T17:58:44.191277Z 116382 0 sink.go:359] SinkServer: warm-up ended
[I2025-10-17T17:58:44.191569Z 116382 0 cmd_stream.go:577] rdb-stream: starting the test command - ["/home/swarming/.swarming/w/ir/cache/tools/bin/result_adapter" "go" "-v=false" "-dump-json" "/home/swarming/.swarming/w/ir/x/w/dist.testjson" "--" "/home/swarming/.swarming/w/ir/x/w/goroot/bin/go" "tool" "dist" "test" "-json" "archive/zip" "cmp" "container/heap" "crypto" "crypto/des" "crypto/ecdh" "crypto/fips140" "crypto/internal/boring/bcache" "crypto/internal/fips140/aes/gcm" "crypto/internal/fips140/ecdsa" "crypto/internal/fips140/edwards25519" "crypto/internal/fips140/entropy" "crypto/internal/fips140/hkdf" "crypto/internal/fips140/hmac" "crypto/internal/fips140/nistec" "crypto/internal/fips140/sha256" "crypto/internal/fips140/sha512" "crypto/internal/fips140/ssh" "crypto/internal/fips140/subtle" "crypto/internal/fips140/tls12" "crypto/internal/fips140cache" "crypto/internal/fips140deps/cpu" "crypto/internal/fips140deps/godebug" "crypto/internal/fips140deps/time" "crypto/internal/sysrand/internal/seccomp" "database/sql" "debug/macho" "embed/internal/embedtest" "errors" "go/constant" "go/format" "go/importer" "go/parser" "go/types" "go/version" "hash/maphash" "html" "image/color" "internal/abi" "internal/chacha8rand" "internal/coverage/encodecounter" "internal/coverage/encodemeta" "internal/coverage/slicewriter" "internal/goexperiment" "internal/goos" "internal/gover" "internal/poll" "internal/profile" "internal/reflectlite" "internal/runtime/gc/internal/gen" "internal/trace" "internal/trace/traceviewer" "internal/trace/traceviewer/format" "log" "log/internal" "log/slog/internal/benchmarks" "maps" "math" "math/big/internal/asmgen" "math/bits" "math/rand/v2" "mime/multipart" "mime/quotedprintable" "net/http/cookiejar" "net/rpc/jsonrpc" "os" "os/exec" "os/user" "reflect" "runtime/cgo" "runtime/coverage" "runtime/pprof" "runtime/race" "runtime/trace" "strings" "sync/atomic" "text/template/parse" "time" "unicode/utf16" "cmd/addr2line" "cmd/asm/internal/lex" "cmd/buildid" "cmd/cgo/internal/test" "cmd/cgo/internal/test/issue27340" "cmd/cgo/internal/test/issue41761a" "cmd/cgo/internal/test/issue8828" "cmd/cgo/internal/test/issue9510b" "cmd/cgo/internal/testcarchive" "cmd/cgo/internal/testlife" "cmd/cgo/internal/testsanitizers" "cmd/cgo/internal/testshared" "cmd/compile/internal/abi" "cmd/compile/internal/arm" "cmd/compile/internal/arm64" "cmd/compile/internal/deadlocals" "cmd/compile/internal/dwarfgen" "cmd/compile/internal/escape" "cmd/compile/internal/liveness" "cmd/compile/internal/logopt" "cmd/compile/internal/mips64" "cmd/compile/internal/riscv64" "cmd/compile/internal/ssa" "cmd/compile/internal/staticinit" "cmd/compile/internal/syntax" "cmd/compile/internal/test" "cmd/compile/internal/types" "cmd/compile/internal/x86" "cmd/fix" "cmd/go/internal/auth" "cmd/go/internal/base" "cmd/go/internal/load" "cmd/go/internal/modfetch" "cmd/go/internal/tool" "cmd/go/internal/vcs" "cmd/go/internal/version" "cmd/go/internal/workcmd" "cmd/gofmt" "cmd/internal/browser" "cmd/internal/codesign" "cmd/internal/cov" "cmd/internal/cov/covcmd" "cmd/internal/gcprog" "cmd/internal/hash" "cmd/internal/moddeps" "cmd/internal/obj/mips" "cmd/internal/obj/ppc64" "cmd/internal/obj/riscv" "cmd/internal/obj/s390x" "cmd/internal/pathcache" "cmd/internal/pkgpath" "cmd/internal/script/scripttest" "cmd/internal/sys" "cmd/link/internal/arm" "cmd/link/internal/arm64" "cmd/link/internal/loadelf" "cmd/link/internal/loadmacho" "cmd/link/internal/mips" "cmd/link/internal/x86" "os/user:osusergo" "crypto/...:gofips140-inprocess" "sync:cpu10" "cmd/cgo/internal/testtls:external" "cmd/internal/testdir:1_10" "cmd/internal/testdir:7_10"]
go tool dist: Failed: exit status 1
ok archive/zip 0.833s
ok cmp 0.033s
ok container/heap 0.015s
ok crypto 2.088s
ok crypto/des 0.102s
ok crypto/ecdh 0.799s
ok crypto/fips140 0.036s
ok crypto/internal/boring/bcache 0.211s
ok crypto/internal/fips140/aes/gcm 0.069s [no tests to run]
ok crypto/internal/fips140/ecdsa 0.465s
ok crypto/internal/fips140/edwards25519 1.423s
ok crypto/internal/fips140/nistec 0.529s
ok crypto/internal/fips140/subtle 0.056s
ok crypto/internal/fips140cache 0.865s
ok database/sql 0.689s
ok debug/macho 0.057s
ok embed/internal/embedtest 0.038s
ok errors 0.044s
ok go/constant 0.050s
ok go/format 0.031s
ok go/importer 0.818s
ok go/parser 0.416s
ok go/types 31.720s
ok go/version 0.043s
ok hash/maphash 0.757s
ok html 0.066s
ok image/color 0.057s
ok internal/abi 0.401s
ok internal/chacha8rand 0.030s
ok internal/coverage/slicewriter 0.028s
ok internal/gover 0.016s
ok internal/poll 0.178s
ok internal/profile 0.025s
ok internal/reflectlite 0.052s
ok internal/trace 32.156s
ok log 0.045s
ok log/slog/internal/benchmarks 0.039s
ok maps 0.025s
ok math 0.059s
ok math/big/internal/asmgen 0.053s
ok math/bits 0.036s
ok math/rand/v2 1.348s
ok mime/multipart 2.652s
ok mime/quotedprintable 0.052s
ok net/http/cookiejar 0.048s
ok net/rpc/jsonrpc 0.026s
ok os 5.659s
ok os/exec 1.022s
ok os/user 0.056s
ok reflect 1.268s
ok runtime/cgo 0.033s
--- FAIL: TestCPUProfile (102.52s)
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 2, ideally 10
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 200ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 5, ideally 20
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 400ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 10, ideally 40
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 800ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 20, ideally 80
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 1.6s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 40, ideally 160
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 3.2s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 80, ideally 320
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 6.4s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 160, ideally 640
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 12.8s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 320, ideally 1280
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 25.6s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 640, ideally 2560
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 51.2s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 1280, ideally 5120
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
panic: test timed out after 3m0s
running tests:
TestCPUProfileInlining (0s)
goroutine 55 gp=0xb88b0e8 m=11 mp=0xba00008 [running]:
panic({0x82217a0, 0xb972018})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:877 +0x14c fp=0xb991f84 sp=0xb991f30 pc=0x80da93c
testing.(*M).startAlarm.func1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2795 +0x367 fp=0xb991ff0 sp=0xb991f84 pc=0x815ee47
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb991ff4 sp=0xb991ff0 pc=0x80e1201
created by time.goFunc
/home/swarming/.swarming/w/ir/x/w/goroot/src/time/sleep.go:215 +0x2f
goroutine 1 gp=0xb802248 m=nil [chan receive]:
runtime.gopark(0x8264674, 0xb818628, 0x13, 0x7, 0x2)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb942c24 sp=0xb942c10 pc=0x80dadec
runtime.chanrecv(0xb8185f0, 0xb942c9f, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/chan.go:667 +0x46f fp=0xb942c64 sp=0xb942c24 pc=0x8058d0f
runtime.chanrecv1(0xb8185f0, 0xb942c9f)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/chan.go:509 +0x1c fp=0xb942c78 sp=0xb942c64 pc=0x805889c
testing.(*T).Run(0xb94c008, {0x8254fd4, 0x16}, 0x8264348)
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2102 +0x54a fp=0xb942d00 sp=0xb942c78 pc=0x815b41a
testing.runTests.func1(0xb94c008)
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2578 +0x45 fp=0xb942d20 sp=0xb942d00 pc=0x815d7a5
testing.tRunner(0xb94c008, 0xb942de8)
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2029 +0x114 fp=0xb942d80 sp=0xb942d20 pc=0x815a344
testing.runTests({0x0, 0x0}, {0x8251a93, 0xd}, 0xb9180b0, {0x83d4e00, 0x2e, 0x2e}, {0xc234bf0a9824cbcb, 0x29e8e12028, ...})
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2576 +0x45c fp=0xb942dfc sp=0xb942d80 pc=0x815d6ac
testing.(*M).Run(0xb910050)
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2436 +0x6bf fp=0xb942f50 sp=0xb942dfc pc=0x815bf8f
main.main()
_testmain.go:140 +0xe5 fp=0xb942fa8 sp=0xb942f50 pc=0x81f75c5
runtime.main()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:289 +0x2dc fp=0xb942ff0 sp=0xb942fa8 pc=0x80a506c
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb942ff4 sp=0xb942ff0 pc=0x80e1201
goroutine 2 gp=0xb8025a8 m=nil [force gc (idle), 2 minutes]:
runtime.gopark(0x8264920, 0x83d7fc8, 0xb, 0xa, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb860fdc sp=0xb860fc8 pc=0x80dadec
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.forcegchelper()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:377 +0xd3 fp=0xb860ff0 sp=0xb860fdc pc=0x80a53a3
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb860ff4 sp=0xb860ff0 pc=0x80e1201
created by runtime.init.6 in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:365 +0x23
goroutine 3 gp=0xb802908 m=nil [GC sweep wait]:
runtime.gopark(0x8264920, 0x83d8170, 0x8, 0x9, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb8617cc sp=0xb8617b8 pc=0x80dadec
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.bgsweep(0xb818320)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go:324 +0x15b fp=0xb8617e8 sp=0xb8617cc pc=0x808d73b
runtime.gcenable.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:212 +0x20 fp=0xb8617f0 sp=0xb8617e8 pc=0x807b6d0
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb8617f4 sp=0xb8617f0 pc=0x80e1201
created by runtime.gcenable in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:212 +0x77
goroutine 4 gp=0xb802a28 m=nil [GC scavenge wait]:
runtime.gopark(0x8264920, 0x83d8540, 0x9, 0xa, 0x2)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb861fb8 sp=0xb861fa4 pc=0x80dadec
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.(*scavengerState).park(0x83d8540)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go:425 +0x60 fp=0xb861fcc sp=0xb861fb8 pc=0x808aca0
runtime.bgscavenge(0xb818320)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go:658 +0x6f fp=0xb861fe8 sp=0xb861fcc pc=0x808b33f
runtime.gcenable.gowrap2()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:213 +0x20 fp=0xb861ff0 sp=0xb861fe8 pc=0x807b6a0
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb861ff4 sp=0xb861ff0 pc=0x80e1201
created by runtime.gcenable in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:213 +0xb7
goroutine 17 gp=0xb902008 m=nil [GOMAXPROCS updater (idle), 2 minutes]:
runtime.gopark(0x8264920, 0x83d8130, 0xc, 0xa, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85c7ac sp=0xb85c798 pc=0x80dadec
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.updateMaxProcsGoroutine()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:6704 +0xf3 fp=0xb85c7f0 sp=0xb85c7ac pc=0x80b3303
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85c7f4 sp=0xb85c7f0 pc=0x80e1201
created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:6692 +0x3f
goroutine 18 gp=0xb902128 m=nil [finalizer wait, 2 minutes]:
runtime.gopark(0x826469c, 0x83e51dc, 0xa, 0xa, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85cf8c sp=0xb85cf78 pc=0x80dadec
runtime.runFinalizers()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go:210 +0xf8 fp=0xb85cff0 sp=0xb85cf8c pc=0x807a738
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85cff4 sp=0xb85cff0 pc=0x80e1201
created by runtime.createfing in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go:172 +0x60
goroutine 113 gp=0xb902248 m=nil [chan receive]:
runtime.gopark(0x8264674, 0xb906178, 0x13, 0x7, 0x2)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb8bce08 sp=0xb8bcdf4 pc=0x80dadec
runtime.chanrecv(0xb906140, 0x0, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/chan.go:667 +0x46f fp=0xb8bce48 sp=0xb8bce08 pc=0x8058d0f
runtime.chanrecv1(0xb906140, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/chan.go:509 +0x1c fp=0xb8bce5c sp=0xb8bce48 pc=0x805889c
runtime/pprof.StopCPUProfile()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:923 +0x8b fp=0xb8bce78 sp=0xb8bce5c pc=0x81b9c4b
runtime/pprof.testCPUProfile(0xb94c3c8, 0xb8bcf48, 0x8264524)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof_test.go:467 +0x270 fp=0xb8bcf00 sp=0xb8bce78 pc=0x81cc400
runtime/pprof.TestCPUProfileInlining(0xb94c3c8)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof_test.go:285 +0x133 fp=0xb8bcf84 sp=0xb8bcf00 pc=0x81ca9d3
testing.tRunner(0xb94c3c8, 0x8264348)
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2029 +0x114 fp=0xb8bcfe4 sp=0xb8bcf84 pc=0x815a344
testing.(*T).Run.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2094 +0x27 fp=0xb8bcff0 sp=0xb8bcfe4 pc=0x815b577
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb8bcff4 sp=0xb8bcff0 pc=0x80e1201
created by testing.(*T).Run in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2094 +0x52b
goroutine 22 gp=0xb902368 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5c000, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85d798 sp=0xb85d784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xb85d7e8 sp=0xb85d798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xb85d7f0 sp=0xb85d7e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85d7f4 sp=0xb85d7f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 23 gp=0xb902488 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5c200, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85df98 sp=0xb85df84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xb85dfe8 sp=0xb85df98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xb85dff0 sp=0xb85dfe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85dff4 sp=0xb85dff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 24 gp=0xb9025a8 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5c400, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85e798 sp=0xb85e784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xb85e7e8 sp=0xb85e798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xb85e7f0 sp=0xb85e7e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85e7f4 sp=0xb85e7f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 25 gp=0xb9026c8 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5c600, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85ef98 sp=0xb85ef84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xb85efe8 sp=0xb85ef98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xb85eff0 sp=0xb85efe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85eff4 sp=0xb85eff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 26 gp=0xb9027e8 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5c800, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85f798 sp=0xb85f784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xb85f7e8 sp=0xb85f798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xb85f7f0 sp=0xb85f7e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85f7f4 sp=0xb85f7f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 27 gp=0xb902908 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5ca00, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb85ff98 sp=0xb85ff84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xb85ffe8 sp=0xb85ff98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xb85fff0 sp=0xb85ffe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb85fff4 sp=0xb85fff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 28 gp=0xb902a28 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5cc00, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb62798 sp=0xbb62784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb627e8 sp=0xbb62798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb627f0 sp=0xbb627e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb627f4 sp=0xbb627f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 29 gp=0xb902b48 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5ce00, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb62f98 sp=0xbb62f84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb62fe8 sp=0xbb62f98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb62ff0 sp=0xbb62fe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb62ff4 sp=0xbb62ff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 30 gp=0xb902c68 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5d000, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb63798 sp=0xbb63784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb637e8 sp=0xbb63798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb637f0 sp=0xbb637e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb637f4 sp=0xbb637f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 31 gp=0xb902d88 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5d200, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb63f98 sp=0xbb63f84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb63fe8 sp=0xbb63f98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb63ff0 sp=0xbb63fe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb63ff4 sp=0xbb63ff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 32 gp=0xb902ea8 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5d400, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb64798 sp=0xbb64784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb647e8 sp=0xbb64798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb647f0 sp=0xbb647e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb647f4 sp=0xbb647f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 33 gp=0xb902fc8 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x82646ac, 0xbb5d600, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb64f98 sp=0xbb64f84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb64fe8 sp=0xbb64f98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb64ff0 sp=0xbb64fe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb64ff4 sp=0xbb64ff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 34 gp=0xb9030e8 m=nil [GC worker (idle)]:
runtime.gopark(0x82646ac, 0xbb5d800, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb65798 sp=0xbb65784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb657e8 sp=0xbb65798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb657f0 sp=0xbb657e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb657f4 sp=0xbb657f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 35 gp=0xb903208 m=nil [GC worker (idle)]:
runtime.gopark(0x82646ac, 0xbb5da00, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb65f98 sp=0xbb65f84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb65fe8 sp=0xbb65f98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb65ff0 sp=0xbb65fe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb65ff4 sp=0xbb65ff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 36 gp=0xb903328 m=nil [GC worker (idle)]:
runtime.gopark(0x82646ac, 0xbb5dc00, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb5e798 sp=0xbb5e784 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb5e7e8 sp=0xbb5e798 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb5e7f0 sp=0xbb5e7e8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb5e7f4 sp=0xbb5e7f0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 37 gp=0xb903448 m=nil [GC worker (idle)]:
runtime.gopark(0x82646ac, 0xbb5de00, 0x1c, 0xa, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xbb5ef98 sp=0xbb5ef84 pc=0x80dadec
runtime.gcBgMarkWorker(0xb906240)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1756 +0xff fp=0xbb5efe8 sp=0xbb5ef98 pc=0x807e81f
runtime.gcBgMarkStartWorkers.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x20 fp=0xbb5eff0 sp=0xbb5efe8 pc=0x807e710
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xbb5eff4 sp=0xbb5eff0 pc=0x80e1201
created by runtime.gcBgMarkStartWorkers in goroutine 21
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1666 +0x12c
goroutine 114 gp=0xb802ea8 m=nil [sleep]:
runtime.gopark(0x826493c, 0x0, 0xe, 0xe, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x10c fp=0xb943f78 sp=0xb943f64 pc=0x80dadec
time.Sleep(0x5f5e100)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go:363 +0x1be fp=0xb943fac sp=0xb943f78 pc=0x80decce
runtime/pprof.profileWriter({0x829f450, 0xb9b8180})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:893 +0x59 fp=0xb943fe4 sp=0xb943fac pc=0x81b9aa9
runtime/pprof.StartCPUProfile.gowrap2()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:878 +0x27 fp=0xb943ff0 sp=0xb943fe4 pc=0x81b9a07
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0xb943ff4 sp=0xb943ff0 pc=0x80e1201
created by runtime/pprof.StartCPUProfile in goroutine 113
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:878 +0x1ab
--- FAIL: TestCPUProfileMultithreadMagnitude (25.82s)
pprof_test.go:122: Failure of this test may indicate that your system suffers from a known Linux kernel bug fixed on newer kernels. See
https://golang.org/issue/49065.
--- FAIL: TestCPUProfileMultithreadMagnitude/parallel (0.21s)
pprof_test.go:185: Running with 16 workers
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 2, ideally 10
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
--- FAIL: TestCPUProfileMultithreadMagnitude/serial (25.61s)
pprof_test.go:185: Running with 1 workers
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 2, ideally 10
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 200ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 5, ideally 20
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 400ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 10, ideally 40
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 800ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 20, ideally 80
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 1.6s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 40, ideally 160
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 3.2s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 80, ideally 320
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 6.4s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 160, ideally 640
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 12.8s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 320, ideally 1280
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
--- FAIL: TestCPUProfileMultithreaded (51.24s)
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 2, ideally 10
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 200ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 5, ideally 20
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 400ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 10, ideally 40
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 800ms duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 20, ideally 80
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 1.6s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 40, ideally 160
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 3.2s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 80, ideally 320
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 6.4s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 160, ideally 640
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 12.8s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 320, ideally 1280
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:477: retrying with 25.6s duration
pprof_test.go:531: total 0 CPU profile samples collected:
pprof_test.go:547: too few samples; got 0, want at least 640, ideally 2560
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog1
pprof_test.go:602: found 0 samples in expected function runtime/pprof.cpuHog2
pprof_test.go:605: no samples in expected functions
pprof_test.go:616: runtime/pprof.cpuHog1 has 0 samples out of 0, want at least 1, ideally 0
pprof_test.go:616: runtime/pprof.cpuHog2 has 0 samples out of 0, want at least 1, ideally 0
FAIL runtime/pprof 180.013s
ok runtime/trace 6.343s
ok strings 2.432s
ok sync/atomic 1.660s
ok text/template/parse 0.052s
ok time 14.555s
ok unicode/utf16 0.023s
ok cmd/addr2line 2.341s
ok cmd/asm/internal/lex 0.021s
ok cmd/cgo/internal/test 1.425s
--- FAIL: TestSIGPROF (2.56s)
carchive_test.go:1047: [/home/swarming/.swarming/w/ir/x/w/goroot/bin/go build -buildmode=c-archive -o libgo6.a ./libgo6]
carchive_test.go:1059: [gcc -fPIC -m32 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build4213119447=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2068353652/pkg/linux_386_shared/testcarchive -o testp6 main6.c libgo6.a]
carchive_test.go:1067: [./testp6]
runtime: failed to configure profiling timer; timer_settime(0, 0, {interval: {0s + 10000000ns} value: {0s + 8311482ns}}, nil) errno=22
fatal error: timer_settime
goroutine 17 gp=0x580025a8 m=1 mp=0x58064008 [running, locked to thread]:
runtime.throw({0x566817b6, 0xd})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:1227 +0x3a fp=0x58072dd0 sp=0x58072dbc pc=0x5662d58a
runtime.setThreadCPUProfiler(0x64)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/os_linux.go:700 +0x5c1 fp=0x58072e88 sp=0x58072dd0 pc=0x565f30b1
runtime.setcpuprofilerate(0x64)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:5752 +0x12b fp=0x58072ea4 sp=0x58072e88 pc=0x5660432b
runtime.SetCPUProfileRate(0x64)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/cpuprof.go:89 +0x189 fp=0x58072edc sp=0x58072ea4 pc=0x565aaf09
runtime/pprof.StartCPUProfile({0x566d28f4, 0x5677ef00})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/pprof/pprof.go:877 +0x198 fp=0x58072f20 sp=0x58072edc pc=0x56674478
main.go_start_profile(...)
/home/swarming/.swarming/w/ir/x/t/carchive_test2068353652/src/testcarchive/libgo6/sigprof.go:16
_cgoexp_bc687279c20c_go_start_profile(0xff9519cf)
_cgo_gotypes.go:44 +0x33 fp=0x58072f34 sp=0x58072f20 pc=0x5667efc3
runtime.cgocallbackg1(0x5667ef90, 0xff9519cf, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/cgocall.go:446 +0x205 fp=0x58072fb0 sp=0x58072f34 pc=0x565a8e15
runtime.cgocallbackg(0x5667ef90, 0xff9519cf, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/cgocall.go:350 +0x130 fp=0x58072fe0 sp=0x58072fb0 pc=0x565a8b30
runtime.cgocallback(0x0, 0x0, 0x0)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:806 +0xa5 fp=0x58072ff0 sp=0x58072fe0 pc=0x56632e05
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0x58072ff4 sp=0x58072ff0 pc=0x56633091
goroutine 2 gp=0x58002908 m=nil [force gc (idle)]:
runtime.gopark(0x566d2488, 0x56772ad8, 0xb, 0xa, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x11b fp=0x58060fdc sp=0x58060fc8 pc=0x5662d6ab
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.forcegchelper()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:377 +0x107 fp=0x58060ff0 sp=0x58060fdc pc=0x565f7f77
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0x58060ff4 sp=0x58060ff0 pc=0x56633091
created by runtime.init.6 in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:365 +0x29
goroutine 3 gp=0x58002c68 m=nil [GC sweep wait]:
runtime.gopark(0x566d2488, 0x56772bf0, 0x8, 0x9, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x11b fp=0x580617cc sp=0x580617b8 pc=0x5662d6ab
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.bgsweep(0x5801a370)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go:279 +0xcd fp=0x580617e8 sp=0x580617cc pc=0x565e22fd
runtime.gcenable.gowrap1()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:212 +0x21 fp=0x580617f0 sp=0x580617e8 pc=0x565ceb01
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0x580617f4 sp=0x580617f0 pc=0x56633091
created by runtime.gcenable in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:212 +0x8e
goroutine 4 gp=0x58002d88 m=nil [GC scavenge wait]:
runtime.gopark(0x566d2488, 0x56772d20, 0x9, 0xa, 0x2)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x11b fp=0x58061fb8 sp=0x58061fa4 pc=0x5662d6ab
runtime.goparkunlock(...)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:470
runtime.(*scavengerState).park(0x56772d20)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go:425 +0x67 fp=0x58061fcc sp=0x58061fb8 pc=0x565df697
runtime.bgscavenge(0x5801a370)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go:653 +0x5b fp=0x58061fe8 sp=0x58061fcc pc=0x565dfdab
runtime.gcenable.gowrap2()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:213 +0x21 fp=0x58061ff0 sp=0x58061fe8 pc=0x565cead1
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0x58061ff4 sp=0x58061ff0 pc=0x56633091
created by runtime.gcenable in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:213 +0xdf
goroutine 5 gp=0x58003328 m=nil [finalizer wait]:
runtime.gopark(0x566d2204, 0x5677ef44, 0xa, 0xa, 0x1)
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:464 +0x11b fp=0x5806078c sp=0x58060778 pc=0x5662d6ab
runtime.runFinalizers()
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go:210 +0x137 fp=0x580607f0 sp=0x5806078c pc=0x565cda07
runtime.goexit({})
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_386.s:1386 +0x1 fp=0x580607f4 sp=0x580607f0 pc=0x56633091
created by runtime.createfing in goroutine 1
/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go:172 +0x70
carchive_test.go:1069: signal: aborted (core dumped)
FAIL
FAIL cmd/cgo/internal/testcarchive 31.923s
ok cmd/cgo/internal/testlife 2.255s
ok cmd/cgo/internal/testsanitizers 0.128s
ok cmd/cgo/internal/testshared 87.463s
ok cmd/compile/internal/dwarfgen 0.355s
ok cmd/compile/internal/liveness 0.037s
ok cmd/compile/internal/logopt 0.059s
ok cmd/compile/internal/ssa 19.022s
ok cmd/compile/internal/syntax 0.063s
ok cmd/compile/internal/test 26.453s
ok cmd/compile/internal/types 0.005s
ok cmd/go/internal/auth 0.016s
ok cmd/go/internal/load 0.012s
ok cmd/go/internal/modfetch 0.021s
ok cmd/go/internal/vcs 0.015s
ok cmd/gofmt 0.056s
ok cmd/internal/cov 0.501s
ok cmd/internal/moddeps 1.870s
ok cmd/internal/obj/ppc64 0.158s
ok cmd/internal/obj/riscv 0.075s
ok cmd/internal/obj/s390x 0.006s
ok cmd/internal/pkgpath 0.027s
ok cmd/internal/sys 0.009s
Warning: no '=' in invocation-link-artifacts pair: "", ignoring
[W2025-10-17T18:02:19.138045Z 116382 0 cmd_stream.go:589] rdb-stream: test process exited with error: exit status 2
[I2025-10-17T18:02:19.138122Z 116382 0 cmd_stream.go:573] rdb-stream: the test process terminated
[I2025-10-17T18:02:19.138254Z 116382 0 sink.go:452] SinkServer: shutdown started
[I2025-10-17T18:02:19.138326Z 116382 0 sink.go:430] SinkServer: HTTP server stopped with "http: Server closed"
[I2025-10-17T18:02:19.138346Z 116382 0 sink_server.go:96] SinkServer: draining TestResult channel started
[I2025-10-17T18:02:19.906122Z 116382 0 sink_server.go:98] SinkServer: draining TestResult channel ended
[I2025-10-17T18:02:19.906184Z 116382 0 sink_server.go:100] SinkServer: draining Artifact channel started
[I2025-10-17T18:02:20.660230Z 116382 0 sink_server.go:102] SinkServer: draining Artifact channel ended
[I2025-10-17T18:02:20.660298Z 116382 0 sink.go:455] SinkServer: shutdown completed successfully
[I2025-10-17T18:02:20.660330Z 116382 0 cmd_stream.go:498] rdb-stream: exiting with 2