Project

General

Profile

Bug #44197 » server_2268322028.log

Tony Davies, 02/19/2020 03:14 AM

 
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING pgs=0 cs=0 l=0).handle_client_banner accept peer addr is v1:10.244.3.182:0/2268322028
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING pgs=0 cs=0 l=0).wait_connect_message
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read start len=33
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read_until len is 33 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read_until read_bulk recv_end is 0 left is 33 got 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read_until need len 33 remaining 33 bytes
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).process
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read continue len=33
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read_until len is 33 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=0).read_until read_bulk recv_end is 0 left is 33 got 33
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING pgs=0 cs=0 l=0).handle_connect_message_1 r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=0).handle_connect_message_2
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=0).handle_connect_message_2 accept got peer connect_seq 0 global_seq 1
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_message_2 accept of host_type 8, policy.lossy=1 policy.server=1 policy.standby=0 policy.resetcheck=0 features 0x3ffddff8ffacffff
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_message_2 accept my proto 15, their proto 15
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_message_2 authorizor_protocol 0 len 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_message_2 accept setting up session_security.
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_message_2 accept new session
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).open
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=1 cs=1 l=1).open accept success, connect_seq = 1 in_seq=0, sending READY
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=1 cs=1 l=1).open accept features 4611087854031667199 authorizer_protocol 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] accept_conn 0x55c564a900 v1:10.244.3.182:0/2268322028
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send cs.send 34 bytes
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send sent bytes 34 remaining bytes 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=1 cs=1 l=1).handle_ready_connect_message_reply_write r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).wait_seq
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=8
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 8 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 8 got 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until need len 8 remaining 8 bytes
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).process
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read continue len=8
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 8 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 8 got 8
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).handle_seq r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 2 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).handle_seq accept get newly_acked_seq 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).discard_requeued_up_to 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).server_ready session_security is 0x55c125c480
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).server_ready accept done
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=ACCEPTING_HANDLED_CONNECT_MSG pgs=1 cs=1 l=1).ready
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).wait_message
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=1
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until need len 1 remaining 1 bytes
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).process
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read continue len=1
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got 148
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message process tag 14
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=8
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 8 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 8 in buffer left is 0 buffer still has 139
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_keepalive2 r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 30 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_keepalive2 got KEEPALIVE2 tag ...
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).append_keepalive_or_ack
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_keepalive2 got KEEPALIVE2 2020-02-19 02:19:08.526069
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).wait_message
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=1
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 1 in buffer left is 0 buffer still has 138
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message process tag 7
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message begin MSG
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=53
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 53 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 53 in buffer left is 0 buffer still has 85
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header r=0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header got MSG header
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header got envelope type=17 src client.? front=64 data=0 off 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_MESSAGE pgs=1 cs=1 l=1).throttle_message
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_BYTES pgs=1 cs=1 l=1).throttle_bytes
debug 2020-02-19 02:19:08.518 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_BYTES pgs=1 cs=1 l=1).throttle_bytes wants 64 bytes from policy throttler 64/104857600
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_DISPATCH_QUEUE pgs=1 cs=1 l=1).throttle_dispatch_queue
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_front
debug 2020-02-19 02:19:08.518 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=64
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 64 state_offset is 0
debug 2020-02-19 02:19:08.518 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 64 in buffer left is 0 buffer still has 21
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).handle_message_front r=0
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).handle_message_front got front 64
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_middle
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_data_prepare
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_data msg_left=0
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_footer
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=21
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 21 state_offset is 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 21 in buffer left is 0 buffer still has 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer r=0
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer aborted = 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer got 64 + 0 + 0 byte message
debug 2020-02-19 02:19:08.522 7f95fa7d00 5 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1). rx client.? seq 1 0x55d5dd3860 auth(proto 0 34 bytes epoch 0) v1
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).wait_message
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=1
debug 2020-02-19 02:19:08.522 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] <== client.? v1:10.244.3.182:0/2268322028 1 ==== auth(proto 0 34 bytes epoch 0) v1 ==== 64+0+0 (unknown 4015746775 0 0) 0x55d5dd3860 con 0x55c564a900
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until need len 1 remaining 1 bytes
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).handle_write
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_event
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send cs.send 9 bytes
debug 2020-02-19 02:19:08.522 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] --> v1:10.244.3.182:0/2268322028 -- auth_reply(proto 2 0 (0) No error information) v1 -- 0x55b272d140 con 0x55c564a900
debug 2020-02-19 02:19:08.522 7f95e51d00 15 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).send_message inline write is denied, reschedule m=0x55b272d140
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send sent bytes 9 remaining bytes 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).handle_write
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_event
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).prepare_send_message m auth_reply(proto 2 0 (0) No error information) v1
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).prepare_send_message encoding features 4611087854031667199 0x55b272d140 auth_reply(proto 2 0 (0) No error information) v1
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message signed m=0x55b272d140): sig = 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending message type=18 src mon.2 front=33 data=0 off 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending 1 0x55b272d140
debug 2020-02-19 02:19:08.522 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send cs.send 108 bytes
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send sent bytes 108 remaining bytes 0
debug 2020-02-19 02:19:08.522 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending 0x55b272d140 done.
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).process
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read continue len=1
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got 141
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message r=0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message process tag 7
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message begin MSG
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=53
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 53 state_offset is 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 53 in buffer left is 0 buffer still has 87
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header r=0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header got MSG header
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header got envelope type=17 src client.? front=66 data=0 off 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_MESSAGE pgs=1 cs=1 l=1).throttle_message
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_BYTES pgs=1 cs=1 l=1).throttle_bytes
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_BYTES pgs=1 cs=1 l=1).throttle_bytes wants 66 bytes from policy throttler 128/104857600
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_DISPATCH_QUEUE pgs=1 cs=1 l=1).throttle_dispatch_queue
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_front
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=66
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 66 state_offset is 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 66 in buffer left is 0 buffer still has 21
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).handle_message_front r=0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).handle_message_front got front 66
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_middle
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_data_prepare
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_data msg_left=0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_footer
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=21
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 21 state_offset is 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 21 in buffer left is 0 buffer still has 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer r=0
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer aborted = 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer got 66 + 0 + 0 byte message
debug 2020-02-19 02:19:08.526 7f95fa7d00 5 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1). rx client.? seq 2 0x55c45dad20 auth(proto 2 36 bytes epoch 0) v1
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).wait_message
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=1
debug 2020-02-19 02:19:08.526 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] <== client.? v1:10.244.3.182:0/2268322028 2 ==== auth(proto 2 36 bytes epoch 0) v1 ==== 66+0+0 (unknown 51150340 0 0) 0x55c45dad20 con 0x55c564a900
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until need len 1 remaining 1 bytes
debug 2020-02-19 02:19:08.526 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] --> v1:10.244.3.182:0/2268322028 -- auth_reply(proto 2 0 (0) No error information) v1 -- 0x55c513b0a0 con 0x55c564a900
debug 2020-02-19 02:19:08.526 7f95e51d00 15 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).send_message inline write is denied, reschedule m=0x55c513b0a0
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).handle_write
debug 2020-02-19 02:19:08.526 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] --> v1:10.244.3.182:0/2268322028 -- mon_map magic: 0 v1 -- 0x55b14d4160 con 0x55c564a900
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_event
debug 2020-02-19 02:19:08.526 7f95e51d00 15 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).send_message inline write is denied, reschedule m=0x55b14d4160
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).prepare_send_message m auth_reply(proto 2 0 (0) No error information) v1
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).prepare_send_message encoding features 4611087854031667199 0x55c513b0a0 auth_reply(proto 2 0 (0) No error information) v1
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message signed m=0x55c513b0a0): sig = 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending message type=18 src mon.2 front=390 data=0 off 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending 2 0x55c513b0a0
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send cs.send 465 bytes
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send sent bytes 465 remaining bytes 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending 0x55c513b0a0 done.
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).prepare_send_message m mon_map magic: 0 v1
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).prepare_send_message encoding features 4611087854031667199 0x55b14d4160 mon_map magic: 0 v1
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message signed m=0x55b14d4160): sig = 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending message type=4 src mon.2 front=377 data=0 off 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending 3 0x55b14d4160
debug 2020-02-19 02:19:08.526 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send cs.send 452 bytes
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1)._try_send sent bytes 452 remaining bytes 0
debug 2020-02-19 02:19:08.526 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).write_message sending 0x55b14d4160 done.
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).process
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read continue len=1
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got 153
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message r=0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message process tag 7
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message begin MSG
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=53
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 53 state_offset is 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 53 in buffer left is 0 buffer still has 99
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header r=0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header got MSG header
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message_header got envelope type=15 src client.? front=78 data=0 off 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_MESSAGE pgs=1 cs=1 l=1).throttle_message
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_BYTES pgs=1 cs=1 l=1).throttle_bytes
debug 2020-02-19 02:19:08.530 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_BYTES pgs=1 cs=1 l=1).throttle_bytes wants 78 bytes from policy throttler 194/104857600
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=THROTTLE_DISPATCH_QUEUE pgs=1 cs=1 l=1).throttle_dispatch_queue
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_front
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=78
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 78 state_offset is 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 78 in buffer left is 0 buffer still has 21
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).handle_message_front r=0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).handle_message_front got front 78
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_middle
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_data_prepare
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_data msg_left=0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_MESSAGE_FRONT pgs=1 cs=1 l=1).read_message_footer
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=21
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 21 state_offset is 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until got 21 in buffer left is 0 buffer still has 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer r=0
debug 2020-02-19 02:19:08.530 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer aborted = 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1).handle_message_footer got 78 + 0 + 0 byte message
debug 2020-02-19 02:19:08.530 7f95fa7d00 5 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=READ_FOOTER_AND_DISPATCH pgs=1 cs=1 l=1). rx client.? seq 3 0x55b97df6c0 mon_subscribe({config=0+,monmap=0+}) v3
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).wait_message
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read start len=1
debug 2020-02-19 02:19:08.530 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] <== client.? v1:10.244.3.182:0/2268322028 3 ==== mon_subscribe({config=0+,monmap=0+}) v3 ==== 78+0+0 (unknown 1105888965 0 0) 0x55b97df6c0 con 0x55c564a900
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until len is 1 state_offset is 0
debug 2020-02-19 02:19:08.530 7f95fa7d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_bulk peer close file descriptor 34
debug 2020-02-19 02:19:08.530 7f95fa7d00 25 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read_bulk recv_end is 0 left is 1 got -1
debug 2020-02-19 02:19:08.530 7f95fa7d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CONNECTION_ESTABLISHED l=1).read_until read failed
debug 2020-02-19 02:19:08.530 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] --> v1:10.244.3.182:0/2268322028 -- config(4 keys) v1 -- 0x55cce02680 con 0x55c564a900
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message r=-1
debug 2020-02-19 02:19:08.530 7f95e51d00 15 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).send_message inline write is denied, reschedule m=0x55cce02680
debug 2020-02-19 02:19:08.530 7f95fa7d00 1 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).handle_message read tag failed
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).fault
debug 2020-02-19 02:19:08.530 7f95fa7d00 1 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).fault on lossy channel, failing
debug 2020-02-19 02:19:08.530 7f95e51d00 1 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] --> v1:10.244.3.182:0/2268322028 -- mon_map magic: 0 v1 -- 0x55bcc74c00 con 0x55c564a900
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).stop
debug 2020-02-19 02:19:08.530 7f95e51d00 15 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).send_message inline write is denied, reschedule m=0x55bcc74c00
debug 2020-02-19 02:19:08.530 7f95fa7d00 2 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).stop
debug 2020-02-19 02:19:08.530 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).discard_out_queue started
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).discard_out_queue discard 0x55cce02680
debug 2020-02-19 02:19:08.530 7f95fa7d00 20 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=OPENED pgs=1 cs=1 l=1).discard_out_queue discard 0x55bcc74c00
debug 2020-02-19 02:19:08.530 7f95fa7d00 10 -- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 legacy=0x55c02bb760 unknown :6789 s=STATE_CLOSED l=1).handle_write
debug 2020-02-19 02:19:08.530 7f95fa7d00 10 --1- [v2:10.105.28.199:3300/0,v1:10.105.28.199:6789/0] >> v1:10.244.3.182:0/2268322028 conn(0x55c564a900 0x55c02bb760 :6789 s=CLOSED pgs=1 cs=1 l=1).write_event
(1-1/2)