14
14
# You should have received a copy of the GNU General Public License
15
15
# along with this program; if not, write to the Free Software
16
# Foundation, Inc., 59 Temple Place, Suite 330, Boston, MA 02111-1307 USA
16
# Foundation, Inc., 51 Franklin Street, Fifth Floor, Boston, MA 02110-1301 USA
18
18
"""A custom importer and regex compiler which logs time spent."""
24
if sys.version_info < (2, 5, 0):
36
if sys.platform == 'win32':
31
40
def stack_add(name, frame_name, frame_lineno, scope_name=None):
56
65
def log_stack_info(out_file, sorted=True, hide_fast=True):
57
66
# Find all of the roots with import = 0
58
out_file.write(' cum inline name\t\t\t\t\t\tframe\n')
67
out_file.write('%5s %5s %-40s @ %s:%s\n'
68
% ('cum', 'inline', 'name', 'file', 'line'))
59
69
todo = [(value[-1], key) for key,value in _info.iteritems() if value[0] == 0]
81
91
# indent, cum_time, mod_time, name,
82
92
# scope_name, frame_name, frame_lineno
83
out_file.write('%5.1f %5.1f %s %-35s\t@ %s:%d\n'
84
% (info[-1]*1000., mod_time*1000., '+'*info[0],
85
cur[1][:40], info[1], info[2]))
93
out_file.write('%5.1f %5.1f %-40s @ %s:%d\n'
94
% (info[-1]*1000., mod_time*1000.,
95
('+'*info[0] + cur[1]),
94
105
_real_import = __import__
96
def timed_import(name, globals, locals, fromlist):
107
def timed_import(name, globals=None, locals=None, fromlist=None, level=None):
97
108
"""Wrap around standard importer to log import time"""
109
# normally there are 4, but if this is called as __import__ eg by
110
# /usr/lib/python2.6/email/__init__.py then there may be only one
112
# level is only passed by python2.6
99
scope_name = globals.get('__name__', None)
100
if scope_name is None:
101
scope_name = globals.get('__file__', None)
102
if scope_name is None:
103
scope_name = globals.keys()
115
# can't determine the scope name afaics; we could peek up the stack to
116
# see where this is being called from, but it should be a rare case.
105
# Trim out paths before bzrlib
106
loc = scope_name.find('bzrlib')
108
scope_name = scope_name[loc:]
109
# For stdlib, trim out early paths
110
loc = scope_name.find('python2.4')
112
scope_name = scope_name[loc:]
119
scope_name = globals.get('__name__', None)
120
if scope_name is None:
121
scope_name = globals.get('__file__', None)
122
if scope_name is None:
123
scope_name = globals.keys()
125
# Trim out paths before bzrlib
126
loc = scope_name.find('bzrlib')
128
scope_name = scope_name[loc:]
129
# For stdlib, trim out early paths
130
loc = scope_name.find('python2.4')
132
scope_name = scope_name[loc:]
114
134
# Figure out the frame that is doing the importing
115
135
frame = sys._getframe(1)
116
136
frame_name = frame.f_globals.get('__name__', '<unknown>')
119
138
if frame_name.endswith('demandload'):
120
139
# If this was demandloaded, we have 3 frames to ignore
121
extra = ' (demandload)'
122
frame = sys._getframe(4)
124
frame_name = frame.f_globals.get('__name__', '<unknown>')
140
extra = '(demandload) '
141
frame = sys._getframe(4)
142
frame_name = frame.f_globals.get('__name__', '<unknown>')
143
elif frame_name.endswith('lazy_import'):
144
# If this was lazily imported, we have 3 frames to ignore
146
frame = sys._getframe(4)
147
frame_name = frame.f_globals.get('__name__', '<unknown>')
149
extra += ' [%s]' % (', '.join(map(str, fromlist)),)
125
150
frame_lineno = frame.f_lineno
127
this = stack_add(name+extra, frame_name, frame_lineno, scope_name)
152
this = stack_add(extra + name, frame_name, frame_lineno, scope_name)
132
157
mod = _real_import(name, globals, locals, fromlist)
134
tload = time.time()-tstart
159
tload = _timer()-tstart
135
160
stack_finish(this, tload)
140
_real_compile = sre._compile
165
_real_compile = re._compile
142
168
def timed_compile(*args, **kwargs):
143
169
"""Log how long it takes to compile a regex"""
145
171
# And who is requesting this?
146
172
frame = sys._getframe(2)
147
173
frame_name = frame.f_globals.get('__name__', '<unknown>')
176
if frame_name.endswith('lazy_regex'):
177
# If this was lazily compiled, we have 3 more frames to ignore
179
frame = sys._getframe(5)
180
frame_name = frame.f_globals.get('__name__', '<unknown>')
148
181
frame_lineno = frame.f_lineno
150
this = stack_add(repr(args[0]), frame_name, frame_lineno)
182
this = stack_add(extra+repr(args[0]), frame_name, frame_lineno)
154
186
# Measure the compile time
155
187
comp = _real_compile(*args, **kwargs)
157
tcompile = time.time() - tstart
189
tcompile = _timer() - tstart
158
190
stack_finish(this, tcompile)