python quick start "hello world" example fails

[ankitpa...@gmail.com]

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 

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 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

[li...@google.com]

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

[ankitpa...@gmail.com]

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 

[li...@google.com]

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

[ankitpa...@gmail.com]

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: grp...@googlegroups.com

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

[li...@google.com]

Hi Ankit,

I span up a Ubuntu 16.04 machine to investigate this issue. I used the default Python2.7, and installed `pip` through `get-pip.py`. And I Installed `grpcio` through `pip`.

Here are the version infos of my machine:

$ uname -a

Linux lidiz-dev-ubuntu-16-04 4.15.0-1023-gcp #24~16.04.1-Ubuntu SMP Wed Oct 10 20:39:59 UTC 2018 x86_64 x86_64 x86_64 GNU/Linux

$ pip show grpcio

Name: grpcio

Version: 1.16.0

Summary: HTTP/2-based RPC framework

Home-page: https://grpc.io

Author: The gRPC Authors

Author-email: grp...@googlegroups.com

License: Apache License 2.0

Location: /home/lidiz/.local/lib/python2.7/site-packages

Requires: six, enum34, futures

Required-by: grpcio-tools

The command I run to get example to work:

# Installation

$ curl https://bootstrap.pypa.io/get-pip.py | sudo python

$ python -m pip install grpcio grpcio-tools googleapis-common-protos --user # Install gRPC and dependencies for the example

# First Terminal

lidiz@lidiz-dev-ubuntu-16-04:~/src/grpc/examples/python/helloworld$ python greeter_server.py

# Second Terminal

lidiz@lidiz-dev-ubuntu-16-04:~/src/grpc/examples/python/helloworld$ python greeter_client.py

Greeter client received: Hello, you!

It works on my machine, I guess there is a difference in the settings. I will continue to investigate this.

Lidi Zheng

[li...@google.com]

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

[ankitpa...@gmail.com]

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

[li...@google.com]

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

[ankitpa...@gmail.com]

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}"

[ankitpa...@gmail.com]

here is server log; i killed server at the end by cntrl+c

I1109 15:02:26.154260194   13636 ev_epoll1_linux.cc:116]     grpc epoll fd: 3

D1109 15:02:26.154434159   13636 ev_posix.cc:169]            Using polling engine: epoll1

D1109 15:02:26.154530558   13636 dns_resolver.cc:338]        Using native dns resolver

I1109 15:02:26.155007186   13636 init.cc:153]                grpc_init(void)

I1109 15:02:26.155213454   13636 completion_queue.cc:474]    grpc_completion_queue_create_internal(completion_type=0, polling_type=0)

I1109 15:02:26.155388910   13636 init.cc:153]                grpc_init(void)

I1109 15:02:26.155498555   13636 server.cc:994]              grpc_server_create((nil), (nil))

I1109 15:02:26.155608844   13636 server.cc:979]              grpc_server_register_completion_queue(server=0x10423b0, cq=0x10e4590, reserved=(nil))

I1109 15:02:26.156318834   13636 server_chttp2.cc:34]        grpc_server_add_insecure_http2_port(server=0x10423b0, addr=[::]:50051)

I1109 15:02:26.156495695   13636 socket_utils_common_posix.cc:310] TCP_USER_TIMEOUT not supported for this platform

I1109 15:02:26.156648253   13636 init.cc:153]                grpc_init(void)

I1109 15:02:26.156761086   13636 completion_queue.cc:474]    grpc_completion_queue_create_internal(completion_type=0, polling_type=1)

I1109 15:02:26.156907935   13636 server.cc:979]              grpc_server_register_completion_queue(server=0x10423b0, cq=0x10b5210, reserved=(nil))

I1109 15:02:26.157128476   13636 server.cc:1089]             grpc_server_start(server=0x10423b0)

I1109 15:02:26.157323173   13636 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10b5210, deadline=gpr_timespec { tv_sec: 1541804546, tv_nsec: 157310009, clock_type: 1 }, reserved=(nil))

I1109 15:02:26.158580160   13636 init.cc:153]                grpc_init(void)

I1109 15:02:26.158879985   13636 init.cc:153]                grpc_init(void)

I1109 15:02:26.159027389   13636 call_details.cc:31]         grpc_call_details_init(cd=0x7fdfe3e076d0)

I1109 15:02:26.159205197   13636 metadata_array.cc:29]       grpc_metadata_array_init(array=0x7fdfe3dd52f0)

I1109 15:02:26.159393998   13636 server.cc:1417]             grpc_server_request_call(server=0x10423b0, call=0x7fdfe3dc85e0, details=0x7fdfe3e076d0, initial_metadata=0x7fdfe3dd52f0, cq_bound_to_call=0x10e4590, cq_for_notification=0x10e4590, tag=0x7fdfe3dd52c0)

I1109 15:02:26.159928640   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804546, tv_nsec: 359917975, clock_type: 1 }, reserved=(nil))

I1109 15:02:26.360656578   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804546, tv_nsec: 560638312, clock_type: 1 }, reserved=(nil))

I1109 15:02:26.561583802   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804546, tv_nsec: 761562116, clock_type: 1 }, reserved=(nil))

I1109 15:02:26.763345769   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804546, tv_nsec: 963336198, clock_type: 1 }, reserved=(nil))

I1109 15:02:26.965018149   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804547, tv_nsec: 165008451, clock_type: 1 }, reserved=(nil))

I1109 15:02:27.165804716   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804547, tv_nsec: 365797710, clock_type: 1 }, reserved=(nil))

I1109 15:02:27.367464272   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804547, tv_nsec: 567454529, clock_type: 1 }, reserved=(nil))

I1109 15:02:27.569251508   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804547, tv_nsec: 769241829, clock_type: 1 }, reserved=(nil))

I1109 15:02:27.771057300   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804547, tv_nsec: 971047705, clock_type: 1 }, reserved=(nil))

I1109 15:02:27.971863679   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804548, tv_nsec: 171853865, clock_type: 1 }, reserved=(nil))

I1109 15:02:28.172670779   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804548, tv_nsec: 372661066, clock_type: 1 }, reserved=(nil))

I1109 15:02:28.374448318   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804548, tv_nsec: 574438418, clock_type: 1 }, reserved=(nil))

I1109 15:02:28.576239230   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804548, tv_nsec: 776229414, clock_type: 1 }, reserved=(nil))

I1109 15:02:28.777999303   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804548, tv_nsec: 977989615, clock_type: 1 }, reserved=(nil))

I1109 15:02:28.978823786   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804549, tv_nsec: 178814027, clock_type: 1 }, reserved=(nil))

I1109 15:02:29.179625639   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804549, tv_nsec: 379618664, clock_type: 1 }, reserved=(nil))

I1109 15:02:29.381323942   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804549, tv_nsec: 581314314, clock_type: 1 }, reserved=(nil))

I1109 15:02:29.583042183   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804549, tv_nsec: 783032618, clock_type: 1 }, reserved=(nil))

I1109 15:02:29.783814207   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804549, tv_nsec: 983804709, clock_type: 1 }, reserved=(nil))

I1109 15:02:29.984460463   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804550, tv_nsec: 184453477, clock_type: 1 }, reserved=(nil))

I1109 15:02:30.186100300   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804550, tv_nsec: 386090576, clock_type: 1 }, reserved=(nil))

I1109 15:02:30.386811208   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804550, tv_nsec: 586801471, clock_type: 1 }, reserved=(nil))

I1109 15:02:30.587600940   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804550, tv_nsec: 787591288, clock_type: 1 }, reserved=(nil))

I1109 15:02:30.789383471   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804550, tv_nsec: 989373774, clock_type: 1 }, reserved=(nil))

I1109 15:02:30.991083179   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804551, tv_nsec: 191073378, clock_type: 1 }, reserved=(nil))

I1109 15:02:31.191883048   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804551, tv_nsec: 391873315, clock_type: 1 }, reserved=(nil))

I1109 15:02:31.392702102   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804551, tv_nsec: 592690574, clock_type: 1 }, reserved=(nil))

I1109 15:02:31.594502801   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804551, tv_nsec: 794492990, clock_type: 1 }, reserved=(nil))

I1109 15:02:31.796204742   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804551, tv_nsec: 996195133, clock_type: 1 }, reserved=(nil))

I1109 15:02:31.997994614   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804552, tv_nsec: 197985041, clock_type: 1 }, reserved=(nil))

I1109 15:02:32.198715744   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804552, tv_nsec: 398708689, clock_type: 1 }, reserved=(nil))

I1109 15:02:32.400364488   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804552, tv_nsec: 600354769, clock_type: 1 }, reserved=(nil))

I1109 15:02:32.602133164   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804552, tv_nsec: 802123429, clock_type: 1 }, reserved=(nil))

I1109 15:02:32.802914210   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804553, tv_nsec: 2904515, clock_type: 1 }, reserved=(nil))

I1109 15:02:33.003742371   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804553, tv_nsec: 203732851, clock_type: 1 }, reserved=(nil))

I1109 15:02:33.022217664   13636 server.cc:1253]             grpc_server_shutdown_and_notify(server=0x10423b0, cq=0x10e4590, tag=0x7fdfe3e27b90)

I1109 15:02:33.022602437   13636 completion_queue.cc:692]    cq_end_op_for_next(cq=0x10e4590, tag=0x7fdfe3dd52c0, error={"created":"@1541804553.022579805","description":"Server Shutdown","file":"src/core/lib/surface/server.cc","file_line":1291}, done=0x7fdfe5ba0100, done_arg=0x1168a10, storage=0x1168a48)

I1109 15:02:33.022879659   13636 completion_queue.cc:692]    cq_end_op_for_next(cq=0x10e4590, tag=0x7fdfe3e27b90, error="No Error", done=0x7fdfe5ba21a0, done_arg=0x10423b0, storage=0xf8e340)

I1109 15:02:33.022918327   13641 completion_queue.cc:1056]   RETURN_EVENT[0x10e4590]: OP_COMPLETE: tag:0x7fdfe3dd52c0 ERROR

I1109 15:02:33.023368555   13641 metadata_array.cc:34]       grpc_metadata_array_destroy(array=0x7fdfe3dd52f0)

I1109 15:02:33.023879476   13641 init.cc:158]                grpc_shutdown(void)

I1109 15:02:33.024085587   13641 call_details.cc:38]         grpc_call_details_destroy(cd=0x7fdfe3e076d0)

I1109 15:02:33.024102496   13646 server.cc:1312]             grpc_server_cancel_all_calls(server=0x10423b0)

I1109 15:02:33.024347567   13641 init.cc:158]                grpc_shutdown(void)

I1109 15:02:33.024829900   13641 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 1541804553, tv_nsec: 224825311, clock_type: 1 }, reserved=(nil))

I1109 15:02:33.024919689   13636 server.cc:1312]             grpc_server_cancel_all_calls(server=0x10423b0)

I1109 15:02:33.025075494   13641 completion_queue.cc:1056]   RETURN_EVENT[0x10e4590]: OP_COMPLETE: tag:0x7fdfe3e27b90 OK

I1109 15:02:33.025697917   13636 server.cc:1327]             grpc_server_destroy(server=0x10423b0)

I1109 15:02:33.025983722   13636 init.cc:158]                grpc_shutdown(void)

I1109 15:02:33.026198861   13636 completion_queue.cc:1382]   grpc_completion_queue_shutdown(cq=0x10b5210)

I1109 15:02:33.026450399   13636 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10b5210, deadline=gpr_timespec { tv_sec: 9223372036854775807, tv_nsec: 0, clock_type: 1 }, reserved=(nil))

I1109 15:02:33.026690807   13636 completion_queue.cc:1056]   RETURN_EVENT[0x10b5210]: QUEUE_SHUTDOWN

I1109 15:02:33.026937828   13636 completion_queue.cc:1388]   grpc_completion_queue_destroy(cq=0x10b5210)

I1109 15:02:33.027185446   13636 completion_queue.cc:1382]   grpc_completion_queue_shutdown(cq=0x10b5210)

I1109 15:02:33.027445231   13636 init.cc:158]                grpc_shutdown(void)

I1109 15:02:33.027693717   13636 completion_queue.cc:1382]   grpc_completion_queue_shutdown(cq=0x10e4590)

I1109 15:02:33.027948928   13636 completion_queue.cc:956]    grpc_completion_queue_next(cq=0x10e4590, deadline=gpr_timespec { tv_sec: 9223372036854775807, tv_nsec: 0, clock_type: 1 }, reserved=(nil))

I1109 15:02:33.028242936   13636 completion_queue.cc:1056]   RETURN_EVENT[0x10e4590]: QUEUE_SHUTDOWN

I1109 15:02:33.028485199   13636 completion_queue.cc:1388]   grpc_completion_queue_destroy(cq=0x10e4590)

I1109 15:02:33.028706949   13636 completion_queue.cc:1382]   grpc_completion_queue_shutdown(cq=0x10e4590)

I1109 15:02:33.028956902   13636 init.cc:158]                grpc_shutdown(void)

[Lidi Zheng]

Hi Ankit,

Thanks for providing the trace log. I will look into it and update if I found anything.

Lidi Zheng

--
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/06e2c008-27d7-48ac-9f65-a976cc8c450e%40googlegroups.com.
For more options, visit https://groups.google.com/d/optout.

[ankitpa...@gmail.com]

Today i tried this example on Centos 7.5 machine and i had same issue. 

...

[Srini Polavarapu]

Your connection issue is due to proxy configuration on your machine. As you can see gRPC is connecting to a proxy for localhost.

http_connect_handshaker.cc:300] Connecting to server localhost:50051 via HTTP proxy ipv4:10.19.8.225:912

Try setting http_proxy= or try no_proxy=localhost.

...

[ankit patel]

Thanks Srini; it worked after this particular addition to my proxy settings.
we can mark this thread as resolved now

> --
> You received this message because you are subscribed to a topic in the Google Groups "grpc.io" group.
> To unsubscribe from this topic, visit https://groups.google.com/d/topic/grpc-io/wfGKWUZaPKc/unsubscribe.
> To unsubscribe from this group and all its topics, 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/e8cfb5f1-aa34-4f5a-b519-69bfa1fc01f0%40googlegroups.com.

> For more options, visit https://groups.google.com/d/optout.

--
Thanks,
Ankit Patel

[ankitpa...@gmail.com]

Thanks Srini; it worked after this particular addition to  my proxy settings.
we can mark this thread as resolved now

...