Skip to content

Instantly share code, notes, and snippets.

@hanfei1991

hanfei1991/gist:cdaa7078a1bebbde97b75e490ff9b343

Created December 14, 2020 18:51
Show Gist options
  • Save hanfei1991/cdaa7078a1bebbde97b75e490ff9b343 to your computer and use it in GitHub Desktop.
Save hanfei1991/cdaa7078a1bebbde97b75e490ff9b343 to your computer and use it in GitHub Desktop.
Download ZIP
client log
Raw
gistfile1.txt
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/surface/call.cc, line number : 1967, log msg : grpc_call_start_batch(call=0x7f51fb22e060, ops=0x7f50ba7d8de0, nops=1, tag=0x7f5186064f80, reserved=(nil))"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/surface/call.cc, line number : 1565, log msg : ops[0]: SEND_MESSAGE ptr=0x7f51a0420140"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 1658, log msg : perform_stream_op[s=0x7f51fb22f450]: SEND_MESSAGE:flags=0x00000000:len=135023"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 1360, log msg : perform_stream_op_locked: SEND_MESSAGE:flags=0x00000000:len=135023; on_complete = 0x7f51fb22f290"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state IDLE -> WRITING [SEND_MESSAGE]"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state WRITING -> WRITING+MORE [SEND_MESSAGE]"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 1199, log msg : complete_closure_step: t=0x7f5153670800 0x7f51fb22f290 refs=1 flags=0x0001 desc=op->on_complete err=\"No Error\" write_state=WRITING+MORE"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/writing.cc, line number : 438, log msg : W:0x7f5153670800 SERVER[11] im-(sent,send)=(1,0) announce=0"] [thread_id=272]
[2020/12/15 02:12:39.009 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[11][svr] | s updt sent | trw: 75772, tlw: 4194304, taw: 4194304, srw: 86829, slw: 4194235, saw: 4194235"] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[11][svr] | data sent | trw: 75772 -> 0, tlw: 4194304, taw: 4194304, srw: 86829 -> 11057, slw: 4194235, saw: 4194235"] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/writing.cc, line number : 438, log msg : W:0x7f5153670800 SERVER[11] im-(sent,send)=(1,0) announce=0"] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[11][svr] | s updt sent | trw: 0, tlw: 4194304, taw: 4194304, srw: 11057, slw: 4194235, saw: 4194235"] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/writing.cc, line number : 154, log msg : ipv4:172.16.5.81:51130:0x7f5153670800 stream 11 moved to stalled list by transport. This is FULLY expected to happen in a healthy program that is not seeing flow control stalls. However, if you know that there are unwanted stalls, here is some helpful data: [fc:pending=59256:pending-compressed=0:flowed=72509318:peer_initwin=4194304:t_win=0:s_win=11057:s_delta=-5308011]"] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[0][svr] | t updt sent | trw: 0, tlw: 4194304, taw: 4194304, srw: , slw: , saw: "] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state WRITING+MORE -> WRITING [begin write in current thread]"] [thread_id=272]
[2020/12/15 02:12:39.010 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state WRITING -> IDLE [finish writing]"] [thread_id=272]
[2020/12/15 02:12:43.945 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[11][svr] | s updt recv | trw: 0, tlw: 4194304, taw: 4194304, srw: 11057 -> 5319068, slw: 4194235, saw: 4194235"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[0][svr] | t updt recv | trw: 0 -> 5290567, tlw: 4194304, taw: 4194304, srw: , slw: , saw: "] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state IDLE -> WRITING [TRANSPORT_FLOW_CONTROL_UNSTALLED]"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/iomgr/timer_generic.cc, line number : 468, log msg : TIMER 0x7f5153671af8: CANCEL pending=true"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/iomgr/timer_generic.cc, line number : 365, log msg : TIMER 0x7f5153671af8: SET 7320005 now 120005 call 0x7f5153671a78[0x796a2f0]"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/iomgr/timer_generic.cc, line number : 402, log msg : .. add to shard 30 with queue_deadline_cap=120121 => is_first_timer=false"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/writing.cc, line number : 438, log msg : W:0x7f5153670800 SERVER[11] im-(sent,send)=(1,0) announce=0"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[11][svr] | s updt sent | trw: 5290567, tlw: 4194304, taw: 4194304, srw: 5319068, slw: 4194235, saw: 4194235"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[11][svr] | data sent | trw: 5290567 -> 5231311, tlw: 4194304, taw: 4194304, srw: 5319068 -> 5259812, slw: 4194235, saw: 4194235"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 1199, log msg : complete_closure_step: t=0x7f5153670800 0x7f51fb22f290 refs=0 flags=0x0001 desc=finish_write_cb err=\"No Error\" write_state=WRITING"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [DEBUG] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/flow_control.cc, line number : 113, log msg : 0x7f5153671868[0][svr] | t updt sent | trw: 5231311, tlw: 4194304, taw: 4194304, srw: , slw: , saw: "] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/writing.cc, line number : 89, log msg : SERVER: Ping delayed [0x7f515361f0e0]: not enough time elapsed since last ping. Last ping 114783.000000: Next ping 124783.000000: Now 120005.000000"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/iomgr/timer_generic.cc, line number : 365, log msg : TIMER 0x7f5153671688: SET 124783 now 120005 call 0x7f51536716c8[0x796d240]"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/iomgr/timer_generic.cc, line number : 402, log msg : .. add to shard 31 with queue_deadline_cap=120217 => is_first_timer=false"] [thread_id=351]
[2020/12/15 02:12:43.945 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state WRITING -> WRITING [begin write in current thread]"] [thread_id=351]
[2020/12/15 02:12:43.946 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/ext/transport/chttp2/transport/chttp2_transport.cc, line number : 800, log msg : W:0x7f5153670800 SERVER [ipv4:172.16.5.81:51130] state WRITING -> IDLE [finish writing]"] [thread_id=351]
[2020/12/15 02:12:43.946 +08:00] [INFO] [<unknown>] ["grpc: /data1/jaysonhuang/grpc/src/core/lib/surface/completion_queue.cc, line number : 767, log msg : cq_end_op_for_pluck(cq=0x7f5185095000, tag=0x7f5186064f80, error=\"No Error\", done=0x78d8230, done_arg=0x7f51fb230780, storage=0x7f51fb2307c8)"] [thread_id=351]
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment