Hi Lidi
here is the log from client side
I1109 14:55:12.420854517 13623 ev_epoll1_linux.cc:116] grpc epoll fd:
3
D1109 14:55:12.420880472 13623 ev_posix.cc:169] Using polling
engine: epoll1
D1109 14:55:12.421021052 13623 dns_resolver.cc:338] Using native
dns resolver
I1109 14:55:12.421063056 13623 init.cc:153] grpc_init(void)
I1109 14:55:12.421084744 13623 channel_create.cc:93]
grpc_insecure_channel_create(target=localhost:50051, args=0x7f7ea7310f20,
reserved=(nil))
I1109 14:55:12.421114130 13623 completion_queue.cc:474]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I1109 14:55:12.421117020 13623 completion_queue.cc:474]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I1109 14:55:12.421185398 13623 completion_queue.cc:474]
grpc_completion_queue_create_internal(completion_type=0, polling_type=0)
I1109 14:55:12.421216138 13623 metadata_array.cc:29]
grpc_metadata_array_init(array=0x7f7ea7957bc8)
I1109 14:55:12.421219223 13623 metadata_array.cc:29]
grpc_metadata_array_init(array=0x7f7ea72c30c0)
I1109 14:55:12.421222059 13623 call.cc:1883]
grpc_call_start_batch(call=0xf55bb0, ops=0xeeab10, nops=6,
tag=0x7f7ea7310ef0, reserved=(nil))
I1109 14:55:12.421226029 13623 call.cc:1488] ops[0]:
SEND_INITIAL_METADATA(nil)
I1109 14:55:12.421228617 13623 call.cc:1488] ops[1]:
SEND_MESSAGE ptr=0xd88d20
I1109 14:55:12.421230879 13623 call.cc:1488] ops[2]:
SEND_CLOSE_FROM_CLIENT
I1109 14:55:12.421233318 13623 call.cc:1488] ops[3]:
RECV_INITIAL_METADATA ptr=0x7f7ea7957bc8
I1109 14:55:12.421235738 13623 call.cc:1488] ops[4]:
RECV_MESSAGE ptr=0x7f7ea72a1820
I1109 14:55:12.421239091 13623 call.cc:1488] ops[5]:
RECV_STATUS_ON_CLIENT metadata=0x7f7ea72c30c0 status=0x7f7ea72c30d8
details=0x7f7ea72c30e0
I1109 14:55:12.421251240 13623 call.cc:598]
OP[client-channel:0xf56540]: SEND_INITIAL_METADATA{key=3a 70 61 74 68
':path' value=2f 68 65 6c 6c 6f 77 6f 72 6c 64 2e 47 72 65 65 74 65 72 2f
53 61 79 48 65 6c 6c 6f '/helloworld.Greeter/SayHello'}
SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
D1109 14:55:12.421255825 13623 dns_resolver.cc:279] Start
resolving.
I1109 14:55:12.421271461 13623 completion_queue.cc:956]
grpc_completion_queue_next(cq=0xd8e220, deadline=gpr_timespec { tv_sec:
1541804112, tv_nsec: 621269667, clock_type: 1 }, reserved=(nil))
I1109 14:55:12.423570682 13625 connectivity_state.cc:92] CONWATCH:
0x7f7e98002ce8 pick_first: get IDLE
I1109 14:55:12.423604043 13625 connectivity_state.cc:116] CONWATCH:
0x7f7e98002ce8 pick_first: from IDLE [cur=IDLE] notify=0x7f7e98002388
I1109 14:55:12.423618199 13625 connectivity_state.cc:164] SET: 0xf27bc8
client_channel: IDLE --> IDLE [resolver_result] error=(nil) "No Error"
I1109 14:55:12.423629677 13625 connectivity_state.cc:92] CONWATCH:
0x7f7e98003c18 subchannel: get IDLE
I1109 14:55:12.423638866 13625 connectivity_state.cc:116] CONWATCH:
0x7f7e98003c18 subchannel: from IDLE [cur=IDLE] notify=0x7f7e98003308
I1109 14:55:12.423649626 13625 connectivity_state.cc:164] SET:
0x7f7e98003c18 subchannel: IDLE --> CONNECTING [connecting] error=(nil) "No
Error"
I1109 14:55:12.423658089 13625 connectivity_state.cc:190] NOTIFY:
0x7f7e98003c18 subchannel: 0x7f7e98003308
I1109 14:55:12.423736694 13625 socket_utils_common_posix.cc:310]
TCP_USER_TIMEOUT not supported for this platform
I1109 14:55:12.423819670 13625 connectivity_state.cc:164] SET:
0x7f7e98002ce8 pick_first: IDLE --> CONNECTING [connecting_changed]
error=(nil) "No Error"
I1109 14:55:12.423830816 13625 connectivity_state.cc:190] NOTIFY:
0x7f7e98002ce8 pick_first: 0x7f7e98002388
I1109 14:55:12.423840274 13625 connectivity_state.cc:116] CONWATCH:
0x7f7e98003c18 subchannel: from CONNECTING [cur=CONNECTING]
notify=0x7f7e98003308
I1109 14:55:12.423851528 13625 connectivity_state.cc:164] SET: 0xf27bc8
client_channel: IDLE --> CONNECTING [lb_changed] error=(nil) "No Error"
I1109 14:55:12.423895462 13625 connectivity_state.cc:116] CONWATCH:
0x7f7e98002ce8 pick_first: from CONNECTING [cur=CONNECTING]
notify=0x7f7e98002948
I1109 14:55:12.424229996 13623 http_connect_handshaker.cc:300] Connecting
to server localhost:50051 via HTTP proxy ipv4:10.19.8.225:912
I1109 14:55:12.425315940 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state IDLE -> WRITING [TRANSPORT_FLOW_CONTROL]
I1109 14:55:12.425345412 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING -> WRITING+MORE [INITIAL_WRITE]
I1109 14:55:12.425390982 13623 subchannel.cc:656] New connected
subchannel at 0xeea6b0 for subchannel 0x7f7e98003b40
I1109 14:55:12.425407600 13623 chttp2_transport.cc:1864]
perform_transport_op[t=0xf5a630]:
ON_CONNECTIVITY_STATE_CHANGE:p=0xecac40:from=READY BIND_POLLSET_SET
I1109 14:55:12.425419326 13623 connectivity_state.cc:164] SET:
0x7f7e98003c18 subchannel: CONNECTING --> READY [connected] error=(nil) "No
Error"
I1109 14:55:12.425428450 13623 connectivity_state.cc:190] NOTIFY:
0x7f7e98003c18 subchannel: 0x7f7e98003308
I1109 14:55:12.425443587 13623 connectivity_state.cc:116] CONWATCH:
0xf5a8d0 client_transport: from READY [cur=READY] notify=0xecac40
I1109 14:55:12.425457519 13623 writing.cc:110] CLIENT: Ping
sent [0xee1ad0]: 2/2
I1109 14:55:12.425466796 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING+MORE -> WRITING [begin write in current thread]
I1109 14:55:12.425498045 13623 chttp2_transport.cc:2595]
ipv4:10.19.8.225:912: Start BDP ping err="No Error"
I1109 14:55:12.425509771 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING -> IDLE [finish writing]
I1109 14:55:12.425535718 13623 connectivity_state.cc:164] SET:
0x7f7e98002ce8 pick_first: CONNECTING --> READY [subchannel_ready]
error=(nil) "No Error"
I1109 14:55:12.425544979 13623 connectivity_state.cc:190] NOTIFY:
0x7f7e98002ce8 pick_first: 0x7f7e98002948
I1109 14:55:12.425554966 13623 connectivity_state.cc:116] CONWATCH:
0x7f7e98003c18 subchannel: from READY [cur=READY] notify=0xe90f28
I1109 14:55:12.425566109 13623 connectivity_state.cc:164] SET: 0xf27bc8
client_channel: CONNECTING --> READY [lb_changed] error=(nil) "No Error"
I1109 14:55:12.425575387 13623 connectivity_state.cc:116] CONWATCH:
0x7f7e98002ce8 pick_first: from READY [cur=READY] notify=0xf0e738
I1109 14:55:12.425613783 13623 subchannel.cc:753]
OP[authority:0xf5e090]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path'
value=2f 68 65 6c 6c 6f 77 6f 72 6c 64 2e 47 72 65 65 74 65 72 2f 53 61 79
48 65 6c 6c 6f '/helloworld.Greeter/SayHello'}
SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I1109 14:55:12.425647150 13623 channel_stack.cc:225]
OP[message_size:0xf5e0a8]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f 72
69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31
'localhost:50051', key=3a 70 61 74 68 ':path' value=2f 68 65 6c 6c 6f 77 6f
72 6c 64 2e 47 72 65 65 74 65 72 2f 53 61 79 48 65 6c 6c 6f
'/helloworld.Greeter/SayHello'} SEND_MESSAGE:flags=0x00000000:len=5
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I1109 14:55:12.425682336 13623 channel_stack.cc:225]
OP[http-client:0xf5e0c0]: SEND_INITIAL_METADATA{key=3a 61 75 74 68 6f 72
69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31
'localhost:50051', key=3a 70 61 74 68 ':path' value=2f 68 65 6c 6c 6f 77 6f
72 6c 64 2e 47 72 65 65 74 65 72 2f 53 61 79 48 65 6c 6c 6f
'/helloworld.Greeter/SayHello'} SEND_MESSAGE:flags=0x00000000:len=5
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I1109 14:55:12.425720210 13623 channel_stack.cc:225]
OP[message_compress:0xf5e0d8]: 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=6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31
'localhost:50051', key=3a 70 61 74 68 ':path' value=2f 68 65 6c 6c 6f 77 6f
72 6c 64 2e 47 72 65 65 74 65 72 2f 53 61 79 48 65 6c 6c 6f
'/helloworld.Greeter/SayHello', 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 36 2e 30 20 67 72 70 63 2d 63 2f 36 2e 30 2e 30 20
28 6d 61 6e 79 6c 69 6e 75 78 3b 20 63 68 74 74 70 32 3b 20 67 61 6f 29
'grpc-python/1.16.0 grpc-c/6.0.0 (manylinux; chttp2; gao)'}
SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{}
RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I1109 14:55:12.425778942 13623 channel_stack.cc:225]
OP[connected:0xf5e0f0]: 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=6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31 'localhost:50051',
key=3a 70 61 74 68 ':path' value=2f 68 65 6c 6c 6f 77 6f 72 6c 64 2e 47 72
65 65 74 65 72 2f 53 61 79 48 65 6c 6c 6f '/helloworld.Greeter/SayHello',
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 36 2e 30 20
67 72 70 63 2d 63 2f 36 2e 30 2e 30 20 28 6d 61 6e 79 6c 69 6e 75 78 3b 20
63 68 74 74 70 32 3b 20 67 61 6f 29 'grpc-python/1.16.0 grpc-c/6.0.0
(manylinux; chttp2; gao)', 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=5
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I1109 14:55:12.425826521 13623 chttp2_transport.cc:1700]
perform_stream_op[s=0xf5eac8]: 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=6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31
'localhost:50051', key=3a 70 61 74 68 ':path' value=2f 68 65 6c 6c 6f 77 6f
72 6c 64 2e 47 72 65 65 74 65 72 2f 53 61 79 48 65 6c 6c 6f
'/helloworld.Greeter/SayHello', 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 36 2e 30 20 67 72 70 63 2d 63 2f 36 2e 30 2e 30 20
28 6d 61 6e 79 6c 69 6e 75 78 3b 20 63 68 74 74 70 32 3b 20 67 61 6f 29
'grpc-python/1.16.0 grpc-c/6.0.0 (manylinux; chttp2; gao)', 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=5
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA
I1109 14:55:12.425875363 13623 chttp2_transport.cc:1396]
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=6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31 'localhost:50051',
key=3a 70 61 74 68 ':path' value=2f 68 65 6c 6c 6f 77 6f 72 6c 64 2e 47 72
65 65 74 65 72 2f 53 61 79 48 65 6c 6c 6f '/helloworld.Greeter/SayHello',
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 36 2e 30 20
67 72 70 63 2d 63 2f 36 2e 30 2e 30 20 28 6d 61 6e 79 6c 69 6e 75 78 3b 20
63 68 74 74 70 32 3b 20 67 61 6f 29 'grpc-python/1.16.0 grpc-c/6.0.0
(manylinux; chttp2; gao)', 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=5
SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE
RECV_TRAILING_METADATA; on_complete = 0xf5e888
I1109 14:55:12.425896318 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: :scheme: http
I1109 14:55:12.425902141 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: :method: POST
I1109 14:55:12.425907939 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: :authority: localhost:50051
I1109 14:55:12.425913740 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: :path: /helloworld.Greeter/SayHello
I1109 14:55:12.425919573 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: te: trailers
I1109 14:55:12.425925178 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: content-type: application/grpc
I1109 14:55:12.425931151 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: user-agent: grpc-python/1.16.0 grpc-c/6.0.0 (manylinux;
chttp2; gao)
I1109 14:55:12.425937362 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: grpc-accept-encoding: identity,deflate,gzip
I1109 14:55:12.425943256 13623 chttp2_transport.cc:1374]
HTTP:0:HDR:CLI: accept-encoding: identity,gzip
I1109 14:55:12.425949493 13623 chttp2_transport.cc:1185] HTTP:CLI:
Allocating new grpc_chttp2_stream 0xf5eac8 to id 1
I1109 14:55:12.425956500 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state IDLE -> WRITING [START_NEW_STREAM]
I1109 14:55:12.425963933 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING -> WRITING+MORE [SEND_MESSAGE]
I1109 14:55:12.425973447 13623 chttp2_transport.cc:1247]
complete_closure_step: t=0xf5a630 0xf5e888 refs=3 flags=0x0001
desc=op->on_complete err="No Error" write_state=WRITING+MORE
I1109 14:55:12.425980632 13623 writing.cc:413] W:0xf5a630
CLIENT[1] im-(sent,send)=(0,1) announce=5
I1109 14:55:12.425988729 13623 hpack_encoder.cc:477] Encode:
':authority: localhost:50051', elem_interned=1 [1], k_interned=1,
v_interned=1
I1109 14:55:12.425997061 13623 hpack_encoder.cc:477] Encode:
':path: /helloworld.Greeter/SayHello', elem_interned=0 [2], k_interned=1,
v_interned=0
I1109 14:55:12.426004138 13623 hpack_encoder.cc:477] Encode: 'te:
trailers', elem_interned=1 [3], k_interned=1, v_interned=1
I1109 14:55:12.426011291 13623 hpack_encoder.cc:477] Encode:
'content-type: application/grpc', elem_interned=1 [3], k_interned=1,
v_interned=1
I1109 14:55:12.426019468 13623 hpack_encoder.cc:477] Encode:
'user-agent: grpc-python/1.16.0 grpc-c/6.0.0 (manylinux; chttp2; gao)',
elem_interned=1 [1], k_interned=1, v_interned=1
I1109 14:55:12.426027416 13623 hpack_encoder.cc:477] Encode:
'grpc-accept-encoding: identity,deflate,gzip', elem_interned=1 [3],
k_interned=1, v_interned=1
I1109 14:55:12.426033930 13623 hpack_encoder.cc:477] Encode:
'accept-encoding: identity,gzip', elem_interned=1 [3], k_interned=1,
v_interned=1
I1109 14:55:12.426043560 13623 chttp2_transport.cc:1247]
complete_closure_step: t=0xf5a630 0xf5e888 refs=2 flags=0x0001
desc=send_initial_metadata_finished err="No Error" write_state=WRITING+MORE
I1109 14:55:12.426053497 13623 chttp2_transport.cc:1247]
complete_closure_step: t=0xf5a630 0xf5e888 refs=1 flags=0x0001
desc=send_trailing_metadata_finished err="No Error" write_state=WRITING+MORE
I1109 14:55:12.426060803 13623 chttp2_transport.cc:1247]
complete_closure_step: t=0xf5a630 0xf5e888 refs=0 flags=0x0001
desc=on_write_finished_cb err="No Error" write_state=WRITING+MORE
I1109 14:55:12.426072262 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING+MORE -> WRITING [begin write in current thread]
I1109 14:55:12.426095561 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING -> IDLE [finish writing]
I1109 14:55:12.426698337 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state IDLE -> WRITING [RST_STREAM]
D1109 14:55:12.426748033 13623 call.cc:700]
set_final_status CLI
D1109 14:55:12.426774713 13623 call.cc:701]
{"created":"@1541804112.426745675","description":"Error received from
peer","file":"src/core/lib/surface/call.cc","file_line":1017,"grpc_message":"Socket
closed","grpc_status":14}
I1109 14:55:12.426791551 13623 completion_queue.cc:692]
cq_end_op_for_next(cq=0xd8e220, tag=0x7f7ea7310ef0, error="No Error",
done=0x7f7ea90a8c20, done_arg=0xf56ac0, storage=0xf56ac8)
I1109 14:55:12.426837069 13623 chttp2_transport.cc:2608]
ipv4:10.19.8.225:912: Complete BDP ping
err={"created":"@1541804112.426689641","description":"Endpoint read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2509,"occurred_during_write":0,"referenced_errors":[{"created":"@1541804112.426684410","description":"Socket
closed","fd":6,"file":"src/core/lib/iomgr/tcp_posix.cc","file_line":465,"grpc_status":14,"target_address":"ipv4:10.19.8.225:912"}]}
I1109 14:55:12.426853313 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING -> WRITING [begin write in current thread]
I1109 14:55:12.426889006 13623 chttp2_transport.cc:850] W:0xf5a630
CLIENT state WRITING -> IDLE [finish writing]
I1109 14:55:12.426898997 13623 chttp2_transport.cc:2788] set
connectivity_state=4
I1109 14:55:12.426933812 13623 connectivity_state.cc:164] SET: 0xf5a8d0
client_transport: READY --> SHUTDOWN [close_transport] error=0xf23d90
{"created":"@1541804112.426738905","description":"Delayed close due to
in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":618,"referenced_errors":[{"created":"@1541804112.426689641","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2509,"occurred_during_write":0,"referenced_errors":[{"created":"@1541804112.426684410","description":"Socket
closed","fd":6,"file":"src/core/lib/iomgr/tcp_posix.cc","file_line":465,"grpc_status":14,"target_address":"ipv4:10.19.8.225:912"}]}]}
I1109 14:55:12.426949521 13623 connectivity_state.cc:190] NOTIFY:
0xf5a8d0 client_transport: 0xecac40
I1109 14:55:12.426980066 13623 connectivity_state.cc:164] SET:
0x7f7e98003c18 subchannel: READY --> TRANSIENT_FAILURE [reflect_child]
error=0xf23d90 {"created":"@1541804112.426738905","description":"Delayed
close due to in-progress
write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":618,"referenced_errors":[{"created":"@1541804112.426689641","description":"Endpoint
read
failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2509,"occurred_during_write":0,"referenced_errors":[{"created":"@1541804112.426684410","description":"Socket
closed","fd":6,"file":"src/core/lib/iomgr/tcp_posix.cc","file_line":465,"grpc_status":14,"target_address":"ipv4:10.19.8.225:912"}]}]}
I1109 14:55:12.426996120 13623 connectivity_state.cc:190] NOTIFY:
0x7f7e98003c18 subchannel: 0xe90f28
I1109 14:55:12.427008600 13623 connectivity_state.cc:164] SET:
0x7f7e98002ce8 pick_first: READY --> IDLE [selected_changed+reresolve]
error=(nil) "No Error"
I1109 14:55:12.427017454 13623 connectivity_state.cc:190] NOTIFY:
0x7f7e98002ce8 pick_first: 0xf0e738
I1109 14:55:12.427028419 13623 connectivity_state.cc:164] SET: 0xf27bc8
client_channel: READY --> IDLE [lb_changed] error=(nil) "No Error"
I1109 14:55:12.427037989 13623 connectivity_state.cc:116] CONWATCH:
0x7f7e98002ce8 pick_first: from IDLE [cur=IDLE] notify=0xdbc978
D1109 14:55:12.427062543 13623 dns_resolver.cc:259] In cooldown
from last resolution (from 6 ms ago). Will resolve again in 994 ms
I1109 14:55:12.427087329 13623 completion_queue.cc:1056]
RETURN_EVENT[0xd8e220]: OP_COMPLETE: tag:0x7f7ea7310ef0 OK
I1109 14:55:12.427133354 13623 metadata_array.cc:34]
grpc_metadata_array_destroy(array=0x7f7ea7957bc8)
I1109 14:55:12.427145778 13623 metadata_array.cc:34]
grpc_metadata_array_destroy(array=0x7f7ea72c30c0)
I1109 14:55:12.427187143 13623 call.cc:546]
grpc_call_unref(c=0xf55bb0)
I1109 14:55:12.427237829 13623 completion_queue.cc:1382]
grpc_completion_queue_shutdown(cq=0xd8e220)
I1109 14:55:12.427248475 13623 completion_queue.cc:1388]
grpc_completion_queue_destroy(cq=0xd8e220)
I1109 14:55:12.427255960 13623 completion_queue.cc:1382]
grpc_completion_queue_shutdown(cq=0xd8e220)
I1109 14:55:12.427450951 13623 completion_queue.cc:1382]
grpc_completion_queue_shutdown(cq=0xcd1580)
I1109 14:55:12.427464337 13623 completion_queue.cc:1388]
grpc_completion_queue_destroy(cq=0xcd1580)
I1109 14:55:12.427484418 13623 completion_queue.cc:1382]
grpc_completion_queue_shutdown(cq=0xcd1580)
I1109 14:55:12.427490161 13623 completion_queue.cc:1382]
grpc_completion_queue_shutdown(cq=0xdd1900)
I1109 14:55:12.427495477 13623 completion_queue.cc:1388]
grpc_completion_queue_destroy(cq=0xdd1900)
I1109 14:55:12.427500346 13623 completion_queue.cc:1382]
grpc_completion_queue_shutdown(cq=0xdd1900)
I1109 14:55:12.427506256 13623 channel.cc:455]
grpc_channel_destroy(channel=0xf27a80)
I1109 14:55:12.427521291 13623 connectivity_state.cc:164] SET: 0xf27bc8
client_channel: IDLE --> SHUTDOWN [disconnect] error=0xecac40
{"created":"@1541804112.427510983","description":"Channel
Destroyed","file":"src/core/lib/surface/channel.cc","file_line":457}
I1109 14:55:12.427538912 13623 connectivity_state.cc:164] SET:
0x7f7e98002ce8 pick_first: IDLE --> SHUTDOWN [shutdown] error=0xede490
{"created":"@1541804112.427530960","description":"Channel
shutdown","file":"src/core/ext/filters/client_channel/lb_policy/pick_first/pick_first.cc","file_line":192}
I1109 14:55:12.427545722 13623 connectivity_state.cc:190] NOTIFY:
0x7f7e98002ce8 pick_first: 0xdbc978
I1109 14:55:12.427568046 13623 init.cc:158]
grpc_shutdown(void)
Traceback (most recent call last):
File "greeter_client.py", line 35, in <module>
run()
File "greeter_client.py", line 30, in run
response = stub.SayHello(helloworld_pb2.HelloRequest(name='you'))
File "/usr/local/lib/python2.7/dist-packages/grpc/_channel.py", line 533,
in __call__
return _end_unary_response_blocking(state, call, False, None)
File "/usr/local/lib/python2.7/dist-packages/grpc/_channel.py", line 467,
in _end_unary_response_blocking
raise _Rendezvous(state, None, None, deadline)
grpc._channel._Rendezvous: <_Rendezvous of RPC that terminated with:
status = StatusCode.UNAVAILABLE
details = "Socket closed"
debug_error_string =
"{"created":"@1541804112.426745675","description":"Error received from
peer","file":"src/core/lib/surface/call.cc","file_line":1017,"grpc_message":"Socket
closed","grpc_status":14}"
>
On Friday, November 9, 2018 at 1:44:18 PM UTC-8, [email protected] wrote:
>
> Hi Ankit,
>
> To define variables in separate line, you need to export them to the whole
> environment like this:
>
> $ export GRPC_VERBOSITY=DEBUG
> $
> export
> GRPC_TRACE=api,channel,call_error,connectivity_state,http,server_channel
>
> Then you can run the examples:
>
> $ python greeter_client.py
> $ python greeter_server.py
>
> The debug logs will go out through standard error output, which will print
> on your terminal.
>
> Lidi Zheng
>
> On Friday, November 9, 2018 at 1:40:23 PM UTC-8, [email protected]
> wrote:
>>
>> Hi Lidi
>>
>> how do i get debug logs? what location they are saved at?
>> I have done following and i dont see any extra information on stdout
>>
>> #server side terminal
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld# GRPC_VERBOSITY=DEBUG
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld#
>> GRPC_TRACE=api,channel,call_error,connectivity_state,http,server_channel
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld# python
>> greeter_server.py
>>
>> #client side terminal
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld# GRPC_VERBOSITY=DEBUG
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld#
>> GRPC_TRACE=api,channel,call_error,connectivity_state,http,server_channel
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld# python
>> greeter_client.py
>> Traceback (most recent call last):
>> File "greeter_client.py", line 35, in <module>
>> run()
>> File "greeter_client.py", line 30, in run
>> response = stub.SayHello(helloworld_pb2.HelloRequest(name='you'))
>> File "/usr/local/lib/python2.7/dist-packages/grpc/_channel.py", line
>> 533, in __call__
>> return _end_unary_response_blocking(state, call, False, None)
>> File "/usr/local/lib/python2.7/dist-packages/grpc/_channel.py", line
>> 467, in _end_unary_response_blocking
>> raise _Rendezvous(state, None, None, deadline)
>> grpc._channel._Rendezvous: <_Rendezvous of RPC that terminated with:
>> status = StatusCode.UNAVAILABLE
>> details = "Socket closed"
>> debug_error_string =
>> "{"created":"@1541799229.437917379","description":"Error received from
>> peer","file":"src/core/lib/surface/call.cc","file_line":1017,"grpc_message":"Socket
>>
>> closed","grpc_status":14}"
>> >
>> root@fmx216:~/gRPC/grpc/examples/python/helloworld#
>>
>> thanks
>> Ankit
>>
>> On Friday, November 9, 2018 at 11:52:45 AM UTC-8, [email protected] wrote:
>>>
>>> Hi Ankit,
>>>
>>> There is one more thing I would like to try. Can you provide the
>>> detailed tracing log for the example server and client?
>>> The trace and debug logs can be turned on by setting environment
>>> variables.
>>>
>>> $ GRPC_VERBOSITY=DEBUG
>>> GRPC_TRACE=api,channel,call_error,connectivity_state,http,server_channel
>>> python greeter_server.py
>>> $ GRPC_VERBOSITY=DEBUG
>>> GRPC_TRACE=api,channel,call_error,connectivity_state,http,server_channel
>>> python greeter_client.py
>>>
>>> Thank you for helping us solve this issue.
>>>
>>> Lidi Zheng
>>>
>>> On Friday, November 9, 2018 at 10:00:33 AM UTC-8, [email protected]
>>> wrote:
>>>>
>>>> Hi Lidi
>>>>
>>>> $ python -m SimpleHTTPServer 8000
>>>> $ curl http://localhost:8000 *Works.*
>>>>
>>>>
>>>> $ nc -6 -l 1234 # in one terminal
>>>> $ echo 'Hello' | nc localhost 1234 # in another terminal
>>>> # The 'Hello' should appear in the first terminal *Works.*
>>>>
>>>>
>>>> # Check your gRPC Python version
>>>> $ pip show grpcio
>>>>
>>>> ---
>>>> Metadata-Version: 2.1
>>>> Name: grpcio
>>>> Version: 1.16.0
>>>> Summary: HTTP/2-based RPC framework
>>>> Home-page: https://grpc.io
>>>> Author: The gRPC Authors
>>>> Author-email: [email protected]
>>>> Installer: pip
>>>> License: Apache License 2.0
>>>> Location: /usr/local/lib/python2.7/dist-packages
>>>> Requires: six, enum34, futures
>>>> Classifiers:
>>>> Development Status :: 5 - Production/Stable
>>>> Programming Language :: Python
>>>> Programming Language :: Python :: 2
>>>> Programming Language :: Python :: 2.7
>>>> Programming Language :: Python :: 3
>>>> Programming Language :: Python :: 3.4
>>>> Programming Language :: Python :: 3.5
>>>> Programming Language :: Python :: 3.6
>>>> License :: OSI Approved :: Apache Software License
>>>>
>>>> i am still not able to make it work :(
>>>>
>>>> thanks
>>>> Ankit
>>>>
>>>>
>>>> On Thursday, November 8, 2018 at 2:50:56 PM UTC-8, [email protected]
>>>> wrote:
>>>>>
>>>>> Hi Ankit,
>>>>>
>>>>> Sorry to hear that none of the fixes work for you. If the server is
>>>>> started and able to bind tcp port, there is a great chance that this
>>>>> might
>>>>> due to some local network setting.
>>>>>
>>>>> To detect whether there is a local network issue or gRPC Python issue,
>>>>> can you try to start other applications that bind a port and try to
>>>>> communicate with it?
>>>>>
>>>>> For example, you can try:
>>>>>
>>>>> $ python -m SimpleHTTPServer 8000
>>>>> $ curl http://localhost:8000
>>>>> # Or visit this address in browser, it should show up a valid HTML web
>>>>> page
>>>>>
>>>>> or
>>>>>
>>>>> $ nc -6 -l 1234 # in one terminal
>>>>> $ echo 'Hello' | nc localhost 1234 # in another terminal
>>>>> # The 'Hello' should appear in the first terminal
>>>>>
>>>>> If one of those work, then there might be some issue with gRPC Python.
>>>>> If so, can you provide your gRPC Python version as well?
>>>>> # Check your gRPC Python version
>>>>> $ pip show grpcio
>>>>>
>>>>> Bests,
>>>>> Lidi
>>>>>
>>>>>
>>>>> On Thursday, November 8, 2018 at 9:18:32 AM UTC-8,
>>>>> [email protected] wrote:
>>>>>>
>>>>>> Hi Lidi
>>>>>>
>>>>>> greeterserver.py is running on a terminal from other terminal i can
>>>>>> see that port 50051 is bound to python
>>>>>> # lsof -i :50051
>>>>>> COMMAND PID USER FD TYPE DEVICE SIZE/OFF NODE NAME
>>>>>> python 18126 root 6u IPv6 32226 0t0 TCP *:50051 (LISTEN)
>>>>>> root@fmx215:~/gRPC/grpc/examples/python/helloworld#
>>>>>>
>>>>>> I am behind corporate proxy; but i think they are working fine since
>>>>>> I am able to reach ernal internet as well as reach to nodes on internal
>>>>>> network
>>>>>> i am not sure if corporate proxy would alter or redirect any traffic
>>>>>> here.
>>>>>>
>>>>>>
>>>>>> following is my /etc/hosts file
>>>>>> 127.0.0.1 localhost
>>>>>> 127.0.1.1 fmx215.xx.xxx.com fmx215
>>>>>>
>>>>>> # The following lines are desirable for IPv6 capable hosts
>>>>>> ::1 localhost ip6-localhost ip6-loopback
>>>>>> ff02::1 ip6-allnodes
>>>>>> ff02::2 ip6-allrouters
>>>>>>
>>>>>> I am still unable to get this example working. please let me know
>>>>>> what else i can check or do to make it working
>>>>>>
>>>>>> thanks
>>>>>> Ankit
>>>>>> On Wednesday, November 7, 2018 at 4:40:59 PM UTC-8, [email protected]
>>>>>> wrote:
>>>>>>>
>>>>>>> Hi Ankit,
>>>>>>>
>>>>>>> After the greeter_server.py started, have you observed the port
>>>>>>> 50051 been bound in your system? Also, have you use any sort of VPN or
>>>>>>> local proxy that may redirect the traffic? One more check, is there a
>>>>>>> loopback entry for 'localhost' in your /etc/hosts?
>>>>>>>
>>>>>>> Lidi
>>>>>>>
>>>>>>> On Wednesday, November 7, 2018 at 4:26:48 PM UTC-8,
>>>>>>> [email protected] wrote:
>>>>>>>>
>>>>>>>> I have ubuntu server system
>>>>>>>>
>>>>>>>> #lsb_release -a
>>>>>>>> Distributor ID: Ubuntu
>>>>>>>> Description: Ubuntu 16.04.5 LTS
>>>>>>>> Release: 16.04
>>>>>>>> Codename: xenial
>>>>>>>>
>>>>>>>> #uname -a
>>>>>>>> Linux fmx215 4.10.0-42-generic #46~16.04.1-Ubuntu SMP Mon Dec 4
>>>>>>>> 15:57:59 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux
>>>>>>>>
>>>>>>>> I am trying to run python quick start example step by step as
>>>>>>>> explained on https://grpc.io/docs/quickstart/python.html
>>>>>>>> <https://grpc.io/docs/quickstart/python.html>
>>>>>>>>
>>>>>>>> greeter_server.py runs without any error and wait for
>>>>>>>> greeter_client.py
>>>>>>>>
>>>>>>>> while running greeter_client.py it fails.
>>>>>>>>
>>>>>>>> # python greeter_client.py
>>>>>>>> Traceback (most recent call last):
>>>>>>>> File "greeter_client.py", line 35, in <module>
>>>>>>>> run()
>>>>>>>> File "greeter_client.py", line 30, in run
>>>>>>>> response =
>>>>>>>> stub.SayHello(helloworld_pb2.HelloRequest(name='you'))
>>>>>>>> File "/usr/local/lib/python2.7/dist-packages/grpc/_channel.py",
>>>>>>>> line 533, in __call__
>>>>>>>> return _end_unary_response_blocking(state, call, False, None)
>>>>>>>> File "/usr/local/lib/python2.7/dist-packages/grpc/_channel.py",
>>>>>>>> line 467, in _end_unary_response_blocking
>>>>>>>> raise _Rendezvous(state, None, None, deadline)
>>>>>>>> grpc._channel._Rendezvous: <_Rendezvous of RPC that terminated with:
>>>>>>>> status = StatusCode.UNAVAILABLE
>>>>>>>> details = "Socket closed"
>>>>>>>> debug_error_string =
>>>>>>>> "{"created":"@1541634693.250829735","description":"Error received from
>>>>>>>> peer","file":"src/core/lib/surface/call.cc","file_line":1017,"grpc_message":"Socket
>>>>>>>>
>>>>>>>> closed","grpc_status":14}"
>>>>>>>>
>>>>>>>>
>>>>>>>> https://stackoverflow.com/questions/53129263/run-grpc-python-example-must-use-sudo-command
>>>>>>>>
>>>>>>>> <https://stackoverflow.com/questions/53129263/run-grpc-python-example-must-use-sudo-command>seem
>>>>>>>>
>>>>>>>> to be same issue but adding "sudo" doesn't help me. I am already
>>>>>>>> running
>>>>>>>> this exercise as root
>>>>>>>>
>>>>>>>> I have checked system using "netstat" and "lsof" and port 50051 is
>>>>>>>> not used by any process.
>>>>>>>>
>>>>>>>> can you help me in running this quick start example without any
>>>>>>>> errors.
>>>>>>>>
>>>>>>>> thank you
>>>>>>>> Ankit
>>>>>>>>
>>>>>>>
--
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/cb88716c-7f91-4dc2-86d1-b2a6b5455d64%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
