From 7d3f1ab0f512861e955b925ac19cc2eff6509d43 Mon Sep 17 00:00:00 2001 From: Lucca Hirschi Date: Wed, 18 Jul 2012 14:17:35 +0200 Subject: [PATCH] Log formatted. --- beau | 4 +- log_benchmark.txt | 126 +++++++++++++++++++++++++++- log_counter-optim_big-query.txt | 18 ++++ log_counter-optim_small-query.txt | 18 ++++ log_counter-optim_small-query~ | 26 ------ log_without-counter_big-query.txt | 55 ++++++++++++ log_without-counter_small-query.txt | 55 ++++++++++++ 7 files changed, 272 insertions(+), 30 deletions(-) create mode 100644 log_counter-optim_big-query.txt create mode 100644 log_counter-optim_small-query.txt delete mode 100644 log_counter-optim_small-query~ create mode 100644 log_without-counter_big-query.txt create mode 100644 log_without-counter_small-query.txt diff --git a/beau b/beau index 01d2cef..4544e26 100755 --- a/beau +++ b/beau @@ -1 +1,3 @@ -grep -v average log_benchmark | grep -v faults| grep -v block| grep -v swap| grep -v messages| grep -v signals| grep -v voluntary > log_benchmark.txt \ No newline at end of file +for log in log_*[^t] ; do + grep -v Average $log | grep -v outputs| grep -v status| grep -v Swap | grep -v Signals| grep -v Socket |grep -v Major > $log.txt +done \ No newline at end of file diff --git a/log_benchmark.txt b/log_benchmark.txt index 58a7c47..bd09d24 100644 --- a/log_benchmark.txt +++ b/log_benchmark.txt @@ -1,58 +1,178 @@ ## 17.07 14:00 ## -#### Big query (4k, 1400 col): +#### Big query (1400 col) // XML Doc of 104M (0.7): ./bench.native_Core+Extentions+Optim-hconsed_V1_23f38d4 145.71 real 144.44 user 1.05 sys 998281216 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 247316 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 9505 involuntary context switches ./bench.native_Core+FS+Self_tested_1f48f81 178.02 real 176.91 user 1.08 sys 1046331392 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 261351 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 1569 involuntary context switches ./bench.native_OptimTag-without-hconsed-formula_71804e8 139.68 real 138.71 user 0.93 sys 996233216 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 245792 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 1 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 1955 involuntary context switches -#### Small query: +#### Small query (72 col) // XML Doc 104M (0.7): ./bench.native_Core+Extentions+Optim-hconsed_V1_23f38d4 16.20 real 15.42 user 0.76 sys 958164992 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 235730 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 391 involuntary context switches ./bench.native_Core+FS+Self_tested_1f48f81 21.16 real 20.37 user 0.77 sys 957571072 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 235585 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 347 involuntary context switches ./bench.native_OptimTag-without-hconsed-formula_71804e8 16.26 real 15.48 user 0.77 sys 965349376 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 238252 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 341 involuntary context switches - ## Older ## + ## Older ## (DOC: 0.5 ?M) ./solve.native_BUOracle-and-BUOverMax_hconsed_d55b57e 22.49 real 21.79 user 0.68 sys 742887424 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 185522 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 722 involuntary context switches ./solve.native_BUOracle_hconsed_47c4225 30.84 real 30.04 user 0.68 sys 743706624 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 185722 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 1244 involuntary context switches ./solve.native_BURoacle-and-BUOverMax-and-fst-TP-Max_hconsed_71804e8 22.72 real 22.01 user 0.64 sys 742940672 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 185535 page reclaims + 0 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 1337 involuntary context switches ./solve.native_NO_hconsign_cafb194 31.81 real 29.61 user 0.67 sys 743292928 maximum resident set size + 0 average shared memory size + 0 average unshared data size + 0 average unshared stack size 185591 page reclaims + 30 page faults + 0 swaps + 0 block input operations + 0 block output operations + 0 messages sent + 0 messages received + 0 signals received + 0 voluntary context switches + 1511 involuntary context switches diff --git a/log_counter-optim_big-query.txt b/log_counter-optim_big-query.txt new file mode 100644 index 0000000..f608e94 --- /dev/null +++ b/log_counter-optim_big-query.txt @@ -0,0 +1,18 @@ +./bench.native_optim-tout-counters-also-for-3algos_2efd748 +Time for Run (ms): 216177.899122 +For tp_max: call 2345264, miss: 165 +For bu_over_max: call 2345264, miss: 163 +For bu_oracle: call 2345264, miss: 162 +Num: call 25401, Num Miss: 13022 +Num: call_i 2346, Num Miss_i: 1365 + Command being timed: "./bench.native_optim-tout-counters-also-for-3algos_2efd748 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/my.xpl" + User time (seconds): 219.52 + System time (seconds): 1.48 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 3:41.02 + Maximum resident set size (kbytes): 3761968 + Minor (reclaiming a frame) page faults: 238141 + Voluntary context switches: 10 + Involuntary context switches: 626 + File system inputs: 0 + Page size (bytes): 4096 diff --git a/log_counter-optim_small-query.txt b/log_counter-optim_small-query.txt new file mode 100644 index 0000000..8edd4cd --- /dev/null +++ b/log_counter-optim_small-query.txt @@ -0,0 +1,18 @@ +./bench.native_optim-tout-counters-also-for-3algos_2efd748 +Time for Run (ms): 19004.356146 +For tp_max: call 2345264, miss: 98 +For bu_over_max: call 2345264, miss: 77 +For bu_oracle: call 2345264, miss: 76 +Num: call 0, Num Miss: 0 +Num: call_i 664, Num Miss_i: 56 + Command being timed: "./bench.native_optim-tout-counters-also-for-3algos_2efd748 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/A1.xpl" + User time (seconds): 23.00 + System time (seconds): 0.83 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 0:23.84 + Maximum resident set size (kbytes): 3846752 + Minor (reclaiming a frame) page faults: 243438 + Voluntary context switches: 7 + Involuntary context switches: 95 + File system inputs: 0 + Page size (bytes): 4096 diff --git a/log_counter-optim_small-query~ b/log_counter-optim_small-query~ deleted file mode 100644 index c5a33c0..0000000 --- a/log_counter-optim_small-query~ +++ /dev/null @@ -1,26 +0,0 @@ -./bench.native_optim-tout-counters-also-for-3algos_2efd748 -Command terminated by signal 2 - Command being timed: "./bench.native_optim-tout-counters-also-for-3algos_2efd748 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/A1.xpl" - User time (seconds): 1.44 - System time (seconds): 0.13 - Percent of CPU this job got: 99% - Elapsed (wall clock) time (h:mm:ss or m:ss): 0:01.57 - Average shared text size (kbytes): 0 - Average unshared data size (kbytes): 0 - Average stack size (kbytes): 0 - Average total size (kbytes): 0 - Maximum resident set size (kbytes): 488432 - Average resident set size (kbytes): 0 - Major (requiring I/O) page faults: 0 - Minor (reclaiming a frame) page faults: 30854 - Voluntary context switches: 8 - Involuntary context switches: 10 - Swaps: 0 - File system inputs: 0 - File system outputs: 0 - Socket messages sent: 0 - Socket messages received: 0 - Signals delivered: 0 - Page size (bytes): 4096 - Exit status: 0 - diff --git a/log_without-counter_big-query.txt b/log_without-counter_big-query.txt new file mode 100644 index 0000000..e364097 --- /dev/null +++ b/log_without-counter_big-query.txt @@ -0,0 +1,55 @@ +./bench.native_optim-tout_5e7268f + Command being timed: "./bench.native_optim-tout_5e7268f ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/my.xpl" + User time (seconds): 217.69 + System time (seconds): 1.19 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 3:39.04 + Maximum resident set size (kbytes): 3761824 + Minor (reclaiming a frame) page faults: 238363 + Voluntary context switches: 46 + Involuntary context switches: 995 + File system inputs: 2648 + Page size (bytes): 4096 + +./bench.native_optim-tout-counters_d84a929 +Time for Run (ms): 212329.730034 +Num: call 25401, Num Miss: 13022 +Num: call_i 2346, Num Miss_i: 1365 + Command being timed: "./bench.native_optim-tout-counters_d84a929 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/my.xpl" + User time (seconds): 215.68 + System time (seconds): 1.49 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 3:37.23 + Maximum resident set size (kbytes): 3762000 + Minor (reclaiming a frame) page faults: 238139 + Voluntary context switches: 15 + Involuntary context switches: 656 + File system inputs: 224 + Page size (bytes): 4096 + +./bench.native_optim-without-formula_71804e8 + Command being timed: "./bench.native_optim-without-formula_71804e8 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/my.xpl" + User time (seconds): 219.88 + System time (seconds): 1.40 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 3:41.31 + Maximum resident set size (kbytes): 3758000 + Minor (reclaiming a frame) page faults: 237893 + Voluntary context switches: 12 + Involuntary context switches: 634 + File system inputs: 0 + Page size (bytes): 4096 + +./bench.native_without-optim_Core+FS+Self_tested_07a2770 + Command being timed: "./bench.native_without-optim_Core+FS+Self_tested_07a2770 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/my.xpl" + User time (seconds): 272.78 + System time (seconds): 1.76 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 4:34.66 + Maximum resident set size (kbytes): 3898160 + Minor (reclaiming a frame) page faults: 246784 + Voluntary context switches: 22 + Involuntary context switches: 1192 + File system inputs: 568 + Page size (bytes): 4096 + diff --git a/log_without-counter_small-query.txt b/log_without-counter_small-query.txt new file mode 100644 index 0000000..6b6484d --- /dev/null +++ b/log_without-counter_small-query.txt @@ -0,0 +1,55 @@ +./bench.native_optim-tout_5e7268f + Command being timed: "./bench.native_optim-tout_5e7268f ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/A1.xpl" + User time (seconds): 23.09 + System time (seconds): 0.83 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 0:23.97 + Maximum resident set size (kbytes): 3846640 + Minor (reclaiming a frame) page faults: 243657 + Voluntary context switches: 25 + Involuntary context switches: 144 + File system inputs: 640 + Page size (bytes): 4096 + +./bench.native_optim-tout-counters_d84a929 +Time for Run (ms): 18818.549156 +Num: call 0, Num Miss: 0 +Num: call_i 664, Num Miss_i: 56 + Command being timed: "./bench.native_optim-tout-counters_d84a929 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/A1.xpl" + User time (seconds): 22.84 + System time (seconds): 0.80 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 0:23.72 + Maximum resident set size (kbytes): 3846800 + Minor (reclaiming a frame) page faults: 243437 + Voluntary context switches: 15 + Involuntary context switches: 116 + File system inputs: 2272 + Page size (bytes): 4096 + +./bench.native_optim-without-formula_71804e8 + Command being timed: "./bench.native_optim-without-formula_71804e8 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/A1.xpl" + User time (seconds): 22.95 + System time (seconds): 0.79 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 0:23.78 + Maximum resident set size (kbytes): 3846640 + Minor (reclaiming a frame) page faults: 243429 + Voluntary context switches: 8 + Involuntary context switches: 64 + File system inputs: 2056 + Page size (bytes): 4096 + +./bench.native_without-optim_Core+FS+Self_tested_07a2770 + Command being timed: "./bench.native_without-optim_Core+FS+Self_tested_07a2770 ./tests/docs/XPath-PT.xml -f ./tests/queries/XPath-PT/A1.xpl" + User time (seconds): 31.20 + System time (seconds): 0.70 + Percent of CPU this job got: 99% + Elapsed (wall clock) time (h:mm:ss or m:ss): 0:31.97 + Maximum resident set size (kbytes): 3844384 + Minor (reclaiming a frame) page faults: 243286 + Voluntary context switches: 13 + Involuntary context switches: 100 + File system inputs: 3072 + Page size (bytes): 4096 + -- 2.17.1