服务端连接日志（AsyncMessenger）

AsyncMessenger 通信层，服务端连接日志

7fdcf4d44700 10  Processor -- accept listen_fd=227fdcf4d44700 10 stack get_worker7fdcf4d44700 10  Processor -- accept accepted incoming on sd 317fdcf4d44700 10 -- 127.0.0.1:17110/0 >> - conn(0x7fdd0584b800 :-1 s=STATE_NONE pgs=0 cs=0 l=0).accept sd=317fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=978).dispatch_event_external 0x7fdd0503c6e0 pending 17fdcf4d44700 10 stack get_worker7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=978).process_events event_wq process is 22 mask is 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=978).process_events do 0x7fdd0503c6e07fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=978).create_file_event create event started fd=31 mask=1 original mask is 07fdcf4d44700 20 EpollDriver.add_event add event fd=31 cur_mask=0 add_mask=1 to 137fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=978).create_file_event create event end fd=31 mask=1 original mask is 17fdcf4d44700  1 -- 127.0.0.1:17110/0 >> - conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING pgs=0 cs=0 l=0)._process_connection sd=31 -7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> - conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING pgs=0 cs=0 l=0)._try_send sent bytes 281 remaining bytes 07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> - conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_BANNER_ADDR pgs=0 cs=0 l=0)._process_connection write banner and addr done: -7fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=978).process_events wait second 30 usec 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> - conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_BANNER_ADDR pgs=0 cs=0 l=0).process prev state is STATE_ACCEPTING_WAIT_BANNER_ADDR7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> - conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_BANNER_ADDR pgs=0 cs=0 l=0)._process_connection accept peer addr is 127.0.0.1:0/36707967027fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG pgs=0 cs=0 l=0).process prev state is STATE_ACCEPTING_WAIT_BANNER_ADDR7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=0).process prev state is STATE_ACCEPTING_WAIT_CONNECT_MSG7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=0)._process_connection accept got peer connect_seq 0 global_seq 10587fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1)._process_connection accept of host_type 8, policy.lossy=1 policy.server=1 policy.standby=0 policy.resetcheck=07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_msg accept my proto 15, their proto 157fdcf4d44700 10 mon.a@0(leader) e2 ms_verify_authorizer 127.0.0.1:0/3670796702 client protocol 07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_msg accept setting up session_security.7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=0 cs=0 l=1).handle_connect_msg accept new session7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=1058 cs=1 l=1).handle_connect_msg accept success, connect_seq = 1 in_seq=0, sending READY7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=1058 cs=1 l=1).handle_connect_msg accept features7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH pgs=1058 cs=1 l=1)._try_send sent bytes 34 remaining bytes 07fdcf4d44700  2 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_SEQ pgs=1058 cs=1 l=1).handle_connect_msg accept write reply msg done7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_SEQ pgs=1058 cs=1 l=1).process prev state is STATE_ACCEPTING_WAIT_CONNECT_MSG_AUTH7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=978).process_events event_wq process is 31 mask is 17fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=978).process_events wait second 30 usec 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_SEQ pgs=1058 cs=1 l=1).process prev state is STATE_ACCEPTING_WAIT_SEQ7fdcf4d44700  2 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_SEQ pgs=1058 cs=1 l=1)._process_connection accept get newly_acked_seq 07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_WAIT_SEQ pgs=1058 cs=1 l=1).discard_requeued_up_to 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_READY pgs=1058 cs=1 l=1).process prev state is STATE_ACCEPTING_WAIT_SEQ7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_ACCEPTING_READY pgs=1058 cs=1 l=1)._process_connection accept done7fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).create_time_event id=978 trigger after 900000000us7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_ACCEPTING_READY7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_KEEPALIVE2 pgs=1058 cs=1 l=1).process prev state is STATE_OPEN7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_KEEPALIVE2 pgs=1058 cs=1 l=1)._append_keepalive_or_ack7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_KEEPALIVE2 pgs=1058 cs=1 l=1).process got KEEPALIVE2 2017-04-18 07:22:38.4634677fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_KEEPALIVE27fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process prev state is STATE_OPEN7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process begin MSG7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process got MSG header7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process got envelope type=17 src client.4097 front=60 data=0 off 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=1058 cs=1 l=1).process wants 60 bytes from policy throttler 0/1048576007fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=1058 cs=1 l=1).process got front 607fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1).process aborted = 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1).process got 60 + 0 + 0 byte message7fdcf4d44700  5 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1). rx client.4097 seq 1 0x7fdd05421f80 auth(proto 0 30 bytes epoch 2) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 queue 0x7fdd05421f80 prio 1277fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).dispatch_event_external 0x7fdd0503c720 pending 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events event_wq process is 31 mask is 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events do 0x7fdd0503c7207fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).handle_write7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1)._try_send sent bytes 9 remaining bytes 07fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events wait second 30 usec 07fdcf053b700 10 mon.a@0(leader) e2 reset/close on session client.4097 127.0.0.1:0/36707967027fdcf053b700 10 mon.a@0(leader) e2 remove_session 0x7fdd056603c0 client.4097 127.0.0.1:0/36707967027fdcf053b700  1 -- 127.0.0.1:17110/0 <== client.4097 127.0.0.1:0/3670796702 1 ==== auth(proto 0 30 bytes epoch 2) v1 ==== 60+0+0 (3860932995 0 0) 0x7fdd05421f80 con 0x7fdd0584b8007fdcf053b700 10 mon.a@0(leader) e2 _ms_dispatch new session 0x7fdd0568e840 MonSession(client.4097 127.0.0.1:0/3670796702 is open)7fdcf053b700 20 mon.a@0(leader) e2  caps 7fdcf053b700 10 mon.a@0(leader).paxosservice(auth 1..2) dispatch 0x7fdd05421f80 auth(proto 0 30 bytes epoch 2) v1 from client.4097 127.0.0.1:0/3670796702 con 0x7fdd0584b8007fdcf053b700  5 mon.a@0(leader).paxos(paxos active c 1..8) is_readable = 1 - now=2017-04-18 07:22:38.463728 lease_expire=0.000000 has v0 lc 87fdcf053b700 10 mon.a@0(leader).auth v2 preprocess_query auth(proto 0 30 bytes epoch 2) v1 from client.4097 127.0.0.1:0/36707967027fdcf053b700 10 mon.a@0(leader).auth v2 prep_auth() blob_size=307fdcf053b700  2 mon.a@0(leader) e2 send_reply 0x7fdd05459e60 0x7fdd05621200 auth_reply(proto 1 0 (0) Success) v17fdcf053b700  1 -- 127.0.0.1:17110/0 --> 127.0.0.1:0/3670796702 -- auth_reply(proto 1 0 (0) Success) v1 -- 0x7fdd05621200 con 07fdcf053b700 15 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).send_message inline write is denied, reschedule m=0x7fdd056212007fdcf053b700  2 Event(0x7fdd050b0c80 nevent=5000 time_id=979).wakeup7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events event_wq process is 14 mask is 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events do 0x7fdd0503c7207fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).handle_write7fdcf053b700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).dispatch_event_external 0x7fdd0503c720 pending 17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).prepare_send_message m auth_reply(proto 1 0 (0) Success) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).prepare_send_message encoding features 1152323339925389307 0x7fdd05621200 auth_reply(proto 1 0 (0) Success) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message signed m=0x7fdd05621200): sig = 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending message type=18 src mon.0 front=24 data=0 off 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending 1 0x7fdd056212007fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1)._try_send sent bytes 99 remaining bytes 07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending 0x7fdd05621200 done.7fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events wait second 30 usec 07fdcf053b700 10 -- 127.0.0.1:17110/0 dispatch_throttle_release 60 to dispatch throttler 60/1048576007fdcf053b700 20 -- 127.0.0.1:17110/0 done calling dispatch on 0x7fdd05421f807fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_OPEN7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process prev state is STATE_OPEN7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process begin MSG7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process got MSG header7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process got envelope type=15 src client.4097 front=42 data=0 off 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=1058 cs=1 l=1).process wants 42 bytes from policy throttler 0/1048576007fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=1058 cs=1 l=1).process got front 427fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1).process aborted = 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1).process got 42 + 0 + 0 byte message7fdcf4d44700  5 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1). rx client.4097 seq 2 0x7fdd05942800 mon_subscribe({mgrmap=0+,monmap=3+}) v27fdcf4d44700 20 -- 127.0.0.1:17110/0 queue 0x7fdd05942800 prio 1277fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process prev state is STATE_OPEN7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process begin MSG7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process got MSG header7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_HEADER pgs=1058 cs=1 l=1).process got envelope type=50 src client.4097 front=80 data=0 off 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_MESSAGE pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_HEADER7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_MESSAGE7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_BYTES pgs=1058 cs=1 l=1).process wants 80 bytes from policy throttler 42/1048576007fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_BYTES7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_THROTTLE_DISPATCH_QUEUE7fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FRONT pgs=1058 cs=1 l=1).process got front 807fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1).process aborted = 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1).process got 80 + 0 + 0 byte message7fdcf4d44700  5 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN_MESSAGE_READ_FOOTER_AND_DISPATCH pgs=1058 cs=1 l=1). rx client.4097 seq 3 0x7fdd05942a00 mon_command({"prefix": "get_command_descriptions"} v 0) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 queue 0x7fdd05942a00 prio 1277fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_OPEN_MESSAGE_READ_FRONT7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events event_wq process is 31 mask is 17fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events wait second 30 usec 07fdcf053b700  1 -- 127.0.0.1:17110/0 <== client.4097 127.0.0.1:0/3670796702 2 ==== mon_subscribe({mgrmap=0+,monmap=3+}) v2 ==== 42+0+0 (1084470344 0 0) 0x7fdd05942800 con 0x7fdd0584b8007fdcf053b700 20 mon.a@0(leader) e2 _ms_dispatch existing session 0x7fdd0568e840 for client.4097 127.0.0.1:0/36707967027fdcf053b700 20 mon.a@0(leader) e2  caps allow *7fdcf053b700 20 is_capable service=mon command= read on cap allow *7fdcf053b700 20  allow so far , doing grant allow *7fdcf053b700 20  allow all7fdcf053b700 10 mon.a@0(leader) e2 handle_subscribe mon_subscribe({mgrmap=0+,monmap=3+}) v27fdcf053b700 20 mon.a@0(leader).mgr e1 Sending map to subscriber 0x7fdd0584b8007fdcf053b700  1 -- 127.0.0.1:17110/0 --> 127.0.0.1:0/3670796702 -- mgrmap(e 1) v1 -- 0x7fdd05421f80 con 07fdcf053b700 15 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).send_message inline write is denied, reschedule m=0x7fdd05421f807fdcf053b700  2 Event(0x7fdd050b0c80 nevent=5000 time_id=979).wakeup7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events event_wq process is 14 mask is 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events do 0x7fdd0503c7207fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).handle_write7fdcf053b700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).dispatch_event_external 0x7fdd0503c720 pending 17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).prepare_send_message m mgrmap(e 1) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).prepare_send_message encoding features 1152323339925389307 0x7fdd05421f80 mgrmap(e 1) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message signed m=0x7fdd05421f80): sig = 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending message type=1796 src mon.0 front=74 data=0 off 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending 2 0x7fdd05421f807fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1)._try_send sent bytes 149 remaining bytes 07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending 0x7fdd05421f80 done.7fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events wait second 30 usec 07fdcf053b700 10 mon.a@0(leader).monmap v2 check_sub monmap next 3 have 27fdcf053b700 10 -- 127.0.0.1:17110/0 dispatch_throttle_release 42 to dispatch throttler 122/1048576007fdcf053b700 20 -- 127.0.0.1:17110/0 done calling dispatch on 0x7fdd059428007fdcf053b700  1 -- 127.0.0.1:17110/0 <== client.4097 127.0.0.1:0/3670796702 3 ==== mon_command({"prefix": "get_command_descriptions"} v 0) v1 ==== 80+0+0 (3872609866 0 0) 0x7fdd05942a00 con 0x7fdd0584b8007fdcf053b700 20 mon.a@0(leader) e2 _ms_dispatch existing session 0x7fdd0568e840 for client.4097 127.0.0.1:0/36707967027fdcf053b700 20 mon.a@0(leader) e2  caps allow *7fdcf053b700  2 mon.a@0(leader) e2 send_reply 0x7fdd05459e60 0x7fdd05942800 mon_command_ack([{"prefix": "get_command_descriptions"}]=0  v0) v17fdcf053b700  1 -- 127.0.0.1:17110/0 --> 127.0.0.1:0/3670796702 -- mon_command_ack([{"prefix": "get_command_descriptions"}]=0  v0) v1 -- 0x7fdd05942800 con 07fdcf053b700 15 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).send_message inline write is denied, reschedule m=0x7fdd059428007fdcf053b700  2 Event(0x7fdd050b0c80 nevent=5000 time_id=979).wakeup7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events event_wq process is 14 mask is 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events do 0x7fdd0503c7207fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).handle_write7fdcf053b700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).dispatch_event_external 0x7fdd0503c720 pending 17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).prepare_send_message m mon_command_ack([{"prefix": "get_command_descriptions"}]=0  v0) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).prepare_send_message encoding features 1152323339925389307 0x7fdd05942800 mon_command_ack([{"prefix": "get_command_descriptions"}]=0  v0) v17fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message signed m=0x7fdd05942800): sig = 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending message type=51 src mon.0 front=72 data=46999 off 07fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending 3 0x7fdd059428007fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1)._try_send sent bytes 47146 remaining bytes 07fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).write_message sending 0x7fdd05942800 done.7fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events wait second 30 usec 07fdcf053b700 10 -- 127.0.0.1:17110/0 dispatch_throttle_release 80 to dispatch throttler 80/1048576007fdcf053b700 20 -- 127.0.0.1:17110/0 done calling dispatch on 0x7fdd05942a007fdcf4d44700 20 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process prev state is STATE_OPEN7fdcf4d44700  1 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).read_bulk peer close file descriptor 317fdcf4d44700  1 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).read_until read failed7fdcf4d44700  1 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).process read tag failed7fdcf4d44700  1 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).fault on lossy channel, failing7fdcf4d44700  2 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1)._stop7fdcf4d44700 10 -- 127.0.0.1:17110/0 >> 127.0.0.1:0/3670796702 conn(0x7fdd0584b800 :17110 s=STATE_OPEN pgs=1058 cs=1 l=1).discard_out_queue started7fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).dispatch_event_external 0x7fdd0503c480 pending 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events event_wq process is 31 mask is 17fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).process_events do 0x7fdd0503c4807fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).delete_time_event id=9787fdcf4d44700 20 Event(0x7fdd050b0c80 nevent=5000 time_id=979).delete_file_event delete event started fd=31 mask=3 original mask is 17fdcf4d44700 20 EpollDriver.del_event del event fd=31 cur_mask=1 delmask=3 to 137fdcf4d44700 10 Event(0x7fdd050b0c80 nevent=5000 time_id=979).delete_file_event delete event end fd=31 mask=3 original mask is 0