go icon indicating copy to clipboard operation
go copied to clipboard
Published 3 weeks ago • verified publisher icon golang

internal/trace: TestTraceGOMAXPROCS/Stress failures

Open gopherbot opened this issue 1 month ago • 5 comments 

#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceGOMAXPROCS/Stress"

Issue created automatically to collect these failures.

Example (log):

=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=50]
        
        M 336379 [ProcStart time=2078662722688 p=1 p_seq=1]
        
        G 5 [status=Waiting seq=0 (gen=50)]
        G 19 [status=Waiting seq=0 (gen=50)]
        G 22 [status=Waiting seq=0 (gen=50)]
        G 23 [status=Waiting seq=0 (gen=50)]
...
        String id=62
        	data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/trace/subscribe.go"
        String id=63
        	data="runtime.forcegchelper"
        String id=64
        	data="runtime.gcBgMarkWorker"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (7.92s)

— watchflakes

gopherbot avatar Nov 27 '25 18:11 gopherbot

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceGOMAXPROCS/Stress"
2025-11-21 19:31 gotip-linux-amd64-longtest go@3fdd183a internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=50]
        
        M 336379 [ProcStart time=2078662722688 p=1 p_seq=1]
        
        G 5 [status=Waiting seq=0 (gen=50)]
        G 19 [status=Waiting seq=0 (gen=50)]
        G 22 [status=Waiting seq=0 (gen=50)]
        G 23 [status=Waiting seq=0 (gen=50)]
...
        String id=62
        	data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/trace/subscribe.go"
        String id=63
        	data="runtime.forcegchelper"
        String id=64
        	data="runtime.gcBgMarkWorker"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (7.92s)
2025-11-24 18:08 gotip-windows-amd64-longtest go@f045ed41 internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=7]
        
        M 7536 [ProcStart time=278439473206 p=1 p_seq=1]
        M 7844 [ProcStart time=278441248291 p=1 p_seq=2]
        
        G 8 [status=Waiting seq=0 (gen=7)]
        G 13 [status=Waiting seq=0 (gen=7)]
        G 15 [status=Waiting seq=0 (gen=7)]
...
        String id=90
        	data="runtime.(*wakeableSleep).sleep"
        String id=91
        	data="runtime.traceStartReadCPU.func1"
        String id=92
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/tracecpu.go"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (4.58s)
2025-11-24 21:20 gotip-windows-amd64-longtest go@0bc19236 internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=4]
        
        M 8620 [ProcStart time=190755471834 p=1 p_seq=13]
        M 6292 [GoUnblock time=190756001218 g=36 g_seq=3 stack=0]
        
        G 1 [status=Waiting seq=0 (gen=4)]
        G 4 [status=Waiting seq=0 (gen=4)]
        G 35 [status=Waiting seq=0 (gen=4)]
...
        String id=102
        	data="C:/b/s/w/ir/x/w/goroot/src/os/file.go"
        String id=103
        	data="runtime.goschedIfBusy"
        String id=104
        	data="runtime.bgsweep"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (1.98s)
2025-11-26 18:40 gotip-windows-amd64-longtest go@e0a4dffb internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=3]
        
        M 1396 [ProcStart time=189972368738 p=1 p_seq=2]
        M 9916 [GoStart time=189972468484 g=7 g_seq=10]
        M 8712 [ProcStart time=189973089532 p=0 p_seq=11]
        
        G 1 [status=Waiting seq=3 (gen=3)]
        G 2 [status=Waiting seq=0 (gen=3)]
...
        String id=98
        	data="runtime.(*scavengerState).park"
        String id=99
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/mgcscavenge.go"
        String id=100
        	data="runtime.bgscavenge"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (3.17s)

— watchflakes

gopherbot avatar Nov 27 '25 18:11 gopherbot

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceGOMAXPROCS/Stress"
2025-12-02 20:32 gotip-windows-amd64-longtest go@e432b4f3 internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=6]
        
        M 8900 [ProcStart time=520203948895 p=1 p_seq=1]
        
        G 12 [status=Waiting seq=0 (gen=6)]
        G 14 [status=Waiting seq=0 (gen=6)]
        G 25 [status=Waiting seq=0 (gen=6)]
        G 26 [status=Running seq=4 (gen=6)]
...
        String id=85
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/mfinal.go"
        String id=86
        	data="runtime.systemstack_switch"
        String id=87
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/asm_amd64.s"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (3.09s)

— watchflakes

gopherbot avatar Dec 02 '25 21:12 gopherbot

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceGOMAXPROCS/Stress"
2025-12-03 16:36 gotip-windows-amd64-longtest go@dcc5fe0c internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=4]
        
        M 3048 [ProcStart time=196106346761 p=1 p_seq=2]
        M 8860 [ProcStart time=196107623317 p=0 p_seq=14]
        M 8120 [GoSyscallEndBlocked time=196108994126]
        M 6184 [ProcStart time=196109149109 p=0 p_seq=16]
        
        G 10 [status=Waiting seq=0 (gen=4)]
...
        String id=76
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/tracecpu.go"
        String id=77
        	data="runtime.forcegchelper"
        String id=78
        	data="runtime.gcBgMarkWorker"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (4.08s)

— watchflakes

gopherbot avatar Dec 03 '25 20:12 gopherbot

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceGOMAXPROCS/Stress"
2025-12-05 15:30 gotip-windows-amd64-longtest go@a70addd3 internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=4]
        
        M 7760 [ProcStart time=198459484018 p=3 p_seq=2]
        M 2608 [ProcStart time=198461173039 p=2 p_seq=4]
        M 7024 [ProcStart time=198461532405 p=3 p_seq=3]
        M 8916 [ProcStart time=198462563138 p=3 p_seq=6]
        M 9000 [GoUnblock time=198464726074 g=4 g_seq=5 stack=0]
        M 8684 [ProcStart time=198464739539 p=6 p_seq=1]
...
        String id=83
        	data="runtime.(*wakeableSleep).sleep"
        String id=84
        	data="runtime.traceStartReadCPU.func1"
        String id=85
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/tracecpu.go"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (6.92s)

— watchflakes

gopherbot avatar Dec 05 '25 17:12 gopherbot

Found new dashboard test flakes for:

#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceGOMAXPROCS/Stress"
2025-12-05 19:51 gotip-windows-amd64-longtest go@b133524c internal/trace.TestTraceGOMAXPROCS/Stress (log) 
=== RUN   TestTraceGOMAXPROCS/Stress
    reader_test.go:113: unexpected error while reading the trace: broken trace: failed to advance: frontier:
        [gen=3]
        
        M 2628 [ProcStart time=450850724284 p=1 p_seq=2]
        M 5056 [ProcStart time=450850752346 p=1 p_seq=3]
        M 5064 [ProcStart time=450851908887 p=1 p_seq=4]
        
        G 34 [status=Waiting seq=0 (gen=3)]
        G 2 [status=Waiting seq=0 (gen=3)]
...
        String id=122
        	data="runtime.goyield"
        String id=123
        	data="runtime.semrelease1"
        String id=124
        	data="C:/b/s/w/ir/x/w/goroot/src/runtime/sema.go"
        EndOfGeneration
        
    helpers.go:39: Convert this to a raw trace with `go test internal/trace/testtrace -convert in.tracetxt -out out.trace`
--- FAIL: TestTraceGOMAXPROCS/Stress (1.85s)

— watchflakes

gopherbot avatar Dec 05 '25 22:12 gopherbot