gistfile1.txt

2011-06-03 07:19:33.260709 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720510 192.168.100.2:0/1031296 76 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2447d80 con 0x243a780
2011-06-03 07:19:33.260727 7f8665d5f700 osd0 34 _dispatch 0x2447d80 ping v1
2011-06-03 07:19:33.260735 7f8665d5f700 osd0 34 ping from client1720510
2011-06-03 07:19:33.674574 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720350 192.168.100.2:0/1017516 76 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449780 con 0x243a640
2011-06-03 07:19:33.674592 7f8665d5f700 osd0 34 _dispatch 0x2449780 ping v1
2011-06-03 07:19:33.674600 7f8665d5f700 osd0 34 ping from client1720350
2011-06-03 07:19:33.716229 7f866c56c700 osd0 34 tick
2011-06-03 07:19:33.716287 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:33.716300 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:33.716308 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:33.716315 7f866c56c700 osd0 34 do_mon_report
2011-06-03 07:19:33.716322 7f866c56c700 osd0 34 send_alive up_thru currently 0 want 0
2011-06-03 07:19:33.716328 7f866c56c700 osd0 34 send_pg_stats
2011-06-03 07:19:33.931225 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:34.531437 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:34.716475 7f866c56c700 osd0 34 tick
2011-06-03 07:19:34.716533 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:34.716547 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:34.716554 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:35.231676 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:35.716702 7f866c56c700 osd0 34 tick
2011-06-03 07:19:35.716760 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:35.931914 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:36.716913 7f866c56c700 osd0 34 tick
2011-06-03 07:19:36.716971 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:36.932142 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:36.964978 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mds0 192.168.100.7:6800/3403 80 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2444600 con 0x243a500
2011-06-03 07:19:36.964999 7f8665d5f700 osd0 34 _dispatch 0x2444600 ping v1
2011-06-03 07:19:36.965010 7f8665d5f700 osd0 34 ping from mds0
2011-06-03 07:19:37.702912 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720602 192.168.100.2:0/1018565 76 ==== ping v1 ==== 0+0+0 (0 0 0) 0x241f300 con 0x243ab40
2011-06-03 07:19:37.702931 7f8665d5f700 osd0 34 _dispatch 0x241f300 ping v1
2011-06-03 07:19:37.702939 7f8665d5f700 osd0 34 ping from client1720602
2011-06-03 07:19:37.717124 7f866c56c700 osd0 34 tick
2011-06-03 07:19:37.717182 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:37.732387 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:37.884797 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720518 192.168.100.2:0/1005545 76 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244d180 con 0x2433c80
2011-06-03 07:19:37.884816 7f8665d5f700 osd0 34 _dispatch 0x244d180 ping v1
2011-06-03 07:19:37.884824 7f8665d5f700 osd0 34 ping from client1720518
2011-06-03 07:19:38.162592 7f866a568700 filestore(/data/osd0) sync_entry woke after 5.000144
2011-06-03 07:19:38.162614 7f866a568700 journal commit_start op_seq 555719, applied_seq 555719, committed_seq 555719
2011-06-03 07:19:38.162625 7f866a568700 journal commit_start nothing to do
2011-06-03 07:19:38.162634 7f866a568700 journal commit_start
2011-06-03 07:19:38.162641 7f866a568700 filestore(/data/osd0) sync_entry waiting for max_interval 5.000000
2011-06-03 07:19:38.261003 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720510 192.168.100.2:0/1031296 77 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2447780 con 0x243a780
2011-06-03 07:19:38.261021 7f8665d5f700 osd0 34 _dispatch 0x2447780 ping v1
2011-06-03 07:19:38.261029 7f8665d5f700 osd0 34 ping from client1720510
2011-06-03 07:19:38.332624 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:38.674736 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720350 192.168.100.2:0/1017516 77 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449000 con 0x243a640
2011-06-03 07:19:38.674755 7f8665d5f700 osd0 34 _dispatch 0x2449000 ping v1
2011-06-03 07:19:38.674763 7f8665d5f700 osd0 34 ping from client1720350
2011-06-03 07:19:38.717339 7f866c56c700 osd0 34 tick
2011-06-03 07:19:38.717396 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:38.717411 7f866c56c700 osd0 34 do_mon_report
2011-06-03 07:19:38.717419 7f866c56c700 osd0 34 send_alive up_thru currently 0 want 0
2011-06-03 07:19:38.717425 7f866c56c700 osd0 34 send_pg_stats
2011-06-03 07:19:38.832855 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:39.533078 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:39.717569 7f866c56c700 osd0 34 tick
2011-06-03 07:19:39.717626 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:40.717763 7f866c56c700 osd0 34 tick
2011-06-03 07:19:40.717820 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:40.933311 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:41.717974 7f866c56c700 osd0 34 tick
2011-06-03 07:19:41.718032 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:41.965119 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mds0 192.168.100.7:6800/3403 81 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2444780 con 0x243a500
2011-06-03 07:19:41.965140 7f8665d5f700 osd0 34 _dispatch 0x2444780 ping v1
2011-06-03 07:19:41.965153 7f8665d5f700 osd0 34 ping from mds0
2011-06-03 07:19:42.033548 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:42.703166 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720602 192.168.100.2:0/1018565 77 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449480 con 0x243ab40
2011-06-03 07:19:42.703186 7f8665d5f700 osd0 34 _dispatch 0x2449480 ping v1
2011-06-03 07:19:42.703194 7f8665d5f700 osd0 34 ping from client1720602
2011-06-03 07:19:42.718193 7f866c56c700 osd0 34 tick
2011-06-03 07:19:42.718251 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:42.718265 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:42.718272 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:42.885045 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720518 192.168.100.2:0/1005545 77 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244d000 con 0x2433c80
2011-06-03 07:19:42.885064 7f8665d5f700 osd0 34 _dispatch 0x244d000 ping v1
2011-06-03 07:19:42.885072 7f8665d5f700 osd0 34 ping from client1720518
2011-06-03 07:19:43.162772 7f866a568700 filestore(/data/osd0) sync_entry woke after 5.000127
2011-06-03 07:19:43.162794 7f866a568700 journal commit_start op_seq 555719, applied_seq 555719, committed_seq 555719
2011-06-03 07:19:43.162805 7f866a568700 journal commit_start nothing to do
2011-06-03 07:19:43.162814 7f866a568700 journal commit_start
2011-06-03 07:19:43.162821 7f866a568700 filestore(/data/osd0) sync_entry waiting for max_interval 5.000000
2011-06-03 07:19:43.261202 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720510 192.168.100.2:0/1031296 78 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244dd80 con 0x243a780
2011-06-03 07:19:43.261220 7f8665d5f700 osd0 34 _dispatch 0x244dd80 ping v1
2011-06-03 07:19:43.261228 7f8665d5f700 osd0 34 ping from client1720510
2011-06-03 07:19:43.333777 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:43.674889 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720350 192.168.100.2:0/1017516 78 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2444c00 con 0x243a640
2011-06-03 07:19:43.674915 7f8665d5f700 osd0 34 _dispatch 0x2444c00 ping v1
2011-06-03 07:19:43.674924 7f8665d5f700 osd0 34 ping from client1720350
2011-06-03 07:19:43.718421 7f866c56c700 osd0 34 tick
2011-06-03 07:19:43.718480 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:43.718494 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:43.718501 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:43.718508 7f866c56c700 osd0 34 do_mon_report
2011-06-03 07:19:43.718515 7f866c56c700 osd0 34 send_alive up_thru currently 0 want 0
2011-06-03 07:19:43.718521 7f866c56c700 osd0 34 send_pg_stats
2011-06-03 07:19:44.718666 7f866c56c700 osd0 34 tick
2011-06-03 07:19:44.718724 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:44.734009 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:44.734077 7f8661c56700 -- 192.168.100.7:6801/19647 --> 192.168.100.7:6789/0 -- mon_subscribe({monmap=2+,osdmap=35}) v1 -- ?+0 0x24381c0 con 0x2432280
2011-06-03 07:19:44.734582 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mon0 192.168.100.7:6789/0 24 ==== mon_subscribe_ack(300s) v1 ==== 20+0+0 (2684212871 0 0) 0x2449600 con 0x2432280
2011-06-03 07:19:45.718873 7f866c56c700 osd0 34 tick
2011-06-03 07:19:45.718931 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:45.718945 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:45.718952 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:45.934278 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:46.719100 7f866c56c700 osd0 34 tick
2011-06-03 07:19:46.719158 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:46.719172 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:46.719179 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:46.734512 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:46.965262 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mds0 192.168.100.7:6800/3403 82 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244bd80 con 0x243a500
2011-06-03 07:19:46.965286 7f8665d5f700 osd0 34 _dispatch 0x244bd80 ping v1
2011-06-03 07:19:46.965297 7f8665d5f700 osd0 34 ping from mds0
2011-06-03 07:19:47.703487 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720602 192.168.100.2:0/1018565 78 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449300 con 0x243ab40
2011-06-03 07:19:47.703509 7f8665d5f700 osd0 34 _dispatch 0x2449300 ping v1
2011-06-03 07:19:47.703522 7f8665d5f700 osd0 34 ping from client1720602
2011-06-03 07:19:47.719328 7f866c56c700 osd0 34 tick
2011-06-03 07:19:47.719385 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:47.834753 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:47.885449 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720518 192.168.100.2:0/1005545 78 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2448d80 con 0x2433c80
2011-06-03 07:19:47.885470 7f8665d5f700 osd0 34 _dispatch 0x2448d80 ping v1
2011-06-03 07:19:47.885482 7f8665d5f700 osd0 34 ping from client1720518
2011-06-03 07:19:48.162969 7f866a568700 filestore(/data/osd0) sync_entry woke after 5.000145
2011-06-03 07:19:48.162991 7f866a568700 journal commit_start op_seq 555719, applied_seq 555719, committed_seq 555719
2011-06-03 07:19:48.163002 7f866a568700 journal commit_start nothing to do
2011-06-03 07:19:48.163011 7f866a568700 journal commit_start
2011-06-03 07:19:48.163019 7f866a568700 filestore(/data/osd0) sync_entry waiting for max_interval 5.000000
2011-06-03 07:19:48.261533 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720510 192.168.100.2:0/1031296 79 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244d600 con 0x243a780
2011-06-03 07:19:48.261554 7f8665d5f700 osd0 34 _dispatch 0x244d600 ping v1
2011-06-03 07:19:48.261566 7f8665d5f700 osd0 34 ping from client1720510
2011-06-03 07:19:48.675121 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720350 192.168.100.2:0/1017516 79 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2444180 con 0x243a640
2011-06-03 07:19:48.675142 7f8665d5f700 osd0 34 _dispatch 0x2444180 ping v1
2011-06-03 07:19:48.675154 7f8665d5f700 osd0 34 ping from client1720350
2011-06-03 07:19:48.719539 7f866c56c700 osd0 34 tick
2011-06-03 07:19:48.719596 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:48.719611 7f866c56c700 osd0 34 do_mon_report
2011-06-03 07:19:48.719618 7f866c56c700 osd0 34 send_alive up_thru currently 0 want 0
2011-06-03 07:19:48.719625 7f866c56c700 osd0 34 send_pg_stats
2011-06-03 07:19:49.234989 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:49.719757 7f866c56c700 osd0 34 tick
2011-06-03 07:19:49.719816 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:49.719829 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:49.719836 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:50.135213 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:50.719987 7f866c56c700 osd0 34 tick
2011-06-03 07:19:50.720045 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:51.135430 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:51.635659 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:51.720195 7f866c56c700 osd0 34 tick
2011-06-03 07:19:51.720254 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:51.720277 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:51.720285 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:51.965395 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mds0 192.168.100.7:6800/3403 83 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244bc00 con 0x243a500
2011-06-03 07:19:51.965418 7f8665d5f700 osd0 34 _dispatch 0x244bc00 ping v1
2011-06-03 07:19:51.965430 7f8665d5f700 osd0 34 ping from mds0
2011-06-03 07:19:52.335898 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:52.703732 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720602 192.168.100.2:0/1018565 79 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449180 con 0x243ab40
2011-06-03 07:19:52.703754 7f8665d5f700 osd0 34 _dispatch 0x2449180 ping v1
2011-06-03 07:19:52.703766 7f8665d5f700 osd0 34 ping from client1720602
2011-06-03 07:19:52.720434 7f866c56c700 osd0 34 tick
2011-06-03 07:19:52.720491 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:52.720505 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:52.720512 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:52.885699 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720518 192.168.100.2:0/1005545 79 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2448c00 con 0x2433c80
2011-06-03 07:19:52.885720 7f8665d5f700 osd0 34 _dispatch 0x2448c00 ping v1
2011-06-03 07:19:52.885732 7f8665d5f700 osd0 34 ping from client1720518
2011-06-03 07:19:53.163163 7f866a568700 filestore(/data/osd0) sync_entry woke after 5.000141
2011-06-03 07:19:53.163185 7f866a568700 journal commit_start op_seq 555719, applied_seq 555719, committed_seq 555719
2011-06-03 07:19:53.163196 7f866a568700 journal commit_start nothing to do
2011-06-03 07:19:53.163205 7f866a568700 journal commit_start
2011-06-03 07:19:53.163213 7f866a568700 filestore(/data/osd0) sync_entry waiting for max_interval 5.000000
2011-06-03 07:19:53.261768 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720510 192.168.100.2:0/1031296 80 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244d780 con 0x243a780
2011-06-03 07:19:53.261789 7f8665d5f700 osd0 34 _dispatch 0x244d780 ping v1
2011-06-03 07:19:53.261802 7f8665d5f700 osd0 34 ping from client1720510
2011-06-03 07:19:53.436138 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:53.675332 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720350 192.168.100.2:0/1017516 80 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2444a80 con 0x243a640
2011-06-03 07:19:53.675353 7f8665d5f700 osd0 34 _dispatch 0x2444a80 ping v1
2011-06-03 07:19:53.675366 7f8665d5f700 osd0 34 ping from client1720350
2011-06-03 07:19:53.720644 7f866c56c700 osd0 34 tick
2011-06-03 07:19:53.720702 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:53.720717 7f866c56c700 osd0 34 do_mon_report
2011-06-03 07:19:53.720724 7f866c56c700 osd0 34 send_alive up_thru currently 0 want 0
2011-06-03 07:19:53.720730 7f866c56c700 osd0 34 send_pg_stats
2011-06-03 07:19:54.636364 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:54.720869 7f866c56c700 osd0 34 tick
2011-06-03 07:19:54.720931 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:55.636595 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:55.721053 7f866c56c700 osd0 34 tick
2011-06-03 07:19:55.721114 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:55.721127 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:55.721135 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:56.721269 7f866c56c700 osd0 34 tick
2011-06-03 07:19:56.721326 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:19:56.721340 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:19:56.721347 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:19:56.836801 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:56.965523 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mds0 192.168.100.7:6800/3403 84 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244ba80 con 0x243a500
2011-06-03 07:19:56.965549 7f8665d5f700 osd0 34 _dispatch 0x244ba80 ping v1
2011-06-03 07:19:56.965561 7f8665d5f700 osd0 34 ping from mds0
2011-06-03 07:19:57.703970 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720602 192.168.100.2:0/1018565 80 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449900 con 0x243ab40
2011-06-03 07:19:57.703991 7f8665d5f700 osd0 34 _dispatch 0x2449900 ping v1
2011-06-03 07:19:57.704003 7f8665d5f700 osd0 34 ping from client1720602
2011-06-03 07:19:57.721493 7f866c56c700 osd0 34 tick
2011-06-03 07:19:57.721551 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:57.737040 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:57.886003 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720518 192.168.100.2:0/1005545 80 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2448a80 con 0x2433c80
2011-06-03 07:19:57.886024 7f8665d5f700 osd0 34 _dispatch 0x2448a80 ping v1
2011-06-03 07:19:57.886037 7f8665d5f700 osd0 34 ping from client1720518
2011-06-03 07:19:58.163360 7f866a568700 filestore(/data/osd0) sync_entry woke after 5.000143
2011-06-03 07:19:58.163381 7f866a568700 journal commit_start op_seq 555719, applied_seq 555719, committed_seq 555719
2011-06-03 07:19:58.163392 7f866a568700 journal commit_start nothing to do
2011-06-03 07:19:58.163401 7f866a568700 journal commit_start
2011-06-03 07:19:58.163409 7f866a568700 filestore(/data/osd0) sync_entry waiting for max_interval 5.000000
2011-06-03 07:19:58.262007 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720510 192.168.100.2:0/1031296 81 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244d900 con 0x243a780
2011-06-03 07:19:58.262028 7f8665d5f700 osd0 34 _dispatch 0x244d900 ping v1
2011-06-03 07:19:58.262041 7f8665d5f700 osd0 34 ping from client1720510
2011-06-03 07:19:58.437270 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:58.675500 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720350 192.168.100.2:0/1017516 81 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2444d80 con 0x243a640
2011-06-03 07:19:58.675529 7f8665d5f700 osd0 34 _dispatch 0x2444d80 ping v1
2011-06-03 07:19:58.675542 7f8665d5f700 osd0 34 ping from client1720350
2011-06-03 07:19:58.721709 7f866c56c700 osd0 34 tick
2011-06-03 07:19:58.721767 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:19:58.721782 7f866c56c700 osd0 34 do_mon_report
2011-06-03 07:19:58.721789 7f866c56c700 osd0 34 send_alive up_thru currently 0 want 0
2011-06-03 07:19:58.721795 7f866c56c700 osd0 34 send_pg_stats
2011-06-03 07:19:59.537501 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:19:59.721938 7f866c56c700 osd0 34 tick
2011-06-03 07:19:59.721995 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:20:00.437727 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:20:00.722131 7f866c56c700 osd0 34 tick
2011-06-03 07:20:00.722189 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:20:01.237960 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:20:01.722344 7f866c56c700 osd0 34 tick
2011-06-03 07:20:01.722402 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = no, randomly backing off
2011-06-03 07:20:01.738182 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:20:01.965653 7f8665d5f700 -- 192.168.100.7:6801/19647 <== mds0 192.168.100.7:6800/3403 85 ==== ping v1 ==== 0+0+0 (0 0 0) 0x244b900 con 0x243a500
2011-06-03 07:20:01.965678 7f8665d5f700 osd0 34 _dispatch 0x244b900 ping v1
2011-06-03 07:20:01.965690 7f8665d5f700 osd0 34 ping from mds0
2011-06-03 07:20:02.704233 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720602 192.168.100.2:0/1018565 81 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2449a80 con 0x243ab40
2011-06-03 07:20:02.704254 7f8665d5f700 osd0 34 _dispatch 0x2449a80 ping v1
2011-06-03 07:20:02.704266 7f8665d5f700 osd0 34 ping from client1720602
2011-06-03 07:20:02.722558 7f866c56c700 osd0 34 tick
2011-06-03 07:20:02.722616 7f866c56c700 osd0 34 scrub_should_schedule loadavg 0 < max 0.5 = yes
2011-06-03 07:20:02.722630 7f866c56c700 osd0 34 sched_scrub
2011-06-03 07:20:02.722637 7f866c56c700 osd0 34 sched_scrub done
2011-06-03 07:20:02.738436 7f8661c56700 osd0 34 update_osd_stat osd_stat(15148 MB used, 131 GB avail, 146 GB total, peers []/[])
2011-06-03 07:20:02.886200 7f8665d5f700 -- 192.168.100.7:6801/19647 <== client1720518 192.168.100.2:0/1005545 81 ==== ping v1 ==== 0+0+0 (0 0 0) 0x2448900 con 0x2433c80
2011-06-03 07:20:02.886229 7f8665d5f700 osd0 34 _dispatch 0x2448900 ping v1
2011-06-03 07:20:02.886241 7f8665d5f700 osd0 34 ping from client1720518
2011-06-03 07:20:03.163558 7f866a568700 filestore(/data/osd0) sync_entry woke after 5.000146