[I2024-09-08T05:23:03.723407Z 256 0 sink.go:276] SinkServer: warm-up started [I2024-09-08T05:23:03.723454Z 256 0 sink.go:346] SinkServer: starting HTTP server... [I2024-09-08T05:23:03.724329Z 256 0 sink.go:281] SinkServer: warm-up ended [I2024-09-08T05:23:03.724529Z 256 0 cmd_stream.go:492] 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" "reflect" "regexp" "runtime" "runtime/pprof" "sort" "strconv" "strings" "sync" "sync/atomic" "syscall" "testing" "time" "cmd/api" "cmd/cgo/internal/test" "cmd/cgo/internal/testcarchive" "cmd/cgo/internal/testcshared" "cmd/cgo/internal/testerrors" "cmd/cgo/internal/testfortran" "cmd/cgo/internal/testplugin" "cmd/cgo/internal/testsanitizers" "cmp" "container/heap" "crypto" "crypto/des" "crypto/ecdh" "crypto/internal/bigmod" "crypto/internal/boring/bcache" "crypto/internal/nistec/fiat" "debug/macho" "embed/internal/embedtest" "errors" "go/constant" "go/format" "go/importer" "go/version" "html" "image/color" "internal/coverage/slicewriter" "internal/gover" "internal/profile" "log" "log/slog/internal/benchmarks" "maps" "mime/multipart" "mime/quotedprintable" "net/http/cookiejar" "net/rpc/jsonrpc" "os/exec" "os/user" "runtime/cgo" "runtime/trace" "text/template/parse" "unicode/utf16" "cmd/addr2line" "cmd/asm/internal/lex" "cmd/cgo/internal/testlife" "cmd/compile/internal/dwarfgen" "cmd/compile/internal/liveness" "cmd/compile/internal/logopt" "cmd/compile/internal/types" "cmd/doc" "cmd/fix" "cmd/go/internal/auth" "cmd/internal/cov" "cmd/internal/notsha256" "cmd/internal/obj/s390x" "cmd/internal/pkgpath" "cmd/internal/sys" "os/user:osusergo" "cmd/cgo/internal/testtls:external" "cmd/internal/testdir:1_10" "cmd/internal/testdir:7_10"] go tool dist: Failed: exit status 1 ok reflect 1.370s ok regexp 0.896s --- FAIL: TestCgoToGoCallGoexit (0.03s) proc_test.go:1166: /home/swarming/.swarming/w/ir/x/t/go-build2925444755/testprogcgo.exe CgoToGoCallGoexit: exit status 2 proc_test.go:1168: want OK , got runtime: mp.lockedInt = 1 fatal error: runtime.Goexit called in a thread that was not created by the Go runtime runtime stack: runtime.throw({0x58336c?, 0x600000000?}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:1069 +0x38 fp=0xfde8ba7a9810 sp=0xfde8ba7a97e0 pc=0x472cb8 runtime.gdestroy(0x4000002700) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:4325 +0x308 fp=0xfde8ba7a9850 sp=0xfde8ba7a9810 pc=0x445598 runtime.goexit0(0x140?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:4278 +0x1c fp=0xfde8ba7a9870 sp=0xfde8ba7a9850 pc=0x44526c runtime.mcall() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:193 +0x54 fp=0xfde8ba7a9880 sp=0xfde8ba7a9870 pc=0x478754 goroutine 1 gp=0x40000021c0 m=0 mp=0x6b0ec0 [syscall]: runtime.cgocall(0x52cef4, 0x4000066e88) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/cgocall.go:167 +0x44 fp=0x4000066e50 sp=0x4000066e10 pc=0x46d114 main._Cfunc_foo() _cgo_gotypes.go:406 +0x30 fp=0x4000066e80 sp=0x4000066e50 pc=0x520a90 main.CgoToGoCallGoexit() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/testdata/testprogcgo/callback.go:52 +0x44 fp=0x4000066ea0 sp=0x4000066e80 pc=0x521bb4 main.main() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/testdata/testprogcgo/main.go:34 +0x11c fp=0x4000066f40 sp=0x4000066ea0 pc=0x5202ac runtime.main() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:283 +0x284 fp=0x4000066fd0 sp=0x4000066f40 pc=0x43bf04 runtime.goexit({}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:1223 +0x4 fp=0x4000066fd0 sp=0x4000066fd0 pc=0x47ac94 goroutine 2 gp=0x4000002c40 m=nil [force gc (idle)]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:435 +0xc8 fp=0x4000054f90 sp=0x4000054f70 pc=0x472dd8 runtime.goparkunlock(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:441 runtime.forcegchelper() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:348 +0xb8 fp=0x4000054fd0 sp=0x4000054f90 pc=0x43c258 runtime.goexit({}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:1223 +0x4 fp=0x4000054fd0 sp=0x4000054fd0 pc=0x47ac94 created by runtime.init.7 in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:336 +0x24 goroutine 3 gp=0x4000003180 m=nil [GC sweep wait]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:435 +0xc8 fp=0x4000055760 sp=0x4000055740 pc=0x472dd8 runtime.goparkunlock(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:441 runtime.bgsweep(0x4000080000) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go:277 +0xa0 fp=0x40000557b0 sp=0x4000055760 pc=0x4240e0 runtime.gcenable.gowrap1() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:203 +0x28 fp=0x40000557d0 sp=0x40000557b0 pc=0x417fb8 runtime.goexit({}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:1223 +0x4 fp=0x40000557d0 sp=0x40000557d0 pc=0x47ac94 created by runtime.gcenable in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:203 +0x6c goroutine 4 gp=0x4000003340 m=nil [GC scavenge wait]: runtime.gopark(0x4000080000?, 0x5af3e8?, 0x1?, 0x0?, 0x4000003340?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:435 +0xc8 fp=0x4000055f60 sp=0x4000055f40 pc=0x472dd8 runtime.goparkunlock(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:441 runtime.(*scavengerState).park(0x6b0440) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go:425 +0x5c fp=0x4000055f90 sp=0x4000055f60 pc=0x421bac runtime.bgscavenge(0x4000080000) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go:653 +0x44 fp=0x4000055fb0 sp=0x4000055f90 pc=0x4220c4 runtime.gcenable.gowrap2() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:204 +0x28 fp=0x4000055fd0 sp=0x4000055fb0 pc=0x417f58 runtime.goexit({}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:1223 +0x4 fp=0x4000055fd0 sp=0x4000055fd0 pc=0x47ac94 created by runtime.gcenable in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:204 +0xac goroutine 18 gp=0x4000106380 m=nil [finalizer wait]: runtime.gopark(0x40000545b8?, 0x473a88?, 0x8?, 0x0?, 0x545040?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:435 +0xc8 fp=0x4000054580 sp=0x4000054560 pc=0x472dd8 runtime.runfinq() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go:193 +0x108 fp=0x40000547d0 sp=0x4000054580 pc=0x4170b8 runtime.goexit({}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:1223 +0x4 fp=0x40000547d0 sp=0x40000547d0 pc=0x47ac94 created by runtime.createfing in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go:163 +0x80 goroutine 19 gp=0x4000106540 m=nil [chan receive]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:435 +0xc8 fp=0x40000506f0 sp=0x40000506d0 pc=0x472dd8 runtime.chanrecv(0x40001000e0, 0x0, 0x1) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/chan.go:639 +0x408 fp=0x4000050770 sp=0x40000506f0 pc=0x407b98 runtime.chanrecv1(0x0?, 0x0?) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/chan.go:489 +0x14 fp=0x40000507a0 sp=0x4000050770 pc=0x407754 runtime.unique_runtime_registerUniqueMapCleanup.func1(...) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1732 runtime.unique_runtime_registerUniqueMapCleanup.gowrap1() /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1735 +0x3c fp=0x40000507d0 sp=0x40000507a0 pc=0x41b0bc runtime.goexit({}) /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_arm64.s:1223 +0x4 fp=0x40000507d0 sp=0x40000507d0 pc=0x47ac94 created by unique.runtime_registerUniqueMapCleanup in goroutine 1 /home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1730 +0xa0 FAIL FAIL runtime 75.297s ok runtime/pprof 20.404s ok sort 0.174s ok strconv 0.305s ok strings 0.816s ok sync 0.594s ok sync/atomic 0.645s ok syscall 3.366s ok testing 2.242s ok time 6.127s ok cmd/api 0.088s ok cmd/cgo/internal/test 1.343s ok cmd/cgo/internal/testcarchive 58.040s ok cmd/cgo/internal/testcshared 28.098s ok cmd/cgo/internal/testerrors 32.711s ok cmd/cgo/internal/testfortran 7.210s ok cmd/cgo/internal/testplugin 57.216s ok cmd/cgo/internal/testsanitizers 43.196s ok cmp 0.075s ok container/heap 0.232s ok crypto 1.305s ok crypto/des 0.066s ok crypto/ecdh 0.213s ok crypto/internal/bigmod 0.209s ok crypto/internal/boring/bcache 0.319s ok crypto/internal/nistec/fiat 0.134s [no tests to run] ok debug/macho 0.090s ok embed/internal/embedtest 0.031s ok errors 0.052s ok go/constant 0.013s ok go/format 0.031s ok go/importer 1.021s ok go/version 0.078s ok html 0.055s ok image/color 0.030s ok internal/coverage/slicewriter 0.032s ok internal/gover 0.048s ok internal/profile 0.040s ok log 0.059s ok log/slog/internal/benchmarks 0.078s ok maps 0.047s ok mime/multipart 1.090s ok mime/quotedprintable 0.079s ok net/http/cookiejar 0.103s ok net/rpc/jsonrpc 0.036s ok os/exec 0.531s ok os/user 0.032s ok runtime/cgo 0.031s ok runtime/trace 0.135s ok text/template/parse 0.029s ok unicode/utf16 0.035s ok cmd/addr2line 0.614s ok cmd/asm/internal/lex 0.010s ok cmd/cgo/internal/testlife 0.924s ok cmd/compile/internal/dwarfgen 0.186s ok cmd/compile/internal/liveness 0.041s ok cmd/compile/internal/logopt 0.072s ok cmd/compile/internal/types 0.015s ok cmd/doc 0.115s ok cmd/fix 5.901s ok cmd/go/internal/auth 0.012s ok cmd/internal/cov 0.305s ok cmd/internal/notsha256 0.008s ok cmd/internal/obj/s390x 0.007s ok cmd/internal/pkgpath 0.018s ok cmd/internal/sys 0.011s Warning: no '=' in invocation-link-artifacts pair: "", ignoring [I2024-09-08T05:24:51.266818Z 256 0 cmd_stream.go:488] rdb-stream: the test process terminated [I2024-09-08T05:24:51.266966Z 256 0 sink.go:371] SinkServer: shutdown started [I2024-09-08T05:24:51.267010Z 256 0 sink.go:349] SinkServer: HTTP server stopped with "http: Server closed" [I2024-09-08T05:24:51.267036Z 256 0 sink_server.go:96] SinkServer: draining TestResult channel started [I2024-09-08T05:24:51.735900Z 256 0 sink_server.go:98] SinkServer: draining TestResult channel ended [I2024-09-08T05:24:51.735950Z 256 0 sink_server.go:100] SinkServer: draining Artifact channel started [I2024-09-08T05:24:51.831825Z 256 0 sink_server.go:102] SinkServer: draining Artifact channel ended [I2024-09-08T05:24:51.831867Z 256 0 sink.go:374] SinkServer: shutdown completed successfully [I2024-09-08T05:24:51.831893Z 256 0 cmd_stream.go:420] rdb-stream: exiting with 1