Hi,
I've got a Python server and two clients (Python and C++) running on the
same machine (all listening and writing to 127.0.0.1:50051) and I'm hitting
the following Python exception on my client:
File "C:\Python27\lib\site-packages\grpc\_channel.py", line 547, 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"
The Python grpc version is 1.17.1
The C++ grpc version is 1.14.1
The execution flow is as follows:
1.- Server starts
2.- Python client starts and creates an insecure_channel on
127.0.0.1:50051. It keeps that channel for the rest of the execution.
Then in a loop:
3.- Python client calls method on Server.
4.- Server runs method and returns.
5.- C++ client starts and creates an insecure_channel on 127.0.0.1:50051.
It doesn't communicate with the server.
6.- C++ client finishes so the shared_ptr to the channel created above is
released.
7.- Goes back to step 3
I get the exception on step 3/4 above but not every time. It usually
happens after 3 or 4 loops from step 3 to step 6. It looks like it's timing
dependent.
When I get the exception, the server sometimes has managed to execute the
call and sometimes it doesn't receive the request from the Python client.
I've attached server and python client debug traces. The C++ one is a bit
trickier to get as it's run as a subprocess and its output is not available
but let me know if you need it and I'll try to get it.
My suspicion is that the Python client channel gets corrupted/closed when
the C++ client finishes and presumably destroys its channel. Could that be
possible?
Please let me know if you need any more info.
Thanks,
Alex.
--
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 [email protected].
To post to this group, send email to [email protected].
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/8f91217f-dfd6-41d3-8380-80edc61f30ce%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
I0219 16:03:34.336000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:656] parsing initial_metadata
I0219 16:03:34.355000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode: ':scheme:
http', elem_interned=1 [3], k_interned=1, v_interned=1
I0219 16:03:34.372000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
:scheme: 68 74 74 70 'http'
I0219 16:03:34.384000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode: ':method:
POST', elem_interned=1 [3], k_interned=1, v_interned=1
I0219 16:03:34.402000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
:method: 50 4f 53 54 'POST'
I0219 16:03:34.414000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode:
':authority: 127.0.0.1:50051', elem_interned=1 [1], k_interned=1, v_interned=1
I0219 16:03:34.433000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
:authority: 31 32 37 2e 30 2e 30 2e 31 3a 35 30 30 35 31 '127.0.0.1:50051'
I0219 16:03:34.451000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode: ':path:
/DM.DataManagement/AddParameterValue', elem_interned=0 [2], k_interned=1,
v_interned=0
I0219 16:03:34.473000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR: :path:
2f 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50 61 72 61
6d 65 74 65 72 56 61 6c 75 65 '/DM.DataManagement/AddParameterValue'
I0219 16:03:34.498000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode: 'te:
trailers', elem_interned=1 [3], k_interned=1, v_interned=1
I0219 16:03:34.515000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR: te: 74
72 61 69 6c 65 72 73 'trailers'
I0219 16:03:34.538000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode:
'content-type: application/grpc', elem_interned=1 [3], k_interned=1,
v_interned=1
I0219 16:03:34.557000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
content-type: 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'
I0219 16:03:34.576000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode:
'user-agent: grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2; gizmo)',
elem_interned=1 [1], k_interned=1, v_interned=1
I0219 16:03:34.601000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
user-agent: 67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 31 37 2e 31 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 69 7a 6d 6f 29 'grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2;
gizmo)'
I0219 16:03:34.644000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode:
'grpc-accept-encoding: identity,deflate,gzip', elem_interned=1 [3],
k_interned=1, v_interned=1
I0219 16:03:34.666000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
grpc-accept-encoding: 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a
69 70 'identity,deflate,gzip'
I0219 16:03:34.701000000 14328
src/core/ext/transport/chttp2/transport/hpack_parser.cc:636] Decode:
'accept-encoding: identity,gzip', elem_interned=1 [3], k_interned=1,
v_interned=1
I0219 16:03:34.721000000 14328
src/core/ext/transport/chttp2/transport/parsing.cc:407] HTTP:5:HDR:SVR:
accept-encoding: 69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip'
I0219 16:03:34.738000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:0000000003584F20 SERVER state IDLE -> WRITING [PING_RESPONSE]
I0219 16:03:34.755000000 14328 src/core/lib/surface/call.cc:613]
OP[server:00000000035E0A60]: RECV_INITIAL_METADATA
I0219 16:03:34.774000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:00000000035E0A78]: RECV_INITIAL_METADATA
I0219 16:03:34.786000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:00000000035E0A90]: RECV_INITIAL_METADATA
I0219 16:03:34.807000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:00000000035E0AA8]: RECV_INITIAL_METADATA
I0219 16:03:34.819000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:00000000035E0AC0]: RECV_INITIAL_METADATA
I0219 16:03:34.832000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[connected:00000000035E0AD8]: RECV_INITIAL_METADATA
I0219 16:03:34.844000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1702]
perform_stream_op[s=00000000035E1A40]: RECV_INITIAL_METADATA
I0219 16:03:34.860000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1398]
perform_stream_op_locked: RECV_INITIAL_METADATA; on_complete = 0000000000000000
I0219 16:03:34.879000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 00000000035E2588 refs=0 flags=0x0000
desc=op->on_complete err="No Error" write_state=WRITING
I0219 16:03:34.905000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:2596]
ipv4:127.0.0.1:61343: Start BDP ping
err={"created":"@1550592214.905000000","description":"Endpoint read failed",
"file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"occurred_during_write":1,"referenced_errors":[{"created":"@1550592214.905000000","description":"End
of TCP stream","file":"src/core/lib/iomgr/tcp_windows.cc","file_line":232}]}
I0219 16:03:34.968000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:2609]
ipv4:127.0.0.1:61343: Complete BDP ping
err={"created":"@1550592214.905000000","description":"Endpoint read failed",
"file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"occurred_during_write":1,"referenced_errors":[{"created":"@1550592214.905000000","description":"End
of TCP stream","file":"src/core/lib/iomgr/tcp_windows.cc","file_line":232}]}
I0219 16:03:35.027000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:0000000003584F20 SERVER state WRITING -> WRITING [begin write in current
thread]
I0219 16:03:35.046000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:0000000003584F20 SERVER state WRITING -> IDLE [finish writing]
I0219 16:03:35.063000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:2790] set
connectivity_state=4
I0219 16:03:35.075000000 14328
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000003585268
server_transport: READY --> SHUTDOWN [close_transport] error=00000000033CD0D0
{"created":"@1550592214.905000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":609,"referenced_errors":[{"created":"@1550592214.905000000","description":"Endpoint
read failed",
"file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"grpc_status":14,"occurred_during_write":1,"referenced_errors":[{"created":"@1550592214.905000000","description":"End
of TCP stream", "file":"src/core/lib/iomgr/tcp_windows.cc","file_line":232}]}]}
I0219 16:03:35.158000000 14328
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000003585268
server_transport: 00000000034DEE00
I0219 16:03:35.172000000 14328 src/core/lib/surface/server.cc:467] Disconnected
client: {"created":"@1550592214.905000000","description":"Delayed close due to
in-progress write",
"file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":609,"referenced_errors":[{"created":"@1550592214.905000000","description":"Endpoint
read failed",
"file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"grpc_status":14,"occurred_during_write":1,"referenced_errors":[{"created":"@1550592214.905000000","description":"End
of TCP stream", "file":"src/core/lib/iomgr/tcp_windows.cc","file_line":232}]}]}
I0219 16:03:35.274000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1866]
perform_transport_op[t=0000000003584F20]:
SET_ACCEPT_STREAM:0000000000000000(0000000000000000,...)
I0219 16:03:35.297000000 14328 src/core/lib/surface/call.cc:613]
OP[server:00000000035E0A60]: RECV_TRAILING_METADATA
I0219 16:03:35.316000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:00000000035E0A78]: RECV_TRAILING_METADATA
I0219 16:03:35.338000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:00000000035E0A90]: RECV_TRAILING_METADATA
I0219 16:03:35.359000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:00000000035E0AA8]: RECV_TRAILING_METADATA
I0219 16:03:35.371000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:00000000035E0AC0]: RECV_TRAILING_METADATA
I0219 16:03:35.384000000 14328 src/core/lib/channel/channel_stack.cc:226]
OP[connected:00000000035E0AD8]: RECV_TRAILING_METADATA
I0219 16:03:35.396000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1702]
perform_stream_op[s=00000000035E1A40]: RECV_TRAILING_METADATA
I0219 16:03:35.413000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1398]
perform_stream_op_locked: RECV_TRAILING_METADATA; on_complete =
0000000000000000
I0219 16:03:35.442000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 00000000035E27F8 refs=0 flags=0x0000
desc=op->on_complete err="No Error" write_state=IDLE
D0219 16:03:35.467000000 14328 src/core/lib/surface/call.cc:719]
set_final_status SVR
D0219 16:03:35.475000000 14328 src/core/lib/surface/call.cc:720] "No Error"
I0219 16:03:35.482000000 12760 src/core/lib/surface/call.cc:613]
OP[server:00000000035E0A60]: RECV_MESSAGE
I0219 16:03:35.491000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:00000000035E0A78]: RECV_MESSAGE
I0219 16:03:35.503000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:00000000035E0A90]: RECV_MESSAGE
I0219 16:03:35.514000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:00000000035E0AA8]: RECV_MESSAGE
I0219 16:03:35.525000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:00000000035E0AC0]: RECV_MESSAGE
I0219 16:03:35.537000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[connected:00000000035E0AD8]: RECV_MESSAGE
I0219 16:03:35.548000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1702]
perform_stream_op[s=00000000035E1A40]: RECV_MESSAGE
I0219 16:03:35.563000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1398]
perform_stream_op_locked: RECV_MESSAGE; on_complete = 0000000000000000
I0219 16:03:35.584000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 00000000035E2948 refs=0 flags=0x0000
desc=op->on_complete err="No Error" write_state=IDLE
I0219 16:03:35.609000000 12760 src/core/lib/surface/call.cc:613]
OP[server:00000000035E0A60]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50
61 72 61 6d 65 74 65 72 56 61 6c 75 65 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
'"/DM.DataManagement/AddParameterValue" requires exactly one request message.'}
I0219 16:03:35.667000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:00000000035E0A78]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50
61 72 61 6d 65 74 65 72 56 61 6c 75 65 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
'"/DM.DataManagement/AddParameterValue" requires exactly one request message.'}
I0219 16:03:35.742000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:00000000035E0A90]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50
61 72 61 6d 65 74 65 72 56 61 6c 75 65 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
'"/DM.DataManagement/AddParameterValue" requires exactly one request message.'}
I0219 16:03:35.803000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:00000000035E0AA8]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50
61 72 61 6d 65 74 65 72 56 61 6c 75 65 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
'"/DM.DataManagement/AddParameterValue" requires exactly one request message.'}
I0219 16:03:35.864000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:00000000035E0AC0]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74
2f 41 64 64 50 61 72 61 6d 65 74 65 72 56 61 6c 75 65 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 '"/DM.DataManagement/AddParameterValue" requires exactly one
request message.'}
I0219 16:03:35.970000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[connected:00000000035E0AD8]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74
2f 41 64 64 50 61 72 61 6d 65 74 65 72 56 61 6c 75 65 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 6765 2e '"/DM.DataManagement/AddParameterValue" requires exactly one
request message.'}
I0219 16:03:36.090000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1702]
perform_stream_op[s=00000000035E1A40]: 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=6
9 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50
61 72 61 6d 65 74 65 72 56 61 6c 75 65 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 '"/DM.DataManagement/AddParameterValue"
requires exactly one request message.'}
I0219 16:03:36.202000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1398]
perform_stream_op_locked: 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 'grp
c-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
44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50 61 72 61 6d
65 74 65 72 56 61 6c 75 65 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 6
d 65 73 73 61 67 65 2e '"/DM.DataManagement/AddParameterValue" requires exactly
one request message.'}; on_complete = 00000000035E1698
I0219 16:03:36.329000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:5:HDR:SVR: :status: 200
I0219 16:03:36.341000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:5:HDR:SVR: content-type: application/grpc
I0219 16:03:36.355000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:5:HDR:SVR: grpc-accept-encoding: identity,deflate,gzip
I0219 16:03:36.372000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:5:HDR:SVR: accept-encoding: identity,gzip
I0219 16:03:36.386000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:5:TRL:SVR: grpc-status: 12
I0219 16:03:36.398000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:5:TRL:SVR: grpc-message: "/DM.DataManagement/AddParameterValue" requires
exactly one request message.
I0219 16:03:36.422000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 00000000035E1698 refs=1 flags=0x0001
desc=send_initial_metadata_finished
err={"created":"@1550592216.421000000","description":"Attempt to send initial
metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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}]} write_state=IDLE
I0219 16:03:36.507000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 00000000035E1698 refs=1 flags=0x0001
desc=send_trailing_metadata_finished
err={"created":"@1550592216.507000000","description":"Attempt to send trailing
metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}
write_state=IDLE
I0219 16:03:36.556000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 00000000035E1698 refs=0 flags=0x0001
desc=op->on_complete err="No Error" write_state=IDLE
I0219 16:03:36.581000000 12760 src/core/lib/surface/call.cc:613]
OP[server:00000000035E0A60]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:36.703000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:00000000035E0A78]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:36.837000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[deadline:00000000035E0A90]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:36.951000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[http-server:00000000035E0AA8]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:37.095000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:00000000035E0AC0]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:37.241000000 12760 src/core/lib/channel/channel_stack.cc:226]
OP[connected:00000000035E0AD8]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:37.393000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1702]
perform_stream_op[s=00000000035E1A40]:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"}
I0219 16:03:37.530000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1398]
perform_stream_op_locked:
CANCEL:{"created":"@1550592216.507000000","description":"Error in HTTP
transport completing
operation","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1262,"referenced_errors":[{"created":"@1550592216.421000000","description":"Attempt
to send initial metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1509,"referenced_errors":[{"created":"@1550592214.905000000","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":"@1550592216.507000000","description":"Attempt
to send trailing metadata after stream was
closed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":1609}],"target_address":"ipv4:127.0.0.1:61343"};
on_complete = 000000000212E570
I0219 16:03:37.652000000 12760
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=0000000003584F20 000000000212E570 refs=0 flags=0x0000
desc=op->on_complete err="No Error" write_state=IDLE
I0219 16:03:37.678000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:000000000358A760 SERVER state IDLE -> WRITING [TRANSPORT_FLOW_CONTROL]
I0219 16:03:37.695000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:000000000358A760 SERVER state WRITING -> WRITING+MORE [INITIAL_WRITE]
I0219 16:03:37.713000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1866]
perform_transport_op[t=000000000358A760]:
ON_CONNECTIVITY_STATE_CHANGE:p=0000000003403440:from=IDLE
SET_ACCEPT_STREAM:00000000639A8319(00000000034033B0,...)
I0219 16:03:37.745000000 14328
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 000000000358AAA8
server_transport: from IDLE [cur=READY] notify=0000000003403440
I0219 16:03:37.763000000 14328
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1866]
perform_transport_op[t=000000000358A760]:
ON_CONNECTIVITY_STATE_CHANGE:p=0000000003403440:from=READY
I0219 16:03:37.796000000 14328
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 000000000358AAA8
server_transport: from READY [cur=READY] notify=0000000003403440
I0219 16:03:37.815000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:227]
CHTTP2:SVR:ipv4:127.0.0.1:61351: got setting ENABLE_PUSH = 0
I0219 16:03:37.830000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:227]
CHTTP2:SVR:ipv4:127.0.0.1:61351: got setting MAX_CONCURRENT_STREAMS = 0
I0219 16:03:37.847000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:220]
000000000358A760[svr] adding 4128769 for initial_window change
I0219 16:03:37.864000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:227]
CHTTP2:SVR:ipv4:127.0.0.1:61351: got setting INITIAL_WINDOW_SIZE = 4194304
I0219 16:03:37.892000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:227]
CHTTP2:SVR:ipv4:127.0.0.1:61351: got setting MAX_FRAME_SIZE = 4194304
I0219 16:03:37.908000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:227]
CHTTP2:SVR:ipv4:127.0.0.1:61351: got setting MAX_HEADER_LIST_SIZE = 8192
I0219 16:03:37.936000000 14328
src/core/ext/transport/chttp2/transport/frame_settings.cc:227]
CHTTP2:SVR:ipv4:127.0.0.1:61351: got setting GRPC_ALLOW_TRUE_BINARY_METADATA = 1I0219 16:26:14.415000000 12960 src/core/lib/surface/completion_queue.cc:481]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I0219 16:26:14.431000000 12960 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=0000000004510210)
I0219 16:26:14.442000000 12960 src/core/lib/surface/metadata_array.cc:29]
grpc_metadata_array_init(array=000000000401DEC8)
I0219 16:26:14.454000000 12960 src/core/lib/surface/call.cc:1903]
grpc_call_start_batch(call=00000000045DDA40, ops=0000000003731410, nops=6,
tag=0000000004529360, reserved=0000000000000000)
I0219 16:26:14.474000000 12960 src/core/lib/surface/call.cc:1508] ops[0]:
SEND_INITIAL_METADATA(nil)
I0219 16:26:14.483000000 12960 src/core/lib/surface/call.cc:1508] ops[1]:
SEND_MESSAGE ptr=00000000040EC380
I0219 16:26:14.492000000 12960 src/core/lib/surface/call.cc:1508] ops[2]:
SEND_CLOSE_FROM_CLIENT
I0219 16:26:14.500000000 12960 src/core/lib/surface/call.cc:1508] ops[3]:
RECV_INITIAL_METADATA ptr=0000000004510210
I0219 16:26:14.510000000 12960 src/core/lib/surface/call.cc:1508] ops[4]:
RECV_MESSAGE ptr=0000000004559FA0
I0219 16:26:14.519000000 12960 src/core/lib/surface/call.cc:1508] ops[5]:
RECV_STATUS_ON_CLIENT metadata=000000000401DEC8 status=000000000401DEE0
details=000000000401DEE8
I0219 16:26:14.538000000 12960 src/core/lib/surface/call.cc:613]
OP[client-channel:00000000045DE4C0]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64
50 61 72 61 6d 65 74 65 72 56 61 6c 75 65
'/DM.DataManagement/AddParameterValue'} SEND_MESSAGE:flags=0x00000000:len=47
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0219 16:26:14.582000000 12960 src/core/lib/transport/connectivity_state.cc:81]
CONWATCH: 0000000003D39B30 client_channel: get READY
I0219 16:26:14.593000000 12960
src/core/ext/filters/client_channel/subchannel.cc:1000]
OP[authority:00000000045DED10]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64
50 61 72 61 6d 65 74 65 72 56 61 6c 75 65
'/DM.DataManagement/AddParameterValue'} SEND_MESSAGE:flags=0x00000000:len=47
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0219 16:26:14.639000000 12960 src/core/lib/channel/channel_stack.cc:226]
OP[message_size:00000000045DED28]: 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 44 4d 2e 44 61 74 61 4d
61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50 61 72 61 6d 65 74 65 72 56 61 6c 75
65 '/DM.DataManagement/AddParameterValue'} SEND_MESSAGE:flags=0x00000000:len=47
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0219 16:26:14.694000000 12960 src/core/lib/channel/channel_stack.cc:226]
OP[http-client:00000000045DED40]: 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 44 4d 2e 44 61 74 61 4d
61 6e 61 67 65 6d 65 6e 74 2f 41 64 64 50 61 72 61 6d 65 74 65 72 56 61 6c 75
65 '/DM.DataManagement/AddParameterValue'} SEND_MESSAGE:flags=0x00000000:len=47
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I0219 16:26:14.750000000 12960 src/core/lib/channel/channel_stack.cc:226]
OP[message_compress:00000000045DED58]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f
41 64 64 50 61 72 61 6d 65 74 65 72 56 61 6c 75 65
'/DM.DataManagement/AddParameterValue', 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 37 2e 31 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 69 7a 6d 6f 29
'grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2; gizmo)'}
SEND_MESSAGE:flags=0x00000000:len=47 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0219 16:26:14.864000000 12960 src/core/lib/channel/channel_stack.cc:226]
OP[connected:00000000045DED70]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64
50 61 72 61 6d 65 74 65 72 56 61 6c 75 65
'/DM.DataManagement/AddParameterValue', 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 37 2e 31 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 69 7a 6d 6f 29
'grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2; gizmo)', 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=47 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0219 16:26:15.009000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1702]
perform_stream_op[s=00000000045DFA20]: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f
41 64 64 50 61 72 61 6d 65 74 65 72 56 61 6c 75 65
'/DM.DataManagement/AddParameterValue', 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 37 2e 31 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 69 7a 6d 6f 29
'grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2; gizmo)', 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=47 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0219 16:26:15.156000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1398]
perform_stream_op_locked: 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 44 4d 2e 44 61 74 61 4d 61 6e 61 67 65 6d 65 6e 74 2f 41 64 64
50 61 72 61 6d 65 74 65 72 56 61 6c 75 65
'/DM.DataManagement/AddParameterValue', 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 37 2e 31 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 69 7a 6d 6f 29
'grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2; gizmo)', 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=47 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA; on_complete =
00000000045DF678
I0219 16:26:15.305000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: :scheme: http
I0219 16:26:15.316000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: :method: POST
I0219 16:26:15.328000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: :authority: 127.0.0.1:50051
I0219 16:26:15.341000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: :path: /DM.DataManagement/AddParameterValue
I0219 16:26:15.356000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: te: trailers
I0219 16:26:15.368000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: content-type: application/grpc
I0219 16:26:15.381000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: user-agent: grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2;
gizmo)
I0219 16:26:15.400000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: grpc-accept-encoding: identity,deflate,gzip
I0219 16:26:15.416000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1376]
HTTP:0:HDR:CLI: accept-encoding: identity,gzip
I0219 16:26:15.430000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1187] HTTP:CLI:
Allocating new grpc_chttp2_stream 00000000045DFA20 to id 13
I0219 16:26:15.447000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state IDLE -> WRITING [START_NEW_STREAM]
I0219 16:26:15.463000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state WRITING -> WRITING+MORE [SEND_MESSAGE]
I0219 16:26:15.480000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=00000000040F8BD0 00000000045DF678 refs=3 flags=0x0001
desc=op->on_complete err="No Error" write_state=WRITING+MORE
I0219 16:26:15.505000000 12960
src/core/ext/transport/chttp2/transport/writing.cc:413] W:00000000040F8BD0
CLIENT[13] im-(sent,send)=(0,1) announce=5
I0219 16:26:15.519000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode:
':authority: 127.0.0.1:50051', elem_interned=1 [1], k_interned=1, v_interned=1
I0219 16:26:15.538000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode: ':path:
/DM.DataManagement/AddParameterValue', elem_interned=0 [2], k_interned=1,
v_interned=0
I0219 16:26:15.559000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode: 'te:
trailers', elem_interned=1 [3], k_interned=1, v_interned=1
I0219 16:26:15.576000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode:
'content-type: application/grpc', elem_interned=1 [3], k_interned=1,
v_interned=1
I0219 16:26:15.595000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode:
'user-agent: grpc-python/1.17.1 grpc-c/7.0.0 (windows; chttp2; gizmo)',
elem_interned=1 [1], k_interned=1, v_interned=1
I0219 16:26:15.619000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode:
'grpc-accept-encoding: identity,deflate,gzip', elem_interned=1 [3],
k_interned=1, v_interned=1
I0219 16:26:15.641000000 12960
src/core/ext/transport/chttp2/transport/hpack_encoder.cc:473] Encode:
'accept-encoding: identity,gzip', elem_interned=1 [3], k_interned=1,
v_interned=1
I0219 16:26:15.661000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=00000000040F8BD0 00000000045DF678 refs=2 flags=0x0001
desc=send_initial_metadata_finished err="No Error" write_state=WRITING+MORE
I0219 16:26:15.687000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=00000000040F8BD0 00000000045DF678 refs=1 flags=0x0001
desc=send_trailing_metadata_finished err="No Error" write_state=WRITING+MORE
I0219 16:26:15.713000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:1249]
complete_closure_step: t=00000000040F8BD0 00000000045DF678 refs=0 flags=0x0001
desc=on_write_finished_cb err="No Error" write_state=WRITING+MORE
I0219 16:26:15.739000000 12960
src/core/ext/transport/chttp2/transport/writing.cc:110] CLIENT: Ping sent
[0000000003785D70]: 2/2
I0219 16:26:15.751000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state WRITING+MORE -> WRITING [begin write in current
thread]
I0219 16:26:15.770000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:2596]
ipv4:127.0.0.1:50051: Start BDP ping err="No Error"
I0219 16:26:15.785000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state WRITING -> IDLE [finish writing]
I0219 16:26:15.802000000 12960 src/core/lib/surface/completion_queue.cc:963]
grpc_completion_queue_next(cq=000000000230A990, deadline=gpr_timespec { tv_sec:
1550593576, tv_nsec: 2000000, clock_type: 1 }, reserved=0000000000000000)
I0219 16:26:15.826000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state IDLE -> WRITING [RST_STREAM]
D0219 16:26:15.842000000 12960 src/core/lib/surface/call.cc:719]
set_final_status CLI
D0219 16:26:15.849000000 12960 src/core/lib/surface/call.cc:720]
{"created":"@1550593575.842000000","description":"Error received from
peer","file":"src/core/lib/surface/call.cc","file_line":1036,"grpc_message":"Stream
removed","grpc_status":2}
I0219 16:26:15.875000000 12960 src/core/lib/surface/completion_queue.cc:699]
cq_end_op_for_next(cq=000000000230A990, tag=0000000004529360, error="No Error",
done=000000006399C5E2, done_arg=00000000045DEAC0, storage=00000000045DEB38)
I0219 16:26:15.900000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:2609]
ipv4:127.0.0.1:50051: Complete BDP ping
err={"created":"@1550593575.826000000","description":"Endpointread
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"occurred_during_write":0,"referenced_errors":[{"created":"@1550593575.826000000","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":198}]}
I0219 16:26:15.961000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state WRITING -> WRITING [begin write in current
thread]
I0219 16:26:15.980000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:852]
W:00000000040F8BD0 CLIENT state WRITING -> IDLE [finish writing]
I0219 16:26:15.996000000 12960
src/core/ext/transport/chttp2/transport/chttp2_transport.cc:2790] set
connectivity_state=4
I0219 16:26:16.007000000 12960
src/core/lib/transport/connectivity_state.cc:164] SET: 00000000040F8F18
client_transport: READY --> SHUTDOWN [close_transport] error=0000000003FE81C0
{"created":"@1550593575.842000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":609,"referenced_errors":[{"created":"@1550593575.826000000","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1550593575.826000000","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":198}]}]}
I0219 16:26:16.100000000 12960
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 00000000040F8F18
client_transport: 000000000365CE28
I0219 16:26:16.113000000 12960
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000003D3A270
subchannel: READY --> TRANSIENT_FAILURE [reflect_child] error=0000000003FE81C0
{"created":"@1550593575.842000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":609,"referenced_errors":[{"created":"@1550593575.826000000","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1550593575.826000000","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":198}]}]}
I0219 16:26:16.205000000 12960
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000003D3A270
subchannel: 0000000002A88C78
I0219 16:26:16.217000000 12960
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000003D3A290
subchannel: READY --> TRANSIENT_FAILURE [reflect_child] error=0000000003FE81C0
{"created":"@1550593575.842000000","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":609,"referenced_errors":[{"created":"@1550593575.826000000","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2510,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1550593575.826000000","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":198}]}]}
I0219 16:26:16.310000000 12960
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000003FC1548
pick_first: READY --> IDLE [selected_changed+reresolve] error=0000000000000000
"No Error"
I0219 16:26:16.331000000 12960
src/core/lib/transport/connectivity_state.cc:190] NOTIFY: 0000000003FC1548
pick_first: 0000000003732AB8
I0219 16:26:16.343000000 12960
src/core/lib/transport/connectivity_state.cc:164] SET: 0000000003D39B30
client_channel: READY --> IDLE [lb_changed] error=0000000000000000 "No Error"
I0219 16:26:16.362000000 12960
src/core/lib/transport/connectivity_state.cc:116] CONWATCH: 0000000003FC1548
pick_first: from IDLE [cur=IDLE] notify=0000000003732A48
D0219 16:26:16.379000000 12960
src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.cc:279]
Start resolving.
I0219 16:26:16.390000000 12960 src/core/lib/surface/completion_queue.cc:1063]
RETURN_EVENT[000000000230A990]: OP_COMPLETE: tag:0000000004529360 OK
I0219 16:26:16.404000000 12960 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=0000000004510210)
I0219 16:26:16.415000000 12960 src/core/lib/surface/metadata_array.cc:34]
grpc_metadata_array_destroy(array=000000000401DEC8)
I0219 16:26:16.427000000 12960 src/core/lib/surface/call.cc:561]
grpc_call_unref(c=00000000045DDA40)
I0219 16:26:16.435000000 12960 src/core/lib/surface/completion_queue.cc:1389]
grpc_completion_queue_shutdown(cq=000000000230A990)
I0219 16:26:16.447000000 12960 src/core/lib/surface/completion_queue.cc:1395]
grpc_completion_queue_destroy(cq=000000000230A990)
I0219 16:26:16.459000000 12960 src/core/lib/surface/completion_queue.cc:1389]
grpc_completion_queue_shutdown(cq=000000000230A990)
-- EXCEPTION --
File "C:\Python27\lib\site-packages\grpc\_channel.py", line 547, 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"
