Test flake in mem pool prune #357
Description
2025-10-22T15:09:11.1689670Z 2025-10-22T14:49:12.205Z WARN request/pool.go:200 NextRequests is called when batching is not enabled {"t": "TestRestartPool", "id": 0} 2025-10-22T15:09:11.1690515Z github.com/hyperledger/fabric-x-orderer/request.(*Pool).NextRequests 2025-10-22T15:09:11.1691136Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/pool.go:200 2025-10-22T15:09:11.1691705Z github.com/hyperledger/fabric-x-orderer/request.TestRestartPool 2025-10-22T15:09:11.1692304Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/pool_test.go:180 2025-10-22T15:09:11.1692757Z testing.tRunner 2025-10-22T15:09:11.1693117Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:1792 2025-10-22T15:09:11.1693864Z 2025-10-22T14:49:12.205Z WARN request/pool.go:200 NextRequests is called when batching is not enabled {"t": "TestRestartPool", "id": 0} 2025-10-22T15:09:11.1694618Z github.com/hyperledger/fabric-x-orderer/request.(*Pool).NextRequests 2025-10-22T15:09:11.1695239Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/pool.go:200 2025-10-22T15:09:11.1695797Z github.com/hyperledger/fabric-x-orderer/request.TestRestartPool 2025-10-22T15:09:11.1696860Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/pool_test.go:184 2025-10-22T15:09:11.1697319Z testing.tRunner 2025-10-22T15:09:11.1697683Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:1792 2025-10-22T15:09:11.1698087Z panic: test timed out after 20m0s 2025-10-22T15:09:11.1698369Z running tests: 2025-10-22T15:09:11.1698625Z TestBasicPrune (19m56s) 2025-10-22T15:09:11.1698790Z 2025-10-22T15:09:11.1698898Z goroutine 27 [running]: 2025-10-22T15:09:11.1699159Z testing.(*M).startAlarm.func1() 2025-10-22T15:09:11.1699620Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:2484 +0x605 2025-10-22T15:09:11.1700013Z created by time.goFunc 2025-10-22T15:09:11.1700322Z /opt/hostedtoolcache/go/1.24.3/x64/src/time/sleep.go:215 +0x45 2025-10-22T15:09:11.1700562Z 2025-10-22T15:09:11.1700671Z goroutine 1 [chan receive, 19 minutes]: 2025-10-22T15:09:11.1700995Z testing.(*T).Run(0xc00032a8c0, {0x1406d66, 0xe}, 0x1453538) 2025-10-22T15:09:11.1701430Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:1859 +0x91e 2025-10-22T15:09:11.1701789Z testing.runTests.func1(0xc00032a8c0) 2025-10-22T15:09:11.1702157Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:2279 +0x86 2025-10-22T15:09:11.1702516Z testing.tRunner(0xc00032a8c0, 0xc000051ae0) 2025-10-22T15:09:11.1702901Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:1792 +0x226 2025-10-22T15:09:11.1703541Z testing.runTests(0xc0001ed728, {0x1d48040, 0xb, 0xb}, {0x4?, 0x0?, 0x1d72480?}) 2025-10-22T15:09:11.1704041Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:2277 +0x96d 2025-10-22T15:09:11.1704382Z testing.(*M).Run(0xc000194000) 2025-10-22T15:09:11.1704726Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:2142 +0xeeb 2025-10-22T15:09:11.1705054Z main.main() 2025-10-22T15:09:11.1705261Z _testmain.go:69 +0x165 2025-10-22T15:09:11.1705388Z 2025-10-22T15:09:11.1705502Z goroutine 24 [sync.Cond.Wait, 19 minutes]: 2025-10-22T15:09:11.1705798Z sync.runtime_notifyListWait(0xc000300b18, 0x0) 2025-10-22T15:09:11.1706380Z /opt/hostedtoolcache/go/1.24.3/x64/src/runtime/sema.go:597 +0x159 2025-10-22T15:09:11.1706723Z sync.(*Cond).Wait(0xc000300b08) 2025-10-22T15:09:11.1707046Z /opt/hostedtoolcache/go/1.24.3/x64/src/sync/cond.go:71 +0x6c 2025-10-22T15:09:11.1707595Z github.com/hyperledger/fabric-x-orderer/request.(*BatchStore).Fetch(0xc000300a80, {0x15a3658, 0xc0005da150}) 2025-10-22T15:09:11.1708255Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/batchstore.go:162 +0x29f 2025-10-22T15:09:11.1708887Z github.com/hyperledger/fabric-x-orderer/request.(*Pool).NextRequests(0xc0000d5b80, {0x15a3658, 0xc0005da150}) 2025-10-22T15:09:11.1709512Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/pool.go:204 +0x15c 2025-10-22T15:09:11.1710021Z github.com/hyperledger/fabric-x-orderer/request.TestBasicPrune(0xc000103180) 2025-10-22T15:09:11.1710570Z /home/runner/work/fabric-x-orderer/fabric-x-orderer/request/pool_test.go:426 +0xa5a 2025-10-22T15:09:11.1710982Z testing.tRunner(0xc000103180, 0x1453538) 2025-10-22T15:09:11.1711356Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:1792 +0x226 2025-10-22T15:09:11.1711751Z created by testing.(*T).Run in goroutine 1 2025-10-22T15:09:11.1712128Z /opt/hostedtoolcache/go/1.24.3/x64/src/testing/testing.go:1851 +0x8f3 2025-10-22T15:09:11.1712542Z FAIL github.com/hyperledger/fabric-x-orderer/request 1200.290s 2025-10-22T15:09:11.1712979Z ? github.com/hyperledger/fabric-x-orderer/request/mocks [no test files] 2025-10-22T15:09:11.1713393Z ok github.com/hyperledger/fabric-x-orderer/test 282.602s 2025-10-22T15:09:11.1713796Z ? github.com/hyperledger/fabric-x-orderer/test/mocks [no test files] 2025-10-22T15:09:11.1714224Z ? github.com/hyperledger/fabric-x-orderer/testutil [no test files] 2025-10-22T15:09:11.1714671Z ? github.com/hyperledger/fabric-x-orderer/testutil/client [no test files] 2025-10-22T15:09:11.1715144Z ? github.com/hyperledger/fabric-x-orderer/testutil/fabric [no test files] 2025-10-22T15:09:11.1715705Z ok github.com/hyperledger/fabric-x-orderer/testutil/tlsgen 2.034s 2025-10-22T15:09:11.1716339Z ? github.com/hyperledger/fabric-x-orderer/testutil/tx [no test files] 2025-10-22T15:09:11.1716671Z FAIL