Skip to content

Instantly share code, notes, and snippets.

@visualphoenix
Created September 14, 2014 00:16
Show Gist options
  • Save visualphoenix/19cb0f738aedf0016f12 to your computer and use it in GitHub Desktop.
Save visualphoenix/19cb0f738aedf0016f12 to your computer and use it in GitHub Desktop.
elliptics master issue
2014-09-13 23:51:36.686787 0000000000000000/4839/2574 DEBUG: 1:900000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 310, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.686820 0000000000000000/4839/2574 DEBUG: 1:a00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 311, size: 5020, cflags: 0x1 [need_ack], start-sent: 0/5140., attrs: []
2014-09-13 23:51:36.686849 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:a00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 5020, nonblocking: 0, attrs: []
2014-09-13 23:51:36.686869 0000000000000000/4839/2574 DEBUG: 1:a00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 311, size: 5020, cflags: 0x1 [need_ack], finish-sent: 5140/5140., attrs: []
2014-09-13 23:51:36.686902 0000000000000000/4839/2574 DEBUG: 1:000000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 304, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.686926 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:000000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.686952 0000000000000000/4839/2574 DEBUG: 1:000000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 304, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.686985 0000000000000000/4839/2574 DEBUG: 1:200000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 306, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.687011 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:200000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.687032 0000000000000000/4839/2574 DEBUG: 1:200000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 306, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.687060 0000000000000000/4839/2574 DEBUG: 1:700000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 309, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.687085 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:700000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.687105 0000000000000000/4839/2574 DEBUG: 1:700000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 309, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.687140 0000000000000000/4839/2574 DEBUG: 1:100000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 305, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.687166 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:100000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.687186 0000000000000000/4839/2574 DEBUG: 1:100000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 305, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.687218 0000000000000000/4839/2574 DEBUG: 1:500000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 308, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.687243 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:500000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.687263 0000000000000000/4839/2574 DEBUG: 1:500000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 308, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.696456 0000000000000000/4839/2574 DEBUG: 1:000000000000...6325874a97cb: received trans: 304 / 0x130, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.696492 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:000000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.696515 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:000000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 1, attrs: []
2014-09-13 23:51:36.696553 0000000000000000/4839/2574 DEBUG: 1:700000000000...6325874a97cb: received trans: 309 / 0x135, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.696576 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:700000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.696596 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:700000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:36.696638 0000000000000000/4839/2574 DEBUG: 1:400000000000...6325874a97cb: received trans: 307 / 0x133, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.696665 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:400000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.696697 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:400000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 2, attrs: []
2014-09-13 23:51:36.696722 0000000000000000/4839/2574 DEBUG: 1:900000000000...6325874a97cb: received trans: 310 / 0x136, reply: 1, size: 632, flags: 0x200 [reply], status: 0., attrs: []
2014-09-13 23:51:36.696753 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:900000000000...6325874a97cb: RECV cmd: INDEXES_FIND: cmd-size: 632, nonblocking: 0, attrs: []
2014-09-13 23:51:36.696774 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:900000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 1, attrs: []
2014-09-13 23:51:36.696799 0000000000000000/4839/2574 DEBUG: 1:100000000000...6325874a97cb: received trans: 305 / 0x131, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.696819 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:100000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.696839 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:100000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 2, attrs: []
2014-09-13 23:51:36.696871 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 1:000000000000...6325874a97cb: got IO event: 0x2b134c09a120: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.696902 5f0f17e33f1917c1/4837/2574 ERROR: 1:000000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 304, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.696966 5f0f17e33f1917c1/4837/2574 INFO: 1:000000000000...6325874a97cb: destruction INDEXES_FIND trans: 304, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 11671, started: 2014-09-13 23:51:36.685287, cached status: -2, attrs: []
2014-09-13 23:51:36.696991 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 1:000000000000...6325874a97cb: processed IO event: 0x2b134c09a120, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.697015 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 1:700000000000...6325874a97cb: got IO event: 0x2b134c096320: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.697038 5f0f17e33f1917c1/4837/2574 ERROR: 1:700000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 309, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.697081 5f0f17e33f1917c1/4837/2574 INFO: 1:700000000000...6325874a97cb: destruction INDEXES_FIND trans: 309, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 10937, started: 2014-09-13 23:51:36.686139, cached status: -2, attrs: []
2014-09-13 23:51:36.697105 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 1:700000000000...6325874a97cb: processed IO event: 0x2b134c096320, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.697129 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:400000000000...6325874a97cb: got IO event: 0x2b134c09c330: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.697152 5f0f17e33f1917c1/4837/2574 ERROR: 1:400000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 307, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.697194 5f0f17e33f1917c1/4837/2574 INFO: 1:400000000000...6325874a97cb: destruction INDEXES_FIND trans: 307, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 11385, started: 2014-09-13 23:51:36.685804, cached status: -2, attrs: []
2014-09-13 23:51:36.697218 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:400000000000...6325874a97cb: processed IO event: 0x2b134c09c330, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.697248 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:900000000000...6325874a97cb: got IO event: 0x2b134c095c40: cmd: INDEXES_FIND, hsize: 120, dsize: 632, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.697270 5f0f17e33f1917c1/4837/2574 NOTICE: 1:900000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 310, status: 0, size: 632, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.697308 5f0f17e33f1917c1/4837/2574 INFO: 1:900000000000...6325874a97cb: destruction INDEXES_FIND trans: 310, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 11027, started: 2014-09-13 23:51:36.686277, cached status: 0, attrs: []
2014-09-13 23:51:36.697332 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:900000000000...6325874a97cb: processed IO event: 0x2b134c095c40, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.697356 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 1:100000000000...6325874a97cb: got IO event: 0x2b134c08f250: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.697378 5f0f17e33f1917c1/4837/2574 ERROR: 1:100000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 305, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.697420 5f0f17e33f1917c1/4837/2574 INFO: 1:100000000000...6325874a97cb: destruction INDEXES_FIND trans: 305, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 11974, started: 2014-09-13 23:51:36.685442, cached status: -2, attrs: []
2014-09-13 23:51:36.697444 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 1:100000000000...6325874a97cb: processed IO event: 0x2b134c08f250, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.697802 0000000000000000/4839/2574 DEBUG: 1:500000000000...6325874a97cb: received trans: 308 / 0x134, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.697827 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:500000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.697849 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:500000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:36.697879 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 1:500000000000...6325874a97cb: got IO event: 0x2b134c08f250: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.697903 5f0f17e33f1917c1/4837/2574 ERROR: 1:500000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 308, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.697947 5f0f17e33f1917c1/4837/2574 INFO: 1:500000000000...6325874a97cb: destruction INDEXES_FIND trans: 308, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 11968, started: 2014-09-13 23:51:36.685974, cached status: -2, attrs: []
2014-09-13 23:51:36.697972 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 1:500000000000...6325874a97cb: processed IO event: 0x2b134c08f250, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.686602 5f0f17e33f1917c1/2574/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:36.699722 5f0f17e33f1917c1/2574/2574 INFO: 1:f00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 312 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.699752 5f0f17e33f1917c1/2574/2574 NOTICE: 1:f00000000000...6325874a97cb: added transaction: 312 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.699784 5f0f17e33f1917c1/2574/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 1, attrs: []
2014-09-13 23:51:36.699811 5f0f17e33f1917c1/2574/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 1, count: 9, attrs: []
2014-09-13 23:51:36.700049 0000000000000000/4839/2574 DEBUG: 1:f00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 312, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.700086 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:f00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.700108 0000000000000000/4839/2574 DEBUG: 1:f00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 312, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.701070 0000000000000000/4839/2574 DEBUG: 1:f00000000000...6325874a97cb: received trans: 312 / 0x138, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.701096 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:f00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.701117 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 1:f00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 1, attrs: []
2014-09-13 23:51:36.701148 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 1:f00000000000...6325874a97cb: got IO event: 0x2b134c08f250: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.701173 5f0f17e33f1917c1/4837/2574 ERROR: 1:f00000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 312, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.701224 5f0f17e33f1917c1/4837/2574 INFO: 1:f00000000000...6325874a97cb: destruction INDEXES_FIND trans: 312, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 1500, started: 2014-09-13 23:51:36.699717, cached status: -2, attrs: []
2014-09-13 23:51:36.701248 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 1:f00000000000...6325874a97cb: processed IO event: 0x2b134c08f250, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.702660 0000000000000000/4839/2574 DEBUG: 1:200000000000...6325874a97cb: received trans: 306 / 0x132, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.702695 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:200000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.702717 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 1:200000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 2, attrs: []
2014-09-13 23:51:36.702747 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 1:200000000000...6325874a97cb: got IO event: 0x2b134c08f250: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.702772 5f0f17e33f1917c1/4837/2574 ERROR: 1:200000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 306, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.702819 5f0f17e33f1917c1/4837/2574 INFO: 1:200000000000...6325874a97cb: destruction INDEXES_FIND trans: 306, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 17164, started: 2014-09-13 23:51:36.685649, cached status: -2, attrs: []
2014-09-13 23:51:36.702844 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 1:200000000000...6325874a97cb: processed IO event: 0x2b134c08f250, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.703138 0000000000000000/4839/2574 DEBUG: 1:a00000000000...6325874a97cb: received trans: 311 / 0x137, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.703162 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:a00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.703182 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:a00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:36.703212 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:a00000000000...6325874a97cb: got IO event: 0x2b134c08f250: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.703244 5f0f17e33f1917c1/4837/2574 ERROR: 1:a00000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 311, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.703302 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, index_requests_set.size: 15, group_index: 1, group_count: 3, attrs: []
2014-09-13 23:51:36.703332 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, next, attrs: []
2014-09-13 23:51:36.703357 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:000000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:36.703389 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:100000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:36.703422 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:200000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:36.703450 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:300000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:36.703479 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 3, state: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:36.703515 5f0f17e33f1917c1/4837/2574 INFO: 2:000000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 313 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.703542 5f0f17e33f1917c1/4837/2574 NOTICE: 2:000000000000...6325874a97cb: added transaction: 313 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.703572 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.703597 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:400000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:36.703646 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:500000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:36.703684 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 2, state: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:36.703718 5f0f17e33f1917c1/4837/2574 INFO: 2:300000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 314 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.703742 5f0f17e33f1917c1/4837/2574 NOTICE: 2:300000000000...6325874a97cb: added transaction: 314 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.703769 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.703795 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:600000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:36.703824 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:700000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:36.703852 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:800000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:36.703880 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 3, state: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:36.703912 5f0f17e33f1917c1/4837/2574 INFO: 2:500000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 315 -> 127.0.0.1:25109, weight: 1.000000., attrs: []
2014-09-13 23:51:36.703936 5f0f17e33f1917c1/4837/2574 NOTICE: 2:500000000000...6325874a97cb: added transaction: 315 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:36.703963 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.703988 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:a00000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:36.704023 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:36.704055 5f0f17e33f1917c1/4837/2574 INFO: 2:800000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 316 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.704079 5f0f17e33f1917c1/4837/2574 NOTICE: 2:800000000000...6325874a97cb: added transaction: 316 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.704105 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.704129 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:b00000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:36.704159 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:36.704190 5f0f17e33f1917c1/4837/2574 INFO: 2:a00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 317 -> 127.0.0.1:25109, weight: 1.000000., attrs: []
2014-09-13 23:51:36.704214 5f0f17e33f1917c1/4837/2574 NOTICE: 2:a00000000000...6325874a97cb: added transaction: 317 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:36.704240 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.704264 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:c00000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:36.704293 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:36.704324 5f0f17e33f1917c1/4837/2574 INFO: 2:b00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 318 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.704348 5f0f17e33f1917c1/4837/2574 NOTICE: 2:b00000000000...6325874a97cb: added transaction: 318 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.704374 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.704398 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:d00000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:36.704427 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:e00000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:36.704456 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 2, state: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:36.704487 5f0f17e33f1917c1/4837/2574 INFO: 2:c00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 319 -> 127.0.0.1:25109, weight: 1.000000., attrs: []
2014-09-13 23:51:36.704511 5f0f17e33f1917c1/4837/2574 NOTICE: 2:c00000000000...6325874a97cb: added transaction: 319 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:36.704536 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.704561 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 2:f00000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:36.704590 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:36.704638 5f0f17e33f1917c1/4837/2574 INFO: 2:e00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 320 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.704663 5f0f17e33f1917c1/4837/2574 NOTICE: 2:e00000000000...6325874a97cb: added transaction: 320 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.704699 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.704725 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:36.704795 0000000000000000/4839/2574 DEBUG: 2:300000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 314, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.704824 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:300000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.704846 0000000000000000/4839/2574 DEBUG: 2:300000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 314, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.704882 0000000000000000/4839/2574 DEBUG: 2:800000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 316, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.704906 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:800000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.704927 0000000000000000/4839/2574 DEBUG: 2:800000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 316, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.704960 0000000000000000/4839/2574 DEBUG: 2:b00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 318, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.704985 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:b00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.705005 0000000000000000/4839/2574 DEBUG: 2:b00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 318, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.705036 0000000000000000/4839/2574 DEBUG: 2:000000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 313, size: 3012, cflags: 0x1 [need_ack], start-sent: 0/3132., attrs: []
2014-09-13 23:51:36.705063 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:000000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 3012, nonblocking: 0, attrs: []
2014-09-13 23:51:36.705084 0000000000000000/4839/2574 DEBUG: 2:000000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 313, size: 3012, cflags: 0x1 [need_ack], finish-sent: 3132/3132., attrs: []
2014-09-13 23:51:36.710645 0000000000000000/4839/2574 DEBUG: 2:e00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 320, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.710689 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:e00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.710712 0000000000000000/4839/2574 DEBUG: 2:e00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 320, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.711632 0000000000000000/4839/2574 DEBUG: 2:000000000000...6325874a97cb: received trans: 313 / 0x139, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.711657 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:000000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.711689 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:000000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 4, attrs: []
2014-09-13 23:51:36.711714 0000000000000000/4839/2574 DEBUG: 2:500000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 315, size: 3012, cflags: 0x1 [need_ack], start-sent: 0/3132., attrs: []
2014-09-13 23:51:36.711741 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:500000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 3012, nonblocking: 0, attrs: []
2014-09-13 23:51:36.711768 0000000000000000/4839/2574 DEBUG: 2:500000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 315, size: 3012, cflags: 0x1 [need_ack], finish-sent: 3132/3132., attrs: []
2014-09-13 23:51:36.713633 0000000000000000/4839/2574 DEBUG: 2:a00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 317, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.713664 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:a00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.713696 0000000000000000/4839/2574 DEBUG: 2:a00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 317, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.713747 0000000000000000/4839/2574 DEBUG: 2:c00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 319, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.713774 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:c00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.713795 0000000000000000/4839/2574 DEBUG: 2:c00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 319, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.713833 0000000000000000/4839/2574 DEBUG: 2:e00000000000...6325874a97cb: received trans: 320 / 0x140, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.713856 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:e00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.713877 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:e00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 3, attrs: []
2014-09-13 23:51:36.713901 0000000000000000/4839/2574 DEBUG: 2:300000000000...6325874a97cb: received trans: 314 / 0x13a, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.713922 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:300000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.713942 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:300000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 3, attrs: []
2014-09-13 23:51:36.713965 0000000000000000/4839/2574 DEBUG: 2:800000000000...6325874a97cb: received trans: 316 / 0x13c, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.713986 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:800000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.714006 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:800000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 4, attrs: []
2014-09-13 23:51:36.714037 0000000000000000/4839/2574 DEBUG: 2:b00000000000...6325874a97cb: received trans: 318 / 0x13e, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.714059 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:b00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.714079 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:b00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 5, attrs: []
2014-09-13 23:51:36.716111 0000000000000000/4839/2574 DEBUG: 2:c00000000000...6325874a97cb: received trans: 319 / 0x13f, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.716138 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:c00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.716165 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:c00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 3, attrs: []
2014-09-13 23:51:36.716199 0000000000000000/4839/2574 DEBUG: 2:500000000000...6325874a97cb: received trans: 315 / 0x13b, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.716221 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:500000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.716242 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:500000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 4, attrs: []
2014-09-13 23:51:36.716272 5f0f17e33f1917c1/4837/2574 INFO: 2:f00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 321 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.716302 5f0f17e33f1917c1/4837/2574 NOTICE: 2:f00000000000...6325874a97cb: added transaction: 321 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.716338 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, attrs: []
2014-09-13 23:51:36.716370 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 2, count: 9, attrs: []
2014-09-13 23:51:36.716417 5f0f17e33f1917c1/4837/2574 INFO: 1:a00000000000...6325874a97cb: destruction INDEXES_FIND trans: 311, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 16772, started: 2014-09-13 23:51:36.686523, cached status: -2, attrs: []
2014-09-13 23:51:36.716444 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:a00000000000...6325874a97cb: processed IO event: 0x2b134c08f250, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.716469 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 2:000000000000...6325874a97cb: got IO event: 0x2b134c08f930: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.716492 5f0f17e33f1917c1/4837/2574 ERROR: 2:000000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 313, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.716545 5f0f17e33f1917c1/4837/2574 INFO: 2:000000000000...6325874a97cb: destruction INDEXES_FIND trans: 313, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 13029, started: 2014-09-13 23:51:36.703510, cached status: -2, attrs: []
2014-09-13 23:51:36.716570 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 2:000000000000...6325874a97cb: processed IO event: 0x2b134c08f930, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.716593 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 2:e00000000000...6325874a97cb: got IO event: 0x2b134c099cc0: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.716633 5f0f17e33f1917c1/4837/2574 ERROR: 2:e00000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 320, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.716690 5f0f17e33f1917c1/4837/2574 INFO: 2:e00000000000...6325874a97cb: destruction INDEXES_FIND trans: 320, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 12049, started: 2014-09-13 23:51:36.704635, cached status: -2, attrs: []
2014-09-13 23:51:36.716716 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 2:e00000000000...6325874a97cb: processed IO event: 0x2b134c099cc0, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.716740 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:300000000000...6325874a97cb: got IO event: 0x2b134c095f00: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.716762 5f0f17e33f1917c1/4837/2574 ERROR: 2:300000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 314, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.716806 5f0f17e33f1917c1/4837/2574 INFO: 2:300000000000...6325874a97cb: destruction INDEXES_FIND trans: 314, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 13086, started: 2014-09-13 23:51:36.703715, cached status: -2, attrs: []
2014-09-13 23:51:36.716836 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:300000000000...6325874a97cb: processed IO event: 0x2b134c095f00, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.716860 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:800000000000...6325874a97cb: got IO event: 0x2b134c0960e0: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.716882 5f0f17e33f1917c1/4837/2574 ERROR: 2:800000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 316, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.716925 5f0f17e33f1917c1/4837/2574 INFO: 2:800000000000...6325874a97cb: destruction INDEXES_FIND trans: 316, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 12868, started: 2014-09-13 23:51:36.704052, cached status: -2, attrs: []
2014-09-13 23:51:36.716949 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:800000000000...6325874a97cb: processed IO event: 0x2b134c0960e0, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.716973 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:b00000000000...6325874a97cb: got IO event: 0x2b134c09feb0: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.716995 5f0f17e33f1917c1/4837/2574 ERROR: 2:b00000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 318, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.717040 5f0f17e33f1917c1/4837/2574 INFO: 2:b00000000000...6325874a97cb: destruction INDEXES_FIND trans: 318, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 12714, started: 2014-09-13 23:51:36.704321, cached status: -2, attrs: []
2014-09-13 23:51:36.717064 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:b00000000000...6325874a97cb: processed IO event: 0x2b134c09feb0, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.717088 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 2:c00000000000...6325874a97cb: got IO event: 0x2b134c09ff80: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.717110 5f0f17e33f1917c1/4837/2574 ERROR: 2:c00000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 319, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.717152 5f0f17e33f1917c1/4837/2574 INFO: 2:c00000000000...6325874a97cb: destruction INDEXES_FIND trans: 319, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 12663, started: 2014-09-13 23:51:36.704484, cached status: -2, attrs: []
2014-09-13 23:51:36.717176 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 2:c00000000000...6325874a97cb: processed IO event: 0x2b134c09ff80, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.717199 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 2:500000000000...6325874a97cb: got IO event: 0x2b134c0a0050: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.717221 5f0f17e33f1917c1/4837/2574 ERROR: 2:500000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 315, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.717265 5f0f17e33f1917c1/4837/2574 INFO: 2:500000000000...6325874a97cb: destruction INDEXES_FIND trans: 315, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 13350, started: 2014-09-13 23:51:36.703909, cached status: -2, attrs: []
2014-09-13 23:51:36.717288 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 2:500000000000...6325874a97cb: processed IO event: 0x2b134c0a0050, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.717317 0000000000000000/4839/2574 DEBUG: 2:f00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 321, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.717344 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:f00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.717371 0000000000000000/4839/2574 DEBUG: 2:f00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 321, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.718388 0000000000000000/4839/2574 DEBUG: 2:f00000000000...6325874a97cb: received trans: 321 / 0x141, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.718413 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:f00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.718434 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 2:f00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 4, attrs: []
2014-09-13 23:51:36.719263 0000000000000000/4839/2574 DEBUG: 2:a00000000000...6325874a97cb: received trans: 317 / 0x13d, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.719289 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:a00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.719310 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 2:a00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 5, attrs: []
2014-09-13 23:51:36.719419 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 2:f00000000000...6325874a97cb: got IO event: 0x2b134c095f00: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.719446 5f0f17e33f1917c1/4837/2574 ERROR: 2:f00000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 321, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.719502 5f0f17e33f1917c1/4837/2574 INFO: 2:f00000000000...6325874a97cb: destruction INDEXES_FIND trans: 321, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 3229, started: 2014-09-13 23:51:36.716266, cached status: -2, attrs: []
2014-09-13 23:51:36.719527 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 2:f00000000000...6325874a97cb: processed IO event: 0x2b134c095f00, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.719551 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 2:a00000000000...6325874a97cb: got IO event: 0x2b134c0960e0: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.719574 5f0f17e33f1917c1/4837/2574 ERROR: 2:a00000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 317, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.719636 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, index_requests_set.size: 15, group_index: 2, group_count: 3, attrs: []
2014-09-13 23:51:36.719668 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, next, attrs: []
2014-09-13 23:51:36.719704 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:000000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:36.719736 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:100000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:36.719767 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:36.719803 5f0f17e33f1917c1/4837/2574 INFO: 3:000000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 322 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.719827 5f0f17e33f1917c1/4837/2574 NOTICE: 3:000000000000...6325874a97cb: added transaction: 322 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.719863 0000000000000000/4839/2574 DEBUG: 3:000000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 322, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.719896 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:000000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.719918 0000000000000000/4839/2574 DEBUG: 3:000000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 322, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.720934 0000000000000000/4839/2574 DEBUG: 3:000000000000...6325874a97cb: received trans: 322 / 0x142, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.720959 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:000000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.720981 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:000000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 6, attrs: []
2014-09-13 23:51:36.721010 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.721038 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:200000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:36.721068 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:36.721101 5f0f17e33f1917c1/4837/2574 INFO: 3:100000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 323 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.721125 5f0f17e33f1917c1/4837/2574 NOTICE: 3:100000000000...6325874a97cb: added transaction: 323 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.721161 0000000000000000/4839/2574 DEBUG: 3:100000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 323, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.721187 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:100000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.721208 0000000000000000/4839/2574 DEBUG: 3:100000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 323, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.722185 0000000000000000/4839/2574 DEBUG: 3:100000000000...6325874a97cb: received trans: 323 / 0x143, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.722212 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:100000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.722233 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:100000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 6, attrs: []
2014-09-13 23:51:36.722263 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.722290 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:300000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:36.722321 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:400000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:36.722351 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:500000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:36.722380 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:600000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:36.722408 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 4, state: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:36.722442 5f0f17e33f1917c1/4837/2574 INFO: 3:200000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 324 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.722474 5f0f17e33f1917c1/4837/2574 NOTICE: 3:200000000000...6325874a97cb: added transaction: 324 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.722508 0000000000000000/4839/2574 DEBUG: 3:200000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 324, size: 4016, cflags: 0x1 [need_ack], start-sent: 0/4136., attrs: []
2014-09-13 23:51:36.722537 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:200000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 4016, nonblocking: 0, attrs: []
2014-09-13 23:51:36.722558 0000000000000000/4839/2574 DEBUG: 3:200000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 324, size: 4016, cflags: 0x1 [need_ack], finish-sent: 4136/4136., attrs: []
2014-09-13 23:51:36.725225 0000000000000000/4839/2574 DEBUG: 3:200000000000...6325874a97cb: received trans: 324 / 0x144, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.725251 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:200000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.725273 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:200000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 7, attrs: []
2014-09-13 23:51:36.725370 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.725400 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:700000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:36.725431 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:36.725464 5f0f17e33f1917c1/4837/2574 INFO: 3:600000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 325 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.725489 5f0f17e33f1917c1/4837/2574 NOTICE: 3:600000000000...6325874a97cb: added transaction: 325 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.725523 0000000000000000/4839/2574 DEBUG: 3:600000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 325, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.725550 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:600000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.725571 0000000000000000/4839/2574 DEBUG: 3:600000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 325, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.726533 0000000000000000/4839/2574 DEBUG: 3:600000000000...6325874a97cb: received trans: 325 / 0x145, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.726559 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:600000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.726580 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:600000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 8, attrs: []
2014-09-13 23:51:36.726609 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.726652 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:800000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:36.726693 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:a00000000000...6325874a97cb, state: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:36.726723 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 2, state: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:36.726756 5f0f17e33f1917c1/4837/2574 INFO: 3:700000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 326 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.726787 5f0f17e33f1917c1/4837/2574 NOTICE: 3:700000000000...6325874a97cb: added transaction: 326 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.726821 0000000000000000/4839/2574 DEBUG: 3:700000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 326, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.726848 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:700000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.726869 0000000000000000/4839/2574 DEBUG: 3:700000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 326, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.728350 0000000000000000/4839/2574 DEBUG: 3:700000000000...6325874a97cb: received trans: 326 / 0x146, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.728375 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:700000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.728397 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:700000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 7, attrs: []
2014-09-13 23:51:36.728426 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.728453 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:b00000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:36.728485 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 1, state: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:36.728517 5f0f17e33f1917c1/4837/2574 INFO: 3:a00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 327 -> 127.0.0.1:25109, weight: 1.000000., attrs: []
2014-09-13 23:51:36.728542 5f0f17e33f1917c1/4837/2574 NOTICE: 3:a00000000000...6325874a97cb: added transaction: 327 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:36.728577 0000000000000000/4839/2574 DEBUG: 3:a00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 327, size: 1004, cflags: 0x1 [need_ack], start-sent: 0/1124., attrs: []
2014-09-13 23:51:36.728604 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 3:a00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 1004, nonblocking: 0, attrs: []
2014-09-13 23:51:36.728654 0000000000000000/4839/2574 DEBUG: 3:a00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25109: trans: 327, size: 1004, cflags: 0x1 [need_ack], finish-sent: 1124/1124., attrs: []
2014-09-13 23:51:36.729598 0000000000000000/4839/2574 DEBUG: 3:a00000000000...6325874a97cb: received trans: 327 / 0x147, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.729640 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 3:a00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.729662 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 3:a00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 8, attrs: []
2014-09-13 23:51:36.729702 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.729731 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:c00000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:36.729761 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:d00000000000...6325874a97cb, state: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:36.729790 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:e00000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:36.729819 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 3, state: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:36.729857 5f0f17e33f1917c1/4837/2574 INFO: 3:b00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 328 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.729883 5f0f17e33f1917c1/4837/2574 NOTICE: 3:b00000000000...6325874a97cb: added transaction: 328 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.729917 0000000000000000/4839/2574 DEBUG: 3:b00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 328, size: 3012, cflags: 0x1 [need_ack], start-sent: 0/3132., attrs: []
2014-09-13 23:51:36.729945 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:b00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 3012, nonblocking: 0, attrs: []
2014-09-13 23:51:36.729966 0000000000000000/4839/2574 DEBUG: 3:b00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:61038: trans: 328, size: 3012, cflags: 0x1 [need_ack], finish-sent: 3132/3132., attrs: []
2014-09-13 23:51:36.732133 0000000000000000/4839/2574 DEBUG: 3:b00000000000...6325874a97cb: received trans: 328 / 0x148, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.732159 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:b00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.732181 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:b00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 8, attrs: []
2014-09-13 23:51:36.732292 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.732320 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, id: 3:f00000000000...6325874a97cb, state: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:36.732352 5f0f17e33f1917c1/4837/2574 NOTICE: INDEXES_FIND: callback: 0x1d96d58, count: 2, state: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:36.732385 5f0f17e33f1917c1/4837/2574 INFO: 3:e00000000000...6325874a97cb: alloc/send INDEXES_FIND trans: 329 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.732411 5f0f17e33f1917c1/4837/2574 NOTICE: 3:e00000000000...6325874a97cb: added transaction: 329 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.732446 0000000000000000/4839/2574 DEBUG: 3:e00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 329, size: 2008, cflags: 0x1 [need_ack], start-sent: 0/2128., attrs: []
2014-09-13 23:51:36.732474 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:e00000000000...6325874a97cb: SENT blocking cmd: INDEXES_FIND: cmd-size: 2008, nonblocking: 0, attrs: []
2014-09-13 23:51:36.732495 0000000000000000/4839/2574 DEBUG: 3:e00000000000...6325874a97cb: INDEXES_FIND: sending -> 127.0.0.1:25963: trans: 329, size: 2008, cflags: 0x1 [need_ack], finish-sent: 2128/2128., attrs: []
2014-09-13 23:51:36.734035 0000000000000000/4839/2574 DEBUG: 3:e00000000000...6325874a97cb: received trans: 329 / 0x149, reply: 1, size: 0, flags: 0x200 [reply], status: -2., attrs: []
2014-09-13 23:51:36.734061 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:e00000000000...6325874a97cb: RECV ACK: INDEXES_FIND: nonblocking: 0, attrs: []
2014-09-13 23:51:36.734082 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 3:e00000000000...6325874a97cb: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 6, attrs: []
2014-09-13 23:51:36.734111 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, attrs: []
2014-09-13 23:51:36.734137 5f0f17e33f1917c1/4837/2574 DEBUG: INDEXES_FIND, callback: 0x1d96d58, group: 3, count: 8, attrs: []
2014-09-13 23:51:36.734178 5f0f17e33f1917c1/4837/2574 INFO: 2:a00000000000...6325874a97cb: destruction INDEXES_FIND trans: 317, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 15444, started: 2014-09-13 23:51:36.704187, cached status: -2, attrs: []
2014-09-13 23:51:36.734204 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 2:a00000000000...6325874a97cb: processed IO event: 0x2b134c0960e0, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734237 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:000000000000...6325874a97cb: got IO event: 0x2b134c08f250: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734261 5f0f17e33f1917c1/4837/2574 ERROR: 3:000000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 322, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.734314 5f0f17e33f1917c1/4837/2574 INFO: 3:000000000000...6325874a97cb: destruction INDEXES_FIND trans: 322, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 14509, started: 2014-09-13 23:51:36.719799, cached status: -2, attrs: []
2014-09-13 23:51:36.734338 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:000000000000...6325874a97cb: processed IO event: 0x2b134c08f250, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734361 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:100000000000...6325874a97cb: got IO event: 0x2b134c09b080: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734384 5f0f17e33f1917c1/4837/2574 ERROR: 3:100000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 323, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.734428 5f0f17e33f1917c1/4837/2574 INFO: 3:100000000000...6325874a97cb: destruction INDEXES_FIND trans: 323, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 13325, started: 2014-09-13 23:51:36.721098, cached status: -2, attrs: []
2014-09-13 23:51:36.734452 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:100000000000...6325874a97cb: processed IO event: 0x2b134c09b080, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734475 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:200000000000...6325874a97cb: got IO event: 0x2b134c08f930: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734497 5f0f17e33f1917c1/4837/2574 ERROR: 3:200000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 324, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.734541 5f0f17e33f1917c1/4837/2574 INFO: 3:200000000000...6325874a97cb: destruction INDEXES_FIND trans: 324, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 12098, started: 2014-09-13 23:51:36.722438, cached status: -2, attrs: []
2014-09-13 23:51:36.734565 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:200000000000...6325874a97cb: processed IO event: 0x2b134c08f930, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734588 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:600000000000...6325874a97cb: got IO event: 0x2b134c0a0040: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734626 5f0f17e33f1917c1/4837/2574 ERROR: 3:600000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 325, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.734683 5f0f17e33f1917c1/4837/2574 INFO: 3:600000000000...6325874a97cb: destruction INDEXES_FIND trans: 325, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 9207, started: 2014-09-13 23:51:36.725461, cached status: -2, attrs: []
2014-09-13 23:51:36.734709 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:600000000000...6325874a97cb: processed IO event: 0x2b134c0a0040, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734733 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:700000000000...6325874a97cb: got IO event: 0x2b134c099cc0: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734755 5f0f17e33f1917c1/4837/2574 ERROR: 3:700000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 326, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.734800 5f0f17e33f1917c1/4837/2574 INFO: 3:700000000000...6325874a97cb: destruction INDEXES_FIND trans: 326, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 8041, started: 2014-09-13 23:51:36.726753, cached status: -2, attrs: []
2014-09-13 23:51:36.734829 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:700000000000...6325874a97cb: processed IO event: 0x2b134c099cc0, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734853 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 3:a00000000000...6325874a97cb: got IO event: 0x2b134c095f00: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734875 5f0f17e33f1917c1/4837/2574 ERROR: 3:a00000000000...6325874a97cb: handled reply from: 127.0.0.1:25109, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 327, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.734918 5f0f17e33f1917c1/4837/2574 INFO: 3:a00000000000...6325874a97cb: destruction INDEXES_FIND trans: 327, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 6399, started: 2014-09-13 23:51:36.728514, cached status: -2, attrs: []
2014-09-13 23:51:36.734942 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 3:a00000000000...6325874a97cb: processed IO event: 0x2b134c095f00, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.734965 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:b00000000000...6325874a97cb: got IO event: 0x2b134c09c090: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.734987 5f0f17e33f1917c1/4837/2574 ERROR: 3:b00000000000...6325874a97cb: handled reply from: 127.0.0.1:61038, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 328, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.735030 5f0f17e33f1917c1/4837/2574 INFO: 3:b00000000000...6325874a97cb: destruction INDEXES_FIND trans: 328, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 5172, started: 2014-09-13 23:51:36.729853, cached status: -2, attrs: []
2014-09-13 23:51:36.735054 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:b00000000000...6325874a97cb: processed IO event: 0x2b134c09c090, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.735077 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:e00000000000...6325874a97cb: got IO event: 0x2b134c09bd60: cmd: INDEXES_FIND, hsize: 120, dsize: 0, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.735099 5f0f17e33f1917c1/4837/2574 ERROR: 3:e00000000000...6325874a97cb: handled reply from: 127.0.0.1:25963, cmd: INDEXES_FIND, flags: 0x200 [reply], trans: 329, status: -2, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.735401 5f0f17e33f1917c1/2574/2574 INFO: 1:407ec9557436...32b969962349: alloc/send LOOKUP trans: 330 -> 127.0.0.1:25109, weight: 1.000000., attrs: []
2014-09-13 23:51:36.735432 5f0f17e33f1917c1/2574/2574 NOTICE: 1:407ec9557436...32b969962349: added transaction: 330 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:36.735461 5f0f17e33f1917c1/2574/2574 INFO: 2:407ec9557436...32b969962349: alloc/send LOOKUP trans: 331 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:36.735484 5f0f17e33f1917c1/2574/2574 NOTICE: 2:407ec9557436...32b969962349: added transaction: 331 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.735507 5f0f17e33f1917c1/2574/2574 INFO: 3:407ec9557436...32b969962349: alloc/send LOOKUP trans: 332 -> 127.0.0.1:61038, weight: 0.529620., attrs: []
2014-09-13 23:51:36.735530 5f0f17e33f1917c1/2574/2574 NOTICE: 3:407ec9557436...32b969962349: added transaction: 332 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:36.735557 0000000000000000/2574/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.735579 0000000000000000/2574/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.735635 0000000000000000/4839/2574 DEBUG: 3:407ec9557436...32b969962349: LOOKUP: sending -> 127.0.0.1:61038: trans: 332, size: 0, cflags: 0x1 [need_ack], start-sent: 0/120., attrs: []
2014-09-13 23:51:36.735688 0000000000000000/4839/2574 DEBUG: 3:407ec9557436...32b969962349: LOOKUP: sending -> 127.0.0.1:61038: trans: 332, size: 0, cflags: 0x1 [need_ack], finish-sent: 120/120., attrs: []
2014-09-13 23:51:36.735723 0000000000000000/4839/2574 DEBUG: 1:407ec9557436...32b969962349: LOOKUP: sending -> 127.0.0.1:25109: trans: 330, size: 0, cflags: 0x1 [need_ack], start-sent: 0/120., attrs: []
2014-09-13 23:51:36.735756 0000000000000000/4839/2574 DEBUG: 1:407ec9557436...32b969962349: LOOKUP: sending -> 127.0.0.1:25109: trans: 330, size: 0, cflags: 0x1 [need_ack], finish-sent: 120/120., attrs: []
2014-09-13 23:51:36.735782 0000000000000000/4839/2574 DEBUG: 2:407ec9557436...32b969962349: LOOKUP: sending -> 127.0.0.1:25963: trans: 331, size: 0, cflags: 0x1 [need_ack], start-sent: 0/120., attrs: []
2014-09-13 23:51:36.735813 0000000000000000/4839/2574 DEBUG: 2:407ec9557436...32b969962349: LOOKUP: sending -> 127.0.0.1:25963: trans: 331, size: 0, cflags: 0x1 [need_ack], finish-sent: 120/120., attrs: []
2014-09-13 23:51:36.738168 0000000000000000/4839/2574 DEBUG: 3:407ec9557436...32b969962349: received trans: 332 / 0x14c, reply: 1, size: 145, flags: 0x200 [reply], status: 0., attrs: []
2014-09-13 23:51:36.738197 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:407ec9557436...32b969962349: RECV cmd: LOOKUP: cmd-size: 145, nonblocking: 0, attrs: []
2014-09-13 23:51:36.738219 0000000000000000/4839/2574 DEBUG: 127.0.0.1:61038: 3:407ec9557436...32b969962349: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 7, attrs: []
2014-09-13 23:51:36.738244 0000000000000000/4839/2574 DEBUG: 2:407ec9557436...32b969962349: received trans: 331 / 0x14b, reply: 1, size: 145, flags: 0x200 [reply], status: 0., attrs: []
2014-09-13 23:51:36.738266 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: RECV cmd: LOOKUP: cmd-size: 145, nonblocking: 0, attrs: []
2014-09-13 23:51:36.738287 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 3, attrs: []
2014-09-13 23:51:36.738310 0000000000000000/4839/2574 DEBUG: 1:407ec9557436...32b969962349: received trans: 330 / 0x14a, reply: 1, size: 145, flags: 0x200 [reply], status: 0., attrs: []
2014-09-13 23:51:36.738332 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:407ec9557436...32b969962349: RECV cmd: LOOKUP: cmd-size: 145, nonblocking: 0, attrs: []
2014-09-13 23:51:36.738352 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25109: 1:407ec9557436...32b969962349: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 2, attrs: []
2014-09-13 23:51:36.738390 5f0f17e33f1917c1/4837/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.738438 5f0f17e33f1917c1/4837/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.738462 5f0f17e33f1917c1/4837/2574 INFO: 3:e00000000000...6325874a97cb: destruction INDEXES_FIND trans: 329, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 2755, started: 2014-09-13 23:51:36.732382, cached status: -2, attrs: []
2014-09-13 23:51:36.738486 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 3:e00000000000...6325874a97cb: processed IO event: 0x2b134c09bd60, cmd: INDEXES_FIND, attrs: []
2014-09-13 23:51:36.738510 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:407ec9557436...32b969962349: got IO event: 0x2b134c09a620: cmd: LOOKUP, hsize: 120, dsize: 145, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.738534 5f0f17e33f1917c1/4837/2574 NOTICE: 3:407ec9557436...32b969962349: handled reply from: 127.0.0.1:61038, cmd: LOOKUP, flags: 0x200 [reply], trans: 332, status: 0, size: 145, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.738578 5f0f17e33f1917c1/4837/2574 INFO: 3:407ec9557436...32b969962349: destruction LOOKUP trans: 332, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 3069, started: 2014-09-13 23:51:36.735505, cached status: 0, attrs: []
2014-09-13 23:51:36.738602 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:61038: 3:407ec9557436...32b969962349: processed IO event: 0x2b134c09a620, cmd: LOOKUP, attrs: []
2014-09-13 23:51:36.738643 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: got IO event: 0x2b134c095c40: cmd: LOOKUP, hsize: 120, dsize: 145, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.738681 5f0f17e33f1917c1/4837/2574 NOTICE: 2:407ec9557436...32b969962349: handled reply from: 127.0.0.1:25963, cmd: LOOKUP, flags: 0x200 [reply], trans: 331, status: 0, size: 145, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.738723 5f0f17e33f1917c1/4837/2574 INFO: 2:407ec9557436...32b969962349: destruction LOOKUP trans: 331, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 3260, started: 2014-09-13 23:51:36.735458, cached status: 0, attrs: []
2014-09-13 23:51:36.738746 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: processed IO event: 0x2b134c095c40, cmd: LOOKUP, attrs: []
2014-09-13 23:51:36.738770 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:407ec9557436...32b969962349: got IO event: 0x2b134c09f6b0: cmd: LOOKUP, hsize: 120, dsize: 145, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.738792 5f0f17e33f1917c1/4837/2574 NOTICE: 1:407ec9557436...32b969962349: handled reply from: 127.0.0.1:25109, cmd: LOOKUP, flags: 0x200 [reply], trans: 330, status: 0, size: 145, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.738854 5f0f17e33f1917c1/4837/2574 INFO: 2:407ec9557436...32b969962349: created trans: 333, cmd: READ, cflags: 0x1 [need_ack], size: 0, offset: 0, fd: -1, local_offset: 0 -> 127.0.0.1:25963 weight: 1.000000, wait-ts: 5., attrs: []
2014-09-13 23:51:36.738882 5f0f17e33f1917c1/4837/2574 NOTICE: 2:407ec9557436...32b969962349: added transaction: 333 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:36.738915 0000000000000000/4839/2574 DEBUG: 2:407ec9557436...32b969962349: READ: sending -> 127.0.0.1:25963: trans: 333, size: 208, cflags: 0x1 [need_ack], start-sent: 0/328., attrs: []
2014-09-13 23:51:36.738941 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: SENT blocking cmd: READ: cmd-size: 208, nonblocking: 0, attrs: []
2014-09-13 23:51:36.738961 0000000000000000/4839/2574 DEBUG: 2:407ec9557436...32b969962349: READ: sending -> 127.0.0.1:25963: trans: 333, size: 208, cflags: 0x1 [need_ack], finish-sent: 328/328., attrs: []
2014-09-13 23:51:36.739376 0000000000000000/4839/2574 DEBUG: 2:407ec9557436...32b969962349: received trans: 333 / 0x14d, reply: 1, size: 796, flags: 0x200 [reply], status: 0., attrs: []
2014-09-13 23:51:36.739402 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: RECV cmd: READ: cmd-size: 796, nonblocking: 0, attrs: []
2014-09-13 23:51:36.739423 0000000000000000/4839/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: backend_id: -1, place: 0x1c52640, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 3, attrs: []
2014-09-13 23:51:36.739461 5f0f17e33f1917c1/4837/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.739484 5f0f17e33f1917c1/4837/2574 INFO: 1:407ec9557436...32b969962349: destruction LOOKUP trans: 330, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 3430, started: 2014-09-13 23:51:36.735396, cached status: 0, attrs: []
2014-09-13 23:51:36.739507 5f0f17e33f1917c1/4837/2574 DEBUG: 127.0.0.1:25109: 1:407ec9557436...32b969962349: processed IO event: 0x2b134c09f6b0, cmd: LOOKUP, attrs: []
2014-09-13 23:51:36.739531 0000000000000000/4837/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: got IO event: 0x2b134c09fd30: cmd: READ, hsize: 120, dsize: 796, mode: BLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:36.739554 0000000000000000/4837/2574 NOTICE: 2:407ec9557436...32b969962349: handled reply from: 127.0.0.1:25963, cmd: READ, flags: 0x200 [reply], trans: 333, status: 0, size: 796, client: 0, last: 1, attrs: []
2014-09-13 23:51:36.739756 0000000000000000/2574/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.741207 0000000000000000/2574/2574 DEBUG: Destroying node., attrs: []
2014-09-13 23:51:36.741720 0000000000000000/4837/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.741772 0000000000000000/4837/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:36.741818 0000000000000000/4837/2574 INFO: 2:407ec9557436...32b969962349: destruction READ trans: 333, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 742, started: 2014-09-13 23:51:36.738850, cached status: 0, ioflags: 0x0 [], io-offset: 0, io-size: 588/588, io-user-flags: 0x0, ts: 1410652296.486117 '2014-09-13 23:51:36.486117', weight: 1.000000 -> 0.884211, attrs: []
2014-09-13 23:51:36.741846 0000000000000000/4837/2574 DEBUG: 127.0.0.1:25963: 2:407ec9557436...32b969962349: processed IO event: 0x2b134c09fd30, cmd: READ, attrs: []
2014-09-13 23:51:36.741869 0000000000000000/4837/2574 NOTICE: finished io thread: #0, nonblocking: 0, backend: -1, attrs: []
2014-09-13 23:51:37.495814 0000000000000000/4838/2574 NOTICE: finished io thread: #0, nonblocking: 1, backend: -1, attrs: []
2014-09-13 23:51:37.496034 0000000000000000/2574/2574 NOTICE: Checking thread stopped., attrs: []
2014-09-13 23:51:37.740648 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25109: resetting state: Structure needs cleaning [-117], attrs: []
2014-09-13 23:51:37.740794 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.740822 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.740847 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.740869 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.740889 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.740910 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.740931 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.740951 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.740971 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.741450 0000000000000000/2574/2574 NOTICE: Cleaned state 127.0.0.1:25109, transactions freed: 0, attrs: []
2014-09-13 23:51:37.741474 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25963: resetting state: Structure needs cleaning [-117], attrs: []
2014-09-13 23:51:37.741497 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.741518 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:37.741538 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:37.741559 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.741579 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:37.741598 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:37.741642 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.741665 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:37.741685 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:37.742039 0000000000000000/2574/2574 NOTICE: Cleaned state 127.0.0.1:25963, transactions freed: 0, attrs: []
2014-09-13 23:51:37.742062 0000000000000000/2574/2574 NOTICE: 127.0.0.1:61038: resetting state: Structure needs cleaning [-117], attrs: []
2014-09-13 23:51:37.742083 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 6d1bf2bc1cfa -> host: 127.0.0.1:61038, backend: 0, attrs: []
2014-09-13 23:51:37.742104 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: f2f6cb4b7d83 -> host: 127.0.0.1:61038, backend: 1, attrs: []
2014-09-13 23:51:37.742123 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 1, id: 13f8734ed140 -> host: 127.0.0.1:61038, backend: 2, attrs: []
2014-09-13 23:51:37.742144 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: df05992a9d9d -> host: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:37.742163 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: efb845c847c5 -> host: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:37.742183 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 2, id: 8472c925fdb7 -> host: 127.0.0.1:61038, backend: 5, attrs: []
2014-09-13 23:51:37.742203 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: 00af302022bc -> host: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:37.742223 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: 10fa4a35cef2 -> host: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:37.742242 0000000000000000/2574/2574 DEBUG: dnet_idc_remove: group: 3, id: a40a7c0badfa -> host: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:37.742604 0000000000000000/2574/2574 NOTICE: Cleaned state 127.0.0.1:61038, transactions freed: 0, attrs: []
2014-09-13 23:51:37.742644 0000000000000000/2574/2574 NOTICE: self: addr: no address, closing socket: 93, attrs: []
2014-09-13 23:51:37.742664 0000000000000000/2574/2574 NOTICE: self: addr: no address, closing socket: 91, attrs: []
2014-09-13 23:51:37.742688 0000000000000000/2574/2574 NOTICE: Cleaned state 127.0.0.1:61038, transactions freed: 0, attrs: []
2014-09-13 23:51:37.742708 0000000000000000/2574/2574 NOTICE: Freeing state 127.0.0.1:61038, socket: 93/91, addr-num: 1., attrs: []
2014-09-13 23:51:37.745994 0000000000000000/2574/2574 INFO: Elliptics starts, flags: 0x0 [], attrs: []
2014-09-13 23:51:37.746110 0000000000000000/2574/2574 NOTICE: Using default stall count (3 transactions)., attrs: []
2014-09-13 23:51:37.746131 0000000000000000/2574/2574 NOTICE: Using default indexes shard count (16 shards)., attrs: []
2014-09-13 23:51:37.746198 0000000000000000/2574/2574 INFO: Grew BLOCKING pool by: 0 -> 1 IO threads, attrs: []
2014-09-13 23:51:37.746233 0000000000000000/2574/2574 INFO: Grew NONBLOCKING pool by: 0 -> 1 IO threads, attrs: []
2014-09-13 23:51:37.746289 0000000000000000/2574/2574 DEBUG: New node has been created., attrs: []
2014-09-13 23:51:37.746448 0000000000000000/2574/2574 INFO: Added 127.0.0.1:25109 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.746498 0000000000000000/2574/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:25109 created successfully, socket: 80, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.746536 0000000000000000/2574/2574 INFO: Added 127.0.0.1:25963 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.746563 0000000000000000/2574/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:25963 created successfully, socket: 81, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.746594 0000000000000000/2574/2574 INFO: Added 127.0.0.1:61038 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.746641 0000000000000000/2574/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:61038 created successfully, socket: 82, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.746670 0000000000000000/2574/2574 INFO: Trying to connect to 3 states of 3 original, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.747350 0000000000000000/2574/2574 DEBUG: 127.0.0.1:25109: socket: 80, state: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.747412 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25109: successfully connected, sending reverse lookup command, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748144 0000000000000000/2574/2574 INFO: 127.0.0.1:25109: reverse lookup command: network version: 2.26.3.22, local version: 2.26.3.22, attrs: []
2014-09-13 23:51:37.748174 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25109: received indexes shard count: local: 16, remote: 16, using server one, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748210 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 0/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 0, group_id: 1, id: 917369109dce., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748251 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 1/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 1, group_id: 1, id: 81b5b074dc3a., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748287 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 2/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 2, group_id: 1, id: 33a2eb582db6., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748321 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 3/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 3, group_id: 2, id: b2ec8778ec01., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748355 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 4/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 4, group_id: 2, id: 4b383322210a., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748397 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 5/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 5, group_id: 2, id: 8e511caa41f5., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748434 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 6/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 6, group_id: 3, id: a0ad2f9c12d9., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748468 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 7/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 7, group_id: 3, id: 8726a8dcdabd., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748502 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25109: received backends: 8/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 8, group_id: 3, id: 8c17efa728bf., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.748543 0000000000000000/2574/2574 DEBUG: dnet_state_create: 127.0.0.1:25109: sockets: 80/90, attrs: []
2014-09-13 23:51:37.748576 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.748598 0000000000000000/2574/2574 NOTICE: Initialized group: 1, total ids: 1, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 0, idc: 0x1d9c400, time-took: 32 usecs., attrs: []
2014-09-13 23:51:37.748646 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.748666 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.748685 0000000000000000/2574/2574 NOTICE: Initialized group: 1, total ids: 2, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 1, idc: 0x1d00cc0, time-took: 49 usecs., attrs: []
2014-09-13 23:51:37.748712 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.748732 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.748751 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.748778 0000000000000000/2574/2574 NOTICE: Initialized group: 1, total ids: 3, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 2, idc: 0x20d49c0, time-took: 72 usecs., attrs: []
2014-09-13 23:51:37.748804 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.748824 0000000000000000/2574/2574 NOTICE: Initialized group: 2, total ids: 1, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 3, idc: 0x1ce83d0, time-took: 25 usecs., attrs: []
2014-09-13 23:51:37.748850 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.748870 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.748890 0000000000000000/2574/2574 NOTICE: Initialized group: 2, total ids: 2, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 4, idc: 0x17d0660, time-took: 46 usecs., attrs: []
2014-09-13 23:51:37.748916 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.748936 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.748955 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.748974 0000000000000000/2574/2574 NOTICE: Initialized group: 2, total ids: 3, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 5, idc: 0x175c0a0, time-took: 64 usecs., attrs: []
2014-09-13 23:51:37.749715 0000000000000000/4846/2574 INFO: Started reconnection thread. Timeout: 20 seconds. Route table update every 20 seconds., attrs: []
2014-09-13 23:51:37.749763 0000000000000000/4846/2574 INFO: Started reconnection process, attrs: []
2014-09-13 23:51:37.749830 0000000000000000/4844/2574 NOTICE: started net pool, attrs: []
2014-09-13 23:51:37.749873 0000000000000000/4843/2574 NOTICE: started io thread: #0, nonblocking: 1, backend: -1, attrs: []
2014-09-13 23:51:37.749912 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.749956 0000000000000000/4846/2574 INFO: Requesting route address from 0 remote addresses, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.750010 0000000000000000/4846/2574 DEBUG: 0:000000000000...000000000000: list route request to 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750034 0000000000000000/4846/2574 NOTICE: 0:000000000000...000000000000: added transaction: 1 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750059 0000000000000000/4846/2574 ERROR: 127.0.0.1:25109: failed to add SEND event, fd: 90: Bad file descriptor [-9], attrs: []
2014-09-13 23:51:37.750086 0000000000000000/4846/2574 NOTICE: Sent route-list request to state: 127.0.0.1:25109, route_list_count: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.750116 0000000000000000/4846/2574 DEBUG: 0:000000000000...000000000000: list route request to 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750137 0000000000000000/4846/2574 NOTICE: 0:000000000000...000000000000: added transaction: 2 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750159 0000000000000000/4846/2574 ERROR: 127.0.0.1:25109: failed to add SEND event, fd: 90: Bad file descriptor [-9], attrs: []
2014-09-13 23:51:37.750180 0000000000000000/4846/2574 NOTICE: Sent route-list request to state: 127.0.0.1:25109, route_list_count: 2, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.750207 0000000000000000/4846/2574 DEBUG: 0:000000000000...000000000000: list route request to 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750228 0000000000000000/4846/2574 NOTICE: 0:000000000000...000000000000: added transaction: 3 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750261 0000000000000000/4846/2574 ERROR: 127.0.0.1:25109: failed to add SEND event, fd: 90: Bad file descriptor [-9], attrs: []
2014-09-13 23:51:37.750282 0000000000000000/4846/2574 NOTICE: Sent route-list request to state: 127.0.0.1:25109, route_list_count: 3, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.750319 0000000000000000/2574/2574 NOTICE: Initialized group: 3, total ids: 1, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 6, idc: 0x175c130, time-took: 710 usecs., attrs: []
2014-09-13 23:51:37.750349 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.750369 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.750401 0000000000000000/2574/2574 NOTICE: Initialized group: 3, total ids: 2, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 7, idc: 0x1ce9790, time-took: 48 usecs., attrs: []
2014-09-13 23:51:37.750431 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.750450 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.750470 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.750489 0000000000000000/2574/2574 NOTICE: Initialized group: 3, total ids: 3, added ids: 1, received ids: 1, state: 127.0.0.1:25109, backend: 8, idc: 0x1ceeea0, time-took: 66 usecs., attrs: []
2014-09-13 23:51:37.750515 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25109: copy addr: 127.0.0.1:25109, idx: 0, attrs: []
2014-09-13 23:51:37.750535 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25109: connected: backends-num: 9, addr-num: 1, idx: 0., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.750567 0000000000000000/2574/2574 INFO: Connected to 127.0.0.1:25109, socket: -1., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.750595 0000000000000000/2574/2574 DEBUG: 0:000000000000...000000000000: list route request to 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750634 0000000000000000/2574/2574 NOTICE: 0:000000000000...000000000000: added transaction: 4 -> 127.0.0.1:25109., attrs: []
2014-09-13 23:51:37.750682 0000000000000000/4842/2574 NOTICE: started io thread: #0, nonblocking: 0, backend: -1, attrs: []
2014-09-13 23:51:37.750718 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 1, size: 0, cflags: 0x19 [need_ack|direct|nolock], start-sent: 0/120., attrs: []
2014-09-13 23:51:37.751360 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 1, size: 0, cflags: 0x19 [need_ack|direct|nolock], finish-sent: 120/120., attrs: []
2014-09-13 23:51:37.751404 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 2, size: 0, cflags: 0x19 [need_ack|direct|nolock], start-sent: 0/120., attrs: []
2014-09-13 23:51:37.751911 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 2, size: 0, cflags: 0x19 [need_ack|direct|nolock], finish-sent: 120/120., attrs: []
2014-09-13 23:51:37.751944 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 3, size: 0, cflags: 0x19 [need_ack|direct|nolock], start-sent: 0/120., attrs: []
2014-09-13 23:51:37.752431 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 3, size: 0, cflags: 0x19 [need_ack|direct|nolock], finish-sent: 120/120., attrs: []
2014-09-13 23:51:37.752463 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 4, size: 0, cflags: 0x19 [need_ack|direct|nolock], start-sent: 0/120., attrs: []
2014-09-13 23:51:37.752509 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:25109: trans: 4, size: 0, cflags: 0x19 [need_ack|direct|nolock], finish-sent: 120/120., attrs: []
2014-09-13 23:51:37.752542 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 1 / 0x1, reply: 1, size: 104, flags: 0x210 [nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.752570 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: RECV cmd: ROUTE_LIST: cmd-size: 104, nonblocking: 1, attrs: []
2014-09-13 23:51:37.752595 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:37.752646 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 2 / 0x2, reply: 1, size: 104, flags: 0x210 [nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.752674 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: RECV cmd: ROUTE_LIST: cmd-size: 104, nonblocking: 1, attrs: []
2014-09-13 23:51:37.752699 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:37.752727 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 3 / 0x3, reply: 1, size: 104, flags: 0x210 [nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.752753 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: RECV cmd: ROUTE_LIST: cmd-size: 104, nonblocking: 1, attrs: []
2014-09-13 23:51:37.752777 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:37.753244 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: got IO event: 0x2b135025b0b0: cmd: ROUTE_LIST, hsize: 120, dsize: 104, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.753282 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 0, addr: 127.0.0.1:25109, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753325 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 1, addr: 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753359 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 2, addr: 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753403 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25109 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753448 0000000000000000/4843/2574 INFO: Added 127.0.0.1:25963 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753479 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:25963 created successfully, socket: 93, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753515 0000000000000000/4843/2574 INFO: Added 127.0.0.1:61038 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753544 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:61038 created successfully, socket: 94, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753579 0000000000000000/4843/2574 INFO: Trying to connect to additional 2 states of 3 original from route_list_recv, state: 127.0.0.1:25109, route_list_count: 4, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753636 0000000000000000/4843/2574 NOTICE: Received route-list reply from state: 127.0.0.1:25109, route_list_count: 3, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753675 0000000000000000/4843/2574 INFO: 0:000000000000...000000000000: destruction ROUTE_LIST trans: 1, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 3624, started: 2014-09-13 23:51:37.750005, cached status: 0, attrs: []
2014-09-13 23:51:37.753714 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: processed IO event: 0x2b135025b0b0, cmd: ROUTE_LIST, attrs: []
2014-09-13 23:51:37.753740 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: got IO event: 0x2b1350269a60: cmd: ROUTE_LIST, hsize: 120, dsize: 104, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.753767 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 0, addr: 127.0.0.1:25109, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753798 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 1, addr: 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753827 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 2, addr: 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753858 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25109 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753890 0000000000000000/4843/2574 INFO: Added 127.0.0.1:25963 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753919 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:25963 created successfully, socket: 95, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753953 0000000000000000/4843/2574 INFO: Added 127.0.0.1:61038 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.753980 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:61038 created successfully, socket: 96, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754009 0000000000000000/4843/2574 INFO: Trying to connect to additional 2 states of 3 original from route_list_recv, state: 127.0.0.1:25109, route_list_count: 4, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754045 0000000000000000/4843/2574 NOTICE: Received route-list reply from state: 127.0.0.1:25109, route_list_count: 3, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754075 0000000000000000/4843/2574 INFO: 0:000000000000...000000000000: destruction ROUTE_LIST trans: 2, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 3927, started: 2014-09-13 23:51:37.750113, cached status: 0, attrs: []
2014-09-13 23:51:37.754099 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: processed IO event: 0x2b1350269a60, cmd: ROUTE_LIST, attrs: []
2014-09-13 23:51:37.754125 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: got IO event: 0x2b1350269ba0: cmd: ROUTE_LIST, hsize: 120, dsize: 104, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.754150 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 0, addr: 127.0.0.1:25109, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754179 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 1, addr: 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754207 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 2, addr: 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754236 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25109 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754273 0000000000000000/4843/2574 INFO: Added 127.0.0.1:25963 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754301 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:25963 created successfully, socket: 97, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754334 0000000000000000/4843/2574 INFO: Added 127.0.0.1:61038 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754362 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:61038 created successfully, socket: 98, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754406 0000000000000000/4843/2574 INFO: Trying to connect to additional 2 states of 3 original from route_list_recv, state: 127.0.0.1:25109, route_list_count: 4, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754442 0000000000000000/4843/2574 NOTICE: Received route-list reply from state: 127.0.0.1:25109, route_list_count: 3, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754471 0000000000000000/4843/2574 INFO: 0:000000000000...000000000000: destruction ROUTE_LIST trans: 3, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 4233, started: 2014-09-13 23:51:37.750204, cached status: 0, attrs: []
2014-09-13 23:51:37.754497 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: processed IO event: 0x2b1350269ba0, cmd: ROUTE_LIST, attrs: []
2014-09-13 23:51:37.754530 0000000000000000/4846/2574 NOTICE: Caught signal from interruptfd, list: 0, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.754980 0000000000000000/4846/2574 NOTICE: Received route-list reply, count: 2, route_list_count: 2, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755011 0000000000000000/4846/2574 NOTICE: we are already connecting to 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755052 0000000000000000/4846/2574 NOTICE: we are already connecting to 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755080 0000000000000000/4846/2574 NOTICE: Received route-list reply, count: 2, route_list_count: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755105 0000000000000000/4846/2574 NOTICE: we are already connecting to 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755132 0000000000000000/4846/2574 NOTICE: we are already connecting to 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755159 0000000000000000/4846/2574 NOTICE: Received route-list reply, count: 2, route_list_count: 0, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755185 0000000000000000/4846/2574 DEBUG: 127.0.0.1:25963: socket: 97, state: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755213 0000000000000000/4846/2574 NOTICE: 127.0.0.1:25963: successfully connected, sending reverse lookup command, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.755895 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 4 / 0x4, reply: 1, size: 104, flags: 0x210 [nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.755927 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: RECV cmd: ROUTE_LIST: cmd-size: 104, nonblocking: 1, attrs: []
2014-09-13 23:51:37.755950 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:37.755995 0000000000000000/4846/2574 INFO: 127.0.0.1:25963: reverse lookup command: network version: 2.26.3.22, local version: 2.26.3.22, attrs: []
2014-09-13 23:51:37.756018 0000000000000000/4846/2574 NOTICE: 127.0.0.1:25963: received indexes shard count: local: 16, remote: 16, using server one, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756051 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 0/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 0, group_id: 1, id: 4859da5cadaf., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756089 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 1/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 1, group_id: 1, id: e6e2f314aa15., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756125 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 2/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 2, group_id: 1, id: 0d774c456533., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756159 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 3/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 3, group_id: 2, id: 2b4fac283dcb., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756201 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 4/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 4, group_id: 2, id: 7ccb21ecc15f., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756235 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 5/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 5, group_id: 2, id: a3a1496ea10a., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756268 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 6/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 6, group_id: 3, id: d5b360e9e4fc., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756302 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 7/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 7, group_id: 3, id: 6ab0d1e6a637., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756335 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 8/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 8, group_id: 3, id: 52f013a30d89., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.756372 0000000000000000/4846/2574 DEBUG: dnet_state_create: 127.0.0.1:25963: sockets: 97/93, attrs: []
2014-09-13 23:51:37.756412 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.756433 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.756453 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.756471 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.756490 0000000000000000/4846/2574 NOTICE: Initialized group: 1, total ids: 4, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 0, idc: 0x2b134c08f800, time-took: 90 usecs., attrs: []
2014-09-13 23:51:37.756518 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.756538 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.756558 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.756577 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.756597 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:37.756634 0000000000000000/4846/2574 NOTICE: Initialized group: 1, total ids: 5, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 1, idc: 0x2b134c08f930, time-took: 122 usecs., attrs: []
2014-09-13 23:51:37.756663 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:37.756683 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.756703 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.756722 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.756741 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.756760 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:37.756786 0000000000000000/4846/2574 NOTICE: Initialized group: 1, total ids: 6, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 2, idc: 0x2b134c08f250, time-took: 130 usecs., attrs: []
2014-09-13 23:51:37.756814 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.756834 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.756853 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.756873 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.756892 0000000000000000/4846/2574 NOTICE: Initialized group: 2, total ids: 4, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 3, idc: 0x2b134c08f3b0, time-took: 86 usecs., attrs: []
2014-09-13 23:51:37.756919 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.756938 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.756957 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:37.756976 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.756996 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.757015 0000000000000000/4846/2574 NOTICE: Initialized group: 2, total ids: 5, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 4, idc: 0x2b134c08f440, time-took: 102 usecs., attrs: []
2014-09-13 23:51:37.757042 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.757062 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.757081 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:37.757101 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.757120 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:37.757139 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.757158 0000000000000000/4846/2574 NOTICE: Initialized group: 2, total ids: 6, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 5, idc: 0x2b134c094960, time-took: 124 usecs., attrs: []
2014-09-13 23:51:37.757186 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.757206 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.757225 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.757244 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.757264 0000000000000000/4846/2574 NOTICE: Initialized group: 3, total ids: 4, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 6, idc: 0x2b134c0949f0, time-took: 85 usecs., attrs: []
2014-09-13 23:51:37.757298 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:37.757318 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.757338 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.757357 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.757376 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.757405 0000000000000000/4846/2574 NOTICE: Initialized group: 3, total ids: 5, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 7, idc: 0x2b134c094a80, time-took: 113 usecs., attrs: []
2014-09-13 23:51:37.757434 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:37.757453 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:37.757473 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.757492 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.757511 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.757530 0000000000000000/4846/2574 DEBUG: dnet_idc_update: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.757550 0000000000000000/4846/2574 NOTICE: Initialized group: 3, total ids: 6, added ids: 1, received ids: 1, state: 127.0.0.1:25963, backend: 8, idc: 0x2b134c094b10, time-took: 123 usecs., attrs: []
2014-09-13 23:51:37.757575 0000000000000000/4846/2574 NOTICE: 127.0.0.1:25963: copy addr: 127.0.0.1:25963, idx: 0, attrs: []
2014-09-13 23:51:37.757595 0000000000000000/4846/2574 NOTICE: 127.0.0.1:25963: connected: backends-num: 9, addr-num: 1, idx: 0., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.757646 0000000000000000/4846/2574 INFO: Connected to 127.0.0.1:25963, socket: -1., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.757673 0000000000000000/4846/2574 DEBUG: 127.0.0.1:61038: socket: 98, state: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.757701 0000000000000000/4846/2574 NOTICE: 127.0.0.1:61038: successfully connected, sending reverse lookup command, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.758357 0000000000000000/2574/2574 NOTICE: Sent route-list request to state: 127.0.0.1:25109, route_list_count: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.758403 0000000000000000/2574/2574 DEBUG: 127.0.0.1:25963: socket: 81, state: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.758434 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25963: successfully connected, sending reverse lookup command, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.758928 0000000000000000/2574/2574 INFO: 127.0.0.1:25963: reverse lookup command: network version: 2.26.3.22, local version: 2.26.3.22, attrs: []
2014-09-13 23:51:37.758954 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25963: received indexes shard count: local: 16, remote: 16, using server one, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.758986 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 0/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 0, group_id: 1, id: 4859da5cadaf., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759026 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 1/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 1, group_id: 1, id: e6e2f314aa15., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759067 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 2/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 2, group_id: 1, id: 0d774c456533., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759101 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 3/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 3, group_id: 2, id: 2b4fac283dcb., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759135 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 4/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 4, group_id: 2, id: 7ccb21ecc15f., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759169 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 5/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 5, group_id: 2, id: a3a1496ea10a., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759203 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 6/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 6, group_id: 3, id: d5b360e9e4fc., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759237 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 7/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 7, group_id: 3, id: 6ab0d1e6a637., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759270 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:25963: received backends: 8/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 8, group_id: 3, id: 52f013a30d89., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759306 0000000000000000/2574/2574 NOTICE: self: addr: no address, closing socket: 81, attrs: []
2014-09-13 23:51:37.759625 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: got IO event: 0x2b135025b0b0: cmd: ROUTE_LIST, hsize: 120, dsize: 104, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.759664 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 0, addr: 127.0.0.1:25109, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759696 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 1, addr: 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759726 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:25109, node: 2, addr: 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759757 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25109 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759786 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25963 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759822 0000000000000000/4843/2574 INFO: Added 127.0.0.1:61038 to connect list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759850 0000000000000000/4843/2574 DEBUG: dnet_socket_create_addresses: socket for state 127.0.0.1:61038 created successfully, socket: 94, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759882 0000000000000000/4843/2574 INFO: Trying to connect to additional 1 states of 3 original from route_list_recv, state: 127.0.0.1:25109, route_list_count: 2, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759920 0000000000000000/4843/2574 NOTICE: Received route-list reply from state: 127.0.0.1:25109, route_list_count: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.759953 0000000000000000/4843/2574 INFO: 0:000000000000...000000000000: destruction ROUTE_LIST trans: 4, reply: 1, st: 127.0.0.1:25109, stall: 0, time: 9323, started: 2014-09-13 23:51:37.750592, cached status: 0, attrs: []
2014-09-13 23:51:37.759982 0000000000000000/4843/2574 DEBUG: 127.0.0.1:25109: 0:000000000000...000000000000: processed IO event: 0x2b135025b0b0, cmd: ROUTE_LIST, attrs: []
2014-09-13 23:51:37.760018 0000000000000000/2574/2574 NOTICE: 127.0.0.1:25963: state already exists., attrs: []
2014-09-13 23:51:37.760046 0000000000000000/2574/2574 DEBUG: 127.0.0.1:61038: socket: 82, state: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760076 0000000000000000/2574/2574 NOTICE: 127.0.0.1:61038: successfully connected, sending reverse lookup command, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760529 0000000000000000/2574/2574 INFO: 127.0.0.1:61038: reverse lookup command: network version: 2.26.3.22, local version: 2.26.3.22, attrs: []
2014-09-13 23:51:37.760553 0000000000000000/2574/2574 NOTICE: 127.0.0.1:61038: received indexes shard count: local: 16, remote: 16, using server one, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760585 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 0/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 0, group_id: 1, id: 6d1bf2bc1cfa., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760639 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 1/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 1, group_id: 1, id: f2f6cb4b7d83., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760675 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 2/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 2, group_id: 1, id: 13f8734ed140., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760709 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 3/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 3, group_id: 2, id: df05992a9d9d., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760744 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 4/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 4, group_id: 2, id: efb845c847c5., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760778 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 5/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 5, group_id: 2, id: 8472c925fdb7., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760812 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 6/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 6, group_id: 3, id: 00af302022bc., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760846 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 7/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 7, group_id: 3, id: 10fa4a35cef2., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760880 0000000000000000/2574/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 8/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 8, group_id: 3, id: a40a7c0badfa., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.760924 0000000000000000/2574/2574 DEBUG: dnet_state_create: 127.0.0.1:61038: sockets: 82/81, attrs: []
2014-09-13 23:51:37.760957 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:37.760977 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.760997 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.761017 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 6d1bf2bc1cfa -> host: 127.0.0.1:61038, backend: 0, attrs: []
2014-09-13 23:51:37.761037 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.761056 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.761076 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:37.761095 0000000000000000/2574/2574 NOTICE: Initialized group: 1, total ids: 7, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 0, idc: 0x1c67680, time-took: 148 usecs., attrs: []
2014-09-13 23:51:37.761129 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:37.761148 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.761168 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.761187 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 6d1bf2bc1cfa -> host: 127.0.0.1:61038, backend: 0, attrs: []
2014-09-13 23:51:37.761206 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.761225 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.761243 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:37.761262 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: f2f6cb4b7d83 -> host: 127.0.0.1:61038, backend: 1, attrs: []
2014-09-13 23:51:37.761281 0000000000000000/2574/2574 NOTICE: Initialized group: 1, total ids: 8, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 1, idc: 0x1cde7d0, time-took: 160 usecs., attrs: []
2014-09-13 23:51:37.761309 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:37.761328 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 13f8734ed140 -> host: 127.0.0.1:61038, backend: 2, attrs: []
2014-09-13 23:51:37.761347 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:37.761366 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:37.761385 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 6d1bf2bc1cfa -> host: 127.0.0.1:61038, backend: 0, attrs: []
2014-09-13 23:51:37.761414 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:37.761433 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:37.761452 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:37.761471 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 1, id: f2f6cb4b7d83 -> host: 127.0.0.1:61038, backend: 1, attrs: []
2014-09-13 23:51:37.761490 0000000000000000/2574/2574 NOTICE: Initialized group: 1, total ids: 9, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 2, idc: 0x1b64ff0, time-took: 189 usecs., attrs: []
2014-09-13 23:51:37.761517 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.761536 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.761555 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:37.761574 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.761593 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:37.761627 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.761648 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: df05992a9d9d -> host: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:37.761674 0000000000000000/2574/2574 NOTICE: Initialized group: 2, total ids: 7, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 3, idc: 0x1930290, time-took: 164 usecs., attrs: []
2014-09-13 23:51:37.761702 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.761721 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.761740 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:37.761759 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.761777 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:37.761796 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.761815 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: df05992a9d9d -> host: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:37.761834 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: efb845c847c5 -> host: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:37.761853 0000000000000000/2574/2574 NOTICE: Initialized group: 2, total ids: 8, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 4, idc: 0x1ffa1b0, time-took: 159 usecs., attrs: []
2014-09-13 23:51:37.761881 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:37.761900 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:37.761919 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:37.761938 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 8472c925fdb7 -> host: 127.0.0.1:61038, backend: 5, attrs: []
2014-09-13 23:51:37.761957 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:37.761976 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:37.761994 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:37.762013 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: df05992a9d9d -> host: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:37.762032 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 2, id: efb845c847c5 -> host: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:37.762051 0000000000000000/2574/2574 NOTICE: Initialized group: 2, total ids: 9, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 5, idc: 0x1d078b0, time-took: 178 usecs., attrs: []
2014-09-13 23:51:37.762079 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 00af302022bc -> host: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:37.762098 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:37.762116 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:37.762135 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.762154 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.762180 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.762199 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.762218 0000000000000000/2574/2574 NOTICE: Initialized group: 3, total ids: 7, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 6, idc: 0x164f7f0, time-took: 147 usecs., attrs: []
2014-09-13 23:51:37.762246 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 00af302022bc -> host: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:37.762265 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 10fa4a35cef2 -> host: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:37.762284 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:37.762302 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:37.762321 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.762340 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.762359 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.762378 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.762408 0000000000000000/2574/2574 NOTICE: Initialized group: 3, total ids: 8, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 7, idc: 0x1ce9690, time-took: 171 usecs., attrs: []
2014-09-13 23:51:37.762437 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 00af302022bc -> host: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:37.762456 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 10fa4a35cef2 -> host: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:37.762475 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:37.762494 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:37.762513 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:37.762531 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:37.762550 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:37.762569 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: a40a7c0badfa -> host: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:37.762588 0000000000000000/2574/2574 DEBUG: dnet_idc_update: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:37.762607 0000000000000000/2574/2574 NOTICE: Initialized group: 3, total ids: 9, added ids: 1, received ids: 1, state: 127.0.0.1:61038, backend: 8, idc: 0x1ceef30, time-took: 178 usecs., attrs: []
2014-09-13 23:51:37.762649 0000000000000000/2574/2574 NOTICE: 127.0.0.1:61038: copy addr: 127.0.0.1:61038, idx: 0, attrs: []
2014-09-13 23:51:37.762669 0000000000000000/2574/2574 NOTICE: 127.0.0.1:61038: connected: backends-num: 9, addr-num: 1, idx: 0., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.762704 0000000000000000/2574/2574 INFO: Connected to 127.0.0.1:61038, socket: -1., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.762732 0000000000000000/2574/2574 DEBUG: 0:000000000000...000000000000: list route request to 127.0.0.1:61038., attrs: []
2014-09-13 23:51:37.762759 0000000000000000/2574/2574 NOTICE: 0:000000000000...000000000000: added transaction: 5 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:37.762823 0000000000000000/4846/2574 INFO: 127.0.0.1:61038: reverse lookup command: network version: 2.26.3.22, local version: 2.26.3.22, attrs: []
2014-09-13 23:51:37.762849 0000000000000000/4846/2574 NOTICE: 127.0.0.1:61038: received indexes shard count: local: 16, remote: 16, using server one, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.762881 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 0/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 0, group_id: 1, id: 6d1bf2bc1cfa., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.762918 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 1/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 1, group_id: 1, id: f2f6cb4b7d83., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.762952 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 2/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 2, group_id: 1, id: 13f8734ed140., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.762985 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 3/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 3, group_id: 2, id: df05992a9d9d., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.763019 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 4/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 4, group_id: 2, id: efb845c847c5., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.763052 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 5/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 5, group_id: 2, id: 8472c925fdb7., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.763085 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 6/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 6, group_id: 3, id: 00af302022bc., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.763118 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 7/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 7, group_id: 3, id: 10fa4a35cef2., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.763151 0000000000000000/4846/2574 NOTICE: connected-to-addr: 127.0.0.1:61038: received backends: 8/9, ids: 0/1, addr-num: 1, idx: 0, backend_id: 8, group_id: 3, id: a40a7c0badfa., attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.763185 0000000000000000/4846/2574 NOTICE: self: addr: no address, closing socket: 98, attrs: []
2014-09-13 23:51:37.763499 0000000000000000/4846/2574 NOTICE: 127.0.0.1:61038: state already exists., attrs: []
2014-09-13 23:51:37.763532 0000000000000000/4846/2574 INFO: Finished reconnection process, attrs: []
2014-09-13 23:51:37.763563 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:61038: trans: 5, size: 0, cflags: 0x19 [need_ack|direct|nolock], start-sent: 0/120., attrs: []
2014-09-13 23:51:37.763604 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: ROUTE_LIST: sending -> 127.0.0.1:61038: trans: 5, size: 0, cflags: 0x19 [need_ack|direct|nolock], finish-sent: 120/120., attrs: []
2014-09-13 23:51:37.764139 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 5 / 0x5, reply: 1, size: 104, flags: 0x210 [nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.764165 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: RECV cmd: ROUTE_LIST: cmd-size: 104, nonblocking: 1, attrs: []
2014-09-13 23:51:37.764187 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: 0, attrs: []
2014-09-13 23:51:37.764220 0000000000000000/4843/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: got IO event: 0x2b135025b0b0: cmd: ROUTE_LIST, hsize: 120, dsize: 104, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.764255 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:61038, node: 0, addr: 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764285 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:61038, node: 1, addr: 127.0.0.1:25963, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764313 0000000000000000/4843/2574 DEBUG: route-list: from: 127.0.0.1:61038, node: 2, addr: 127.0.0.1:25109, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764340 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:61038 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764366 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25963 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764398 0000000000000000/4843/2574 NOTICE: Address 127.0.0.1:25109 already exists in route table, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764426 0000000000000000/4843/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: processed IO event: 0x2b135025b0b0, cmd: ROUTE_LIST, attrs: []
2014-09-13 23:51:37.764461 0000000000000000/2574/2574 NOTICE: Received route-list reply from state: 127.0.0.1:61038, route_list_count: 0, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764493 0000000000000000/2574/2574 INFO: 0:000000000000...000000000000: destruction ROUTE_LIST trans: 5, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 1726, started: 2014-09-13 23:51:37.762729, cached status: 0, attrs: []
2014-09-13 23:51:37.764518 0000000000000000/2574/2574 NOTICE: Sent route-list request to state: 127.0.0.1:61038, route_list_count: 1, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764547 0000000000000000/2574/2574 NOTICE: Caught signal from interruptfd, list: 0, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764572 0000000000000000/2574/2574 NOTICE: we are already connecting to 127.0.0.1:61038, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764601 0000000000000000/2574/2574 NOTICE: Received route-list reply, count: 1, route_list_count: 0, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.764657 0000000000000000/2574/2574 INFO: Added 3 states to explicit route list, attrs: ['source': 'dnet_add_state']
2014-09-13 23:51:37.765779 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 917369109dce, group: 1, backend: 0, idc: 0x1d9c400, attrs: []
2014-09-13 23:51:37.765821 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 81b5b074dc3a, group: 1, backend: 1, idc: 0x1d00cc0, attrs: []
2014-09-13 23:51:37.765843 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 33a2eb582db6, group: 1, backend: 2, idc: 0x20d49c0, attrs: []
2014-09-13 23:51:37.765864 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: b2ec8778ec01, group: 2, backend: 3, idc: 0x1ce83d0, attrs: []
2014-09-13 23:51:37.765884 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 4b383322210a, group: 2, backend: 4, idc: 0x17d0660, attrs: []
2014-09-13 23:51:37.765905 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 8e511caa41f5, group: 2, backend: 5, idc: 0x175c0a0, attrs: []
2014-09-13 23:51:37.765925 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: a0ad2f9c12d9, group: 3, backend: 6, idc: 0x175c130, attrs: []
2014-09-13 23:51:37.765945 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 8726a8dcdabd, group: 3, backend: 7, idc: 0x1ce9790, attrs: []
2014-09-13 23:51:37.765964 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25109: 8c17efa728bf, group: 3, backend: 8, idc: 0x1ceeea0, attrs: []
2014-09-13 23:51:37.765984 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: 4859da5cadaf, group: 1, backend: 0, idc: 0x2b134c08f800, attrs: []
2014-09-13 23:51:37.766007 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: e6e2f314aa15, group: 1, backend: 1, idc: 0x2b134c08f930, attrs: []
2014-09-13 23:51:37.766031 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: 0d774c456533, group: 1, backend: 2, idc: 0x2b134c08f250, attrs: []
2014-09-13 23:51:37.766062 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: 2b4fac283dcb, group: 2, backend: 3, idc: 0x2b134c08f3b0, attrs: []
2014-09-13 23:51:37.766082 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: 7ccb21ecc15f, group: 2, backend: 4, idc: 0x2b134c08f440, attrs: []
2014-09-13 23:51:37.766102 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: a3a1496ea10a, group: 2, backend: 5, idc: 0x2b134c094960, attrs: []
2014-09-13 23:51:37.766122 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: d5b360e9e4fc, group: 3, backend: 6, idc: 0x2b134c0949f0, attrs: []
2014-09-13 23:51:37.766141 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: 6ab0d1e6a637, group: 3, backend: 7, idc: 0x2b134c094a80, attrs: []
2014-09-13 23:51:37.766164 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:25963: 52f013a30d89, group: 3, backend: 8, idc: 0x2b134c094b10, attrs: []
2014-09-13 23:51:37.766184 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: 6d1bf2bc1cfa, group: 1, backend: 0, idc: 0x1c67680, attrs: []
2014-09-13 23:51:37.766204 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: f2f6cb4b7d83, group: 1, backend: 1, idc: 0x1cde7d0, attrs: []
2014-09-13 23:51:37.766224 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: 13f8734ed140, group: 1, backend: 2, idc: 0x1b64ff0, attrs: []
2014-09-13 23:51:37.766244 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: df05992a9d9d, group: 2, backend: 3, idc: 0x1930290, attrs: []
2014-09-13 23:51:37.766263 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: efb845c847c5, group: 2, backend: 4, idc: 0x1ffa1b0, attrs: []
2014-09-13 23:51:37.766282 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: 8472c925fdb7, group: 2, backend: 5, idc: 0x1d078b0, attrs: []
2014-09-13 23:51:37.766302 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: 00af302022bc, group: 3, backend: 6, idc: 0x164f7f0, attrs: []
2014-09-13 23:51:37.766321 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: 10fa4a35cef2, group: 3, backend: 7, idc: 0x1ce9690, attrs: []
2014-09-13 23:51:37.766341 4e4aaf9c5352a02e/2574/2574 INFO: 127.0.0.1:61038: a40a7c0badfa, group: 3, backend: 8, idc: 0x1ceef30, attrs: []
2014-09-13 23:51:37.767281 4e4aaf9c5352a02e/2574/2574 INFO: 0:000000000000...000000000000: alloc/send MONITOR_STAT trans: 6 -> 127.0.0.1:25963, weight: 1.000000., attrs: []
2014-09-13 23:51:37.767321 4e4aaf9c5352a02e/2574/2574 NOTICE: 0:000000000000...000000000000: added transaction: 6 -> 127.0.0.1:25963., attrs: []
2014-09-13 23:51:37.767371 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: MONITOR_STAT: sending -> 127.0.0.1:25963: trans: 6, size: 44, cflags: 0x100000019 [need_ack|direct|nolock], start-sent: 0/164., attrs: []
2014-09-13 23:51:37.767423 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: SENT nonblocking cmd: MONITOR_STAT: cmd-size: 44, nonblocking: 1, attrs: []
2014-09-13 23:51:37.767447 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: MONITOR_STAT: sending -> 127.0.0.1:25963: trans: 6, size: 44, cflags: 0x100000019 [need_ack|direct|nolock], finish-sent: 164/164., attrs: []
2014-09-13 23:51:37.768015 0000000000000000/2574/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:37.927816 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 6 / 0x6, reply: 1, size: 167294, flags: 0x10000021b [need_ack|more|direct|nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.928569 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: RECV cmd: MONITOR_STAT: cmd-size: 167294, nonblocking: 1, attrs: []
2014-09-13 23:51:37.928602 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: -1, attrs: []
2014-09-13 23:51:37.928676 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: got IO event: 0x2b1350269ea0: cmd: MONITOR_STAT, hsize: 120, dsize: 167294, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.928724 4e4aaf9c5352a02e/4843/2574 NOTICE: 0:000000000000...000000000000: handled reply from: 127.0.0.1:25963, cmd: MONITOR_STAT, flags: 0x10000021b [need_ack|more|direct|nolock|reply], trans: 6, status: 0, size: 167294, client: 0, last: 0, attrs: []
2014-09-13 23:51:37.929475 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: processed IO event: 0x2b1350269ea0, cmd: MONITOR_STAT, attrs: []
2014-09-13 23:51:37.931843 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 6 / 0x6, reply: 1, size: 0, flags: 0x100000218 [direct|nolock|reply], status: 0., attrs: []
2014-09-13 23:51:37.931894 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: RECV ACK: MONITOR_STAT: nonblocking: 1, attrs: []
2014-09-13 23:51:37.931918 0000000000000000/4844/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: -1, attrs: []
2014-09-13 23:51:37.933192 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: got IO event: 0x2b1350269a60: cmd: MONITOR_STAT, hsize: 120, dsize: 0, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:37.933232 4e4aaf9c5352a02e/4843/2574 NOTICE: 0:000000000000...000000000000: handled reply from: 127.0.0.1:25963, cmd: MONITOR_STAT, flags: 0x100000218 [direct|nolock|reply], trans: 6, status: 0, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:37.935655 4e4aaf9c5352a02e/4843/2574 INFO: 0:000000000000...000000000000: destruction MONITOR_STAT trans: 6, reply: 1, st: 127.0.0.1:25963, stall: 0, time: 166020, started: 2014-09-13 23:51:37.767275, cached status: 0, attrs: []
2014-09-13 23:51:37.935720 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:25963: 0:000000000000...000000000000: processed IO event: 0x2b1350269a60, cmd: MONITOR_STAT, attrs: []
2014-09-13 23:51:38.246487 4e4aaf9c5352a02e/2574/2574 INFO: 0:000000000000...000000000000: alloc/send MONITOR_STAT trans: 7 -> 127.0.0.1:61038, weight: 1.000000., attrs: []
2014-09-13 23:51:38.246645 4e4aaf9c5352a02e/2574/2574 NOTICE: 0:000000000000...000000000000: added transaction: 7 -> 127.0.0.1:61038., attrs: []
2014-09-13 23:51:38.246721 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: MONITOR_STAT: sending -> 127.0.0.1:61038: trans: 7, size: 44, cflags: 0x100000019 [need_ack|direct|nolock], start-sent: 0/164., attrs: []
2014-09-13 23:51:38.246778 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: SENT nonblocking cmd: MONITOR_STAT: cmd-size: 44, nonblocking: 1, attrs: []
2014-09-13 23:51:38.246800 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: MONITOR_STAT: sending -> 127.0.0.1:61038: trans: 7, size: 44, cflags: 0x100000019 [need_ack|direct|nolock], finish-sent: 164/164., attrs: []
2014-09-13 23:51:38.252684 0000000000000000/2574/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:38.443038 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 7 / 0x7, reply: 1, size: 182018, flags: 0x10000021b [need_ack|more|direct|nolock|reply], status: 0., attrs: []
2014-09-13 23:51:38.443861 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: RECV cmd: MONITOR_STAT: cmd-size: 182018, nonblocking: 1, attrs: []
2014-09-13 23:51:38.443897 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: -1, attrs: []
2014-09-13 23:51:38.443921 0000000000000000/4844/2574 INFO: input io queue report: elapsed: 0.698 s, current size: 1, min: 0, max: 3, volume: 8, noneblocking: 1, attrs: []
2014-09-13 23:51:38.443983 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: got IO event: 0x2b1350517000: cmd: MONITOR_STAT, hsize: 120, dsize: 182018, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:38.444030 4e4aaf9c5352a02e/4843/2574 NOTICE: 0:000000000000...000000000000: handled reply from: 127.0.0.1:61038, cmd: MONITOR_STAT, flags: 0x10000021b [need_ack|more|direct|nolock|reply], trans: 7, status: 0, size: 182018, client: 0, last: 0, attrs: []
2014-09-13 23:51:38.444774 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: processed IO event: 0x2b1350517000, cmd: MONITOR_STAT, attrs: []
2014-09-13 23:51:38.447498 0000000000000000/4844/2574 DEBUG: 0:000000000000...000000000000: received trans: 7 / 0x7, reply: 1, size: 0, flags: 0x100000218 [direct|nolock|reply], status: 0., attrs: []
2014-09-13 23:51:38.447553 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: RECV ACK: MONITOR_STAT: nonblocking: 1, attrs: []
2014-09-13 23:51:38.447577 0000000000000000/4844/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: backend_id: -1, place: 0x1ff4660, backend_place: (nil), backend_place->pool->backend_id: -1, cmd->backend_id: -1, attrs: []
2014-09-13 23:51:38.447634 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: got IO event: 0x2b1350269a60: cmd: MONITOR_STAT, hsize: 120, dsize: 0, mode: NONBLOCKING, backend_id: -1, attrs: []
2014-09-13 23:51:38.447675 4e4aaf9c5352a02e/4843/2574 NOTICE: 0:000000000000...000000000000: handled reply from: 127.0.0.1:61038, cmd: MONITOR_STAT, flags: 0x100000218 [direct|nolock|reply], trans: 7, status: 0, size: 0, client: 0, last: 1, attrs: []
2014-09-13 23:51:38.447760 4e4aaf9c5352a02e/4843/2574 INFO: 0:000000000000...000000000000: destruction MONITOR_STAT trans: 7, reply: 1, st: 127.0.0.1:61038, stall: 0, time: 201275, started: 2014-09-13 23:51:38.246463, cached status: 0, attrs: []
2014-09-13 23:51:38.447789 4e4aaf9c5352a02e/4843/2574 DEBUG: 127.0.0.1:61038: 0:000000000000...000000000000: processed IO event: 0x2b1350269a60, cmd: MONITOR_STAT, attrs: []
2014-09-13 23:51:39.555320 0000000000000000/3444/2574 ERROR: 127.0.0.1:25109: peer has disconnected, socket: 13/18., attrs: []
2014-09-13 23:51:39.555375 0000000000000000/3444/2574 ERROR: self: addr: no address, resetting state: 0x140c230, attrs: []
2014-09-13 23:51:39.555394 0000000000000000/3444/2574 ERROR: self: addr: no address, resetting state: 127.0.0.1:25109, attrs: []
2014-09-13 23:51:39.555413 0000000000000000/3444/2574 ERROR: 127.0.0.1:25109: resetting state: Connection reset by peer [-104], attrs: []
2014-09-13 23:51:39.555439 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:39.555460 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:39.555481 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:39.555502 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:39.555522 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:39.555543 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:39.555572 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:39.555594 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:39.555632 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:39.555674 0000000000000000/3444/2574 INFO: Added reconnection addr: 127.0.0.1:25109, join state: 0x2., attrs: []
2014-09-13 23:51:39.555858 0000000000000000/3444/2574 ERROR: 127.0.0.1:61038: peer has disconnected, socket: 15/23., attrs: []
2014-09-13 23:51:39.555881 0000000000000000/3444/2574 ERROR: self: addr: no address, resetting state: 0x13fe130, attrs: []
2014-09-13 23:51:39.555906 0000000000000000/3444/2574 ERROR: self: addr: no address, resetting state: 127.0.0.1:61038, attrs: []
2014-09-13 23:51:39.555926 0000000000000000/3444/2574 ERROR: 127.0.0.1:61038: resetting state: Connection reset by peer [-104], attrs: []
2014-09-13 23:51:39.555947 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: f2f6cb4b7d83 -> host: 127.0.0.1:61038, backend: 1, attrs: []
2014-09-13 23:51:39.555968 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 6d1bf2bc1cfa -> host: 127.0.0.1:61038, backend: 0, attrs: []
2014-09-13 23:51:39.555988 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 13f8734ed140 -> host: 127.0.0.1:61038, backend: 2, attrs: []
2014-09-13 23:51:39.556008 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: 8472c925fdb7 -> host: 127.0.0.1:61038, backend: 5, attrs: []
2014-09-13 23:51:39.556028 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: df05992a9d9d -> host: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:39.556049 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: efb845c847c5 -> host: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:39.556068 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: 10fa4a35cef2 -> host: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:39.556088 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: 00af302022bc -> host: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:39.556108 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: a40a7c0badfa -> host: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:39.556146 0000000000000000/3444/2574 INFO: Added reconnection addr: 127.0.0.1:61038, join state: 0x2., attrs: []
2014-09-13 23:51:39.561344 0000000000000000/4844/2574 ERROR: 127.0.0.1:25109: peer has disconnected, socket: 80/90., attrs: []
2014-09-13 23:51:39.561388 0000000000000000/4844/2574 ERROR: self: addr: no address, resetting state: 0x1d008c0, attrs: []
2014-09-13 23:51:39.561406 0000000000000000/4844/2574 ERROR: self: addr: no address, resetting state: 127.0.0.1:25109, attrs: []
2014-09-13 23:51:39.561425 0000000000000000/4844/2574 ERROR: 127.0.0.1:25109: resetting state: Connection reset by peer [-104], attrs: []
2014-09-13 23:51:39.561448 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 917369109dce -> host: 127.0.0.1:25109, backend: 0, attrs: []
2014-09-13 23:51:39.561469 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 81b5b074dc3a -> host: 127.0.0.1:25109, backend: 1, attrs: []
2014-09-13 23:51:39.561489 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 33a2eb582db6 -> host: 127.0.0.1:25109, backend: 2, attrs: []
2014-09-13 23:51:39.561511 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: b2ec8778ec01 -> host: 127.0.0.1:25109, backend: 3, attrs: []
2014-09-13 23:51:39.561531 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: 4b383322210a -> host: 127.0.0.1:25109, backend: 4, attrs: []
2014-09-13 23:51:39.561551 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: 8e511caa41f5 -> host: 127.0.0.1:25109, backend: 5, attrs: []
2014-09-13 23:51:39.561584 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: a0ad2f9c12d9 -> host: 127.0.0.1:25109, backend: 6, attrs: []
2014-09-13 23:51:39.561604 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: 8726a8dcdabd -> host: 127.0.0.1:25109, backend: 7, attrs: []
2014-09-13 23:51:39.561643 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: 8c17efa728bf -> host: 127.0.0.1:25109, backend: 8, attrs: []
2014-09-13 23:51:39.561682 0000000000000000/4844/2574 INFO: Added reconnection addr: 127.0.0.1:25109, join state: 0x2., attrs: []
2014-09-13 23:51:39.562363 0000000000000000/4844/2574 ERROR: 127.0.0.1:61038: peer has disconnected, socket: 82/81., attrs: []
2014-09-13 23:51:39.562387 0000000000000000/4844/2574 ERROR: self: addr: no address, resetting state: 0x1ff9db0, attrs: []
2014-09-13 23:51:39.562405 0000000000000000/4844/2574 ERROR: self: addr: no address, resetting state: 127.0.0.1:61038, attrs: []
2014-09-13 23:51:39.562431 0000000000000000/4844/2574 ERROR: 127.0.0.1:61038: resetting state: Connection reset by peer [-104], attrs: []
2014-09-13 23:51:39.562453 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 6d1bf2bc1cfa -> host: 127.0.0.1:61038, backend: 0, attrs: []
2014-09-13 23:51:39.562475 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: f2f6cb4b7d83 -> host: 127.0.0.1:61038, backend: 1, attrs: []
2014-09-13 23:51:39.562495 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 13f8734ed140 -> host: 127.0.0.1:61038, backend: 2, attrs: []
2014-09-13 23:51:39.562516 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: df05992a9d9d -> host: 127.0.0.1:61038, backend: 3, attrs: []
2014-09-13 23:51:39.562536 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: efb845c847c5 -> host: 127.0.0.1:61038, backend: 4, attrs: []
2014-09-13 23:51:39.562556 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: 8472c925fdb7 -> host: 127.0.0.1:61038, backend: 5, attrs: []
2014-09-13 23:51:39.562587 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: 00af302022bc -> host: 127.0.0.1:61038, backend: 6, attrs: []
2014-09-13 23:51:39.562607 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: 10fa4a35cef2 -> host: 127.0.0.1:61038, backend: 7, attrs: []
2014-09-13 23:51:39.562646 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: a40a7c0badfa -> host: 127.0.0.1:61038, backend: 8, attrs: []
2014-09-13 23:51:39.562684 0000000000000000/4844/2574 INFO: Added reconnection addr: 127.0.0.1:61038, join state: 0x2., attrs: []
2014-09-13 23:51:39.564911 0000000000000000/3444/2574 ERROR: 127.0.0.1:25963: peer has disconnected, socket: 14/21., attrs: []
2014-09-13 23:51:39.564955 0000000000000000/3444/2574 ERROR: self: addr: no address, resetting state: 0x13fd240, attrs: []
2014-09-13 23:51:39.564974 0000000000000000/3444/2574 ERROR: self: addr: no address, resetting state: 127.0.0.1:25963, attrs: []
2014-09-13 23:51:39.564994 0000000000000000/3444/2574 ERROR: 127.0.0.1:25963: resetting state: Connection reset by peer [-104], attrs: []
2014-09-13 23:51:39.565018 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:39.565040 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:39.565061 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:39.565081 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:39.565100 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:39.565120 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:39.565139 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:39.565159 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:39.565178 0000000000000000/3444/2574 DEBUG: dnet_idc_remove: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:39.565222 0000000000000000/3444/2574 INFO: Added reconnection addr: 127.0.0.1:25963, join state: 0x2., attrs: []
2014-09-13 23:51:39.567190 0000000000000000/4844/2574 ERROR: 127.0.0.1:25963: peer has disconnected, socket: 97/93., attrs: []
2014-09-13 23:51:39.567218 0000000000000000/4844/2574 ERROR: self: addr: no address, resetting state: 0x2b134c094560, attrs: []
2014-09-13 23:51:39.567236 0000000000000000/4844/2574 ERROR: self: addr: no address, resetting state: 127.0.0.1:25963, attrs: []
2014-09-13 23:51:39.567261 0000000000000000/4844/2574 ERROR: 127.0.0.1:25963: resetting state: Connection reset by peer [-104], attrs: []
2014-09-13 23:51:39.567283 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 4859da5cadaf -> host: 127.0.0.1:25963, backend: 0, attrs: []
2014-09-13 23:51:39.567303 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: e6e2f314aa15 -> host: 127.0.0.1:25963, backend: 1, attrs: []
2014-09-13 23:51:39.567322 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 1, id: 0d774c456533 -> host: 127.0.0.1:25963, backend: 2, attrs: []
2014-09-13 23:51:39.567342 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: 2b4fac283dcb -> host: 127.0.0.1:25963, backend: 3, attrs: []
2014-09-13 23:51:39.567362 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: 7ccb21ecc15f -> host: 127.0.0.1:25963, backend: 4, attrs: []
2014-09-13 23:51:39.567381 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 2, id: a3a1496ea10a -> host: 127.0.0.1:25963, backend: 5, attrs: []
2014-09-13 23:51:39.567401 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: d5b360e9e4fc -> host: 127.0.0.1:25963, backend: 6, attrs: []
2014-09-13 23:51:39.567420 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: 6ab0d1e6a637 -> host: 127.0.0.1:25963, backend: 7, attrs: []
2014-09-13 23:51:39.567439 0000000000000000/4844/2574 DEBUG: dnet_idc_remove: group: 3, id: 52f013a30d89 -> host: 127.0.0.1:25963, backend: 8, attrs: []
2014-09-13 23:51:39.567477 0000000000000000/4844/2574 INFO: Added reconnection addr: 127.0.0.1:25963, join state: 0x2., attrs: []
2014-09-13 23:51:39.567498 0000000000000000/4844/2574 NOTICE: self: addr: no address, closing socket: 97, attrs: []
2014-09-13 23:51:39.567517 0000000000000000/4844/2574 NOTICE: self: addr: no address, closing socket: 93, attrs: []
2014-09-13 23:51:39.567540 0000000000000000/4844/2574 NOTICE: Cleaned state 127.0.0.1:25963, transactions freed: 0, attrs: []
2014-09-13 23:51:39.567559 0000000000000000/4844/2574 NOTICE: Freeing state 127.0.0.1:25963, socket: 97/93, addr-num: 1., attrs: []
2014-09-13 23:51:40.585016 0000000000000000/2574/2574 DEBUG: Destroying node., attrs: []
2014-09-13 23:51:41.213269 0000000000000000/3443/2574 NOTICE: finished io thread: #0, nonblocking: 1, backend: -1, attrs: []
2014-09-13 23:51:41.213512 0000000000000000/2574/2574 NOTICE: Checking thread stopped., attrs: []
2014-09-13 23:51:41.469019 0000000000000000/3442/2574 NOTICE: finished io thread: #0, nonblocking: 0, backend: -1, attrs: []
2014-09-13 23:51:42.035564 0000000000000000/2574/2574 DEBUG: Destroying session., attrs: []
2014-09-13 23:51:42.035727 0000000000000000/2574/2574 DEBUG: Destroying node., attrs: []
2014-09-13 23:51:42.448296 0000000000000000/4843/2574 NOTICE: finished io thread: #0, nonblocking: 1, backend: -1, attrs: []
2014-09-13 23:51:42.751322 0000000000000000/4842/2574 NOTICE: finished io thread: #0, nonblocking: 0, backend: -1, attrs: []
2014-09-13 23:51:42.764461 0000000000000000/2574/2574 NOTICE: Checking thread stopped., attrs: []
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment