client log

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]