TestFilteredSubscriptions timeout on Windows #1575

Open
opened 2025-12-28 17:16:53 +00:00 by sami · 0 comments
Owner

Originally created by @AnnaShaleva on GitHub (Nov 12, 2025).

Go 1.25, Windows, firstly discovered in #4058 (https://github.com/nspcc-dev/neo-go/actions/runs/19288898247/job/55159821870?pr=4058):

2025-11-12T08:26:10.2619390Z === RUN   TestFilteredSubscriptions/execution_matching_state
2025-11-12T08:26:10.2619829Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	initial gas supply is not set or wrong, setting default value	{"InitialGASSupply": "52000000"}
2025-11-12T08:26:10.2620226Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	MaxBlockSize is not set or wrong, setting default value	{"MaxBlockSize": 262144}
2025-11-12T08:26:10.2620678Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	MaxBlockSystemFee is not set or wrong, setting default value	{"MaxBlockSystemFee": 900000000000}
2025-11-12T08:26:10.2621223Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	Genesis MaxTraceableBlocks is not set or wrong, using default value	{"Genesis MaxTraceableBlocks": 200000}
2025-11-12T08:26:10.2621701Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	MaxTransactionsPerBlock is not set or wrong, using default value	{"MaxTransactionsPerBlock": 512}
2025-11-12T08:26:10.2622208Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	MaxValidUntilBlockIncrement is not set or wrong, using default value	{"MaxValidUntilBlockIncrement": 5760}
2025-11-12T08:26:10.2622785Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	Genesis MaxValidUntilBlockIncrement is not set or wrong, using default value	{"Genesis MaxValidUntilBlockIncrement": 5760}
2025-11-12T08:26:10.2623104Z     logger.go:146: 2025-11-12T08:25:09.235Z	INFO	no storage version found! creating genesis block
2025-11-12T08:26:10.2623534Z     logger.go:146: 2025-11-12T08:25:09.240Z	INFO	ExtensiblePoolSize is not set or wrong, using default value	{"ExtensiblePoolSize": 20}
2025-11-12T08:26:10.2624005Z     logger.go:146: 2025-11-12T08:25:09.240Z	INFO	BroadcastTxsBatchDelay is not set or wrong, using default value	{"BroadcastTxsBatchDelay": "50ms"}
2025-11-12T08:26:10.2624423Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	SessionPoolSize is not set or wrong, setting default value	{"SessionPoolSize": 20}
2025-11-12T08:26:10.2624886Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	MaxIteratorResultItems is not set or wrong, setting default value	{"MaxIteratorResultItems": 100}
2025-11-12T08:26:10.2625310Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	MaxFindResultItems is not set or wrong, setting default value	{"MaxFindResultItems": 100}
2025-11-12T08:26:10.2625707Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	MaxNEP11Tokens is not set or wrong, setting default value	{"MaxNEP11Tokens": 100}
2025-11-12T08:26:10.2626153Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	MaxRequestBodyBytes is not set or wong, setting default value	{"MaxRequestBodyBytes": 5242880}
2025-11-12T08:26:10.2626622Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	MaxRequestHeaderBytes is not set or wong, setting default value	{"MaxRequestHeaderBytes": 1048576}
2025-11-12T08:26:10.2627098Z     logger.go:146: 2025-11-12T08:25:09.242Z	INFO	MaxWebSocketClients is not set or wrong, setting default value	{"MaxWebSocketClients": 64}
2025-11-12T08:26:10.2627513Z     logger.go:146: 2025-11-12T08:25:09.243Z	INFO	MaxWebSocketFeeds is not set or wrong, setting default value	{"MaxWebSocketFeeds": 16}
2025-11-12T08:26:10.2627796Z     logger.go:146: 2025-11-12T08:25:09.243Z	INFO	starting rpc-server	{"endpoint": "127.0.0.1:0"}
2025-11-12T08:26:10.2628171Z     logger.go:146: 2025-11-12T08:25:09.486Z	DEBUG	processing rpc request	{"method": "subscribe", "params": "[block_added]"}
2025-11-12T08:26:10.2628585Z     logger.go:146: 2025-11-12T08:25:09.549Z	DEBUG	processing rpc request	{"method": "subscribe", "params": "[transaction_executed ]"}
2025-11-12T08:26:10.2628970Z     logger.go:146: 2025-11-12T08:25:09.706Z	DEBUG	done processing headers	{"headerIndex": 1, "blockHeight": 0, "took": "0s"}
2025-11-12T08:26:10.2629378Z     logger.go:146: 2025-11-12T08:25:09.706Z	DEBUG	done processing headers	{"headerIndex": 2, "blockHeight": 1, "took": "0s"}
2025-11-12T08:26:10.2629785Z     logger.go:146: 2025-11-12T08:25:09.721Z	DEBUG	done processing headers	{"headerIndex": 3, "blockHeight": 2, "took": "15.0456ms"}
2025-11-12T08:26:10.2630300Z     logger.go:146: 2025-11-12T08:25:09.832Z	DEBUG	done processing headers	{"headerIndex": 4, "blockHeight": 3, "took": "532.2µs"}
2025-11-12T08:26:10.2630682Z     logger.go:146: 2025-11-12T08:25:09.832Z	DEBUG	done processing headers	{"headerIndex": 5, "blockHeight": 4, "took": "0s"}
2025-11-12T08:26:10.2631057Z     logger.go:146: 2025-11-12T08:25:09.832Z	DEBUG	done processing headers	{"headerIndex": 6, "blockHeight": 5, "took": "0s"}
2025-11-12T08:26:10.2631470Z     logger.go:146: 2025-11-12T08:25:09.847Z	DEBUG	done processing headers	{"headerIndex": 7, "blockHeight": 6, "took": "0s"}
2025-11-12T08:26:10.2631869Z     logger.go:146: 2025-11-12T08:25:09.926Z	DEBUG	done processing headers	{"headerIndex": 8, "blockHeight": 7, "took": "79.0171ms"}
2025-11-12T08:26:10.2632249Z     logger.go:146: 2025-11-12T08:25:09.926Z	DEBUG	done processing headers	{"headerIndex": 9, "blockHeight": 8, "took": "0s"}
2025-11-12T08:26:10.2632630Z     logger.go:146: 2025-11-12T08:25:09.926Z	DEBUG	done processing headers	{"headerIndex": 10, "blockHeight": 9, "took": "0s"}
2025-11-12T08:26:10.2633037Z     logger.go:146: 2025-11-12T08:25:10.099Z	DEBUG	done processing headers	{"headerIndex": 11, "blockHeight": 10, "took": "172.7512ms"}
2025-11-12T08:26:10.2633423Z     logger.go:146: 2025-11-12T08:25:10.099Z	DEBUG	done processing headers	{"headerIndex": 12, "blockHeight": 11, "took": "0s"}
2025-11-12T08:26:10.2633837Z     logger.go:146: 2025-11-12T08:25:10.114Z	DEBUG	done processing headers	{"headerIndex": 13, "blockHeight": 12, "took": "0s"}
2025-11-12T08:26:10.2634271Z     logger.go:146: 2025-11-12T08:25:10.303Z	INFO	persisted to disk	{"blocks": 12, "keys": 498, "headerHeight": 13, "blockHeight": 12, "took": "0s"}
2025-11-12T08:26:10.2634667Z     logger.go:146: 2025-11-12T08:25:10.303Z	DEBUG	done processing headers	{"headerIndex": 14, "blockHeight": 13, "took": "0s"}
2025-11-12T08:26:10.2635043Z     logger.go:146: 2025-11-12T08:25:10.303Z	DEBUG	done processing headers	{"headerIndex": 15, "blockHeight": 14, "took": "0s"}
2025-11-12T08:26:10.2635423Z     logger.go:146: 2025-11-12T08:25:10.303Z	DEBUG	done processing headers	{"headerIndex": 16, "blockHeight": 15, "took": "0s"}
2025-11-12T08:26:10.2635798Z     logger.go:146: 2025-11-12T08:25:10.317Z	DEBUG	done processing headers	{"headerIndex": 17, "blockHeight": 16, "took": "0s"}
2025-11-12T08:26:10.2636177Z     logger.go:146: 2025-11-12T08:25:10.459Z	DEBUG	done processing headers	{"headerIndex": 18, "blockHeight": 17, "took": "0s"}
2025-11-12T08:26:10.2636562Z     logger.go:146: 2025-11-12T08:25:10.459Z	DEBUG	done processing headers	{"headerIndex": 19, "blockHeight": 18, "took": "0s"}
2025-11-12T08:26:10.2636939Z     logger.go:146: 2025-11-12T08:25:10.474Z	DEBUG	done processing headers	{"headerIndex": 20, "blockHeight": 19, "took": "0s"}
2025-11-12T08:26:10.2637361Z     logger.go:146: 2025-11-12T08:25:10.474Z	DEBUG	done processing headers	{"headerIndex": 21, "blockHeight": 20, "took": "0s"}
2025-11-12T08:26:10.2637766Z     logger.go:146: 2025-11-12T08:25:10.600Z	DEBUG	done processing headers	{"headerIndex": 22, "blockHeight": 21, "took": "126.1322ms"}
2025-11-12T08:26:10.2638144Z     logger.go:146: 2025-11-12T08:25:10.615Z	DEBUG	done processing headers	{"headerIndex": 23, "blockHeight": 22, "took": "0s"}
2025-11-12T08:26:10.2638863Z     logger.go:146: 2025-11-12T08:25:10.615Z	WARN	contract invocation failed	{"tx": "82279bfe9bada282ca0f8cb8e0bb124b921af36f00c69a518320322c6f4fef60", "block": 23, "error": "at instruction 0 (ABORT): ABORT"}
2025-11-12T08:26:10.2639211Z     logger.go:146: 2025-11-12T08:25:10.881Z	DEBUG	processing rpc request	{"method": "unsubscribe", "params": "[1]"}
2025-11-12T08:26:10.2639556Z     logger.go:146: 2025-11-12T08:25:11.257Z	DEBUG	processing rpc request	{"method": "unsubscribe", "params": "[0]"}
2025-11-12T08:26:10.2640041Z     logger.go:146: 2025-11-12T08:25:11.452Z	INFO	persisted to disk	{"blocks": 11, "keys": 848, "headerHeight": 23, "blockHeight": 23, "took": "1.5207ms"}
2025-11-12T08:26:10.2640127Z panic: test timed out after 15m0s
2025-11-12T08:26:10.2640207Z 	running tests:
2025-11-12T08:26:10.2640306Z 		TestFilteredSubscriptions (33s)
2025-11-12T08:26:10.2640477Z 		TestFilteredSubscriptions/execution_matching_state (2s)
2025-11-12T08:26:10.2640487Z 
2025-11-12T08:26:10.2640562Z goroutine 15754 [running]:
2025-11-12T08:26:10.2640641Z testing.(*M).startAlarm.func1()
2025-11-12T08:26:10.2640867Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2682 +0x605
2025-11-12T08:26:10.2641194Z created by time.goFunc
2025-11-12T08:26:10.2641388Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/time/sleep.go:215 +0x45
2025-11-12T08:26:10.2641399Z 
2025-11-12T08:26:10.2641473Z goroutine 1 [chan receive]:
2025-11-12T08:26:10.2641610Z testing.(*T).Run(0xc000086a80, {0x1417011d3, 0x19}, 0x1417f44f0)
2025-11-12T08:26:10.2641824Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2005 +0xa08
2025-11-12T08:26:10.2641912Z testing.runTests.func1(0xc000086a80)
2025-11-12T08:26:10.2642114Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2477 +0x86
2025-11-12T08:26:10.2642210Z testing.tRunner(0xc000086a80, 0xc00041fae0)
2025-11-12T08:26:10.2642407Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1934 +0x1c4
2025-11-12T08:26:10.2642653Z testing.runTests(0xc0000d63a8, {0x14141d7c0, 0x39, 0x39}, {0x1?, 0x140080fe5?, 0x1422fdde0?})
2025-11-12T08:26:10.2642898Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2475 +0x96d
2025-11-12T08:26:10.2642983Z testing.(*M).Run(0xc000383d60)
2025-11-12T08:26:10.2643183Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2337 +0xed5
2025-11-12T08:26:10.2643244Z main.main()
2025-11-12T08:26:10.2643323Z 	_testmain.go:159 +0x165
2025-11-12T08:26:10.2643330Z 
2025-11-12T08:26:10.2643413Z goroutine 16125 [semacquire]:
2025-11-12T08:26:10.2643524Z internal/poll.runtime_Semacquire(0xc002010c88?)
2025-11-12T08:26:10.2643710Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/runtime/sema.go:76 +0x25
2025-11-12T08:26:10.2643802Z internal/poll.(*FD).Close(0xc002010c88)
2025-11-12T08:26:10.2644031Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:485 +0xdf
2025-11-12T08:26:10.2644123Z net.(*netFD).Close(0xc002010c88)
2025-11-12T08:26:10.2644304Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/fd_posix.go:50 +0x3e
2025-11-12T08:26:10.2644381Z net.(*TCPListener).close(...)
2025-11-12T08:26:10.2644576Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock_posix.go:167
2025-11-12T08:26:10.2644664Z net.(*TCPListener).Close(0xc000899140)
2025-11-12T08:26:10.2644843Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock.go:393 +0x6b
2025-11-12T08:26:10.2644954Z net/http/httptest.(*Server).Close(0xc002be5260)
2025-11-12T08:26:10.2645224Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/httptest/server.go:206 +0xb0
2025-11-12T08:26:10.2645313Z testing.(*common).Cleanup.func1()
2025-11-12T08:26:10.2645514Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1308 +0x169
2025-11-12T08:26:10.2645618Z testing.(*common).runCleanup(0xc00107f6c0, 0x0)
2025-11-12T08:26:10.2645821Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1572 +0x226
2025-11-12T08:26:10.2645895Z testing.tRunner.func2()
2025-11-12T08:26:10.2646092Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1928 +0x4d
2025-11-12T08:26:10.2646191Z testing.tRunner(0xc00107f6c0, 0xc000385f50)
2025-11-12T08:26:10.2646386Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1940 +0x1f7
2025-11-12T08:26:10.2646495Z created by testing.(*T).Run in goroutine 14364
2025-11-12T08:26:10.2646690Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1997 +0x9dd
2025-11-12T08:26:10.2646694Z 
2025-11-12T08:26:10.2646765Z goroutine 16132 [select]:
2025-11-12T08:26:10.2647029Z github.com/nspcc-dev/neo-go/pkg/core/mempool.(*Pool).notificationDispatcher(...)
2025-11-12T08:26:10.2647181Z 	D:/a/neo-go/neo-go/pkg/core/mempool/subscriptions.go:55
2025-11-12T08:26:10.2647464Z created by github.com/nspcc-dev/neo-go/pkg/core/mempool.(*Pool).RunSubscriptions in goroutine 16127
2025-11-12T08:26:10.2647623Z 	D:/a/neo-go/neo-go/pkg/core/mempool/subscriptions.go:13 +0xda
2025-11-12T08:26:10.2647629Z 
2025-11-12T08:26:10.2647698Z goroutine 16129 [IO wait]:
2025-11-12T08:26:10.2647810Z internal/poll.runtime_pollWait(0x48a0ea00, 0x72)
2025-11-12T08:26:10.2648013Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/runtime/netpoll.go:351 +0x85
2025-11-12T08:26:10.2648173Z internal/poll.(*pollDesc).wait(0xc002010bb0, 0x72, 0x0)
2025-11-12T08:26:10.2648416Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_poll_runtime.go:84 +0xb1
2025-11-12T08:26:10.2648497Z internal/poll.waitIO(0xc002010a20)
2025-11-12T08:26:10.2648731Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:187 +0x158
2025-11-12T08:26:10.2648835Z internal/poll.execIO(0xc002010a20, 0xc00005b8d8)
2025-11-12T08:26:10.2649061Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:240 +0x2d2
2025-11-12T08:26:10.2649281Z internal/poll.(*FD).acceptOne(0xc002010a08, 0x63c, {0xc00109bb30, 0x2, 0x2}, 0xc002010a20)
2025-11-12T08:26:10.2649502Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1059 +0xee
2025-11-12T08:26:10.2649620Z internal/poll.(*FD).Accept(0xc002010a08, 0xc00005bb50)
2025-11-12T08:26:10.2649886Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1093 +0x3ca
2025-11-12T08:26:10.2649968Z net.(*netFD).accept(0xc002010a08)
2025-11-12T08:26:10.2650172Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/fd_windows.go:202 +0x85
2025-11-12T08:26:10.2650257Z net.(*TCPListener).accept(0xc000899100)
2025-11-12T08:26:10.2650460Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock_posix.go:159 +0x4e
2025-11-12T08:26:10.2650552Z net.(*TCPListener).Accept(0xc000899100)
2025-11-12T08:26:10.2650734Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock.go:380 +0x65
2025-11-12T08:26:10.2650880Z net/http.(*Server).Serve(0xc001324900, {0x1419d53c0, 0xc000899100})
2025-11-12T08:26:10.2651081Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/server.go:3463 +0x610
2025-11-12T08:26:10.2651300Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).Start.func1(0xc001324900)
2025-11-12T08:26:10.2651450Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:426 +0x79
2025-11-12T08:26:10.2651700Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).Start in goroutine 16125
2025-11-12T08:26:10.2651847Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:425 +0x1ac
2025-11-12T08:26:10.2651852Z 
2025-11-12T08:26:10.2651926Z goroutine 16131 [select]:
2025-11-12T08:26:10.2652159Z github.com/nspcc-dev/neo-go/pkg/core.(*Blockchain).notificationDispatcher(0xc001fac008)
2025-11-12T08:26:10.2652334Z 	D:/a/neo-go/neo-go/pkg/core/blockchain.go:1702 +0x54f
2025-11-12T08:26:10.2652547Z created by github.com/nspcc-dev/neo-go/pkg/core.(*Blockchain).Run in goroutine 16127
2025-11-12T08:26:10.2652673Z 	D:/a/neo-go/neo-go/pkg/core/blockchain.go:1348 +0x14b
2025-11-12T08:26:10.2652678Z 
2025-11-12T08:26:10.2652754Z goroutine 16130 [runnable]:
2025-11-12T08:26:10.2652890Z internal/poll.runtime_pollWaitCanceled(0x48a4b400, 0x72)
2025-11-12T08:26:10.2653096Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/runtime/netpoll.go:367 +0x2e
2025-11-12T08:26:10.2653198Z internal/poll.(*pollDesc).waitCanceled(...)
2025-11-12T08:26:10.2653425Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_poll_runtime.go:100
2025-11-12T08:26:10.2653513Z internal/poll.cancelIO(0xc002010ca0)
2025-11-12T08:26:10.2653739Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:210 +0x145
2025-11-12T08:26:10.2653845Z internal/poll.execIO(0xc002010ca0, 0xc0003059a0)
2025-11-12T08:26:10.2654109Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:243 +0x2f2
2025-11-12T08:26:10.2654326Z internal/poll.(*FD).acceptOne(0xc002010c88, 0x688, {0xc00109bd10, 0x2, 0x2}, 0xc002010ca0)
2025-11-12T08:26:10.2654554Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1059 +0xee
2025-11-12T08:26:10.2654676Z internal/poll.(*FD).Accept(0xc002010c88, 0xc000305c18)
2025-11-12T08:26:10.2654902Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1093 +0x3ca
2025-11-12T08:26:10.2654984Z net.(*netFD).accept(0xc002010c88)
2025-11-12T08:26:10.2655183Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/fd_windows.go:202 +0x85
2025-11-12T08:26:10.2655304Z net.(*TCPListener).accept(0xc000899140)
2025-11-12T08:26:10.2655511Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock_posix.go:159 +0x4e
2025-11-12T08:26:10.2655591Z net.(*TCPListener).Accept(0xc000899140)
2025-11-12T08:26:10.2655773Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock.go:380 +0x65
2025-11-12T08:26:10.2655928Z net/http.(*Server).Serve(0xc001324a00, {0x1419d53c0, 0xc000899140})
2025-11-12T08:26:10.2656126Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/server.go:3463 +0x610
2025-11-12T08:26:10.2656230Z net/http/httptest.(*Server).goServe.func1()
2025-11-12T08:26:10.2656454Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/httptest/server.go:311 +0xb3
2025-11-12T08:26:10.2656616Z created by net/http/httptest.(*Server).goServe in goroutine 16125
2025-11-12T08:26:10.2656844Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/httptest/server.go:309 +0xa5
2025-11-12T08:26:10.2656849Z 
2025-11-12T08:26:10.2656957Z goroutine 4886 [runnable]:
2025-11-12T08:26:10.2657272Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*WSClient).notifySubscribers(0xc000b281c0, {0xd4?, {0x0?, 0x0?}})
2025-11-12T08:26:10.2657413Z 	D:/a/neo-go/neo-go/pkg/rpcclient/wsclient.go:754 +0x167
2025-11-12T08:26:10.2657609Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc000b281c0)
2025-11-12T08:26:10.2657741Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:64 +0x47
2025-11-12T08:26:10.2657954Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 4614
2025-11-12T08:26:10.2658075Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd
2025-11-12T08:26:10.2658084Z 
2025-11-12T08:26:10.2658154Z goroutine 16127 [select]:
2025-11-12T08:26:10.2658321Z github.com/nspcc-dev/neo-go/pkg/core.(*Blockchain).Run(0xc001fac008)
2025-11-12T08:26:10.2658458Z 	D:/a/neo-go/neo-go/pkg/core/blockchain.go:1353 +0x2b1
2025-11-12T08:26:10.2658789Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.getUnitTestChainWithCustomConfig in goroutine 16125
2025-11-12T08:26:10.2658975Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server_helper_test.go:85 +0x714
2025-11-12T08:26:10.2658980Z 
2025-11-12T08:26:10.2659066Z goroutine 5186 [runnable]:
2025-11-12T08:26:10.2659364Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*WSClient).notifySubscribers(0xc0009fe8c0, {0x0?, {0x0?, 0x0?}})
2025-11-12T08:26:10.2659545Z 	D:/a/neo-go/neo-go/pkg/rpcclient/wsclient.go:743 +0x609
2025-11-12T08:26:10.2659741Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc0009fe8c0)
2025-11-12T08:26:10.2659862Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:64 +0x47
2025-11-12T08:26:10.2660080Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 5171
2025-11-12T08:26:10.2660201Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd
2025-11-12T08:26:10.2660205Z 
2025-11-12T08:26:10.2660285Z goroutine 14364 [chan receive]:
2025-11-12T08:26:10.2660424Z testing.(*T).Run(0xc002622540, {0x1416ffc10, 0x18}, 0xc000385f50)
2025-11-12T08:26:10.2660632Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2005 +0xa08
2025-11-12T08:26:10.2660883Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.TestFilteredSubscriptions(0xc002622540)
2025-11-12T08:26:10.2661070Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/subscription_test.go:393 +0xbd6
2025-11-12T08:26:10.2661197Z testing.tRunner(0xc002622540, 0x1417f44f0)
2025-11-12T08:26:10.2661406Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1934 +0x1c4
2025-11-12T08:26:10.2661495Z created by testing.(*T).Run in goroutine 1
2025-11-12T08:26:10.2661698Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1997 +0x9dd
2025-11-12T08:26:10.2661703Z 
2025-11-12T08:26:10.2661773Z goroutine 5250 [runnable]:
2025-11-12T08:26:10.2661856Z context.(*cancelCtx).Done(0xc0014add10)
2025-11-12T08:26:10.2662062Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/context/context.go:448 +0x1aa
2025-11-12T08:26:10.2662261Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc000b28e00)
2025-11-12T08:26:10.2662417Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:55 +0x70
2025-11-12T08:26:10.2662632Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 5244
2025-11-12T08:26:10.2662752Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd
2025-11-12T08:26:10.2662759Z 
2025-11-12T08:26:10.2662836Z goroutine 6954 [runnable]:
2025-11-12T08:26:10.2662916Z sync/atomic.(*Value).Load(0xc00260cf18)
2025-11-12T08:26:10.2663117Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/sync/atomic/value.go:36 +0x93
2025-11-12T08:26:10.2663204Z context.(*cancelCtx).Done(0xc00260cf00)
2025-11-12T08:26:10.2663403Z 	C:/hostedtoolcache/windows/go/1.25.3/x64/src/context/context.go:449 +0x4d
2025-11-12T08:26:10.2663595Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc00107efc0)
2025-11-12T08:26:10.2663719Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:55 +0x70
2025-11-12T08:26:10.2663964Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 6983
2025-11-12T08:26:10.2664093Z 	D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd
2025-11-12T08:26:10.2664097Z 
2025-11-12T08:26:10.2664176Z goroutine 15791 [runnable]:
2025-11-12T08:26:10.2664542Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).handleWsWrites(0xc002010788, 0xc003bcb340, 0xc0004740e0, 0xc0012de000)
2025-11-12T08:26:10.2664699Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:705 +0x191
2025-11-12T08:26:10.2664991Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).handleHTTPRequest in goroutine 16133
2025-11-12T08:26:10.2665139Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:545 +0xbf3
2025-11-12T08:26:10.2665143Z 
2025-11-12T08:26:10.2665211Z goroutine 16128 [select]:
2025-11-12T08:26:10.2665442Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).handleSubEvents(0xc002010788)
2025-11-12T08:26:10.2665593Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:3087 +0x8d3
2025-11-12T08:26:10.2665840Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).Start in goroutine 16125
2025-11-12T08:26:10.2665981Z 	D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:413 +0xeb
2025-11-12T08:26:10.2666131Z FAIL	github.com/nspcc-dev/neo-go/pkg/services/rpcsrv	900.267s
Originally created by @AnnaShaleva on GitHub (Nov 12, 2025). Go 1.25, Windows, firstly discovered in #4058 (https://github.com/nspcc-dev/neo-go/actions/runs/19288898247/job/55159821870?pr=4058): ``` 2025-11-12T08:26:10.2619390Z === RUN TestFilteredSubscriptions/execution_matching_state 2025-11-12T08:26:10.2619829Z logger.go:146: 2025-11-12T08:25:09.235Z INFO initial gas supply is not set or wrong, setting default value {"InitialGASSupply": "52000000"} 2025-11-12T08:26:10.2620226Z logger.go:146: 2025-11-12T08:25:09.235Z INFO MaxBlockSize is not set or wrong, setting default value {"MaxBlockSize": 262144} 2025-11-12T08:26:10.2620678Z logger.go:146: 2025-11-12T08:25:09.235Z INFO MaxBlockSystemFee is not set or wrong, setting default value {"MaxBlockSystemFee": 900000000000} 2025-11-12T08:26:10.2621223Z logger.go:146: 2025-11-12T08:25:09.235Z INFO Genesis MaxTraceableBlocks is not set or wrong, using default value {"Genesis MaxTraceableBlocks": 200000} 2025-11-12T08:26:10.2621701Z logger.go:146: 2025-11-12T08:25:09.235Z INFO MaxTransactionsPerBlock is not set or wrong, using default value {"MaxTransactionsPerBlock": 512} 2025-11-12T08:26:10.2622208Z logger.go:146: 2025-11-12T08:25:09.235Z INFO MaxValidUntilBlockIncrement is not set or wrong, using default value {"MaxValidUntilBlockIncrement": 5760} 2025-11-12T08:26:10.2622785Z logger.go:146: 2025-11-12T08:25:09.235Z INFO Genesis MaxValidUntilBlockIncrement is not set or wrong, using default value {"Genesis MaxValidUntilBlockIncrement": 5760} 2025-11-12T08:26:10.2623104Z logger.go:146: 2025-11-12T08:25:09.235Z INFO no storage version found! creating genesis block 2025-11-12T08:26:10.2623534Z logger.go:146: 2025-11-12T08:25:09.240Z INFO ExtensiblePoolSize is not set or wrong, using default value {"ExtensiblePoolSize": 20} 2025-11-12T08:26:10.2624005Z logger.go:146: 2025-11-12T08:25:09.240Z INFO BroadcastTxsBatchDelay is not set or wrong, using default value {"BroadcastTxsBatchDelay": "50ms"} 2025-11-12T08:26:10.2624423Z logger.go:146: 2025-11-12T08:25:09.242Z INFO SessionPoolSize is not set or wrong, setting default value {"SessionPoolSize": 20} 2025-11-12T08:26:10.2624886Z logger.go:146: 2025-11-12T08:25:09.242Z INFO MaxIteratorResultItems is not set or wrong, setting default value {"MaxIteratorResultItems": 100} 2025-11-12T08:26:10.2625310Z logger.go:146: 2025-11-12T08:25:09.242Z INFO MaxFindResultItems is not set or wrong, setting default value {"MaxFindResultItems": 100} 2025-11-12T08:26:10.2625707Z logger.go:146: 2025-11-12T08:25:09.242Z INFO MaxNEP11Tokens is not set or wrong, setting default value {"MaxNEP11Tokens": 100} 2025-11-12T08:26:10.2626153Z logger.go:146: 2025-11-12T08:25:09.242Z INFO MaxRequestBodyBytes is not set or wong, setting default value {"MaxRequestBodyBytes": 5242880} 2025-11-12T08:26:10.2626622Z logger.go:146: 2025-11-12T08:25:09.242Z INFO MaxRequestHeaderBytes is not set or wong, setting default value {"MaxRequestHeaderBytes": 1048576} 2025-11-12T08:26:10.2627098Z logger.go:146: 2025-11-12T08:25:09.242Z INFO MaxWebSocketClients is not set or wrong, setting default value {"MaxWebSocketClients": 64} 2025-11-12T08:26:10.2627513Z logger.go:146: 2025-11-12T08:25:09.243Z INFO MaxWebSocketFeeds is not set or wrong, setting default value {"MaxWebSocketFeeds": 16} 2025-11-12T08:26:10.2627796Z logger.go:146: 2025-11-12T08:25:09.243Z INFO starting rpc-server {"endpoint": "127.0.0.1:0"} 2025-11-12T08:26:10.2628171Z logger.go:146: 2025-11-12T08:25:09.486Z DEBUG processing rpc request {"method": "subscribe", "params": "[block_added]"} 2025-11-12T08:26:10.2628585Z logger.go:146: 2025-11-12T08:25:09.549Z DEBUG processing rpc request {"method": "subscribe", "params": "[transaction_executed ]"} 2025-11-12T08:26:10.2628970Z logger.go:146: 2025-11-12T08:25:09.706Z DEBUG done processing headers {"headerIndex": 1, "blockHeight": 0, "took": "0s"} 2025-11-12T08:26:10.2629378Z logger.go:146: 2025-11-12T08:25:09.706Z DEBUG done processing headers {"headerIndex": 2, "blockHeight": 1, "took": "0s"} 2025-11-12T08:26:10.2629785Z logger.go:146: 2025-11-12T08:25:09.721Z DEBUG done processing headers {"headerIndex": 3, "blockHeight": 2, "took": "15.0456ms"} 2025-11-12T08:26:10.2630300Z logger.go:146: 2025-11-12T08:25:09.832Z DEBUG done processing headers {"headerIndex": 4, "blockHeight": 3, "took": "532.2µs"} 2025-11-12T08:26:10.2630682Z logger.go:146: 2025-11-12T08:25:09.832Z DEBUG done processing headers {"headerIndex": 5, "blockHeight": 4, "took": "0s"} 2025-11-12T08:26:10.2631057Z logger.go:146: 2025-11-12T08:25:09.832Z DEBUG done processing headers {"headerIndex": 6, "blockHeight": 5, "took": "0s"} 2025-11-12T08:26:10.2631470Z logger.go:146: 2025-11-12T08:25:09.847Z DEBUG done processing headers {"headerIndex": 7, "blockHeight": 6, "took": "0s"} 2025-11-12T08:26:10.2631869Z logger.go:146: 2025-11-12T08:25:09.926Z DEBUG done processing headers {"headerIndex": 8, "blockHeight": 7, "took": "79.0171ms"} 2025-11-12T08:26:10.2632249Z logger.go:146: 2025-11-12T08:25:09.926Z DEBUG done processing headers {"headerIndex": 9, "blockHeight": 8, "took": "0s"} 2025-11-12T08:26:10.2632630Z logger.go:146: 2025-11-12T08:25:09.926Z DEBUG done processing headers {"headerIndex": 10, "blockHeight": 9, "took": "0s"} 2025-11-12T08:26:10.2633037Z logger.go:146: 2025-11-12T08:25:10.099Z DEBUG done processing headers {"headerIndex": 11, "blockHeight": 10, "took": "172.7512ms"} 2025-11-12T08:26:10.2633423Z logger.go:146: 2025-11-12T08:25:10.099Z DEBUG done processing headers {"headerIndex": 12, "blockHeight": 11, "took": "0s"} 2025-11-12T08:26:10.2633837Z logger.go:146: 2025-11-12T08:25:10.114Z DEBUG done processing headers {"headerIndex": 13, "blockHeight": 12, "took": "0s"} 2025-11-12T08:26:10.2634271Z logger.go:146: 2025-11-12T08:25:10.303Z INFO persisted to disk {"blocks": 12, "keys": 498, "headerHeight": 13, "blockHeight": 12, "took": "0s"} 2025-11-12T08:26:10.2634667Z logger.go:146: 2025-11-12T08:25:10.303Z DEBUG done processing headers {"headerIndex": 14, "blockHeight": 13, "took": "0s"} 2025-11-12T08:26:10.2635043Z logger.go:146: 2025-11-12T08:25:10.303Z DEBUG done processing headers {"headerIndex": 15, "blockHeight": 14, "took": "0s"} 2025-11-12T08:26:10.2635423Z logger.go:146: 2025-11-12T08:25:10.303Z DEBUG done processing headers {"headerIndex": 16, "blockHeight": 15, "took": "0s"} 2025-11-12T08:26:10.2635798Z logger.go:146: 2025-11-12T08:25:10.317Z DEBUG done processing headers {"headerIndex": 17, "blockHeight": 16, "took": "0s"} 2025-11-12T08:26:10.2636177Z logger.go:146: 2025-11-12T08:25:10.459Z DEBUG done processing headers {"headerIndex": 18, "blockHeight": 17, "took": "0s"} 2025-11-12T08:26:10.2636562Z logger.go:146: 2025-11-12T08:25:10.459Z DEBUG done processing headers {"headerIndex": 19, "blockHeight": 18, "took": "0s"} 2025-11-12T08:26:10.2636939Z logger.go:146: 2025-11-12T08:25:10.474Z DEBUG done processing headers {"headerIndex": 20, "blockHeight": 19, "took": "0s"} 2025-11-12T08:26:10.2637361Z logger.go:146: 2025-11-12T08:25:10.474Z DEBUG done processing headers {"headerIndex": 21, "blockHeight": 20, "took": "0s"} 2025-11-12T08:26:10.2637766Z logger.go:146: 2025-11-12T08:25:10.600Z DEBUG done processing headers {"headerIndex": 22, "blockHeight": 21, "took": "126.1322ms"} 2025-11-12T08:26:10.2638144Z logger.go:146: 2025-11-12T08:25:10.615Z DEBUG done processing headers {"headerIndex": 23, "blockHeight": 22, "took": "0s"} 2025-11-12T08:26:10.2638863Z logger.go:146: 2025-11-12T08:25:10.615Z WARN contract invocation failed {"tx": "82279bfe9bada282ca0f8cb8e0bb124b921af36f00c69a518320322c6f4fef60", "block": 23, "error": "at instruction 0 (ABORT): ABORT"} 2025-11-12T08:26:10.2639211Z logger.go:146: 2025-11-12T08:25:10.881Z DEBUG processing rpc request {"method": "unsubscribe", "params": "[1]"} 2025-11-12T08:26:10.2639556Z logger.go:146: 2025-11-12T08:25:11.257Z DEBUG processing rpc request {"method": "unsubscribe", "params": "[0]"} 2025-11-12T08:26:10.2640041Z logger.go:146: 2025-11-12T08:25:11.452Z INFO persisted to disk {"blocks": 11, "keys": 848, "headerHeight": 23, "blockHeight": 23, "took": "1.5207ms"} 2025-11-12T08:26:10.2640127Z panic: test timed out after 15m0s 2025-11-12T08:26:10.2640207Z running tests: 2025-11-12T08:26:10.2640306Z TestFilteredSubscriptions (33s) 2025-11-12T08:26:10.2640477Z TestFilteredSubscriptions/execution_matching_state (2s) 2025-11-12T08:26:10.2640487Z 2025-11-12T08:26:10.2640562Z goroutine 15754 [running]: 2025-11-12T08:26:10.2640641Z testing.(*M).startAlarm.func1() 2025-11-12T08:26:10.2640867Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2682 +0x605 2025-11-12T08:26:10.2641194Z created by time.goFunc 2025-11-12T08:26:10.2641388Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/time/sleep.go:215 +0x45 2025-11-12T08:26:10.2641399Z 2025-11-12T08:26:10.2641473Z goroutine 1 [chan receive]: 2025-11-12T08:26:10.2641610Z testing.(*T).Run(0xc000086a80, {0x1417011d3, 0x19}, 0x1417f44f0) 2025-11-12T08:26:10.2641824Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2005 +0xa08 2025-11-12T08:26:10.2641912Z testing.runTests.func1(0xc000086a80) 2025-11-12T08:26:10.2642114Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2477 +0x86 2025-11-12T08:26:10.2642210Z testing.tRunner(0xc000086a80, 0xc00041fae0) 2025-11-12T08:26:10.2642407Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1934 +0x1c4 2025-11-12T08:26:10.2642653Z testing.runTests(0xc0000d63a8, {0x14141d7c0, 0x39, 0x39}, {0x1?, 0x140080fe5?, 0x1422fdde0?}) 2025-11-12T08:26:10.2642898Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2475 +0x96d 2025-11-12T08:26:10.2642983Z testing.(*M).Run(0xc000383d60) 2025-11-12T08:26:10.2643183Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2337 +0xed5 2025-11-12T08:26:10.2643244Z main.main() 2025-11-12T08:26:10.2643323Z _testmain.go:159 +0x165 2025-11-12T08:26:10.2643330Z 2025-11-12T08:26:10.2643413Z goroutine 16125 [semacquire]: 2025-11-12T08:26:10.2643524Z internal/poll.runtime_Semacquire(0xc002010c88?) 2025-11-12T08:26:10.2643710Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/runtime/sema.go:76 +0x25 2025-11-12T08:26:10.2643802Z internal/poll.(*FD).Close(0xc002010c88) 2025-11-12T08:26:10.2644031Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:485 +0xdf 2025-11-12T08:26:10.2644123Z net.(*netFD).Close(0xc002010c88) 2025-11-12T08:26:10.2644304Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/fd_posix.go:50 +0x3e 2025-11-12T08:26:10.2644381Z net.(*TCPListener).close(...) 2025-11-12T08:26:10.2644576Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock_posix.go:167 2025-11-12T08:26:10.2644664Z net.(*TCPListener).Close(0xc000899140) 2025-11-12T08:26:10.2644843Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock.go:393 +0x6b 2025-11-12T08:26:10.2644954Z net/http/httptest.(*Server).Close(0xc002be5260) 2025-11-12T08:26:10.2645224Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/httptest/server.go:206 +0xb0 2025-11-12T08:26:10.2645313Z testing.(*common).Cleanup.func1() 2025-11-12T08:26:10.2645514Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1308 +0x169 2025-11-12T08:26:10.2645618Z testing.(*common).runCleanup(0xc00107f6c0, 0x0) 2025-11-12T08:26:10.2645821Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1572 +0x226 2025-11-12T08:26:10.2645895Z testing.tRunner.func2() 2025-11-12T08:26:10.2646092Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1928 +0x4d 2025-11-12T08:26:10.2646191Z testing.tRunner(0xc00107f6c0, 0xc000385f50) 2025-11-12T08:26:10.2646386Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1940 +0x1f7 2025-11-12T08:26:10.2646495Z created by testing.(*T).Run in goroutine 14364 2025-11-12T08:26:10.2646690Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1997 +0x9dd 2025-11-12T08:26:10.2646694Z 2025-11-12T08:26:10.2646765Z goroutine 16132 [select]: 2025-11-12T08:26:10.2647029Z github.com/nspcc-dev/neo-go/pkg/core/mempool.(*Pool).notificationDispatcher(...) 2025-11-12T08:26:10.2647181Z D:/a/neo-go/neo-go/pkg/core/mempool/subscriptions.go:55 2025-11-12T08:26:10.2647464Z created by github.com/nspcc-dev/neo-go/pkg/core/mempool.(*Pool).RunSubscriptions in goroutine 16127 2025-11-12T08:26:10.2647623Z D:/a/neo-go/neo-go/pkg/core/mempool/subscriptions.go:13 +0xda 2025-11-12T08:26:10.2647629Z 2025-11-12T08:26:10.2647698Z goroutine 16129 [IO wait]: 2025-11-12T08:26:10.2647810Z internal/poll.runtime_pollWait(0x48a0ea00, 0x72) 2025-11-12T08:26:10.2648013Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/runtime/netpoll.go:351 +0x85 2025-11-12T08:26:10.2648173Z internal/poll.(*pollDesc).wait(0xc002010bb0, 0x72, 0x0) 2025-11-12T08:26:10.2648416Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_poll_runtime.go:84 +0xb1 2025-11-12T08:26:10.2648497Z internal/poll.waitIO(0xc002010a20) 2025-11-12T08:26:10.2648731Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:187 +0x158 2025-11-12T08:26:10.2648835Z internal/poll.execIO(0xc002010a20, 0xc00005b8d8) 2025-11-12T08:26:10.2649061Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:240 +0x2d2 2025-11-12T08:26:10.2649281Z internal/poll.(*FD).acceptOne(0xc002010a08, 0x63c, {0xc00109bb30, 0x2, 0x2}, 0xc002010a20) 2025-11-12T08:26:10.2649502Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1059 +0xee 2025-11-12T08:26:10.2649620Z internal/poll.(*FD).Accept(0xc002010a08, 0xc00005bb50) 2025-11-12T08:26:10.2649886Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1093 +0x3ca 2025-11-12T08:26:10.2649968Z net.(*netFD).accept(0xc002010a08) 2025-11-12T08:26:10.2650172Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/fd_windows.go:202 +0x85 2025-11-12T08:26:10.2650257Z net.(*TCPListener).accept(0xc000899100) 2025-11-12T08:26:10.2650460Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock_posix.go:159 +0x4e 2025-11-12T08:26:10.2650552Z net.(*TCPListener).Accept(0xc000899100) 2025-11-12T08:26:10.2650734Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock.go:380 +0x65 2025-11-12T08:26:10.2650880Z net/http.(*Server).Serve(0xc001324900, {0x1419d53c0, 0xc000899100}) 2025-11-12T08:26:10.2651081Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/server.go:3463 +0x610 2025-11-12T08:26:10.2651300Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).Start.func1(0xc001324900) 2025-11-12T08:26:10.2651450Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:426 +0x79 2025-11-12T08:26:10.2651700Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).Start in goroutine 16125 2025-11-12T08:26:10.2651847Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:425 +0x1ac 2025-11-12T08:26:10.2651852Z 2025-11-12T08:26:10.2651926Z goroutine 16131 [select]: 2025-11-12T08:26:10.2652159Z github.com/nspcc-dev/neo-go/pkg/core.(*Blockchain).notificationDispatcher(0xc001fac008) 2025-11-12T08:26:10.2652334Z D:/a/neo-go/neo-go/pkg/core/blockchain.go:1702 +0x54f 2025-11-12T08:26:10.2652547Z created by github.com/nspcc-dev/neo-go/pkg/core.(*Blockchain).Run in goroutine 16127 2025-11-12T08:26:10.2652673Z D:/a/neo-go/neo-go/pkg/core/blockchain.go:1348 +0x14b 2025-11-12T08:26:10.2652678Z 2025-11-12T08:26:10.2652754Z goroutine 16130 [runnable]: 2025-11-12T08:26:10.2652890Z internal/poll.runtime_pollWaitCanceled(0x48a4b400, 0x72) 2025-11-12T08:26:10.2653096Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/runtime/netpoll.go:367 +0x2e 2025-11-12T08:26:10.2653198Z internal/poll.(*pollDesc).waitCanceled(...) 2025-11-12T08:26:10.2653425Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_poll_runtime.go:100 2025-11-12T08:26:10.2653513Z internal/poll.cancelIO(0xc002010ca0) 2025-11-12T08:26:10.2653739Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:210 +0x145 2025-11-12T08:26:10.2653845Z internal/poll.execIO(0xc002010ca0, 0xc0003059a0) 2025-11-12T08:26:10.2654109Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:243 +0x2f2 2025-11-12T08:26:10.2654326Z internal/poll.(*FD).acceptOne(0xc002010c88, 0x688, {0xc00109bd10, 0x2, 0x2}, 0xc002010ca0) 2025-11-12T08:26:10.2654554Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1059 +0xee 2025-11-12T08:26:10.2654676Z internal/poll.(*FD).Accept(0xc002010c88, 0xc000305c18) 2025-11-12T08:26:10.2654902Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/internal/poll/fd_windows.go:1093 +0x3ca 2025-11-12T08:26:10.2654984Z net.(*netFD).accept(0xc002010c88) 2025-11-12T08:26:10.2655183Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/fd_windows.go:202 +0x85 2025-11-12T08:26:10.2655304Z net.(*TCPListener).accept(0xc000899140) 2025-11-12T08:26:10.2655511Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock_posix.go:159 +0x4e 2025-11-12T08:26:10.2655591Z net.(*TCPListener).Accept(0xc000899140) 2025-11-12T08:26:10.2655773Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/tcpsock.go:380 +0x65 2025-11-12T08:26:10.2655928Z net/http.(*Server).Serve(0xc001324a00, {0x1419d53c0, 0xc000899140}) 2025-11-12T08:26:10.2656126Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/server.go:3463 +0x610 2025-11-12T08:26:10.2656230Z net/http/httptest.(*Server).goServe.func1() 2025-11-12T08:26:10.2656454Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/httptest/server.go:311 +0xb3 2025-11-12T08:26:10.2656616Z created by net/http/httptest.(*Server).goServe in goroutine 16125 2025-11-12T08:26:10.2656844Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/net/http/httptest/server.go:309 +0xa5 2025-11-12T08:26:10.2656849Z 2025-11-12T08:26:10.2656957Z goroutine 4886 [runnable]: 2025-11-12T08:26:10.2657272Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*WSClient).notifySubscribers(0xc000b281c0, {0xd4?, {0x0?, 0x0?}}) 2025-11-12T08:26:10.2657413Z D:/a/neo-go/neo-go/pkg/rpcclient/wsclient.go:754 +0x167 2025-11-12T08:26:10.2657609Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc000b281c0) 2025-11-12T08:26:10.2657741Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:64 +0x47 2025-11-12T08:26:10.2657954Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 4614 2025-11-12T08:26:10.2658075Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd 2025-11-12T08:26:10.2658084Z 2025-11-12T08:26:10.2658154Z goroutine 16127 [select]: 2025-11-12T08:26:10.2658321Z github.com/nspcc-dev/neo-go/pkg/core.(*Blockchain).Run(0xc001fac008) 2025-11-12T08:26:10.2658458Z D:/a/neo-go/neo-go/pkg/core/blockchain.go:1353 +0x2b1 2025-11-12T08:26:10.2658789Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.getUnitTestChainWithCustomConfig in goroutine 16125 2025-11-12T08:26:10.2658975Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server_helper_test.go:85 +0x714 2025-11-12T08:26:10.2658980Z 2025-11-12T08:26:10.2659066Z goroutine 5186 [runnable]: 2025-11-12T08:26:10.2659364Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*WSClient).notifySubscribers(0xc0009fe8c0, {0x0?, {0x0?, 0x0?}}) 2025-11-12T08:26:10.2659545Z D:/a/neo-go/neo-go/pkg/rpcclient/wsclient.go:743 +0x609 2025-11-12T08:26:10.2659741Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc0009fe8c0) 2025-11-12T08:26:10.2659862Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:64 +0x47 2025-11-12T08:26:10.2660080Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 5171 2025-11-12T08:26:10.2660201Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd 2025-11-12T08:26:10.2660205Z 2025-11-12T08:26:10.2660285Z goroutine 14364 [chan receive]: 2025-11-12T08:26:10.2660424Z testing.(*T).Run(0xc002622540, {0x1416ffc10, 0x18}, 0xc000385f50) 2025-11-12T08:26:10.2660632Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:2005 +0xa08 2025-11-12T08:26:10.2660883Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.TestFilteredSubscriptions(0xc002622540) 2025-11-12T08:26:10.2661070Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/subscription_test.go:393 +0xbd6 2025-11-12T08:26:10.2661197Z testing.tRunner(0xc002622540, 0x1417f44f0) 2025-11-12T08:26:10.2661406Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1934 +0x1c4 2025-11-12T08:26:10.2661495Z created by testing.(*T).Run in goroutine 1 2025-11-12T08:26:10.2661698Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/testing/testing.go:1997 +0x9dd 2025-11-12T08:26:10.2661703Z 2025-11-12T08:26:10.2661773Z goroutine 5250 [runnable]: 2025-11-12T08:26:10.2661856Z context.(*cancelCtx).Done(0xc0014add10) 2025-11-12T08:26:10.2662062Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/context/context.go:448 +0x1aa 2025-11-12T08:26:10.2662261Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc000b28e00) 2025-11-12T08:26:10.2662417Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:55 +0x70 2025-11-12T08:26:10.2662632Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 5244 2025-11-12T08:26:10.2662752Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd 2025-11-12T08:26:10.2662759Z 2025-11-12T08:26:10.2662836Z goroutine 6954 [runnable]: 2025-11-12T08:26:10.2662916Z sync/atomic.(*Value).Load(0xc00260cf18) 2025-11-12T08:26:10.2663117Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/sync/atomic/value.go:36 +0x93 2025-11-12T08:26:10.2663204Z context.(*cancelCtx).Done(0xc00260cf00) 2025-11-12T08:26:10.2663403Z C:/hostedtoolcache/windows/go/1.25.3/x64/src/context/context.go:449 +0x4d 2025-11-12T08:26:10.2663595Z github.com/nspcc-dev/neo-go/pkg/rpcclient.(*Internal).eventLoop(0xc00107efc0) 2025-11-12T08:26:10.2663719Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:55 +0x70 2025-11-12T08:26:10.2663964Z created by github.com/nspcc-dev/neo-go/pkg/rpcclient.NewInternal in goroutine 6983 2025-11-12T08:26:10.2664093Z D:/a/neo-go/neo-go/pkg/rpcclient/local.go:45 +0x3bd 2025-11-12T08:26:10.2664097Z 2025-11-12T08:26:10.2664176Z goroutine 15791 [runnable]: 2025-11-12T08:26:10.2664542Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).handleWsWrites(0xc002010788, 0xc003bcb340, 0xc0004740e0, 0xc0012de000) 2025-11-12T08:26:10.2664699Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:705 +0x191 2025-11-12T08:26:10.2664991Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).handleHTTPRequest in goroutine 16133 2025-11-12T08:26:10.2665139Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:545 +0xbf3 2025-11-12T08:26:10.2665143Z 2025-11-12T08:26:10.2665211Z goroutine 16128 [select]: 2025-11-12T08:26:10.2665442Z github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).handleSubEvents(0xc002010788) 2025-11-12T08:26:10.2665593Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:3087 +0x8d3 2025-11-12T08:26:10.2665840Z created by github.com/nspcc-dev/neo-go/pkg/services/rpcsrv.(*Server).Start in goroutine 16125 2025-11-12T08:26:10.2665981Z D:/a/neo-go/neo-go/pkg/services/rpcsrv/server.go:413 +0xeb 2025-11-12T08:26:10.2666131Z FAIL github.com/nspcc-dev/neo-go/pkg/services/rpcsrv 900.267s ```
Sign in to join this conversation.
No milestone
No project
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference
nspcc-dev/neo-go#1575
No description provided.