Skip to content

Instantly share code, notes, and snippets.

@6uv1s

6uv1s/gist:3762542

Created September 21, 2012 16:41
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save 6uv1s/3762542 to your computer and use it in GitHub Desktop.
Save 6uv1s/3762542 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
gistfile1.txt
2012-09-21 08:39:30.501474 7f5cb0093780 0 ceph version (commit:), process ceph-mds, pid 7026
2012-09-21 08:39:30.501682 7f5cb0093780 1 accepter.accepter.bind my_inst.addr is 0.0.0.0:6803/7026 need_addr=1
2012-09-21 08:39:30.503071 7f5cb0093780 1 -- 0.0.0.0:6803/7026 messenger.start
2012-09-21 08:39:30.503091 7f5cb0093780 1 accepter.accepter.start
2012-09-21 08:39:30.504018 7f5cb0093780 1 -- 0.0.0.0:6803/7026 --> 192.168.106.221:6791/0 -- auth(proto 0 26 bytes epoch 0) v1 -- ?+0 0x3a7d400 con 0x3a7fdc0
2012-09-21 08:39:30.504290 7f5cb0092700 1 -- 192.168.106.221:6803/7026 learned my addr 192.168.106.221:6803/7026
2012-09-21 08:39:30.504558 7f5cab210700 0 mds.-1.0 ms_handle_connect on 192.168.106.221:6791/0
2012-09-21 08:39:30.505095 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 1 ==== mon_map v1 ==== 473+0+0 (1815337937 0 0) 0x3a7da00 con 0x3a7fdc0
2012-09-21 08:39:30.505220 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 2 ==== auth_reply(proto 1 0 Success) v1 ==== 24+0+0 (188082336 0 0) 0x3a7d800 con 0x3a7fdc0
2012-09-21 08:39:30.505259 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({monmap=0+}) v2 -- ?+0 0x3a931c0 con 0x3a7fdc0
2012-09-21 08:39:30.505433 7f5cb0093780 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:boot seq 1 v0) v2 -- ?+0 0x3a9c500 con 0x3a7fdc0
2012-09-21 08:39:30.505567 7f5cb0093780 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({monmap=2+,osdmap=0}) v2 -- ?+0 0x3a938c0 con 0x3a7fdc0
2012-09-21 08:39:30.505608 7f5cb0093780 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({mdsmap=0+,monmap=2+,osdmap=0}) v2 -- ?+0 0x3a93700 con 0x3a7fdc0
2012-09-21 08:39:30.505893 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 3 ==== mon_map v1 ==== 473+0+0 (1815337937 0 0) 0x3aa2600 con 0x3a7fdc0
2012-09-21 08:39:30.505997 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 4 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a931c0 con 0x3a7fdc0
2012-09-21 08:39:30.506547 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 5 ==== osd_map(3..3 src has 1..3) v3 ==== 1402+0+0 (598681491 0 0) 0x3aa2400 con 0x3a7fdc0
2012-09-21 08:39:30.507145 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 6 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a93a80 con 0x3a7fdc0
2012-09-21 08:39:30.507169 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 7 ==== mdsmap(e 1) v1 ==== 313+0+0 (2754202167 0 0) 0x3aa2200 con 0x3a7fdc0
2012-09-21 08:39:30.507288 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 8 ==== osd_map(3..3 src has 1..3) v3 ==== 1402+0+0 (598681491 0 0) 0x3aa2000 con 0x3a7fdc0
2012-09-21 08:39:30.507341 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 9 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a938c0 con 0x3a7fdc0
2012-09-21 08:39:30.969502 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 10 ==== mdsmap(e 2) v1 ==== 511+0+0 (2162340175 0 0) 0x3aa2800 con 0x3a7fdc0
2012-09-21 08:39:30.969632 7f5cab210700 1 mds.-1.0 handle_mds_map standby
2012-09-21 08:39:31.585810 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 11 ==== mdsmap(e 3) v1 ==== 535+0+0 (1432190470 0 0) 0x3aa2600 con 0x3a7fdc0
2012-09-21 08:39:31.585955 7f5cab210700 1 mds.0.1 handle_mds_map i am now mds.0.1
2012-09-21 08:39:31.585962 7f5cab210700 1 mds.0.1 handle_mds_map state change up:standby --> up:creating
2012-09-21 08:39:31.586147 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({mdsmap=4+,monmap=2+,osdmap=4}) v2 -- ?+0 0x3a938c0 con 0x3a7fdc0
2012-09-21 08:39:31.586767 7f5cab210700 0 mds.0.cache creating system inode with ino:1
2012-09-21 08:39:31.586963 7f5cab210700 0 mds.0.cache creating system inode with ino:2
2012-09-21 08:39:31.587588 7f5cab210700 0 mds.0.cache creating system inode with ino:100
2012-09-21 08:39:31.587638 7f5cab210700 0 mds.0.cache creating system inode with ino:600
2012-09-21 08:39:31.587871 7f5cab210700 0 mds.0.cache creating system inode with ino:601
2012-09-21 08:39:31.588075 7f5cab210700 0 mds.0.cache creating system inode with ino:602
2012-09-21 08:39:31.588275 7f5cab210700 0 mds.0.cache creating system inode with ino:603
2012-09-21 08:39:31.588470 7f5cab210700 0 mds.0.cache creating system inode with ino:604
2012-09-21 08:39:31.588672 7f5cab210700 0 mds.0.cache creating system inode with ino:605
2012-09-21 08:39:31.588870 7f5cab210700 0 mds.0.cache creating system inode with ino:606
2012-09-21 08:39:31.589069 7f5cab210700 0 mds.0.cache creating system inode with ino:607
2012-09-21 08:39:31.589275 7f5cab210700 0 mds.0.cache creating system inode with ino:608
2012-09-21 08:39:31.589478 7f5cab210700 0 mds.0.cache creating system inode with ino:609
2012-09-21 08:39:31.589671 7f5cab210700 0 mds.0.cache creating system inode with ino:200
2012-09-21 08:39:31.972870 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 12 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a93e00 con 0x3a7fdc0
2012-09-21 08:39:32.327895 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 13 ==== mdsmap(e 4) v1 ==== 733+0+0 (3824975548 0 0) 0x3a7de00 con 0x3a7fdc0
2012-09-21 08:39:33.255225 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 14 ==== osd_map(4..4 src has 1..4) v3 ==== 618+0+0 (280348493 0 0) 0x3a7d800 con 0x3a7fdc0
2012-09-21 08:39:33.255935 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:1 200.00000000 [writefull 0~84] 1.844f3494) v4 -- ?+0 0x3a75900 con 0x3aa18c0
2012-09-21 08:39:33.255986 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:2 200.00000001 [delete] 1.6e5f474) v4 -- ?+0 0x3adb240 con 0x3aa18c0
2012-09-21 08:39:33.256026 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:3 200.00000002 [delete] 1.eb272dbb) v4 -- ?+0 0x3adb000 con 0x3aa18c0
2012-09-21 08:39:33.256058 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:4 200.00000003 [delete] 1.95e3ab) v4 -- ?+0 0x3adbd80 con 0x3aa18c0
2012-09-21 08:39:33.256090 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:5 200.00000004 [delete] 1.1ba6bc1f) v4 -- ?+0 0x3adbb40 con 0x3aa18c0
2012-09-21 08:39:33.256122 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:6 200.00000005 [delete] 1.96d5827a) v4 -- ?+0 0x3adb900 con 0x3aa18c0
2012-09-21 08:39:33.256157 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:7 200.00000006 [delete] 1.ade7efde) v4 -- ?+0 0x3adb6c0 con 0x3aa18c0
2012-09-21 08:39:33.256281 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:8 2.00000000 [setxattr path (6),setxattr parent (38),tmapup 0~0] 1.96f33707) v4 -- ?+0 0x3b36900 con 0x3aa18c0
2012-09-21 08:39:33.256382 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:9 1.00000000 [setxattr path,setxattr parent (13),tmapput 0~557] 1.6b2cdaff) v4 -- ?+0 0x3b366c0 con 0x3aa18c0
2012-09-21 08:39:33.256454 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:10 1.00000000.inode [writefull 0~428] 1.232c0e14) v4 -- ?+0 0x3b36480 con 0x3aa18c0
2012-09-21 08:39:33.256522 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:11 600.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.c2e541b0) v4 -- ?+0 0x3b36240 con 0x3aa18c0
2012-09-21 08:39:33.256631 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:12 601.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.81580290) v4 -- ?+0 0x3b36000 con 0x3aa18c0
2012-09-21 08:39:33.256724 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:13 602.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.2a8b6bd0) v4 -- ?+0 0x3b36b40 con 0x3aa18c0
2012-09-21 08:39:33.256803 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:14 603.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.7a4d91b0) v4 -- ?+0 0x3adb240 con 0x3aa18c0
2012-09-21 08:39:33.256891 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:15 604.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.43e85c95) v4 -- ?+0 0x3adbb40 con 0x3aa18c0
2012-09-21 08:39:33.256983 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:16 605.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.d0c18e1d) v4 -- ?+0 0x3adbd80 con 0x3aa18c0
2012-09-21 08:39:33.257082 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:17 606.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.f89eaaf4) v4 -- ?+0 0x3adb000 con 0x3aa18c0
2012-09-21 08:39:33.257191 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:18 607.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.bb590b7c) v4 -- ?+0 0x3a75900 con 0x3aa18c0
2012-09-21 08:39:33.257297 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:19 608.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.c4405e8e) v4 -- ?+0 0x3b36d80 con 0x3aa18c0
2012-09-21 08:39:33.257382 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:20 609.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.60b82d07) v4 -- ?+0 0x3b36900 con 0x3aa18c0
2012-09-21 08:39:33.257474 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:21 100.00000000 [setxattr path (5),setxattr parent (13),tmapput 0~4089] 1.c5265ab3) v4 -- ?+0 0x3b36480 con 0x3aa18c0
2012-09-21 08:39:33.257547 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:22 100.00000000.inode [writefull 0~399] 1.85dde07f) v4 -- ?+0 0x3adb6c0 con 0x3aa18c0
2012-09-21 08:39:33.257614 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:23 mds0_inotable [writefull 0~29] 1.b852b893) v4 -- ?+0 0x3adb900 con 0x3aa18c0
2012-09-21 08:39:33.257660 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:24 mds0_sessionmap [writefull 0~17] 1.3270c60b) v4 -- ?+0 0x3adb240 con 0x3aa18c0
2012-09-21 08:39:33.257729 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:25 mds_anchortable [writefull 0~29] 1.a977f6a7) v4 -- ?+0 0x3b36b40 con 0x3aa18c0
2012-09-21 08:39:33.257773 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:26 mds_snaptable [writefull 0~41] 1.d90270ad) v4 -- ?+0 0x3b36000 con 0x3aa18c0
2012-09-21 08:39:33.257957 7f5cab210700 0 mds.0.1 ms_handle_connect on 192.168.106.221:6800/7003
2012-09-21 08:39:33.403428 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 15 ==== mdsmap(e 5) v1 ==== 931+0+0 (1063859619 0 0) 0x3aa2e00 con 0x3a7fdc0
2012-09-21 08:39:34.487838 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 16 ==== mdsmap(e 6) v1 ==== 931+0+0 (26169171 0 0) 0x3aa2c00 con 0x3a7fdc0
2012-09-21 08:39:34.505704 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:creating seq 2 v6) v2 -- ?+0 0x3a9ca00 con 0x3a7fdc0
2012-09-21 08:39:34.985772 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 1 ==== osd_op_reply(7 200.00000006 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (2358461066 0 0) 0x3a7d800 con 0x3aa18c0
2012-09-21 08:39:35.046923 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 2 ==== osd_op_reply(5 200.00000004 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (4212787475 0 0) 0x3aa2600 con 0x3aa18c0
2012-09-21 08:39:35.104318 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 17 ==== mdsmap(e 7) v1 ==== 979+0+0 (2430714112 0 0) 0x3aa2a00 con 0x3a7fdc0
2012-09-21 08:39:35.104743 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 3 ==== osd_op_reply(19 608.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3063162972 0 0) 0x3aa2800 con 0x3aa18c0
2012-09-21 08:39:35.192780 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 4 ==== osd_op_reply(8 2.00000000 [setxattr (6),setxattr (38),tmapup 0~0] ondisk = 0) v4 ==== 193+0+0 (75409663 0 0) 0x3aa2e00 con 0x3aa18c0
2012-09-21 08:39:35.193004 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 5 ==== osd_op_reply(9 1.00000000 [setxattr,setxattr (13),tmapput 0~557] ondisk = 0) v4 ==== 193+0+0 (1320404367 0 0) 0x3b18600 con 0x3aa18c0
2012-09-21 08:39:35.193193 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 6 ==== osd_op_reply(22 100.00000000.inode [writefull 0~399] ondisk = 0) v4 ==== 117+0+0 (1327442681 0 0) 0x3b18400 con 0x3aa18c0
2012-09-21 08:39:35.193319 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 7 ==== osd_op_reply(25 mds_anchortable [writefull 0~29] ondisk = 0) v4 ==== 114+0+0 (3828916659 0 0) 0x3b18200 con 0x3aa18c0
2012-09-21 08:39:35.193414 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 8 ==== osd_op_reply(20 609.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (4129803120 0 0) 0x3b18000 con 0x3aa18c0
2012-09-21 08:39:35.318002 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 9 ==== osd_op_reply(2 200.00000001 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (1521823429 0 0) 0x3aa2800 con 0x3aa18c0
2012-09-21 08:39:35.328429 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 10 ==== osd_op_reply(6 200.00000005 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (3073949940 0 0) 0x3aa2a00 con 0x3aa18c0
2012-09-21 08:39:35.364588 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 18 ==== mdsbeacon(4103/a up:creating seq 2 v7) v2 ==== 103+0+0 (3238762577 0 0) 0x3a9ca00 con 0x3a7fdc0
2012-09-21 08:39:35.365933 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 11 ==== osd_op_reply(3 200.00000002 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (3225044059 0 0) 0x3aa2600 con 0x3aa18c0
2012-09-21 08:39:35.366137 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:27 200.00000001 [write 0~131] 1.6e5f474) v4 -- ?+0 0x3a75480 con 0x3aa18c0
2012-09-21 08:39:35.366682 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 12 ==== osd_op_reply(4 200.00000003 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (2625316585 0 0) 0x3aa2c00 con 0x3aa18c0
2012-09-21 08:39:35.438378 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 13 ==== osd_op_reply(1 200.00000000 [writefull 0~84] ondisk = 0) v4 ==== 111+0+0 (3741540391 0 0) 0x3a7d800 con 0x3aa18c0
2012-09-21 08:39:35.541231 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 14 ==== osd_op_reply(10 1.00000000.inode [writefull 0~428] ondisk = 0) v4 ==== 115+0+0 (484962512 0 0) 0x3aa2e00 con 0x3aa18c0
2012-09-21 08:39:35.541623 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 15 ==== osd_op_reply(17 606.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (1144539666 0 0) 0x3aa2600 con 0x3aa18c0
2012-09-21 08:39:35.541856 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 16 ==== osd_op_reply(18 607.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3068120739 0 0) 0x3aa2a00 con 0x3aa18c0
2012-09-21 08:39:35.542339 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 17 ==== osd_op_reply(26 mds_snaptable [writefull 0~41] ondisk = 0) v4 ==== 112+0+0 (4280208072 0 0) 0x3aa2800 con 0x3aa18c0
2012-09-21 08:39:35.542826 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 18 ==== osd_op_reply(15 604.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (2615680033 0 0) 0x3b31c00 con 0x3aa18c0
2012-09-21 08:39:35.543036 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 19 ==== osd_op_reply(16 605.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (1875551794 0 0) 0x3b31a00 con 0x3aa18c0
2012-09-21 08:39:35.543623 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 20 ==== osd_op_reply(23 mds0_inotable [writefull 0~29] ondisk = 0) v4 ==== 112+0+0 (3018966183 0 0) 0x3b31800 con 0x3aa18c0
2012-09-21 08:39:35.543801 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 21 ==== osd_op_reply(24 mds0_sessionmap [writefull 0~17] ondisk = 0) v4 ==== 114+0+0 (3420776324 0 0) 0x3b31600 con 0x3aa18c0
2012-09-21 08:39:35.544009 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 22 ==== osd_op_reply(21 100.00000000 [setxattr (5),setxattr (13),tmapput 0~4089] ondisk = 0) v4 ==== 195+0+0 (615053910 0 0) 0x3b31400 con 0x3aa18c0
2012-09-21 08:39:35.544366 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 23 ==== osd_op_reply(11 600.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (2111839394 0 0) 0x3b31200 con 0x3aa18c0
2012-09-21 08:39:35.544544 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 24 ==== osd_op_reply(12 601.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3731805080 0 0) 0x3b31000 con 0x3aa18c0
2012-09-21 08:39:35.544703 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 25 ==== osd_op_reply(13 602.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3733063563 0 0) 0x3b31a00 con 0x3aa18c0
2012-09-21 08:39:35.544857 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 26 ==== osd_op_reply(14 603.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (241941242 0 0) 0x3b31c00 con 0x3aa18c0
2012-09-21 08:39:35.545484 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 27 ==== osd_op_reply(27 200.00000001 [write 0~131] ondisk = 0) v4 ==== 111+0+0 (2226982505 0 0) 0x3aa2800 con 0x3aa18c0
2012-09-21 08:39:35.545559 7f5cab210700 1 mds.0.1 creating_done
2012-09-21 08:39:35.545605 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 3 v7) v2 -- ?+0 0x3a9ca00 con 0x3a7fdc0
2012-09-21 08:39:36.008666 7f5ca8f09700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3a75480 sd=18 :6803 pgs=0 cs=0 l=0).accept sd=18
2012-09-21 08:39:36.009296 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 1 ==== mdsmap(e 8) v1 ==== 979+0+0 (472031258 0 0) 0x3b31e00 con 0x3aa1c80
2012-09-21 08:39:36.009586 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 2 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9200 con 0x3aa1c80
2012-09-21 08:39:36.009622 7f5cab210700 0 mds.0.cache discover_reply not yet active(|still rejoining), delaying
2012-09-21 08:39:36.058371 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 19 ==== mdsmap(e 8) v1 ==== 979+0+0 (472031258 0 0) 0x3aa2000 con 0x3a7fdc0
2012-09-21 08:39:36.059439 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 20 ==== mdsbeacon(4103/a up:active seq 3 v8) v2 ==== 103+0+0 (1086776141 0 0) 0x3a9c500 con 0x3a7fdc0
2012-09-21 08:39:39.545886 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 4 v8) v2 -- ?+0 0x3a9cc80 con 0x3a7fdc0
2012-09-21 08:39:39.747609 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 3 ==== mdsmap(e 9) v1 ==== 979+0+0 (1281234092 0 0) 0x3ae9000 con 0x3aa1c80
2012-09-21 08:39:39.747801 7f5cab210700 1 mds.0.1 handle_mds_map i am now mds.0.1
2012-09-21 08:39:39.747807 7f5cab210700 1 mds.0.1 handle_mds_map state change up:creating --> up:active
2012-09-21 08:39:39.747816 7f5cab210700 1 mds.0.1 active_start
2012-09-21 08:39:39.748138 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6812/7140 -- discover_reply(1 1) v2 -- ?+0 0x3b36480 con 0x3aa1c80
2012-09-21 08:39:39.748328 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 4 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9600 con 0x3aa1c80
2012-09-21 08:39:39.748434 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6812/7140 -- discover_reply(1 1) v2 -- ?+0 0x3b36b40 con 0x3aa1c80
2012-09-21 08:39:39.748471 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 5 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9400 con 0x3aa1c80
2012-09-21 08:39:39.748627 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6812/7140 -- discover_reply(1 1) v2 -- ?+0 0x3b36900 con 0x3aa1c80
2012-09-21 08:39:39.903553 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 21 ==== mdsmap(e 9) v1 ==== 979+0+0 (1281234092 0 0) 0x3aa2200 con 0x3a7fdc0
2012-09-21 08:39:40.506170 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:28 200.00000001 [write 131~671] 1.6e5f474) v4 -- ?+0 0x3a75900 con 0x3aa18c0
2012-09-21 08:39:40.527823 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 28 ==== osd_op_reply(28 200.00000001 [write 131~671] ondisk = 0) v4 ==== 111+0+0 (1541244549 0 0) 0x3aa2a00 con 0x3aa18c0
2012-09-21 08:39:43.546192 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 5 v9) v2 -- ?+0 0x3ae6500 con 0x3a7fdc0
2012-09-21 08:39:43.660555 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 22 ==== mdsbeacon(4103/a up:active seq 5 v9) v2 ==== 103+0+0 (4012509064 0 0) 0x3ae6500 con 0x3a7fdc0
2012-09-21 08:39:44.283666 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 23 ==== mdsmap(e 10) v1 ==== 979+0+0 (136734347 0 0) 0x3aa2400 con 0x3a7fdc0
2012-09-21 08:39:44.284541 7f5ca8d07700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3b36000 sd=19 :6803 pgs=0 cs=0 l=0).accept sd=19
2012-09-21 08:39:44.285171 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 1 ==== mdsmap(e 10) v1 ==== 979+0+0 (136734347 0 0) 0x3ae9800 con 0x3aeb3c0
2012-09-21 08:39:44.285265 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 2 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9c00 con 0x3aeb3c0
2012-09-21 08:39:44.285437 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6805/7080 -- discover_reply(1 1) v2 -- ?+0 0x3b36240 con 0x3aeb3c0
2012-09-21 08:39:47.546484 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 6 v10) v2 -- ?+0 0x3ae6280 con 0x3a7fdc0
2012-09-21 08:39:47.547971 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 24 ==== mdsbeacon(4103/a up:active seq 6 v10) v2 ==== 103+0+0 (2762297722 0 0) 0x3a9cc80 con 0x3a7fdc0
2012-09-21 08:39:50.506907 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- HB v1 -- ?+0 0x3b36b40
2012-09-21 08:39:50.506951 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- HB v1 -- ?+0 0x3b36480
2012-09-21 08:39:50.507729 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 6 ==== HB v1 ==== 293+0+0 (409344659 0 0) 0x3b36480 con 0x3aa1c80
2012-09-21 08:39:50.507877 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 3 ==== HB v1 ==== 293+0+0 (409344659 0 0) 0x3b36b40 con 0x3aeb3c0
2012-09-21 08:39:50.507974 7f5cab210700 0 mds.0.bal mds.0 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85> = 0 ~ 0
2012-09-21 08:39:50.508009 7f5cab210700 0 mds.0.bal mds.1 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85> = 0 ~ 0
2012-09-21 08:39:50.508038 7f5cab210700 0 mds.0.bal mds.2 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85> = 0 ~ 0
2012-09-21 08:39:51.546791 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 7 v10) v2 -- ?+0 0x3ae6000 con 0x3a7fdc0
2012-09-21 08:39:51.548383 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 25 ==== mdsbeacon(4103/a up:active seq 7 v10) v2 ==== 103+0+0 (3716951069 0 0) 0x3a9ca00 con 0x3a7fdc0
2012-09-21 08:39:55.547080 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 8 v10) v2 -- ?+0 0x3ae6500 con 0x3a7fdc0
2012-09-21 08:39:55.549041 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 26 ==== mdsbeacon(4103/a up:active seq 8 v10) v2 ==== 103+0+0 (1891719410 0 0) 0x3ae6c80 con 0x3a7fdc0
2012-09-21 08:39:59.547385 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 9 v10) v2 -- ?+0 0x3a9cc80 con 0x3a7fdc0
2012-09-21 08:39:59.549123 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 27 ==== mdsbeacon(4103/a up:active seq 9 v10) v2 ==== 103+0+0 (166208917 0 0) 0x3ae6a00 con 0x3a7fdc0
2012-09-21 08:40:00.507335 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- HB v1 -- ?+0 0x3ade6c0
2012-09-21 08:40:00.507365 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- HB v1 -- ?+0 0x3ade480
2012-09-21 08:40:00.508012 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 4 ==== HB v1 ==== 293+0+0 (354905568 0 0) 0x3ade6c0 con 0x3aeb3c0
2012-09-21 08:40:00.508341 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 7 ==== HB v1 ==== 293+0+0 (354905568 0 0) 0x3ade480 con 0x3aa1c80
2012-09-21 08:40:00.508440 7f5cab210700 0 mds.0.bal mds.0 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56> = 0 ~ 0
2012-09-21 08:40:00.508471 7f5cab210700 0 mds.0.bal mds.1 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56> = 0 ~ 0
2012-09-21 08:40:00.508501 7f5cab210700 0 mds.0.bal mds.2 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56> = 0 ~ 0
2012-09-21 08:40:03.547688 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 10 v10) v2 -- ?+0 0x3a9c500 con 0x3a7fdc0
2012-09-21 08:40:03.549470 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 28 ==== mdsbeacon(4103/a up:active seq 10 v10) v2 ==== 103+0+0 (2190713404 0 0) 0x3ae6780 con 0x3a7fdc0
2012-09-21 08:40:07.548001 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 11 v10) v2 -- ?+0 0x3a9c780 con 0x3a7fdc0
2012-09-21 08:40:07.549757 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 29 ==== mdsbeacon(4103/a up:active seq 11 v10) v2 ==== 103+0+0 (4223328091 0 0) 0x3a9cc80 con 0x3a7fdc0
2012-09-21 08:40:10.507778 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- HB v1 -- ?+0 0x3ade240
2012-09-21 08:40:10.507810 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- HB v1 -- ?+0 0x3aded80
2012-09-21 08:40:10.508590 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 5 ==== HB v1 ==== 293+0+0 (240180757 0 0) 0x3ade240 con 0x3aeb3c0
2012-09-21 08:40:10.508776 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 8 ==== HB v1 ==== 293+0+0 (240180757 0 0) 0x3aded80 con 0x3aa1c80
2012-09-21 08:40:10.508852 7f5cab210700 0 mds.0.bal mds.0 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32> = 0 ~ 0
2012-09-21 08:40:10.508894 7f5cab210700 0 mds.0.bal mds.1 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32> = 0 ~ 0
2012-09-21 08:40:10.508922 7f5cab210700 0 mds.0.bal mds.2 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32> = 0 ~ 0
2012-09-21 08:40:10.958671 7f5ca8b05700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3b36900 sd=20 :6803 pgs=0 cs=0 l=0).accept sd=20
2012-09-21 08:40:10.959045 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 1 ==== client_session(request_open) v1 ==== 28+0+0 (0 0 0) 0x3b13000 con 0x3aeb140
2012-09-21 08:40:10.959481 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:29 200.00000001 [write 802~854] 1.6e5f474) v4 -- ?+0 0x3aded80 con 0x3aa18c0
2012-09-21 08:40:10.959645 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:30 200.00000000 [writefull 0~84] 1.844f3494) v4 -- ?+0 0x3ade240 con 0x3aa18c0
2012-09-21 08:40:10.985769 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 29 ==== osd_op_reply(29 200.00000001 [write 802~854] ondisk = 0) v4 ==== 111+0+0 (3617161125 0 0) 0x3aa2600 con 0x3aa18c0
2012-09-21 08:40:10.985846 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> client.4107 192.168.106.221:0/1007208 -- client_session(open) v1 -- ?+0 0x3a93e00
2012-09-21 08:40:10.986419 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 2 ==== client_session(request_renewcaps seq 1) v1 ==== 28+0+0 (968950716 0 0) 0x3a93e00 con 0x3aeb140
2012-09-21 08:40:10.986470 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(renewcaps seq 1) v1 -- ?+0 0x3a93a80 con 0x3aeb140
2012-09-21 08:40:10.986711 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 3 ==== client_request(client.4107:1 getattr pAsLsXsFs #1) v1 ==== 114+0+0 (4011615131 0 0) 0x3ae6c80 con 0x3aeb140
2012-09-21 08:40:10.987079 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:1 = 0 Success) v1 -- ?+0 0x3a9cc80 con 0x3aeb140
2012-09-21 08:40:10.988318 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 4 ==== client_request(client.4107:2 lookup #1/open_test_7208) v1 ==== 128+0+0 (1226895642 0 0) 0x3a9cc80 con 0x3aeb140
2012-09-21 08:40:10.988458 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:2 = -2 No such file or directory) v1 -- ?+0 0x3ae6c80 con 0x3aeb140
2012-09-21 08:40:10.989359 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 5 ==== client_request(client.4107:3 mkdir #1/open_test_7208) v1 ==== 172+0+0 (3702870140 0 0) 0x3ae6c80 con 0x3aeb140
2012-09-21 08:40:10.989607 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3ade480
2012-09-21 08:40:10.989751 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- mdsmap(e 10) v1 -- ?+0 0x3aa2600
2012-09-21 08:40:10.989776 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3ade6c0
2012-09-21 08:40:10.990203 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 6 ==== lock(a=mixack ifile 1.head) v1 ==== 64+0+0 (3574224453 0 0) 0x3ade480 con 0x3aeb3c0
2012-09-21 08:40:10.990369 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 9 ==== lock(a=mixack ifile 1.head) v1 ==== 64+0+0 (1226401603 0 0) 0x3ade6c0 con 0x3aa1c80
2012-09-21 08:40:10.990454 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3ade480
2012-09-21 08:40:10.990497 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3b36b40
2012-09-21 08:40:10.990690 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3b36480
2012-09-21 08:40:10.990746 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3adb240
2012-09-21 08:40:10.991195 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 10 ==== lock(a=mixack inest 1.head) v1 ==== 64+0+0 (1378384186 0 0) 0x3adb240 con 0x3aa1c80
2012-09-21 08:40:10.991247 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 7 ==== lock(a=mixack inest 1.head) v1 ==== 64+0+0 (3459991612 0 0) 0x3b36480 con 0x3aeb3c0
2012-09-21 08:40:10.991314 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3adb240
2012-09-21 08:40:10.991361 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3ade6c0
2012-09-21 08:40:10.992262 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:31 200.00000001 [write 1656~2172] 1.6e5f474) v4 -- ?+0 0x3adb900 con 0x3aa18c0
2012-09-21 08:40:11.010589 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 30 ==== osd_op_reply(30 200.00000000 [writefull 0~84] ondisk = 0) v4 ==== 111+0+0 (715516510 0 0) 0x3aa2e00 con 0x3aa18c0
2012-09-21 08:40:11.027533 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 31 ==== osd_op_reply(31 200.00000001 [write 1656~2172] ondisk = 0) v4 ==== 111+0+0 (2082473059 0 0) 0x3aa2c00 con 0x3aa18c0
2012-09-21 08:40:11.027799 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:3 = 0 Success safe) v1 -- ?+0 0x3a9cc80 con 0x3aeb140
2012-09-21 08:40:11.029330 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 6 ==== client_request(client.4107:4 create #10000000000/created_file_7208) v1 ==== 175+0+0 (521926727 0 0) 0x3a9cc80 con 0x3aeb140
2012-09-21 08:40:11.029706 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_caps(revoke ino 10000000000 2 seq 3 caps=pAsLsXsxFsx dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:10.991530) v2 -- ?+0 0x3a94340 con 0x3aeb140
2012-09-21 08:40:11.030591 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 7 ==== client_caps(update ino 10000000000 2 seq 3 caps=pAsLsXsFs dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:10.991530) v2 ==== 180+0+0 (905707034 0 0) 0x3a94340 con 0x3aeb140
2012-09-21 08:40:11.031125 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:4 = 0 Success unsafe) v1 -- ?+0 0x3ae6c80 con 0x3aeb140
2012-09-21 08:40:11.548307 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 12 v10) v2 -- ?+0 0x3ad2a00 con 0x3a7fdc0
2012-09-21 08:40:11.550120 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 30 ==== mdsbeacon(4103/a up:active seq 12 v10) v2 ==== 103+0+0 (2441665439 0 0) 0x3ae6500 con 0x3a7fdc0
2012-09-21 08:40:11.957109 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 8 ==== client_session(request_renewcaps seq 2) v1 ==== 28+0+0 (4076679903 0 0) 0x3a93a80 con 0x3aeb140
2012-09-21 08:40:11.957180 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(renewcaps seq 2) v1 -- ?+0 0x3a93e00 con 0x3aeb140
2012-09-21 08:40:15.507741 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:32 200.00000001 [write 3828~2102] 1.6e5f474) v4 -- ?+0 0x3adeb40 con 0x3aa18c0
2012-09-21 08:40:15.531005 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 32 ==== osd_op_reply(32 200.00000001 [write 3828~2102] ondisk = 0) v4 ==== 111+0+0 (3015535970 0 0) 0x3a7d800 con 0x3aa18c0
2012-09-21 08:40:15.531344 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:4 = 0 Success safe) v1 -- ?+0 0x3ae6500 con 0x3aeb140
2012-09-21 08:40:15.532749 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 9 ==== client_caps(update ino 10000000001 3 seq 1 caps=p dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:11.030810) v2 ==== 180+0+0 (4181515013 0 0) 0x3a94680 con 0x3aeb140
2012-09-21 08:40:15.532876 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 10 ==== client_caps(update ino 1 1 seq 3 caps=p dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:10.991530) v2 ==== 180+0+0 (2554533167 0 0) 0x3a94d00 con 0x3aeb140
2012-09-21 08:40:15.532993 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 11 ==== client_caps(update ino 10000000000 2 seq 4 caps=p dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:11.030810) v2 ==== 180+0+0 (1678527455 0 0) 0x3a949c0 con 0x3aeb140
2012-09-21 08:40:15.533542 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 12 ==== client_session(request_close seq 1) v1 ==== 28+0+0 (4220035607 0 0) 0x3a93e00 con 0x3aeb140
2012-09-21 08:40:15.533922 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:33 200.00000001 [write 5930~870] 1.6e5f474) v4 -- ?+0 0x3b36480 con 0x3aa18c0
2012-09-21 08:40:15.548642 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 13 v10) v2 -- ?+0 0x3ad2780 con 0x3a7fdc0
2012-09-21 08:40:15.550484 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 31 ==== mdsbeacon(4103/a up:active seq 13 v10) v2 ==== 103+0+0 (3902908152 0 0) 0x3ae6000 con 0x3a7fdc0
2012-09-21 08:40:15.572664 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 33 ==== osd_op_reply(33 200.00000001 [write 5930~870] ondisk = 0) v4 ==== 111+0+0 (3111485359 0 0) 0x3ae9e00 con 0x3aa18c0
2012-09-21 08:40:15.572835 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(close) v1 -- ?+0 0x3a93e00 con 0x3aeb140
2012-09-21 08:40:15.572864 7f5cab210700 1 -- 192.168.106.221:6803/7026 mark_disposable 0x3aeb140 -- 0x3b36900
2012-09-21 08:40:15.573975 7f5cab210700 0 mds.0.1 ms_handle_reset on 192.168.106.221:0/1007208
2012-09-21 08:40:15.574044 7f5cab210700 1 -- 192.168.106.221:6803/7026 mark_down 192.168.106.221:0/1007208 -- pipe dne
2012-09-21 08:40:15.577905 7f5ca8b05700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3b13d80 sd=20 :6803 pgs=0 cs=0 l=0).accept sd=20
2012-09-21 08:40:15.578209 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 1 ==== client_session(request_open) v1 ==== 28+0+0 (0 0 0) 0x3a93e00 con 0x3b12280
2012-09-21 08:40:15.579330 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:34 200.00000001 [write 6800~854] 1.6e5f474) v4 -- ?+0 0x3adb000 con 0x3aa18c0
2012-09-21 08:40:15.614306 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 34 ==== osd_op_reply(34 200.00000001 [write 6800~854] ondisk = 0) v4 ==== 111+0+0 (2178446690 0 0) 0x3ad6e00 con 0x3aa18c0
2012-09-21 08:40:15.614367 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> client.4110 192.168.106.221:0/1007208 -- client_session(open) v1 -- ?+0 0x3a93e00
2012-09-21 08:40:15.614860 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 2 ==== client_session(request_renewcaps seq 1) v1 ==== 28+0+0 (968950716 0 0) 0x3a93e00 con 0x3b12280
2012-09-21 08:40:15.614892 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(renewcaps seq 1) v1 -- ?+0 0x3a93a80 con 0x3b12280
2012-09-21 08:40:15.615146 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 3 ==== client_request(client.4110:1 getattr pAsLsXsFs #1) v1 ==== 114+0+0 (4011615131 0 0) 0x3ae6c80 con 0x3b12280
2012-09-21 08:40:15.615299 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3adb6c0
2012-09-21 08:40:15.615333 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3adbb40
2012-09-21 08:40:15.615851 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 11 ==== lock(a=syncack ifile 1.head) v1 ==== 110+0+0 (3421590228 0 0) 0x3adbb40 con 0x3aa1c80
2012-09-21 08:40:15.615944 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 8 ==== lock(a=syncack ifile 1.head) v1 ==== 110+0+0 (843043591 0 0) 0x3adb6c0 con 0x3aeb3c0
2012-09-21 08:40:15.616019 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3adbb40
2012-09-21 08:40:15.616060 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3ad7d80
2012-09-21 08:40:15.616312 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:1 = 0 Success) v1 -- ?+0 0x3ae6000 con 0x3b12280
2012-09-21 08:40:15.617118 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 4 ==== client_request(client.4110:2 lookup #1/open_test_7208) v1 ==== 128+0+0 (4086801148 0 0) 0x3ae6000 con 0x3b12280
2012-09-21 08:40:15.617334 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:2 = 0 Success) v1 -- ?+0 0x3ae6c80 con 0x3b12280
2012-09-21 08:40:15.618323 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 5 ==== client_request(client.4110:3 lookup #10000000000) v1 ==== 114+0+0 (191063834 0 0) 0x3ae6c80 con 0x3b12280
2012-09-21 08:40:15.625316 7f5cab210700 -1 *** Caught signal (Segmentation fault) **
in thread 7f5cab210700
ceph version (commit:)
1: (ceph::BackTrace::BackTrace(int)+0x33) [0xb2665f]
2: /home/slang/dev/ceph/build-wip-2285/src/ceph-mds() [0xc868e8]
3: (()+0xef60) [0x7f5cafc7df60]
4: (Server::handle_client_getattr(MDRequest*, bool)+0x548) [0x8a4bc8]
5: (Server::dispatch_client_request(MDRequest*)+0x3a6) [0x89bb1e]
6: (Server::handle_client_request(MClientRequest*)+0xd91) [0x89b767]
7: (Server::dispatch(Message*)+0x513) [0x8910ad]
8: (MDS::handle_deferrable_message(Message*)+0x7e7) [0x81299b]
9: (MDS::_dispatch(Message*)+0x238) [0x813f1a]
10: (MDS::ms_dispatch(Message*)+0x1c9) [0x8110db]
11: (Messenger::ms_deliver_dispatch(Message*)+0x94) [0xbf19e2]
12: (DispatchQueue::entry()+0xaae) [0xbf1484]
13: (DispatchQueue::DispatchThread::entry()+0x22) [0xb9c7c0]
14: (Thread::_entry_func(void*)+0x29) [0xc02601]
15: (()+0x68ba) [0x7f5cafc758ba]
16: (clone()+0x6d) [0x7f5cae4fe02d]
NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed to interpret this.
--- begin dump of recent events ---
-675> 2012-09-21 08:39:30.495138 7f5cb0093780 5 asok(0x3a76000) register_command perfcounters_dump hook 0x3a6b010
-674> 2012-09-21 08:39:30.495166 7f5cb0093780 5 asok(0x3a76000) register_command 1 hook 0x3a6b010
-673> 2012-09-21 08:39:30.495171 7f5cb0093780 5 asok(0x3a76000) register_command perf dump hook 0x3a6b010
-672> 2012-09-21 08:39:30.495183 7f5cb0093780 5 asok(0x3a76000) register_command perfcounters_schema hook 0x3a6b010
-671> 2012-09-21 08:39:30.495188 7f5cb0093780 5 asok(0x3a76000) register_command 2 hook 0x3a6b010
-670> 2012-09-21 08:39:30.495193 7f5cb0093780 5 asok(0x3a76000) register_command perf schema hook 0x3a6b010
-669> 2012-09-21 08:39:30.495200 7f5cb0093780 5 asok(0x3a76000) register_command config show hook 0x3a6b010
-668> 2012-09-21 08:39:30.495206 7f5cb0093780 5 asok(0x3a76000) register_command config set hook 0x3a6b010
-667> 2012-09-21 08:39:30.495215 7f5cb0093780 5 asok(0x3a76000) register_command log flush hook 0x3a6b010
-666> 2012-09-21 08:39:30.495225 7f5cb0093780 5 asok(0x3a76000) register_command log dump hook 0x3a6b010
-665> 2012-09-21 08:39:30.495232 7f5cb0093780 5 asok(0x3a76000) register_command log reopen hook 0x3a6b010
-664> 2012-09-21 08:39:30.501474 7f5cb0093780 0 ceph version (commit:), process ceph-mds, pid 7026
-663> 2012-09-21 08:39:30.501682 7f5cb0093780 1 accepter.accepter.bind my_inst.addr is 0.0.0.0:6803/7026 need_addr=1
-662> 2012-09-21 08:39:30.502491 7f5cb0093780 1 finished global_init_daemonize
-661> 2012-09-21 08:39:30.502686 7f5cb0093780 5 asok(0x3a76000) init out/mds.a.asok
-660> 2012-09-21 08:39:30.502708 7f5cb0093780 5 asok(0x3a76000) bind_and_listen out/mds.a.asok
-659> 2012-09-21 08:39:30.502744 7f5cb0093780 5 asok(0x3a76000) register_command 0 hook 0x3a6a040
-658> 2012-09-21 08:39:30.502753 7f5cb0093780 5 asok(0x3a76000) register_command version hook 0x3a6a040
-657> 2012-09-21 08:39:30.502768 7f5cb0093780 5 asok(0x3a76000) register_command git_version hook 0x3a6a040
-656> 2012-09-21 08:39:30.502777 7f5cb0093780 5 asok(0x3a76000) register_command help hook 0x3a6b050
-655> 2012-09-21 08:39:30.502871 7f5cad214700 5 asok(0x3a76000) entry start
-654> 2012-09-21 08:39:30.503071 7f5cb0093780 1 -- 0.0.0.0:6803/7026 messenger.start
-653> 2012-09-21 08:39:30.503091 7f5cb0093780 1 accepter.accepter.start
-652> 2012-09-21 08:39:30.503841 7f5cb0093780 2 auth: KeyRing::load: loaded key file dev/mds.a/keyring
-651> 2012-09-21 08:39:30.504018 7f5cb0093780 1 -- 0.0.0.0:6803/7026 --> 192.168.106.221:6791/0 -- auth(proto 0 26 bytes epoch 0) v1 -- ?+0 0x3a7d400 con 0x3a7fdc0
-650> 2012-09-21 08:39:30.504290 7f5cb0092700 1 -- 192.168.106.221:6803/7026 learned my addr 192.168.106.221:6803/7026
-649> 2012-09-21 08:39:30.504558 7f5cab210700 0 mds.-1.0 ms_handle_connect on 192.168.106.221:6791/0
-648> 2012-09-21 08:39:30.505005 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 473 (0 -> 473)
-647> 2012-09-21 08:39:30.505093 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 24 (473 -> 497)
-646> 2012-09-21 08:39:30.505095 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 1 ==== mon_map v1 ==== 473+0+0 (1815337937 0 0) 0x3a7da00 con 0x3a7fdc0
-645> 2012-09-21 08:39:30.505185 7f5cab210700 1 monclient(hunting): found mon.c
-644> 2012-09-21 08:39:30.505197 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 473 (0x1072c08 -> 24)
-643> 2012-09-21 08:39:30.505220 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 2 ==== auth_reply(proto 1 0 Success) v1 ==== 24+0+0 (188082336 0 0) 0x3a7d800 con 0x3a7fdc0
-642> 2012-09-21 08:39:30.505259 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({monmap=0+}) v2 -- ?+0 0x3a931c0 con 0x3a7fdc0
-641> 2012-09-21 08:39:30.505314 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 24 (0x1072c08 -> 0)
-640> 2012-09-21 08:39:30.505315 7f5cb0093780 5 monclient: authenticate success, global_id 4103
-639> 2012-09-21 08:39:30.505433 7f5cb0093780 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:boot seq 1 v0) v2 -- ?+0 0x3a9c500 con 0x3a7fdc0
-638> 2012-09-21 08:39:30.505517 7f5cb0093780 5 asok(0x3a76000) register_command objecter_requests hook 0x3a6b0c0
-637> 2012-09-21 08:39:30.505567 7f5cb0093780 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({monmap=2+,osdmap=0}) v2 -- ?+0 0x3a938c0 con 0x3a7fdc0
-636> 2012-09-21 08:39:30.505608 7f5cb0093780 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({mdsmap=0+,monmap=2+,osdmap=0}) v2 -- ?+0 0x3a93700 con 0x3a7fdc0
-635> 2012-09-21 08:39:30.505819 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 473 (0 -> 473)
-634> 2012-09-21 08:39:30.505895 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 20 (473 -> 493)
-633> 2012-09-21 08:39:30.505893 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 3 ==== mon_map v1 ==== 473+0+0 (1815337937 0 0) 0x3aa2600 con 0x3a7fdc0
-632> 2012-09-21 08:39:30.505979 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 473 (0x1072c08 -> 20)
-631> 2012-09-21 08:39:30.505997 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 4 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a931c0 con 0x3a7fdc0
-630> 2012-09-21 08:39:30.506017 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 20 (0x1072c08 -> 0)
-629> 2012-09-21 08:39:30.506415 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 1402 (0 -> 1402)
-628> 2012-09-21 08:39:30.506544 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 20 (1402 -> 1422)
-627> 2012-09-21 08:39:30.506547 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 5 ==== osd_map(3..3 src has 1..3) v3 ==== 1402+0+0 (598681491 0 0) 0x3aa2400 con 0x3a7fdc0
-626> 2012-09-21 08:39:30.506602 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 313 (1422 -> 1735)
-625> 2012-09-21 08:39:30.506835 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 1402 (1735 -> 3137)
-624> 2012-09-21 08:39:30.506941 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 20 (3137 -> 3157)
-623> 2012-09-21 08:39:30.507130 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 1402 (0x1072c08 -> 1755)
-622> 2012-09-21 08:39:30.507145 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 6 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a93a80 con 0x3a7fdc0
-621> 2012-09-21 08:39:30.507163 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 20 (0x1072c08 -> 1735)
-620> 2012-09-21 08:39:30.507169 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 7 ==== mdsmap(e 1) v1 ==== 313+0+0 (2754202167 0 0) 0x3aa2200 con 0x3a7fdc0
-619> 2012-09-21 08:39:30.507184 7f5cab210700 5 mds.-1.0 handle_mds_map epoch 1 from mon.2
-618> 2012-09-21 08:39:30.507276 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 313 (0x1072c08 -> 1422)
-617> 2012-09-21 08:39:30.507288 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 8 ==== osd_map(3..3 src has 1..3) v3 ==== 1402+0+0 (598681491 0 0) 0x3aa2000 con 0x3a7fdc0
-616> 2012-09-21 08:39:30.507328 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 1402 (0x1072c08 -> 20)
-615> 2012-09-21 08:39:30.507341 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 9 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a938c0 con 0x3a7fdc0
-614> 2012-09-21 08:39:30.507355 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 20 (0x1072c08 -> 0)
-613> 2012-09-21 08:39:30.969403 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 511 (0 -> 511)
-612> 2012-09-21 08:39:30.969502 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 10 ==== mdsmap(e 2) v1 ==== 511+0+0 (2162340175 0 0) 0x3aa2800 con 0x3a7fdc0
-611> 2012-09-21 08:39:30.969530 7f5cab210700 5 mds.-1.-1 handle_mds_map epoch 2 from mon.2
-610> 2012-09-21 08:39:30.969632 7f5cab210700 1 mds.-1.0 handle_mds_map standby
-609> 2012-09-21 08:39:30.969659 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 511 (0x1072c08 -> 0)
-608> 2012-09-21 08:39:31.585646 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 535 (0 -> 535)
-607> 2012-09-21 08:39:31.585810 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 11 ==== mdsmap(e 3) v1 ==== 535+0+0 (1432190470 0 0) 0x3aa2600 con 0x3a7fdc0
-606> 2012-09-21 08:39:31.585835 7f5cab210700 5 mds.-1.0 handle_mds_map epoch 3 from mon.2
-605> 2012-09-21 08:39:31.585955 7f5cab210700 1 mds.0.1 handle_mds_map i am now mds.0.1
-604> 2012-09-21 08:39:31.585962 7f5cab210700 1 mds.0.1 handle_mds_map state change up:standby --> up:creating
-603> 2012-09-21 08:39:31.585967 7f5cab210700 3 mds.0.1 boot_create
-602> 2012-09-21 08:39:31.585980 7f5cab210700 5 mds.0.log create empty log
-601> 2012-09-21 08:39:31.585996 7f5cab210700 1 mds.0.journaler(ro) set_writeable
-600> 2012-09-21 08:39:31.586001 7f5cab210700 1 mds.0.journaler(rw) create blank journal
-599> 2012-09-21 08:39:31.586069 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 84
-598> 2012-09-21 08:39:31.586077 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-597> 2012-09-21 08:39:31.586147 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mon_subscribe({mdsmap=4+,monmap=2+,osdmap=4}) v2 -- ?+0 0x3a938c0 con 0x3a7fdc0
-596> 2012-09-21 08:39:31.586332 7f5cab210700 5 mds.0.log submit_entry 4194304~127 : ESubtreeMap 0 subtrees , 0 ambiguous [metablob]
-595> 2012-09-21 08:39:31.586441 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 0
-594> 2012-09-21 08:39:31.586449 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-593> 2012-09-21 08:39:31.586501 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 0
-592> 2012-09-21 08:39:31.586510 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-591> 2012-09-21 08:39:31.586559 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 0
-590> 2012-09-21 08:39:31.586571 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-589> 2012-09-21 08:39:31.586623 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 0
-588> 2012-09-21 08:39:31.586630 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-587> 2012-09-21 08:39:31.586680 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 0
-586> 2012-09-21 08:39:31.586686 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-585> 2012-09-21 08:39:31.586732 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 0
-584> 2012-09-21 08:39:31.586737 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-583> 2012-09-21 08:39:31.586762 7f5cab210700 3 mds.0.1 boot_create creating fresh hierarchy
-582> 2012-09-21 08:39:31.586767 7f5cab210700 0 mds.0.cache creating system inode with ino:1
-581> 2012-09-21 08:39:31.586963 7f5cab210700 0 mds.0.cache creating system inode with ino:2
-580> 2012-09-21 08:39:31.587247 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 256
-579> 2012-09-21 08:39:31.587256 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-578> 2012-09-21 08:39:31.587442 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 580
-577> 2012-09-21 08:39:31.587450 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-576> 2012-09-21 08:39:31.587553 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 428
-575> 2012-09-21 08:39:31.587561 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-574> 2012-09-21 08:39:31.587585 7f5cab210700 3 mds.0.1 boot_create creating mydir hierarchy
-573> 2012-09-21 08:39:31.587588 7f5cab210700 0 mds.0.cache creating system inode with ino:100
-572> 2012-09-21 08:39:31.587638 7f5cab210700 0 mds.0.cache creating system inode with ino:600
-571> 2012-09-21 08:39:31.587832 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-570> 2012-09-21 08:39:31.587840 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-569> 2012-09-21 08:39:31.587871 7f5cab210700 0 mds.0.cache creating system inode with ino:601
-568> 2012-09-21 08:39:31.588042 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-567> 2012-09-21 08:39:31.588050 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-566> 2012-09-21 08:39:31.588075 7f5cab210700 0 mds.0.cache creating system inode with ino:602
-565> 2012-09-21 08:39:31.588241 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-564> 2012-09-21 08:39:31.588250 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-563> 2012-09-21 08:39:31.588275 7f5cab210700 0 mds.0.cache creating system inode with ino:603
-562> 2012-09-21 08:39:31.588437 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-561> 2012-09-21 08:39:31.588445 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-560> 2012-09-21 08:39:31.588470 7f5cab210700 0 mds.0.cache creating system inode with ino:604
-559> 2012-09-21 08:39:31.588639 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-558> 2012-09-21 08:39:31.588647 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-557> 2012-09-21 08:39:31.588672 7f5cab210700 0 mds.0.cache creating system inode with ino:605
-556> 2012-09-21 08:39:31.588837 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-555> 2012-09-21 08:39:31.588845 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-554> 2012-09-21 08:39:31.588870 7f5cab210700 0 mds.0.cache creating system inode with ino:606
-553> 2012-09-21 08:39:31.589033 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-552> 2012-09-21 08:39:31.589044 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-551> 2012-09-21 08:39:31.589069 7f5cab210700 0 mds.0.cache creating system inode with ino:607
-550> 2012-09-21 08:39:31.589241 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-549> 2012-09-21 08:39:31.589250 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-548> 2012-09-21 08:39:31.589275 7f5cab210700 0 mds.0.cache creating system inode with ino:608
-547> 2012-09-21 08:39:31.589445 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-546> 2012-09-21 08:39:31.589453 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-545> 2012-09-21 08:39:31.589478 7f5cab210700 0 mds.0.cache creating system inode with ino:609
-544> 2012-09-21 08:39:31.589637 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 263
-543> 2012-09-21 08:39:31.589646 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-542> 2012-09-21 08:39:31.589671 7f5cab210700 0 mds.0.cache creating system inode with ino:200
-541> 2012-09-21 08:39:31.590166 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 4117
-540> 2012-09-21 08:39:31.590174 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-539> 2012-09-21 08:39:31.590270 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 399
-538> 2012-09-21 08:39:31.590278 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-537> 2012-09-21 08:39:31.590358 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 29
-536> 2012-09-21 08:39:31.590365 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-535> 2012-09-21 08:39:31.590435 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 17
-534> 2012-09-21 08:39:31.590442 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-533> 2012-09-21 08:39:31.590524 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 29
-532> 2012-09-21 08:39:31.590531 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-531> 2012-09-21 08:39:31.590601 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 41
-530> 2012-09-21 08:39:31.590607 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-529> 2012-09-21 08:39:31.590711 7f5cab210700 5 mds.0.bal rebalance done
-528> 2012-09-21 08:39:31.590742 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 535 (0x1072c08 -> 0)
-527> 2012-09-21 08:39:31.972765 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 20 (0 -> 20)
-526> 2012-09-21 08:39:31.972870 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 12 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (807215053 0 0) 0x3a93e00 con 0x3a7fdc0
-525> 2012-09-21 08:39:31.972897 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 20 (0x1072c08 -> 0)
-524> 2012-09-21 08:39:32.327786 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 733 (0 -> 733)
-523> 2012-09-21 08:39:32.327895 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 13 ==== mdsmap(e 4) v1 ==== 733+0+0 (3824975548 0 0) 0x3a7de00 con 0x3a7fdc0
-522> 2012-09-21 08:39:32.327922 7f5cab210700 5 mds.0.1 handle_mds_map epoch 4 from mon.2
-521> 2012-09-21 08:39:32.328076 7f5cab210700 5 mds.0.bal rebalance done
-520> 2012-09-21 08:39:32.328113 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 733 (0x1072c08 -> 0)
-519> 2012-09-21 08:39:33.255035 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 618 (0 -> 618)
-518> 2012-09-21 08:39:33.255225 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 14 ==== osd_map(4..4 src has 1..4) v3 ==== 618+0+0 (280348493 0 0) 0x3a7d800 con 0x3a7fdc0
-517> 2012-09-21 08:39:33.255935 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:1 200.00000000 [writefull 0~84] 1.844f3494) v4 -- ?+0 0x3a75900 con 0x3aa18c0
-516> 2012-09-21 08:39:33.255986 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:2 200.00000001 [delete] 1.6e5f474) v4 -- ?+0 0x3adb240 con 0x3aa18c0
-515> 2012-09-21 08:39:33.256026 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:3 200.00000002 [delete] 1.eb272dbb) v4 -- ?+0 0x3adb000 con 0x3aa18c0
-514> 2012-09-21 08:39:33.256058 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:4 200.00000003 [delete] 1.95e3ab) v4 -- ?+0 0x3adbd80 con 0x3aa18c0
-513> 2012-09-21 08:39:33.256090 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:5 200.00000004 [delete] 1.1ba6bc1f) v4 -- ?+0 0x3adbb40 con 0x3aa18c0
-512> 2012-09-21 08:39:33.256122 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:6 200.00000005 [delete] 1.96d5827a) v4 -- ?+0 0x3adb900 con 0x3aa18c0
-511> 2012-09-21 08:39:33.256157 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:7 200.00000006 [delete] 1.ade7efde) v4 -- ?+0 0x3adb6c0 con 0x3aa18c0
-510> 2012-09-21 08:39:33.256281 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:8 2.00000000 [setxattr path (6),setxattr parent (38),tmapup 0~0] 1.96f33707) v4 -- ?+0 0x3b36900 con 0x3aa18c0
-509> 2012-09-21 08:39:33.256382 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:9 1.00000000 [setxattr path,setxattr parent (13),tmapput 0~557] 1.6b2cdaff) v4 -- ?+0 0x3b366c0 con 0x3aa18c0
-508> 2012-09-21 08:39:33.256454 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:10 1.00000000.inode [writefull 0~428] 1.232c0e14) v4 -- ?+0 0x3b36480 con 0x3aa18c0
-507> 2012-09-21 08:39:33.256522 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:11 600.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.c2e541b0) v4 -- ?+0 0x3b36240 con 0x3aa18c0
-506> 2012-09-21 08:39:33.256631 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:12 601.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.81580290) v4 -- ?+0 0x3b36000 con 0x3aa18c0
-505> 2012-09-21 08:39:33.256724 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:13 602.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.2a8b6bd0) v4 -- ?+0 0x3b36b40 con 0x3aa18c0
-504> 2012-09-21 08:39:33.256803 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:14 603.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.7a4d91b0) v4 -- ?+0 0x3adb240 con 0x3aa18c0
-503> 2012-09-21 08:39:33.256891 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:15 604.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.43e85c95) v4 -- ?+0 0x3adbb40 con 0x3aa18c0
-502> 2012-09-21 08:39:33.256983 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:16 605.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.d0c18e1d) v4 -- ?+0 0x3adbd80 con 0x3aa18c0
-501> 2012-09-21 08:39:33.257082 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:17 606.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.f89eaaf4) v4 -- ?+0 0x3adb000 con 0x3aa18c0
-500> 2012-09-21 08:39:33.257191 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:18 607.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.bb590b7c) v4 -- ?+0 0x3a75900 con 0x3aa18c0
-499> 2012-09-21 08:39:33.257297 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:19 608.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.c4405e8e) v4 -- ?+0 0x3b36d80 con 0x3aa18c0
-498> 2012-09-21 08:39:33.257382 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:20 609.00000000 [setxattr path (12),setxattr parent (39),tmapup 0~0] 1.60b82d07) v4 -- ?+0 0x3b36900 con 0x3aa18c0
-497> 2012-09-21 08:39:33.257474 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:21 100.00000000 [setxattr path (5),setxattr parent (13),tmapput 0~4089] 1.c5265ab3) v4 -- ?+0 0x3b36480 con 0x3aa18c0
-496> 2012-09-21 08:39:33.257547 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:22 100.00000000.inode [writefull 0~399] 1.85dde07f) v4 -- ?+0 0x3adb6c0 con 0x3aa18c0
-495> 2012-09-21 08:39:33.257614 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:23 mds0_inotable [writefull 0~29] 1.b852b893) v4 -- ?+0 0x3adb900 con 0x3aa18c0
-494> 2012-09-21 08:39:33.257660 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:24 mds0_sessionmap [writefull 0~17] 1.3270c60b) v4 -- ?+0 0x3adb240 con 0x3aa18c0
-493> 2012-09-21 08:39:33.257729 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:25 mds_anchortable [writefull 0~29] 1.a977f6a7) v4 -- ?+0 0x3b36b40 con 0x3aa18c0
-492> 2012-09-21 08:39:33.257773 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:26 mds_snaptable [writefull 0~41] 1.d90270ad) v4 -- ?+0 0x3b36000 con 0x3aa18c0
-491> 2012-09-21 08:39:33.257925 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 618 (0x1072c08 -> 0)
-490> 2012-09-21 08:39:33.257957 7f5cab210700 0 mds.0.1 ms_handle_connect on 192.168.106.221:6800/7003
-489> 2012-09-21 08:39:33.403348 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 931 (0 -> 931)
-488> 2012-09-21 08:39:33.403428 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 15 ==== mdsmap(e 5) v1 ==== 931+0+0 (1063859619 0 0) 0x3aa2e00 con 0x3a7fdc0
-487> 2012-09-21 08:39:33.403448 7f5cab210700 5 mds.0.1 handle_mds_map epoch 5 from mon.2
-486> 2012-09-21 08:39:33.403641 7f5cab210700 5 mds.0.bal rebalance done
-485> 2012-09-21 08:39:33.403681 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 931 (0x1072c08 -> 0)
-484> 2012-09-21 08:39:34.487728 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 931 (0 -> 931)
-483> 2012-09-21 08:39:34.487838 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 16 ==== mdsmap(e 6) v1 ==== 931+0+0 (26169171 0 0) 0x3aa2c00 con 0x3a7fdc0
-482> 2012-09-21 08:39:34.487880 7f5cab210700 5 mds.0.1 handle_mds_map epoch 6 from mon.2
-481> 2012-09-21 08:39:34.488049 7f5cab210700 5 mds.0.bal rebalance done
-480> 2012-09-21 08:39:34.488083 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 931 (0x1072c08 -> 0)
-479> 2012-09-21 08:39:34.505704 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:creating seq 2 v6) v2 -- ?+0 0x3a9ca00 con 0x3a7fdc0
-478> 2012-09-21 08:39:34.985640 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-477> 2012-09-21 08:39:34.985772 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 1 ==== osd_op_reply(7 200.00000006 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (2358461066 0 0) 0x3a7d800 con 0x3aa18c0
-476> 2012-09-21 08:39:34.985840 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 0 (0x1072c08 -> 8610)
-475> 2012-09-21 08:39:34.985848 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 25)
-474> 2012-09-21 08:39:34.985894 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-473> 2012-09-21 08:39:35.046833 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-472> 2012-09-21 08:39:35.046923 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 2 ==== osd_op_reply(5 200.00000004 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (4212787475 0 0) 0x3aa2600 con 0x3aa18c0
-471> 2012-09-21 08:39:35.046967 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 0 (0x1072c08 -> 8610)
-470> 2012-09-21 08:39:35.046972 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 24)
-469> 2012-09-21 08:39:35.047003 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-468> 2012-09-21 08:39:35.104247 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-467> 2012-09-21 08:39:35.104318 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 17 ==== mdsmap(e 7) v1 ==== 979+0+0 (2430714112 0 0) 0x3aa2a00 con 0x3a7fdc0
-466> 2012-09-21 08:39:35.104341 7f5cab210700 5 mds.0.1 handle_mds_map epoch 7 from mon.2
-465> 2012-09-21 08:39:35.104446 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (979 -> 1174)
-464> 2012-09-21 08:39:35.104679 7f5cab210700 5 mds.0.bal rebalance done
-463> 2012-09-21 08:39:35.104725 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 195)
-462> 2012-09-21 08:39:35.104743 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 3 ==== osd_op_reply(19 608.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3063162972 0 0) 0x3aa2800 con 0x3aa18c0
-461> 2012-09-21 08:39:35.104791 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 8347)
-460> 2012-09-21 08:39:35.104798 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 23)
-459> 2012-09-21 08:39:35.104883 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-458> 2012-09-21 08:39:35.192656 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 193 (0 -> 193)
-457> 2012-09-21 08:39:35.192780 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 4 ==== osd_op_reply(8 2.00000000 [setxattr (6),setxattr (38),tmapup 0~0] ondisk = 0) v4 ==== 193+0+0 (75409663 0 0) 0x3aa2e00 con 0x3aa18c0
-456> 2012-09-21 08:39:35.192808 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 193 (193 -> 386)
-455> 2012-09-21 08:39:35.192842 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 256 (0x1072c08 -> 8091)
-454> 2012-09-21 08:39:35.192856 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 22)
-453> 2012-09-21 08:39:35.192946 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 117 (386 -> 503)
-452> 2012-09-21 08:39:35.192974 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 193 (0x1072c08 -> 310)
-451> 2012-09-21 08:39:35.193004 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 5 ==== osd_op_reply(9 1.00000000 [setxattr,setxattr (13),tmapput 0~557] ondisk = 0) v4 ==== 193+0+0 (1320404367 0 0) 0x3b18600 con 0x3aa18c0
-450> 2012-09-21 08:39:35.193058 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 580 (0x1072c08 -> 7511)
-449> 2012-09-21 08:39:35.193064 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 21)
-448> 2012-09-21 08:39:35.193068 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 114 (310 -> 424)
-447> 2012-09-21 08:39:35.193173 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 193 (0x1072c08 -> 231)
-446> 2012-09-21 08:39:35.193194 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (231 -> 426)
-445> 2012-09-21 08:39:35.193193 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 6 ==== osd_op_reply(22 100.00000000.inode [writefull 0~399] ondisk = 0) v4 ==== 117+0+0 (1327442681 0 0) 0x3b18400 con 0x3aa18c0
-444> 2012-09-21 08:39:35.193239 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 399 (0x1072c08 -> 7112)
-443> 2012-09-21 08:39:35.193247 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 20)
-442> 2012-09-21 08:39:35.193303 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 117 (0x1072c08 -> 309)
-441> 2012-09-21 08:39:35.193319 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 7 ==== osd_op_reply(25 mds_anchortable [writefull 0~29] ondisk = 0) v4 ==== 114+0+0 (3828916659 0 0) 0x3b18200 con 0x3aa18c0
-440> 2012-09-21 08:39:35.193352 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 29 (0x1072c08 -> 7083)
-439> 2012-09-21 08:39:35.193358 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 19)
-438> 2012-09-21 08:39:35.193398 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 114 (0x1072c08 -> 195)
-437> 2012-09-21 08:39:35.193414 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 8 ==== osd_op_reply(20 609.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (4129803120 0 0) 0x3b18000 con 0x3aa18c0
-436> 2012-09-21 08:39:35.193438 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 6820)
-435> 2012-09-21 08:39:35.193441 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 18)
-434> 2012-09-21 08:39:35.193495 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-433> 2012-09-21 08:39:35.317863 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-432> 2012-09-21 08:39:35.318002 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 9 ==== osd_op_reply(2 200.00000001 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (1521823429 0 0) 0x3aa2800 con 0x3aa18c0
-431> 2012-09-21 08:39:35.318035 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 0 (0x1072c08 -> 6820)
-430> 2012-09-21 08:39:35.318039 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 17)
-429> 2012-09-21 08:39:35.318061 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-428> 2012-09-21 08:39:35.328346 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-427> 2012-09-21 08:39:35.328429 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 10 ==== osd_op_reply(6 200.00000005 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (3073949940 0 0) 0x3aa2a00 con 0x3aa18c0
-426> 2012-09-21 08:39:35.328469 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 0 (0x1072c08 -> 6820)
-425> 2012-09-21 08:39:35.328475 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 16)
-424> 2012-09-21 08:39:35.328506 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-423> 2012-09-21 08:39:35.364512 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-422> 2012-09-21 08:39:35.364588 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 18 ==== mdsbeacon(4103/a up:creating seq 2 v7) v2 ==== 103+0+0 (3238762577 0 0) 0x3a9ca00 con 0x3a7fdc0
-421> 2012-09-21 08:39:35.364640 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-420> 2012-09-21 08:39:35.365858 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-419> 2012-09-21 08:39:35.365933 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 11 ==== osd_op_reply(3 200.00000002 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (3225044059 0 0) 0x3aa2600 con 0x3aa18c0
-418> 2012-09-21 08:39:35.365975 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 0 (0x1072c08 -> 6820)
-417> 2012-09-21 08:39:35.365980 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 15)
-416> 2012-09-21 08:39:35.366070 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 131
-415> 2012-09-21 08:39:35.366076 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-414> 2012-09-21 08:39:35.366137 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:27 200.00000001 [write 0~131] 1.6e5f474) v4 -- ?+0 0x3a75480 con 0x3aa18c0
-413> 2012-09-21 08:39:35.366194 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-412> 2012-09-21 08:39:35.366522 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-411> 2012-09-21 08:39:35.366682 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 12 ==== osd_op_reply(4 200.00000003 [delete] ondisk = -2 (No such file or directory)) v4 ==== 111+0+0 (2625316585 0 0) 0x3aa2c00 con 0x3aa18c0
-410> 2012-09-21 08:39:35.366710 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 0 (0x1072c08 -> 6951)
-409> 2012-09-21 08:39:35.366713 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 15)
-408> 2012-09-21 08:39:35.366731 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-407> 2012-09-21 08:39:35.438294 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-406> 2012-09-21 08:39:35.438378 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 13 ==== osd_op_reply(1 200.00000000 [writefull 0~84] ondisk = 0) v4 ==== 111+0+0 (3741540391 0 0) 0x3a7d800 con 0x3aa18c0
-405> 2012-09-21 08:39:35.438409 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 84 (0x1072c08 -> 6867)
-404> 2012-09-21 08:39:35.438413 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 14)
-403> 2012-09-21 08:39:35.438456 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-402> 2012-09-21 08:39:35.541135 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 115 (0 -> 115)
-401> 2012-09-21 08:39:35.541231 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 14 ==== osd_op_reply(10 1.00000000.inode [writefull 0~428] ondisk = 0) v4 ==== 115+0+0 (484962512 0 0) 0x3aa2e00 con 0x3aa18c0
-400> 2012-09-21 08:39:35.541271 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 428 (0x1072c08 -> 6439)
-399> 2012-09-21 08:39:35.541278 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 13)
-398> 2012-09-21 08:39:35.541320 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 115 (0x1072c08 -> 0)
-397> 2012-09-21 08:39:35.541517 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (0 -> 195)
-396> 2012-09-21 08:39:35.541623 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 15 ==== osd_op_reply(17 606.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (1144539666 0 0) 0x3aa2600 con 0x3aa18c0
-395> 2012-09-21 08:39:35.541670 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 6176)
-394> 2012-09-21 08:39:35.541676 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 12)
-393> 2012-09-21 08:39:35.541762 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (195 -> 390)
-392> 2012-09-21 08:39:35.541777 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 195)
-391> 2012-09-21 08:39:35.541856 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 16 ==== osd_op_reply(18 607.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3068120739 0 0) 0x3aa2a00 con 0x3aa18c0
-390> 2012-09-21 08:39:35.541899 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 5913)
-389> 2012-09-21 08:39:35.541905 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 11)
-388> 2012-09-21 08:39:35.541979 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-387> 2012-09-21 08:39:35.542235 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 112 (0 -> 112)
-386> 2012-09-21 08:39:35.542339 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 17 ==== osd_op_reply(26 mds_snaptable [writefull 0~41] ondisk = 0) v4 ==== 112+0+0 (4280208072 0 0) 0x3aa2800 con 0x3aa18c0
-385> 2012-09-21 08:39:35.542379 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 41 (0x1072c08 -> 5872)
-384> 2012-09-21 08:39:35.542383 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 10)
-383> 2012-09-21 08:39:35.542413 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 112 (0x1072c08 -> 0)
-382> 2012-09-21 08:39:35.542726 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (0 -> 195)
-381> 2012-09-21 08:39:35.542826 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 18 ==== osd_op_reply(15 604.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (2615680033 0 0) 0x3b31c00 con 0x3aa18c0
-380> 2012-09-21 08:39:35.542862 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 5609)
-379> 2012-09-21 08:39:35.542869 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 9)
-378> 2012-09-21 08:39:35.542921 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-377> 2012-09-21 08:39:35.542943 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (0 -> 195)
-376> 2012-09-21 08:39:35.543036 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 19 ==== osd_op_reply(16 605.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (1875551794 0 0) 0x3b31a00 con 0x3aa18c0
-375> 2012-09-21 08:39:35.543069 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 5346)
-374> 2012-09-21 08:39:35.543076 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 8)
-373> 2012-09-21 08:39:35.543123 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-372> 2012-09-21 08:39:35.543542 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 112 (0 -> 112)
-371> 2012-09-21 08:39:35.543623 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 20 ==== osd_op_reply(23 mds0_inotable [writefull 0~29] ondisk = 0) v4 ==== 112+0+0 (3018966183 0 0) 0x3b31800 con 0x3aa18c0
-370> 2012-09-21 08:39:35.543663 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 29 (0x1072c08 -> 5317)
-369> 2012-09-21 08:39:35.543669 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 7)
-368> 2012-09-21 08:39:35.543710 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 112 (0x1072c08 -> 0)
-367> 2012-09-21 08:39:35.543730 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 114 (0 -> 114)
-366> 2012-09-21 08:39:35.543801 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 21 ==== osd_op_reply(24 mds0_sessionmap [writefull 0~17] ondisk = 0) v4 ==== 114+0+0 (3420776324 0 0) 0x3b31600 con 0x3aa18c0
-365> 2012-09-21 08:39:35.543838 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 17 (0x1072c08 -> 5300)
-364> 2012-09-21 08:39:35.543844 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 6)
-363> 2012-09-21 08:39:35.543899 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 114 (0x1072c08 -> 0)
-362> 2012-09-21 08:39:35.543937 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (0 -> 195)
-361> 2012-09-21 08:39:35.544009 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 22 ==== osd_op_reply(21 100.00000000 [setxattr (5),setxattr (13),tmapput 0~4089] ondisk = 0) v4 ==== 195+0+0 (615053910 0 0) 0x3b31400 con 0x3aa18c0
-360> 2012-09-21 08:39:35.544042 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 4117 (0x1072c08 -> 1183)
-359> 2012-09-21 08:39:35.544049 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 5)
-358> 2012-09-21 08:39:35.544137 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-357> 2012-09-21 08:39:35.544286 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (0 -> 195)
-356> 2012-09-21 08:39:35.544366 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 23 ==== osd_op_reply(11 600.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (2111839394 0 0) 0x3b31200 con 0x3aa18c0
-355> 2012-09-21 08:39:35.544408 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 920)
-354> 2012-09-21 08:39:35.544414 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 4)
-353> 2012-09-21 08:39:35.544435 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (195 -> 390)
-352> 2012-09-21 08:39:35.544489 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 195)
-351> 2012-09-21 08:39:35.544544 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 24 ==== osd_op_reply(12 601.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3731805080 0 0) 0x3b31000 con 0x3aa18c0
-350> 2012-09-21 08:39:35.544576 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 657)
-349> 2012-09-21 08:39:35.544580 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (195 -> 390)
-348> 2012-09-21 08:39:35.544583 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 3)
-347> 2012-09-21 08:39:35.544682 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 195)
-346> 2012-09-21 08:39:35.544703 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 25 ==== osd_op_reply(13 602.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (3733063563 0 0) 0x3b31a00 con 0x3aa18c0
-345> 2012-09-21 08:39:35.544727 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 195 (195 -> 390)
-344> 2012-09-21 08:39:35.544738 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 394)
-343> 2012-09-21 08:39:35.544747 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 2)
-342> 2012-09-21 08:39:35.544838 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 195)
-341> 2012-09-21 08:39:35.544857 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 26 ==== osd_op_reply(14 603.00000000 [setxattr (12),setxattr (39),tmapup 0~0] ondisk = 0) v4 ==== 195+0+0 (241941242 0 0) 0x3b31c00 con 0x3aa18c0
-340> 2012-09-21 08:39:35.544888 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 263 (0x1072c08 -> 131)
-339> 2012-09-21 08:39:35.544894 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 1)
-338> 2012-09-21 08:39:35.544941 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 195 (0x1072c08 -> 0)
-337> 2012-09-21 08:39:35.545408 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-336> 2012-09-21 08:39:35.545484 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 27 ==== osd_op_reply(27 200.00000001 [write 0~131] ondisk = 0) v4 ==== 111+0+0 (2226982505 0 0) 0x3aa2800 con 0x3aa18c0
-335> 2012-09-21 08:39:35.545517 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 131 (0x1072c08 -> 0)
-334> 2012-09-21 08:39:35.545523 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 0)
-333> 2012-09-21 08:39:35.545559 7f5cab210700 1 mds.0.1 creating_done
-332> 2012-09-21 08:39:35.545565 7f5cab210700 3 mds.0.1 request_state up:active
-331> 2012-09-21 08:39:35.545605 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 3 v7) v2 -- ?+0 0x3a9ca00 con 0x3a7fdc0
-330> 2012-09-21 08:39:35.545856 7f5cab210700 5 mds.0.log submit_entry 4194435~667 : ESubtreeMap 2 subtrees , 0 ambiguous [metablob 1, 2 dirs]
-329> 2012-09-21 08:39:35.545928 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-328> 2012-09-21 08:39:36.008666 7f5ca8f09700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3a75480 sd=18 :6803 pgs=0 cs=0 l=0).accept sd=18
-327> 2012-09-21 08:39:36.009202 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-326> 2012-09-21 08:39:36.009279 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 43 (979 -> 1022)
-325> 2012-09-21 08:39:36.009296 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 1 ==== mdsmap(e 8) v1 ==== 979+0+0 (472031258 0 0) 0x3b31e00 con 0x3aa1c80
-324> 2012-09-21 08:39:36.009337 7f5cab210700 5 mds.0.1 handle_mds_map epoch 8 from mds.2
-323> 2012-09-21 08:39:36.009535 7f5cab210700 5 mds.0.bal rebalance done
-322> 2012-09-21 08:39:36.009571 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 43)
-321> 2012-09-21 08:39:36.009586 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 2 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9200 con 0x3aa1c80
-320> 2012-09-21 08:39:36.009622 7f5cab210700 0 mds.0.cache discover_reply not yet active(|still rejoining), delaying
-319> 2012-09-21 08:39:36.009635 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 43 (0x1072c08 -> 0)
-318> 2012-09-21 08:39:36.058277 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-317> 2012-09-21 08:39:36.058371 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 19 ==== mdsmap(e 8) v1 ==== 979+0+0 (472031258 0 0) 0x3aa2000 con 0x3a7fdc0
-316> 2012-09-21 08:39:36.058392 7f5cab210700 5 mds.0.1 handle_mds_map epoch 8 from mon.2
-315> 2012-09-21 08:39:36.058397 7f5cab210700 5 mds.0.1 old map epoch 8 <= 8, discarding
-314> 2012-09-21 08:39:36.058411 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 0)
-313> 2012-09-21 08:39:36.059336 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-312> 2012-09-21 08:39:36.059439 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 20 ==== mdsbeacon(4103/a up:active seq 3 v8) v2 ==== 103+0+0 (1086776141 0 0) 0x3a9c500 con 0x3a7fdc0
-311> 2012-09-21 08:39:36.059496 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-310> 2012-09-21 08:39:39.545886 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 4 v8) v2 -- ?+0 0x3a9cc80 con 0x3a7fdc0
-309> 2012-09-21 08:39:39.747504 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-308> 2012-09-21 08:39:39.747571 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 43 (979 -> 1022)
-307> 2012-09-21 08:39:39.747609 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 3 ==== mdsmap(e 9) v1 ==== 979+0+0 (1281234092 0 0) 0x3ae9000 con 0x3aa1c80
-306> 2012-09-21 08:39:39.747629 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 43 (1022 -> 1065)
-305> 2012-09-21 08:39:39.747644 7f5cab210700 5 mds.0.1 handle_mds_map epoch 9 from mds.2
-304> 2012-09-21 08:39:39.747801 7f5cab210700 1 mds.0.1 handle_mds_map i am now mds.0.1
-303> 2012-09-21 08:39:39.747807 7f5cab210700 1 mds.0.1 handle_mds_map state change up:creating --> up:active
-302> 2012-09-21 08:39:39.747816 7f5cab210700 1 mds.0.1 active_start
-301> 2012-09-21 08:39:39.748138 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6812/7140 -- discover_reply(1 1) v2 -- ?+0 0x3b36480 con 0x3aa1c80
-300> 2012-09-21 08:39:39.748261 7f5cab210700 5 mds.0.bal rebalance done
-299> 2012-09-21 08:39:39.748318 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 86)
-298> 2012-09-21 08:39:39.748328 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 4 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9600 con 0x3aa1c80
-297> 2012-09-21 08:39:39.748434 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6812/7140 -- discover_reply(1 1) v2 -- ?+0 0x3b36b40 con 0x3aa1c80
-296> 2012-09-21 08:39:39.748461 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 43 (0x1072c08 -> 43)
-295> 2012-09-21 08:39:39.748471 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 5 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9400 con 0x3aa1c80
-294> 2012-09-21 08:39:39.748627 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6812/7140 -- discover_reply(1 1) v2 -- ?+0 0x3b36900 con 0x3aa1c80
-293> 2012-09-21 08:39:39.748662 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 43 (0x1072c08 -> 0)
-292> 2012-09-21 08:39:39.903463 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-291> 2012-09-21 08:39:39.903553 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 21 ==== mdsmap(e 9) v1 ==== 979+0+0 (1281234092 0 0) 0x3aa2200 con 0x3a7fdc0
-290> 2012-09-21 08:39:39.903572 7f5cab210700 5 mds.0.1 handle_mds_map epoch 9 from mon.2
-289> 2012-09-21 08:39:39.903574 7f5cab210700 5 mds.0.1 old map epoch 9 <= 9, discarding
-288> 2012-09-21 08:39:39.903586 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 0)
-287> 2012-09-21 08:39:40.506105 7f5ca990c700 5 throttle(objecter_bytes 0x3a94238) take 671
-286> 2012-09-21 08:39:40.506115 7f5ca990c700 5 throttle(objecter_ops 0x3a942b8) take 1
-285> 2012-09-21 08:39:40.506170 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:28 200.00000001 [write 131~671] 1.6e5f474) v4 -- ?+0 0x3a75900 con 0x3aa18c0
-284> 2012-09-21 08:39:40.506507 7f5ca990c700 2 mds.0.cache check_memory_usage total 150424, rss 19264, heap 96280, malloc 8579 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-283> 2012-09-21 08:39:40.527734 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-282> 2012-09-21 08:39:40.527823 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 28 ==== osd_op_reply(28 200.00000001 [write 131~671] ondisk = 0) v4 ==== 111+0+0 (1541244549 0 0) 0x3aa2a00 con 0x3aa18c0
-281> 2012-09-21 08:39:40.527867 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 671 (0x1072c08 -> 0)
-280> 2012-09-21 08:39:40.527874 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 0)
-279> 2012-09-21 08:39:40.527919 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-278> 2012-09-21 08:39:43.546192 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 5 v9) v2 -- ?+0 0x3ae6500 con 0x3a7fdc0
-277> 2012-09-21 08:39:43.660472 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-276> 2012-09-21 08:39:43.660555 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 22 ==== mdsbeacon(4103/a up:active seq 5 v9) v2 ==== 103+0+0 (4012509064 0 0) 0x3ae6500 con 0x3a7fdc0
-275> 2012-09-21 08:39:43.660604 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-274> 2012-09-21 08:39:44.283582 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-273> 2012-09-21 08:39:44.283666 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 23 ==== mdsmap(e 10) v1 ==== 979+0+0 (136734347 0 0) 0x3aa2400 con 0x3a7fdc0
-272> 2012-09-21 08:39:44.283687 7f5cab210700 5 mds.0.1 handle_mds_map epoch 10 from mon.2
-271> 2012-09-21 08:39:44.283852 7f5cab210700 5 mds.0.1 handle_mds_recovery mds.1
-270> 2012-09-21 08:39:44.283939 7f5cab210700 5 mds.0.bal rebalance done
-269> 2012-09-21 08:39:44.283971 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 0)
-268> 2012-09-21 08:39:44.284541 7f5ca8d07700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3b36000 sd=19 :6803 pgs=0 cs=0 l=0).accept sd=19
-267> 2012-09-21 08:39:44.285108 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 979 (0 -> 979)
-266> 2012-09-21 08:39:44.285168 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 43 (979 -> 1022)
-265> 2012-09-21 08:39:44.285171 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 1 ==== mdsmap(e 10) v1 ==== 979+0+0 (136734347 0 0) 0x3ae9800 con 0x3aeb3c0
-264> 2012-09-21 08:39:44.285201 7f5cab210700 5 mds.0.1 handle_mds_map epoch 10 from mds.1
-263> 2012-09-21 08:39:44.285211 7f5cab210700 5 mds.0.1 old map epoch 10 <= 10, discarding
-262> 2012-09-21 08:39:44.285227 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 979 (0x1072c08 -> 43)
-261> 2012-09-21 08:39:44.285265 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 2 ==== discover(1 1.* ) v1 ==== 43+0+0 (1733826840 0 0) 0x3ae9c00 con 0x3aeb3c0
-260> 2012-09-21 08:39:44.285437 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6805/7080 -- discover_reply(1 1) v2 -- ?+0 0x3b36240 con 0x3aeb3c0
-259> 2012-09-21 08:39:44.285467 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 43 (0x1072c08 -> 0)
-258> 2012-09-21 08:39:45.506484 7f5ca990c700 2 mds.0.cache check_memory_usage total 152480, rss 19380, heap 98328, malloc 8572 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-257> 2012-09-21 08:39:47.546484 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 6 v10) v2 -- ?+0 0x3ae6280 con 0x3a7fdc0
-256> 2012-09-21 08:39:47.547832 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-255> 2012-09-21 08:39:47.547971 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 24 ==== mdsbeacon(4103/a up:active seq 6 v10) v2 ==== 103+0+0 (2762297722 0 0) 0x3a9cc80 con 0x3a7fdc0
-254> 2012-09-21 08:39:47.548021 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-253> 2012-09-21 08:39:50.506724 7f5ca990c700 2 mds.0.cache check_memory_usage total 152480, rss 19380, heap 98328, malloc 8573 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-252> 2012-09-21 08:39:50.506859 7f5ca990c700 5 mds.0.bal mds.0 epoch 1 load mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85>
-251> 2012-09-21 08:39:50.506907 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- HB v1 -- ?+0 0x3b36b40
-250> 2012-09-21 08:39:50.506951 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- HB v1 -- ?+0 0x3b36480
-249> 2012-09-21 08:39:50.507593 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 293 (0 -> 293)
-248> 2012-09-21 08:39:50.507752 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 293 (293 -> 586)
-247> 2012-09-21 08:39:50.507729 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 6 ==== HB v1 ==== 293+0+0 (409344659 0 0) 0x3b36480 con 0x3aa1c80
-246> 2012-09-21 08:39:50.507832 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 293 (0x1072c08 -> 293)
-245> 2012-09-21 08:39:50.507877 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 3 ==== HB v1 ==== 293+0+0 (409344659 0 0) 0x3b36b40 con 0x3aeb3c0
-244> 2012-09-21 08:39:50.507947 7f5cab210700 5 mds.0.bal prep_rebalance: cluster loads are
-243> 2012-09-21 08:39:50.507974 7f5cab210700 0 mds.0.bal mds.0 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85> = 0 ~ 0
-242> 2012-09-21 08:39:50.508009 7f5cab210700 0 mds.0.bal mds.1 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85> = 0 ~ 0
-241> 2012-09-21 08:39:50.508038 7f5cab210700 0 mds.0.bal mds.2 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.85> = 0 ~ 0
-240> 2012-09-21 08:39:50.508058 7f5cab210700 5 mds.0.bal prep_rebalance: my load 0 target 0 total 0
-239> 2012-09-21 08:39:50.508062 7f5cab210700 5 mds.0.bal i am sufficiently overloaded
-238> 2012-09-21 08:39:50.508120 7f5cab210700 5 mds.0.bal rebalance done
-237> 2012-09-21 08:39:50.508140 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 293 (0x1072c08 -> 0)
-236> 2012-09-21 08:39:51.546791 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 7 v10) v2 -- ?+0 0x3ae6000 con 0x3a7fdc0
-235> 2012-09-21 08:39:51.548314 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-234> 2012-09-21 08:39:51.548383 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 25 ==== mdsbeacon(4103/a up:active seq 7 v10) v2 ==== 103+0+0 (3716951069 0 0) 0x3a9ca00 con 0x3a7fdc0
-233> 2012-09-21 08:39:51.548441 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-232> 2012-09-21 08:39:55.506949 7f5ca990c700 2 mds.0.cache check_memory_usage total 152480, rss 19396, heap 98328, malloc 8581 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-231> 2012-09-21 08:39:55.547080 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 8 v10) v2 -- ?+0 0x3ae6500 con 0x3a7fdc0
-230> 2012-09-21 08:39:55.548966 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-229> 2012-09-21 08:39:55.549041 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 26 ==== mdsbeacon(4103/a up:active seq 8 v10) v2 ==== 103+0+0 (1891719410 0 0) 0x3ae6c80 con 0x3a7fdc0
-228> 2012-09-21 08:39:55.549078 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-227> 2012-09-21 08:39:59.547385 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 9 v10) v2 -- ?+0 0x3a9cc80 con 0x3a7fdc0
-226> 2012-09-21 08:39:59.549009 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-225> 2012-09-21 08:39:59.549123 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 27 ==== mdsbeacon(4103/a up:active seq 9 v10) v2 ==== 103+0+0 (166208917 0 0) 0x3ae6a00 con 0x3a7fdc0
-224> 2012-09-21 08:39:59.549184 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-223> 2012-09-21 08:40:00.507158 7f5ca990c700 2 mds.0.cache check_memory_usage total 152480, rss 19396, heap 98328, malloc 8584 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-222> 2012-09-21 08:40:00.507297 7f5ca990c700 5 mds.0.bal mds.0 epoch 2 load mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56>
-221> 2012-09-21 08:40:00.507335 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- HB v1 -- ?+0 0x3ade6c0
-220> 2012-09-21 08:40:00.507365 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- HB v1 -- ?+0 0x3ade480
-219> 2012-09-21 08:40:00.507925 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 293 (0 -> 293)
-218> 2012-09-21 08:40:00.508012 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 4 ==== HB v1 ==== 293+0+0 (354905568 0 0) 0x3ade6c0 con 0x3aeb3c0
-217> 2012-09-21 08:40:00.508094 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 293 (0x1072c08 -> 0)
-216> 2012-09-21 08:40:00.508196 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 293 (0 -> 293)
-215> 2012-09-21 08:40:00.508341 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 7 ==== HB v1 ==== 293+0+0 (354905568 0 0) 0x3ade480 con 0x3aa1c80
-214> 2012-09-21 08:40:00.508415 7f5cab210700 5 mds.0.bal prep_rebalance: cluster loads are
-213> 2012-09-21 08:40:00.508440 7f5cab210700 0 mds.0.bal mds.0 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56> = 0 ~ 0
-212> 2012-09-21 08:40:00.508471 7f5cab210700 0 mds.0.bal mds.1 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56> = 0 ~ 0
-211> 2012-09-21 08:40:00.508501 7f5cab210700 0 mds.0.bal mds.2 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.56> = 0 ~ 0
-210> 2012-09-21 08:40:00.508525 7f5cab210700 5 mds.0.bal prep_rebalance: my load 0 target 0 total 0
-209> 2012-09-21 08:40:00.508530 7f5cab210700 5 mds.0.bal i am sufficiently overloaded
-208> 2012-09-21 08:40:00.508592 7f5cab210700 5 mds.0.bal rebalance done
-207> 2012-09-21 08:40:00.508611 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 293 (0x1072c08 -> 0)
-206> 2012-09-21 08:40:03.547688 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 10 v10) v2 -- ?+0 0x3a9c500 con 0x3a7fdc0
-205> 2012-09-21 08:40:03.549355 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-204> 2012-09-21 08:40:03.549470 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 28 ==== mdsbeacon(4103/a up:active seq 10 v10) v2 ==== 103+0+0 (2190713404 0 0) 0x3ae6780 con 0x3a7fdc0
-203> 2012-09-21 08:40:03.549505 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-202> 2012-09-21 08:40:05.507402 7f5ca990c700 2 mds.0.cache check_memory_usage total 152480, rss 19396, heap 98328, malloc 8589 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-201> 2012-09-21 08:40:07.548001 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 11 v10) v2 -- ?+0 0x3a9c780 con 0x3a7fdc0
-200> 2012-09-21 08:40:07.549620 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-199> 2012-09-21 08:40:07.549757 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 29 ==== mdsbeacon(4103/a up:active seq 11 v10) v2 ==== 103+0+0 (4223328091 0 0) 0x3a9cc80 con 0x3a7fdc0
-198> 2012-09-21 08:40:07.549834 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-197> 2012-09-21 08:40:10.507612 7f5ca990c700 2 mds.0.cache check_memory_usage total 152480, rss 19396, heap 98328, malloc 8591 mmap 0, baseline 96280, buffers 0, max 1048576, 0 / 14 inodes have caps, 0 caps, 0 caps per inode
-196> 2012-09-21 08:40:10.507739 7f5ca990c700 5 mds.0.bal mds.0 epoch 3 load mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32>
-195> 2012-09-21 08:40:10.507778 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- HB v1 -- ?+0 0x3ade240
-194> 2012-09-21 08:40:10.507810 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- HB v1 -- ?+0 0x3aded80
-193> 2012-09-21 08:40:10.508440 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 293 (0 -> 293)
-192> 2012-09-21 08:40:10.508590 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 5 ==== HB v1 ==== 293+0+0 (240180757 0 0) 0x3ade240 con 0x3aeb3c0
-191> 2012-09-21 08:40:10.508645 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 293 (293 -> 586)
-190> 2012-09-21 08:40:10.508671 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 293 (0x1072c08 -> 293)
-189> 2012-09-21 08:40:10.508776 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 8 ==== HB v1 ==== 293+0+0 (240180757 0 0) 0x3aded80 con 0x3aa1c80
-188> 2012-09-21 08:40:10.508833 7f5cab210700 5 mds.0.bal prep_rebalance: cluster loads are
-187> 2012-09-21 08:40:10.508852 7f5cab210700 0 mds.0.bal mds.0 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32> = 0 ~ 0
-186> 2012-09-21 08:40:10.508894 7f5cab210700 0 mds.0.bal mds.1 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32> = 0 ~ 0
-185> 2012-09-21 08:40:10.508922 7f5cab210700 0 mds.0.bal mds.2 mdsload<[0,0 0]/[0,0 0], req 0, hr 0, qlen 0, cpu 1.32> = 0 ~ 0
-184> 2012-09-21 08:40:10.508942 7f5cab210700 5 mds.0.bal prep_rebalance: my load 0 target 0 total 0
-183> 2012-09-21 08:40:10.508947 7f5cab210700 5 mds.0.bal i am sufficiently overloaded
-182> 2012-09-21 08:40:10.508998 7f5cab210700 5 mds.0.bal rebalance done
-181> 2012-09-21 08:40:10.509017 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 293 (0x1072c08 -> 0)
-180> 2012-09-21 08:40:10.958671 7f5ca8b05700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3b36900 sd=20 :6803 pgs=0 cs=0 l=0).accept sd=20
-179> 2012-09-21 08:40:10.958963 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 28 (0 -> 28)
-178> 2012-09-21 08:40:10.959045 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 1 ==== client_session(request_open) v1 ==== 28+0+0 (0 0 0) 0x3b13000 con 0x3aeb140
-177> 2012-09-21 08:40:10.959088 7f5cab210700 3 mds.0.server handle_client_session client_session(request_open) v1 from client.4107
-176> 2012-09-21 08:40:10.959132 7f5cab210700 5 mds.0.log submit_entry 4195106~179 : ESession client.4107 192.168.106.221:0/1007208 open cmapv 1
-175> 2012-09-21 08:40:10.959318 7f5cab210700 5 mds.0.log submit_entry 4195289~667 : ESubtreeMap 2 subtrees , 0 ambiguous [metablob 1, 2 dirs]
-174> 2012-09-21 08:40:10.959415 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 854
-173> 2012-09-21 08:40:10.959423 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-172> 2012-09-21 08:40:10.959481 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:29 200.00000001 [write 802~854] 1.6e5f474) v4 -- ?+0 0x3aded80 con 0x3aa18c0
-171> 2012-09-21 08:40:10.959587 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 84
-170> 2012-09-21 08:40:10.959596 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-169> 2012-09-21 08:40:10.959645 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:30 200.00000000 [writefull 0~84] 1.844f3494) v4 -- ?+0 0x3ade240 con 0x3aa18c0
-168> 2012-09-21 08:40:10.959721 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 28 (0x1072c08 -> 0)
-167> 2012-09-21 08:40:10.985630 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-166> 2012-09-21 08:40:10.985769 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 29 ==== osd_op_reply(29 200.00000001 [write 802~854] ondisk = 0) v4 ==== 111+0+0 (3617161125 0 0) 0x3aa2600 con 0x3aa18c0
-165> 2012-09-21 08:40:10.985806 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 854 (0x1072c08 -> 84)
-164> 2012-09-21 08:40:10.985812 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 1)
-163> 2012-09-21 08:40:10.985846 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> client.4107 192.168.106.221:0/1007208 -- client_session(open) v1 -- ?+0 0x3a93e00
-162> 2012-09-21 08:40:10.985882 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-161> 2012-09-21 08:40:10.986342 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 28 (0 -> 28)
-160> 2012-09-21 08:40:10.986419 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 2 ==== client_session(request_renewcaps seq 1) v1 ==== 28+0+0 (968950716 0 0) 0x3a93e00 con 0x3aeb140
-159> 2012-09-21 08:40:10.986454 7f5cab210700 3 mds.0.server handle_client_session client_session(request_renewcaps seq 1) v1 from client.4107
-158> 2012-09-21 08:40:10.986470 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(renewcaps seq 1) v1 -- ?+0 0x3a93a80 con 0x3aeb140
-157> 2012-09-21 08:40:10.986496 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 28 (0x1072c08 -> 0)
-156> 2012-09-21 08:40:10.986626 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 114 (0 -> 114)
-155> 2012-09-21 08:40:10.986711 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 3 ==== client_request(client.4107:1 getattr pAsLsXsFs #1) v1 ==== 114+0+0 (4011615131 0 0) 0x3ae6c80 con 0x3aeb140
-154> 2012-09-21 08:40:10.986769 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4107:1 getattr pAsLsXsFs #1) v1
-153> 2012-09-21 08:40:10.987079 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:1 = 0 Success) v1 -- ?+0 0x3a9cc80 con 0x3aeb140
-152> 2012-09-21 08:40:10.987261 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 114 (0x1072c08 -> 0)
-151> 2012-09-21 08:40:10.988243 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 128 (0 -> 128)
-150> 2012-09-21 08:40:10.988318 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 4 ==== client_request(client.4107:2 lookup #1/open_test_7208) v1 ==== 128+0+0 (1226895642 0 0) 0x3a9cc80 con 0x3aeb140
-149> 2012-09-21 08:40:10.988346 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4107:2 lookup #1/open_test_7208) v1
-148> 2012-09-21 08:40:10.988458 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:2 = -2 No such file or directory) v1 -- ?+0 0x3ae6c80 con 0x3aeb140
-147> 2012-09-21 08:40:10.988510 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 128 (0x1072c08 -> 0)
-146> 2012-09-21 08:40:10.989287 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 172 (0 -> 172)
-145> 2012-09-21 08:40:10.989359 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 5 ==== client_request(client.4107:3 mkdir #1/open_test_7208) v1 ==== 172+0+0 (3702870140 0 0) 0x3ae6c80 con 0x3aeb140
-144> 2012-09-21 08:40:10.989384 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4107:3 mkdir #1/open_test_7208) v1
-143> 2012-09-21 08:40:10.989607 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3ade480
-142> 2012-09-21 08:40:10.989751 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- mdsmap(e 10) v1 -- ?+0 0x3aa2600
-141> 2012-09-21 08:40:10.989776 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3ade6c0
-140> 2012-09-21 08:40:10.989845 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 172 (0x1072c08 -> 0)
-139> 2012-09-21 08:40:10.990139 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 64 (0 -> 64)
-138> 2012-09-21 08:40:10.990203 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 6 ==== lock(a=mixack ifile 1.head) v1 ==== 64+0+0 (3574224453 0 0) 0x3ade480 con 0x3aeb3c0
-137> 2012-09-21 08:40:10.990259 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 64 (0x1072c08 -> 0)
-136> 2012-09-21 08:40:10.990309 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 64 (0 -> 64)
-135> 2012-09-21 08:40:10.990369 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 9 ==== lock(a=mixack ifile 1.head) v1 ==== 64+0+0 (1226401603 0 0) 0x3ade6c0 con 0x3aa1c80
-134> 2012-09-21 08:40:10.990454 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3ade480
-133> 2012-09-21 08:40:10.990497 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix ifile 1.head) v1 -- ?+0 0x3b36b40
-132> 2012-09-21 08:40:10.990690 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3b36480
-131> 2012-09-21 08:40:10.990746 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3adb240
-130> 2012-09-21 08:40:10.990861 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 64 (0x1072c08 -> 0)
-129> 2012-09-21 08:40:10.991093 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 64 (0 -> 64)
-128> 2012-09-21 08:40:10.991108 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 64 (64 -> 128)
-127> 2012-09-21 08:40:10.991195 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 10 ==== lock(a=mixack inest 1.head) v1 ==== 64+0+0 (1378384186 0 0) 0x3adb240 con 0x3aa1c80
-126> 2012-09-21 08:40:10.991234 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 64 (0x1072c08 -> 64)
-125> 2012-09-21 08:40:10.991247 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 7 ==== lock(a=mixack inest 1.head) v1 ==== 64+0+0 (3459991612 0 0) 0x3b36480 con 0x3aeb3c0
-124> 2012-09-21 08:40:10.991314 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3adb240
-123> 2012-09-21 08:40:10.991361 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=mix inest 1.head) v1 -- ?+0 0x3ade6c0
-122> 2012-09-21 08:40:10.991962 7f5cab210700 5 mds.0.log submit_entry 4195960~1497 : EUpdate mkdir [metablob 1, 2 dirs alloc_ino=10000000000 prealloc_ino=[10000000001~3e8] v2]
-121> 2012-09-21 08:40:10.992115 7f5cab210700 5 mds.0.log submit_entry 4197461~667 : ESubtreeMap 2 subtrees , 0 ambiguous [metablob 1, 2 dirs]
-120> 2012-09-21 08:40:10.992207 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 2172
-119> 2012-09-21 08:40:10.992215 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-118> 2012-09-21 08:40:10.992262 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:31 200.00000001 [write 1656~2172] 1.6e5f474) v4 -- ?+0 0x3adb900 con 0x3aa18c0
-117> 2012-09-21 08:40:10.992341 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 64 (0x1072c08 -> 0)
-116> 2012-09-21 08:40:11.010498 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-115> 2012-09-21 08:40:11.010589 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 30 ==== osd_op_reply(30 200.00000000 [writefull 0~84] ondisk = 0) v4 ==== 111+0+0 (715516510 0 0) 0x3aa2e00 con 0x3aa18c0
-114> 2012-09-21 08:40:11.010634 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 84 (0x1072c08 -> 2172)
-113> 2012-09-21 08:40:11.010641 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 1)
-112> 2012-09-21 08:40:11.010672 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-111> 2012-09-21 08:40:11.027391 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-110> 2012-09-21 08:40:11.027533 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 31 ==== osd_op_reply(31 200.00000001 [write 1656~2172] ondisk = 0) v4 ==== 111+0+0 (2082473059 0 0) 0x3aa2c00 con 0x3aa18c0
-109> 2012-09-21 08:40:11.027565 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 2172 (0x1072c08 -> 0)
-108> 2012-09-21 08:40:11.027571 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 0)
-107> 2012-09-21 08:40:11.027799 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:3 = 0 Success safe) v1 -- ?+0 0x3a9cc80 con 0x3aeb140
-106> 2012-09-21 08:40:11.027907 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-105> 2012-09-21 08:40:11.029132 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 175 (0 -> 175)
-104> 2012-09-21 08:40:11.029330 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 6 ==== client_request(client.4107:4 create #10000000000/created_file_7208) v1 ==== 175+0+0 (521926727 0 0) 0x3a9cc80 con 0x3aeb140
-103> 2012-09-21 08:40:11.029387 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4107:4 create #10000000000/created_file_7208) v1
-102> 2012-09-21 08:40:11.029706 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_caps(revoke ino 10000000000 2 seq 3 caps=pAsLsXsxFsx dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:10.991530) v2 -- ?+0 0x3a94340 con 0x3aeb140
-101> 2012-09-21 08:40:11.029776 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 175 (0x1072c08 -> 0)
-100> 2012-09-21 08:40:11.030472 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 180 (0 -> 180)
-99> 2012-09-21 08:40:11.030591 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 7 ==== client_caps(update ino 10000000000 2 seq 3 caps=pAsLsXsFs dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:10.991530) v2 ==== 180+0+0 (905707034 0 0) 0x3a94340 con 0x3aeb140
-98> 2012-09-21 08:40:11.031125 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:4 = 0 Success unsafe) v1 -- ?+0 0x3ae6c80 con 0x3aeb140
-97> 2012-09-21 08:40:11.031285 7f5cab210700 5 mds.0.log submit_entry 4198132~1427 : EUpdate openc [metablob 1, 2 dirs prealloc_ino=[100000003e9~1] used_prealloc_ino=10000000001 v3]
-96> 2012-09-21 08:40:11.031453 7f5cab210700 5 mds.0.log submit_entry 4199563~667 : ESubtreeMap 2 subtrees , 0 ambiguous [metablob 1, 2 dirs]
-95> 2012-09-21 08:40:11.031643 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 180 (0x1072c08 -> 0)
-94> 2012-09-21 08:40:11.548307 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 12 v10) v2 -- ?+0 0x3ad2a00 con 0x3a7fdc0
-93> 2012-09-21 08:40:11.549953 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-92> 2012-09-21 08:40:11.550120 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 30 ==== mdsbeacon(4103/a up:active seq 12 v10) v2 ==== 103+0+0 (2441665439 0 0) 0x3ae6500 con 0x3a7fdc0
-91> 2012-09-21 08:40:11.550182 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-90> 2012-09-21 08:40:11.956967 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 28 (0 -> 28)
-89> 2012-09-21 08:40:11.957109 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 8 ==== client_session(request_renewcaps seq 2) v1 ==== 28+0+0 (4076679903 0 0) 0x3a93a80 con 0x3aeb140
-88> 2012-09-21 08:40:11.957156 7f5cab210700 3 mds.0.server handle_client_session client_session(request_renewcaps seq 2) v1 from client.4107
-87> 2012-09-21 08:40:11.957180 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(renewcaps seq 2) v1 -- ?+0 0x3a93e00 con 0x3aeb140
-86> 2012-09-21 08:40:11.957226 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 28 (0x1072c08 -> 0)
-85> 2012-09-21 08:40:15.507662 7f5ca990c700 5 throttle(objecter_bytes 0x3a94238) take 2102
-84> 2012-09-21 08:40:15.507676 7f5ca990c700 5 throttle(objecter_ops 0x3a942b8) take 1
-83> 2012-09-21 08:40:15.507741 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:32 200.00000001 [write 3828~2102] 1.6e5f474) v4 -- ?+0 0x3adeb40 con 0x3aa18c0
-82> 2012-09-21 08:40:15.508062 7f5ca990c700 2 mds.0.cache check_memory_usage total 154536, rss 19916, heap 100376, malloc 8673 mmap 0, baseline 96280, buffers 0, max 1048576, 3 / 16 inodes have caps, 3 caps, 0.1875 caps per inode
-81> 2012-09-21 08:40:15.530902 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-80> 2012-09-21 08:40:15.531005 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 32 ==== osd_op_reply(32 200.00000001 [write 3828~2102] ondisk = 0) v4 ==== 111+0+0 (3015535970 0 0) 0x3a7d800 con 0x3aa18c0
-79> 2012-09-21 08:40:15.531052 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 2102 (0x1072c08 -> 0)
-78> 2012-09-21 08:40:15.531060 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 0)
-77> 2012-09-21 08:40:15.531344 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:4 = 0 Success safe) v1 -- ?+0 0x3ae6500 con 0x3aeb140
-76> 2012-09-21 08:40:15.531502 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-75> 2012-09-21 08:40:15.532620 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 180 (0 -> 180)
-74> 2012-09-21 08:40:15.532691 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 180 (180 -> 360)
-73> 2012-09-21 08:40:15.532749 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 9 ==== client_caps(update ino 10000000001 3 seq 1 caps=p dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:11.030810) v2 ==== 180+0+0 (4181515013 0 0) 0x3a94680 con 0x3aeb140
-72> 2012-09-21 08:40:15.532859 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 180 (0x1072c08 -> 180)
-71> 2012-09-21 08:40:15.532888 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 180 (180 -> 360)
-70> 2012-09-21 08:40:15.532876 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 10 ==== client_caps(update ino 1 1 seq 3 caps=p dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:10.991530) v2 ==== 180+0+0 (2554533167 0 0) 0x3a94d00 con 0x3aeb140
-69> 2012-09-21 08:40:15.532977 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 180 (0x1072c08 -> 180)
-68> 2012-09-21 08:40:15.532993 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 11 ==== client_caps(update ino 10000000000 2 seq 4 caps=p dirty=- wanted=- follows 0 size 0/0 ts 1 mtime 2012-09-21 08:40:11.030810) v2 ==== 180+0+0 (1678527455 0 0) 0x3a949c0 con 0x3aeb140
-67> 2012-09-21 08:40:15.533072 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 180 (0x1072c08 -> 0)
-66> 2012-09-21 08:40:15.533415 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 28 (0 -> 28)
-65> 2012-09-21 08:40:15.533542 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4107 192.168.106.221:0/1007208 12 ==== client_session(request_close seq 1) v1 ==== 28+0+0 (4220035607 0 0) 0x3a93e00 con 0x3aeb140
-64> 2012-09-21 08:40:15.533568 7f5cab210700 3 mds.0.server handle_client_session client_session(request_close seq 1) v1 from client.4107
-63> 2012-09-21 08:40:15.533619 7f5cab210700 5 mds.0.log submit_entry 4200234~195 : ESession client.4107 192.168.106.221:0/1007208 close cmapv 5 (1000 inos, v4)
-62> 2012-09-21 08:40:15.533805 7f5cab210700 5 mds.0.log submit_entry 4200433~667 : ESubtreeMap 2 subtrees , 0 ambiguous [metablob 1, 2 dirs]
-61> 2012-09-21 08:40:15.533879 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 870
-60> 2012-09-21 08:40:15.533886 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-59> 2012-09-21 08:40:15.533922 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:33 200.00000001 [write 5930~870] 1.6e5f474) v4 -- ?+0 0x3b36480 con 0x3aa18c0
-58> 2012-09-21 08:40:15.533970 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 28 (0x1072c08 -> 0)
-57> 2012-09-21 08:40:15.548642 7f5ca990c700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6791/0 -- mdsbeacon(4103/a up:active seq 13 v10) v2 -- ?+0 0x3ad2780 con 0x3a7fdc0
-56> 2012-09-21 08:40:15.550366 7f5ca9a0d700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 103 (0 -> 103)
-55> 2012-09-21 08:40:15.550484 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mon.2 192.168.106.221:6791/0 31 ==== mdsbeacon(4103/a up:active seq 13 v10) v2 ==== 103+0+0 (3902908152 0 0) 0x3ae6000 con 0x3a7fdc0
-54> 2012-09-21 08:40:15.550523 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 103 (0x1072c08 -> 0)
-53> 2012-09-21 08:40:15.572562 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-52> 2012-09-21 08:40:15.572664 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 33 ==== osd_op_reply(33 200.00000001 [write 5930~870] ondisk = 0) v4 ==== 111+0+0 (3111485359 0 0) 0x3ae9e00 con 0x3aa18c0
-51> 2012-09-21 08:40:15.572697 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 870 (0x1072c08 -> 0)
-50> 2012-09-21 08:40:15.572704 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 0)
-49> 2012-09-21 08:40:15.572835 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(close) v1 -- ?+0 0x3a93e00 con 0x3aeb140
-48> 2012-09-21 08:40:15.572864 7f5cab210700 1 -- 192.168.106.221:6803/7026 mark_disposable 0x3aeb140 -- 0x3b36900
-47> 2012-09-21 08:40:15.572898 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-46> 2012-09-21 08:40:15.573975 7f5cab210700 0 mds.0.1 ms_handle_reset on 192.168.106.221:0/1007208
-45> 2012-09-21 08:40:15.574044 7f5cab210700 1 -- 192.168.106.221:6803/7026 mark_down 192.168.106.221:0/1007208 -- pipe dne
-44> 2012-09-21 08:40:15.577905 7f5ca8b05700 1 -- 192.168.106.221:6803/7026 >> :/0 pipe(0x3b13d80 sd=20 :6803 pgs=0 cs=0 l=0).accept sd=20
-43> 2012-09-21 08:40:15.578152 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 28 (0 -> 28)
-42> 2012-09-21 08:40:15.578209 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 1 ==== client_session(request_open) v1 ==== 28+0+0 (0 0 0) 0x3a93e00 con 0x3b12280
-41> 2012-09-21 08:40:15.578233 7f5cab210700 3 mds.0.server handle_client_session client_session(request_open) v1 from client.4110
-40> 2012-09-21 08:40:15.579042 7f5cab210700 5 mds.0.log submit_entry 4201104~179 : ESession client.4110 192.168.106.221:0/1007208 open cmapv 6
-39> 2012-09-21 08:40:15.579202 7f5cab210700 5 mds.0.log submit_entry 4201287~667 : ESubtreeMap 2 subtrees , 0 ambiguous [metablob 1, 2 dirs]
-38> 2012-09-21 08:40:15.579278 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) take 854
-37> 2012-09-21 08:40:15.579286 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) take 1
-36> 2012-09-21 08:40:15.579330 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:6800/7003 -- osd_op(mds.0.1:34 200.00000001 [write 6800~854] 1.6e5f474) v4 -- ?+0 0x3adb000 con 0x3aa18c0
-35> 2012-09-21 08:40:15.579378 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 28 (0x1072c08 -> 0)
-34> 2012-09-21 08:40:15.614213 7f5ca900a700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 111 (0 -> 111)
-33> 2012-09-21 08:40:15.614306 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== osd.0 192.168.106.221:6800/7003 34 ==== osd_op_reply(34 200.00000001 [write 6800~854] ondisk = 0) v4 ==== 111+0+0 (2178446690 0 0) 0x3ad6e00 con 0x3aa18c0
-32> 2012-09-21 08:40:15.614337 7f5cab210700 5 throttle(objecter_bytes 0x3a94238) put 854 (0x1072c08 -> 0)
-31> 2012-09-21 08:40:15.614341 7f5cab210700 5 throttle(objecter_ops 0x3a942b8) put 1 (0x1072c08 -> 0)
-30> 2012-09-21 08:40:15.614367 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> client.4110 192.168.106.221:0/1007208 -- client_session(open) v1 -- ?+0 0x3a93e00
-29> 2012-09-21 08:40:15.614400 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 111 (0x1072c08 -> 0)
-28> 2012-09-21 08:40:15.614801 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 28 (0 -> 28)
-27> 2012-09-21 08:40:15.614860 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 2 ==== client_session(request_renewcaps seq 1) v1 ==== 28+0+0 (968950716 0 0) 0x3a93e00 con 0x3b12280
-26> 2012-09-21 08:40:15.614882 7f5cab210700 3 mds.0.server handle_client_session client_session(request_renewcaps seq 1) v1 from client.4110
-25> 2012-09-21 08:40:15.614892 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_session(renewcaps seq 1) v1 -- ?+0 0x3a93a80 con 0x3b12280
-24> 2012-09-21 08:40:15.614915 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 28 (0x1072c08 -> 0)
-23> 2012-09-21 08:40:15.615061 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 114 (0 -> 114)
-22> 2012-09-21 08:40:15.615146 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 3 ==== client_request(client.4110:1 getattr pAsLsXsFs #1) v1 ==== 114+0+0 (4011615131 0 0) 0x3ae6c80 con 0x3b12280
-21> 2012-09-21 08:40:15.615179 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4110:1 getattr pAsLsXsFs #1) v1
-20> 2012-09-21 08:40:15.615299 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3adb6c0
-19> 2012-09-21 08:40:15.615333 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3adbb40
-18> 2012-09-21 08:40:15.615395 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 114 (0x1072c08 -> 0)
-17> 2012-09-21 08:40:15.615724 7f5ca8f09700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 110 (0 -> 110)
-16> 2012-09-21 08:40:15.615791 7f5ca8d07700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 110 (110 -> 220)
-15> 2012-09-21 08:40:15.615851 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.2 192.168.106.221:6812/7140 11 ==== lock(a=syncack ifile 1.head) v1 ==== 110+0+0 (3421590228 0 0) 0x3adbb40 con 0x3aa1c80
-14> 2012-09-21 08:40:15.615926 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 110 (0x1072c08 -> 110)
-13> 2012-09-21 08:40:15.615944 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== mds.1 192.168.106.221:6805/7080 8 ==== lock(a=syncack ifile 1.head) v1 ==== 110+0+0 (843043591 0 0) 0x3adb6c0 con 0x3aeb3c0
-12> 2012-09-21 08:40:15.616019 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.1 192.168.106.221:6805/7080 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3adbb40
-11> 2012-09-21 08:40:15.616060 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> mds.2 192.168.106.221:6812/7140 -- lock(a=sync ifile 1.head) v1 -- ?+0 0x3ad7d80
-10> 2012-09-21 08:40:15.616312 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:1 = 0 Success) v1 -- ?+0 0x3ae6000 con 0x3b12280
-9> 2012-09-21 08:40:15.616504 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 110 (0x1072c08 -> 0)
-8> 2012-09-21 08:40:15.616995 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 128 (0 -> 128)
-7> 2012-09-21 08:40:15.617118 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 4 ==== client_request(client.4110:2 lookup #1/open_test_7208) v1 ==== 128+0+0 (4086801148 0 0) 0x3ae6000 con 0x3b12280
-6> 2012-09-21 08:40:15.617152 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4110:2 lookup #1/open_test_7208) v1
-5> 2012-09-21 08:40:15.617334 7f5cab210700 1 -- 192.168.106.221:6803/7026 --> 192.168.106.221:0/1007208 -- client_reply(???:2 = 0 Success) v1 -- ?+0 0x3ae6c80 con 0x3b12280
-4> 2012-09-21 08:40:15.617442 7f5cab210700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) put 128 (0x1072c08 -> 0)
-3> 2012-09-21 08:40:15.618214 7f5ca8b05700 5 throttle(msgr_dispatch_throttler-mds 0x3a85488) get 114 (0 -> 114)
-2> 2012-09-21 08:40:15.618323 7f5cab210700 1 -- 192.168.106.221:6803/7026 <== client.4110 192.168.106.221:0/1007208 5 ==== client_request(client.4110:3 lookup #10000000000) v1 ==== 114+0+0 (191063834 0 0) 0x3ae6c80 con 0x3b12280
-1> 2012-09-21 08:40:15.618351 7f5cab210700 4 mds.0.server handle_client_request client_request(client.4110:3 lookup #10000000000) v1
0> 2012-09-21 08:40:15.625316 7f5cab210700 -1 *** Caught signal (Segmentation fault) **
in thread 7f5cab210700
ceph version (commit:)
1: (ceph::BackTrace::BackTrace(int)+0x33) [0xb2665f]
2: /home/slang/dev/ceph/build-wip-2285/src/ceph-mds() [0xc868e8]
3: (()+0xef60) [0x7f5cafc7df60]
4: (Server::handle_client_getattr(MDRequest*, bool)+0x548) [0x8a4bc8]
5: (Server::dispatch_client_request(MDRequest*)+0x3a6) [0x89bb1e]
6: (Server::handle_client_request(MClientRequest*)+0xd91) [0x89b767]
7: (Server::dispatch(Message*)+0x513) [0x8910ad]
8: (MDS::handle_deferrable_message(Message*)+0x7e7) [0x81299b]
9: (MDS::_dispatch(Message*)+0x238) [0x813f1a]
10: (MDS::ms_dispatch(Message*)+0x1c9) [0x8110db]
11: (Messenger::ms_deliver_dispatch(Message*)+0x94) [0xbf19e2]
12: (DispatchQueue::entry()+0xaae) [0xbf1484]
13: (DispatchQueue::DispatchThread::entry()+0x22) [0xb9c7c0]
14: (Thread::_entry_func(void*)+0x29) [0xc02601]
15: (()+0x68ba) [0x7f5cafc758ba]
16: (clone()+0x6d) [0x7f5cae4fe02d]
NOTE: a copy of the executable, or `objdump -rdS <executable>` is needed to interpret this.
--- logging levels ---
0/ 5 none
0/ 5 lockdep
0/ 5 context
1/ 5 crush
1/ 5 mds
1/ 5 mds_balancer
1/ 5 mds_locker
1/ 5 mds_log
1/ 5 mds_log_expire
1/ 5 mds_migrator
0/ 0 buffer
0/ 5 timer
0/ 5 filer
0/ 0 objecter
0/ 5 rados
0/ 5 rbd
0/ 5 journaler
0/ 5 objectcacher
0/ 5 client
0/ 5 osd
0/ 5 optracker
0/ 5 objclass
1/ 5 filestore
1/ 5 journal
1/ 1 ms
1/ 5 mon
0/ 5 monc
0/ 5 paxos
0/ 5 tp
1/ 5 auth
1/ 5 finisher
1/ 5 heartbeatmap
1/ 5 perfcounter
1/ 5 rgw
1/ 5 hadoop
1/ 5 asok
1/ 5 throttle
-2/-2 (syslog threshold)
-1/-1 (stderr threshold)
max_recent 10000
max_new 1000000
log_file out/mds.a.log
--- end dump of recent events ---
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment