Hi Lidi,
I've set those environment variables you mentioned and I've attached the
log files (serverlog.txt and clientlog.txt).
Since I reported this issue, I've upgrade the Python version of gRPC, so
the attached log files were created with grpcio 1.19.0
Do they give you any hint as to what could be wrong?
Thanks,
Alex.
On Thu, 28 Mar 2019 at 17:07, Alejandro Villagrán <negral...@gmail.com>
wrote:
> Hi Lidi,
>
> In the last version of the code I attached, I don't use CherryPy anymore
> so the issue cannot come from there. Eric said that fork handlers are not
> registered on Windows so he ruled out that option too.
> Unfortunately I haven't got access to a Linux machine and I'm not familiar
> enough with Linux to be able to recreate the issue there.
>
> I did use turn on the debug trace a few weeks ago but I couldn't see
> anything obvious. I'll find time to enable them again and share the logs
> with you.
>
> Thanks,
> Alex.
>
> On Thu, 28 Mar 2019 at 16:55, Lidi Zheng <li...@google.com> wrote:
>
>> My apologies Alex. There are other stuff keep come up and consumed my
>> time. I'm not a Windows expert, it will take me a long time to setup the
>> compilation environment for gRPC in Windows with debugger. And I failed to
>> find a Windows expert to debug your issue.
>> Eric has mentioned that the breakage can be caused by either fork
>> handlers registration or CherryPy. Do you think you can migrate the
>> reproduce case to Linux, if the root cause is the software? It would be
>> much easier to debug.
>> Also, have you tried to turn on the debug trace in gRPC by setting
>> environmental
>> variables
>> <https://github.com/grpc/grpc/blob/master/doc/environment_variables.md> "
>> *GRPC_VERBOSITY*" to "*DEBUG*", and "*GRPC_TRACE*" to "
>> *api,channel,connectivity_state*"? They might produce useful information
>> for us to identify the problem.
>>
>> Thanks,
>> Lidi Zheng
>>
>> On Thu, Mar 28, 2019 at 3:47 AM Alejandro Villagrán <negral...@gmail.com>
>> wrote:
>>
>>> Hi Lidi,
>>>
>>> Did you manage to reproduce the issue?
>>>
>>> Thanks,
>>> Alex.
>>>
>>> On Tue, 19 Mar 2019 at 01:06, Lidi Zheng <li...@google.com> wrote:
>>>
>>>> Hi Alex,
>>>>
>>>> Thank you for providing the reproduce code. I will spin up a Windows
>>>> machine to investigate this error.
>>>> If I'm able to find something useful, I'll let you know.
>>>>
>>>> Lidi Zheng
>>>>
>>>> On Mon, Mar 18, 2019 at 4:09 AM Alejandro Villagrán <
>>>> negral...@gmail.com> wrote:
>>>>
>>>>> Hi Eric/Lidi,
>>>>>
>>>>> Yes, I'm running on Windows. I have now removed the CherryPy code and
>>>>> I still get the exception.
>>>>>
>>>>> Please follow these steps to reproduce the issue:
>>>>> - Unzip ReproduceGRPCIssue.zip
>>>>> - Go to the BreakGRPC folder and compile BreakGRPC.sln. Make sure
>>>>> BreakGRPC.exe is saved in BreakGRPC/x64/Release.
>>>>> - Go to the Services/src folder and open two command prompts there.
>>>>> - Run "python GRPCserver.py" in one command prompt.
>>>>> - Run "python GRPCclient.py" in the other command prompt.
>>>>>
>>>>> You should see the exception on the client command prompt.
>>>>>
>>>>> Please let me know if you are still unable to reproduce the issue with
>>>>> this version of the code.
>>>>>
>>>>> Thanks,
>>>>> Alex.
>>>>>
>>>>> On Mon, 4 Mar 2019 at 17:04, Eric Gribkoff <ericgribk...@google.com>
>>>>> wrote:
>>>>>
>>>>>> +Lidi Zheng <li...@google.com>, who will be available for any
>>>>>> follow-up questions (it will be easier for him to notice your questions
>>>>>> if
>>>>>> you include his email address on the "to:" line)
>>>>>>
>>>>>> Hi Alex,
>>>>>>
>>>>>> Sorry for the delay. I was not able to reproduce the problem; it
>>>>>> looks like you are running on Windows, in which case gRPC's fork handlers
>>>>>> are not registered/run, so those shouldn't be the cause here . Since the
>>>>>> reproduction example also uses CherryPy websockets, it's quite possible
>>>>>> the
>>>>>> issue stems from that software rather than the gRPC stack - we'd likely
>>>>>> need a reproduction case that only uses gRPC, without the websockets, to
>>>>>> be
>>>>>> able to help debug this further.
>>>>>>
>>>>>> Thanks,
>>>>>>
>>>>>> Eric
>>>>>>
>>>>>> On Mon, Mar 4, 2019 at 2:36 AM Alex <negral...@gmail.com> wrote:
>>>>>>
>>>>>>> Hi Eric,
>>>>>>>
>>>>>>> Just wondering if you had time to run my attached example and
>>>>>>> managed to reproduce the problem?
>>>>>>>
>>>>>>> Thanks,
>>>>>>> Alex.
>>>>>>>
>>>>>>> On Wednesday, February 20, 2019 at 7:04:51 PM UTC, Eric Gribkoff
>>>>>>> wrote:
>>>>>>>>
>>>>>>>> Can you post the code you're using to reproduce this error? If
>>>>>>>> you're using subprocess.Popen (or otherwise using fork+exec) to start
>>>>>>>> the
>>>>>>>> C++ grpc client process, the C++ client itself cannot be interfering
>>>>>>>> with
>>>>>>>> the Python process. Something could be going wrong in the gRPC core
>>>>>>>> fork
>>>>>>>> handlers, however - you can try running with the environment variable
>>>>>>>> `GRPC_ENABLE_FORK_SUPPORT=0` to disable this feature and see if it
>>>>>>>> fixes
>>>>>>>> the issue.
>>>>>>>>
>>>>>>>> Also, in your step 5 you note that the C++ client isn't
>>>>>>>> communicating with the server. If you remove the fork+exec of a C++
>>>>>>>> subprocess altogether, do you still see this intermittent exception in
>>>>>>>> the
>>>>>>>> Python client?
>>>>>>>>
>>>>>>>> Eric
>>>>>>>>
>>>>>>>> On Wed, Feb 20, 2019 at 6:57 AM Alex <negr...@gmail.com> wrote:
>>>>>>>>
>>>>>>>>> I should add that the Python client application which owns the
>>>>>>>>> Python grpc client is the one that runs the C++ grpc client as a
>>>>>>>>> subprocess
>>>>>>>>> in case that makes a difference.
>>>>>>>>>
>>>>>>>>> --
>>>>>>>>> You received this message because you are subscribed to the Google
>>>>>>>>> Groups "grpc.io" group.
>>>>>>>>> To unsubscribe from this group and stop receiving emails from it,
>>>>>>>>> send an email to grpc-io+u...@googlegroups.com.
>>>>>>>>> To post to this group, send email to grp...@googlegroups.com.
>>>>>>>>> Visit this group at https://groups.google.com/group/grpc-io.
>>>>>>>>> To view this discussion on the web visit
>>>>>>>>> https://groups.google.com/d/msgid/grpc-io/b323fac3-978b-47c1-b1fa-555c2f62b544%40googlegroups.com
>>>>>>>>> <https://groups.google.com/d/msgid/grpc-io/b323fac3-978b-47c1-b1fa-555c2f62b544%40googlegroups.com?utm_medium=email&utm_source=footer>
>>>>>>>>> .
>>>>>>>>> For more options, visit https://groups.google.com/d/optout.
>>>>>>>>>
>>>>>>>> --
>>>>>>> You received this message because you are subscribed to the Google
>>>>>>> Groups "grpc.io" group.
>>>>>>> To unsubscribe from this group and stop receiving emails from it,
>>>>>>> send an email to grpc-io+unsubscr...@googlegroups.com.
>>>>>>> To post to this group, send email to grpc-io@googlegroups.com.
>>>>>>> Visit this group at https://groups.google.com/group/grpc-io.
>>>>>>> To view this discussion on the web visit
>>>>>>> https://groups.google.com/d/msgid/grpc-io/af4d55fa-a5e0-4e3f-a5ad-9cb62378703d%40googlegroups.com
>>>>>>> <https://groups.google.com/d/msgid/grpc-io/af4d55fa-a5e0-4e3f-a5ad-9cb62378703d%40googlegroups.com?utm_medium=email&utm_source=footer>
>>>>>>> .
>>>>>>> For more options, visit https://groups.google.com/d/optout.
>>>>>>>
>>>>>>
--
You received this message because you are subscribed to the Google Groups
"grpc.io" group.
To unsubscribe from this group and stop receiving emails from it, send an email
to grpc-io+unsubscr...@googlegroups.com.
To post to this group, send email to grpc-io@googlegroups.com.
Visit this group at https://groups.google.com/group/grpc-io.
To view this discussion on the web visit
https://groups.google.com/d/msgid/grpc-io/CAKQGxBBF3SjGBPiU%2BAbh4m7zUT%2BdfY97nTn%2Baxa0LMjXfmYxdQ%40mail.gmail.com.
For more options, visit https://groups.google.com/d/optout.
ReproduceGRPCIssue\Services\src>python GRPCserver.py
D0401 15:27:54.517000000 19804
src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.cc:334]
Using native dns resolver
I0401 15:27:54.521000000 19804 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:54.525000000 19804 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I0401 15:27:54.530000000 19804 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:54.533000000 19804 src/core/lib/surface/server.cc:1015]
grpc_server_create(0000000006B58758, 0000000000000000)
I0401 15:27:54.538000000 19804 src/core/lib/surface/server.cc:998]
grpc_server_register_completion_queue(server=0000000006A1F830,
cq=0000000006AAA9D0, reserved=0000000000000000)
I0401 15:27:54.547000000 19804
src/core/ext/transport/chttp2/server/insecure/server_chttp2.cc:34]
grpc_server_add_insecure_http2_port(server=0000000006A1F830, addr=[::]:50051)
I0401 15:27:54.557000000 19804 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:54.561000000 19804 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=1)
I0401 15:27:54.568000000 19804 src/core/lib/surface/server.cc:998]
grpc_server_register_completion_queue(server=0000000006A1F830,
cq=0000000006AAAB30, reserved=0000000000000000)
I0401 15:27:54.576000000 19804 src/core/lib/surface/server.cc:1119]
grpc_server_start(server=0000000006A1F830)
I0401 15:27:54.580000000 19804 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAAB30, deadline=gpr_timespec { tv_sec:
1554128874, tv_nsec: 579999923, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:54.592000000 19804 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:54.596000000 19804 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:54.599000000 19804 src/core/lib/surface/call_details.cc:31]
grpc_call_details_init(cd=0000000006BA05F0)
I0401 15:27:54.604000000 19804 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=0000000006B54798)
I0401 15:27:54.608000000 19804 src/core/lib/surface/server.cc:1487]
grpc_server_request_call(server=0000000006A1F830, call=0000000006B9E790,
details=0000000006BA05F0, initial_metadata=0000000006B54798,
cq_bound_to_call=0000000006AAA9D0, cq_for_notification=0000000006AAA9D0,
tag=0000000006B54768)
I0401 15:27:54.619000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128874, tv_nsec: 819000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:54.821000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128875, tv_nsec: 21000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.022000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128875, tv_nsec: 222000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.224000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128875, tv_nsec: 424000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.426000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128875, tv_nsec: 618999958, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.621000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128875, tv_nsec: 821000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.817000000 22696
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000006BDAEA0
server_transport: from IDLE [cur=READY] notify=00000000069FD510
I0401 15:27:55.826000000 22696
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000006BDAEA0
server_transport: from READY [cur=READY] notify=00000000069FD510
I0401 15:27:55.840000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 40000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.041000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 241000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.087000000 22696 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_INITIAL_METADATA ptr=0000000006BDF378
I0401 15:27:56.092000000 22696 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_INITIAL_METADATA
I0401 15:27:56.097000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_INITIAL_METADATA
I0401 15:27:56.102000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_INITIAL_METADATA
I0401 15:27:56.107000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_INITIAL_METADATA
I0401 15:27:56.114000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_INITIAL_METADATA
I0401 15:27:56.119000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_INITIAL_METADATA
I0401 15:27:56.124000000 22696 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B54768, error="No Error",
done=00000000639ACD40, done_arg=0000000004936EA0, storage=0000000004936ED8)
I0401 15:27:56.133000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B54768 OK
I0401 15:27:56.139000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006B54798)
I0401 15:27:56.147000000 22696 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000005E156F0, nops=1,
tag=0000000006B58BF8, reserved=0000000000000000)
I0401 15:27:56.154000000 22696 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_CLOSE_ON_SERVER cancelled=0000000006AFC910
I0401 15:27:56.159000000 22696 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_TRAILING_METADATA
I0401 15:27:56.164000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_TRAILING_METADATA
I0401 15:27:56.170000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_TRAILING_METADATA
I0401 15:27:56.175000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_TRAILING_METADATA
I0401 15:27:56.181000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_TRAILING_METADATA
I0401 15:27:56.186000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_TRAILING_METADATA
I0401 15:27:56.193000000 22696 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:56.197000000 22696 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:56.200000000 22696 src/core/lib/surface/call_details.cc:31]
grpc_call_details_init(cd=0000000006BA0970)
I0401 15:27:56.200000000 14312 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000005E15330, nops=1,
tag=0000000006B58C50, reserved=0000000000000000)
I0401 15:27:56.213000000 14312 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_MESSAGE ptr=0000000006B9BB20
I0401 15:27:56.217000000 14312 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_MESSAGE
I0401 15:27:56.221000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_MESSAGE
I0401 15:27:56.227000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_MESSAGE
I0401 15:27:56.231000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_MESSAGE
I0401 15:27:56.236000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_MESSAGE
I0401 15:27:56.241000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_MESSAGE
I0401 15:27:56.246000000 14312 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B58C50, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE1140, storage=0000000006BE11B8)
I0401 15:27:56.204000000 22696 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=0000000006B541F8)
I0401 15:27:56.260000000 22696 src/core/lib/surface/server.cc:1487]
grpc_server_request_call(server=0000000006A1F830, call=0000000006B9EFE0,
details=0000000006BA0970, initial_metadata=0000000006B541F8,
cq_bound_to_call=0000000006AAA9D0, cq_for_notification=0000000006AAA9D0,
tag=0000000006B541C8)
I0401 15:27:56.271000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 471000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.281000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B58C50 OK
II0n GRPCCall4
01 15:27:56.287000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 487000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.297000000 14312 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=000000000620B120, nops=3,
tag=0000000006B58C50, reserved=0000000000000000)
I0401 15:27:56.304000000 14312 src/core/lib/surface/call.cc:1511] ops[0]:
SEND_STATUS_FROM_SERVER status=0 details=(nil)
I0401 15:27:56.309000000 14312 src/core/lib/surface/call.cc:1511] ops[1]:
SEND_INITIAL_METADATA(nil)
I0401 15:27:56.313000000 14312 src/core/lib/surface/call.cc:1511] ops[2]:
SEND_MESSAGE ptr=0000000006BE2280
I0401 15:27:56.317000000 14312 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:56.328000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:56.344000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:56.361000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:56.373000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: SEND_INITIAL_METADATA{key=3a 73 74 61
74 75 73 ':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70
65 'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63
'application/grpc'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=}
I0401 15:27:56.393000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: SEND_INITIAL_METADATA{key=3a 73 74 61 74 75 73
':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70 65
'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63
'application/grpc', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69
6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74
65 2c 67 7a 69 70 'identity,deflate,gzip', key=61 63 63 65 70 74 2d 65 6e 63 6f
64 69 6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70
'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=}
I0401 15:27:56.427000000 14312 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B58BF8, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE0FF0, storage=0000000006BE1068)
I0401 15:27:56.436000000 14312 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B58C50, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE1290, storage=0000000006BE1308)
I0401 15:27:56.436000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B58BF8 OK
I0401 15:27:56.446000000 14312 src/core/lib/surface/call_details.cc:38]
grpc_call_details_destroy(cd=0000000006BA05F0)
I0401 15:27:56.452000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 652000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.457000000 14312 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:56.467000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B58C50 OK
I0401 15:27:56.481000000 22696 src/core/lib/surface/call.cc:562]
grpc_call_unref(c=0000000006BDE7F0)
I0401 15:27:56.484000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006BDF378)
I0401 15:27:56.490000000 22696 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:56.493000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 693000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.696000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 896000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.867000000 22696 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_INITIAL_METADATA ptr=0000000006BDF378
I0401 15:27:56.872000000 22696 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_INITIAL_METADATA
I0401 15:27:56.879000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_INITIAL_METADATA
I0401 15:27:56.884000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_INITIAL_METADATA
I0401 15:27:56.889000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_INITIAL_METADATA
I0401 15:27:56.895000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_INITIAL_METADATA
I0401 15:27:56.900000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_INITIAL_METADATA
I0401 15:27:56.905000000 22696 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B541C8, error="No Error",
done=00000000639ACD40, done_arg=0000000004937200, storage=0000000004937238)
I0401 15:27:56.914000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B541C8 OK
I0401 15:27:56.919000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006B541F8)
I0401 15:27:56.925000000 22696 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000005E151B0, nops=1,
tag=0000000006B58A98, reserved=0000000000000000)
I0401 15:27:56.933000000 22696 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_CLOSE_ON_SERVER cancelled=0000000006AFC910
I0401 15:27:56.938000000 22696 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_TRAILING_METADATA
I0401 15:27:56.943000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_TRAILING_METADATA
I0401 15:27:56.948000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_TRAILING_METADATA
I0401 15:27:56.953000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_TRAILING_METADATA
I0401 15:27:56.959000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_TRAILING_METADATA
I0401 15:27:56.965000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_TRAILING_METADATA
I0401 15:27:56.970000000 14312 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000005E156F0, nops=1,
tag=0000000006CFF830, reserved=0000000000000000)
I0401 15:27:56.970000000 22696 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:56.979000000 14312 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_MESSAGE ptr=0000000006B9BA20
I0401 15:27:56.986000000 14312 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_MESSAGE
I0401 15:27:56.990000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_MESSAGE
I0401 15:27:56.996000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_MESSAGE
I0401 15:27:57.000000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_MESSAGE
I0401 15:27:56.982000000 22696 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:57.005000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_MESSAGE
I0401 15:27:57.011000000 22696 src/core/lib/surface/call_details.cc:31]
grpc_call_details_init(cd=0000000006D0D4A0)
I0401 15:27:57.016000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_MESSAGE
I0401 15:27:57.025000000 14312 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006CFF830, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE1140, storage=0000000006BE11B8)
I0401 15:27:57.021000000 22696 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=0000000006B541F8)
I0401 15:27:57.039000000 22696 src/core/lib/surface/server.cc:1487]
grpc_server_request_call(server=0000000006A1F830, call=0000000006D08838,
details=0000000006D0D4A0, initial_metadata=0000000006B541F8,
cq_bound_to_call=0000000006AAA9D0, cq_for_notification=0000000006AAA9D0,
tag=0000000006B541C8)
I0401 15:27:57.051000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 251000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.060000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006CFF830 OK
II0n GRPCCall4
01 15:27:57.066000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 266000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.066000000 14312 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=000000000620A480, nops=3,
tag=0000000006CFF830, reserved=0000000000000000)
I0401 15:27:57.082000000 14312 src/core/lib/surface/call.cc:1511] ops[0]:
SEND_STATUS_FROM_SERVER status=0 details=(nil)
I0401 15:27:57.087000000 14312 src/core/lib/surface/call.cc:1511] ops[1]:
SEND_INITIAL_METADATA(nil)
I0401 15:27:57.090000000 14312 src/core/lib/surface/call.cc:1511] ops[2]:
SEND_MESSAGE ptr=0000000006BE23D0
I0401 15:27:57.095000000 14312 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:57.106000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:57.120000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:57.133000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: SEND_INITIAL_METADATA{}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{key=67 72 70 63 2d
73 74 61 74 75 73 'grpc-status' value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61
67 65 'grpc-message' value=}
I0401 15:27:57.145000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: SEND_INITIAL_METADATA{key=3a 73 74 61
74 75 73 ':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70
65 'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63
'application/grpc'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=}
I0401 15:27:57.166000000 14312 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: SEND_INITIAL_METADATA{key=3a 73 74 61 74 75 73
':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70 65
'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63
'application/grpc', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69
6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74
65 2c 67 7a 69 70 'identity,deflate,gzip', key=61 63 63 65 70 74 2d 65 6e 63 6f
64 69 6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70
'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=30 '0', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message' value=}
I0401 15:27:57.200000000 14312 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B58A98, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE0FF0, storage=0000000006BE1068)
I0401 15:27:57.210000000 14312 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006CFF830, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE1290, storage=0000000006BE1308)
I0401 15:27:57.210000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B58A98 OK
I0401 15:27:57.220000000 14312 src/core/lib/surface/call_details.cc:38]
grpc_call_details_destroy(cd=0000000006BA0970)
I0401 15:27:57.234000000 14312 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:57.228000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 428000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.248000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006CFF830 OK
I0401 15:27:57.254000000 22696 src/core/lib/surface/call.cc:562]
grpc_call_unref(c=0000000006BDE7F0)
I0401 15:27:57.259000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006BDF378)
I0401 15:27:57.264000000 22696 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:57.267000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 467000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.470000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 670000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.672000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 872000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.869000000 22696 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_INITIAL_METADATA ptr=0000000006BDF378
I0401 15:27:57.874000000 22696 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_INITIAL_METADATA
I0401 15:27:57.880000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_INITIAL_METADATA
I0401 15:27:57.886000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_INITIAL_METADATA
I0401 15:27:57.892000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_INITIAL_METADATA
I0401 15:27:57.899000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_INITIAL_METADATA
I0401 15:27:57.909000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_INITIAL_METADATA
I0401 15:27:57.915000000 22696 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B541C8, error="No Error",
done=00000000639ACD40, done_arg=0000000004937200, storage=0000000004937238)
I0401 15:27:57.926000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B541C8 OK
I0401 15:27:57.932000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006B541F8)
I0401 15:27:57.938000000 22696 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000005E15F90, nops=1,
tag=0000000006B58A98, reserved=0000000000000000)
I0401 15:27:57.949000000 22696 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_CLOSE_ON_SERVER cancelled=0000000006AFC910
I0401 15:27:57.955000000 22696 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_TRAILING_METADATA
I0401 15:27:57.960000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_TRAILING_METADATA
I0401 15:27:57.966000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_TRAILING_METADATA
I0401 15:27:57.972000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_TRAILING_METADATA
I0401 15:27:57.978000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_TRAILING_METADATA
I0401 15:27:57.984000000 22696 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_TRAILING_METADATA
I0401 15:27:57.989000000 22696 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:57.993000000 22696 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:57.996000000 22696 src/core/lib/surface/call_details.cc:31]
grpc_call_details_init(cd=0000000006D0D430)
I0401 15:27:57.996000000 11784 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000005E151B0, nops=1,
tag=0000000006CFF830, reserved=0000000000000000)
I0401 15:27:58.001000000 22696 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=0000000006B541F8)
I0401 15:27:58.013000000 22696 src/core/lib/surface/server.cc:1487]
grpc_server_request_call(server=0000000006A1F830, call=0000000006D08988,
details=0000000006D0D430, initial_metadata=0000000006B541F8,
cq_bound_to_call=0000000006AAA9D0, cq_for_notification=0000000006AAA9D0,
tag=0000000006B541C8)
I0401 15:27:58.008000000 11784 src/core/lib/surface/call.cc:1511] ops[0]:
RECV_MESSAGE ptr=0000000006B9BA20
I0401 15:27:58.026000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128878, tv_nsec: 226000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:58.031000000 11784 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: RECV_MESSAGE
I0401 15:27:58.049000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: RECV_MESSAGE
I0401 15:27:58.040000000 22696
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006BDAEA0
server_transport: READY --> SHUTDOWN [close_transport] error=000000000620B080
{"created":"@1554128878.040000000","description":"Endpoint read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2500,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1554128878.040000000","description":"End
of TCP stream","file":"src/core/lib/iomgr/tcp_windows.cc","file_line":231}]}
I0401 15:27:58.055000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: RECV_MESSAGE
I0401 15:27:58.075000000 22696
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000006BDAEA0
server_transport: 00000000069FD510
I0401 15:27:58.080000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: RECV_MESSAGE
I0401 15:27:58.091000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: RECV_MESSAGE
I0401 15:27:58.096000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: RECV_MESSAGE
I0401 15:27:58.101000000 11784 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006CFF830, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE1140, storage=0000000006BE11B8)
I0401 15:27:58.111000000 11784 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B58A98, error="No Error",
done=00000000639A2C20, done_arg=0000000006BE0FF0, storage=0000000006BE1068)
I0401 15:27:58.111000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006CFF830 OK
I0401 15:27:58.125000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128878, tv_nsec: 325000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:58.135000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B58A98 OK
I0401 15:27:58.125000000 11784 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006BDE7F0, ops=0000000006590F50, nops=2,
tag=0000000006CFF830, reserved=0000000000000000)
I0401 15:27:58.149000000 11784 src/core/lib/surface/call.cc:1511] ops[0]:
SEND_INITIAL_METADATA(nil)
I0401 15:27:58.153000000 11784 src/core/lib/surface/call.cc:1511] ops[1]:
SEND_STATUS_FROM_SERVER status=12 details="/GRPCTest/GRPCCall" requires exactly
one request message.(nil)
I0401 15:27:58.161000000 11784 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]: SEND_INITIAL_METADATA{}
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message'
value=22 2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c 22 20 72 65 71
75 69 72 65 73 20 65 78 61 63 74 6c 79 20 6f 6e 65 20 72 65 71 75 65 73 74 20
6d 65 73 73 61 67 65 2e '"/GRPCTest/GRPCCall" requires exactly one request
message.'}
I0401 15:27:58.181000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]: SEND_INITIAL_METADATA{}
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message'
value=22 2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c 22 20 72 65 71
75 69 72 65 73 20 65 78 61 63 74 6c 79 20 6f 6e 65 20 72 65 71 75 65 73 74 20
6d 65 73 73 61 67 65 2e '"/GRPCTest/GRPCCall" requires exactly one request
message.'}
I0401 15:27:58.201000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]: SEND_INITIAL_METADATA{}
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message'
value=22 2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c 22 20 72 65 71
75 69 72 65 73 20 65 78 61 63 74 6c 79 20 6f 6e 65 20 72 65 71 75 65 73 74 20
6d 65 73 73 61 67 65 2e '"/GRPCTest/GRPCCall" requires exactly one request
message.'}
I0401 15:27:58.220000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]: SEND_INITIAL_METADATA{}
SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73 'grpc-status'
value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65 'grpc-message'
value=22 2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c 22 20 72 65 71
75 69 72 65 73 20 65 78 61 63 74 6c 79 20 6f 6e 65 20 72 65 71 75 65 73 74 20
6d 65 73 73 61 67 65 2e '"/GRPCTest/GRPCCall" requires exactly one request
message.'}
I0401 15:27:58.240000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]: SEND_INITIAL_METADATA{key=3a 73 74 61
74 75 73 ':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70
65 'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63
'application/grpc'} SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73
'grpc-status' value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65
'grpc-message' value=22 2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
22 20 72 65 71 75 69 72 65 73 20 65 78 61 63 74 6c 79 20 6f 6e 65 20 72 65 71
75 65 73 74 20 6d 65 73 73 61 67 65 2e '"/GRPCTest/GRPCCall" requires exactly
one request message.'}
I0401 15:27:58.268000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]: SEND_INITIAL_METADATA{key=3a 73 74 61 74 75 73
':status' value=32 30 30 '200', key=63 6f 6e 74 65 6e 74 2d 74 79 70 65
'content-type' value=61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63
'application/grpc', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69
6e 67 'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74
65 2c 67 7a 69 70 'identity,deflate,gzip', key=61 63 63 65 70 74 2d 65 6e 63 6f
64 69 6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70
'identity,gzip'} SEND_TRAILING_METADATA{key=67 72 70 63 2d 73 74 61 74 75 73
'grpc-status' value=31 32 '12', key=67 72 70 63 2d 6d 65 73 73 61 67 65
'grpc-message' value=22 2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
22 20 72 65 71 75 69 72 65 73 20 65 78 61 63 74 6c 79 20 6f 6e 65 20 72 65 71
75 65 73 74 20 6d 65 73 73 61 67 65 2e '"/GRPCTest/GRPCCall" requires exactly
one request message.'}
I0401 15:27:58.310000000 11784 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006CFF830,
error={"created":"@1554128878.310000000","description":"Error in HTTP transport
completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"},
done=00000000639A2C20, done_arg=0000000006BE12D0, storage=0000000006BE1348)
I0401 15:27:58.352000000 11784 src/core/lib/surface/call.cc:615]
OP[server:0000000006BDF270]:
CANCEL:{"created":"@1554128878.310000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"}
I0401 15:27:58.389000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006BDF288]:
CANCEL:{"created":"@1554128878.310000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"}
I0401 15:27:58.426000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:0000000006BDF2A0]:
CANCEL:{"created":"@1554128878.310000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"}
I0401 15:27:58.465000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:0000000006BDF2B8]:
CANCEL:{"created":"@1554128878.310000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"}
I0401 15:27:58.504000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006BDF2D0]:
CANCEL:{"created":"@1554128878.310000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"}
I0401 15:27:58.548000000 11784 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006BDF2E8]:
CANCEL:{"created":"@1554128878.310000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1247,"referenced_errors":[{"created":"@1554128878.310000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1496,"referenced_errors":[{"created":"@1554128878.040000000","description":"RST_STREAM","file":"src/core/ext/transport/chttp2/transport/frame_rst_stream.cc","file_line":102,"grpc_message":"Received
RST_STREAM with error code
2","http2_error":2}]},{"created":"@1554128878.310000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1596}],"target_address":"ipv4:127.0.0.1:59627"}
I0401 15:27:58.586000000 11784 src/core/lib/surface/call_details.cc:38]
grpc_call_details_destroy(cd=0000000006D0D4A0)
I0401 15:27:58.591000000 11784 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:58.586000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128878, tv_nsec: 786000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:58.604000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006CFF830 ERROR
I0401 15:27:58.610000000 22696 src/core/lib/surface/call.cc:562]
grpc_call_unref(c=0000000006BDE7F0)
I0401 15:27:58.614000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006BDF378)
I0401 15:27:58.619000000 22696 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:58.622000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128878, tv_nsec: 822000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:58.823000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128879, tv_nsec: 23000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:59.025000000 22696 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006AAA9D0, deadline=gpr_timespec { tv_sec:
1554128879, tv_nsec: 225000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:59.084000000 19804 src/core/lib/surface/server.cc:1311]
grpc_server_shutdown_and_notify(server=0000000006A1F830, cq=0000000006AAA9D0,
tag=0000000006D0B4C8)
I0401 15:27:59.090000000 19804 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006AAA9D0, tag=0000000006B541C8,
error={"created":"@1554128879.090000000","description":"Server
Shutdown","file":"src/core/lib/surface/server.cc","file_line":1349},
done=00000000639ACD40, done_arg=0000000004937170, storage=00000000049371A8)
I0401 15:27:59.104000000 22696 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006AAA9D0]: OP_COMPLETE: tag:0000000006B541C8 ERROR
I0401 15:27:59.104000000 9040 src/core/lib/surface/server.cc:1378]
grpc_server_cancel_all_calls(server=0000000006A1F830)
I0401 15:27:59.109000000 22696 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000006B541F8)
I0401 15:27:59.120000000 22696 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
I0401 15:27:59.123000000 22696 src/core/lib/surface/call_details.cc:38]
grpc_call_details_destroy(cd=0000000006D0D430)
I0401 15:27:59.133000000 22696 src/core/lib/surface/init.cc:160]
grpc_shutdown(void)
C:\Users\umqpx3\Downloads\ReproduceGRPCIssue\Services\src>ReproduceGRPCIssue\Services\src>python GRPCclient.py
D0401 15:27:55.480000000 11152
src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.cc:334]
Using native dns resolver
I0401 15:27:55.486000000 11152 src/core/lib/surface/init.cc:155] grpc_init(void)
I0401 15:27:55.490000000 11152
src/core/ext/transport/chttp2/client/insecure/channel_create.cc:91]
grpc_insecure_channel_create(target=127.0.0.1:50051, args=0000000006B00078,
reserved=0000000000000000)
I0401 15:27:55.500000000 11152 src/core/lib/surface/channel.cc:285]
grpc_channel_get_target(channel=00000000069EB890)
I0401 15:27:55.506000000 11152 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I0401 15:27:55.513000000 11152 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
Hello World!
I0401 15:27:55.668000000 1364 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I0401 15:27:55.675000000 1364 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=00000000069BEF30)
I0401 15:27:55.680000000 1364 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=00000000069B6B08)
I0401 15:27:55.684000000 1364 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006AD1E00, ops=0000000006994A80, nops=6,
tag=0000000006C7B308, reserved=0000000000000000)
I0401 15:27:55.692000000 1364 src/core/lib/surface/call.cc:1511] ops[0]:
SEND_INITIAL_METADATA(nil)
I0401 15:27:55.695000000 1364 src/core/lib/surface/call.cc:1511] ops[1]:
SEND_MESSAGE ptr=0000000006147200
I0401 15:27:55.699000000 1364 src/core/lib/surface/call.cc:1511] ops[2]:
SEND_CLOSE_FROM_CLIENT
I0401 15:27:55.702000000 1364 src/core/lib/surface/call.cc:1511] ops[3]:
RECV_INITIAL_METADATA ptr=00000000069BEF30
I0401 15:27:55.707000000 1364 src/core/lib/surface/call.cc:1511] ops[4]:
RECV_MESSAGE ptr=0000000006AD8320
I0401 15:27:55.711000000 1364 src/core/lib/surface/call.cc:1511] ops[5]:
RECV_STATUS_ON_CLIENT metadata=00000000069B6B08 status=00000000069B6B20
details=00000000069B6B28
I0401 15:27:55.717000000 1364 src/core/lib/surface/call.cc:615]
OP[client-channel:0000000006AD2880]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0401 15:27:55.731000000 1364 src/core/lib/transport/connectivity_state.cc:81]
CONWATCH: 00000000069EBA68 request_router: get IDLE
D0401 15:27:55.737000000 1364
src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.cc:275]
Start resolving.
I0401 15:27:55.743000000 1364 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143A10, deadline=gpr_timespec { tv_sec:
1554128875, tv_nsec: 943000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.743000000 21952 src/core/lib/transport/connectivity_state.cc:92]
CONWATCH: 0000000006996BE0 subchannel: get IDLE
I0401 15:27:55.758000000 21952 src/core/lib/transport/connectivity_state.cc:92]
CONWATCH: 00000000066E0408 pick_first: get IDLE
I0401 15:27:55.764000000 21952
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 00000000066E0408
pick_first: from IDLE [cur=IDLE] notify=00000000061403C0
I0401 15:27:55.771000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000069EBA68
request_router: IDLE --> IDLE [resolver_result] error=0000000000000000 "No
Error"
I0401 15:27:55.778000000 21952 src/core/lib/transport/connectivity_state.cc:81]
CONWATCH: 00000000069EBA68 request_router: get IDLE
I0401 15:27:55.784000000 21952 src/core/lib/transport/connectivity_state.cc:92]
CONWATCH: 0000000006996BE0 subchannel: get IDLE
I0401 15:27:55.789000000 21952
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000006996BE0
subchannel: from IDLE [cur=IDLE] notify=0000000006140038
I0401 15:27:55.795000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006996BE0
subchannel: IDLE --> CONNECTING [connecting] error=0000000000000000 "No Error"
I0401 15:27:55.803000000 21952
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000006996BE0
subchannel: 0000000006140038
I0401 15:27:55.809000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006996C00
subchannel: IDLE --> CONNECTING [connecting] error=0000000000000000 "No Error"
I0401 15:27:55.817000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000066E0408
pick_first: IDLE --> CONNECTING [connecting_changed] error=0000000000000000 "No
Error"
I0401 15:27:55.817000000 1364
src/core/ext/filters/client_channel/subchannel.cc:1025] New connected
subchannel at 0000000006A63A00 for subchannel 0000000006996B00
I0401 15:27:55.834000000 1364
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006996BE0
subchannel: CONNECTING --> READY [subchannel_connected] error=0000000000000000
"No Error"
I0401 15:27:55.841000000 1364
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006996C00
subchannel: CONNECTING --> READY [subchannel_connected] error=0000000000000000
"No Error"
I0401 15:27:55.852000000 1364
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000006CA53A0
client_transport: from READY [cur=READY] notify=000000000698E560
I0401 15:27:55.827000000 21952
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 00000000066E0408
pick_first: 00000000061403C0
I0401 15:27:55.865000000 21952
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000006996BE0
subchannel: from CONNECTING [cur=READY] notify=00000000061402B8
I0401 15:27:55.871000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000069EBA68
request_router: IDLE --> CONNECTING [lb_changed] error=0000000000000000 "No
Error"
I0401 15:27:55.882000000 21952
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 00000000066E0408
pick_first: from CONNECTING [cur=CONNECTING] notify=00000000061403C0
I0401 15:27:55.889000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000066E0408
pick_first: CONNECTING --> READY [subchannel_ready] error=0000000000000000 "No
Error"
I0401 15:27:55.896000000 21952
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 00000000066E0408
pick_first: 00000000061403C0
I0401 15:27:55.901000000 21952
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000006996BE0
subchannel: from READY [cur=READY] notify=00000000061405B8
I0401 15:27:55.907000000 21952
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000069EBA68
request_router: CONNECTING --> READY [lb_changed] error=0000000000000000 "No
Error"
I0401 15:27:55.915000000 21952
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 00000000066E0408
pick_first: from READY [cur=READY] notify=00000000061403C0
I0401 15:27:55.921000000 21952
src/core/ext/filters/client_channel/subchannel.cc:175]
OP[authority:0000000006AD3B30]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0401 15:27:55.937000000 21952 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006AD3B48]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f
72 69 74 79 ':authority' value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31
'127.0.0.1:50051', key=3a 70 61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74
2f 47 52 50 43 43 61 6c 6c '/GRPCTest/GRPCCall'}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:55.943000000 1364 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143A10, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 143000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:55.956000000 21952 src/core/lib/channel/channel_stack.cc:226]
OP[http-client:0000000006AD3B60]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f
72 69 74 79 ':authority' value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31
'127.0.0.1:50051', key=3a 70 61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74
2f 47 52 50 43 43 61 6c 6c '/GRPCTest/GRPCCall'}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:55.984000000 21952 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006AD3B78]: SEND_INITIAL_METADATA{key=3a 73 63 68
65 6d 65 ':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method'
value=50 4f 53 54 'POST', key=3a 61 75 74 68 6f 72 69 74 79 ':authority'
value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051', key=3a 70
61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers',
key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63
61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65
6e 74 'user-agent' value=67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 39 2e 30
20 67 72 70 63 2d 63 2f 37 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68
74 74 70 32 3b 20 67 6f 6c 64 29 'grpc-python/1.19.0 grpc-c/7.0.0 (windows;
chttp2; gold)'} SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:56.029000000 21952 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006AD3B90]: SEND_INITIAL_METADATA{key=3a 73 63 68 65 6d 65
':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method' value=50
4f 53 54 'POST', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=31 32 37
2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051', key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers',
key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63
61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65
6e 74 'user-agent' value=67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 39 2e 30
20 67 72 70 63 2d 63 2f 37 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68
74 74 70 32 3b 20 67 6f 6c 64 29 'grpc-python/1.19.0 grpc-c/7.0.0 (windows;
chttp2; gold)', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67
'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c
67 7a 69 70 'identity,deflate,gzip', key=61 63 63 65 70 74 2d 65 6e 63 6f 64 69
6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70
'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:56.145000000 1364 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143A10, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 345000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.346000000 1364 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143A10, deadline=gpr_timespec { tv_sec:
1554128876, tv_nsec: 546000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:56.436000000 1364 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006143A10, tag=0000000006C7B308, error="No Error",
done=00000000639A2C20, done_arg=0000000006AD2ED0, storage=0000000006AD2F48)
I0401 15:27:56.446000000 1364 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006143A10]: OP_COMPLETE: tag:0000000006C7B308 OK
I0401 15:27:56.452000000 1364 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=00000000069BEF30)
I0401 15:27:56.457000000 1364 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=00000000069B6B08)
I0401 15:27:56.463000000 1364 src/core/lib/surface/call.cc:562]
grpc_call_unref(c=0000000006AD1E00)
I0401 15:27:56.467000000 1364 src/core/lib/surface/completion_queue.cc:1386]
grpc_completion_queue_shutdown(cq=0000000006143A10)
I0401 15:27:56.475000000 1364 src/core/lib/surface/completion_queue.cc:1392]
grpc_completion_queue_destroy(cq=0000000006143A10)
I0401 15:27:56.480000000 1364 src/core/lib/surface/completion_queue.cc:1386]
grpc_completion_queue_shutdown(cq=0000000006143A10)
Hello World!
I0401 15:27:56.636000000 7572 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I0401 15:27:56.643000000 7572 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=00000000069BEF30)
I0401 15:27:56.649000000 7572 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=00000000069B6B08)
I0401 15:27:56.654000000 7572 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006B17FD0, ops=0000000006994A80, nops=6,
tag=0000000006C7B308, reserved=0000000000000000)
I0401 15:27:56.662000000 7572 src/core/lib/surface/call.cc:1511] ops[0]:
SEND_INITIAL_METADATA(nil)
I0401 15:27:56.666000000 7572 src/core/lib/surface/call.cc:1511] ops[1]:
SEND_MESSAGE ptr=0000000006CA8E80
I0401 15:27:56.671000000 7572 src/core/lib/surface/call.cc:1511] ops[2]:
SEND_CLOSE_FROM_CLIENT
I0401 15:27:56.674000000 7572 src/core/lib/surface/call.cc:1511] ops[3]:
RECV_INITIAL_METADATA ptr=00000000069BEF30
I0401 15:27:56.680000000 7572 src/core/lib/surface/call.cc:1511] ops[4]:
RECV_MESSAGE ptr=0000000006AA8720
I0401 15:27:56.684000000 7572 src/core/lib/surface/call.cc:1511] ops[5]:
RECV_STATUS_ON_CLIENT metadata=00000000069B6B08 status=00000000069B6B20
details=00000000069B6B28
I0401 15:27:56.692000000 7572 src/core/lib/surface/call.cc:615]
OP[client-channel:0000000006B18A50]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0401 15:27:56.707000000 7572 src/core/lib/transport/connectivity_state.cc:81]
CONWATCH: 00000000069EBA68 request_router: get READY
I0401 15:27:56.712000000 7572
src/core/ext/filters/client_channel/subchannel.cc:175]
OP[authority:0000000006B192F0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0401 15:27:56.727000000 7572 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006B19308]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f
72 69 74 79 ':authority' value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31
'127.0.0.1:50051', key=3a 70 61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74
2f 47 52 50 43 43 61 6c 6c '/GRPCTest/GRPCCall'}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:56.746000000 7572 src/core/lib/channel/channel_stack.cc:226]
OP[http-client:0000000006B19320]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f
72 69 74 79 ':authority' value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31
'127.0.0.1:50051', key=3a 70 61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74
2f 47 52 50 43 43 61 6c 6c '/GRPCTest/GRPCCall'}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:56.765000000 7572 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006B19338]: SEND_INITIAL_METADATA{key=3a 73 63 68
65 6d 65 ':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method'
value=50 4f 53 54 'POST', key=3a 61 75 74 68 6f 72 69 74 79 ':authority'
value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051', key=3a 70
61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers',
key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63
61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65
6e 74 'user-agent' value=67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 39 2e 30
20 67 72 70 63 2d 63 2f 37 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68
74 74 70 32 3b 20 67 6f 6c 64 29 'grpc-python/1.19.0 grpc-c/7.0.0 (windows;
chttp2; gold)'} SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:56.810000000 7572 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006B19350]: SEND_INITIAL_METADATA{key=3a 73 63 68 65 6d 65
':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method' value=50
4f 53 54 'POST', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=31 32 37
2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051', key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers',
key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63
61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65
6e 74 'user-agent' value=67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 39 2e 30
20 67 72 70 63 2d 63 2f 37 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68
74 74 70 32 3b 20 67 6f 6c 64 29 'grpc-python/1.19.0 grpc-c/7.0.0 (windows;
chttp2; gold)', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67
'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c
67 7a 69 70 'identity,deflate,gzip', key=61 63 63 65 70 74 2d 65 6e 63 6f 64 69
6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70
'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:56.867000000 7572 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143E30, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 67000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.068000000 7572 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143E30, deadline=gpr_timespec { tv_sec:
1554128877, tv_nsec: 268000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.210000000 7572 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006143E30, tag=0000000006C7B308, error="No Error",
done=00000000639A2C20, done_arg=0000000006B190A0, storage=0000000006B19118)
I0401 15:27:57.224000000 7572 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006143E30]: OP_COMPLETE: tag:0000000006C7B308 OK
I0401 15:27:57.235000000 7572 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=00000000069BEF30)
I0401 15:27:57.242000000 7572 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=00000000069B6B08)
I0401 15:27:57.248000000 7572 src/core/lib/surface/call.cc:562]
grpc_call_unref(c=0000000006B17FD0)
I0401 15:27:57.252000000 7572 src/core/lib/surface/completion_queue.cc:1386]
grpc_completion_queue_shutdown(cq=0000000006143E30)
I0401 15:27:57.258000000 7572 src/core/lib/surface/completion_queue.cc:1392]
grpc_completion_queue_destroy(cq=0000000006143E30)
I0401 15:27:57.264000000 7572 src/core/lib/surface/completion_queue.cc:1386]
grpc_completion_queue_shutdown(cq=0000000006143E30)
Hello World!
I0401 15:27:57.641000000 6176 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I0401 15:27:57.649000000 6176 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=00000000069BEF30)
I0401 15:27:57.657000000 6176 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=00000000069B6B08)
I0401 15:27:57.662000000 6176 src/core/lib/surface/call.cc:1905]
grpc_call_start_batch(call=0000000006B17FD0, ops=0000000006994A80, nops=6,
tag=0000000006C7B308, reserved=0000000000000000)
I0401 15:27:57.671000000 6176 src/core/lib/surface/call.cc:1511] ops[0]:
SEND_INITIAL_METADATA(nil)
I0401 15:27:57.678000000 6176 src/core/lib/surface/call.cc:1511] ops[1]:
SEND_MESSAGE ptr=0000000006A69C20
I0401 15:27:57.682000000 6176 src/core/lib/surface/call.cc:1511] ops[2]:
SEND_CLOSE_FROM_CLIENT
I0401 15:27:57.686000000 6176 src/core/lib/surface/call.cc:1511] ops[3]:
RECV_INITIAL_METADATA ptr=00000000069BEF30
I0401 15:27:57.692000000 6176 src/core/lib/surface/call.cc:1511] ops[4]:
RECV_MESSAGE ptr=0000000006AA8720
I0401 15:27:57.696000000 6176 src/core/lib/surface/call.cc:1511] ops[5]:
RECV_STATUS_ON_CLIENT metadata=00000000069B6B08 status=00000000069B6B20
details=00000000069B6B28
I0401 15:27:57.703000000 6176 src/core/lib/surface/call.cc:615]
OP[client-channel:0000000006B18A50]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0401 15:27:57.717000000 6176 src/core/lib/transport/connectivity_state.cc:81]
CONWATCH: 00000000069EBA68 request_router: get READY
I0401 15:27:57.724000000 6176
src/core/ext/filters/client_channel/subchannel.cc:175]
OP[authority:0000000006B192F0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall'} SEND_MESSAGE:flags=0x00000000:len=0
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0401 15:27:57.738000000 6176 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:0000000006B19308]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f
72 69 74 79 ':authority' value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31
'127.0.0.1:50051', key=3a 70 61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74
2f 47 52 50 43 43 61 6c 6c '/GRPCTest/GRPCCall'}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:57.759000000 6176 src/core/lib/channel/channel_stack.cc:226]
OP[http-client:0000000006B19320]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f
72 69 74 79 ':authority' value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31
'127.0.0.1:50051', key=3a 70 61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74
2f 47 52 50 43 43 61 6c 6c '/GRPCTest/GRPCCall'}
SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:57.777000000 6176 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:0000000006B19338]: SEND_INITIAL_METADATA{key=3a 73 63 68
65 6d 65 ':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method'
value=50 4f 53 54 'POST', key=3a 61 75 74 68 6f 72 69 74 79 ':authority'
value=31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051', key=3a 70
61 74 68 ':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers',
key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63
61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65
6e 74 'user-agent' value=67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 39 2e 30
20 67 72 70 63 2d 63 2f 37 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68
74 74 70 32 3b 20 67 6f 6c 64 29 'grpc-python/1.19.0 grpc-c/7.0.0 (windows;
chttp2; gold)'} SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:57.818000000 6176 src/core/lib/channel/channel_stack.cc:226]
OP[connected:0000000006B19350]: SEND_INITIAL_METADATA{key=3a 73 63 68 65 6d 65
':scheme' value=68 74 74 70 'http', key=3a 6d 65 74 68 6f 64 ':method' value=50
4f 53 54 'POST', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=31 32 37
2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051', key=3a 70 61 74 68
':path' value=2f 47 52 50 43 54 65 73 74 2f 47 52 50 43 43 61 6c 6c
'/GRPCTest/GRPCCall', key=74 65 'te' value=74 72 61 69 6c 65 72 73 'trailers',
key=63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type' value=61 70 70 6c 69 63
61 74 69 6f 6e 2f 67 72 70 63 'application/grpc', key=75 73 65 72 2d 61 67 65
6e 74 'user-agent' value=67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 39 2e 30
20 67 72 70 63 2d 63 2f 37 2e 30 2e 30 20 28 77 69 6e 64 6f 77 73 3b 20 63 68
74 74 70 32 3b 20 67 6f 6c 64 29 'grpc-python/1.19.0 grpc-c/7.0.0 (windows;
chttp2; gold)', key=67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67
'grpc-accept-encoding' value=69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c
67 7a 69 70 'identity,deflate,gzip', key=61 63 63 65 70 74 2d 65 6e 63 6f 64 69
6e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70
'identity,gzip'} SEND_MESSAGE:flags=0x00000000:len=0 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0401 15:27:57.869000000 6176 src/core/lib/surface/completion_queue.cc:959]
grpc_completion_queue_next(cq=0000000006143750, deadline=gpr_timespec { tv_sec:
1554128878, tv_nsec: 69000000, clock_type: 1 }, reserved=0000000000000000)
I0401 15:27:57.879000000 6176 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=0000000006143750, tag=0000000006C7B308, error="No Error",
done=00000000639A2C20, done_arg=0000000006B190A0, storage=0000000006B19118)
I0401 15:27:57.890000000 6176
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006CA53A0
client_transport: READY --> SHUTDOWN [close_transport] error=0000000006189280
{"created":"@1554128877.879000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":587,"referenced_errors":[{"created":"@1554128877.879000000","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2500,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1554128877.879000000","description":"The
I/O operation has been aborted because of either a thread exit or an
application
request.\r\n","file":"src/core/lib/iomgr/tcp_windows.cc","file_line":197}]}]}
I0401 15:27:57.929000000 6176
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000006CA53A0
client_transport: 000000000698E560
I0401 15:27:57.935000000 6176
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006996BE0
subchannel: READY --> TRANSIENT_FAILURE [reflect_child] error=0000000006189280
{"created":"@1554128877.879000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":587,"referenced_errors":[{"created":"@1554128877.879000000","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2500,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1554128877.879000000","description":"The
I/O operation has been aborted because of either a thread exit or an
application
request.\r\n","file":"src/core/lib/iomgr/tcp_windows.cc","file_line":197}]}]}
I0401 15:27:57.970000000 6176
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000006996BE0
subchannel: 00000000061405B8
I0401 15:27:57.978000000 6176
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000006996C00
subchannel: READY --> TRANSIENT_FAILURE [reflect_child] error=0000000006189280
{"created":"@1554128877.879000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":587,"referenced_errors":[{"created":"@1554128877.879000000","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2500,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1554128877.879000000","description":"The
I/O operation has been aborted because of either a thread exit or an
application
request.\r\n","file":"src/core/lib/iomgr/tcp_windows.cc","file_line":197}]}]}
I0401 15:27:58.009000000 6176
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000066E0408
pick_first: READY --> IDLE [selected_changed+reresolve] error=0000000000000000
"No Error"
I0401 15:27:58.017000000 6176
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 00000000066E0408
pick_first: 00000000061403C0
I0401 15:27:58.025000000 6176
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000069EBA68
request_router: READY --> IDLE [lb_changed] error=0000000000000000 "No Error"
I0401 15:27:58.032000000 6176
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 00000000066E0408
pick_first: from IDLE [cur=IDLE] notify=00000000061403C0
D0401 15:27:58.040000000 6176
src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.cc:275]
Start resolving.
I0401 15:27:58.050000000 6176 src/core/lib/surface/completion_queue.cc:1059]
RETURN_EVENT[0000000006143750]: OP_COMPLETE: tag:0000000006C7B308 OK
I0401 15:27:58.056000000 6176 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=00000000069BEF30)
I0401 15:27:58.061000000 6176 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=00000000069B6B08)
I0401 15:27:58.050000000 21952 src/core/lib/transport/connectivity_state.cc:92]
CONWATCH: 0000000006996BE0 subchannel: get TRANSIENT_FAILURE
I0401 15:27:58.067000000 6176 src/core/lib/surface/call.cc:562]
grpc_call_unref(c=0000000006B17FD0)
I0401 15:27:58.077000000 6176 src/core/lib/surface/completion_queue.cc:1386]
grpc_completion_queue_shutdown(cq=0000000006143750)
I0401 15:27:58.082000000 6176 src/core/lib/surface/completion_queue.cc:1392]
grpc_completion_queue_destroy(cq=0000000006143750)
I0401 15:27:58.087000000 6176 src/core/lib/surface/completion_queue.cc:1386]
grpc_completion_queue_shutdown(cq=0000000006143750)
Exception in thread Thread-3:
Traceback (most recent call last):
File "C:\Python27\lib\threading.py", line 801, in __bootstrap_inner
self.run()
File "GRPCclient.py", line 26, in run
grpc_stub.GRPCCall()
File "GRPCclient.py", line 16, in GRPCCall
self.stub.GRPCCall(request)
File "C:\Python27\lib\site-packages\grpc\_channel.py", line 549, in __call__
return _end_unary_response_blocking(state, call, False, None)
File "C:\Python27\lib\site-packages\grpc\_channel.py", line 466, in
_end_unary_response_blocking
raise _Rendezvous(state, None, None, deadline)
_Rendezvous: <_Rendezvous of RPC that terminated with:
status = StatusCode.UNKNOWN
details = "Stream removed"
debug_error_string =
"{"created":"@1554128877.879000000","description":"Error received from
peer","file":"src/core/lib/surface/call.cc","file_line":1039,"grpc_message":"Stream
removed","grpc_status":2}"
>
C:\Users\umqpx3\Downloads\ReproduceGRPCIssue\Services\src>
