14:31:51.72 Logging started, 11/11/04 %%%%%%%%%%%%%%%%%%%% 14:31:51.72 ActivePassiveProfiler: starting %%%%% 14:31:51.88 ______ #1 |84.2 %| active_start ...psyco/profiler.py:277 14:31:51.88 tag function: mult3.Matrix.__mul__ % 14:31:52.14 ______ #1 |82.3 %| active_start ...psyco/profiler.py:277 14:31:52.28 ______ #1 |82.2 %| active_start ...psyco/profiler.py:277 14:31:52.48 ______ #1 |82.2 %| active_start ...psyco/profiler.py:277 14:31:52.68 ______ #1 |82.2 %| active_start ...psyco/profiler.py:277 14:31:52.88 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:53.08 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:53.28 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:53.48 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:53.68 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:53.88 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:54.08 ______ #1 |82.1 %| active_start ...psyco/profiler.py:277 14:31:54.28 ______ #1 |82.0 %| active_start ...psyco/profiler.py:277 14:31:54.51 tag function: mult1.Matrix.__mul__ % 14:31:54.51 ______ #1 |69.3 %| active_start ...psyco/profiler.py:277 #2 | 2.2 %| __init__ mult1.py:2 #3 | 1.1 %| inner :2 14:31:54.64 tag function: mult1.Matrix.__init__ % 14:31:54.75 ______ #1 |59.2 %| active_start ...psyco/profiler.py:277 #2 | 3.2 %| inner :2 #3 | 2.3 %| __mul__ mult1.py:26 #4 | 0.9 %| timeit ...thon2.3/timeit.py:144 #5 | 0.8 %| __init__ mult3.py:7 #6 | 0.2 %| matmul prof.py:3 14:31:54.88 ______ #1 |57.6 %| active_start ...psyco/profiler.py:277 #2 | 3.3 %| __init__ mult3.py:7 #3 | 3.1 %| inner :2 #4 | 2.3 %| __mul__ mult1.py:26 #5 | 0.9 %| timeit ...thon2.3/timeit.py:144 #6 | 0.2 %| matmul prof.py:3 14:31:54.94 tag function: mult3.Matrix.__init__ % 14:31:55.11 ______ #1 |53.1 %| active_start ...psyco/profiler.py:277 #2 | 2.9 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 0.8 %| timeit ...thon2.3/timeit.py:144 #5 | 0.2 %| matmul prof.py:3 14:31:55.30 ______ #1 |53.0 %| active_start ...psyco/profiler.py:277 #2 | 2.9 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 0.8 %| timeit ...thon2.3/timeit.py:144 #5 | 0.2 %| dumpcharges ...s/psyco/logger.py:56 #6 | 0.2 %| do_profile ...psyco/profiler.py:318 #7 | 0.2 %| matmul prof.py:3 14:31:55.50 ______ #1 |53.0 %| active_start ...psyco/profiler.py:277 #2 | 2.9 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 0.8 %| timeit ...thon2.3/timeit.py:144 #5 | 0.2 %| do_profile ...psyco/profiler.py:318 #6 | 0.2 %| dumpcharges ...s/psyco/logger.py:56 #7 | 0.2 %| matmul prof.py:3 14:31:55.70 ______ #1 |52.9 %| active_start ...psyco/profiler.py:277 #2 | 2.9 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 0.8 %| timeit ...thon2.3/timeit.py:144 #5 | 0.4 %| dumpcharges ...s/psyco/logger.py:56 #6 | 0.2 %| do_profile ...psyco/profiler.py:318 #7 | 0.2 %| matmul prof.py:3 14:31:55.90 ______ #1 |52.8 %| active_start ...psyco/profiler.py:277 #2 | 2.9 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 0.8 %| timeit ...thon2.3/timeit.py:144 #5 | 0.6 %| dumpcharges ...s/psyco/logger.py:56 #6 | 0.2 %| do_profile ...psyco/profiler.py:318 #7 | 0.2 %| matmul prof.py:3 14:31:56.41 ______ #1 |52.4 %| active_start ...psyco/profiler.py:277 #2 | 2.8 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 0.9 %| timeit ...thon2.3/timeit.py:144 #5 | 0.8 %| dumpcharges ...s/psyco/logger.py:56 #6 | 0.5 %| do_profile ...psyco/profiler.py:318 #7 | 0.2 %| matmul prof.py:3 #8 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:56.71 ______ #1 |52.1 %| active_start ...psyco/profiler.py:277 #2 | 2.8 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 1.0 %| timeit ...thon2.3/timeit.py:144 #5 | 0.9 %| dumpcharges ...s/psyco/logger.py:56 #6 | 0.5 %| do_profile ...psyco/profiler.py:318 #7 | 0.3 %| matmul prof.py:3 #8 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:56.85 ______ #1 |52.0 %| active_start ...psyco/profiler.py:277 #2 | 2.8 %| inner :2 #3 | 2.1 %| __mul__ mult1.py:26 #4 | 1.2 %| dumpcharges ...s/psyco/logger.py:56 #5 | 1.0 %| timeit ...thon2.3/timeit.py:144 #6 | 0.5 %| do_profile ...psyco/profiler.py:318 #7 | 0.3 %| matmul prof.py:3 #8 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:57.05 ______ #1 |51.7 %| active_start ...psyco/profiler.py:277 #2 | 2.8 %| inner :2 #3 | 2.0 %| __mul__ mult1.py:26 #4 | 1.3 %| dumpcharges ...s/psyco/logger.py:56 #5 | 1.0 %| timeit ...thon2.3/timeit.py:144 #6 | 0.9 %| do_profile ...psyco/profiler.py:318 #7 | 0.3 %| matmul prof.py:3 #8 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:57.25 ______ #1 |51.4 %| active_start ...psyco/profiler.py:277 #2 | 2.8 %| inner :2 #3 | 2.0 %| __mul__ mult1.py:26 #4 | 1.5 %| dumpcharges ...s/psyco/logger.py:56 #5 | 1.2 %| do_profile ...psyco/profiler.py:318 #6 | 1.0 %| timeit ...thon2.3/timeit.py:144 #7 | 0.3 %| matmul prof.py:3 #8 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:57.45 ______ #1 |50.8 %| active_start ...psyco/profiler.py:277 #2 | 2.8 %| inner :2 #3 | 2.0 %| dumpcharges ...s/psyco/logger.py:56 #4 | 2.0 %| __mul__ mult1.py:26 #5 | 1.8 %| do_profile ...psyco/profiler.py:318 #6 | 1.0 %| timeit ...thon2.3/timeit.py:144 #7 | 0.3 %| matmul prof.py:3 #8 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:57.98 ______ #1 |49.4 %| active_start ...psyco/profiler.py:277 #2 | 3.0 %| dumpcharges ...s/psyco/logger.py:56 #3 | 2.7 %| inner :2 #4 | 2.0 %| __mul__ mult1.py:26 #5 | 2.0 %| do_profile ...psyco/profiler.py:318 #6 | 1.2 %| timeit ...thon2.3/timeit.py:144 #7 | 0.6 %| matmul prof.py:3 #8 | 0.5 %| __init__ ...thon2.3/timeit.py:108 #9 | 0.4 %| inner :2 #10 | 0.1 %| reindent ...thon2.3/timeit.py:88 14:31:58.31 ______ #1 |48.6 %| active_start ...psyco/profiler.py:277 #2 | 3.4 %| dumpcharges ...s/psyco/logger.py:56 #3 | 2.6 %| inner :2 #4 | 2.4 %| do_profile ...psyco/profiler.py:318 #5 | 1.9 %| __mul__ mult1.py:26 #6 | 1.4 %| timeit ...thon2.3/timeit.py:144 #7 | 0.7 %| matmul prof.py:3 #8 | 0.5 %| __init__ ...thon2.3/timeit.py:108 #9 | 0.5 %| inner :2 #10 | 0.4 %| inner :2 14:31:58.55 ______ #1 |47.8 %| active_start ...psyco/profiler.py:277 #2 | 4.4 %| dumpcharges ...s/psyco/logger.py:56 #3 | 2.9 %| do_profile ...psyco/profiler.py:318 #4 | 2.6 %| inner :2 #5 | 1.9 %| __mul__ mult1.py:26 #6 | 1.4 %| timeit ...thon2.3/timeit.py:144 #7 | 0.7 %| matmul prof.py:3 #8 | 0.5 %| __init__ ...thon2.3/timeit.py:108 #9 | 0.5 %| inner :2 #10 | 0.4 %| inner :2 14:31:58.75 ______ #1 |46.8 %| active_start ...psyco/profiler.py:277 #2 | 5.5 %| dumpcharges ...s/psyco/logger.py:56 #3 | 3.6 %| do_profile ...psyco/profiler.py:318 #4 | 2.5 %| inner :2 #5 | 1.9 %| __mul__ mult1.py:26 #6 | 1.3 %| timeit ...thon2.3/timeit.py:144 #7 | 0.7 %| matmul prof.py:3 #8 | 0.5 %| __init__ ...thon2.3/timeit.py:108 #9 | 0.4 %| inner :2 #10 | 0.4 %| inner :2 14:31:58.95 ______ #1 |45.3 %| active_start ...psyco/profiler.py:277 #2 | 7.0 %| dumpcharges ...s/psyco/logger.py:56 #3 | 5.2 %| do_profile ...psyco/profiler.py:318 #4 | 2.5 %| inner :2 #5 | 1.8 %| __mul__ mult1.py:26 #6 | 1.3 %| timeit ...thon2.3/timeit.py:144 #7 | 0.7 %| matmul prof.py:3 #8 | 0.5 %| __init__ ...thon2.3/timeit.py:108 #9 | 0.4 %| inner :2 #10 | 0.4 %| inner :2 14:31:59.15 ______ #1 |43.4 %| active_start ...psyco/profiler.py:277 #2 | 8.7 %| dumpcharges ...s/psyco/logger.py:56 #3 | 7.2 %| do_profile ...psyco/profiler.py:318 #4 | 2.4 %| inner :2 #5 | 1.7 %| __mul__ mult1.py:26 #6 | 1.2 %| timeit ...thon2.3/timeit.py:144 #7 | 0.6 %| matmul prof.py:3 #8 | 0.4 %| __init__ ...thon2.3/timeit.py:108 #9 | 0.4 %| inner :2 #10 | 0.4 %| inner :2 14:31:59.15 tag function: psyco.logger.dumpcharges % 14:31:59.15 tag function: psyco.profiler.PassiveProfiler.do_profile % 14:31:59.20 memory usage: 142+ kb % 14:31:59.20 program exit, 11/11/04 %%%%%%%%%%%%%%%%%%%%