Skip to content

Instantly share code, notes, and snippets.

Embed
What would you like to do?
python-grpcio-1.3.0-error
D0510 16:54:32.514515313 13 ev_posix.c:107] Using polling engine: poll
D0510 16:54:32.514565254 13 dns_resolver.c:316] Using native dns resolver
### NOTE: First RPC issued
I0510 16:54:47.539423892 13 client_channel.c:1229] OP[client-channel:0xb75de8]: [COVERED] 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', 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=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'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
D0510 16:54:47.540472938 18 connectivity_state.c:110] CONWATCH: 0x7fee90001e00 (null): get IDLE
D0510 16:54:47.540524277 18 connectivity_state.c:185] SET: 0xb51368 client_channel: IDLE --> IDLE [new_lb+resolver] error=(nil) "No Error"
D0510 16:54:47.540537960 18 connectivity_state.c:135] CONWATCH: 0x7fee90001e00 (null): from IDLE [cur=IDLE] notify=0x7fee90002e08
D0510 16:54:47.540551844 18 connectivity_state.c:135] CONWATCH: 0x7fee900027a0 subchannel: from IDLE [cur=IDLE] notify=0x7fee90000ab8
D0510 16:54:47.540569323 18 connectivity_state.c:185] SET: 0x7fee900027a0 subchannel: IDLE --> CONNECTING [state_change] error=(nil) "No Error"
D0510 16:54:47.540580410 18 connectivity_state.c:212] NOTIFY: 0x7fee900027a0 subchannel: 0x7fee90000ab8
D0510 16:54:47.540850686 18 tcp_client_posix.c:334] CLIENT_CONNECT: ipv6:[::1]:50051: asynchronously connecting
D0510 16:54:47.540900227 18 connectivity_state.c:185] SET: 0x7fee90001e00 (null): IDLE --> CONNECTING [connecting_changed] error=(nil) "No Error"
D0510 16:54:47.540912413 18 connectivity_state.c:212] NOTIFY: 0x7fee90001e00 (null): 0x7fee90002e08
D0510 16:54:47.540944374 18 connectivity_state.c:135] CONWATCH: 0x7fee900027a0 subchannel: from CONNECTING [cur=CONNECTING] notify=0x7fee90000ab8
D0510 16:54:47.540957359 18 connectivity_state.c:185] SET: 0xb51368 client_channel: IDLE --> CONNECTING [lb_changed] error=(nil) "No Error"
D0510 16:54:47.540962453 13 tcp_client_posix.c:157] CLIENT_CONNECT: ipv6:[::1]:50051: on_writable: error="No Error"
D0510 16:54:47.540989720 18 connectivity_state.c:135] CONWATCH: 0x7fee90001e00 (null): from CONNECTING [cur=CONNECTING] notify=0x7fee90001d08
D0510 16:54:47.541008498 13 tcp_client_posix.c:119] CLIENT_CONNECT: ipv6:[::1]:50051: on_alarm: error="Cancelled"
D0510 16:54:47.541108978 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state IDLE -> WRITING [initial_write]
D0510 16:54:47.541138443 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> WRITING+MORE [push_setting]
D0510 16:54:47.541200269 13 connectivity_state.c:185] SET: 0x7fee900027a0 subchannel: CONNECTING --> READY [connected] error=(nil) "No Error"
D0510 16:54:47.541232031 13 connectivity_state.c:212] NOTIFY: 0x7fee900027a0 subchannel: 0x7fee90000ab8
D0510 16:54:47.541289862 13 connectivity_state.c:135] CONWATCH: 0xb78d48 client_transport: from READY [cur=READY] notify=0xb77af0
D0510 16:54:47.541342798 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING+MORE -> WRITING [begin writing]
D0510 16:54:47.541387645 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 50 52 49 20 2a 20 48 54 54 50 2f 32 2e 30 0d 0a 0d 0a 53 4d 0d 0a 0d 0a 'PRI * HTTP/2.0....SM....'
D0510 16:54:47.541407021 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 1e 04 00 00 00 00 00 00 02 00 00 00 00 00 03 00 00 00 00 00 04 00 00 ff ff 00 06 00 00 40 00 fe 03 00 00 00 01 '...............................@.......'
D0510 16:54:47.541455963 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 04 08 00 00 00 00 00 7f ff 00 00 '.............'
D0510 16:54:47.541540862 13 tcp_posix.c:531] write: "No Error"
D0510 16:54:47.541585708 13 connectivity_state.c:185] SET: 0x7fee90001e00 (null): CONNECTING --> READY [connecting_ready] error=(nil) "No Error"
D0510 16:54:47.541621565 13 connectivity_state.c:212] NOTIFY: 0x7fee90001e00 (null): 0x7fee90001d08
D0510 16:54:47.541691981 13 connectivity_state.c:185] SET: 0xb51368 client_channel: CONNECTING --> READY [lb_changed] error=(nil) "No Error"
D0510 16:54:47.541730035 13 connectivity_state.c:135] CONWATCH: 0x7fee90001e00 (null): from READY [cur=READY] notify=0xb6f968
I0510 16:54:47.541818630 13 http_client_filter.c:427] OP[http-client:0xb7c968]: [COVERED] 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', 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=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'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0510 16:54:47.541972546 13 connected_channel.c:70] OP[connected:0xb7c998]: [COVERED] 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 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=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=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=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 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
D0510 16:54:47.542093101 13 chttp2_transport.c:1459] perform_stream_op[s=0xb7cd00/0]: [COVERED] 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 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=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=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=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 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
D0510 16:54:47.542173705 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> IDLE [finish writing]
D0510 16:54:47.542207764 13 connectivity_state.c:135] CONWATCH: 0xb78d48 client_transport: from READY [cur=READY] notify=0x7fee90001db0
D0510 16:54:47.542309243 13 chttp2_transport.c:1224] perform_stream_op_locked: [COVERED] 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 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=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=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=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 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA; on_complete = 0xb7cc18
I0510 16:54:47.542375164 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :scheme: http
I0510 16:54:47.542394441 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :method: POST
I0510 16:54:47.542412020 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :path: /helloworld.Greeter/SayHello
I0510 16:54:47.542430998 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :authority: localhost:50051
I0510 16:54:47.542474546 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: grpc-accept-encoding: identity,deflate,gzip
I0510 16:54:47.542491625 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: te: trailers
I0510 16:54:47.542508205 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: content-type: application/grpc
D0510 16:54:47.542612880 13 chttp2_transport.c:1020] HTTP:CLI: Allocating new grpc_chttp2_stream 0xb7cd00 to id 1
D0510 16:54:47.542713060 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state IDLE -> WRITING [new_stream]
D0510 16:54:47.542814539 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> WRITING+MORE+COVERED [op.send_message]
D0510 16:54:47.542865278 13 chttp2_transport.c:1086] complete_closure_step: 0xb7cc18 refs=4 flags=0x0003 desc=op->on_complete err="No Error"
D0510 16:54:47.542914719 13 writing.c:218] W:0xb78b00 CLIENT[1] im-(sent,send)=(0,1) announce=5
D0510 16:54:47.542939190 13 hpack_encoder.c:432] Encode: ':path: /helloworld.Greeter/SayHello', elem_interned=0 [2], k_interned=1, v_interned=0
D0510 16:54:47.542994224 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING+MORE+COVERED -> WRITING [begin writing]
D0510 16:54:47.543034376 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 fb 01 04 00 00 00 01 40 07 '.........@.'
D0510 16:54:47.543047461 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 3a 73 63 68 65 6d 65 ':scheme'
D0510 16:54:47.543071532 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 04 '.'
D0510 16:54:47.543082619 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 68 74 74 70 'http'
D0510 16:54:47.543115380 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 40 07 '@.'
D0510 16:54:47.543127465 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 3a 6d 65 74 68 6f 64 ':method'
D0510 16:54:47.543156730 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 04 '.'
D0510 16:54:47.543169315 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 50 4f 53 54 'POST'
D0510 16:54:47.543180901 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 05 '..'
D0510 16:54:47.543223550 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 3a 70 61 74 68 ':path'
D0510 16:54:47.543262404 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 1c '.'
D0510 16:54:47.543280582 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 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'
D0510 16:54:47.543300059 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 40 0a '@.'
D0510 16:54:47.543324330 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 3a 61 75 74 68 6f 72 69 74 79 ':authority'
D0510 16:54:47.543335417 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 0f '.'
D0510 16:54:47.543364781 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 6c 6f 63 61 6c 68 6f 73 74 3a 35 30 30 35 31 'localhost:50051'
D0510 16:54:47.543388953 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 40 14 '@.'
D0510 16:54:47.543421813 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 'grpc-accept-encoding'
D0510 16:54:47.543457071 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 15 '.'
D0510 16:54:47.543492329 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 'identity,deflate,gzip'
D0510 16:54:47.543514702 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 40 02 '@.'
D0510 16:54:47.543546464 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 74 65 'te'
D0510 16:54:47.543562645 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 08 '.'
D0510 16:54:47.543593109 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 74 72 61 69 6c 65 72 73 'trailers'
D0510 16:54:47.543636656 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 40 0c '@.'
D0510 16:54:47.543687596 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 63 6f 6e 74 65 6e 74 2d 74 79 70 65 'content-type'
D0510 16:54:47.543713065 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 10 '.'
D0510 16:54:47.543736837 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'
D0510 16:54:47.543780385 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 40 0a '@.'
D0510 16:54:47.543819838 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 75 73 65 72 2d 61 67 65 6e 74 'user-agent'
D0510 16:54:47.543862586 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 3a ':'
D0510 16:54:47.543903038 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 67 72 70 63 2d 70 79 74 68 6f 6e 2f 31 2e 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'
D0510 16:54:47.543948084 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 04 08 00 00 00 00 01 00 00 00 05 00 00 '...............'
D0510 16:54:47.543968660 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 0a 00 01 00 00 00 01 00 00 00 00 05 0a 03 79 '..............y'
D0510 16:54:47.544004017 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 6f 75 'ou'
D0510 16:54:47.544088516 13 tcp_posix.c:531] write: "No Error"
D0510 16:54:47.544128069 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> IDLE [finish writing]
D0510 16:54:47.544166923 13 chttp2_transport.c:1086] complete_closure_step: 0xb7cc18 refs=3 flags=0x0003 desc=send_initial_metadata_finished err="No Error"
D0510 16:54:47.544213667 13 chttp2_transport.c:1086] complete_closure_step: 0xb7cc18 refs=2 flags=0x0003 desc=finish_write_cb err="No Error"
D0510 16:54:47.544249124 13 chttp2_transport.c:1086] complete_closure_step: 0xb7cc18 refs=1 flags=0x0003 desc=send_trailing_metadata_finished err="No Error"
D0510 16:54:47.544314646 13 tcp_posix.c:227] read: error="No Error"
D0510 16:54:47.544348805 13 tcp_posix.c:232] READ 0xb77250 (peer=ipv6:[::1]:50051): 00 00 12 04 00 00 00 00 00 00 04 00 00 ff ff 00 06 00 00 40 00 fe 03 00 00 00 01 00 00 04 08 00 00 00 00 00 7f ff 00 00 00 00 00 04 01 00 00 00 00 '...................@.............................'
D0510 16:54:47.544376073 13 frame_settings.c:228] CHTTP2:CLI:ipv6:[::1]:50051: got setting INITIAL_WINDOW_SIZE = 65535
D0510 16:54:47.544400843 13 frame_settings.c:228] CHTTP2:CLI:ipv6:[::1]:50051: got setting MAX_HEADER_LIST_SIZE = 16384
D0510 16:54:47.544422118 13 frame_settings.c:228] CHTTP2:CLI:ipv6:[::1]:50051: got setting GRPC_ALLOW_TRUE_BINARY_METADATA = 1
D0510 16:54:47.544447587 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state IDLE -> WRITING [send_ping]
D0510 16:54:47.544469761 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> WRITING [begin writing]
D0510 16:54:47.544497627 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 00 04 01 00 00 00 00 '.........'
D0510 16:54:47.544540476 13 tcp_posix.c:531] write: "No Error"
D0510 16:54:47.544576333 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> IDLE [finish writing]
D0510 16:54:47.545646055 13 tcp_posix.c:227] read: error="No Error"
D0510 16:54:47.545700689 13 tcp_posix.c:232] READ 0xb77250 (peer=ipv6:[::1]:50051): 00 00 58 01 04 00 00 00 01 40 07 3a 73 74 61 74 75 73 03 32 30 30 40 0c 63 6f 6e 74 65 6e 74 2d 74 79 70 65 10 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 40 14 67 72 70 63 2d 61 63 63 65 70 74 2d 65 6e 63 6f 64 69 6e 67 15 69 64 65 6e 74 69 74 79 2c 64 65 66 6c 61 74 65 2c 67 7a 69 70 00 00 12 00 00 00 00 00 01 00 00 00 00 0d 0a 0b 48 65 6c 6c 6f 2c 20 79 6f 75 21 00 00 1e 01 05 00 00 00 01 40 0b 67 72 70 63 2d 73 74 61 74 75 73 01 30 00 0c 67 72 70 63 2d 6d 65 73 73 61 67 65 00 '..X......@.:status.200@.content-type.application/grpc@.grpc-accept-encoding.identity,deflate,gzip................Hello, you!.........@.grpc-status.0..grpc-message.'
I0510 16:54:47.545741241 13 parsing.c:500] HTTP:1:HDR:CLI: :status: 32 30 30 '200'
I0510 16:54:47.545764413 13 parsing.c:500] HTTP:1:HDR:CLI: content-type: 61 70 70 6c 69 63 61 74 69 6f 6e 2f 67 72 70 63 'application/grpc'
I0510 16:54:47.545807462 13 parsing.c:500] HTTP:1:HDR:CLI: 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'
I0510 16:54:47.545844717 13 parsing.c:580] HTTP:1:TRL:CLI: grpc-status: 30 '0'
D0510 16:54:47.545884170 13 hpack_parser.c:673] Decode: 'grpc-message: ', elem_interned=0 [2], k_interned=1, v_interned=0
I0510 16:54:47.545904046 13 parsing.c:580] HTTP:1:TRL:CLI: grpc-message:
D0510 16:54:47.545940303 13 chttp2_transport.c:1086] complete_closure_step: 0xb7cc18 refs=0 flags=0x0003 desc=recv_trailing_metadata_finished err="No Error"
Greeter client received: Hello, you!
### NOTE: First RPC complete
### NOTE: Server restarted
### NOTE: Second RPC issued
I0510 16:55:02.562677648 13 client_channel.c:1229] OP[client-channel:0xb832a0]: [COVERED] 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', 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=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'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0510 16:55:02.562730885 13 http_client_filter.c:427] OP[http-client:0xb83770]: [COVERED] 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', 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=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'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
I0510 16:55:02.562757453 13 connected_channel.c:70] OP[connected:0xb837a0]: [COVERED] 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 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=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=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=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 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
D0510 16:55:02.562780525 13 chttp2_transport.c:1459] perform_stream_op[s=0xb83b08/0]: [COVERED] 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 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=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=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=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 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA
D0510 16:55:02.562810090 13 chttp2_transport.c:1224] perform_stream_op_locked: [COVERED] 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 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=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=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=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 33 2e 30 20 67 72 70 63 2d 63 2f 33 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 65 6e 74 6c 65 29 'grpc-python/1.3.0 grpc-c/3.0.0 (manylinux; chttp2; gentle)'} SEND_MESSAGE:flags=0x00000000:len=5 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA; on_complete = 0xb83a20
I0510 16:55:02.562837557 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :scheme: http
I0510 16:55:02.562846447 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :method: POST
I0510 16:55:02.562854237 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :path: /helloworld.Greeter/SayHello
I0510 16:55:02.562862028 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: :authority: localhost:50051
I0510 16:55:02.562871517 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: grpc-accept-encoding: identity,deflate,gzip
I0510 16:55:02.562880006 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: te: trailers
I0510 16:55:02.562887997 13 chttp2_transport.c:1206] HTTP:0:HDR:CLI: content-type: application/grpc
D0510 16:55:02.562896487 13 chttp2_transport.c:1020] HTTP:CLI: Allocating new grpc_chttp2_stream 0xb83b08 to id 3
D0510 16:55:02.562905476 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state IDLE -> WRITING [new_stream]
D0510 16:55:02.562914965 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> WRITING+MORE+COVERED [op.send_message]
D0510 16:55:02.562924154 13 chttp2_transport.c:1086] complete_closure_step: 0xb83a20 refs=4 flags=0x0003 desc=op->on_complete err="No Error"
D0510 16:55:02.562934142 13 writing.c:218] W:0xb78b00 CLIENT[3] im-(sent,send)=(0,1) announce=5
D0510 16:55:02.562943930 13 hpack_encoder.c:432] Encode: ':path: /helloworld.Greeter/SayHello', elem_interned=0 [2], k_interned=1, v_interned=0
D0510 16:55:02.562955616 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING+MORE+COVERED -> WRITING [begin writing]
D0510 16:55:02.562965205 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 2b 01 04 00 00 00 03 c4 c3 00 05 '..+..........'
D0510 16:55:02.562973495 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 3a 70 61 74 68 ':path'
D0510 16:55:02.562981086 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 1c '.'
D0510 16:55:02.562989276 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 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'
D0510 16:55:02.562998065 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): c2 c1 c0 bf be 00 00 04 08 00 00 00 00 03 00 '...............'
D0510 16:55:02.563022436 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 05 00 00 0a 00 01 00 00 00 03 00 00 00 '...............'
D0510 16:55:02.563060591 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 05 0a 03 79 6f 75 '....you'
D0510 16:55:02.563155477 13 tcp_posix.c:531] write: "No Error"
D0510 16:55:02.563181546 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> IDLE [finish writing]
D0510 16:55:02.563191534 13 chttp2_transport.c:1086] complete_closure_step: 0xb83a20 refs=3 flags=0x0003 desc=send_initial_metadata_finished err="No Error"
D0510 16:55:02.563200224 13 chttp2_transport.c:1086] complete_closure_step: 0xb83a20 refs=2 flags=0x0003 desc=finish_write_cb err="No Error"
D0510 16:55:02.563208014 13 chttp2_transport.c:1086] complete_closure_step: 0xb83a20 refs=1 flags=0x0003 desc=send_trailing_metadata_finished err="No Error"
D0510 16:55:02.563275434 13 tcp_posix.c:227] read: error="No Error"
D0510 16:55:02.563288318 13 tcp_posix.c:232] READ 0xb77250 (peer=ipv6:[::1]:50051): 00 00 17 07 00 00 00 00 00 00 00 00 01 00 00 00 00 53 65 72 76 65 72 20 73 68 75 74 64 6f 77 6e '.................Server shutdown'
D0510 16:55:02.563302002 13 chttp2_transport.c:2397] set connectivity_state=3
D0510 16:55:02.563321079 13 connectivity_state.c:185] SET: 0xb78d48 client_transport: READY --> TRANSIENT_FAILURE [got_goaway] error=0xb76f20 {"created":"@1494435302.563298506","description":"GOAWAY received","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.c","file_line":1001,"http2_error":0,"raw_bytes":"Server shutdown"}
D0510 16:55:02.563330868 13 connectivity_state.c:212] NOTIFY: 0xb78d48 client_transport: 0x7fee90001db0
D0510 16:55:02.563349146 13 connectivity_state.c:212] NOTIFY: 0xb78d48 client_transport: 0xb77af0
D0510 16:55:02.563363828 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state IDLE -> WRITING [push_setting]
D0510 16:55:02.563384004 13 connectivity_state.c:185] SET: 0x7fee900027a0 subchannel: READY --> SHUTDOWN [reflect_child] error=0xb76f20 {"created":"@1494435302.563298506","description":"GOAWAY received","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.c","file_line":1001,"http2_error":0,"raw_bytes":"Server shutdown"}
D0510 16:55:02.563403381 13 tcp_posix.c:227] read: error={"created":"@1494435302.563395890","description":"Socket closed","fd":8,"file":"src/core/lib/iomgr/tcp_posix.c","file_line":293,"target_address":"ipv6:[::1]:50051"}
D0510 16:55:02.563424955 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> WRITING [begin writing]
D0510 16:55:02.563434643 13 tcp_posix.c:499] WRITE 0xb77250 (peer=ipv6:[::1]:50051): 00 00 06 04 00 00 00 00 00 00 04 00 01 5f 19 '............._.'
D0510 16:55:02.563454819 13 tcp_posix.c:531] write: {"created":"@1494435302.563446829","description":"OS Error","errno":32,"file":"src/core/lib/iomgr/tcp_posix.c","file_line":424,"grpc_status":14,"os_error":"Broken pipe","syscall":"sendmsg"}
D0510 16:55:02.563466006 13 connectivity_state.c:185] SET: 0x7fee90001e00 (null): READY --> SHUTDOWN [selected_changed] error=0xb76f20 {"created":"@1494435302.563298506","description":"GOAWAY received","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.c","file_line":1001,"http2_error":0,"raw_bytes":"Server shutdown"}
D0510 16:55:02.563485583 13 connectivity_state.c:212] NOTIFY: 0x7fee90001e00 (null): 0xb6f968
D0510 16:55:02.563536622 13 connectivity_state.c:185] SET: 0xb51368 client_channel: READY --> TRANSIENT_FAILURE [lb_changed] error=0xb76f20 {"created":"@1494435302.563298506","description":"GOAWAY received","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.c","file_line":1001,"http2_error":0,"raw_bytes":"Server shutdown"}
D0510 16:55:02.563568783 13 connectivity_state.c:185] SET: 0x7fee90001e00 (null): SHUTDOWN --> SHUTDOWN [shutdown] error=0xb78450 {"created":"@1494435302.563557896","description":"Channel shutdown","file":"src/core/ext/filters/client_channel/lb_policy/pick_first/pick_first.c","file_line":104}
D0510 16:55:02.563596051 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> IDLE [finish writing]
D0510 16:55:02.563628811 13 chttp2_transport.c:2397] set connectivity_state=4
D0510 16:55:02.563668264 13 connectivity_state.c:185] SET: 0xb78d48 client_transport: TRANSIENT_FAILURE --> SHUTDOWN [close_transport] error=0xb78640 {"created":"@1494435302.563420460","description":"Delayed close due to in-progress write","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.c","file_line":591,"referenced_errors":[{"created":"@1494435302.563416565","description":"Endpoint read failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.c","file_line":2142,"grpc_status":14,"occurred_during_write":1,"referenced_errors":[{"created":"@1494435302.563395890","description":"Socket closed","fd":8,"file":"src/core/lib/iomgr/tcp_posix.c","file_line":293,"target_address":"ipv6:[::1]:50051"}]},{"created":"@1494435302.563446829","description":"OS Error","errno":32,"file":"src/core/lib/iomgr/tcp_posix.c","file_line":424,"grpc_status":14,"os_error":"Broken pipe","syscall":"sendmsg"}]}
D0510 16:55:02.563696231 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state IDLE -> WRITING [rst_stream]
D0510 16:55:02.563728093 13 chttp2_transport.c:1086] complete_closure_step: 0xb83a20 refs=0 flags=0x0003 desc=recv_trailing_metadata_finished err="No Error"
D0510 16:55:02.563740977 13 connectivity_state.c:132] CONWATCH: 0xb78d48 client_transport: unsubscribe notify=0x7fee90001db0
D0510 16:55:02.563756758 13 chttp2_transport.c:2253] ipv6:[::1]:50051: Start BDP ping
D0510 16:55:02.563764749 13 chttp2_transport.c:2266] ipv6:[::1]:50051: Complete BDP ping
D0510 16:55:02.563772440 13 chttp2_transport.c:800] W:0xb78b00 CLIENT state WRITING -> IDLE [begin writing nothing]
D0510 16:55:02.563853243 24 connectivity_state.c:110] CONWATCH: 0x7fee900016b0 (null): get IDLE
D0510 16:55:02.563869424 24 connectivity_state.c:185] SET: 0xb51368 client_channel: TRANSIENT_FAILURE --> IDLE [new_lb+resolver] error=(nil) "No Error"
D0510 16:55:02.563876815 24 connectivity_state.c:135] CONWATCH: 0x7fee900016b0 (null): from IDLE [cur=IDLE] notify=0x7fee90001788
### NOTE: Error in client code from failed RPC
Traceback (most recent call last):
File "greeter_client.py", line 53, in <module>
run()
File "greeter_client.py", line 48, in run
response = stub.SayHello(helloworld_pb2.HelloRequest(name='you'))
File "/usr/local/lib/python3.5/site-packages/grpc/_channel.py", line 507, in __call__
return _end_unary_response_blocking(state, call, False, deadline)
File "/usr/local/lib/python3.5/site-packages/grpc/_channel.py", line 455, in _end_unary_response_blocking
raise _Rendezvous(state, None, None, deadline)
grpc._channel._Rendezvous: <_Rendezvous of RPC that terminated with (StatusCode.UNAVAILABLE, Endpoint read failed)>
D0510 16:55:02.577961081 13 connectivity_state.c:185] SET: 0xb51368 client_channel: IDLE --> SHUTDOWN [disconnect] error=0xb753f0 {"created":"@1494435302.577942503","description":"Channel Destroyed","file":"src/core/lib/surface/channel.c","file_line":402}
D0510 16:55:02.578025004 13 connectivity_state.c:185] SET: 0xb51368 client_channel: SHUTDOWN --> SHUTDOWN [resolver_gone] error=0xb78450 {"created":"@1494435302.578009922","description":"Got config after disconnection","file":"src/core/ext/filters/client_channel/client_channel.c","file_line":509,"referenced_errors":[{"created":"@1494435302.577997737","description":"Resolver Shutdown","file":"src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.c","file_line":119},{"created":"@1494435302.578004029","description":"No load balancing policy","file":"src/core/ext/filters/client_channel/client_channel.c","file_line":359}]}
D0510 16:55:02.578040985 13 connectivity_state.c:185] SET: 0x7fee900016b0 (null): IDLE --> SHUTDOWN [shutdown] error=0xb753f0 {"created":"@1494435302.578034792","description":"Channel shutdown","file":"src/core/ext/filters/client_channel/lb_policy/pick_first/pick_first.c","file_line":104}
D0510 16:55:02.578058065 13 connectivity_state.c:212] NOTIFY: 0x7fee900016b0 (null): 0x7fee90001788
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
You can’t perform that action at this time.