Project

General

Profile

Bug #40053 ยป osd-73.log

Randall Smith, 05/31/2019 09:05 PM

 
2019-05-31 15:05:03.597 7fc6b0cab700 2 Event(0x21eae00 nevent=5000 time_id=1).set_owner idx=0 owner=140491346327296
2019-05-31 15:05:03.597 7fc6b0cab700 20 Event(0x21eae00 nevent=5000 time_id=1).create_file_event create event started fd=5 mask=1 original mask is 0
2019-05-31 15:05:03.597 7fc6b0cab700 20 EpollDriver.add_event add event fd=5 cur_mask=0 add_mask=1 to 4
2019-05-31 15:05:03.597 7fc6b0cab700 20 Event(0x21eae00 nevent=5000 time_id=1).create_file_event create event end fd=5 mask=1 original mask is 1
2019-05-31 15:05:03.597 7fc6b0cab700 10 stack operator() starting
2019-05-31 15:05:03.597 7fc6b04aa700 2 Event(0x21eb040 nevent=5000 time_id=1).set_owner idx=1 owner=140491337934592
2019-05-31 15:05:03.597 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event started fd=8 mask=1 original mask is 0
2019-05-31 15:05:03.597 7fc6b04aa700 20 EpollDriver.add_event add event fd=8 cur_mask=0 add_mask=1 to 7
2019-05-31 15:05:03.597 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event end fd=8 mask=1 original mask is 1
2019-05-31 15:05:03.597 7fc6b04aa700 10 stack operator() starting
2019-05-31 15:05:03.597 7fc6afca9700 2 Event(0x21eb280 nevent=5000 time_id=1).set_owner idx=2 owner=140491329541888
2019-05-31 15:05:03.597 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event started fd=11 mask=1 original mask is 0
2019-05-31 15:05:03.597 7fc6afca9700 20 EpollDriver.add_event add event fd=11 cur_mask=0 add_mask=1 to 10
2019-05-31 15:05:03.597 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event end fd=11 mask=1 original mask is 1
2019-05-31 15:05:03.597 7fc6afca9700 10 stack operator() starting
2019-05-31 15:05:03.597 7fc6bf4ec200 10 -- - ready -
2019-05-31 15:05:03.597 7fc6bf4ec200 1 Processor -- start
2019-05-31 15:05:03.597 7fc6bf4ec200 1 -- - start start
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - create_connect 192.168.253.147:6789/0, creating connection and registering
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_NONE pgs=0 cs=0 l=0)._connect csq=0
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb040 nevent=5000 time_id=1).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - get_connection mon.1 192.168.253.147:6789/0 new 0x2636e00
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - create_connect 192.168.253.148:6789/0, creating connection and registering
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_NONE pgs=0 cs=0 l=0)._connect csq=0
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb280 nevent=5000 time_id=1).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - get_connection mon.2 192.168.253.148:6789/0 new 0x2637500
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING pgs=0 cs=0 l=0).send_keepalive
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb040 nevent=5000 time_id=1).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 1 -- - --> 192.168.253.147:6789/0 -- auth(proto 0 27 bytes epoch 0) v1 -- 0x25b4780 con 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING
2019-05-31 15:05:03.601 7fc6bf4ec200 15 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING pgs=0 cs=0 l=0).send_message inline write is denied, reschedule m=0x25b4780
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING pgs=0 cs=0 l=0).send_keepalive
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb280 nevent=5000 time_id=1).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 1 -- - --> 192.168.253.148:6789/0 -- auth(proto 0 27 bytes epoch 0) v1 -- 0x25b4a00 con 0
2019-05-31 15:05:03.601 7fc6bf4ec200 15 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING pgs=0 cs=0 l=0).send_message inline write is denied, reschedule m=0x25b4a00
2019-05-31 15:05:03.601 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event started fd=14 mask=1 original mask is 0
2019-05-31 15:05:03.601 7fc6afca9700 20 EpollDriver.add_event add event fd=14 cur_mask=0 add_mask=1 to 10
2019-05-31 15:05:03.601 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event started fd=13 mask=1 original mask is 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 EpollDriver.add_event add event fd=13 cur_mask=0 add_mask=1 to 7
2019-05-31 15:05:03.601 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event end fd=14 mask=1 original mask is 1
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING
2019-05-31 15:05:03.601 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event end fd=13 mask=1 original mask is 1
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING
2019-05-31 15:05:03.601 7fc6b04aa700 10 NetHandler reconnect reconnect: Operation already in progress
2019-05-31 15:05:03.601 7fc6afca9700 10 NetHandler reconnect reconnect: Operation already in progress
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0)._process_connection nonblock connect inprogress
2019-05-31 15:05:03.601 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event started fd=14 mask=2 original mask is 1
2019-05-31 15:05:03.601 7fc6afca9700 20 EpollDriver.add_event add event fd=14 cur_mask=1 add_mask=2 to 10
2019-05-31 15:05:03.601 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event end fd=14 mask=2 original mask is 3
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0)._process_connection nonblock connect inprogress
2019-05-31 15:05:03.601 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event started fd=13 mask=2 original mask is 1
2019-05-31 15:05:03.601 7fc6b04aa700 20 EpollDriver.add_event add event fd=13 cur_mask=1 add_mask=2 to 7
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event end fd=13 mask=2 original mask is 3
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_RE
2019-05-31 15:05:03.601 7fc6afca9700 20 EpollDriver.del_event del event fd=14 cur_mask=3 delmask=2 to 10
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_RE
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0)._process_connection connect successfully, ready to send banner
2019-05-31 15:05:03.601 7fc6b04aa700 20 EpollDriver.del_event del event fd=13 cur_mask=3 delmask=2 to 7
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0)._process_connection connect successfully, ready to send banner
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0)._try_send sent bytes 9 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event started fd=14 mask=2 original mask is 1
2019-05-31 15:05:03.601 7fc6afca9700 20 EpollDriver.add_event add event fd=14 cur_mask=1 add_mask=2 to 10
2019-05-31 15:05:03.601 7fc6afca9700 20 Event(0x21eb280 nevent=5000 time_id=1).create_file_event create event end fd=14 mask=2 original mask is 3
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_RE pgs=0 cs=0 l=0)._try_send sent bytes 9 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._process_connection connect write banner done: 192.168.253.148:6789/0
2019-05-31 15:05:03.601 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event started fd=13 mask=2 original mask is 1
2019-05-31 15:05:03.601 7fc6b04aa700 20 EpollDriver.add_event add event fd=13 cur_mask=1 add_mask=2 to 7
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_RE
2019-05-31 15:05:03.601 7fc6b04aa700 20 Event(0x21eb040 nevent=5000 time_id=1).create_file_event create event end fd=13 mask=2 original mask is 3
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._process_connection connect write banner done: 192.168.253.147:6789/0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_RE
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6afca9700 10 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._try_send sent bytes 0 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._process_connection connect read peer addr 192.168.253.148:6789/0 on socket 14
2019-05-31 15:05:03.601 7fc6afca9700 20 -- - >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._process_connection connect peer addr for me is 192.168.253.146:53360/0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- - >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._process_connection connect read peer addr 192.168.253.147:6789/0 on socket 13
2019-05-31 15:05:03.601 7fc6afca9700 1 -- 192.168.253.146:0/1920221312 learned_addr learned my addr 192.168.253.146:0/1920221312
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._process_connection connect peer addr for me is 192.168.253.146:45422/0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._try_send sent bytes 136 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0)._process_connection connect sent my addr 192.168.253.146:0/1920221312
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0)._process_connection connect sending gseq=2 cseq=0 proto=15
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY pgs=0 cs=0 l=0)._try_send sent bytes 136 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0)._process_connection connect sent my addr 192.168.253.146:0/1920221312
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0)._try_send sent bytes 33 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_BANNER_AND_IDENTIFY
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0)._process_connection connect wrote (self +) cseq, waiting for reply
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0)._process_connection connect sending gseq=1 cseq=0 proto=15
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_SEND_CONNECT_MSG
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_SEND_CONNECT_MSG pgs=0 cs=0 l=0)._try_send sent bytes 33 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0)._try_send sent bytes 0 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0)._process_connection connect wrote (self +) cseq, waiting for reply
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_SEND_CONNECT_MSG
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0)._try_send sent bytes 0 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_CONNECT_REPLY
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0)._process_connection connect got reply tag 13 connect_seq 1 global_seq 3100169 proto 15 flags 1 features 4611087854031142907
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY_AUTH pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_CONNECT_REPLY
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY_AUTH pgs=0 cs=0 l=0).handle_connect_reply got CEPH_MSGR_TAG_SEQ, reading acked_seq and writing in_seq
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_CONNECT_REPLY_AUTH
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_CONNECT_REPLY
2019-05-31 15:05:03.601 7fc6afca9700 2 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0)._process_connection got newly_acked_seq 0 vs out_seq 0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0).discard_requeued_up_to 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY pgs=0 cs=0 l=0)._process_connection connect got reply tag 13 connect_seq 1 global_seq 3081004 proto 15 flags 1 features 4611087854031142907
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY_AUTH pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_CONNECT_REPLY
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_CONNECT_REPLY_AUTH pgs=0 cs=0 l=0).handle_connect_reply got CEPH_MSGR_TAG_SEQ, reading acked_seq and writing in_seq
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0)._try_send sent bytes 8 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_CONNECT_REPLY_AUTH
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_READY pgs=0 cs=0 l=0)._process_connection send in_seq done
2019-05-31 15:05:03.601 7fc6b04aa700 2 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0)._process_connection got newly_acked_seq 0 vs out_seq 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_CONNECTING_READY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_ACK_SEQ
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0).discard_requeued_up_to 0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1)._process_connection connect success 1, lossy = 1, features 4611087854031142907
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_WAIT_ACK_SEQ pgs=0 cs=0 l=0)._try_send sent bytes 8 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_READY pgs=0 cs=0 l=0)._process_connection send in_seq done
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_CONNECTING_READY pgs=0 cs=0 l=0).process prev state is STATE_CONNECTING_WAIT_ACK_SEQ
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_CONNECTING_READY
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1)._process_connection connect success 1, lossy = 1, features 4611087854031142907
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).handle_write
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1)._append_keepalive_or_ack
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_CONNECTING_READY
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).prepare_send_message m auth(proto 0 27 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).handle_write
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).prepare_send_message encoding features 4611087854031142907 0x25b4a00 auth(proto 0 27 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1)._append_keepalive_or_ack
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message no session security
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).prepare_send_message m auth(proto 0 27 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).prepare_send_message encoding features 4611087854031142907 0x25b4780 auth(proto 0 27 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message sending message type=17 src client.? front=57 data=0 off 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message sending 1 0x25b4a00
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message no session security
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message sending message type=17 src client.? front=57 data=0 off 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message sending 1 0x25b4780
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1)._try_send sent bytes 141 remaining bytes 0
2019-05-31 15:05:03.601 7fc6afca9700 20 EpollDriver.del_event del event fd=14 cur_mask=3 delmask=2 to 10
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message sending 0x25b4a00 done.
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1)._try_send sent bytes 141 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 EpollDriver.del_event del event fd=13 cur_mask=3 delmask=2 to 7
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message sending 0x25b4780 done.
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).handle_write
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_KEEPALIVE2_ACK pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_KEEPALIVE2_ACK pgs=3081004 cs=1 l=1).process got KEEPALIVE_ACK
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_KEEPALIVE2_ACK pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_KEEPALIVE2_ACK
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_KEEPALIVE2_ACK pgs=3100169 cs=1 l=1).process got KEEPALIVE_ACK
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_KEEPALIVE2_ACK
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process begin MSG
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process got MSG header
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process got envelope type=4 src mon.2 front=370 data=0 off 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process begin MSG
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process got MSG header
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process got envelope type=4 src mon.1 front=370 data=0 off 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3100169 cs=1 l=1).process got front 370
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process aborted = 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process got 370 + 0 + 0 byte message
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3081004 cs=1 l=1).process got front 370
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process aborted = 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process got 370 + 0 + 0 byte message
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process no session security set
2019-05-31 15:05:03.601 7fc6afca9700 5 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1). rx mon.2 seq 1 0x21ebf80 mon_map magic: 0 v1
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process no session security set
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 queue 0x21ebf80 prio 196
2019-05-31 15:05:03.601 7fc6b04aa700 5 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1). rx mon.1 seq 1 0x21ec1c0 mon_map magic: 0 v1
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 queue 0x21ec1c0 prio 196
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process begin MSG
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process got MSG header
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process begin MSG
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process got envelope type=18 src mon.2 front=33 data=0 off 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process got MSG header
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process got envelope type=18 src mon.1 front=33 data=0 off 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 <== mon.2 192.168.253.148:6789/0 1 ==== mon_map magic: 0 v1 ==== 370+0+0 (3034216899 0 0) 0x21ebf80 con 0x2637500
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3100169 cs=1 l=1).process got front 33
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3081004 cs=1 l=1).process got front 33
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process aborted = 0
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process aborted = 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process got 33 + 0 + 0 byte message
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process got 33 + 0 + 0 byte message
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process no session security set
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process no session security set
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 dispatch_throttle_release 370 to dispatch throttler 806/104857600
2019-05-31 15:05:03.601 7fc6af4a8700 20 -- 192.168.253.146:0/1920221312 done calling dispatch on 0x21ebf80
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 <== mon.1 192.168.253.147:6789/0 1 ==== mon_map magic: 0 v1 ==== 370+0+0 (3034216899 0 0) 0x21ec1c0 con 0x2636e00
2019-05-31 15:05:03.601 7fc6afca9700 5 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1). rx mon.2 seq 2 0x25b4a00 auth_reply(proto 2 0 (0) Success) v1
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 queue 0x25b4a00 prio 196
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT
2019-05-31 15:05:03.601 7fc6b04aa700 5 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1). rx mon.1 seq 2 0x25b4780 auth_reply(proto 2 0 (0) Success) v1
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 queue 0x25b4780 prio 196
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 dispatch_throttle_release 370 to dispatch throttler 436/104857600
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT
2019-05-31 15:05:03.601 7fc6af4a8700 20 -- 192.168.253.146:0/1920221312 done calling dispatch on 0x21ec1c0
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 <== mon.2 192.168.253.148:6789/0 2 ==== auth_reply(proto 2 0 (0) Success) v1 ==== 33+0+0 (1019608147 0 0) 0x25b4a00 con 0x2637500
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 --> 192.168.253.148:6789/0 -- auth(proto 2 32 bytes epoch 0) v1 -- 0x25b4f00 con 0
2019-05-31 15:05:03.601 7fc6af4a8700 15 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).send_message inline write is denied, reschedule m=0x25b4f00
2019-05-31 15:05:03.601 7fc6af4a8700 20 Event(0x21eb280 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 dispatch_throttle_release 33 to dispatch throttler 66/104857600
2019-05-31 15:05:03.601 7fc6af4a8700 20 -- 192.168.253.146:0/1920221312 done calling dispatch on 0x25b4a00
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).handle_write
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 <== mon.1 192.168.253.147:6789/0 2 ==== auth_reply(proto 2 0 (0) Success) v1 ==== 33+0+0 (1900983216 0 0) 0x25b4780 con 0x2636e00
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).prepare_send_message m auth(proto 2 32 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).prepare_send_message encoding features 4611087854031142907 0x25b4f00 auth(proto 2 32 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 --> 192.168.253.147:6789/0 -- auth(proto 2 32 bytes epoch 0) v1 -- 0x25b4a00 con 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message no session security
2019-05-31 15:05:03.601 7fc6af4a8700 15 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).send_message inline write is denied, reschedule m=0x25b4a00
2019-05-31 15:05:03.601 7fc6af4a8700 20 Event(0x21eb040 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message sending message type=17 src client.? front=62 data=0 off 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message sending 2 0x25b4f00
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 dispatch_throttle_release 33 to dispatch throttler 33/104857600
2019-05-31 15:05:03.601 7fc6af4a8700 20 -- 192.168.253.146:0/1920221312 done calling dispatch on 0x25b4780
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).handle_write
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1)._try_send sent bytes 137 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).prepare_send_message m auth(proto 2 32 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).write_message sending 0x25b4f00 done.
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).prepare_send_message encoding features 4611087854031142907 0x25b4a00 auth(proto 2 32 bytes epoch 0) v1
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message no session security
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message sending message type=17 src client.? front=62 data=0 off 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message sending 2 0x25b4a00
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1)._try_send sent bytes 137 remaining bytes 0
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).write_message sending 0x25b4a00 done.
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process begin MSG
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process got MSG header
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3100169 cs=1 l=1).process got envelope type=18 src mon.2 front=24 data=0 off 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3100169 cs=1 l=1).process got front 24
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process aborted = 0
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process got 24 + 0 + 0 byte message
2019-05-31 15:05:03.601 7fc6afca9700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1).process no session security set
2019-05-31 15:05:03.601 7fc6afca9700 5 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3100169 cs=1 l=1). rx mon.2 seq 3 0x25b4f00 auth_reply(proto 2 -1 (1) Operation not permitted) v1
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 queue 0x25b4f00 prio 196
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN
2019-05-31 15:05:03.601 7fc6afca9700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process begin MSG
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process got MSG header
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 <== mon.2 192.168.253.148:6789/0 3 ==== auth_reply(proto 2 -1 (1) Operation not permitted) v1 ==== 24+0+0 (3423368118 0 0) 0x25b4f00 con 0x2637500
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).mark_down
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_HEADER pgs=3081004 cs=1 l=1).process got envelope type=18 src mon.1 front=24 data=0 off 0
2019-05-31 15:05:03.601 7fc6af4a8700 2 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1)._stop
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.148:6789/0 conn(0x2637500 :-1 s=STATE_OPEN pgs=3100169 cs=1 l=1).discard_out_queue started
2019-05-31 15:05:03.601 7fc6af4a8700 20 Event(0x21eb280 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 dispatch_throttle_release 24 to dispatch throttler 48/104857600
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=3081004 cs=1 l=1).process got front 24
2019-05-31 15:05:03.601 7fc6af4a8700 20 -- 192.168.253.146:0/1920221312 done calling dispatch on 0x25b4f00
2019-05-31 15:05:03.601 7fc6afca9700 20 EpollDriver.del_event del event fd=14 cur_mask=1 delmask=3 to 10
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process aborted = 0
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process got 24 + 0 + 0 byte message
2019-05-31 15:05:03.601 7fc6b04aa700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1).process no session security set
2019-05-31 15:05:03.601 7fc6b04aa700 5 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=3081004 cs=1 l=1). rx mon.1 seq 3 0x25b4a00 auth_reply(proto 2 -1 (1) Operation not permitted) v1
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 queue 0x25b4a00 prio 196
2019-05-31 15:05:03.601 7fc6b04aa700 20 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 <== mon.1 192.168.253.147:6789/0 3 ==== auth_reply(proto 2 -1 (1) Operation not permitted) v1 ==== 24+0+0 (2269057026 0 0) 0x25b4a00 con 0x2636e00
2019-05-31 15:05:03.601 7fc6af4a8700 1 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).mark_down
2019-05-31 15:05:03.601 7fc6af4a8700 2 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1)._stop
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.147:6789/0 conn(0x2636e00 :-1 s=STATE_OPEN pgs=3081004 cs=1 l=1).discard_out_queue started
2019-05-31 15:05:03.601 7fc6af4a8700 20 Event(0x21eb040 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6b04aa700 20 EpollDriver.del_event del event fd=13 cur_mask=1 delmask=3 to 7
2019-05-31 15:05:03.601 7fc6af4a8700 10 -- 192.168.253.146:0/1920221312 dispatch_throttle_release 24 to dispatch throttler 24/104857600
2019-05-31 15:05:03.601 7fc6af4a8700 20 -- 192.168.253.146:0/1920221312 done calling dispatch on 0x25b4a00
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- 192.168.253.146:0/1920221312 shutdown 192.168.253.146:0/1920221312
2019-05-31 15:05:03.601 7fc6bf4ec200 10 Processor -- stop
2019-05-31 15:05:03.601 7fc6bf4ec200 1 -- 192.168.253.146:0/1920221312 shutdown_connections
2019-05-31 15:05:03.601 7fc6bf4ec200 5 -- 192.168.253.146:0/1920221312 shutdown_connections mark down 192.168.253.148:6789/0 0x2637500
2019-05-31 15:05:03.601 7fc6bf4ec200 5 -- 192.168.253.146:0/1920221312 shutdown_connections mark down 192.168.253.147:6789/0 0x2636e00
2019-05-31 15:05:03.601 7fc6bf4ec200 5 -- 192.168.253.146:0/1920221312 shutdown_connections delete 0x2636e00
2019-05-31 15:05:03.601 7fc6bf4ec200 5 -- 192.168.253.146:0/1920221312 shutdown_connections delete 0x2637500
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eae00 nevent=5000 time_id=1).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb040 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb280 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- 192.168.253.146:0/1920221312 wait: waiting for dispatch queue
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- 192.168.253.146:0/1920221312 wait: dispatch queue is stopped
2019-05-31 15:05:03.601 7fc6bf4ec200 1 -- 192.168.253.146:0/1920221312 shutdown_connections
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eae00 nevent=5000 time_id=1).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb040 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eb280 nevent=5000 time_id=2).wakeup
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- 192.168.253.146:0/1920221312 wait: done.
2019-05-31 15:05:03.601 7fc6bf4ec200 1 -- 192.168.253.146:0/1920221312 wait complete.
2019-05-31 15:05:03.601 7fc6bf4ec200 1 -- 192.168.253.146:0/1920221312 >> 192.168.253.146:0/1920221312 conn(0x2636700 :-1 s=STATE_NONE pgs=0 cs=0 l=0).mark_down
2019-05-31 15:05:03.601 7fc6bf4ec200 2 -- 192.168.253.146:0/1920221312 >> 192.168.253.146:0/1920221312 conn(0x2636700 :-1 s=STATE_NONE pgs=0 cs=0 l=0)._stop
2019-05-31 15:05:03.601 7fc6bf4ec200 10 -- 192.168.253.146:0/1920221312 >> 192.168.253.146:0/1920221312 conn(0x2636700 :-1 s=STATE_NONE pgs=0 cs=0 l=0).discard_out_queue started
2019-05-31 15:05:03.601 7fc6bf4ec200 20 Event(0x21eae00 nevent=5000 time_id=1).wakeup
failed to fetch mon config (--no-mon-config to skip)
    (1-1/1)