#!watchflakes
default <- pkg == "internal/trace" && test ~ `TestTraceStacks`
Issue created automatically to collect these failures.
Example (log):
=== RUN TestTraceStacks/Default
trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}}
trace_test.go:668: found bad trace; dumping to test log...
trace_test.go:679: Trace Go1.25
EventBatch gen=1 m=18446744073709551615 time=3561093234 size=23
Sync
Frequency freq=15625000
ClockSnapshot dt=122 mono=227909974775 sec=1751057236 nsec=143347899
EventBatch gen=1 m=28599 time=3562885851 size=7
ProcStart dt=217 p=2 p_seq=3
...
String id=237
data="/home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/sock_cloexec.go"
String id=238
data="runtime.createfing"
String id=239
data="runtime.SetFinalizer"
String id=240
data="net.(*netFD).setAddr"
--- FAIL: TestTraceStacks/Default (7.67s)
Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-27 20:38 gotip-linux-amd64-longtest go@2a22aefa internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=3561093234 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=122 mono=227909974775 sec=1751057236 nsec=143347899 EventBatch gen=1 m=28599 time=3562885851 size=7 ProcStart dt=217 p=2 p_seq=3 ... String id=237 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/sock_cloexec.go" String id=238 data="runtime.createfing" String id=239 data="runtime.SetFinalizer" String id=240 data="net.(*netFD).setAddr" --- FAIL: TestTraceStacks/Default (7.67s)2025-06-27 20:56 gotip-linux-amd64-asan-clang15 go@e81c6246 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2564254135 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=121 mono=164112272358 sec=1751058486 nsec=147607058 EventBatch gen=1 m=26939 time=2566829469 size=293 ProcStart dt=194 p=4 p_seq=1 ... String id=250 data="sync.(*WaitGroup).Done" String id=251 data="runtime.forcegchelper" String id=252 data="net.setNoDelay" String id=253 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/net/tcpsockopt_posix.go" --- FAIL: TestTraceStacks/Default (4.68s)Comment From: thepudds
CC @mknyszek. (Maybe related to runtime: account for missing frame pointer in preamble, or maybe not 😅)
Comment From: thepudds
And watchflakes issue #74418 seems plausibly related.
Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-28 01:17 gotip-linux-arm go@b51f1cdb internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=690126375019001 size=26 Sync Frequency freq=15625000 ClockSnapshot dt=64 mono=44168088001220099 sec=1751081186 nsec=980487350 EventBatch gen=1 m=2607139 time=690126375425763 size=94 ProcStart dt=61 p=3 p_seq=1 ... String id=236 data="sync.(*WaitGroup).Wait" String id=237 data="runtime.(*scavengerState).park" String id=238 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go" String id=239 data="runtime.bgscavenge" --- FAIL: TestTraceStacks/Default (4.89s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-30 14:40 gotip-darwin-amd64_12 go@9d1cd0b8 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=479807028825 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=48 mono=30707649847905 sec=1751295124 nsec=397398000 EventBatch gen=1 m=123145408192512 time=479807034415 size=289 ProcStart dt=33 p=0 p_seq=4 ... String id=221 data="runtime.(*cleanupQueue).dequeue" String id=222 data="/Users/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mcleanup.go" String id=223 data="runtime.runCleanups" String id=224 data="runtime.updateMaxProcsGoroutine" --- FAIL: TestTraceStacks/Default (2.68s)Comment From: mknyszek
Yup, this is a new property the test is checking. Either: 1. The relevant goroutine isn't being synchronously preempted at all at the point we want in the trace. 2. There's still a bug in the traceback.
These should be evident from the failed traces, and indeed, it is. It appears to be (1). In one of the failed traces, the relevant goroutine is always being asynchronously preempted. It's a short test, so with enough runs it's certainly possible for the signals to consistently win out over the synchronous preemption.
That being said, this is just a test-only failure. I don't know what the best way to de-flake this is besides turning off async preemption for this test. (We could run it twice, once with, once without.)
Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-30 16:00 gotip-darwin-arm64_13 go@03ad694d internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=343696552043 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=330 mono=21996579351666 sec=1751300246 nsec=8298000 EventBatch gen=1 m=6132232192 time=343700195373 size=7 ProcStart dt=33 p=4 p_seq=1 ... String id=221 data="internal/poll.(*FD).Close" String id=222 data="net.(*netFD).Close" String id=223 data="net.(*conn).Close" String id=224 data="runtime.stopTheWorldGC" --- FAIL: TestTraceStacks/Default (1.80s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-30 20:32 gotip-linux-amd64-longtest go@8002d283 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2285563626 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=131 mono=146276080222 sec=1751316011 nsec=441716856 EventBatch gen=1 m=26097 time=2290407675 size=6 ProcStart dt=117 p=4 p_seq=1 ... String id=237 data="runtime.(*scavengerState).park" String id=238 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go" String id=239 data="runtime.bgscavenge" String id=240 data="runtime.startTheWorldGC" --- FAIL: TestTraceStacks/Default (9.16s)2025-06-30 20:58 gotip-linux-amd64-longtest-noswissmap go@479b51ee internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2470485260 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=150 mono=158111066202 sec=1751317866 nsec=791711746 EventBatch gen=1 m=25864 time=2473061949 size=57 ProcStart dt=176 p=3 p_seq=9 ... String id=244 data="runtime.runFinalizers" String id=245 data="syscall.getsockopt" String id=246 data="syscall.GetsockoptInt" String id=247 data="runtime.stopTheWorldGC" --- FAIL: TestTraceStacks/Default (11.60s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-30 23:52 gotip-linux-amd64-longtest-aliastypeparams go@86fca3dc internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=3037749324 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=130 mono=194415964987 sec=1751328554 nsec=962946987 EventBatch gen=1 m=28445 time=3039766726 size=62 ProcStart dt=176 p=2 p_seq=11 ... String id=244 data="runtime.createfing" String id=245 data="runtime.SetFinalizer" String id=246 data="net.(*netFD).setAddr" String id=247 data="main.main.func6" --- FAIL: TestTraceStacks/Default (7.49s)2025-07-01 15:29 gotip-linux-amd64-longtest go@27ad1f50 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2329533093 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=122 mono=149090125725 sec=1751384450 nsec=787208967 EventBatch gen=1 m=26115 time=2331892173 size=64 ProcStart dt=244 p=3 p_seq=7 ... String id=244 data="runtime.GOMAXPROCS" String id=245 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/debug.go" String id=246 data="runtime.startTheWorldGC" String id=247 data="internal/poll.(*FD).WaitWrite" --- FAIL: TestTraceStacks/Default (9.42s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-07-01 16:11 go1.25-linux-amd64-longtest-noswissmap release-branch.go1.25@6e95fd96 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2385782643 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=119 mono=152690096706 sec=1751398155 nsec=77647660 EventBatch gen=1 m=25910 time=2387861774 size=49 ProcStart dt=919 p=3 p_seq=1 ... String id=237 data="sync.(*Mutex).Lock" String id=238 data="os.(*file).close" String id=239 data="os.(*File).Close" String id=240 data="net.(*file).close" --- FAIL: TestTraceStacks/Default (10.23s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-30 16:31 go1.25-linux-amd64-longtest release-branch.go1.25@47318323 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=3609698225 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=133 mono=231020694901 sec=1751400309 nsec=252485960 EventBatch gen=1 m=28857 time=3609698458 size=65455 ProcStatus dt=114 p=0 pstatus=1 ... String id=239 data="runtime.createfing" String id=240 data="runtime.SetFinalizer" String id=241 data="net.(*netFD).setAddr" String id=242 data="runtime.startTheWorldGC" --- FAIL: TestTraceStacks/Default (11.41s)2025-06-30 19:14 go1.25-linux-amd64-longtest-aliastypeparams release-branch.go1.25@fdd7713f internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=38055326935 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=114 mono=2435540931075 sec=1751400434 nsec=977514543 EventBatch gen=1 m=311593 time=38057311729 size=372 ProcStart dt=122 p=2 p_seq=9 ... String id=237 data="net.(*file).readLine" String id=238 data="net.setDefaultListenerSockopts" String id=239 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/net/sockopt_linux.go" String id=240 data="runtime.gcWaitOnMark" --- FAIL: TestTraceStacks/Default (11.19s)2025-06-30 23:52 go1.25-linux-amd64-longtest-aliastypeparams release-branch.go1.25@86fca3dc internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=6512270004 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=129 mono=416785288462 sec=1751399301 nsec=873371365 EventBatch gen=1 m=41721 time=6514424819 size=38 ProcStart dt=146 p=3 p_seq=1 ... String id=237 data="net.(*netFD).setAddr" String id=238 data="runtime.traceStartReadCPU" String id=239 data="syscall.connect" String id=240 data="syscall.Connect" --- FAIL: TestTraceStacks/Default (9.82s)2025-07-01 17:07 go1.25-darwin-amd64_12 release-branch.go1.25@2f653a5a internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=323265972151 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=88 mono=20689022223282 sec=1751400020 nsec=329967000 EventBatch gen=1 m=123145562816512 time=323265977061 size=286 ProcStart dt=2 p=0 p_seq=4 ... String id=226 data="syscall.getsockopt" String id=227 data="syscall.GetsockoptInt" String id=228 data="syscall.Listen" String id=229 data="net.setKeepAliveIdle" --- FAIL: TestTraceStacks/Default (2.83s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-07-01 16:11 gotip-linux-amd64-longtest go@6e95fd96 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2227000688 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=127 mono=142528052148 sec=1751386913 nsec=11019048 EventBatch gen=1 m=23706 time=2229189348 size=69 ProcStart dt=168 p=3 p_seq=10 ... String id=237 data="runtime.forcegchelper" String id=238 data="runtime.block" String id=239 data="net.setDefaultListenerSockopts" String id=240 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/net/sockopt_linux.go" --- FAIL: TestTraceStacks/Default (7.56s)2025-07-01 16:11 gotip-linux-amd64-longtest-noswissmap go@6e95fd96 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2311517483 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=128 mono=147937127081 sec=1751386935 nsec=725700959 EventBatch gen=1 m=24946 time=2313403219 size=45 ProcStart dt=4 p=3 p_seq=8 ... String id=237 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go" String id=238 data="main.main.func5" String id=239 data="internal/poll.(*FD).WaitWrite" String id=240 data="runtime.forcegchelper" --- FAIL: TestTraceStacks/Default (8.17s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test == "TestTraceStacks/Default"
2025-06-30 17:44 go1.25-darwin-amd64_13 release-branch.go1.25@9ae38be3 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=576539655368 size=26 Sync Frequency freq=15625000 ClockSnapshot dt=136 mono=36898537952275 sec=1751424317 nsec=704372000 EventBatch gen=1 m=123145462984704 time=576541365289 size=7 ProcStart dt=160 p=2 p_seq=9 ... String id=221 data="syscall.Connect" String id=222 data="sync.(*Cond).Signal" String id=223 data="runtime.block" String id=224 data="runtime.startTheWorldGC" --- FAIL: TestTraceStacks/Default (4.96s)Comment From: cherrymui
don't know what the best way to de-flake this is besides turning off async preemption for this test.
Disabling async preemption for a test is fine, given that the test explicitly wants to test synchronous preemption.
Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test ~ `TestTraceStacks`
2025-06-27 20:56 gotip-linux-amd64-longtest go@e81c6246 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=21526743195 size=26 Sync Frequency freq=15625000 ClockSnapshot dt=66028 mono=1377715789992 sec=1751058383 nsec=970828722 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=21526810000 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="runtime.traceLocker.GoUnpark" String id=238 data="runtime.injectglist" String id=239 data="runtime.bgsweep" String id=240 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go" --- FAIL: TestTraceStacks/AllocFree (2.01s)2025-06-28 01:17 go1.25-linux-amd64-longtest-noswissmap release-branch.go1.25@b51f1cdb internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=63583888133 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=140 mono=4069368849453 sec=1751401731 nsec=482043055 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=63583888767 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=242 data="net.(*file).close" String id=243 data="sync.(*Mutex).Lock" String id=244 data="runtime.updateMaxProcsGoroutine" String id=245 data="runtime.gcBgMarkStartWorkers" --- FAIL: TestTraceStacks/AllocFree (2.64s)2025-06-30 16:00 go1.25-linux-amd64-longtest-aliastypeparams release-branch.go1.25@03ad694d internal/trace.TestTraceStacks/Stress (log)
=== RUN TestTraceStacks/Stress trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=60928360429 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=182 mono=3899415079059 sec=1751401571 nsec=459196432 EventBatch gen=1 m=391301 time=60928458845 size=156 ProcStart dt=105 p=2 p_seq=1 ... String id=77 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go" String id=78 data="runtime.(*cleanupQueue).dequeue" String id=79 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mcleanup.go" String id=80 data="runtime.runCleanups" --- FAIL: TestTraceStacks/Stress (2.37s)2025-06-30 16:00 gotip-linux-amd64-longtest-noswissmap go@03ad694d internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=10432767308 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=131 mono=667697116040 sec=1751300146 nsec=218277355 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=10432767947 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go" String id=238 data="runtime.updateMaxProcsGoroutine" String id=239 data="syscall.getsockopt" String id=240 data="syscall.GetsockoptInt" --- FAIL: TestTraceStacks/AllocFree (4.06s)2025-06-30 16:31 gotip-linux-amd64-longtest go@47318323 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=12937011216 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=125 mono=827968725758 sec=1751302144 nsec=236294488 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=12937011789 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=244 data="io.ReadFull" String id=245 data="net.(*file).readLine" String id=246 data="time.Sleep" String id=247 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/time.go" --- FAIL: TestTraceStacks/AllocFree (2.74s)2025-06-30 17:44 go1.25-linux-amd64-longtest-aliastypeparams release-branch.go1.25@9ae38be3 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=24647784039 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=278 mono=1577458185850 sec=1751400452 nsec=23293544 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=24647784739 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=244 data="io.ReadAtLeast" String id=245 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go" String id=246 data="io.ReadFull" String id=247 data="net.(*file).readLine" --- FAIL: TestTraceStacks/AllocFree (2.12s)2025-06-30 17:44 gotip-linux-amd64-longtest-aliastypeparams go@9ae38be3 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2281330242 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=127 mono=146005143593 sec=1751306565 nsec=642537769 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=2281330866 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=250 data="io.ReadAtLeast" String id=251 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/io/io.go" String id=252 data="io.ReadFull" String id=253 data="net.(*file).readLine" --- FAIL: TestTraceStacks/AllocFree (1.48s)2025-06-30 20:32 go1.25-linux-amd64-longtest-aliastypeparams release-branch.go1.25@8002d283 internal/trace.TestTraceStacks/Stress (log)
=== RUN TestTraceStacks/Stress trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=22541578817 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=149 mono=1442661053811 sec=1751400383 nsec=271163378 EventBatch gen=1 m=283932 time=22541773631 size=110 ProcStart dt=151 p=1 p_seq=1 ... String id=95 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/preempt_amd64.s" String id=96 data="main.syncPreemptPoint" String id=97 data="main.main.func12" String id=98 data="runtime.updateMaxProcsGoroutine" --- FAIL: TestTraceStacks/Stress (2.22s)2025-06-30 20:32 go1.25-linux-amd64-longtest-noswissmap release-branch.go1.25@8002d283 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=38349028839 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=122 mono=2454337853314 sec=1751400463 nsec=575102952 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=38349029430 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=244 data="runtime.SetFinalizer" String id=245 data="net.(*netFD).setAddr" String id=246 data="runtime.startTheWorldGC" String id=247 data="runtime.gcWaitOnMark" --- FAIL: TestTraceStacks/AllocFree (2.46s)2025-06-30 23:52 gotip-linux-amd64-longtest-noswissmap go@6bd9944c internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2526532060 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=142 mono=161698060873 sec=1751328568 nsec=126554427 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=2526533464 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mfinal.go" String id=238 data="runtime.createfing" String id=239 data="runtime.SetFinalizer" String id=240 data="net.(*netFD).setAddr" --- FAIL: TestTraceStacks/AllocFree (1.89s)2025-06-30 23:52 go1.25-linux-amd64-longtest release-branch.go1.25@86fca3dc internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2925252491 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=126 mono=187216167481 sec=1751399070 nsec=592626293 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=2925253082 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="syscall.accept4" String id=238 data="syscall.Accept4" String id=239 data="internal/poll.accept" String id=240 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/sock_cloexec.go" --- FAIL: TestTraceStacks/AllocFree (2.19s)2025-06-30 23:52 go1.25-linux-amd64-longtest-noswissmap release-branch.go1.25@86fca3dc internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=21319641468 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=132 mono=1364457062374 sec=1751399367 nsec=212887693 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=21319642064 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/os/pipe2_unix.go" String id=238 data="runtime.traceStartReadCPU" String id=239 data="internal/poll.(*FD).WaitWrite" String id=240 data="sync.(*Mutex).Unlock" --- FAIL: TestTraceStacks/AllocFree (3.17s)2025-07-01 17:07 go1.25-linux-arm64-longtest release-branch.go1.25@2f653a5a internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=8929047050 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=73 mono=571459015868 sec=1751398229 nsec=266429868 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=8929047287 data="\x01\x80\x80\x80\x80\x80\b\x80@\b\x80\x10" ... String id=237 data="syscall.connect" String id=238 data="syscall.Connect" String id=239 data="runtime.forcegchelper" String id=240 data="sync.(*Mutex).Lock" --- FAIL: TestTraceStacks/AllocFree (1.19s)2025-07-01 17:07 gotip-linux-amd64-longtest go@2f653a5a internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=6633892548 size=25 Sync Frequency freq=15625000 ClockSnapshot dt=128 mono=424569131224 sec=1751390371 nsec=605714807 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=6633893130 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=240 data="runtime.bgsweep" String id=241 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go" String id=242 data="sync.(*WaitGroup).Add" String id=243 data="sync.(*WaitGroup).Done" --- FAIL: TestTraceStacks/AllocFree (2.12s)2025-07-01 17:07 gotip-linux-amd64-longtest-noswissmap go@2f653a5a internal/trace.TestTraceStacks/Stress (log)
=== RUN TestTraceStacks/Stress trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2383755173 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=118 mono=152560338612 sec=1751390419 nsec=948924265 EventBatch gen=1 m=25202 time=2383824267 size=113 ProcStart dt=139 p=1 p_seq=1 ... String id=71 data="main.main.func1" String id=72 data="runtime.chansend" String id=73 data="runtime.chansend1" String id=74 data="main.main.func2" --- FAIL: TestTraceStacks/Stress (2.42s)2025-07-01 21:05 gotip-linux-amd64-longtest-aliastypeparams go@de646d94 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=17163541715 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=127 mono=1098466677837 sec=1751404489 nsec=84511491 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=17163542367 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="os.ignoringEINTR" String id=238 data="sync.(*Cond).Signal" String id=239 data="net.setDefaultListenerSockopts" String id=240 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/net/sockopt_linux.go" --- FAIL: TestTraceStacks/AllocFree (2.46s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test ~ `TestTraceStacks`
2025-07-02 11:21 gotip-linux-amd64-longtest-noswissmap go@15d9fe43 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2335466359 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=121 mono=149469854696 sec=1751456018 nsec=858833395 EventBatch gen=1 m=24624 time=2335805275 size=440 ProcStart dt=7 p=3 p_seq=1 ... String id=244 data="syscall.accept4" String id=245 data="syscall.Accept4" String id=246 data="internal/poll.accept" String id=247 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/internal/poll/sock_cloexec.go" --- FAIL: TestTraceStacks/Default (7.45s)2025-07-02 14:47 gotip-linux-amd64-longtest-noswissmap go@b3251514 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=9036510806 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=117 mono=578336699030 sec=1751468393 nsec=985141660 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=9036511383 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=240 data="runtime.stopTheWorldGC" String id=241 data="runtime.(*scavengerState).park" String id=242 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcscavenge.go" String id=243 data="runtime.bgscavenge" --- FAIL: TestTraceStacks/AllocFree (1.92s)Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test ~ `TestTraceStacks`
2025-07-02 14:47 gotip-linux-amd64-longtest-aliastypeparams go@b3251514 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2727187797 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=126 mono=174540027008 sec=1751468325 nsec=599306060 EventBatch gen=1 m=26751 time=2730719744 size=31 ProcStart dt=148 p=3 p_seq=6 ... String id=245 data="syscall.Connect" String id=246 data="sync.(*Mutex).Unlock" String id=247 data="syscall.getsockopt" String id=248 data="syscall.GetsockoptInt" --- FAIL: TestTraceStacks/Default (6.94s)2025-07-03 20:33 gotip-linux-amd64-longtest-aliastypeparams go@dd687c38 internal/trace.TestTraceStacks/Default (log)
=== RUN TestTraceStacks/Default trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=2407059364 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=121 mono=154051806983 sec=1751575582 nsec=110664204 EventBatch gen=1 m=24634 time=2408926257 size=42 ProcStart dt=133 p=3 p_seq=4 ... String id=238 data="sync.(*WaitGroup).Add" String id=239 data="sync.(*WaitGroup).Done" String id=240 data="runtime.startTheWorldGC" String id=241 data="syscall.Listen" --- FAIL: TestTraceStacks/Default (7.73s)2025-07-04 01:43 gotip-linux-amd64-longtest go@6c3b5a27 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=10603099798 size=23 Sync Frequency freq=15625000 ClockSnapshot dt=118 mono=678598394586 sec=1751594258 nsec=146496029 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=10603100406 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="/home/swarming/.swarming/w/ir/x/w/goroot/src/runtime/mgcsweep.go" String id=238 data="runtime.createfing" String id=239 data="runtime.SetFinalizer" String id=240 data="net.(*netFD).setAddr" --- FAIL: TestTraceStacks/AllocFree (2.41s)Comment From: gopherbot
Change https://go.dev/cl/686055 mentions this issue: internal/trace: only test for sync preemption if async preemption is off
Comment From: gopherbot
Found new dashboard test flakes for:
#!watchflakes
default <- pkg == "internal/trace" && test ~ `TestTraceStacks`
2025-07-08 17:29 go1.25-linux-amd64-longtest-aliastypeparams release-branch.go1.25@e73dadc7 internal/trace.TestTraceStacks/AllocFree (log)
=== RUN TestTraceStacks/AllocFree trace_test.go:499: no match for StateTransition Match=Goroutine Running->Runnable Stack=[]trace_test.frame{trace_test.frame{fn:"main.syncPreemptPoint", line:0}, trace_test.frame{fn:"main.main.func12", line:0}} trace_test.go:668: found bad trace; dumping to test log... trace_test.go:679: Trace Go1.25 EventBatch gen=1 m=18446744073709551615 time=58219797670 size=24 Sync Frequency freq=15625000 ClockSnapshot dt=124 mono=3726067058773 sec=1751996129 nsec=515614359 ExperimentalBatch exp=1 gen=1 m=18446744073709551615 time=58219798244 data="\x01\x80\x80\x80\x80\x80\x18\x80@\b\x80\x10" ... String id=237 data="os.ignoringEINTR" String id=238 data="runtime.createfing" String id=239 data="runtime.SetFinalizer" String id=240 data="net.(*netFD).setAddr" --- FAIL: TestTraceStacks/AllocFree (2.18s)Comment From: mknyszek
Looks like this tested at a commit that didn't have the fix? https://go.googlesource.com/go/+log/e73dadc7 It's weird, because if I go directly to the branch, it does seem like the fix was included in RC2. Anyway, I don't think this issue persists.