multiprocess: ipc_tests fail on *BSD #31618

issue hebasto openend this issue on January 7, 2025
  1. hebasto commented at 4:42 pm on January 7, 2025: member

    https://github.com/hebasto/bitcoin-core-nightly/actions/runs/12655211444/job/35264969094:

     0...
 1./test/ipc_tests.cpp(11): Entering test suite "ipc_tests"
 2./test/ipc_tests.cpp(12): Entering test case "ipc_tests"
 3terminate called after throwing an instance of 'std::system_error'
 4  what():  Invalid argument
 52025-01-07T16:33:06.682484Z [unknown] [test/util/random.cpp:46] [SeedRandomStateForTest] Setting random seed for current tests to RANDOM_CTX_SEED=fa0a6578ac6467296e537ce4d0824c43c31c96f23b59a41c7bd63fe96397244a
 62025-01-07T16:33:06.685389Z [test] [init/common.cpp:153] [LogPackageVersion] Bitcoin Core version v28.99.0-433412fd8478-dirty (release build)
 72025-01-07T16:33:06.715163Z [test] [kernel/context.cpp:20] [operator()] Using the 'x86_shani(1way,2way)' SHA256 implementation
 82025-01-07T16:33:06.715258Z [test] [random.cpp:114] [ReportHardwareRand] Using RdSeed as an additional entropy source
 92025-01-07T16:33:06.715319Z [test] [random.cpp:117] [ReportHardwareRand] Using RdRand as an additional entropy source
102025-01-07T16:33:06.724146Z [test] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client first request from current thread, constructing waiter
112025-01-07T16:33:06.724475Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.add$Params (a = 1, b = 2)
122025-01-07T16:33:06.724918Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#1](/bitcoin-bitcoin/1/) FooInterface.add$Params (a = 1, b = 2)
132025-01-07T16:33:06.725025Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#1](/bitcoin-bitcoin/1/) FooInterface.add$Results (result = 3)
142025-01-07T16:33:06.725288Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.add$Results (result = 3)
152025-01-07T16:33:06.726546Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.passOutPoint$Params (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")
162025-01-07T16:33:06.726863Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#2](/bitcoin-bitcoin/2/) FooInterface.passOutPoint$Params (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")
172025-01-07T16:33:06.726969Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#2](/bitcoin-bitcoin/2/) FooInterface.passOutPoint$Results (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")
182025-01-07T16:33:06.727206Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.passOutPoint$Results (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")
192025-01-07T16:33:06.727512Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.passUniValue$Params (arg = "{\\"i\\":1,\\"s\\":\\"two\\"}")
202025-01-07T16:33:06.727775Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#3](/bitcoin-bitcoin/3/) FooInterface.passUniValue$Params (arg = "{\\"i\\":1,\\"s\\":\\"two\\"}")
212025-01-07T16:33:06.727906Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#3](/bitcoin-bitcoin/3/) FooInterface.passUniValue$Results (result = "{\\"i\\":1,\\"s\\":\\"two\\"}")
222025-01-07T16:33:06.728111Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.passUniValue$Results (result = "{\\"i\\":1,\\"s\\":\\"two\\"}")
232025-01-07T16:33:06.728494Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.passTransaction$Params (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\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
242025-01-07T16:33:06.730213Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#4](/bitcoin-bitcoin/4/) FooInterface.passTransaction$Params (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\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
252025-01-07T16:33:06.730295Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#4](/bitcoin-bitcoin/4/) FooInterface.passTransaction$Results (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\\000\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
262025-01-07T16:33:06.730417Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.passTransaction$Results (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\\000\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
272025-01-07T16:33:06.730550Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.passVectorChar$Params (arg = "Hello")
282025-01-07T16:33:06.730651Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#5](/bitcoin-bitcoin/5/) FooInterface.passVectorChar$Params (arg = "Hello")
292025-01-07T16:33:06.730697Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#5](/bitcoin-bitcoin/5/) FooInterface.passVectorChar$Results (result = "Hello")
302025-01-07T16:33:06.730842Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.passVectorChar$Results (result = "Hello")
312025-01-07T16:33:06.731889Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.passBlockState$Params (arg = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
322025-01-07T16:33:06.731989Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#6](/bitcoin-bitcoin/6/) FooInterface.passBlockState$Params (arg = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
332025-01-07T16:33:06.732036Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#6](/bitcoin-bitcoin/6/) FooInterface.passBlockState$Results (result = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
342025-01-07T16:33:06.732127Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.passBlockState$Results (result = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
352025-01-07T16:33:06.732273Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client send FooInterface.passBlockState$Params (arg = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
362025-01-07T16:33:06.732419Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server recv request  [#7](/bitcoin-bitcoin/7/) FooInterface.passBlockState$Params (arg = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
372025-01-07T16:33:06.732463Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server send response [#7](/bitcoin-bitcoin/7/) FooInterface.passBlockState$Results (result = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
382025-01-07T16:33:06.732555Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c38369c800} IPC client recv FooInterface.passBlockState$Results (result = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
392025-01-07T16:33:06.750906Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} IPC server destroy N2mp11ProxyServerIN3gen12FooInterfaceEEE
402025-01-07T16:33:06.751182Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} EventLoop::loop done, cancelling event listeners.
412025-01-07T16:33:06.751242Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-5456/0x77c3833af000} EventLoop::loop bye.
42...

    For now, I’m not sure whether this issue is reliably reproducible.

  2. hebasto added the label Tests on Jan 7, 2025
  3. hebasto commented at 4:42 pm on January 7, 2025: member
    cc @ryanofsky
  4. ryanofsky commented at 8:22 pm on January 7, 2025: contributor

    I would guess based on the std::system_error “Invalid argument” error this failure should probably happen reliably instead of randomly, but hard to know.

    It seems like from the logs IpcPipeTest is succeeding and the error is probably coming from one of the following tests IpcSocketPairTest or IpcSocketTest even though those tests do not appear to be printing anything. Maybe the reason they aren’t printing anything is because they are using LogDebug instead of LogPrintf

    It would be useful to see more log output but probably most helpful to be able to see a stack trace, if it’s possible to get one from gdb with catch throw and bt. Alternately running the test under strace could be helpful since that should which system call is triggering the “Invalid argument” (EINVAL) error.

  5. hebasto commented at 12:02 pm on January 17, 2025: member

    Another failure happened on FreeBSD:

     0...
 1test/ipc_tests.cpp(11): Entering test suite "ipc_tests"
 2test/ipc_tests.cpp(12): Entering test case "ipc_tests"
 32025-01-17T03:21:27.796591Z [unknown] [test/util/random.cpp:46] [SeedRandomStateForTest] Setting random seed for current tests to RANDOM_CTX_SEED=0140cbe5acaa3785e6c2a1897e6b52be00debbec3b124b182a626a06ad224950
 42025-01-17T03:21:27.797051Z [test] [init/common.cpp:153] [LogPackageVersion] Bitcoin Core version v28.99.0-f9032a4abb74 (release build)
 52025-01-17T03:21:27.797168Z [test] [kernel/context.cpp:20] [operator()] Using the 'x86_shani(1way,2way)' SHA256 implementation
 62025-01-17T03:21:27.797178Z [test] [random.cpp:114] [ReportHardwareRand] Using RdSeed as an additional entropy source
 72025-01-17T03:21:27.797185Z [test] [random.cpp:117] [ReportHardwareRand] Using RdRand as an additional entropy source
 82025-01-17T03:21:27.797523Z [test] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client first request from current thread, constructing waiter
 92025-01-17T03:21:27.797615Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.add$Params (a = 1, b = 2)
102025-01-17T03:21:27.797696Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#1](/bitcoin-bitcoin/1/) FooInterface.add$Params (a = 1, b = 2)
112025-01-17T03:21:27.797715Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#1](/bitcoin-bitcoin/1/) FooInterface.add$Results (result = 3)
122025-01-17T03:21:27.797755Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.add$Results (result = 3)
132025-01-17T03:21:27.797798Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.passOutPoint$Params (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")
142025-01-17T03:21:27.797837Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#2](/bitcoin-bitcoin/2/) FooInterface.passOutPoint$Params (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")
152025-01-17T03:21:27.797856Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#2](/bitcoin-bitcoin/2/) FooInterface.passOutPoint$Results (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")
162025-01-17T03:21:27.797882Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.passOutPoint$Results (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")
172025-01-17T03:21:27.797914Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.passUniValue$Params (arg = "{\\"i\\":1,\\"s\\":\\"two\\"}")
182025-01-17T03:21:27.797943Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#3](/bitcoin-bitcoin/3/) FooInterface.passUniValue$Params (arg = "{\\"i\\":1,\\"s\\":\\"two\\"}")
192025-01-17T03:21:27.797963Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#3](/bitcoin-bitcoin/3/) FooInterface.passUniValue$Results (result = "{\\"i\\":1,\\"s\\":\\"two\\"}")
202025-01-17T03:21:27.797986Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.passUniValue$Results (result = "{\\"i\\":1,\\"s\\":\\"two\\"}")
212025-01-17T03:21:27.798030Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.passTransaction$Params (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\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
222025-01-17T03:21:27.798119Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#4](/bitcoin-bitcoin/4/) FooInterface.passTransaction$Params (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\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
232025-01-17T03:21:27.798145Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#4](/bitcoin-bitcoin/4/) FooInterface.passTransaction$Results (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\\000\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
242025-01-17T03:21:27.798175Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.passTransaction$Results (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\\000\\000\\000\\000\\377\\377\\377\\377\\001\\000\\341\\365\\005\\000\\000\\000\\000\\000\\003\\000\\000\\000")
252025-01-17T03:21:27.798202Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.passVectorChar$Params (arg = "Hello")
262025-01-17T03:21:27.798229Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#5](/bitcoin-bitcoin/5/) FooInterface.passVectorChar$Params (arg = "Hello")
272025-01-17T03:21:27.798278Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#5](/bitcoin-bitcoin/5/) FooInterface.passVectorChar$Results (result = "Hello")
282025-01-17T03:21:27.798309Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.passVectorChar$Results (result = "Hello")
292025-01-17T03:21:27.798343Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.passBlockState$Params (arg = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
302025-01-17T03:21:27.798375Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#6](/bitcoin-bitcoin/6/) FooInterface.passBlockState$Params (arg = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
312025-01-17T03:21:27.798394Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#6](/bitcoin-bitcoin/6/) FooInterface.passBlockState$Results (result = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
322025-01-17T03:21:27.798419Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.passBlockState$Results (result = (mode = 1, result = 8, rejectReason = "reject reason", debugMessage = "debug message"))
332025-01-17T03:21:27.798448Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client send FooInterface.passBlockState$Params (arg = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
342025-01-17T03:21:27.798476Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server recv request  [#7](/bitcoin-bitcoin/7/) FooInterface.passBlockState$Params (arg = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
352025-01-17T03:21:27.798494Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server send response [#7](/bitcoin-bitcoin/7/) FooInterface.passBlockState$Results (result = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
362025-01-17T03:21:27.798574Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/b-test-100326} IPC client recv FooInterface.passBlockState$Results (result = (mode = 0, result = 0, rejectReason = "", debugMessage = ""))
372025-01-17T03:21:27.798798Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} IPC server destroy N2mp11ProxyServerIN3gen12FooInterfaceEEE
382025-01-17T03:21:27.798857Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} EventLoop::loop done, cancelling event listeners.
392025-01-17T03:21:27.798868Z [unknown] [test/ipc_test.cpp:60] [operator()] LOG0: {IpcPipeTest-7062/101158} EventLoop::loop bye.
402025-01-17T03:21:27.799167Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Init.construct$Params (threadMap = <external capability>)
412025-01-17T03:21:27.799230Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server recv request  [#8](/bitcoin-bitcoin/8/) Init.construct$Params (threadMap = <external capability>)
422025-01-17T03:21:27.799293Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server send response [#8](/bitcoin-bitcoin/8/) Init.construct$Results (threadMap = <external capability>)
432025-01-17T03:21:27.799336Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client recv Init.construct$Results (threadMap = <external capability>)
442025-01-17T03:21:27.799385Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Init.makeEcho$Params (context = (thread = <external capability>, callbackThread = <external capability>))
452025-01-17T03:21:27.799519Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server recv request  [#9](/bitcoin-bitcoin/9/) Init.makeEcho$Params (context = (thread = <external capability>, callbackThread = <external capability>))
462025-01-17T03:21:27.799542Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server post request  [#9](/bitcoin-bitcoin/9/) {test-serve-7062/101161 (from IpcPipeTest-7062/b-test-100326)}
472025-01-17T03:21:27.799636Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server send response [#9](/bitcoin-bitcoin/9/) Init.makeEcho$Results (result = <external capability>)
482025-01-17T03:21:27.799663Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client recv Init.makeEcho$Results (result = <external capability>)
492025-01-17T03:21:27.799699Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Echo.echo$Params (context = (thread = <external capability>, callbackThread = <external capability>), echo = "echo test")
502025-01-17T03:21:27.799743Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server recv request  [#10](/bitcoin-bitcoin/10/) Echo.echo$Params (context = (thread = <external capability>, callbackThread = <external capability>), echo = "echo test")
512025-01-17T03:21:27.799758Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server post request  [#10](/bitcoin-bitcoin/10/) {test-serve-7062/101161 (from IpcPipeTest-7062/b-test-100326)}
522025-01-17T03:21:27.799798Z [unknown] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/101160} IPC server send response [#10](/bitcoin-bitcoin/10/) Echo.echo$Results (result = "echo test")
532025-01-17T03:21:27.804764Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Echo.destroy$Params (context = (thread = <external capability>, callbackThread = <external capability>))
542025-01-17T03:21:27.804796Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server recv request  [#27](/bitcoin-bitcoin/27/) Echo.destroy$Params (context = (thread = <external capability>, callbackThread = <external capability>))
552025-01-17T03:21:27.804809Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server post request  [#27](/bitcoin-bitcoin/27/) {test-serve-7062/101168 (from IpcPipeTest-7062/b-test-100326)}
562025-01-17T03:21:27.804896Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server send response [#27](/bitcoin-bitcoin/27/) Echo.destroy$Results ()
572025-01-17T03:21:27.804922Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client recv Echo.destroy$Results ()
582025-01-17T03:21:27.804947Z [test] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client destroy N2mp11ProxyClientIN3ipc5capnp8messages4InitEEE
592025-01-17T03:21:27.805019Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Init.construct$Params (threadMap = <external capability>)
602025-01-17T03:21:27.805062Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server destroy N2mp11ProxyServerIN3ipc5capnp8messages4EchoEEE
612025-01-17T03:21:27.805154Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server recv request  [#28](/bitcoin-bitcoin/28/) Init.construct$Params (threadMap = <external capability>)
622025-01-17T03:21:27.805172Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server send response [#28](/bitcoin-bitcoin/28/) Init.construct$Results (threadMap = <external capability>)
632025-01-17T03:21:27.805317Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server: socket disconnected.
642025-01-17T03:21:27.805332Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server destroy N2mp11ProxyServerIN3ipc5capnp8messages4InitEEE
652025-01-17T03:21:27.805368Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client recv Init.construct$Results (threadMap = <external capability>)
662025-01-17T03:21:27.805409Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Init.makeEcho$Params (context = (thread = <external capability>, callbackThread = <external capability>))
672025-01-17T03:21:27.805513Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server recv request  [#29](/bitcoin-bitcoin/29/) Init.makeEcho$Params (context = (thread = <external capability>, callbackThread = <external capability>))
682025-01-17T03:21:27.805531Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server post request  [#29](/bitcoin-bitcoin/29/) {test-serve-7062/101169 (from IpcPipeTest-7062/b-test-100326)}
692025-01-17T03:21:27.805615Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server send response [#29](/bitcoin-bitcoin/29/) Init.makeEcho$Results (result = <external capability>)
702025-01-17T03:21:27.805643Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client recv Init.makeEcho$Results (result = <external capability>)
712025-01-17T03:21:27.805675Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Echo.echo$Params (context = (thread = <external capability>, callbackThread = <external capability>), echo = "echo test")
722025-01-17T03:21:27.805720Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server recv request  [#30](/bitcoin-bitcoin/30/) Echo.echo$Params (context = (thread = <external capability>, callbackThread = <external capability>), echo = "echo test")
732025-01-17T03:21:27.805734Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server post request  [#30](/bitcoin-bitcoin/30/) {test-serve-7062/101169 (from IpcPipeTest-7062/b-test-100326)}
742025-01-17T03:21:27.805777Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server send response [#30](/bitcoin-bitcoin/30/) Echo.echo$Results (result = "echo test")
752025-01-17T03:21:27.805803Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-10032Terminating due to uncaught exception 0x4144ec2e62c0 of type std::__1::system_error
766} IPC client recv Echo.echo$Results (result = "echo test")
772025-01-17T03:21:27.805900Z [test] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client destroy N2mp11ProxyClientIN3ipc5capnp8messages4EchoEEE
782025-01-17T03:21:27.805931Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client send Echo.destroy$Params (context = (thread = <external capability>, callbackThread = <external capability>))
792025-01-17T03:21:27.805965Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server recv request  [#31](/bitcoin-bitcoin/31/) Echo.destroy$Params (context = (thread = <external capability>, callbackThread = <external capability>))
802025-01-17T03:21:27.805977Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server post request  [#31](/bitcoin-bitcoin/31/) {test-serve-7062/101169 (from IpcPipeTest-7062/b-test-100326)}
812025-01-17T03:21:27.806012Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server send response [#31](/bitcoin-bitcoin/31/) Echo.destroy$Results ()
822025-01-17T03:21:27.806034Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client recv Echo.destroy$Results ()
832025-01-17T03:21:27.806057Z [test] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {IpcPipeTest-7062/b-test-100326} IPC client destroy N2mp11ProxyClientIN3ipc5capnp8messages4InitEEE
842025-01-17T03:21:27.806183Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server destroy N2mp11ProxyServerIN3ipc5capnp8messages4EchoEEE
852025-01-17T03:21:27.806395Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server: socket disconnected.
862025-01-17T03:21:27.806412Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} IPC server destroy N2mp11ProxyServerIN3ipc5capnp8messages4InitEEE
872025-01-17T03:21:27.806460Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} EventLoop::loop done, cancelling event listeners.
882025-01-17T03:21:27.806474Z [capnp-loop] [ipc/capnp/protocol.cpp:35] [IpcLogFn] [ipc] {test-serve-7062/b-capnp-loop-101163} EventLoop::loop bye.
89...
  6. hebasto renamed this:
    multiprocess: `ipc_tests` fail on NetBSD
    multiprocess: `ipc_tests` fail on *BSD
    on Jan 17, 2025
  7. ryanofsky commented at 2:53 pm on January 17, 2025: contributor
    Thanks, do we know yet if these errors happen reliably or are random? Do you know what the easiest way I might be able to reproduce this is? Is this a custom CI setup? Is there a way to run this CI job in a VM in on linux?
  8. hebasto commented at 3:17 pm on January 17, 2025: member

    Thanks, do we know yet if these errors happen reliably or are random?

    They seem intermittent. I failed to reproduce them on my local NetBSD and FreeBSD installations.

    Do you know what the easiest way I might be able to reproduce this is? Is this a custom CI setup?

    Yes. It is a custom CI setup based on https://github.com/vmactions.

    Is there a way to run this CI job in a VM in on linux?

    It is possible to run FreeBSD on Cirrus CI though. And @maflcko runs it :)

  9. maflcko commented at 3:26 pm on January 17, 2025: member

    It is possible to run FreeBSD on Cirrus CI though. And @maflcko runs it :)

    Though, the ipc_tests are not enabled there (yet?)


hebasto ryanofsky maflcko

Labels
Tests

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: 2025-01-21 03:12 UTC
This site is hosted by @0xB10C
More mirrored repositories can be found on mirror.b10c.me