Sharedwww / talks / pyrex / log-psycoOpen in CoCalc
Author: William A. Stein
1
14:31:51.72 Logging started, 11/11/04 %%%%%%%%%%%%%%%%%%%%
2
14:31:51.72 ActivePassiveProfiler: starting %%%%%
3
14:31:51.88 ______
4
#1 |84.2 %| active_start ...psyco/profiler.py:277
5
14:31:51.88 tag function: mult3.Matrix.__mul__ %
6
14:31:52.14 ______
7
#1 |82.3 %| active_start ...psyco/profiler.py:277
8
14:31:52.28 ______
9
#1 |82.2 %| active_start ...psyco/profiler.py:277
10
14:31:52.48 ______
11
#1 |82.2 %| active_start ...psyco/profiler.py:277
12
14:31:52.68 ______
13
#1 |82.2 %| active_start ...psyco/profiler.py:277
14
14:31:52.88 ______
15
#1 |82.1 %| active_start ...psyco/profiler.py:277
16
14:31:53.08 ______
17
#1 |82.1 %| active_start ...psyco/profiler.py:277
18
14:31:53.28 ______
19
#1 |82.1 %| active_start ...psyco/profiler.py:277
20
14:31:53.48 ______
21
#1 |82.1 %| active_start ...psyco/profiler.py:277
22
14:31:53.68 ______
23
#1 |82.1 %| active_start ...psyco/profiler.py:277
24
14:31:53.88 ______
25
#1 |82.1 %| active_start ...psyco/profiler.py:277
26
14:31:54.08 ______
27
#1 |82.1 %| active_start ...psyco/profiler.py:277
28
14:31:54.28 ______
29
#1 |82.0 %| active_start ...psyco/profiler.py:277
30
14:31:54.51 tag function: mult1.Matrix.__mul__ %
31
14:31:54.51 ______
32
#1 |69.3 %| active_start ...psyco/profiler.py:277
33
#2 | 2.2 %| __init__ mult1.py:2
34
#3 | 1.1 %| inner <timeit-src>:2
35
14:31:54.64 tag function: mult1.Matrix.__init__ %
36
14:31:54.75 ______
37
#1 |59.2 %| active_start ...psyco/profiler.py:277
38
#2 | 3.2 %| inner <timeit-src>:2
39
#3 | 2.3 %| __mul__ mult1.py:26
40
#4 | 0.9 %| timeit ...thon2.3/timeit.py:144
41
#5 | 0.8 %| __init__ mult3.py:7
42
#6 | 0.2 %| matmul prof.py:3
43
14:31:54.88 ______
44
#1 |57.6 %| active_start ...psyco/profiler.py:277
45
#2 | 3.3 %| __init__ mult3.py:7
46
#3 | 3.1 %| inner <timeit-src>:2
47
#4 | 2.3 %| __mul__ mult1.py:26
48
#5 | 0.9 %| timeit ...thon2.3/timeit.py:144
49
#6 | 0.2 %| matmul prof.py:3
50
14:31:54.94 tag function: mult3.Matrix.__init__ %
51
14:31:55.11 ______
52
#1 |53.1 %| active_start ...psyco/profiler.py:277
53
#2 | 2.9 %| inner <timeit-src>:2
54
#3 | 2.1 %| __mul__ mult1.py:26
55
#4 | 0.8 %| timeit ...thon2.3/timeit.py:144
56
#5 | 0.2 %| matmul prof.py:3
57
14:31:55.30 ______
58
#1 |53.0 %| active_start ...psyco/profiler.py:277
59
#2 | 2.9 %| inner <timeit-src>:2
60
#3 | 2.1 %| __mul__ mult1.py:26
61
#4 | 0.8 %| timeit ...thon2.3/timeit.py:144
62
#5 | 0.2 %| dumpcharges ...s/psyco/logger.py:56
63
#6 | 0.2 %| do_profile ...psyco/profiler.py:318
64
#7 | 0.2 %| matmul prof.py:3
65
14:31:55.50 ______
66
#1 |53.0 %| active_start ...psyco/profiler.py:277
67
#2 | 2.9 %| inner <timeit-src>:2
68
#3 | 2.1 %| __mul__ mult1.py:26
69
#4 | 0.8 %| timeit ...thon2.3/timeit.py:144
70
#5 | 0.2 %| do_profile ...psyco/profiler.py:318
71
#6 | 0.2 %| dumpcharges ...s/psyco/logger.py:56
72
#7 | 0.2 %| matmul prof.py:3
73
14:31:55.70 ______
74
#1 |52.9 %| active_start ...psyco/profiler.py:277
75
#2 | 2.9 %| inner <timeit-src>:2
76
#3 | 2.1 %| __mul__ mult1.py:26
77
#4 | 0.8 %| timeit ...thon2.3/timeit.py:144
78
#5 | 0.4 %| dumpcharges ...s/psyco/logger.py:56
79
#6 | 0.2 %| do_profile ...psyco/profiler.py:318
80
#7 | 0.2 %| matmul prof.py:3
81
14:31:55.90 ______
82
#1 |52.8 %| active_start ...psyco/profiler.py:277
83
#2 | 2.9 %| inner <timeit-src>:2
84
#3 | 2.1 %| __mul__ mult1.py:26
85
#4 | 0.8 %| timeit ...thon2.3/timeit.py:144
86
#5 | 0.6 %| dumpcharges ...s/psyco/logger.py:56
87
#6 | 0.2 %| do_profile ...psyco/profiler.py:318
88
#7 | 0.2 %| matmul prof.py:3
89
14:31:56.41 ______
90
#1 |52.4 %| active_start ...psyco/profiler.py:277
91
#2 | 2.8 %| inner <timeit-src>:2
92
#3 | 2.1 %| __mul__ mult1.py:26
93
#4 | 0.9 %| timeit ...thon2.3/timeit.py:144
94
#5 | 0.8 %| dumpcharges ...s/psyco/logger.py:56
95
#6 | 0.5 %| do_profile ...psyco/profiler.py:318
96
#7 | 0.2 %| matmul prof.py:3
97
#8 | 0.1 %| reindent ...thon2.3/timeit.py:88
98
14:31:56.71 ______
99
#1 |52.1 %| active_start ...psyco/profiler.py:277
100
#2 | 2.8 %| inner <timeit-src>:2
101
#3 | 2.1 %| __mul__ mult1.py:26
102
#4 | 1.0 %| timeit ...thon2.3/timeit.py:144
103
#5 | 0.9 %| dumpcharges ...s/psyco/logger.py:56
104
#6 | 0.5 %| do_profile ...psyco/profiler.py:318
105
#7 | 0.3 %| matmul prof.py:3
106
#8 | 0.1 %| reindent ...thon2.3/timeit.py:88
107
14:31:56.85 ______
108
#1 |52.0 %| active_start ...psyco/profiler.py:277
109
#2 | 2.8 %| inner <timeit-src>:2
110
#3 | 2.1 %| __mul__ mult1.py:26
111
#4 | 1.2 %| dumpcharges ...s/psyco/logger.py:56
112
#5 | 1.0 %| timeit ...thon2.3/timeit.py:144
113
#6 | 0.5 %| do_profile ...psyco/profiler.py:318
114
#7 | 0.3 %| matmul prof.py:3
115
#8 | 0.1 %| reindent ...thon2.3/timeit.py:88
116
14:31:57.05 ______
117
#1 |51.7 %| active_start ...psyco/profiler.py:277
118
#2 | 2.8 %| inner <timeit-src>:2
119
#3 | 2.0 %| __mul__ mult1.py:26
120
#4 | 1.3 %| dumpcharges ...s/psyco/logger.py:56
121
#5 | 1.0 %| timeit ...thon2.3/timeit.py:144
122
#6 | 0.9 %| do_profile ...psyco/profiler.py:318
123
#7 | 0.3 %| matmul prof.py:3
124
#8 | 0.1 %| reindent ...thon2.3/timeit.py:88
125
14:31:57.25 ______
126
#1 |51.4 %| active_start ...psyco/profiler.py:277
127
#2 | 2.8 %| inner <timeit-src>:2
128
#3 | 2.0 %| __mul__ mult1.py:26
129
#4 | 1.5 %| dumpcharges ...s/psyco/logger.py:56
130
#5 | 1.2 %| do_profile ...psyco/profiler.py:318
131
#6 | 1.0 %| timeit ...thon2.3/timeit.py:144
132
#7 | 0.3 %| matmul prof.py:3
133
#8 | 0.1 %| reindent ...thon2.3/timeit.py:88
134
14:31:57.45 ______
135
#1 |50.8 %| active_start ...psyco/profiler.py:277
136
#2 | 2.8 %| inner <timeit-src>:2
137
#3 | 2.0 %| dumpcharges ...s/psyco/logger.py:56
138
#4 | 2.0 %| __mul__ mult1.py:26
139
#5 | 1.8 %| do_profile ...psyco/profiler.py:318
140
#6 | 1.0 %| timeit ...thon2.3/timeit.py:144
141
#7 | 0.3 %| matmul prof.py:3
142
#8 | 0.1 %| reindent ...thon2.3/timeit.py:88
143
14:31:57.98 ______
144
#1 |49.4 %| active_start ...psyco/profiler.py:277
145
#2 | 3.0 %| dumpcharges ...s/psyco/logger.py:56
146
#3 | 2.7 %| inner <timeit-src>:2
147
#4 | 2.0 %| __mul__ mult1.py:26
148
#5 | 2.0 %| do_profile ...psyco/profiler.py:318
149
#6 | 1.2 %| timeit ...thon2.3/timeit.py:144
150
#7 | 0.6 %| matmul prof.py:3
151
#8 | 0.5 %| __init__ ...thon2.3/timeit.py:108
152
#9 | 0.4 %| inner <timeit-src>:2
153
#10 | 0.1 %| reindent ...thon2.3/timeit.py:88
154
14:31:58.31 ______
155
#1 |48.6 %| active_start ...psyco/profiler.py:277
156
#2 | 3.4 %| dumpcharges ...s/psyco/logger.py:56
157
#3 | 2.6 %| inner <timeit-src>:2
158
#4 | 2.4 %| do_profile ...psyco/profiler.py:318
159
#5 | 1.9 %| __mul__ mult1.py:26
160
#6 | 1.4 %| timeit ...thon2.3/timeit.py:144
161
#7 | 0.7 %| matmul prof.py:3
162
#8 | 0.5 %| __init__ ...thon2.3/timeit.py:108
163
#9 | 0.5 %| inner <timeit-src>:2
164
#10 | 0.4 %| inner <timeit-src>:2
165
14:31:58.55 ______
166
#1 |47.8 %| active_start ...psyco/profiler.py:277
167
#2 | 4.4 %| dumpcharges ...s/psyco/logger.py:56
168
#3 | 2.9 %| do_profile ...psyco/profiler.py:318
169
#4 | 2.6 %| inner <timeit-src>:2
170
#5 | 1.9 %| __mul__ mult1.py:26
171
#6 | 1.4 %| timeit ...thon2.3/timeit.py:144
172
#7 | 0.7 %| matmul prof.py:3
173
#8 | 0.5 %| __init__ ...thon2.3/timeit.py:108
174
#9 | 0.5 %| inner <timeit-src>:2
175
#10 | 0.4 %| inner <timeit-src>:2
176
14:31:58.75 ______
177
#1 |46.8 %| active_start ...psyco/profiler.py:277
178
#2 | 5.5 %| dumpcharges ...s/psyco/logger.py:56
179
#3 | 3.6 %| do_profile ...psyco/profiler.py:318
180
#4 | 2.5 %| inner <timeit-src>:2
181
#5 | 1.9 %| __mul__ mult1.py:26
182
#6 | 1.3 %| timeit ...thon2.3/timeit.py:144
183
#7 | 0.7 %| matmul prof.py:3
184
#8 | 0.5 %| __init__ ...thon2.3/timeit.py:108
185
#9 | 0.4 %| inner <timeit-src>:2
186
#10 | 0.4 %| inner <timeit-src>:2
187
14:31:58.95 ______
188
#1 |45.3 %| active_start ...psyco/profiler.py:277
189
#2 | 7.0 %| dumpcharges ...s/psyco/logger.py:56
190
#3 | 5.2 %| do_profile ...psyco/profiler.py:318
191
#4 | 2.5 %| inner <timeit-src>:2
192
#5 | 1.8 %| __mul__ mult1.py:26
193
#6 | 1.3 %| timeit ...thon2.3/timeit.py:144
194
#7 | 0.7 %| matmul prof.py:3
195
#8 | 0.5 %| __init__ ...thon2.3/timeit.py:108
196
#9 | 0.4 %| inner <timeit-src>:2
197
#10 | 0.4 %| inner <timeit-src>:2
198
14:31:59.15 ______
199
#1 |43.4 %| active_start ...psyco/profiler.py:277
200
#2 | 8.7 %| dumpcharges ...s/psyco/logger.py:56
201
#3 | 7.2 %| do_profile ...psyco/profiler.py:318
202
#4 | 2.4 %| inner <timeit-src>:2
203
#5 | 1.7 %| __mul__ mult1.py:26
204
#6 | 1.2 %| timeit ...thon2.3/timeit.py:144
205
#7 | 0.6 %| matmul prof.py:3
206
#8 | 0.4 %| __init__ ...thon2.3/timeit.py:108
207
#9 | 0.4 %| inner <timeit-src>:2
208
#10 | 0.4 %| inner <timeit-src>:2
209
14:31:59.15 tag function: psyco.logger.dumpcharges %
210
14:31:59.15 tag function: psyco.profiler.PassiveProfiler.do_profile %
211
14:31:59.20 memory usage: 142+ kb %
212
14:31:59.20 program exit, 11/11/04 %%%%%%%%%%%%%%%%%%%%
213