Skip to content

C#: Detected 1 client calls that weren't disposed properly #1734

Closed
Closed
C#: Detected 1 client calls that weren't disposed properly#1734
Assignees
jtattermusch
Labels
lang/C#lang/all wrapped languages
Milestone
Beta
@jtattermusch

Description

@jtattermusch
jtattermusch
opened on May 22, 2015

Can be reproduced by
tools/run_tests/run_tests.py -c dbg -l csharp -n 100 -j 10

Might be a race or C core API misuse.

***** Grpc.IntegrationTesting.InteropClientServerTest.PingPong
running ping_pong
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d475fc380, nops=3, tag=0x7f9d2c10c7c0)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_INITIAL_METADATA
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[1]: RECV_INITIAL_METADATA ptr=0x7f9d2c10c800
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[2]: RECV_STATUS_ON_CLIENT metadata=0x7f9d2c10c820 status=0x7f9d2c10c838 details=0x7f9d2c10c840
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d34003860 OK
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d475fc490, nops=1, tag=0x7f9d2c0e4380)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d2c10f570
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c0e4380 OK
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d475fc410, nops=1, tag=0x7f9d2c0e4380)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d2c0e43d8
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d475fc150, nops=2, tag=0x7f9d2c0c29d0)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_INITIAL_METADATA
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[1]: RECV_CLOSE_ON_SERVER cancelled=0x7f9d2c0c2a60
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d475fbc50, nops=1, tag=0x7f9d2c0c3790)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d2c0c37e8
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c0c3790 OK
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c6d42b0, nops=1, tag=0x7f9d38502d40)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d384edf60
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d38502d40 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c2d22e0, nops=1, tag=0x7f9d30013e10)
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d30013e68
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c0e4380 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d5c2d23b0, nops=1, tag=0x7f9d3000caa0)
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d3000cba0
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3000caa0 OK
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d30013e10 OK
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d5c6d43b0, nops=1, tag=0x7f9d384f4b70)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d384f4bc8
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d477fd2b0, nops=1, tag=0x7f9d34004280)
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d34004380
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d34004280 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d384f4b70 OK
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c6d42e0, nops=1, tag=0x7f9d38502d40)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d38502d98
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d5c4d33b0, nops=1, tag=0x7f9d3c03c8a0)
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d3c03c410
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3c03c8a0 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d38502d40 OK
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d5c6d43b0, nops=1, tag=0x7f9d384f4b70)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d384f4bc8
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c102a50, ops=0x7f9d475fc590, nops=1, tag=0x7f9d2c0e4380)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d2c0c3790
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c2d22b0, nops=1, tag=0x7f9d3000cc00)
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d3000cd00
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c0e4380 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3000cc00 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c4d32e0, nops=1, tag=0x7f9d3c03c8a0)
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d3c03c8f8
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d384f4b70 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3c03c8a0 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c2d22b0, nops=1, tag=0x7f9d3000cc40)
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d30004580
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3000cc40 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c10c7c0 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d38516320, ops=0x7f9d5c4d32e0, nops=1, tag=0x7f9d3c03c8a0)
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d3c03c8f8
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3c03c8a0 OK
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c0c29d0 OK
Exception occured while invoking completion delegate: System.InvalidOperationException: The task has already completed
at System.Runtime.CompilerServices.AsyncTaskMethodBuilder.SetException (System.Exception exception) [0x00000] in :0
at Grpc.IntegrationTesting.InteropClient+c__AnonStorey14+c__async13.MoveNext () [0x00000] in :0
at (wrapper unbox) Grpc.IntegrationTesting.InteropClient/c__AnonStorey14/c__async13:MoveNext ()
at System.Threading.Tasks.AwaiterActionContinuation.Execute () [0x00000] in :0
at System.Threading.Tasks.Task.ProcessCompleteDelegates () [0x00000] in :0
at System.Threading.Tasks.Task.Finish () [0x00000] in :0
at System.Threading.Tasks.Task1[System.Boolean].TrySetResult (Boolean result) [0x00000] in <filename unknown>:0 at System.Runtime.CompilerServices.AsyncTaskMethodBuilder1[System.Boolean].SetResult (Boolean result) [0x00000] in :0
at Grpc.Core.Internal.ClientResponseStream2+<MoveNext>c__async0[grpc.testing.StreamingOutputCallRequest,grpc.testing.StreamingOutputCallResponse].MoveNext () [0x00000] in <filename unknown>:0 at (wrapper unbox) Grpc.Core.Internal.ClientResponseStream2/c__async0<grpc.testing.StreamingOutputCallRequest, grpc.testing.StreamingOutputCallResponse>:MoveNext ()
at System.Threading.Tasks.AwaiterActionContinuation.Execute () [0x00000] in :0
at System.Threading.Tasks.Task.ProcessCompleteDelegates () [0x00000] in :0
at System.Threading.Tasks.Task.Finish () [0x00000] in :0
at System.Threading.Tasks.Task1[grpc.testing.StreamingOutputCallResponse].TrySetResult (grpc.testing.StreamingOutputCallResponse result) [0x00000] in <filename unknown>:0 at System.Threading.Tasks.TaskCompletionSource1[grpc.testing.StreamingOutputCallResponse].TrySetResult (grpc.testing.StreamingOutputCallResponse result) [0x00000] in :0
at System.Threading.Tasks.TaskCompletionSource1[grpc.testing.StreamingOutputCallResponse].SetResult (grpc.testing.StreamingOutputCallResponse result) [0x00000] in <filename unknown>:0 at Grpc.Core.Internal.AsyncCompletionTaskSource1[grpc.testing.StreamingOutputCallResponse].HandleCompletion (grpc.testing.StreamingOutputCallResponse value, System.Exception error) [0x00000] in :0
at Grpc.Core.Internal.AsyncCallBase2[grpc.testing.StreamingOutputCallRequest,grpc.testing.StreamingOutputCallResponse].FireCompletion[StreamingOutputCallResponse] (Grpc.Core.Internal.AsyncCompletionDelegate1 completionDelegate, grpc.testing.StreamingOutputCallResponse value, System.Exception error) [0x00000] in :0
***** Grpc.IntegrationTesting.InteropClientServerTest.ServerStreaming
running server_streaming
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c10dfc0, ops=0x7f9d475fc310, nops=5, tag=0x7f9d2c01a610)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_INITIAL_METADATA
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[1]: SEND_MESSAGE ptr=0x7f9d2c10ede0
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[2]: SEND_CLOSE_FROM_CLIENT
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[3]: RECV_INITIAL_METADATA ptr=0x7f9d2c01a650
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[4]: RECV_STATUS_ON_CLIENT metadata=0x7f9d2c01a670 status=0x7f9d2c01a688 details=0x7f9d2c01a690
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d38517fd0 OK
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c10dfc0, ops=0x7f9d475fc1b0, nops=1, tag=0x7f9d2c104200)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d2c104258
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d475fc110, nops=2, tag=0x7f9d2c10bea0)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_INITIAL_METADATA
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[1]: RECV_CLOSE_ON_SERVER cancelled=0x7f9d2c10bf30
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d475fbf30, nops=1, tag=0x7f9d2c1155a0)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d2c1155f8
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c1155a0 OK
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d475fc3a0, nops=1, tag=0x7f9d2c1188d0)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d2c118928
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c1188d0 OK
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d477fd160, nops=1, tag=0x7f9d34009910)
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d340126c0
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d34009910 OK
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d475fc620, nops=1, tag=0x7f9d2c1189d0)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d2c118ad0
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c1189d0 OK
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d477fd5c0, nops=1, tag=0x7f9d34012520)
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d34009910
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c104200 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d34012520 OK
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c10dfc0, ops=0x7f9d5c6d42f0, nops=1, tag=0x7f9d385176b0)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d38517708
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d385176b0 OK
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c10dfc0, ops=0x7f9d5c6d42f0, nops=1, tag=0x7f9d38517870)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d385178c8
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d38517870 OK
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d475fc620, nops=1, tag=0x7f9d2c104200)
I5/22/2015 3:49:28 PM 140313484056320 src/core/surface/call.c:1198: ops[0]: SEND_MESSAGE ptr=0x7f9d2c1188d0
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c10dfc0, ops=0x7f9d477fd2f0, nops=1, tag=0x7f9d34012520)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c104200 OK
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d34012578
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d34007320, ops=0x7f9d5c6d4360, nops=1, tag=0x7f9d384ee670)
I5/22/2015 3:49:28 PM 140313837262592 src/core/surface/call.c:1198: ops[0]: SEND_STATUS_FROM_SERVER status=0 details=
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d384ee670 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c10bea0 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d34012520 OK
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d2c01a610 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: grpc_call_start_batch(call=0x7f9d2c10dfc0, ops=0x7f9d5c4d32f0, nops=1, tag=0x7f9d3c03c8a0)
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/call.c:1198: ops[0]: RECV_MESSAGE ptr=0x7f9d3c03c8f8
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d3c03c8a0 OK
Passed!
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d4c1c9690 OK
I5/22/2015 3:49:28 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: OP_COMPLETE: tag:0x7f9d34003860 ERROR
Waiting for GPRC threads to finish.
I5/22/2015 3:49:28 PM 140313486157568 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: QUEUE_SHUTDOWN
I5/22/2015 3:49:28 PM 140313833060096 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: QUEUE_SHUTDOWN
Completion queue has shutdown successfully, thread grpc 3 exiting.
Completion queue has shutdown successfully, thread grpc 2 exiting.
I5/22/2015 3:49:29 PM 140313835161344 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: QUEUE_SHUTDOWN
I5/22/2015 3:49:29 PM 140313837262592 src/core/surface/completion_queue.c:223: RETURN_EVENT[0x7f9d4c015cc0]: QUEUE_SHUTDOWN
Completion queue has shutdown successfully, thread grpc 0 exiting.
Completion queue has shutdown successfully, thread grpc 1 exiting.
GRPC shutdown.
Warning: Detected 1 client calls that weren't disposed properly.

Tests run: 8, Errors: 1, Failures: 0, Inconclusive: 0, Time: 3.007163 seconds
Not run: 0, Invalid: 0, Ignored: 0, Skipped: 0

Errors and Failures:

  1. Test Error : Grpc.IntegrationTesting.InteropClientServerTest.PingPong
    System.AggregateException : One or more errors occured
    ----> System.InvalidOperationException : Only one read can be pending at a time
    at System.Threading.Tasks.Task.Wait (Int32 millisecondsTimeout, CancellationToken cancellationToken) [0x00000] in :0
    at System.Threading.Tasks.Task.Wait () [0x00000] in :0
    at Grpc.IntegrationTesting.InteropClient.RunPingPong (ITestServiceClient client) [0x00000] in :0
    at Grpc.IntegrationTesting.InteropClientServerTest.PingPong () [0x00000] in :0
    at (wrapper managed-to-native) System.Reflection.MonoMethod:InternalInvoke (System.Reflection.MonoMethod,object,object[],System.Exception&)
    at System.Reflection.MonoMethod.Invoke (System.Object obj, BindingFlags invokeAttr, System.Reflection.Binder binder, System.Object[] parameters, System.Globalization.CultureInfo culture) [0x00000] in :0
    --InvalidOperationException
    at Grpc.Core.Utils.Preconditions.CheckState (Boolean condition, System.String errorMessage) [0x00000] in :0
    at Grpc.Core.Internal.AsyncCallBase2[grpc.testing.StreamingOutputCallRequest,grpc.testing.StreamingOutputCallResponse].CheckReadingAllowed () [0x00000] in <filename unknown>:0 at Grpc.Core.Internal.AsyncCallBase2[grpc.testing.StreamingOutputCallRequest,grpc.testing.StreamingOutputCallResponse].StartReadMessageInternal (Grpc.Core.Internal.AsyncCompletionDelegate1 completionDelegate) [0x00000] in <filename unknown>:0 at Grpc.Core.Internal.AsyncCall2[grpc.testing.StreamingOutputCallRequest,grpc.testing.StreamingOutputCallResponse].StartReadMessage (Grpc.Core.Internal.AsyncCompletionDelegate1 completionDelegate) [0x00000] in <filename unknown>:0 at Grpc.Core.Internal.ClientResponseStream2+c__async0[grpc.testing.StreamingOutputCallRequest,grpc.testing.StreamingOutputCallResponse].MoveNext () [0x00000] in :0
    --- End of stack trace from previous location where exception was thrown ---
    at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw () [0x00000] in :0
    at System.Runtime.CompilerServices.TaskAwaiter`1[System.Boolean].GetResult () [0x00000] in :0
    at Grpc.IntegrationTesting.InteropClient+c__AnonStorey14+c__async13.MoveNext () [0x00000] in :0

Cannot load type 'grpc.testing.TestServiceImpl+c__async5'
Cannot load type 'grpc.testing.TestServiceImpl+c__async5'
Cannot load type 'grpc.testing.TestServiceImpl+c__async5'
Cannot load type 'grpc.testing.TestServiceImpl+c__async5'
Cannot load type 'grpc.testing.TestServiceImpl+c__async8'
Cannot load type 'grpc.testing.TestServiceImpl+c__async8'
Cannot load type 'grpc.testing.TestServiceImpl+c__async8'
Cannot load type 'grpc.testing.TestServiceImpl+c__async8'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async5'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async5'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async5'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async5'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__asyncC'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__asyncC'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__asyncC'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__asyncC'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async13'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async13'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async13'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async13'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async1A'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async1A'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async1A'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async1A'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async21'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async21'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async21'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async21'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async28'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async28'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async28'
Cannot load type 'Grpc.IntegrationTesting.InteropClient+c__async28'
Stacktrace:

at <0xffffffff>
at (wrapper managed-to-native) Grpc.Core.Internal.CallSafeHandle.grpcsharp_call_destroy (intptr) <0xffffffff>
at Grpc.Core.Internal.CallSafeHandle.ReleaseHandle () <0x00017>
at System.Runtime.InteropServices.SafeHandle.Finalize () <0x0003d>
at (wrapper runtime-invoke) object.runtime_invoke_virtual_void__this__ (object,intptr,intptr,intptr) <0xffffffff>

Native stacktrace:

/usr/bin/cli() [0x4b73d8]
/lib/x86_64-linux-gnu/libpthread.so.0(+0x10340) [0x7f9d61afe340]
/lib/x86_64-linux-gnu/libc.so.6(gsignal+0x39) [0x7f9d6175fcc9]
/lib/x86_64-linux-gnu/libc.so.6(abort+0x148) [0x7f9d617630d8]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgpr.so.0(gpr_mu_lock+0x4a) [0x7f9d5d58de87]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(grpc_iomgr_add_delayed_callback+0x4e) [0x7f9d5d16e105]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(grpc_iomgr_add_callback+0x28) [0x7f9d5d16e188]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(+0x5a321) [0x7f9d5d167321]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(grpc_child_call_destroy+0x85) [0x7f9d5d16761a]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(+0x5b3be) [0x7f9d5d1683be]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(grpc_call_stack_destroy+0x6f) [0x7f9d5d166cf6]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(+0x6da83) [0x7f9d5d17aa83]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(grpc_call_internal_unref+0x3c) [0x7f9d5d17acc4]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc.so.0(grpc_call_destroy+0x80) [0x7f9d5d17cc71]
/usr/local/google/home/jtattermusch/github/grpc/libs/dbg/libgrpc_csharp_ext.so(grpcsharp_call_destroy+0x18) [0x7f9d5d796387]
[0x4048801b]

Debug info from gdb:

Cannot attach to process 15907: Operation not permitted (1)
If your uid matches the uid of the target process, check the setting of
/proc/sys/kernel/yama/ptrace_scope. See /etc/sysctl.d/10-ptrace.conf.
If ptrace scope is enabled, you can lift the restriction with:
sudo sysctl -w kernel.yama.ptrace_scope=0
No threads.

Got a SIGABRT while executing native code. This usually indicates
a fatal error in the mono runtime or one of the native libraries

used by your application.

Metadata

Metadata

Assignees

Labels

lang/C#lang/all wrapped languages

Type

No type

Projects

No projects

Milestone

Relationships

None yet

Development

No branches or pull requests

Issue actions