From 33a9d85b4c699f3d83274bf8a0ece42930d4ab89 Mon Sep 17 00:00:00 2001 From: ben Date: Wed, 22 May 2002 00:16:02 +0000 Subject: Just some temporary profiling information that it might be interesting to keep for a while git-svn-id: http://svn.savannah.nongnu.org/svn/rdiff-backup/trunk@101 2b77aa54-bcbc-44c9-a7ec-4f6cf2b41109 --- rdiff-backup/rdiff_backup/temp-stats | 241 +++++++++++++++++++++++++++++++++++ rdiff-backup/src/temp-stats | 241 +++++++++++++++++++++++++++++++++++ 2 files changed, 482 insertions(+) create mode 100644 rdiff-backup/rdiff_backup/temp-stats create mode 100644 rdiff-backup/src/temp-stats diff --git a/rdiff-backup/rdiff_backup/temp-stats b/rdiff-backup/rdiff_backup/temp-stats new file mode 100644 index 0000000..0efea64 --- /dev/null +++ b/rdiff-backup/rdiff_backup/temp-stats @@ -0,0 +1,241 @@ +No changes in 10000 files: + +Wed May 15 23:54:53 2002 profile-output + + 1805774 function calls (1480846 primitive calls) in 45.710 CPU seconds + + Ordered by: internal time + List reduced from 259 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) +151824/30615 5.620 0.000 18.810 0.001 lazy.py:251(__call__) +91212/30612 4.060 0.000 17.300 0.001 lazy.py:222(process_w_subinstance) + 31006 2.630 0.000 4.650 0.000 rpath.py:448(setdata) +101629/20412 1.890 0.000 14.690 0.001 selection.py:96(Iterate) +61218/20410 1.880 0.000 14.370 0.001 selection.py:122(iterate_in_dir) + 10206 1.400 0.000 44.620 0.004 highlevel.py:245(error_checked) +30618/10206 1.350 0.000 27.650 0.003 rorpiter.py:124(Collate2Iters) + 486 1.280 0.003 19.690 0.041 highlevel.py:165(generate_dissimilar) + 30748 1.270 0.000 6.640 0.000 rpath.py:408(__init__) + 31006 1.260 0.000 1.260 0.000 rpath.py:182(tupled_lstat) + 121209 1.140 0.000 1.140 0.000 lazy.py:214(intree) + 20411 1.110 0.000 9.010 0.000 destructive_stepping.py:33(__init__) + 10205 1.050 0.000 1.050 0.000 rpath.py:430(__str__) + 30612 0.860 0.000 1.410 0.000 lazy.py:218(set_subinstance) + 20411 0.840 0.000 2.830 0.000 destructive_stepping.py:77(set_init_perms) + 486 0.830 0.002 26.900 0.055 highlevel.py:98(diffs) + 30771 0.780 0.000 0.780 0.000 /usr/lib/python2.2/posixpath.py:44(join) + 10205 0.720 0.000 3.870 0.000 increment.py:138(start_process) + 91845 0.710 0.000 0.710 0.000 rorpiter.py:246(__getitem__) + 20616 0.690 0.000 0.930 0.000 rpath.py:593(isowner) + + +Wed May 15 23:54:53 2002 profile-output + + 1805774 function calls (1480846 primitive calls) in 45.710 CPU seconds + + Ordered by: cumulative time + List reduced from 259 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.010 0.010 45.710 45.710 profile:0(Globals.Main.Main(['--no-resume', 'manyfiles/', 'out'])) + 1 0.000 0.000 45.700 45.700 main.py:188(Main) + 1 0.000 0.000 45.700 45.700 :1(?) + 1 0.000 0.000 45.670 45.670 main.py:169(take_action) + 1 0.000 0.000 45.670 45.670 main.py:221(Backup) + 1 0.000 0.000 45.650 45.650 highlevel.py:50(Mirror_and_increment) + 1 0.240 0.240 45.650 45.650 highlevel.py:239(patch_increment_and_finalize) + 10208 0.270 0.000 44.890 0.004 highlevel.py:268(check_skip_error) + 10206 1.400 0.000 44.620 0.004 highlevel.py:245(error_checked) +30618/10206 1.350 0.000 27.650 0.003 rorpiter.py:124(Collate2Iters) + 486 0.830 0.002 26.900 0.055 highlevel.py:98(diffs) + 486 0.020 0.000 19.720 0.041 rorpiter.py:58(Signatures) + 486 1.280 0.003 19.690 0.041 highlevel.py:165(generate_dissimilar) +151824/30615 5.620 0.000 18.810 0.001 lazy.py:251(__call__) +91212/30612 4.060 0.000 17.300 0.001 lazy.py:222(process_w_subinstance) + 20412 0.520 0.000 14.900 0.001 lazy.py:166(yielda) +101629/20412 1.890 0.000 14.690 0.001 selection.py:96(Iterate) +61218/20410 1.880 0.000 14.370 0.001 selection.py:122(iterate_in_dir) + 41229 0.660 0.000 11.180 0.000 robust.py:194(check_common_error) + 20409 0.310 0.000 9.810 0.000 selection.py:130() + +---------------------------------------------------------------------------- + +Writing 10000 new small files + +Thu May 16 00:05:36 2002 profile-output + + 2452569 function calls (2249461 primitive calls) in 75.680 CPU seconds + + Ordered by: internal time + List reduced from 236 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 71752 4.580 0.000 4.580 0.000 rpath.py:182(tupled_lstat) + 71752 4.170 0.000 9.860 0.000 rpath.py:448(setdata) +101216/20410 3.550 0.000 11.070 0.001 lazy.py:251(__call__) +60808/20408 2.690 0.000 9.980 0.000 lazy.py:222(process_w_subinstance) + 10206 2.460 0.000 74.630 0.007 highlevel.py:217(error_checked) + 20012 2.080 0.000 2.820 0.000 rpath.py:674(open) + 40819 2.050 0.000 13.440 0.000 destructive_stepping.py:33(__init__) + 10228 2.050 0.000 5.030 0.000 rpath.py:625(normalize) + 10000 1.980 0.000 8.060 0.001 rpath.py:684(write_from_fileobj) + 51086 1.970 0.000 9.210 0.000 rpath.py:408(__init__) + 143692 1.640 0.000 1.640 0.000 connection.py:40(__getattr__) + 10206 1.610 0.000 8.920 0.001 highlevel.py:165(generate_dissimilar) +30618/10206 1.550 0.000 29.540 0.003 rorpiter.py:124(Collate2Iters) + 10205 1.520 0.000 1.520 0.000 rpath.py:227(__str__) + 20410 1.450 0.000 1.710 0.000 rpath.py:525(setmtime) + 10226 1.380 0.000 1.670 0.000 rpath.py:163(rename) + 20000 1.380 0.000 1.380 0.000 rpath.py:772(read) + 10206 1.350 0.000 3.490 0.000 rpath.py:109(copy_attribs) + 40819 1.290 0.000 3.100 0.000 destructive_stepping.py:77(set_init_perms) +50817/10208 1.250 0.000 7.890 0.001 selection.py:96(Iterate) + + +Thu May 16 00:05:36 2002 profile-output + + 2452569 function calls (2249461 primitive calls) in 75.680 CPU seconds + + Ordered by: cumulative time + List reduced from 236 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.000 0.000 75.680 75.680 profile:0(Globals.Main.Main(['manyfiles/', 'out'])) + 1 0.000 0.000 75.680 75.680 :1(?) + 1 0.000 0.000 75.680 75.680 main.py:188(Main) + 1 0.000 0.000 75.670 75.670 main.py:221(Backup) + 1 0.000 0.000 75.670 75.670 main.py:169(take_action) + 1 0.000 0.000 75.650 75.650 highlevel.py:27(Mirror) + 1 0.450 0.450 75.650 75.650 highlevel.py:210(patch_and_finalize) + 10206 0.190 0.000 74.820 0.007 highlevel.py:268(check_skip_error) + 10206 2.460 0.000 74.630 0.007 highlevel.py:217(error_checked) +30618/10206 1.550 0.000 29.540 0.003 rorpiter.py:124(Collate2Iters) + 10206 1.060 0.000 29.020 0.003 highlevel.py:98(diffs) + 10216 0.270 0.000 28.800 0.003 robust.py:54(execute) + 10205 1.140 0.000 22.760 0.002 robust.py:148(init) + 40819 2.050 0.000 13.440 0.000 destructive_stepping.py:33(__init__) +101216/20410 3.550 0.000 11.070 0.001 lazy.py:251(__call__) +60808/20408 2.690 0.000 9.980 0.000 lazy.py:222(process_w_subinstance) + 10206 0.570 0.000 9.860 0.001 rorpiter.py:58(Signatures) + 71752 4.170 0.000 9.860 0.000 rpath.py:448(setdata) + 10226 0.540 0.000 9.770 0.001 robust.py:240(new) + 51086 1.970 0.000 9.210 0.000 rpath.py:408(__init__) + +---------------------------------------------------------------------------- +Nothing changed, source side: + + ncalls tottime percall cumtime percall filename:lineno(function) +50608/10205 1.970 0.000 5.420 0.001 lazy.py:251(__call__) + 10208/1 1.260 0.000 17.740 17.740 iterfile.py:166(addtobuffer) +30404/10204 1.240 0.000 5.010 0.000 lazy.py:222(process_w_subinstance) + 1 1.180 1.180 17.740 17.740 highlevel.py:98(diffs) + 10221 1.050 0.000 1.930 0.000 rpath.py:448(setdata) +50814/10206 0.880 0.000 7.610 0.001 selection.py:96(Iterate) +30609/10205 0.780 0.000 7.420 0.001 selection.py:122(iterate_in_dir) + 10205 0.600 0.000 0.600 0.000 iterfile.py:197(_l2s) + 10204 0.600 0.000 0.700 0.000 lazy.py:218(set_subinstance) + 10215 0.550 0.000 2.760 0.000 rpath.py:408(__init__) + 10206 0.470 0.000 4.900 0.000 destructive_stepping.py:33(__init__) + 10206 0.430 0.000 1.570 0.000 destructive_stepping.py:77(set_init_perms) + 10411 0.430 0.000 0.610 0.000 rpath.py:593(isowner) + 10206 0.390 0.000 0.390 0.000 lazy.py:177(yieldb) + 10206 0.380 0.000 10.860 0.001 rorpiter.py:124(Collate2Iters) + 10208 0.370 0.000 0.370 0.000 rpath.py:182(tupled_lstat) + 20614 0.360 0.000 6.010 0.000 robust.py:194(check_common_error) + 40403 0.330 0.000 0.330 0.000 lazy.py:214(intree) + 10205 0.320 0.000 0.980 0.000 rpath.py:581(readable) + 10205 0.320 0.000 0.790 0.000 destructive_stepping.py:206(end_process) + + +Thu May 16 00:21:24 2002 profile-output + + 646330 function calls (503964 primitive calls) in 17.820 CPU seconds + + Ordered by: cumulative time + List reduced from 213 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.000 0.000 17.820 17.820 :1(?) + 1 0.000 0.000 17.820 17.820 profile:0(Globals.Main.Main(['--remote-schema', '%s --server', 'manyfiles/', './rdiff-backup::out'])) + 1 0.000 0.000 17.820 17.820 main.py:188(Main) + 1 0.000 0.000 17.770 17.770 main.py:221(Backup) + 1 0.000 0.000 17.770 17.770 main.py:169(take_action) + 47/46 0.000 0.000 17.750 0.386 connection.py:425(__call__) + 1 0.000 0.000 17.750 17.750 highlevel.py:50(Mirror_and_increment) + 47/46 0.000 0.000 17.750 0.386 connection.py:352(reval) + 22/1 0.000 0.000 17.740 17.740 connection.py:448(readfromid) + 10208/1 1.260 0.000 17.740 17.740 iterfile.py:166(addtobuffer) + 22/1 0.140 0.006 17.740 17.740 iterfile.py:153(read) + 22/1 0.030 0.001 17.740 17.740 connection.py:326(answer_request) + 47/46 0.000 0.000 17.740 0.386 connection.py:307(get_response) + 10207/1 0.130 0.000 17.740 17.740 rorpiter.py:23(ToRaw) + 1 1.180 1.180 17.740 17.740 highlevel.py:98(diffs) + 10206 0.380 0.000 10.860 0.001 rorpiter.py:124(Collate2Iters) + 1 0.000 0.000 10.050 10.050 connection.py:480(read) + 1 0.000 0.000 10.050 10.050 iterfile.py:221(read) + 1 0.000 0.000 10.050 10.050 rorpiter.py:31(FromRaw) + 1 0.000 0.000 10.050 10.050 iterfile.py:23(_get) + +---------------------------------------------------------------------------- +Nothing changed, destination side: + +Thu May 16 00:27:58 2002 profile-output + + 2769004 function calls (2586001 primitive calls) in 113.720 CPU seconds + + Ordered by: internal time + List reduced from 325 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) +80820/60409 12.080 0.000 26.640 0.000 iterfile.py:221(read) + 92424 9.240 0.000 9.240 0.000 rpath.py:182(tupled_lstat) + 92426 6.060 0.000 16.750 0.000 rpath.py:448(setdata) + 10243 5.360 0.001 10.890 0.001 rpath.py:625(normalize) +40411/30205 4.010 0.000 31.240 0.001 iterfile.py:23(_get) +101216/20410 3.810 0.000 73.120 0.004 lazy.py:251(__call__) +60808/20408 3.300 0.000 72.140 0.004 lazy.py:222(process_w_subinstance) + 61337 2.770 0.000 15.330 0.000 rpath.py:408(__init__) + 40409 2.630 0.000 2.630 0.000 iterfile.py:16(_s2l) + 10000 2.220 0.000 13.140 0.001 rpath.py:684(write_from_fileobj) + 10206 2.200 0.000 112.620 0.011 highlevel.py:245(error_checked) + 20411 1.940 0.000 2.260 0.000 rpath.py:525(setmtime) + 154184 1.910 0.000 1.910 0.000 connection.py:40(__getattr__) + 10204 1.890 0.000 57.280 0.006 increment.py:211(init_non_dir) + 10207 1.820 0.000 4.090 0.000 rpath.py:568(touch) + 30615 1.730 0.000 13.490 0.000 destructive_stepping.py:33(__init__) + 71585 1.690 0.000 1.690 0.000 /usr/lib/python2.2/posixpath.py:44(join) + 10233 1.440 0.000 1.730 0.000 rpath.py:163(rename) + 10204 1.400 0.000 1.400 0.000 rpath.py:227(__str__) + 10206 1.330 0.000 3.900 0.000 rpath.py:109(copy_attribs) + + +Thu May 16 00:27:58 2002 profile-output + + 2769004 function calls (2586001 primitive calls) in 113.720 CPU seconds + + Ordered by: cumulative time + List reduced from 325 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.000 0.000 113.720 113.720 :1(?) + 1 0.000 0.000 113.720 113.720 profile:0(Globals.Main.Main(['--remote-schema', '%s --server', './rdiff-backup::manyfiles/', 'out'])) + 1 0.000 0.000 113.720 113.720 main.py:188(Main) + 1 0.000 0.000 113.690 113.690 main.py:169(take_action) + 1 0.000 0.000 113.690 113.690 main.py:221(Backup) + 1 0.010 0.010 113.660 113.660 highlevel.py:50(Mirror_and_increment) + 1 0.300 0.300 113.650 113.650 highlevel.py:239(patch_increment_and_finalize) + 10208 0.300 0.000 112.940 0.011 highlevel.py:268(check_skip_error) + 10206 2.200 0.000 112.620 0.011 highlevel.py:245(error_checked) +101216/20410 3.810 0.000 73.120 0.004 lazy.py:251(__call__) +60808/20408 3.300 0.000 72.140 0.004 lazy.py:222(process_w_subinstance) + 10205 0.780 0.000 61.080 0.006 increment.py:138(start_process) + 10204 1.890 0.000 57.280 0.006 increment.py:211(init_non_dir) + 10220 0.520 0.000 45.290 0.004 robust.py:54(execute) + 10218 0.420 0.000 34.170 0.003 robust.py:76(init) + 10204 1.090 0.000 33.700 0.003 robust.py:148(init) +40411/30205 4.010 0.000 31.240 0.001 iterfile.py:23(_get) +20412/10206 0.800 0.000 30.020 0.003 rorpiter.py:124(Collate2Iters) +20411/10205 1.300 0.000 29.480 0.003 rorpiter.py:31(FromRaw) +30411/20205 0.890 0.000 27.950 0.001 iterfile.py:54(next) + diff --git a/rdiff-backup/src/temp-stats b/rdiff-backup/src/temp-stats new file mode 100644 index 0000000..0efea64 --- /dev/null +++ b/rdiff-backup/src/temp-stats @@ -0,0 +1,241 @@ +No changes in 10000 files: + +Wed May 15 23:54:53 2002 profile-output + + 1805774 function calls (1480846 primitive calls) in 45.710 CPU seconds + + Ordered by: internal time + List reduced from 259 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) +151824/30615 5.620 0.000 18.810 0.001 lazy.py:251(__call__) +91212/30612 4.060 0.000 17.300 0.001 lazy.py:222(process_w_subinstance) + 31006 2.630 0.000 4.650 0.000 rpath.py:448(setdata) +101629/20412 1.890 0.000 14.690 0.001 selection.py:96(Iterate) +61218/20410 1.880 0.000 14.370 0.001 selection.py:122(iterate_in_dir) + 10206 1.400 0.000 44.620 0.004 highlevel.py:245(error_checked) +30618/10206 1.350 0.000 27.650 0.003 rorpiter.py:124(Collate2Iters) + 486 1.280 0.003 19.690 0.041 highlevel.py:165(generate_dissimilar) + 30748 1.270 0.000 6.640 0.000 rpath.py:408(__init__) + 31006 1.260 0.000 1.260 0.000 rpath.py:182(tupled_lstat) + 121209 1.140 0.000 1.140 0.000 lazy.py:214(intree) + 20411 1.110 0.000 9.010 0.000 destructive_stepping.py:33(__init__) + 10205 1.050 0.000 1.050 0.000 rpath.py:430(__str__) + 30612 0.860 0.000 1.410 0.000 lazy.py:218(set_subinstance) + 20411 0.840 0.000 2.830 0.000 destructive_stepping.py:77(set_init_perms) + 486 0.830 0.002 26.900 0.055 highlevel.py:98(diffs) + 30771 0.780 0.000 0.780 0.000 /usr/lib/python2.2/posixpath.py:44(join) + 10205 0.720 0.000 3.870 0.000 increment.py:138(start_process) + 91845 0.710 0.000 0.710 0.000 rorpiter.py:246(__getitem__) + 20616 0.690 0.000 0.930 0.000 rpath.py:593(isowner) + + +Wed May 15 23:54:53 2002 profile-output + + 1805774 function calls (1480846 primitive calls) in 45.710 CPU seconds + + Ordered by: cumulative time + List reduced from 259 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.010 0.010 45.710 45.710 profile:0(Globals.Main.Main(['--no-resume', 'manyfiles/', 'out'])) + 1 0.000 0.000 45.700 45.700 main.py:188(Main) + 1 0.000 0.000 45.700 45.700 :1(?) + 1 0.000 0.000 45.670 45.670 main.py:169(take_action) + 1 0.000 0.000 45.670 45.670 main.py:221(Backup) + 1 0.000 0.000 45.650 45.650 highlevel.py:50(Mirror_and_increment) + 1 0.240 0.240 45.650 45.650 highlevel.py:239(patch_increment_and_finalize) + 10208 0.270 0.000 44.890 0.004 highlevel.py:268(check_skip_error) + 10206 1.400 0.000 44.620 0.004 highlevel.py:245(error_checked) +30618/10206 1.350 0.000 27.650 0.003 rorpiter.py:124(Collate2Iters) + 486 0.830 0.002 26.900 0.055 highlevel.py:98(diffs) + 486 0.020 0.000 19.720 0.041 rorpiter.py:58(Signatures) + 486 1.280 0.003 19.690 0.041 highlevel.py:165(generate_dissimilar) +151824/30615 5.620 0.000 18.810 0.001 lazy.py:251(__call__) +91212/30612 4.060 0.000 17.300 0.001 lazy.py:222(process_w_subinstance) + 20412 0.520 0.000 14.900 0.001 lazy.py:166(yielda) +101629/20412 1.890 0.000 14.690 0.001 selection.py:96(Iterate) +61218/20410 1.880 0.000 14.370 0.001 selection.py:122(iterate_in_dir) + 41229 0.660 0.000 11.180 0.000 robust.py:194(check_common_error) + 20409 0.310 0.000 9.810 0.000 selection.py:130() + +---------------------------------------------------------------------------- + +Writing 10000 new small files + +Thu May 16 00:05:36 2002 profile-output + + 2452569 function calls (2249461 primitive calls) in 75.680 CPU seconds + + Ordered by: internal time + List reduced from 236 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 71752 4.580 0.000 4.580 0.000 rpath.py:182(tupled_lstat) + 71752 4.170 0.000 9.860 0.000 rpath.py:448(setdata) +101216/20410 3.550 0.000 11.070 0.001 lazy.py:251(__call__) +60808/20408 2.690 0.000 9.980 0.000 lazy.py:222(process_w_subinstance) + 10206 2.460 0.000 74.630 0.007 highlevel.py:217(error_checked) + 20012 2.080 0.000 2.820 0.000 rpath.py:674(open) + 40819 2.050 0.000 13.440 0.000 destructive_stepping.py:33(__init__) + 10228 2.050 0.000 5.030 0.000 rpath.py:625(normalize) + 10000 1.980 0.000 8.060 0.001 rpath.py:684(write_from_fileobj) + 51086 1.970 0.000 9.210 0.000 rpath.py:408(__init__) + 143692 1.640 0.000 1.640 0.000 connection.py:40(__getattr__) + 10206 1.610 0.000 8.920 0.001 highlevel.py:165(generate_dissimilar) +30618/10206 1.550 0.000 29.540 0.003 rorpiter.py:124(Collate2Iters) + 10205 1.520 0.000 1.520 0.000 rpath.py:227(__str__) + 20410 1.450 0.000 1.710 0.000 rpath.py:525(setmtime) + 10226 1.380 0.000 1.670 0.000 rpath.py:163(rename) + 20000 1.380 0.000 1.380 0.000 rpath.py:772(read) + 10206 1.350 0.000 3.490 0.000 rpath.py:109(copy_attribs) + 40819 1.290 0.000 3.100 0.000 destructive_stepping.py:77(set_init_perms) +50817/10208 1.250 0.000 7.890 0.001 selection.py:96(Iterate) + + +Thu May 16 00:05:36 2002 profile-output + + 2452569 function calls (2249461 primitive calls) in 75.680 CPU seconds + + Ordered by: cumulative time + List reduced from 236 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.000 0.000 75.680 75.680 profile:0(Globals.Main.Main(['manyfiles/', 'out'])) + 1 0.000 0.000 75.680 75.680 :1(?) + 1 0.000 0.000 75.680 75.680 main.py:188(Main) + 1 0.000 0.000 75.670 75.670 main.py:221(Backup) + 1 0.000 0.000 75.670 75.670 main.py:169(take_action) + 1 0.000 0.000 75.650 75.650 highlevel.py:27(Mirror) + 1 0.450 0.450 75.650 75.650 highlevel.py:210(patch_and_finalize) + 10206 0.190 0.000 74.820 0.007 highlevel.py:268(check_skip_error) + 10206 2.460 0.000 74.630 0.007 highlevel.py:217(error_checked) +30618/10206 1.550 0.000 29.540 0.003 rorpiter.py:124(Collate2Iters) + 10206 1.060 0.000 29.020 0.003 highlevel.py:98(diffs) + 10216 0.270 0.000 28.800 0.003 robust.py:54(execute) + 10205 1.140 0.000 22.760 0.002 robust.py:148(init) + 40819 2.050 0.000 13.440 0.000 destructive_stepping.py:33(__init__) +101216/20410 3.550 0.000 11.070 0.001 lazy.py:251(__call__) +60808/20408 2.690 0.000 9.980 0.000 lazy.py:222(process_w_subinstance) + 10206 0.570 0.000 9.860 0.001 rorpiter.py:58(Signatures) + 71752 4.170 0.000 9.860 0.000 rpath.py:448(setdata) + 10226 0.540 0.000 9.770 0.001 robust.py:240(new) + 51086 1.970 0.000 9.210 0.000 rpath.py:408(__init__) + +---------------------------------------------------------------------------- +Nothing changed, source side: + + ncalls tottime percall cumtime percall filename:lineno(function) +50608/10205 1.970 0.000 5.420 0.001 lazy.py:251(__call__) + 10208/1 1.260 0.000 17.740 17.740 iterfile.py:166(addtobuffer) +30404/10204 1.240 0.000 5.010 0.000 lazy.py:222(process_w_subinstance) + 1 1.180 1.180 17.740 17.740 highlevel.py:98(diffs) + 10221 1.050 0.000 1.930 0.000 rpath.py:448(setdata) +50814/10206 0.880 0.000 7.610 0.001 selection.py:96(Iterate) +30609/10205 0.780 0.000 7.420 0.001 selection.py:122(iterate_in_dir) + 10205 0.600 0.000 0.600 0.000 iterfile.py:197(_l2s) + 10204 0.600 0.000 0.700 0.000 lazy.py:218(set_subinstance) + 10215 0.550 0.000 2.760 0.000 rpath.py:408(__init__) + 10206 0.470 0.000 4.900 0.000 destructive_stepping.py:33(__init__) + 10206 0.430 0.000 1.570 0.000 destructive_stepping.py:77(set_init_perms) + 10411 0.430 0.000 0.610 0.000 rpath.py:593(isowner) + 10206 0.390 0.000 0.390 0.000 lazy.py:177(yieldb) + 10206 0.380 0.000 10.860 0.001 rorpiter.py:124(Collate2Iters) + 10208 0.370 0.000 0.370 0.000 rpath.py:182(tupled_lstat) + 20614 0.360 0.000 6.010 0.000 robust.py:194(check_common_error) + 40403 0.330 0.000 0.330 0.000 lazy.py:214(intree) + 10205 0.320 0.000 0.980 0.000 rpath.py:581(readable) + 10205 0.320 0.000 0.790 0.000 destructive_stepping.py:206(end_process) + + +Thu May 16 00:21:24 2002 profile-output + + 646330 function calls (503964 primitive calls) in 17.820 CPU seconds + + Ordered by: cumulative time + List reduced from 213 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.000 0.000 17.820 17.820 :1(?) + 1 0.000 0.000 17.820 17.820 profile:0(Globals.Main.Main(['--remote-schema', '%s --server', 'manyfiles/', './rdiff-backup::out'])) + 1 0.000 0.000 17.820 17.820 main.py:188(Main) + 1 0.000 0.000 17.770 17.770 main.py:221(Backup) + 1 0.000 0.000 17.770 17.770 main.py:169(take_action) + 47/46 0.000 0.000 17.750 0.386 connection.py:425(__call__) + 1 0.000 0.000 17.750 17.750 highlevel.py:50(Mirror_and_increment) + 47/46 0.000 0.000 17.750 0.386 connection.py:352(reval) + 22/1 0.000 0.000 17.740 17.740 connection.py:448(readfromid) + 10208/1 1.260 0.000 17.740 17.740 iterfile.py:166(addtobuffer) + 22/1 0.140 0.006 17.740 17.740 iterfile.py:153(read) + 22/1 0.030 0.001 17.740 17.740 connection.py:326(answer_request) + 47/46 0.000 0.000 17.740 0.386 connection.py:307(get_response) + 10207/1 0.130 0.000 17.740 17.740 rorpiter.py:23(ToRaw) + 1 1.180 1.180 17.740 17.740 highlevel.py:98(diffs) + 10206 0.380 0.000 10.860 0.001 rorpiter.py:124(Collate2Iters) + 1 0.000 0.000 10.050 10.050 connection.py:480(read) + 1 0.000 0.000 10.050 10.050 iterfile.py:221(read) + 1 0.000 0.000 10.050 10.050 rorpiter.py:31(FromRaw) + 1 0.000 0.000 10.050 10.050 iterfile.py:23(_get) + +---------------------------------------------------------------------------- +Nothing changed, destination side: + +Thu May 16 00:27:58 2002 profile-output + + 2769004 function calls (2586001 primitive calls) in 113.720 CPU seconds + + Ordered by: internal time + List reduced from 325 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) +80820/60409 12.080 0.000 26.640 0.000 iterfile.py:221(read) + 92424 9.240 0.000 9.240 0.000 rpath.py:182(tupled_lstat) + 92426 6.060 0.000 16.750 0.000 rpath.py:448(setdata) + 10243 5.360 0.001 10.890 0.001 rpath.py:625(normalize) +40411/30205 4.010 0.000 31.240 0.001 iterfile.py:23(_get) +101216/20410 3.810 0.000 73.120 0.004 lazy.py:251(__call__) +60808/20408 3.300 0.000 72.140 0.004 lazy.py:222(process_w_subinstance) + 61337 2.770 0.000 15.330 0.000 rpath.py:408(__init__) + 40409 2.630 0.000 2.630 0.000 iterfile.py:16(_s2l) + 10000 2.220 0.000 13.140 0.001 rpath.py:684(write_from_fileobj) + 10206 2.200 0.000 112.620 0.011 highlevel.py:245(error_checked) + 20411 1.940 0.000 2.260 0.000 rpath.py:525(setmtime) + 154184 1.910 0.000 1.910 0.000 connection.py:40(__getattr__) + 10204 1.890 0.000 57.280 0.006 increment.py:211(init_non_dir) + 10207 1.820 0.000 4.090 0.000 rpath.py:568(touch) + 30615 1.730 0.000 13.490 0.000 destructive_stepping.py:33(__init__) + 71585 1.690 0.000 1.690 0.000 /usr/lib/python2.2/posixpath.py:44(join) + 10233 1.440 0.000 1.730 0.000 rpath.py:163(rename) + 10204 1.400 0.000 1.400 0.000 rpath.py:227(__str__) + 10206 1.330 0.000 3.900 0.000 rpath.py:109(copy_attribs) + + +Thu May 16 00:27:58 2002 profile-output + + 2769004 function calls (2586001 primitive calls) in 113.720 CPU seconds + + Ordered by: cumulative time + List reduced from 325 to 20 due to restriction <20> + + ncalls tottime percall cumtime percall filename:lineno(function) + 1 0.000 0.000 113.720 113.720 :1(?) + 1 0.000 0.000 113.720 113.720 profile:0(Globals.Main.Main(['--remote-schema', '%s --server', './rdiff-backup::manyfiles/', 'out'])) + 1 0.000 0.000 113.720 113.720 main.py:188(Main) + 1 0.000 0.000 113.690 113.690 main.py:169(take_action) + 1 0.000 0.000 113.690 113.690 main.py:221(Backup) + 1 0.010 0.010 113.660 113.660 highlevel.py:50(Mirror_and_increment) + 1 0.300 0.300 113.650 113.650 highlevel.py:239(patch_increment_and_finalize) + 10208 0.300 0.000 112.940 0.011 highlevel.py:268(check_skip_error) + 10206 2.200 0.000 112.620 0.011 highlevel.py:245(error_checked) +101216/20410 3.810 0.000 73.120 0.004 lazy.py:251(__call__) +60808/20408 3.300 0.000 72.140 0.004 lazy.py:222(process_w_subinstance) + 10205 0.780 0.000 61.080 0.006 increment.py:138(start_process) + 10204 1.890 0.000 57.280 0.006 increment.py:211(init_non_dir) + 10220 0.520 0.000 45.290 0.004 robust.py:54(execute) + 10218 0.420 0.000 34.170 0.003 robust.py:76(init) + 10204 1.090 0.000 33.700 0.003 robust.py:148(init) +40411/30205 4.010 0.000 31.240 0.001 iterfile.py:23(_get) +20412/10206 0.800 0.000 30.020 0.003 rorpiter.py:124(Collate2Iters) +20411/10205 1.300 0.000 29.480 0.003 rorpiter.py:31(FromRaw) +30411/20205 0.890 0.000 27.950 0.001 iterfile.py:54(next) + -- cgit v1.2.1