Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

cmd/go: tests timing out on linux-amd64 #21850

Closed
ch3ck opened this issue Sep 12, 2017 · 8 comments
Closed

cmd/go: tests timing out on linux-amd64 #21850

ch3ck opened this issue Sep 12, 2017 · 8 comments
Labels
FrozenDueToAge NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one.
Milestone
Go1.10

Comments

@ch3ck
Copy link

ch3ck commented Sep 12, 2017

Please answer these questions before submitting your issue. Thanks!

What version of Go are you using (go version)?

1.9

Does this issue reproduce with the latest release?

Yes

What operating system and processor architecture are you using (go env)?

GOARCH="amd64"
GOBIN=""
GOEXE=""
GOHOSTARCH="amd64"
GOHOSTOS="linux"
GOOS="linux"
GOPATH="/home/localhost/go"
GORACE=""
GOROOT="/usr/local/go"
GOTOOLDIR="/usr/local/go/pkg/tool/linux_amd64"
GCCGO="gccgo"
CC="gcc"
GOGCCFLAGS="-fPIC -m64 -pthread -fmessage-length=0 -fdebug-prefix-map=/tmp/go-build648426851=/tmp/go-build -gno-record-gcc-switches"
CXX="g++"
CGO_ENABLED="1"
CGO_CFLAGS="-g -O2"
CGO_CPPFLAGS=""
CGO_CXXFLAGS="-g -O2"
CGO_FFLAGS="-g -O2"
CGO_LDFLAGS="-g -O2"
PKG_CONFIG="pkg-config"

What did you do?

Go routine race deadlock from compiling and testing latest checkout from master
If possible, provide a recipe for reproducing the error.
A complete runnable program is good.
A link on play.golang.org is best.

What did you expect to see?

Ok

What did you see instead?

panic: test timed out after 3m0s

goroutine 939 [running]:
testing.(*M).startAlarm.func1()
/home/localhost/golang/go/src/testing/testing.go:1162 +0xe8
created by time.goFunc
/home/localhost/golang/go/src/time/sleep.go:172 +0x44

goroutine 1 [chan receive]:
testing.tRunner.func1(0xc4201ba000)
/home/localhost/golang/go/src/testing/testing.go:724 +0x110
testing.tRunner(0xc4201ba000, 0xc42014db70)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
testing.runTests(0xc42016c860, 0xb8ad20, 0xdc, 0xdc, 0xbae1c0)
/home/localhost/golang/go/src/testing/testing.go:1013 +0x29a
testing.(*M).Run(0xc420150850, 0x0)
/home/localhost/golang/go/src/testing/testing.go:933 +0x161
cmd/go_test.TestMain(0xc420150850)
/home/localhost/golang/go/src/cmd/go/go_test.go:143 +0x150
main.main()
cmd/go/_test/_testmain.go:482 +0x156

goroutine 5 [syscall, 2 minutes]:
os/signal.signal_recv(0x0)
/home/localhost/golang/go/src/runtime/sigqueue.go:131 +0xa6
os/signal.loop()
/home/localhost/golang/go/src/os/signal/signal_unix.go:22 +0x22
created by os/signal.init.0
/home/localhost/golang/go/src/os/signal/signal_unix.go:28 +0x41

goroutine 464 [chan send]:
testing.tRunner.func1(0xc4202f32c0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f32c0, 0x933be8)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 937 [IO wait]:
internal/poll.runtime_pollWait(0x7f4a5a501af0, 0x72, 0x1)
/home/localhost/golang/go/src/runtime/netpoll.go:173 +0x57
internal/poll.(*pollDesc).wait(0xc4204173c8, 0x72, 0xffffffffffffff01, 0x978220, 0xb4e578)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:85 +0xae
internal/poll.(*pollDesc).waitRead(0xc4204173c8, 0xc420293401, 0x200, 0x200)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d
internal/poll.(*FD).Read(0xc4204173b0, 0xc420293400, 0x200, 0x200, 0x0, 0x0, 0x0)
/home/localhost/golang/go/src/internal/poll/fd_unix.go:125 +0x17d
os.(*File).read(0xc42000e2b0, 0xc420293400, 0x200, 0x200, 0x4dfa01, 0xc420293400, 0x0)
/home/localhost/golang/go/src/os/file_unix.go:216 +0x4e
os.(*File).Read(0xc42000e2b0, 0xc420293400, 0x200, 0x200, 0x0, 0x0, 0xc4201b1660)
/home/localhost/golang/go/src/os/file.go:103 +0x6d
bytes.(*Buffer).ReadFrom(0xc4202f6ba0, 0x977780, 0xc42000e2b0, 0x7f4a5a4bd020, 0xc4202f6ba0, 0xc420283301)
/home/localhost/golang/go/src/bytes/buffer.go:200 +0xa3
io.copyBuffer(0x976f00, 0xc4202f6ba0, 0x977780, 0xc42000e2b0, 0x0, 0x0, 0x0, 0xc4203905f0, 0x0, 0x0)
/home/localhost/golang/go/src/io/io.go:386 +0x2bb
io.Copy(0x976f00, 0xc4202f6ba0, 0x977780, 0xc42000e2b0, 0x404d63, 0xc4201a8300, 0xc4201b17b0)
/home/localhost/golang/go/src/io/io.go:362 +0x5a
os/exec.(*Cmd).writerDescriptor.func1(0xc4201a8300, 0xc4201b17b0)
/home/localhost/golang/go/src/os/exec/exec.go:264 +0x44
os/exec.(*Cmd).Start.func1(0xc420152840, 0xc42035ea40)
/home/localhost/golang/go/src/os/exec/exec.go:380 +0x27
created by os/exec.(*Cmd).Start
/home/localhost/golang/go/src/os/exec/exec.go:379 +0x5ea

goroutine 471 [chan send]:
testing.tRunner.func1(0xc4201bb0e0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bb0e0, 0x933710)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 401 [chan send]:
testing.tRunner.func1(0xc4202f3770)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f3770, 0x933ab8)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 305 [chan receive]:
testing.tRunner.func1(0xc4201bb680)
/home/localhost/golang/go/src/testing/testing.go:724 +0x110
testing.tRunner(0xc4201bb680, 0x933b98)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 470 [chan send]:
testing.tRunner.func1(0xc4201baff0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201baff0, 0x933750)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 743 [chan receive]:
testing.(*testContext).waitParallel(0xc4201808a0)
/home/localhost/golang/go/src/testing/testing.go:835 +0x98
testing.(*T).Parallel(0xc4202f2870)
/home/localhost/golang/go/src/testing/testing.go:680 +0x11d
cmd/go_test.(*testgoData).parallel(0xc4202f6280)
/home/localhost/golang/go/src/cmd/go/go_test.go:224 +0x351
cmd/go_test.TestListTemplateContextFunction.func1(0xc4202f2870)
/home/localhost/golang/go/src/cmd/go/go_test.go:2616 +0x5b
testing.tRunner(0xc4202f2870, 0xc4204d4540)
/home/localhost/golang/go/src/testing/testing.go:748 +0xc0
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 418 [chan send]:
testing.tRunner.func1(0xc420233860)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc420233860, 0x933990)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 518 [chan send]:
testing.tRunner.func1(0xc4202f3c20)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f3c20, 0x933b90)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 952 [IO wait]:
internal/poll.runtime_pollWait(0x7f4a5a501a20, 0x72, 0x1)
/home/localhost/golang/go/src/runtime/netpoll.go:173 +0x57
internal/poll.(*pollDesc).wait(0xc420390a68, 0x72, 0xffffffffffffff01, 0x978220, 0xb4e578)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:85 +0xae
internal/poll.(*pollDesc).waitRead(0xc420390a68, 0xc420293201, 0x200, 0x200)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d
internal/poll.(*FD).Read(0xc420390a50, 0xc420293200, 0x200, 0x200, 0x0, 0x0, 0x0)
/home/localhost/golang/go/src/internal/poll/fd_unix.go:125 +0x17d
os.(*File).read(0xc42016e280, 0xc420293200, 0x200, 0x200, 0x4dfa01, 0xc420293200, 0x0)
/home/localhost/golang/go/src/os/file_unix.go:216 +0x4e
os.(*File).Read(0xc42016e280, 0xc420293200, 0x200, 0x200, 0x0, 0x0, 0xc4201b1e60)
/home/localhost/golang/go/src/os/file.go:103 +0x6d
bytes.(*Buffer).ReadFrom(0xc4202f6fc8, 0x977780, 0xc42016e280, 0x7f4a5a4bd020, 0xc4202f6fc8, 0xc42018a901)
/home/localhost/golang/go/src/bytes/buffer.go:200 +0xa3
io.copyBuffer(0x976f00, 0xc4202f6fc8, 0x977780, 0xc42016e280, 0x0, 0x0, 0x0, 0xc4201f2780, 0x0, 0x0)
/home/localhost/golang/go/src/io/io.go:386 +0x2bb
io.Copy(0x976f00, 0xc4202f6fc8, 0x977780, 0xc42016e280, 0x404d63, 0xc420072180, 0xc4201b1fb0)
/home/localhost/golang/go/src/io/io.go:362 +0x5a
os/exec.(*Cmd).writerDescriptor.func1(0xc420072180, 0xc4201b1fb0)
/home/localhost/golang/go/src/os/exec/exec.go:264 +0x44
os/exec.(*Cmd).Start.func1(0xc4204706e0, 0xc4204106c0)
/home/localhost/golang/go/src/os/exec/exec.go:380 +0x27
created by os/exec.(*Cmd).Start
/home/localhost/golang/go/src/os/exec/exec.go:379 +0x5ea

goroutine 366 [chan send]:
testing.tRunner.func1(0xc4202f2f00)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f2f00, 0x9338c8)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 342 [chan send]:
testing.tRunner.func1(0xc4201bb950)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bb950, 0x933760)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 411 [chan send]:
testing.tRunner.func1(0xc4201ba2d0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201ba2d0, 0x933a50)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 402 [chan send]:
testing.tRunner.func1(0xc4202f3860)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f3860, 0x933ab0)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 448 [chan send]:
testing.tRunner.func1(0xc4201bac30)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bac30, 0x9337a0)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 447 [chan send, 1 minutes]:
testing.tRunner.func1(0xc4201bab40)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bab40, 0x933c98)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 472 [chan send]:
testing.tRunner.func1(0xc4201bb1d0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bb1d0, 0x933860)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 474 [chan send]:
testing.tRunner.func1(0xc4201bb4a0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bb4a0, 0x9338b8)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 519 [chan send]:
testing.tRunner.func1(0xc4202f3d10)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f3d10, 0x933b80)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 520 [chan send]:
testing.tRunner.func1(0xc4202f3e00)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f3e00, 0x9337d0)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 528 [chan send]:
testing.tRunner.func1(0xc420468000)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc420468000, 0x933840)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 529 [chan send]:
testing.tRunner.func1(0xc4204680f0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4204680f0, 0x933820)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 543 [chan send]:
testing.tRunner.func1(0xc4201baf00)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201baf00, 0x933c48)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 544 [chan send]:
testing.tRunner.func1(0xc4201bb3b0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4201bb3b0, 0x933d48)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 562 [chan send]:
testing.tRunner.func1(0xc4202f2780)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f2780, 0x933a48)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 742 [chan receive]:
testing.(*testContext).waitParallel(0xc4201808a0)
/home/localhost/golang/go/src/testing/testing.go:835 +0x98
testing.(*T).Parallel(0xc4202f2690)
/home/localhost/golang/go/src/testing/testing.go:680 +0x11d
cmd/go_test.(*testgoData).parallel(0xc4200afcc0)
/home/localhost/golang/go/src/cmd/go/go_test.go:224 +0x351
cmd/go_test.TestListTemplateContextFunction.func1(0xc4202f2690)
/home/localhost/golang/go/src/cmd/go/go_test.go:2616 +0x5b
testing.tRunner(0xc4202f2690, 0xc4204d44e0)
/home/localhost/golang/go/src/testing/testing.go:748 +0xc0
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 653 [chan receive, 1 minutes]:
testing.runTests.func1.1(0xc4201ba000)
/home/localhost/golang/go/src/testing/testing.go:1020 +0x3b
created by testing.runTests.func1
/home/localhost/golang/go/src/testing/testing.go:1020 +0xa2

goroutine 745 [chan receive]:
testing.(*testContext).waitParallel(0xc4201808a0)
/home/localhost/golang/go/src/testing/testing.go:835 +0x98
testing.(*T).Parallel(0xc4202f2d20)
/home/localhost/golang/go/src/testing/testing.go:680 +0x11d
cmd/go_test.(*testgoData).parallel(0xc4202f6c80)
/home/localhost/golang/go/src/cmd/go/go_test.go:224 +0x351
cmd/go_test.TestListTemplateContextFunction.func1(0xc4202f2d20)
/home/localhost/golang/go/src/cmd/go/go_test.go:2616 +0x5b
testing.tRunner(0xc4202f2d20, 0xc4204d4600)
/home/localhost/golang/go/src/testing/testing.go:748 +0xc0
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 744 [syscall]:
syscall.Syscall6(0xf7, 0x1, 0x731c, 0xc4204f9ae8, 0x1000004, 0x0, 0x0, 0x0, 0x0, 0xc420198e00)
/home/localhost/golang/go/src/syscall/asm_linux_amd64.s:44 +0x5
os.(*Process).blockUntilWaitable(0xc420173920, 0xc420173920, 0x0, 0x0)
/home/localhost/golang/go/src/os/wait_waitid.go:31 +0x98
os.(*Process).wait(0xc420173920, 0xc4204ea480, 0xc420152918, 0x3)
/home/localhost/golang/go/src/os/exec_unix.go:22 +0x42
os.(*Process).Wait(0xc420173920, 0x0, 0x0, 0x9345f0)
/home/localhost/golang/go/src/os/exec.go:115 +0x2b
os/exec.(*Cmd).Wait(0xc420152840, 0x0, 0x0)
/home/localhost/golang/go/src/os/exec/exec.go:446 +0x62
os/exec.(*Cmd).Run(0xc420152840, 0x8, 0xc4203f4f60)
/home/localhost/golang/go/src/os/exec/exec.go:289 +0x5c
cmd/go_test.(*testgoData).doRun(0xc4202f6b40, 0xc4203f4f60, 0x3, 0x3, 0x0, 0x0)
/home/localhost/golang/go/src/cmd/go/go_test.go:327 +0x3ee
cmd/go_test.(*testgoData).run(0xc4202f6b40, 0xc4203f4f60, 0x3, 0x3)
/home/localhost/golang/go/src/cmd/go/go_test.go:343 +0x72
cmd/go_test.TestListTemplateContextFunction.func1(0xc4202f2960)
/home/localhost/golang/go/src/cmd/go/go_test.go:2619 +0x171
testing.tRunner(0xc4202f2960, 0xc4204d45a0)
/home/localhost/golang/go/src/testing/testing.go:748 +0xc0
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 938 [IO wait]:
internal/poll.runtime_pollWait(0x7f4a5a501bc0, 0x72, 0x1)
/home/localhost/golang/go/src/runtime/netpoll.go:173 +0x57
internal/poll.(*pollDesc).wait(0xc420417468, 0x72, 0xffffffffffffff01, 0x978220, 0xb4e578)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:85 +0xae
internal/poll.(*pollDesc).waitRead(0xc420417468, 0xc420293601, 0x200, 0x200)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d
internal/poll.(*FD).Read(0xc420417450, 0xc420293600, 0x200, 0x200, 0x0, 0x0, 0x0)
/home/localhost/golang/go/src/internal/poll/fd_unix.go:125 +0x17d
os.(*File).read(0xc42000e2c8, 0xc420293600, 0x200, 0x200, 0x4dfa01, 0xc420293600, 0x0)
/home/localhost/golang/go/src/os/file_unix.go:216 +0x4e
os.(*File).Read(0xc42000e2c8, 0xc420293600, 0x200, 0x200, 0x0, 0x0, 0xc420431e60)
/home/localhost/golang/go/src/os/file.go:103 +0x6d
bytes.(*Buffer).ReadFrom(0xc4202f6c08, 0x977780, 0xc42000e2c8, 0x7f4a5a4bd020, 0xc4202f6c08, 0xc420283301)
/home/localhost/golang/go/src/bytes/buffer.go:200 +0xa3
io.copyBuffer(0x976f00, 0xc4202f6c08, 0x977780, 0xc42000e2c8, 0x0, 0x0, 0x0, 0xc420390550, 0x0, 0x0)
/home/localhost/golang/go/src/io/io.go:386 +0x2bb
io.Copy(0x976f00, 0xc4202f6c08, 0x977780, 0xc42000e2c8, 0x404d63, 0xc4201a8300, 0xc420431fb0)
/home/localhost/golang/go/src/io/io.go:362 +0x5a
os/exec.(*Cmd).writerDescriptor.func1(0xc4201a8300, 0xc420431fb0)
/home/localhost/golang/go/src/os/exec/exec.go:264 +0x44
os/exec.(*Cmd).Start.func1(0xc420152840, 0xc42035ea80)
/home/localhost/golang/go/src/os/exec/exec.go:380 +0x27
created by os/exec.(*Cmd).Start
/home/localhost/golang/go/src/os/exec/exec.go:379 +0x5ea

goroutine 746 [chan send]:
testing.tRunner.func1(0xc4202f2e10)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f2e10, 0xc4204d4660)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 747 [syscall]:
syscall.Syscall6(0xf7, 0x1, 0x7314, 0xc42004cae8, 0x1000004, 0x0, 0x0, 0x0, 0x0, 0xc420042a80)
/home/localhost/golang/go/src/syscall/asm_linux_amd64.s:44 +0x5
os.(*Process).blockUntilWaitable(0xc42037ed80, 0xc42037ed80, 0x0, 0x0)
/home/localhost/golang/go/src/os/wait_waitid.go:31 +0x98
os.(*Process).wait(0xc42037ed80, 0xc420234240, 0xc4204707b8, 0x3)
/home/localhost/golang/go/src/os/exec_unix.go:22 +0x42
os.(*Process).Wait(0xc42037ed80, 0x0, 0x0, 0x9345f0)
/home/localhost/golang/go/src/os/exec.go:115 +0x2b
os/exec.(*Cmd).Wait(0xc4204706e0, 0x0, 0x0)
/home/localhost/golang/go/src/os/exec/exec.go:446 +0x62
os/exec.(*Cmd).Run(0xc4204706e0, 0x8, 0xc4204d4870)
/home/localhost/golang/go/src/os/exec/exec.go:289 +0x5c
cmd/go_test.(*testgoData).doRun(0xc4202f6f00, 0xc4204d4870, 0x3, 0x3, 0x0, 0x0)
/home/localhost/golang/go/src/cmd/go/go_test.go:327 +0x3ee
cmd/go_test.(*testgoData).run(0xc4202f6f00, 0xc4204d4870, 0x3, 0x3)
/home/localhost/golang/go/src/cmd/go/go_test.go:343 +0x72
cmd/go_test.TestListTemplateContextFunction.func1(0xc4202f2ff0)
/home/localhost/golang/go/src/cmd/go/go_test.go:2619 +0x171
testing.tRunner(0xc4202f2ff0, 0xc4204d46c0)
/home/localhost/golang/go/src/testing/testing.go:748 +0xc0
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 748 [chan send]:
testing.tRunner.func1(0xc4202f30e0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f30e0, 0xc4204d4720)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 749 [chan send]:
testing.tRunner.func1(0xc4202f31d0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f31d0, 0xc4204d4780)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 750 [chan send]:
testing.tRunner.func1(0xc4202f33b0)
/home/localhost/golang/go/src/testing/testing.go:743 +0x17a
testing.tRunner(0xc4202f33b0, 0xc4204d47e0)
/home/localhost/golang/go/src/testing/testing.go:750 +0xca
created by testing.(*T).Run
/home/localhost/golang/go/src/testing/testing.go:791 +0x2b1

goroutine 951 [IO wait]:
internal/poll.runtime_pollWait(0x7f4a5a501950, 0x72, 0x1)
/home/localhost/golang/go/src/runtime/netpoll.go:173 +0x57
internal/poll.(*pollDesc).wait(0xc4203909c8, 0x72, 0xffffffffffffff01, 0x978220, 0xb4e578)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:85 +0xae
internal/poll.(*pollDesc).waitRead(0xc4203909c8, 0xc420251a01, 0x200, 0x200)
/home/localhost/golang/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d
internal/poll.(*FD).Read(0xc4203909b0, 0xc420251a00, 0x200, 0x200, 0x0, 0x0, 0x0)
/home/localhost/golang/go/src/internal/poll/fd_unix.go:125 +0x17d
os.(*File).read(0xc42016e268, 0xc420251a00, 0x200, 0x200, 0x4dfa01, 0xc420251a00, 0x0)
/home/localhost/golang/go/src/os/file_unix.go:216 +0x4e
os.(*File).Read(0xc42016e268, 0xc420251a00, 0x200, 0x200, 0x0, 0x0, 0xc4201b2e60)
/home/localhost/golang/go/src/os/file.go:103 +0x6d
bytes.(*Buffer).ReadFrom(0xc4202f6f60, 0x977780, 0xc42016e268, 0x7f4a5a4bd020, 0xc4202f6f60, 0xc420410501)
/home/localhost/golang/go/src/bytes/buffer.go:200 +0xa3
io.copyBuffer(0x976f00, 0xc4202f6f60, 0x977780, 0xc42016e268, 0x0, 0x0, 0x0, 0xc4203908c0, 0x0, 0x0)
/home/localhost/golang/go/src/io/io.go:386 +0x2bb
io.Copy(0x976f00, 0xc4202f6f60, 0x977780, 0xc42016e268, 0x404d63, 0xc4201a8420, 0xc4201b2fb0)
/home/localhost/golang/go/src/io/io.go:362 +0x5a
os/exec.(*Cmd).writerDescriptor.func1(0xc4201a8420, 0xc4201b2fb0)
/home/localhost/golang/go/src/os/exec/exec.go:264 +0x44
os/exec.(*Cmd).Start.func1(0xc4204706e0, 0xc420410680)
/home/localhost/golang/go/src/os/exec/exec.go:380 +0x27
created by os/exec.(*Cmd).Start
/home/localhost/golang/go/src/os/exec/exec.go:379 +0x5ea
FAIL cmd/go 189.185s
@ianlancetaylor ianlancetaylor changed the title Go routine deadlock: Failing internal poll tests in latest build. internal/poll: goroutine deadlock: internal/poll tests failing in latest build Sep 12, 2017
@ianlancetaylor
Copy link
Contributor

You said "latest build" and you also said 1.9. Can you clarify exactly which sources you are using? Thanks.

@ianlancetaylor ianlancetaylor changed the title internal/poll: goroutine deadlock: internal/poll tests failing in latest build cmd/go: goroutine deadlock: tests failing in latest build Sep 12, 2017
@ianlancetaylor
Copy link
Contributor

How repeatable is this? The builders (https://build.golang.org) look fine.

@ianlancetaylor
Copy link
Contributor

The hanging test, if it is indeed hanging, seems to be TestListTemplateContextFunction in cmd/go/go_test.go.

@ianlancetaylor ianlancetaylor added this to the Go1.10 milestone Sep 12, 2017
@ianlancetaylor ianlancetaylor added the NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. label Sep 12, 2017
@ianlancetaylor ianlancetaylor changed the title cmd/go: goroutine deadlock: tests failing in latest build cmd/go: tests timing out on linux-amd64 Sep 12, 2017
@ianlancetaylor
Copy link
Contributor

The TestListTemplateContextFunction test only takes 2.5 seconds to run on my laptop. I wonder if this is simply an overall timeout of go test cmd/go, which does take a while to run. On my laptop it takes 60 seconds.

@ch3ck
Copy link
Author

ch3ck commented Sep 12, 2017 •
edited

@ianlancetaylor I"m compiling the source code, from the master branch. I think it might be a timeout issue. I'll try to run it on a different system and see if the results are any different.

@njnuwjq
Copy link

njnuwjq commented Sep 13, 2017

Please answer these questions before submitting your issue. Thanks!

What version of Go are you using ( go version )?

1.9

Does this issue reproduce with the latest release?

Yes

What operating system and processor architecture are you using ( go env )?

cross-compiler linux amd64

GOROOT=c:\go
#GOBIN=
GOARCH=amd64
GOOS=linux
CGO_ENABLED=0

PATH=C:\MinGW\bin;%GOROOT%\bin;%PATH%

LITEIDE_GDB=gdb
LITEIDE_MAKE=mingw32-make
LITEIDE_TERM=%COMSPEC%
LITEIDE_TERMARGS=
LITEIDE_EXEC=%COMSPEC%
LITEIDE_EXECOPT=/C

What did you do?
create a lot of tcp connections, and send msg on them

What did you expect to see?

no error

What did you see instead?

lots of goroute fail, like

goroutine 26580 [IO wait]:
internal/poll.runtime_pollWait(0x7fac8d42a430, 0x72, 0x0)
c:/go/src/runtime/netpoll.go:173 +0x57
internal/poll.(*pollDesc).wait(0xc42460ec18, 0x72, 0xffffffffffffff00, 0xc1dea0, 0xc19470)
c:/go/src/internal/poll/fd_poll_runtime.go:85 +0xae
internal/poll.(*pollDesc).waitRead(0xc42460ec18, 0xc424651000, 0x1000, 0x1000)
c:/go/src/internal/poll/fd_poll_runtime.go:90 +0x3d
internal/poll.(*FD).Read(0xc42460ec00, 0xc424651000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
c:/go/src/internal/poll/fd_unix.go:125 +0x18a
net.(*netFD).Read(0xc42460ec00, 0xc424651000, 0x1000, 0x1000, 0x47f644, 0x47d2d5, 0x1)
c:/go/src/net/fd_unix.go:202 +0x52
net.(*conn).Read(0xc423c40318, 0xc424651000, 0x1000, 0x1000, 0x0, 0x0, 0x0)
c:/go/src/net/net.go:176 +0x6d
bufio.(*Reader).fill(0xc4243d7ce0)
c:/go/src/bufio/bufio.go:97 +0x11a
bufio.(*Reader).ReadSlice(0xc4243d7ce0, 0xc42460ec0a, 0xc4266273c0, 0x53fee2, 0xc42460ec00, 0xc424650000, 0x1b)
c:/go/src/bufio/bufio.go:338 +0x2c
github.com/garyburd/redigo/redis.(*conn).readLine(0xc4245e4a00, 0xc426627428, 0x551e0d, 0xc42460ec00, 0xc424650000, 0x1b)
D:/GOPATH/src/github.com/garyburd/redigo/redis/conn.go:393 +0x38
github.com/garyburd/redigo/redis.(*conn).readReply(0xc4245e4a00, 0x0, 0x0, 0xc4319e1a80, 0x1)
D:/GOPATH/src/github.com/garyburd/redigo/redis/conn.go:466 +0x40
github.com/garyburd/redigo/redis.(*conn).Do(0xc4245e4a00, 0x99d65d, 0x3, 0xc4319e1a80, 0x1, 0x1, 0x8, 0x0, 0x0, 0x0)
D:/GOPATH/src/github.com/garyburd/redigo/redis/conn.go:614 +0x136
main.(*tcp_client).redisCheckToken(0xc4291838c0, 0xc4272ef820, 0x6, 0x0, 0x0, 0x0, 0x0, 0x0)
D:/GOPATH/src/GoPush/src/connector/tcp_client.go:425 +0x2b0
main.(*tcp_client).TCPHandleLogin(0xc4291838c0, 0xc425b00f00, 0x14, 0xc212a0)
D:/GOPATH/src/GoPush/src/connector/tcp_client.go:523 +0x169
main.(*tcp_client).HandleMsg(0xc4291838c0, 0x2711, 0xc424e96a8c, 0x14, 0x14, 0xc42000e5d0, 0xc421997560)
D:/GOPATH/src/GoPush/src/connector/tcp_client.go:832 +0x4b9
main.(*tcp_client).Depack(0xc4291838c0, 0xc424e96a80, 0x20, 0x20, 0x20, 0xc424e96a80, 0x0, 0x20, 0xc4235f9e98)
D:/GOPATH/src/GoPush/src/connector/tcp_client.go:903 +0x89d
main.(*tcp_client).handleTCPConn(0xc4291838c0)
D:/GOPATH/src/GoPush/src/connector/tcp_client.go:274 +0x7ae
created by main.startTcpClient
D:/GOPATH/src/GoPush/src/connector/tcp_client.go:146 +0x125
halo_op@test115:~/wenjq$

@ianlancetaylor
Copy link
Contributor

@njnuwjq I'm not sure what you are reporting, but that is definitely a different problem. This issue is about a problem running the Go standard library tests. You are running some other program. Please open a separate issue, and in that issue please explain why you think the problem is related to the Go tools or standard library. Thanks.

@rsc
Copy link
Contributor

rsc commented Dec 1, 2017

@ch3ck's failure trace looks like there are two different go list subprocesses running (we can't see them) with associated goroutines waiting for them. For both, there is a goroutine reading output from the subprocess and another goroutine doing waitid in expectation of the process exiting. If only one of these two goroutines existed I might be worried that we'd missed something in the os/exec code, but since both exist it really seems like the the subprocess is stuck. But we can't see the subprocess at all in the stack traces. In the absence of more information, going to close this as not reproducible.

@rsc rsc closed this as completed Dec 1, 2017
@golang golang locked and limited conversation to collaborators Dec 1, 2018
Sign up for free to subscribe to this conversation on GitHub. Already have an account? Sign in.
Assignees
No one assigned
Labels
FrozenDueToAge NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one.
Projects
None yet
Milestone
Go1.10
Development

No branches or pull requests
5 participants
@rsc @njnuwjq @ianlancetaylor @ch3ck @gopherbot