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

os/signal: hang in TestTerminalSignal on darwin #64700

Open
gopherbot opened this issue Dec 13, 2023 · 4 comments
Open

os/signal: hang in TestTerminalSignal on darwin #64700

gopherbot opened this issue Dec 13, 2023 · 4 comments
Assignees
@cherrymui
Labels
compiler/runtime Issues related to the Go compiler and/or runtime. NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. OS-Darwin
Milestone
Backlog

Comments

@gopherbot
Copy link

gopherbot commented Dec 13, 2023 •
edited by bcmills 

#!watchflakes
post <- goos == "darwin" && pkg == "os/signal" && log ~ `test timed out` && log ~ `^\s+TestTerminalSignal\s+\(`

Issue created automatically to collect these failures.

Example (log):

SIGQUIT: quit
PC=0x7ff802ac934e m=0 sigcode=0

goroutine 0 gp=0xb34b560 m=0 mp=0xb34bd60 [idle]:
runtime.kevent(0x5, 0x0, 0x0, 0x7ff7b5e25bf8, 0x40, 0x7ff7b5e25bc8)
	/tmp/buildlet/go/src/runtime/sys_darwin.go:458 +0x2e fp=0x7ff7b5e25b70 sp=0x7ff7b5e25b50 pc=0xb132c0e
runtime.netpoll(0xc000014030?)
	/tmp/buildlet/go/src/runtime/netpoll_kqueue.go:141 +0xfd fp=0x7ff7b5e26410 sp=0x7ff7b5e25b70 pc=0xb10de9d
runtime.findRunnable()
	/tmp/buildlet/go/src/runtime/proc.go:3468 +0x8c5 fp=0x7ff7b5e26588 sp=0x7ff7b5e26410 pc=0xb119865
...
panic: test timed out after 3m0s
running tests:
	TestTerminalSignal (2m55s)

goroutine 396 gp=0xc000101180 m=6 mp=0xc00012a008 [running]:
panic({0x603baa0?, 0xc0002040a0?})
	/tmp/buildlet/go/src/runtime/panic.go:779 +0x158 fp=0xc0000f5f10 sp=0xc0000f5e60 pc=0x5f0f418
testing.(*M).startAlarm.func1()
	/tmp/buildlet/go/src/testing/testing.go:2366 +0x385 fp=0xc0000f5fe0 sp=0xc0000f5f10 pc=0x5facee5
runtime.goexit({})
...

goroutine 102 gp=0xc000007a40 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x11fc3520920?, 0x0?, 0x0?, 0x0?, 0x0?)
	/tmp/buildlet/go/src/runtime/proc.go:402 +0xce fp=0xc00020bf50 sp=0xc00020bf30 pc=0x5f12d8e
runtime.gcBgMarkWorker()
	/tmp/buildlet/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc00020bfe0 sp=0xc00020bf50 pc=0x5ef42a5
runtime.goexit({})
	/tmp/buildlet/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00020bfe8 sp=0xc00020bfe0 pc=0x5f49681
created by runtime.gcBgMarkStartWorkers in goroutine 126
	/tmp/buildlet/go/src/runtime/mgc.go:1234 +0x1c

— watchflakes
@gopherbot gopherbot added the NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. label Dec 13, 2023
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "os/signal" && test == ""
2023-12-13 14:32 darwin-amd64-12_0 go@58c28ba2 os/signal (log) 
SIGQUIT: quit
PC=0x7ff802ac934e m=0 sigcode=0

goroutine 0 gp=0xb34b560 m=0 mp=0xb34bd60 [idle]:
runtime.kevent(0x5, 0x0, 0x0, 0x7ff7b5e25bf8, 0x40, 0x7ff7b5e25bc8)
	/tmp/buildlet/go/src/runtime/sys_darwin.go:458 +0x2e fp=0x7ff7b5e25b70 sp=0x7ff7b5e25b50 pc=0xb132c0e
runtime.netpoll(0xc000014030?)
	/tmp/buildlet/go/src/runtime/netpoll_kqueue.go:141 +0xfd fp=0x7ff7b5e26410 sp=0x7ff7b5e25b70 pc=0xb10de9d
runtime.findRunnable()
	/tmp/buildlet/go/src/runtime/proc.go:3468 +0x8c5 fp=0x7ff7b5e26588 sp=0x7ff7b5e26410 pc=0xb119865
...
panic: test timed out after 3m0s
running tests:
	TestTerminalSignal (2m55s)

goroutine 396 gp=0xc000101180 m=6 mp=0xc00012a008 [running]:
panic({0x603baa0?, 0xc0002040a0?})
	/tmp/buildlet/go/src/runtime/panic.go:779 +0x158 fp=0xc0000f5f10 sp=0xc0000f5e60 pc=0x5f0f418
testing.(*M).startAlarm.func1()
	/tmp/buildlet/go/src/testing/testing.go:2366 +0x385 fp=0xc0000f5fe0 sp=0xc0000f5f10 pc=0x5facee5
runtime.goexit({})
...

goroutine 102 gp=0xc000007a40 m=nil [GC worker (idle), 2 minutes]:
runtime.gopark(0x11fc3520920?, 0x0?, 0x0?, 0x0?, 0x0?)
	/tmp/buildlet/go/src/runtime/proc.go:402 +0xce fp=0xc00020bf50 sp=0xc00020bf30 pc=0x5f12d8e
runtime.gcBgMarkWorker()
	/tmp/buildlet/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc00020bfe0 sp=0xc00020bf50 pc=0x5ef42a5
runtime.goexit({})
	/tmp/buildlet/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00020bfe8 sp=0xc00020bfe0 pc=0x5f49681
created by runtime.gcBgMarkStartWorkers in goroutine 126
	/tmp/buildlet/go/src/runtime/mgc.go:1234 +0x1c

— watchflakes

@gopherbot gopherbot added the compiler/runtime Issues related to the Go compiler and/or runtime. label Dec 13, 2023
@bcmills bcmills mentioned this issue Dec 13, 2023
Open
@bcmills bcmills changed the title os/signal: unrecognized failures os/signal: hang in TestTerminalSignal on darwin Dec 13, 2023
@bcmills
Copy link
Contributor

bcmills commented Dec 13, 2023

This may be another symptom of #63937, and/or a duplicate of #61595.

See also:

@mknyszek mknyszek added this to the Backlog milestone Dec 13, 2023
@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- goos == "darwin" && pkg == "os/signal" && log ~ `test timed out` && log ~ `^\s+TestTerminalSignal\s+\(`
2024-03-19 07:29 gotip-darwin-amd64_14 go@84960608 os/signal.TestTerminalSignal [ABORT] (log) 
=== RUN   TestTerminalSignal
=== PAUSE TestTerminalSignal
=== CONT  TestTerminalSignal
    signal_cgo_test.go:199: Sending ^Z...
    signal_cgo_test.go:211: Sending SIGCONT...
    signal_cgo_test.go:224: Waiting for exit...
    exec.go:193: test timed out while running command: /Users/swarming/.swarming/w/ir/x/t/go-build3635438088/b1427/signal.test -test.run=^TestTerminalSignal$ -test.timeout=11m11.600184238s
SIGQUIT: quit
PC=0x7ff811dba706 m=0 sigcode=0

...
panic: test timed out after 9m0s
running tests:
	TestTerminalSignal (8m57s)

goroutine 418 gp=0xc0001ff180 m=4 mp=0xc000080008 [running]:
panic({0x3c68c80?, 0xc0000a2030?})
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/panic.go:778 +0x168 fp=0xc00007cf10 sp=0xc00007ce60 pc=0x3b3c968
testing.(*M).startAlarm.func1()
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/testing/testing.go:2366 +0x385 fp=0xc00007cfe0 sp=0xc00007cf10 pc=0x3bda3c5
runtime.goexit({})
...
runtime.gopark(0x21bcad625c46?, 0x1?, 0x12?, 0x44?, 0x0?)
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/proc.go:402 +0xce fp=0xc0001f6f38 sp=0xc0001f6f18 pc=0x3b4026e
runtime.gcBgMarkWorker(0xc0002167e0)
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1333 +0xe9 fp=0xc0001f6fc8 sp=0xc0001f6f38 pc=0x3b216c9
runtime.gcBgMarkStartWorkers.gowrap1()
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1249 +0x25 fp=0xc0001f6fe0 sp=0xc0001f6fc8 pc=0x3b215a5
runtime.goexit({})
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0001f6fe8 sp=0xc0001f6fe0 pc=0x3b76b41
created by runtime.gcBgMarkStartWorkers in goroutine 61
	/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgc.go:1249 +0x105

— watchflakes

@gopherbot
Copy link
Author

Found new dashboard test flakes for:

#!watchflakes
post <- goos == "darwin" && pkg == "os/signal" && log ~ `test timed out` && log ~ `^\s+TestTerminalSignal\s+\(`
2024-03-21 21:04 darwin-amd64-12_0 go@87056756 os/signal (log) 
SIGQUIT: quit
PC=0x7ff803b0b34e m=0 sigcode=0

goroutine 0 gp=0xdaac760 m=0 mp=0xdaacf60 [idle]:
runtime.kevent(0x5, 0x0, 0x0, 0x7ff7b36c5be8, 0x40, 0x7ff7b36c5bb8)
	/tmp/buildlet/go/src/runtime/sys_darwin.go:458 +0x2e fp=0x7ff7b36c5b60 sp=0x7ff7b36c5b40 pc=0xd89318e
runtime.netpoll(0xc000014030?)
	/tmp/buildlet/go/src/runtime/netpoll_kqueue.go:141 +0xfd fp=0x7ff7b36c6400 sp=0x7ff7b36c5b60 pc=0xd86e33d
runtime.findRunnable()
	/tmp/buildlet/go/src/runtime/proc.go:3471 +0x8c5 fp=0x7ff7b36c6578 sp=0x7ff7b36c6400 pc=0xd879c65
...
panic: test timed out after 3m0s
running tests:
	TestTerminalSignal (2m55s)

goroutine 430 gp=0xc00011c700 m=4 mp=0xc000080008 [running]:
panic({0xad16ca0?, 0xc000190070?})
	/tmp/buildlet/go/src/runtime/panic.go:778 +0x168 fp=0xc00009ef10 sp=0xc00009ee60 pc=0xabea8a8
testing.(*M).startAlarm.func1()
	/tmp/buildlet/go/src/testing/testing.go:2411 +0x385 fp=0xc00009efe0 sp=0xc00009ef10 pc=0xac88525
runtime.goexit({})
...
runtime.chansend1(0xc000098f18?, 0xad45c68?)
	/tmp/buildlet/go/src/runtime/chan.go:146 +0x17 fp=0xc000098e88 sp=0xc000098e58 pc=0xabb8bb7
os/exec.(*Cmd).watchCtx(0xc0001f4160, 0xc0000223f0)
	/tmp/buildlet/go/src/os/exec/exec.go:848 +0x477 fp=0xc000098fc0 sp=0xc000098e88 pc=0xac90c17
os/exec.(*Cmd).Start.gowrap2()
	/tmp/buildlet/go/src/os/exec/exec.go:750 +0x25 fp=0xc000098fe0 sp=0xc000098fc0 pc=0xac90505
runtime.goexit({})
	/tmp/buildlet/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000098fe8 sp=0xc000098fe0 pc=0xac24a81
created by os/exec.(*Cmd).Start in goroutine 56
	/tmp/buildlet/go/src/os/exec/exec.go:750 +0x973

— watchflakes

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@cherrymui cherrymui

Labels
compiler/runtime Issues related to the Go compiler and/or runtime. NeedsInvestigation Someone must examine and confirm this is a valid issue and not a duplicate of an existing one. OS-Darwin
Projects
Go Compiler / Runtime
Status: Todo
Test Flakes
Status: No status
Milestone
Backlog
Development

No branches or pull requests
4 participants
@mknyszek @bcmills @gopherbot @cherrymui