search

golang / go

The Go programming language
https://go.dev
BSD 3-Clause "New" or "Revised" License
121.36k stars 17.38k forks source link

cmd/cgo/internal/testcarchive: TestManyCalls failures #67566

Open gopherbot opened 1 month ago

gopherbot commented 1 month ago 
#!watchflakes
default <- pkg == "cmd/cgo/internal/testcarchive" && test == "TestManyCalls"

Issue created automatically to collect these failures.

Example (log):

=== RUN   TestManyCalls
=== PAUSE TestManyCalls
=== CONT  TestManyCalls
    carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7]
    carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2815952951=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2671988462/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a]
    carchive_test.go:1237: test program timed out
    carchive_test.go:1244: [./testp7]
    carchive_test.go:1246: signal: killed
--- FAIL: TestManyCalls (63.87s)

— watchflakes

gopherbot commented 1 month ago

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/cgo/internal/testcarchive" && test == "TestManyCalls"
2024-05-21 22:34 gotip-linux-amd64-longtest go@4b778470 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2815952951=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2671988462/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.87s)

— watchflakes

gopherbot commented 1 month ago

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/cgo/internal/testcarchive" && test == "TestManyCalls"
2024-05-22 09:58 gotip-linux-amd64-longtest go@cd41d717 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1168042934=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test224014420/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.41s)
2024-05-22 14:20 gotip-linux-amd64-longtest go@1d3d7d39 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1182557413=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2972530068/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.87s)

— watchflakes

cherrymui commented 2 days ago

There are more flaky failures on this since June 20, https://ci.chromium.org/ui/test/golang/cmd%2Fcgo%2Finternal%2Ftestcarchive.TestManyCalls?q=V%3Abuilder%3Dgotip-linux-amd64-longtest-test_only+V%3Ago_branch%3Dmaster+V%3Agoarch%3Damd64+V%3Agoos%3Dlinux+V%3Ahost_goarch%3Damd64+V%3Ahost_goos%3Dlinux which were matched to #61069.

Tentatively mark as a release blocker, as the high-rate flaky failures seem to start recently.

gopherbot commented 2 days ago

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/cgo/internal/testcarchive" && test == "TestManyCalls"
2024-06-21 19:58 gotip-linux-386-longtest go@d79c3509 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m32 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2379281893=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test846712421/pkg/linux_386_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.81s)
2024-06-21 19:58 gotip-linux-amd64-longtest go@d79c3509 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1297635274=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3111046110/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.66s)
2024-06-21 20:30 gotip-linux-amd64-longtest go@cb3b3434 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1392598236=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test193818245/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.80s)
2024-06-22 00:00 gotip-linux-amd64-longtest go@44f18706 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build4194400131=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test403248468/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (64.24s)
2024-06-24 15:28 gotip-linux-amd64-longtest go@085cf0fc cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2761594464=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3551687877/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.88s)
2024-06-24 15:33 gotip-linux-amd64-longtest go@0def9d5c cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build82568437=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3040444538/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.78s)
2024-06-24 16:39 gotip-linux-386-longtest go@b98803e8 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m32 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3932238390=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3639547755/pkg/linux_386_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (62.18s)
2024-06-24 16:39 gotip-linux-amd64-longtest go@b98803e8 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build332999988=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test1393194044/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.59s)
2024-06-24 17:52 go1.22-linux-amd64-longtest release-branch.go1.22@3560cf0a cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2672286316=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test431803681/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.47s)
2024-06-24 18:55 gotip-linux-amd64-longtest go@f214a760 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3021580691=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test480891144/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.32s)
2024-06-24 21:07 gotip-linux-amd64-longtest go@68315bc8 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build552437588=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2734782223/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.56s)
2024-06-26 16:10 gotip-linux-amd64-longtest go@a2e90be9 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2993139507=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test315953837/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.60s)
2024-06-27 17:41 gotip-linux-amd64-longtest go@ea537cca cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1970432366=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2499208346/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.85s)
2024-06-28 15:25 gotip-linux-amd64-longtest go@7f90b960 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1348995302=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test464773713/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.19s)
2024-06-28 19:59 gotip-linux-amd64-longtest go@82c371a3 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build4134761125=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test513351738/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.46s)
2024-07-01 14:58 gotip-linux-amd64-longtest go@d3c93f2f cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build533927821=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test521971101/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.27s)
2024-07-02 18:51 go1.22-linux-amd64-longtest release-branch.go1.22@8e1fdea8 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1448477477=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test6028936/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.27s)
2024-07-03 00:14 gotip-linux-amd64-longtest go@148755a2 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build920887134=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2646131870/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.31s)
2024-07-03 13:03 gotip-linux-amd64-longtest go@71f9dbb1 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2328236361=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2690339460/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.33s)
2024-07-07 16:40 gotip-linux-amd64-longtest go@d0146bd8 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build593565273=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3769716976/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (69.24s)
2024-07-07 19:43 gotip-linux-amd64-longtest go@b43d6c57 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1780134935=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test90360660/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (64.58s)
2024-07-08 00:05 gotip-linux-amd64-longtest go@6d89b38e cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3998046951=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2189679450/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.88s)
2024-07-10 02:44 gotip-linux-amd64-longtest go@70e453b4 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1749024690=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test765026489/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.41s)
2024-07-10 03:19 gotip-linux-386-longtest go@b3040679 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m32 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build257476081=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test1248722746/pkg/linux_386_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (62.11s)
2024-07-10 19:27 go1.22-linux-amd64-longtest release-branch.go1.22@49906f95 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1981229932=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test187455808/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.52s)
2024-07-10 19:29 go1.22-linux-amd64-longtest release-branch.go1.22@45f9ded1 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build4188300449=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2204560682/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.96s)
2024-07-11 15:01 gotip-linux-amd64-longtest go@08a6e080 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build406361605=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2219636962/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.48s)
2024-07-11 15:01 gotip-linux-amd64-longtest go@611f18c4 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3609333747=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test74597656/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.31s)
2024-07-11 17:23 gotip-linux-amd64-longtest go@a71bb570 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1875363026=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3248917447/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.10s)
2024-07-12 22:42 gotip-linux-amd64-longtest go@071b8d51 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3625069990=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2910945480/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.52s)

— watchflakes

gopherbot commented 2 days ago

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/cgo/internal/testcarchive" && test == "TestManyCalls"
2024-07-09 16:14 go1.23-linux-386-longtest release-branch.go1.23@e89e880e cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m32 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build490386741=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test3752947999/pkg/linux_386_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (63.11s)
2024-07-09 16:14 go1.23-linux-amd64-longtest release-branch.go1.23@e89e880e cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3583602013=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2109964676/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (67.01s)
2024-07-12 22:42 go1.23-linux-amd64-longtest release-branch.go1.23@071b8d51 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build3851497827=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test150421289/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (65.38s)

— watchflakes

cherrymui commented 1 day ago

I pulled the test timing data from LUCI. timing_master So it seems the test timing was pretty steadily <20 seconds, then it starts rising since d881ed63, up to 180ea455 which is 50+ seconds. At afbbc289 it drops back to mostly <30 seconds (with few exceptions), until d79c3509 it rises again.

For the first elevated period, as afbbc289 is a revert of d881ed63, it is likely that d881ed63 is the cause and the revert fixes it.

For the second elevation, d79c3509 is unrelated, so are a few commits nearby. So it is probably something else. Maybe builder change?

Then I pulled the test timing data for the 1.22 release branch, which doesn't have any of those commits. timing_go122 It seems the timing also rises since June 24, on 3560cf0a. The commit might be related, but the corresponding master branch CL landed on May 15, which did not cause the time rising. As the rising on on the release branch is about the same time as on the master branch, I'm more leaning towards builder configuration change. @mknyszek is there any builder configuration change at the time around June 24 or so?

Lastly, the test has a hard-coded 1 minute timeout. I don't think it makes much sense to say 55 seconds is pass, 65 seconds is fail. So the 1 minute timeout may be a bit arbitrary. We probably want to replace it with t.Deadline.

gopherbot commented 8 hours ago

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "cmd/cgo/internal/testcarchive" && test == "TestManyCalls"
2024-07-16 18:02 gotip-linux-amd64-longtest go@5c7f5413 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build27128390=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test1476725237/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (68.14s)
2024-07-16 18:17 gotip-linux-amd64-longtest go@b4a92f56 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build1793352452=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test2116150728/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.29s)
2024-07-16 18:17 gotip-linux-amd64-longtest go@97ccc224 cmd/cgo/internal/testcarchive.TestManyCalls (log) === RUN TestManyCalls === PAUSE TestManyCalls === CONT TestManyCalls carchive_test.go:1209: [go build -buildmode=c-archive -o libgo7.a ./libgo7] carchive_test.go:1221: [gcc -fPIC -m64 -pthread -Wl,--no-gc-sections -fmessage-length=0 -ffile-prefix-map=/home/swarming/.swarming/w/ir/x/t/go-build2797306960=/tmp/go-build -gno-record-gcc-switches -I /home/swarming/.swarming/w/ir/x/t/carchive_test478452091/pkg/linux_amd64_shared/testcarchive -o testp7 main7.c libgo7.a] carchive_test.go:1237: test program timed out carchive_test.go:1244: [./testp7] carchive_test.go:1246: signal: killed --- FAIL: TestManyCalls (66.22s)

— watchflakes

gopherbot commented 2 hours ago

Change https://go.dev/cl/599056 mentions this issue: cmd/cgo/internal/testcarchive: remove 1-minute timeout

cherrymui commented 2 hours ago

CL https://go.dev/cl/599056 replaced the 1-minute timeout with the test deadline. Hopefully this will stop the failures on the builder. At this point I think the test slowdown is more likely due to builder infrastructure change, not the code change in the main repo (which we're going to release). Also, the test was written to catch deadlocks, not speed. Finishing in more than a minute is still deadlock. So at this point I think it is not a release blocker.

It would be great we can understand the slowdown, though. So keep the issue open.