Skip to content

flaky test 10m timeout failures: TestServerClose: 651s panic: test timed out after 10m0s #260

@jas4711

Description

@jas4711

Hi! I help maintain this package in Debian. Normally the self-tests runs quickly, but I got some random failures where the self-tests just keeps on running and is eventually killed for 10 minutes. This happend on 3 out of 6 invocations in the Debian debci infrastructure, although I cannot reproduce it locally on my laptop.

FAIL log:
https://ci.debian.net/packages/g/golang-github-gliderlabs-ssh/unstable/amd64/71636539/#L626

PASS log (runtime <1min):
https://ci.debian.net/packages/g/golang-github-gliderlabs-ssh/unstable/amd64/71636540/

This is version 0.3.8.

Any ideas?

/Simon

 52s === RUN   TestServerClose
651s panic: test timed out after 10m0s
651s 	running tests:
651s 		TestServerClose (10m0s)
651s 
651s goroutine 146 [running]:
651s testing.(*M).startAlarm.func1()
651s 	/usr/lib/go-1.26/src/testing/testing.go:2802 +0x354
651s created by time.goFunc
651s 	/usr/lib/go-1.26/src/time/sleep.go:215 +0x2d
651s 
651s goroutine 1 [chan receive, 9 minutes]:
651s testing.(*T).Run(0x15c91e404488, {0x6e52f9?, 0x0?}, 0x6f8228)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2109 +0x4e5
651s testing.runTests.func1(0x15c91e404488)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2585 +0x3e
651s testing.tRunner(0x15c91e404488, 0x15c91e325c58)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s testing.runTests({0x0, 0x0}, {0x6e929a, 0x19}, 0x15c91e21e678, {0x8fbd20, 0x18, 0x18}, {0xc27e1807a3e82c8a, 0x8bb2d4e889, ...})
651s 	/usr/lib/go-1.26/src/testing/testing.go:2583 +0x505
651s testing.(*M).Run(0x15c91e356d20)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2443 +0x6ac
651s main.main()
651s 	_testmain.go:94 +0x9b
651s 
651s goroutine 7 [chan receive, 9 minutes]:
651s testing.(*T).Parallel(0x15c91e4046c8)
651s 	/usr/lib/go-1.26/src/testing/testing.go:1803 +0x230
651s github.com/gliderlabs/ssh.TestSetPermissions(0x15c91e4046c8)
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/context_test.go:9 +0x2b
651s testing.tRunner(0x15c91e4046c8, 0x6f8238)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s created by testing.(*T).Run in goroutine 1
651s 	/usr/lib/go-1.26/src/testing/testing.go:2101 +0x4c5
651s 
651s goroutine 8 [chan receive, 9 minutes]:
651s testing.(*T).Parallel(0x15c91e404908)
651s 	/usr/lib/go-1.26/src/testing/testing.go:1803 +0x230
651s github.com/gliderlabs/ssh.TestSetValue(0x15c91e404908)
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/context_test.go:30 +0x2b
651s testing.tRunner(0x15c91e404908, 0x6f8240)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s created by testing.(*T).Run in goroutine 1
651s 	/usr/lib/go-1.26/src/testing/testing.go:2101 +0x4c5
651s 
651s goroutine 12 [chan receive, 9 minutes]:
651s testing.(*T).Parallel(0x15c91e404d88)
651s 	/usr/lib/go-1.26/src/testing/testing.go:1803 +0x230
651s github.com/gliderlabs/ssh.TestPasswordAuth(0x15c91e404d88)
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/options_test.go:22 +0x39
651s testing.tRunner(0x15c91e404d88, 0x6f8208)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s created by testing.(*T).Run in goroutine 1
651s 	/usr/lib/go-1.26/src/testing/testing.go:2101 +0x4c5
651s 
651s goroutine 10 [runnable]:
651s github.com/gliderlabs/ssh.(*sshContext).Value(0x15c91e3599b0, {0x67f3a0, 0x6fa520})
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/context.go:125 +0x135
651s github.com/gliderlabs/ssh.TestSetValueConcurrency.func1()
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/context_test.go:60 +0x4e
651s created by github.com/gliderlabs/ssh.TestSetValueConcurrency in goroutine 9
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/context_test.go:56 +0x85
651s 
651s goroutine 13 [chan receive, 9 minutes]:
651s testing.(*T).Parallel(0x15c91e404fc8)
651s 	/usr/lib/go-1.26/src/testing/testing.go:1803 +0x230
651s github.com/gliderlabs/ssh.TestPasswordAuthBadPass(0x15c91e404fc8)
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/options_test.go:51 +0x2a
651s testing.tRunner(0x15c91e404fc8, 0x6f8210)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s created by testing.(*T).Run in goroutine 1
651s 	/usr/lib/go-1.26/src/testing/testing.go:2101 +0x4c5
651s 
651s goroutine 14 [chan receive, 9 minutes]:
651s testing.(*T).Parallel(0x15c91e405208)
651s 	/usr/lib/go-1.26/src/testing/testing.go:1803 +0x230
651s github.com/gliderlabs/ssh.TestConnWrapping(0x15c91e405208)
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/options_test.go:84 +0x39
651s testing.tRunner(0x15c91e405208, 0x6f81d0)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s created by testing.(*T).Run in goroutine 1
651s 	/usr/lib/go-1.26/src/testing/testing.go:2101 +0x4c5
651s 
651s goroutine 101 [chan receive, 9 minutes]:
651s github.com/gliderlabs/ssh.TestServerClose(0x15c91e52a008)
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/server_test.go:123 +0x278
651s testing.tRunner(0x15c91e52a008, 0x6f8228)
651s 	/usr/lib/go-1.26/src/testing/testing.go:2036 +0xea
651s created by testing.(*T).Run in goroutine 1
651s 	/usr/lib/go-1.26/src/testing/testing.go:2101 +0x4c5
651s 
651s goroutine 135 [runnable]:
651s golang.org/x/crypto/ssh.(*channel).SendRequest(0x15c91e5281a0, {0x6e2308, 0x4}, 0x1, {0x15c91e95e080, 0x4, 0x40})
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/golang.org/x/crypto/ssh/channel.go:637 +0x586
651s golang.org/x/crypto/ssh.(*Session).Start(0x15c91e96a000, {0x0, 0x0})
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/golang.org/x/crypto/ssh/session.go:286 +0xdc
651s golang.org/x/crypto/ssh.(*Session).Run(0x15c91e96a000, {0x0?, 0x2a1a4?})
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/golang.org/x/crypto/ssh/session.go:310 +0x1d
651s github.com/gliderlabs/ssh.TestServerClose.func3()
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/server_test.go:104 +0x7a
651s created by github.com/gliderlabs/ssh.TestServerClose in goroutine 101
651s 	/tmp/autopkgtest-lxc.mo_sp0by/downtmp/autopkgtest_tmp/_build/src/github.com/gliderlabs/ssh/server_test.go:100 +0x1a6
651s FAIL	github.com/gliderlabs/ssh	600.013s

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type
    No fields configured for issues without a type.

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions