Intermittent ipc_test failure: timing race in IPC event loop #36345

issue darosior opened this issue on September 26, 2026
  1. darosior commented at 4:22 PM on September 26, 2026: member

    Triggered in ASAN job on #35793.

    <details> <summary>CI logs snippet</summary>

    ipc/test/ipc_tests.cpp(202): Entering test suite "ipc_tests"
    ipc/test/ipc_tests.cpp(235): Entering test case "check_spawned_test"
    2026-09-25T15:13:01.982321Z [unknown] [test/util/random.cpp:48] [SeedRandomStateForTest] Setting random seed for current tests to RANDOM_CTX_SEED=061689c41dab4832e1572a4912d45c6c726f2ec9f4da6c63c46832f70bfd2c0f
    2026-09-25T15:13:01.985588Z [test] [init/common.cpp:158] [LogPackageVersion] Bitcoin Core version v32.99.0-0cf16e49e542 (release build)
    2026-09-25T15:13:01.985774Z [test] [init/common.cpp:104] [SetLoggingCategories] Log output may contain privacy-sensitive information. Be cautious when sharing logs.
    2026-09-25T15:13:01.986206Z [test] [kernel/context.cpp:20] [operator()] Using the 'x86_shani(1way;2way)' SHA256 implementation
    2026-09-25T15:13:01.986267Z [test] [random.cpp:110] [ReportHardwareRand] Using RdSeed as an additional entropy source
    2026-09-25T15:13:01.986298Z [test] [random.cpp:113] [ReportHardwareRand] Using RdRand as an additional entropy source
    ipc/test/ipc_tests.cpp(235): Leaving test case "check_spawned_test"; testing time: 6228us
    ipc/test/ipc_tests.cpp(203): Entering test case "ipc_tests"
    2026-09-25T15:13:01.988549Z [test] [test/util/random.cpp:48] [SeedRandomStateForTest] Setting random seed for current tests to RANDOM_CTX_SEED=061689c41dab4832e1572a4912d45c6c726f2ec9f4da6c63c46832f70bfd2c0f
    2026-09-25T15:13:01.990390Z [test] [init/common.cpp:158] [LogPackageVersion] Bitcoin Core version v32.99.0-0cf16e49e542 (release build)
    2026-09-25T15:13:01.990548Z [test] [init/common.cpp:104] [SetLoggingCategories] Log output may contain privacy-sensitive information. Be cautious when sharing logs.
    2026-09-25T15:13:01.992735Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} Creating mp::ProxyClientBase<gen::FooInterface, FooImplementation> 0x7bd921418020
    2026-09-25T15:13:01.992792Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} Creating mp::ProxyServerBase<gen::FooInterface, FooImplementation> 0x7be9213f8120
    2026-09-25T15:13:01.993083Z [test] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client first request from current thread, constructing waiter
    2026-09-25T15:13:01.993170Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.add$Params
    2026-09-25T15:13:01.993236Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (a = 1, b = 2)
    2026-09-25T15:13:01.993342Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#1](/bitcoin-bitcoin/1/) FooInterface.add$Params
    2026-09-25T15:13:01.993392Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (a = 1, b = 2)
    2026-09-25T15:13:01.993463Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#1](/bitcoin-bitcoin/1/) FooInterface.add$Results
    2026-09-25T15:13:01.993511Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = 3)
    2026-09-25T15:13:01.993611Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.add$Results
    2026-09-25T15:13:01.993665Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = 3)
    2026-09-25T15:13:01.993756Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.passOutPoint$Params
    2026-09-25T15:13:01.993816Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (arg = "d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\000\\000")
    2026-09-25T15:13:01.993961Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#2](/bitcoin-bitcoin/2/) FooInterface.passOutPoint$Params
    2026-09-25T15:13:01.994018Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (arg = "d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\000\\000")
    2026-09-25T15:13:01.994088Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#2](/bitcoin-bitcoin/2/) FooInterface.passOutPoint$Results
    2026-09-25T15:13:01.994145Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = "d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\000\\000")
    2026-09-25T15:13:01.994246Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.passOutPoint$Results
    2026-09-25T15:13:01.994301Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = "d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\000\\000")
    2026-09-25T15:13:01.994416Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.passUniValue$Params
    2026-09-25T15:13:01.994470Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (arg = "{\\"i\\":1,\\"s\\":\\"two\\"}")
    2026-09-25T15:13:01.994566Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#3](/bitcoin-bitcoin/3/) FooInterface.passUniValue$Params
    2026-09-25T15:13:01.994617Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (arg = "{\\"i\\":1,\\"s\\":\\"two\\"}")
    2026-09-25T15:13:01.994713Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#3](/bitcoin-bitcoin/3/) FooInterface.passUniValue$Results
    2026-09-25T15:13:01.994775Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = "{\\"i\\":1,\\"s\\":\\"two\\"}")
    2026-09-25T15:13:01.994874Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.passUniValue$Results
    2026-09-25T15:13:01.994932Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = "{\\"i\\":1,\\"s\\":\\"two\\"}")
    2026-09-25T15:13:01.995069Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.passTransaction$Params
    2026-09-25T15:13:01.995123Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (arg = "\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\...
    2026-09-25T15:13:01.995234Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#4](/bitcoin-bitcoin/4/) FooInterface.passTransaction$Params
    2026-09-25T15:13:01.995298Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (arg = "\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\...
    2026-09-25T15:13:01.995381Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#4](/bitcoin-bitcoin/4/) FooInterface.passTransaction$Results
    2026-09-25T15:13:01.995446Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = "\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\00...
    2026-09-25T15:13:01.995540Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.passTransaction$Results
    2026-09-25T15:13:01.995593Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = "\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\00...
    2026-09-25T15:13:01.995717Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.passTransactions$Params
    2026-09-25T15:13:01.995781Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (arg = ["\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\...
    2026-09-25T15:13:01.995923Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#5](/bitcoin-bitcoin/5/) FooInterface.passTransactions$Params
    2026-09-25T15:13:01.996000Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (arg = ["\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\000\\...
    2026-09-25T15:13:01.996087Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#5](/bitcoin-bitcoin/5/) FooInterface.passTransactions$Results
    2026-09-25T15:13:01.996156Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = ["\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\0...
    2026-09-25T15:13:01.996258Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.passTransactions$Results
    2026-09-25T15:13:01.996321Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = ["\\002\\000\\000\\000\\001d\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\000\\310\\0...
    2026-09-25T15:13:01.996425Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.passVectorChar$Params
    2026-09-25T15:13:01.996483Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (arg = "Hello")
    2026-09-25T15:13:01.996580Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#6](/bitcoin-bitcoin/6/) FooInterface.passVectorChar$Params
    2026-09-25T15:13:01.996628Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (arg = "Hello")
    2026-09-25T15:13:01.996709Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#6](/bitcoin-bitcoin/6/) FooInterface.passVectorChar$Results
    2026-09-25T15:13:01.996766Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = "Hello")
    2026-09-25T15:13:01.996869Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.passVectorChar$Results
    2026-09-25T15:13:01.996922Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = "Hello")
    2026-09-25T15:13:01.997008Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client send FooInterface.passScript$Params
    2026-09-25T15:13:01.997057Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: send data: (arg = "[")
    2026-09-25T15:13:01.997139Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server recv request  [#7](/bitcoin-bitcoin/7/) FooInterface.passScript$Params
    2026-09-25T15:13:01.997195Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} request data: (arg = "[")
    2026-09-25T15:13:01.997265Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server send response [#7](/bitcoin-bitcoin/7/) FooInterface.passScript$Results
    2026-09-25T15:13:01.997338Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} response data: (result = "[")
    2026-09-25T15:13:01.997423Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client recv FooInterface.passScript$Results
    2026-09-25T15:13:01.997471Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: recv data: (result = "[")
    2026-09-25T15:13:01.997523Z [test] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} IPC client destroy mp::ProxyClient<gen::FooInterface>
    2026-09-25T15:13:01.997562Z [test] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} Cleaning up mp::ProxyClientBase<gen::FooInterface, FooImplementation> 0x7bd921418020
    2026-09-25T15:13:01.997963Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} IPC server destroy mp::ProxyServer<gen::FooInterface>
    2026-09-25T15:13:01.998007Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} Cleaning up mp::ProxyServerBase<gen::FooInterface, FooImplementation> 0x7be9213f8120
    2026-09-25T15:13:01.998093Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} Destroying mp::ProxyServerBase<gen::FooInterface, FooImplementation> 0x7be9213f8120
    2026-09-25T15:13:01.998150Z [test] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11657} Destroying mp::ProxyClientBase<gen::FooInterface, FooImplementation> 0x7bd921418020
    2026-09-25T15:13:01.998339Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} EventLoop::loop done, cancelling event listeners.
    2026-09-25T15:13:01.998391Z [unknown] [ipc/test/ipc_tests.cpp:68] [operator()] LOG0: {IpcPipeTest-11657/b-test-11658} EventLoop::loop bye.
    2026-09-25T15:13:02.002132Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} Creating mp::ProxyServerBase<ipc::capnp::messages::Init, interfaces::Init> 0x7be9213f8e20
    2026-09-25T15:13:02.002173Z [unknown] [ipc/capnp/protocol.cpp:53] [IpcLogFn] ipc: {IpcSocketPairTest-11657/b-test-11660} IPC server: socket connected.
    2026-09-25T15:13:02.002249Z [test] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcPipeTest-11657/b-test-11657} Creating mp::ProxyClientBase<ipc::capnp::messages::Init, interfaces::Init> 0x7bd921415c40
    2026-09-25T15:13:02.002415Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcPipeTest-11657/b-test-11657} IPC client send Init.construct$Params
    2026-09-25T15:13:02.002450Z [unknown] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] send data: (threadMap = <external capability>)
    2026-09-25T15:13:02.002550Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} IPC server recv request  [#8](/bitcoin-bitcoin/8/) Init.construct$Params
    2026-09-25T15:13:02.002586Z [unknown] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] {IpcSocketPairTest-11657/b-test-11660} request data: (threadMap = <external capability>)
    2026-09-25T15:13:02.002634Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} IPC server send response [#8](/bitcoin-bitcoin/8/) Init.construct$Results
    2026-09-25T15:13:02.002659Z [unknown] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] {IpcSocketPairTest-11657/b-test-11660} response data: (threadMap = <external capability>)
    2026-09-25T15:13:02.002730Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcPipeTest-11657/b-test-11657} IPC client recv Init.construct$Results
    2026-09-25T15:13:02.002767Z [unknown] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] recv data: (threadMap = <external capability>)
    2026-09-25T15:13:02.002835Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} Creating mp::ProxyClientBase<mp::Thread, capnp::Void> 0x7c39213fb7f8
    2026-09-25T15:13:02.002911Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} Creating mp::ProxyClientBase<mp::Thread, capnp::Void> 0x7c39213fb8c8
    2026-09-25T15:13:02.002938Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcPipeTest-11657/b-test-11657} IPC client send Init.makeEcho$Params
    2026-09-25T15:13:02.002972Z [unknown] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] send data: (context = (thread = <external capability>, callbackThread = <external capability>))
    2026-09-25T15:13:02.003282Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} IPC server recv request  [#9](/bitcoin-bitcoin/9/) Init.makeEcho$Params
    2026-09-25T15:13:02.003331Z [unknown] [ipc/capnp/protocol.cpp:47] [IpcLogFn] [ipc:trace] {IpcSocketPairTest-11657/b-test-11660} request data: (context = (thread = <external capability>, callbackThread = <external capability>))
    2026-09-25T15:13:02.003378Z [unknown] [ipc/capnp/protocol.cpp:50] [IpcLogFn] [ipc] {IpcSocketPairTest-11657/b-test-11660} IPC server post request  [#9](/bitcoin-bitcoin/9/) {IpcSocketPairTest-11657/b-test-11661 (from IpcPipeTest-11657/b-test-11657)}
    terminate called after throwing an instance of 'kj::ExceptionImpl'
      what():  kj/timer.c++:120: failed: expected newTime >= time [932.041647579s >= 932.041669554s]; can't advance backwards in time
    stack: 7f692299eaca 7f6922a919b8 7f6922a378fd 7f6922a37ba5 7f6922a2c30d 7f6922a2c683 55cc45895e04 55cc45889057 55cc44db3204 55cc43c28f05 7f69227dfdb3 55cc4208f2da 7f6922453b83 7f69224e0cc3
    
    375/381 Test [#349](/bitcoin-bitcoin/349/): txvalidationcache_tests ....................................................   Passed   24.80 sec
    376/381 Test [#316](/bitcoin-bitcoin/316/): script_assets_tests ........................................................   Passed   42.60 sec
    377/381 Test [#375](/bitcoin-bitcoin/375/): wallet_interfaces_tests ....................................................   Passed   13.73 sec
    378/381 Test [#374](/bitcoin-bitcoin/374/): wallet_crypto_tests ........................................................   Passed   28.38 sec
    379/381 Test [#295](/bitcoin-bitcoin/295/): net_tests ..................................................................   Passed   66.01 sec
    380/381 Test [#377](/bitcoin-bitcoin/377/): wallet_tests ...............................................................   Passed   36.11 sec
    381/381 Test [#365](/bitcoin-bitcoin/365/): coinselector_tests .........................................................   Passed   81.97 sec
    
    99% tests passed, 1 tests failed out of 381
    
    Label Time Summary:
    secp256k1_exhaustive        =  50.19 sec*proc (1 test)
    secp256k1_noverify_tests    = 160.71 sec*proc (109 tests)
    secp256k1_tests             = 404.72 sec*proc (109 tests)
    
    Total Test time (real) = 216.49 sec
    
    The following tests FAILED:
    Errors while running CTest
    	381 - ipc_tests (Subprocess aborted)
    Command '['docker', 'exec', '--env', 'DANGER_RUN_CI_ON_HOST=1', '534c56ca3ee665e43578ad9be4259c24ac07e4f3a233e6e8ba6e1ee4e13f6814', '/home/runner/work/_temp/ci/test/03_test_script.sh']' returned non-zero exit status 8.
    

    </details>

  2. VijayabaskarR-06 commented at 6:38 AM on September 27, 2026: none

    Hi @darosior , I would like to investigate this issue.

    I have started looking into the intermittent ipc_tests failure and the kj::timer.c++ assertion:

    expected newTime >= time

    I will try to reproduce the failure locally and trace the interaction between the IPC event loop and timer scheduling before proposing a fix.

  3. maflcko added the label IPC on Sep 28, 2026
  4. maflcko added the label CI failed on Sep 28, 2026

github-metadata-mirror

This is a metadata mirror of the GitHub repository bitcoin/bitcoin. This site is not affiliated with GitHub. Content is generated from a GitHub metadata backup.
generated: 2026-09-28 10:51 UTC

This site is hosted by @0xB10C
More mirrored repositories can be found on mirror.b10c.me