go icon indicating copy to clipboard operation
go copied to clipboard

internal/trace/v2: test timeouts

Open gopherbot opened this issue 1 year ago • 25 comments

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`

Issue created automatically to collect these failures.

Example (log):

--- FAIL: TestTraceManyStartStop (0.74s)
    --- FAIL: TestTraceManyStartStop/Default (0.74s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:551: stderr: SIGQUIT: quit
            PC=0x250a4627 m=3 sigcode=0

            goroutine 0 gp=0x57406b48 m=3 mp=0x57440b08 [idle]:
            runtime.kevent(0x36, 0x0, 0x0, 0x19f70acc, 0x40, 0x19f70aa4)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x19f70a68 sp=0x19f70a58 pc=0x80a5541
            runtime.netpoll(0x3478c32f)
...
            edi    0x784
            esi    0x57406b48
            ebp    0x19f70a18
            esp    0x19f709fc
            eip    0x250a4627
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:553: exit status 2

watchflakes

gopherbot avatar Dec 19 '23 01:12 gopherbot

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "internal/trace/v2" && test == "TestTraceManyStartStop"
2023-12-18 21:30 openbsd-386-72 go@c564d4ae internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (0.74s)
    --- FAIL: TestTraceManyStartStop/Default (0.74s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:551: stderr: SIGQUIT: quit
            PC=0x250a4627 m=3 sigcode=0

            goroutine 0 gp=0x57406b48 m=3 mp=0x57440b08 [idle]:
            runtime.kevent(0x36, 0x0, 0x0, 0x19f70acc, 0x40, 0x19f70aa4)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x19f70a68 sp=0x19f70a58 pc=0x80a5541
            runtime.netpoll(0x3478c32f)
...
            edi    0x784
            esi    0x57406b48
            ebp    0x19f70a18
            esp    0x19f709fc
            eip    0x250a4627
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:553: exit status 2

watchflakes

gopherbot avatar Dec 19 '23 01:12 gopherbot

Given that TestTraceManyStartStop was running for less than a second before it timed out, it seems likely that some other test in this package is too long/expensive and should be skipped when testing.Short() is true.

(attn @mknyszek)

bcmills avatar Jan 02 '24 16:01 bcmills

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-01-08 18:52 gotip-linux-amd64-longtest go@8eaa7935 internal/trace/v2.TestTraceStacks/Stress (log)
=== RUN   TestTraceStacks/Stress
    exec.go:193: test timed out while running command: /home/swarming/.swarming/w/ir/x/w/goroot/bin/go run testdata/testprog/stacks.go
    trace_test.go:553: signal: killed
--- FAIL: TestTraceStacks/Stress (842.69s)

watchflakes

gopherbot avatar Jan 19 '24 20:01 gopherbot

https://storage.googleapis.com/go-build-log/33992f4a/openbsd-amd64-72_ed2d35db.log shows a timeout with a long running time in TestTraceStressStartStop/Default.

That one is a bit strange because it seems to show a go run command with a lot of goroutines in buildActionID, waiting on the results of go tool compile -V=full. 🤔

bcmills avatar Jan 24 '24 14:01 bcmills

These tests do invoke the compiler, but only on very small programs. Still, if that's a problem, I can restructure the tests to group all the programs together like src/runtime/testdata/testprog. (Originally there weren't enough of them for me to consider that necessary, but maybe now there's enough that it causes problems...)

mknyszek avatar Jan 24 '24 15:01 mknyszek

It seems odd to me that the go run command stalled for that long on the builder in the first place. I'm not sure what's going on there. Linking can be expensive, but that go run command doesn't seem to have even gotten that far. I wonder if that's another symptom of a bug in the kqueue netpoll implementation...

bcmills avatar Jan 24 '24 16:01 bcmills

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-01-29 19:39 openbsd-386-72 go@b91bad78 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (158.22s)
    --- FAIL: TestTraceStressStartStop/Default (158.22s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:562: exit status 1

watchflakes

gopherbot avatar Jan 29 '24 22:01 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-02-22 01:57 gotip-linux-amd64-longtest-race go@5d4e8f51 internal/trace/v2.TestTraceStacks/Stress (log)
=== RUN   TestTraceStacks/Stress
    exec.go:193: test timed out while running command: /home/swarming/.swarming/w/ir/x/w/goroot/bin/go run -race testdata/testprog/stacks.go
    trace_test.go:567: signal: killed
--- FAIL: TestTraceStacks/Stress (1676.23s)

watchflakes

gopherbot avatar Feb 23 '24 17:02 gopherbot

Woooaaah... that is way longer than that test should ever take. That could be a deadlock, perhaps. I'll try to reproduce.

mknyszek avatar Feb 23 '24 17:02 mknyszek

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-03-05 20:52 linux-mipsle-rtrk go@9e3b1d53 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (658.46s)
    --- FAIL: TestTraceStressStartStop/Default (658.46s)
        exec.go:193: test timed out while running command: /tmp/gobuilder-mips64le/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:567: signal: killed

watchflakes

gopherbot avatar Mar 06 '24 05:03 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-03-22 04:41 gotip-linux-amd64-longtest-race go@ac0b2f95 internal/trace/v2.TestTraceStacks/Stress (log)
=== RUN   TestTraceStacks/Stress
    exec.go:193: test timed out while running command: /home/swarming/.swarming/w/ir/x/w/goroot/bin/go run -race testdata/testprog/stacks.go
    trace_test.go:567: signal: killed
--- FAIL: TestTraceStacks/Stress (1665.72s)

watchflakes

gopherbot avatar Mar 22 '24 21:03 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-03-27 17:55 openbsd-386-72 go@979b34b3 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (54.60s)
    --- FAIL: TestTraceStressStartStop/Default (54.60s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:571: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceIterPull (1s)

goroutine 126 gp=0x6b0070e8 m=0 mp=0x83aa020 [running]:
panic({0x8212be0, 0x6be62878})
...
runtime.chansend1(0x6b3a78c0, 0x6b087fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x6b087f38 sp=0x6b087f20 pc=0x804d224
os/exec.(*Cmd).watchCtx(0x6b3b1388, 0x6b3a78c0)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x4c6 fp=0x6b087fe4 sp=0x6b087f38 pc=0x8161006
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x6b087ff0 sp=0x6b087fe4 pc=0x81608f8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6b087ff4 sp=0x6b087ff0 pc=0x80baa11
created by os/exec.(*Cmd).Start in goroutine 121
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x901

watchflakes

gopherbot avatar Mar 27 '24 19:03 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-05 22:18 openbsd-386-72 go@58c5db31 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (72.49s)
    --- FAIL: TestTraceStressStartStop/Default (72.49s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-05 22:18 openbsd-386-72 go@58c5db31 internal/trace/v2.TestTraceWaitOnPipe (log)
--- FAIL: TestTraceWaitOnPipe (1.73s)
    --- FAIL: TestTraceWaitOnPipe/Default (1.73s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x2e651b1b m=3 sigcode=0

            goroutine 0 gp=0x50c08b48 m=3 mp=0x50c40a08 [idle]:
            runtime.thrsleep
        trace_test.go:575: signal: killed
2024-04-05 22:18 openbsd-386-72 go@58c5db31 internal/trace/v2.TestTraceIterPull (log)
--- FAIL: TestTraceIterPull (0.00s)
    --- FAIL: TestTraceIterPull/Default (0.00s)
        trace_test.go:575: context deadline exceeded
panic: test timed out after 3m0s

goroutine 124 gp=0x61c06d88 m=2 mp=0x61c3a508 [running]:
panic({0x8213ca0, 0x638b8808})
	/tmp/workdir/go/src/runtime/panic.go:778 +0x138 fp=0x61c36784 sp=0x61c36730 pc=0x8081cb8
testing.(*M).startAlarm.func1()
	/tmp/workdir/go/src/testing/testing.go:2366 +0x386 fp=0x61c367f0 sp=0x61c36784 pc=0x813d2f6
...
runtime.gopark(0x82560ec, 0x61c180f0, 0x1a, 0xa, 0x0)
	/tmp/workdir/go/src/runtime/proc.go:402 +0xfa fp=0x61c38f8c sp=0x61c38f78 pc=0x808569a
runtime.gcBgMarkWorker(0x61c1ea00)
	/tmp/workdir/go/src/runtime/mgc.go:1333 +0xeb fp=0x61c38fe8 sp=0x61c38f8c pc=0x80636db
runtime.gcBgMarkStartWorkers.gowrap1()
	/tmp/workdir/go/src/runtime/mgc.go:1249 +0x21 fp=0x61c38ff0 sp=0x61c38fe8 pc=0x80635d1
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x61c38ff4 sp=0x61c38ff0 pc=0x80bb191
created by runtime.gcBgMarkStartWorkers in goroutine 10
	/tmp/workdir/go/src/runtime/mgc.go:1249 +0x114

watchflakes

gopherbot avatar Apr 07 '24 04:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-09 20:03 openbsd-386-72 go@a555ac63 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (123.61s)
    --- FAIL: TestTraceStressStartStop/Default (123.61s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: exit status 1

watchflakes

gopherbot avatar Apr 09 '24 21:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-15 19:10 openbsd-386-72 go@cfbe6cd9 internal/trace/v2.TestTraceStress (log)
--- FAIL: TestTraceStress (132.95s)
    --- FAIL: TestTraceStress/Default (132.95s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceStressStartStop (7s)
	TestTraceStressStartStop/Default (7s)

goroutine 108 gp=0x5e407c28 m=2 mp=0x5e43a508 [running]:
...
runtime.chansend1(0x5e7a6200, 0x5e483fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x5e483f38 sp=0x5e483f20 pc=0x804d224
os/exec.(*Cmd).watchCtx(0x5e7c8008, 0x5e7a6200)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x4c6 fp=0x5e483fe4 sp=0x5e483f38 pc=0x8161f26
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x5e483ff0 sp=0x5e483fe4 pc=0x8161818
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e483ff4 sp=0x5e483ff0 pc=0x80bb4f1
created by os/exec.(*Cmd).Start in goroutine 103
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x901

watchflakes

gopherbot avatar Apr 15 '24 21:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-16 21:06 openbsd-386-72 go@15cec430 internal/trace/v2.TestTraceStress (log)
--- FAIL: TestTraceStress (133.33s)
    --- FAIL: TestTraceStress/Default (133.33s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceStressStartStop (7s)
	TestTraceStressStartStop/Default (7s)

goroutine 108 gp=0x62c070e8 m=2 mp=0x62c3a508 [running]:
...
internal/trace/v2_test.testTraceProg.func2(0x6300c208)
	/tmp/workdir/go/src/internal/trace/v2/trace_test.go:611 +0x22 fp=0x62c85f98 sp=0x62c85f8c pc=0x81f8562
testing.tRunner(0x6300c208, 0x63a520e8)
	/tmp/workdir/go/src/testing/testing.go:1689 +0x11f fp=0x62c85fe4 sp=0x62c85f98 pc=0x813927f
testing.(*T).Run.gowrap1()
	/tmp/workdir/go/src/testing/testing.go:1742 +0x28 fp=0x62c85ff0 sp=0x62c85fe4 pc=0x813a258
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x62c85ff4 sp=0x62c85ff0 pc=0x80bb4f1
created by testing.(*T).Run in goroutine 102
	/tmp/workdir/go/src/testing/testing.go:1742 +0x3b3

watchflakes

gopherbot avatar Apr 16 '24 23:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-22 18:14 openbsd-386-72 go@79065f0a internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (12.68s)
    --- FAIL: TestTraceStressStartStop/Default (12.68s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-22 18:14 openbsd-386-72 go@79065f0a internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (0.54s)
    --- FAIL: TestTraceManyStartStop/Default (0.54s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x5010b0a7 m=3 sigcode=0

            goroutine 0 gp=0x70408b48 m=3 mp=0x70440a08 [idle]:
            runtime.kevent(0x39, 0x0, 0x0, 0x4558fc84, 0x40, 0x0)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x4558fc30 sp=0x4558fc20 pc=0x80a5e01
            runtime.netpoll(0xffffffffffffffff)
...
            edi    0x79c
            esi    0x70408b48
            ebp    0x4558fbd8
            esp    0x4558fbbc
            eip    0x5010b0a7
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2

watchflakes

gopherbot avatar Apr 22 '24 20:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-22 20:48 openbsd-386-72 go@aa63ef4b internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (46.49s)
    --- FAIL: TestTraceStressStartStop/Default (46.49s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: exit status 1
2024-04-22 22:06 openbsd-386-72 go@5a3b6400 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (27.29s)
    --- FAIL: TestTraceStressStartStop/Default (27.29s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-22 22:06 openbsd-386-72 go@5a3b6400 internal/trace/v2.TestTraceWaitOnPipe (log)
--- FAIL: TestTraceWaitOnPipe (0.29s)
    --- FAIL: TestTraceWaitOnPipe/Default (0.28s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x3ae6e1a7 m=3 sigcode=0

            goroutine 0 gp=0x5b008b48 m=3 mp=0x5b040a08 [idle]:
            runtime.thrsleep(0x5b040d68, 0x3, 0x0, 0x0, 0x5b040d68)
            	runtime/sys_openbsd1.go:17 +0x21 fp=0x2adc4260 sp=0x2adc4250 pc=0x80a57a1
            runtime.semasleep(0xffffffffffffffff)
...
            	os/file.go:399 +0x37 fp=0x5b02d7ac sp=0x5b02d794 pc=0x81397d7
            os.ReadDir({0x5b226108, 0x17})
            	os/dir.go:118 +0x5d fp=0x5b02d800 sp=0x5b02d7ac pc=0x813581d
            cmd/go/internal/fsys.readDir({0x5b226108, 0x17})
            	cmd/go/internal/fsys/fsys.go:306 +0x27 fp=0x5b02d84c sp=0x5b02d800 pc=0x83c19d7
            cmd/go/internal/fsys.ReadDir({0x5b226108, 0x17})
            	cmd/go/internal/fsys/fsys.go:339 +0x2d7 fp=0x5b02d9f4 sp=0x5b02d84c pc=0x83c1f57
            cmd/go/internal/modindex.dirHash({0x5b2260f0, 0x13}, {0x5b226108, 0x17})
            	cmd/go/internal/modindex/read.go:89 +0x1ba fp=0x5b02dabc sp=0x5b02d9f4 pc=
        trace_test.go:575: signal: killed
2024-04-22 22:06 openbsd-386-72 go@5a3b6400 internal/trace/v2.TestTraceIterPull (log)
--- FAIL: TestTraceIterPull (0.00s)
    --- FAIL: TestTraceIterPull/Default (0.00s)
        trace_test.go:575: context deadline exceeded
panic: test timed out after 3m0s

goroutine 132 gp=0x6c407e68 m=0 mp=0x83b3640 [running]:
panic({0x8216de0, 0x6e118ec0})
	/tmp/workdir/go/src/runtime/panic.go:778 +0x138 fp=0x6db97f84 sp=0x6db97f30 pc=0x8081fe8
testing.(*M).startAlarm.func1()
	/tmp/workdir/go/src/testing/testing.go:2366 +0x386 fp=0x6db97ff0 sp=0x6db97f84 pc=0x813d776
...
testing.(*F).Fuzz.func1.1(0x6c809608)
	/tmp/workdir/go/src/testing/fuzz.go:335 +0x2f8 fp=0x6c485f98 sp=0x6c485f00 pc=0x8130fd8
testing.tRunner(0x6c809608, 0x6d1fa500)
	/tmp/workdir/go/src/testing/testing.go:1689 +0x11f fp=0x6c485fe4 sp=0x6c485f98 pc=0x813943f
testing.(*F).Fuzz.func1.gowrap1()
	/tmp/workdir/go/src/testing/fuzz.go:322 +0x28 fp=0x6c485ff0 sp=0x6c485fe4 pc=0x8130cc8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6c485ff4 sp=0x6c485ff0 pc=0x80bb711
created by testing.(*F).Fuzz.func1 in goroutine 124
	/tmp/workdir/go/src/testing/fuzz.go:322 +0x4df
2024-04-22 22:06 openbsd-386-72 go@674657e1 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (28.27s)
    --- FAIL: TestTraceStressStartStop/Default (28.27s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-22 22:06 openbsd-386-72 go@674657e1 internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (1.29s)
    --- FAIL: TestTraceManyStartStop/Default (1.29s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:575: exit status 1

watchflakes

gopherbot avatar Apr 23 '24 00:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-23 17:45 openbsd-386-72 go@a62c290c internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (17.28s)
    --- FAIL: TestTraceStressStartStop/Default (17.28s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-23 17:45 openbsd-386-72 go@a62c290c internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (0.76s)
    --- FAIL: TestTraceManyStartStop/Default (0.76s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x45cb20d7 m=3 sigcode=0

            goroutine 0 gp=0x66008b48 m=3 mp=0x66040a08 [idle]:
            runtime.kevent(0x39, 0x0, 0x0, 0x2856c0c4, 0x40, 0x2856c098)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x2856c070 sp=0x2856c060 pc=0x80a5de1
            runtime.netpoll(0x3408b16e)
...
            edi    0x79c
            esi    0x66008b48
            ebp    0x2856c018
            esp    0x2856bffc
            eip    0x45cb20d7
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2
2024-04-23 17:45 openbsd-386-72 go@960fa9bf internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (15.77s)
    --- FAIL: TestTraceStressStartStop/Default (15.77s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-23 17:45 openbsd-386-72 go@960fa9bf internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (0.71s)
    --- FAIL: TestTraceManyStartStop/Default (0.71s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x45344ce7 m=3 sigcode=0

            goroutine 0 gp=0x6ec08b48 m=3 mp=0x6ec40a08 [idle]:
            runtime.kevent(0x39, 0x0, 0x0, 0x28de4614, 0x40, 0x0)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x28de45c0 sp=0x28de45b0 pc=0x80a5de1
            runtime.netpoll(0xffffffffffffffff)
...
            edi    0x79c
            esi    0x6ec08b48
            ebp    0x28de4568
            esp    0x28de454c
            eip    0x45344ce7
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2

watchflakes

gopherbot avatar Apr 24 '24 15:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-24 13:37 openbsd-386-72 go@4792ca7b internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (67.23s)
    --- FAIL: TestTraceStressStartStop/Default (67.23s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceWaitOnPipe (2s)
	TestTraceWaitOnPipe/Default (2s)

goroutine 120 gp=0x68807d48 m=2 mp=0x6883a508 [running]:
...
runtime.chansend1(0x68b671c0, 0x68882fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x68882f38 sp=0x68882f20 pc=0x804d214
os/exec.(*Cmd).watchCtx(0x68b5ce48, 0x68b671c0)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x4c6 fp=0x68882fe4 sp=0x68882f38 pc=0x8161fd6
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x68882ff0 sp=0x68882fe4 pc=0x81618c8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x68882ff4 sp=0x68882ff0 pc=0x80bb731
created by os/exec.(*Cmd).Start in goroutine 115
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x901

watchflakes

gopherbot avatar Apr 24 '24 17:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-24 15:55 openbsd-386-72 go@d037bba1 internal/trace/v2.TestTraceStress (log)
--- FAIL: TestTraceStress (127.61s)
    --- FAIL: TestTraceStress/Default (127.61s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go
        trace_test.go:575: exit status 1
2024-04-24 15:55 openbsd-386-72 go@d037bba1 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (11.54s)
    --- FAIL: TestTraceStressStartStop/Default (11.54s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceManyStartStop (1s)
	TestTraceManyStartStop/Default (1s)

goroutine 115 gp=0x5e807e68 m=3 mp=0x5e83aa08 [running]:
...
runtime.chansend1(0x5eba44c0, 0x5e885fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x5e885f38 sp=0x5e885f20 pc=0x804d214
os/exec.(*Cmd).watchCtx(0x5ebc8188, 0x5eba44c0)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x4c6 fp=0x5e885fe4 sp=0x5e885f38 pc=0x8161fd6
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x5e885ff0 sp=0x5e885fe4 pc=0x81618c8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5e885ff4 sp=0x5e885ff0 pc=0x80bb731
created by os/exec.(*Cmd).Start in goroutine 110
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x901

watchflakes

gopherbot avatar Apr 24 '24 19:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-24 21:50 openbsd-386-72 go@9b9de261 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (64.65s)
    --- FAIL: TestTraceStressStartStop/Default (64.65s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-24 21:50 openbsd-386-72 go@9b9de261 internal/trace/v2.TestTraceWaitOnPipe (log)
--- FAIL: TestTraceWaitOnPipe (1.86s)
    --- FAIL: TestTraceWaitOnPipe/Default (1.86s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x44c713ab m=3 sigcode=0

            goroutine 0 gp=0x64c08b48 m=3 mp=0x64c40a08 [idle]:
            runtime.thrsleep(0x64c40d68, 0x3, 0x0, 0x0, 0x64c40d68)
            	runtime/sys_openbsd1.go:17 +0x21 fp=0x165ffd70 sp=0x165ffd60 pc=0x80a5781
            runtime.semasleep(0xffffffffffffffff)
...
            edi    0x1ac
            esi    0x64c08b48
            ebp    0x165ffd18
            esp    0x165ffd00
            eip    0x44c713ab
            eflags 0x202
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2
2024-04-24 21:50 openbsd-386-72 go@9b9de261 internal/trace/v2.TestTraceIterPull (log)
--- FAIL: TestTraceIterPull (0.00s)
    --- FAIL: TestTraceIterPull/Default (0.00s)
        trace_test.go:575: context deadline exceeded
panic: test timed out after 3m0s

goroutine 132 gp=0x62fb0248 m=0 mp=0x83b3640 [running]:
panic({0x8216de0, 0x63a24cc8})
	/tmp/workdir/go/src/runtime/panic.go:778 +0x138 fp=0x62c36784 sp=0x62c36730 pc=0x8081fe8
testing.(*M).startAlarm.func1()
	/tmp/workdir/go/src/testing/testing.go:2366 +0x386 fp=0x62c367f0 sp=0x62c36784 pc=0x813d796
...
runtime.gopark(0x82594ac, 0x62c180f0, 0x1a, 0xa, 0x0)
	/tmp/workdir/go/src/runtime/proc.go:401 +0xfa fp=0x62c38f8c sp=0x62c38f78 pc=0x80859ca
runtime.gcBgMarkWorker(0x62c1ea00)
	/tmp/workdir/go/src/runtime/mgc.go:1352 +0xeb fp=0x62c38fe8 sp=0x62c38f8c pc=0x806383b
runtime.gcBgMarkStartWorkers.gowrap1()
	/tmp/workdir/go/src/runtime/mgc.go:1268 +0x21 fp=0x62c38ff0 sp=0x62c38fe8 pc=0x8063731
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x62c38ff4 sp=0x62c38ff0 pc=0x80bb731
created by runtime.gcBgMarkStartWorkers in goroutine 10
	/tmp/workdir/go/src/runtime/mgc.go:1268 +0x114

watchflakes

gopherbot avatar Apr 25 '24 02:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-25 11:28 openbsd-386-72 go@8960925a internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (24.99s)
    --- FAIL: TestTraceStressStartStop/Default (24.99s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceManyStartStop (1s)
	TestTraceManyStartStop/Default (1s)

goroutine 114 gp=0x6e807c28 m=3 mp=0x6e83aa08 [running]:
...
internal/trace/v2_test.testTraceProg.func2(0x6ec0a508)
	/tmp/workdir/go/src/internal/trace/v2/trace_test.go:611 +0x22 fp=0x6e835798 sp=0x6e83578c pc=0x81f86a2
testing.tRunner(0x6ec0a508, 0x7050c5e8)
	/tmp/workdir/go/src/testing/testing.go:1689 +0x11f fp=0x6e8357e4 sp=0x6e835798 pc=0x813945f
testing.(*T).Run.gowrap1()
	/tmp/workdir/go/src/testing/testing.go:1742 +0x28 fp=0x6e8357f0 sp=0x6e8357e4 pc=0x813a438
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6e8357f4 sp=0x6e8357f0 pc=0x80bb731
created by testing.(*T).Run in goroutine 108
	/tmp/workdir/go/src/testing/testing.go:1742 +0x3b3
2024-04-25 16:05 openbsd-386-72 go@8c0da423 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (45.25s)
    --- FAIL: TestTraceStressStartStop/Default (45.25s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceWaitOnPipe (1s)
	TestTraceWaitOnPipe/Default (1s)

goroutine 120 gp=0x6d007c28 m=3 mp=0x6d03aa08 [running]:
...
runtime.chansend1(0x6d3a6100, 0x6d083fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x6d083f38 sp=0x6d083f20 pc=0x804d214
os/exec.(*Cmd).watchCtx(0x6d3cb088, 0x6d3a6100)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x4c6 fp=0x6d083fe4 sp=0x6d083f38 pc=0x8161fd6
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x6d083ff0 sp=0x6d083fe4 pc=0x81618c8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6d083ff4 sp=0x6d083ff0 pc=0x80bb731
created by os/exec.(*Cmd).Start in goroutine 115
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x901

watchflakes

gopherbot avatar Apr 25 '24 18:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-25 18:50 openbsd-386-72 go@a8ba1636 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (14.88s)
    --- FAIL: TestTraceStressStartStop/Default (14.88s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-25 18:50 openbsd-386-72 go@a8ba1636 internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (0.64s)
    --- FAIL: TestTraceManyStartStop/Default (0.64s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x3905c3ab m=3 sigcode=0

            goroutine 0 gp=0x5e808b48 m=3 mp=0x5e840a08 [idle]:
            runtime.kevent(0x39, 0x0, 0x0, 0x25a22164, 0x40, 0x0)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x25a22110 sp=0x25a22100 pc=0x80a5de1
            runtime.netpoll(0xffffffffffffffff)
...
            edi    0x79c
            esi    0x5e808b48
            ebp    0x25a220b8
            esp    0x25a2209c
            eip    0x3905c3ab
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2

watchflakes

gopherbot avatar Apr 25 '24 21:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-26 23:07 openbsd-386-72 go@ad22356e internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (37.42s)
    --- FAIL: TestTraceStressStartStop/Default (37.42s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-26 23:07 openbsd-386-72 go@ad22356e internal/trace/v2.TestTraceWaitOnPipe (log)
--- FAIL: TestTraceWaitOnPipe (0.29s)
    --- FAIL: TestTraceWaitOnPipe/Default (0.29s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/wait-on-pipe.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x3a0c6cb7 m=3 sigcode=0

            goroutine 0 gp=0x5a408b48 m=3 mp=0x5a440a08 [idle]:
            runtime.thrsleep(0x5a440d68, 0x3, 0x0, 0x0, 0x5a440d68)
            	runtime/sys_openbsd1.go:17 +0x21 fp=0x39f2cb00 sp=0x39f2caf0 pc=0x80a4ec1
            runtime.semasleep(0xffffffffffffffff)
...
            edi    0x1ac
            esi    0x5a408b48
            ebp    0x39f2caa8
            esp    0x39f2ca90
            eip    0x3a0c6cb7
            eflags 0x202
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2
2024-04-26 23:07 openbsd-386-72 go@ad22356e internal/trace/v2.TestTraceIterPull (log)
--- FAIL: TestTraceIterPull (0.00s)
    --- FAIL: TestTraceIterPull/Default (0.00s)
        trace_test.go:575: context deadline exceeded
panic: test timed out after 3m0s

goroutine 132 gp=0x6c007c28 m=2 mp=0x6c03a508 [running]:
panic({0x8213e40, 0x6ce3ce18})
	/tmp/workdir/go/src/runtime/panic.go:778 +0x138 fp=0x6c033784 sp=0x6c033730 pc=0x80819f8
testing.(*M).startAlarm.func1()
	/tmp/workdir/go/src/testing/testing.go:2366 +0x386 fp=0x6c0337f0 sp=0x6c033784 pc=0x813c326
...
runtime.selectgo(0x6c082f94, 0x6c082f58, 0x0, 0x1, 0x1, 0x1)
	/tmp/workdir/go/src/runtime/select.go:335 +0xb6f fp=0x6c082f38 sp=0x6c082e4c pc=0x809755f
os/exec.(*Cmd).watchCtx(0x6ce6bb08, 0x6c3aee80)
	/tmp/workdir/go/src/os/exec/exec.go:794 +0x268 fp=0x6c082fe4 sp=0x6c082f38 pc=0x8160058
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x6c082ff0 sp=0x6c082fe4 pc=0x815fba8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6c082ff4 sp=0x6c082ff0 pc=0x80baab1
created by os/exec.(*Cmd).Start in goroutine 115
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x8d5
2024-04-26 23:07 openbsd-386-72 go@ceef0633 internal/trace/v2.TestTraceStress (log)
--- FAIL: TestTraceStress (132.47s)
    --- FAIL: TestTraceStress/Default (132.47s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceStressStartStop (7s)
	TestTraceStressStartStop/Default (7s)

goroutine 108 gp=0x6cfb0248 m=3 mp=0x6cc3aa08 [running]:
...
runtime.chansend1(0x6cfa6200, 0x6cc83fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x6cc83f38 sp=0x6cc83f20 pc=0x804d164
os/exec.(*Cmd).watchCtx(0x6cfbc008, 0x6cfa6200)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x497 fp=0x6cc83fe4 sp=0x6cc83f38 pc=0x8160297
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x6cc83ff0 sp=0x6cc83fe4 pc=0x815fbb8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x6cc83ff4 sp=0x6cc83ff0 pc=0x80baab1
created by os/exec.(*Cmd).Start in goroutine 103
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x8d5

watchflakes

gopherbot avatar Apr 27 '24 13:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-29 14:01 openbsd-386-72 go@f6e6b637 internal/trace/v2.TestTraceStress (log)
--- FAIL: TestTraceStress (132.86s)
    --- FAIL: TestTraceStress/Default (132.86s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceStressStartStop (7s)
	TestTraceStressStartStop/Default (7s)

goroutine 108 gp=0x514070e8 m=2 mp=0x5143a508 [running]:
...
internal/trace/v2_test.testTraceProg.func2(0x5180a208)
	/tmp/workdir/go/src/internal/trace/v2/trace_test.go:611 +0x22 fp=0x51482f84 sp=0x51482f78 pc=0x81f5d82
testing.tRunner(0x5180a208, 0x522540e0)
	/tmp/workdir/go/src/testing/testing.go:1689 +0x113 fp=0x51482fe4 sp=0x51482f84 pc=0x81380e3
testing.(*T).Run.gowrap1()
	/tmp/workdir/go/src/testing/testing.go:1742 +0x28 fp=0x51482ff0 sp=0x51482fe4 pc=0x81390e8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x51482ff4 sp=0x51482ff0 pc=0x80baab1
created by testing.(*T).Run in goroutine 102
	/tmp/workdir/go/src/testing/testing.go:1742 +0x3cb

watchflakes

gopherbot avatar Apr 29 '24 15:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-04-29 14:35 openbsd-386-72 go@16ce8b39 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (28.79s)
    --- FAIL: TestTraceStressStartStop/Default (28.79s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceWaitOnPipe (1s)
	TestTraceWaitOnPipe/Default (1s)

goroutine 120 gp=0x59bb0248 m=2 mp=0x5983a508 [running]:
...
runtime.chansend1(0x59ba5c40, 0x59883fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x59883f38 sp=0x59883f20 pc=0x804d164
os/exec.(*Cmd).watchCtx(0x5b374608, 0x59ba5c40)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x497 fp=0x59883fe4 sp=0x59883f38 pc=0x8160277
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x59883ff0 sp=0x59883fe4 pc=0x815fb98
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x59883ff4 sp=0x59883ff0 pc=0x80baab1
created by os/exec.(*Cmd).Start in goroutine 115
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x8d5
2024-04-30 12:44 openbsd-386-72 go@dc164ead internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (25.03s)
    --- FAIL: TestTraceStressStartStop/Default (25.03s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-30 12:44 openbsd-386-72 go@dc164ead internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (1.14s)
    --- FAIL: TestTraceManyStartStop/Default (1.14s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:575: exit status 1
2024-04-30 15:34 openbsd-386-72 go@476a3525 internal/trace/v2.TestTraceStress (log)
--- FAIL: TestTraceStress (132.31s)
    --- FAIL: TestTraceStress/Default (132.30s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceStressStartStop (7s)
	TestTraceStressStartStop/Default (7s)

goroutine 108 gp=0x5f7b0248 m=2 mp=0x5f43a508 [running]:
...
runtime.chansend1(0x60efe200, 0x5f481fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x5f481f38 sp=0x5f481f20 pc=0x804d164
os/exec.(*Cmd).watchCtx(0x5f7c8008, 0x60efe200)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x497 fp=0x5f481fe4 sp=0x5f481f38 pc=0x8160277
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x5f481ff0 sp=0x5f481fe4 pc=0x815fb98
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x5f481ff4 sp=0x5f481ff0 pc=0x80baab1
created by os/exec.(*Cmd).Start in goroutine 103
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x8d5
2024-04-30 15:38 openbsd-386-72 go@5616ab60 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (23.39s)
    --- FAIL: TestTraceStressStartStop/Default (23.39s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-04-30 15:38 openbsd-386-72 go@5616ab60 internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (1.12s)
    --- FAIL: TestTraceManyStartStop/Default (1.12s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x40af01cf m=3 sigcode=0

            goroutine 0 gp=0x60c08b48 m=3 mp=0x60c40a08 [idle]:
            runtime.kevent(0x3a, 0x0, 0x0, 0x146592c4, 0x40, 0x0)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x14659270 sp=0x14659260 pc=0x80a5501
            runtime.netpoll(0xffffffffffffffff)
...
            edi    0x79c
            esi    0x60c08b48
            ebp    0x14659218
            esp    0x146591fc
            eip    0x40af01cf
            eflags 0x207
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2
2024-04-30 15:38 openbsd-386-72 go@8509f693 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (24.93s)
    --- FAIL: TestTraceStressStartStop/Default (24.93s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceWaitOnPipe (1s)
	TestTraceWaitOnPipe/Default (1s)

goroutine 117 gp=0x4d007e68 m=2 mp=0x4d03a508 [running]:
...
created by os/exec.(*Cmd).Start in goroutine 109
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x8d5

goroutine 116 gp=0x4d3b0248 m=nil [runnable]:
context.WithDeadlineCause.func2()
	/tmp/workdir/go/src/context/context.go:638 fp=0x4d0327f0 sp=0x4d0327ec pc=0x8127f10
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x4d0327f4 sp=0x4d0327f0 pc=0x80baab1
created by time.goFunc
	/tmp/workdir/go/src/time/sleep.go:214 +0x29

watchflakes

gopherbot avatar Apr 30 '24 17:04 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-05-02 18:41 openbsd-386-72 go@865cf5c5 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (32.35s)
    --- FAIL: TestTraceStressStartStop/Default (32.35s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceManyStartStop (2s)
	TestTraceManyStartStop/Default (2s)

goroutine 114 gp=0x69007e68 m=0 mp=0x83b1640 [running]:
...
internal/trace/v2_test.testTraceProg.func2(0x6940a508)
	/tmp/workdir/go/src/internal/trace/v2/trace_test.go:611 +0x22 fp=0x69085f84 sp=0x69085f78 pc=0x81f5d92
testing.tRunner(0x6940a508, 0x69e5e630)
	/tmp/workdir/go/src/testing/testing.go:1690 +0x113 fp=0x69085fe4 sp=0x69085f84 pc=0x81380e3
testing.(*T).Run.gowrap1()
	/tmp/workdir/go/src/testing/testing.go:1743 +0x28 fp=0x69085ff0 sp=0x69085fe4 pc=0x81390e8
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x69085ff4 sp=0x69085ff0 pc=0x80baab1
created by testing.(*T).Run in goroutine 108
	/tmp/workdir/go/src/testing/testing.go:1743 +0x3cb

watchflakes

gopherbot avatar May 02 '24 23:05 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-05-02 22:14 openbsd-386-72 go@b64e5e38 internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (20.28s)
    --- FAIL: TestTraceStressStartStop/Default (20.28s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-05-02 22:14 openbsd-386-72 go@b64e5e38 internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (1.01s)
    --- FAIL: TestTraceManyStartStop/Default (1.01s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:575: signal: killed
2024-05-02 22:18 openbsd-386-72 go@e602625c internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (18.33s)
    --- FAIL: TestTraceStressStartStop/Default (18.33s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
2024-05-02 22:18 openbsd-386-72 go@e602625c internal/trace/v2.TestTraceManyStartStop (log)
--- FAIL: TestTraceManyStartStop (0.83s)
    --- FAIL: TestTraceManyStartStop/Default (0.83s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/many-start-stop.go
        trace_test.go:573: stderr: SIGQUIT: quit
            PC=0x1140a317 m=3 sigcode=0

            goroutine 0 gp=0x50c08b48 m=3 mp=0x50c40a08 [idle]:
            runtime.kevent(0x3a, 0x0, 0x0, 0x1f1c28d4, 0x40, 0x1f1c28a8)
            	runtime/sys_openbsd2.go:219 +0x21 fp=0x1f1c2880 sp=0x1f1c2870 pc=0x80a5501
            runtime.netpoll(0x335e9a79)
...
            edi    0x79c
            esi    0x50c08b48
            ebp    0x1f1c2828
            esp    0x1f1c280c
            eip    0x1140a317
            eflags 0x203
            cs     0x2b
            fs     0x5b
            gs     0x63
        trace_test.go:575: exit status 2

watchflakes

gopherbot avatar May 03 '24 00:05 gopherbot

Found new dashboard test flakes for:

#!watchflakes
post <- pkg == "internal/trace/v2" && `test timed out`
2024-05-03 02:38 openbsd-386-72 go@a3eb55ce internal/trace/v2.TestTraceStressStartStop (log)
--- FAIL: TestTraceStressStartStop (26.02s)
    --- FAIL: TestTraceStressStartStop/Default (26.02s)
        exec.go:193: test timed out while running command: /tmp/workdir/go/bin/go run testdata/testprog/stress-start-stop.go
        trace_test.go:575: signal: killed
panic: test timed out after 3m0s
running tests:
	TestTraceWaitOnPipe (1s)
	TestTraceWaitOnPipe/Default (1s)

goroutine 120 gp=0x607b0248 m=2 mp=0x6043a508 [running]:
...
runtime.chansend1(0x607a5b40, 0x60483fd4)
	/tmp/workdir/go/src/runtime/chan.go:146 +0x24 fp=0x60483f38 sp=0x60483f20 pc=0x804d164
os/exec.(*Cmd).watchCtx(0x617fc908, 0x607a5b40)
	/tmp/workdir/go/src/os/exec/exec.go:848 +0x497 fp=0x60483fe4 sp=0x60483f38 pc=0x8160277
os/exec.(*Cmd).Start.gowrap2()
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x28 fp=0x60483ff0 sp=0x60483fe4 pc=0x815fb98
runtime.goexit({})
	/tmp/workdir/go/src/runtime/asm_386.s:1393 +0x1 fp=0x60483ff4 sp=0x60483ff0 pc=0x80baab1
created by os/exec.(*Cmd).Start in goroutine 115
	/tmp/workdir/go/src/os/exec/exec.go:750 +0x8d5

watchflakes

gopherbot avatar May 03 '24 13:05 gopherbot