Skip to content

Instantly share code, notes, and snippets.

@frankpinto
Created June 13, 2018 16:48
Show Gist options
  • Save frankpinto/3bcdedff7daf7116ed1ce19486f504bd to your computer and use it in GitHub Desktop.
Save frankpinto/3bcdedff7daf7116ed1ce19486f504bd to your computer and use it in GitHub Desktop.
GRPC debug output
D0613 12:34:16.149015455 29990 ev_posix.cc:131] Using polling engine: poll
D0613 12:34:16.149171806 29990 dns_resolver.cc:307] Using native dns resolver
I0613 12:34:16.151401059 29990 call.cc:631] OP[client-channel:0x7efff800d808]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 62 61 73 65 2e 42 61 73 65 2f 69 6e 73 65 72 74 53 33 44 61 74 61 '/base.Base/inser
tS3Data', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 34 36 30 35 31 'localhost:46051' deadline=60001} SEND_MESSAGE:flags=0x00000000:len=4217991 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_ME
SSAGE RECV_TRAILING_METADATA COLLECT_STATS:0x7efff800d588
D0613 12:34:16.151570198 29990 client_channel.cc:1424] chand=0x7efff8005068 calld=0x7efff800d828: entering client_channel combiner
D0613 12:34:16.151609716 29990 client_channel.cc:303] chand=0x7efff8005068: starting name resolution
D0613 12:34:16.151642277 29990 client_channel.cc:1280] chand=0x7efff8005068 calld=0x7efff800d828: deferring pick pending resolver result
D0613 12:34:16.151822864 30090 client_channel.cc:376] chand=0x7efff8005068: got resolver result: error="No Error"
D0613 12:34:16.151889416 30090 client_channel.cc:486] chand=0x7efff8005068: resolver result: lb_policy_name="pick_first" (changed), service_config="(null)"
D0613 12:34:16.151924365 30090 client_channel.cc:568] chand=0x7efff8005068: initializing new LB policy
D0613 12:34:16.151957196 30090 client_channel.cc:251] chand=0x7efff8005068: setting connectivity state to IDLE
D0613 12:34:16.151991466 30090 client_channel.cc:1237] chand=0x7efff8005068 calld=0x7efff800d828: resolver returned, doing pick
D0613 12:34:16.152021209 30090 client_channel.cc:1113] chand=0x7efff8005068 calld=0x7efff800d828: starting pick on lb_policy=0x7effec001910
D0613 12:34:16.152053168 30090 client_channel.cc:972] chand=0x7efff8005068 calld=0x7efff800d828: applying service config to call
D0613 12:34:16.152239905 30090 client_channel.cc:265] chand=0x7efff8005068: lb_policy=0x7effec001910 state changed to CONNECTING
D0613 12:34:16.152285710 30090 client_channel.cc:251] chand=0x7efff8005068: setting connectivity state to CONNECTING
D0613 12:34:16.152377914 30090 client_channel.cc:265] chand=0x7efff8005068: lb_policy=0x7effec001910 state changed to READY
D0613 12:34:16.152413517 30090 client_channel.cc:251] chand=0x7efff8005068: setting connectivity state to READY
D0613 12:34:16.152443163 30090 client_channel.cc:1096] chand=0x7efff8005068 calld=0x7efff800d828: pick completed asynchronously
D0613 12:34:16.152475466 30090 client_channel.cc:1018] chand=0x7efff8005068 calld=0x7efff800d828: create subchannel_call=0x7effec00a3d8: error="No Error"
D0613 12:34:16.152504079 30090 client_channel.cc:945] chand=0x7efff8005068 calld=0x7efff800d828: sending 0 pending batches to subchannel_call=0x7effec00a3d8
I0613 12:34:16.152538558 30090 subchannel.cc:733] OP[message_size:0x7effec00a438]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 62 61 73 65 2e 42 61 73 65 2f 69 6e 73 65 72 74 53 33 44 61 74 61 '/base.Base/insertS
3Data', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 34 36 30 35 31 'localhost:46051' deadline=60001} SEND_MESSAGE:flags=0x00000000:len=4217991 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESS
AGE RECV_TRAILING_METADATA COLLECT_STATS:0x7efff800d588
I0613 12:34:16.152576049 30090 channel_stack.cc:235] OP[http-client:0x7effec00a450]: SEND_INITIAL_METADATA{key=3a 70 61 74 68 ':path' value=2f 62 61 73 65 2e 42 61 73 65 2f 69 6e 73 65 72 74 53 33 44 61 74 61 '/base.Base/insertS3
Data', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 34 36 30 35 31 'localhost:46051' deadline=60001} SEND_MESSAGE:flags=0x00000000:len=4217991 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSA
GE RECV_TRAILING_METADATA COLLECT_STATS:0x7efff800d588
I0613 12:34:16.152618916 30090 channel_stack.cc:235] OP[message_compress:0x7effec00a468]: 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 'PO
ST', key=3a 70 61 74 68 ':path' value=2f 62 61 73 65 2e 42 61 73 65 2f 69 6e 73 65 72 74 53 33 44 61 74 61 '/base.Base/insertS3Data', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 34 36 30 35 31 'local
host:46051', 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 38 2e 33 20 67 72 70 63 2d 63 2f 35 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 65 72 6f 75 73 29 'grpc-python/1.8.3 grpc-c/5.0.0 (manylinux;
chttp2; generous)' deadline=60001} SEND_MESSAGE:flags=0x00000000:len=4217991 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA COLLECT_STATS:0x7efff800d588
I0613 12:34:16.152721615 30090 channel_stack.cc:235] OP[connected:0x7effec00a480]: 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', ke
y=3a 70 61 74 68 ':path' value=2f 62 61 73 65 2e 42 61 73 65 2f 69 6e 73 65 72 74 53 33 44 61 74 61 '/base.Base/insertS3Data', key=3a 61 75 74 68 6f 72 69 74 79 ':authority' value=6c 6f 63 61 6c 68 6f 73 74 3a 34 36 30 35 31 'localhost:46
051', 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 38 2e 33 20 67 72 70 63 2d 63 2f 35 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 65 72 6f 75 73 29 'grpc-python/1.8.3 grpc-c/5.0.0 (manylinux; chttp2;
generous)', 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 6
e 67 'accept-encoding' value=69 64 65 6e 74 69 74 79 2c 67 7a 69 70 'identity,gzip' deadline=60001} SEND_MESSAGE:flags=0x00000000:len=4217991 SEND_TRAILING_METADATA{} RECV_INITIAL_METADATA RECV_MESSAGE RECV_TRAILING_METADATA COLLECT_STATS
:0x7efff800d588
D0613 12:34:16.152788659 30090 writing.cc:138] ipv6:[::1]:46051:0x7effec005160 stream 1 stalled by transport [fc:pending=0:flowed=0:peer_initwin=65535:t_win=0:s_win=0:s_delta=-65535]
I0613 12:34:16.152911470 29935 call.cc:631] OP[server:0x7fb24c008a88]: RECV_INITIAL_METADATA
I0613 12:34:16.152922266 29935 channel_stack.cc:235] OP[message_size:0x7fb24c008aa0]: RECV_INITIAL_METADATA
I0613 12:34:16.152926740 29935 channel_stack.cc:235] OP[deadline:0x7fb24c008ab8]: RECV_INITIAL_METADATA
I0613 12:34:16.152930934 29935 channel_stack.cc:235] OP[http-server:0x7fb24c008ad0]: RECV_INITIAL_METADATA
I0613 12:34:16.152934778 29935 channel_stack.cc:235] OP[message_compress:0x7fb24c008ae8]: RECV_INITIAL_METADATA
I0613 12:34:16.152938500 29935 channel_stack.cc:235] OP[connected:0x7fb24c008b00]: RECV_INITIAL_METADATA
I0613 12:34:16.153062932 29935 call.cc:631] OP[server:0x7fb24c008a88]: RECV_TRAILING_METADATA COLLECT_STATS:0x7fb24c008808
I0613 12:34:16.153108195 29935 channel_stack.cc:235] OP[message_size:0x7fb24c008aa0]: RECV_TRAILING_METADATA COLLECT_STATS:0x7fb24c008808
I0613 12:34:16.153113848 29935 channel_stack.cc:235] OP[deadline:0x7fb24c008ab8]: RECV_TRAILING_METADATA COLLECT_STATS:0x7fb24c008808
I0613 12:34:16.153118038 29935 channel_stack.cc:235] OP[http-server:0x7fb24c008ad0]: RECV_TRAILING_METADATA COLLECT_STATS:0x7fb24c008808
I0613 12:34:16.153121938 29935 channel_stack.cc:235] OP[message_compress:0x7fb24c008ae8]: RECV_TRAILING_METADATA COLLECT_STATS:0x7fb24c008808
I0613 12:34:16.153126422 29935 channel_stack.cc:235] OP[connected:0x7fb24c008b00]: RECV_TRAILING_METADATA COLLECT_STATS:0x7fb24c008808
I0613 12:34:16.153250342 29979 call.cc:631] OP[server:0x7fb24c008a88]: RECV_MESSAGE
I0613 12:34:16.153257390 29979 channel_stack.cc:235] OP[message_size:0x7fb24c008aa0]: RECV_MESSAGE
I0613 12:34:16.153261289 29979 channel_stack.cc:235] OP[deadline:0x7fb24c008ab8]: RECV_MESSAGE
I0613 12:34:16.153265051 29979 channel_stack.cc:235] OP[http-server:0x7fb24c008ad0]: RECV_MESSAGE
I0613 12:34:16.153269216 29979 channel_stack.cc:235] OP[message_compress:0x7fb24c008ae8]: RECV_MESSAGE
I0613 12:34:16.153273486 29979 channel_stack.cc:235] OP[connected:0x7fb24c008b00]: RECV_MESSAGE
I0613 12:34:16.153294893 29979 call.cc:631] OP[server:0x7fb24c008a88]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/message
_size/message_size_filter.cc","file_line":122,"grpc_status":8}
I0613 12:34:16.153301454 29979 channel_stack.cc:235] OP[message_size:0x7fb24c008aa0]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/m
essage_size/message_size_filter.cc","file_line":122,"grpc_status":8}
I0613 12:34:16.153301454 29979 channel_stack.cc:235] OP[message_size:0x7fb24c008aa0]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/message_size/message_size_filter.cc","file_line":122,"grpc_status":8}
I0613 12:34:16.153307128 29979 channel_stack.cc:235] OP[deadline:0x7fb24c008ab8]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/message_size/message_size_filter.cc","file_line":122,"grpc_status":8}
I0613 12:34:16.153312734 29979 channel_stack.cc:235] OP[http-server:0x7fb24c008ad0]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/message_size/message_size_filter.cc","file_line":122,"grpc_status":8}
I0613 12:34:16.153318273 29979 channel_stack.cc:235] OP[message_compress:0x7fb24c008ae8]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/message_size/message_size_filter.cc","file_line":122,"grpc_status":8}
I0613 12:34:16.153323792 29979 channel_stack.cc:235] OP[connected:0x7fb24c008b00]: CANCEL:{"created":"@1528907656.153280510","description":"Received message larger than max (4217991 vs. 4194304)","file":"src/core/ext/filters/message_size/message_size_filter.cc","file_line":122,"grpc_status":8}
D0613 12:34:16.154764436 30090 writing.cc:138] ipv6:[::1]:46051:0x7effec005160 stream 1 stalled by stream [fc:pending=0:flowed=0:peer_initwin=4194304:t_win=4194304:s_win=0:s_delta=-4194304]
D0613 12:34:16.155561814 29990 client_channel.cc:251] chand=0x7efff8005068: setting connectivity state to SHUTDOWN
D0613 12:34:16.155624194 29990 client_channel.cc:376] chand=0x7efff8005068: got resolver result: error={"created":"@1528907656.155615463","description":"Resolver Shutdown","file":"src/core/ext/filters/client_channel/resolver/dns/native/dns_resolver.cc","file_line":107}
D0613 12:34:16.155660627 29990 client_channel.cc:486] chand=0x7efff8005068: resolver result: lb_policy_name="(null)", service_config="(null)"
D0613 12:34:16.155690724 29990 client_channel.cc:541] chand=0x7efff8005068: shutting down
D0613 12:34:16.155722112 29990 client_channel.cc:251] chand=0x7efff8005068: setting connectivity state to SHUTDOWN
I0613 12:34:16.155837440 29935 server.cc:432] Disconnected client: {"created":"@1528907656.155815075","description":"Endpoint read failed","file":"src/core/ext/transport/chttp2/transport/chttp2_transport.cc","file_line":2504,"grpc_status":14,"occurred_during_write":0,"referenced_errors":[{"created":"@1528907656.155813320","description":"Socket closed","fd":57,"file":"src/core/lib/iomgr/tcp_posix.cc","file_line":429,"target_address":"ipv6:[::1]:39474"}]}
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment