← Back to branch summary

~bzr-pqm/bzr/bzr.dev

« back to all changes in this revision

Viewing changes to profile_imports.py

  • Committer: Patch Queue Manager
  • Date: 2011-09-22 14:12:18 UTC
  • mfrom: (6155.3.1 jam)
  • Revision ID: pqm@pqm.ubuntu.com-20110922141218-86s4uu6nqvourw4f
(jameinel) Cleanup comments bzrlib/smart/__init__.py (John A Meinel)

Show diffs side-by-side

added added

removed removed

Lines of Context:
profile_imports.py
1
 
# Copyright (C) 2006 by Canonical Ltd
 
1
# Copyright (C) 2006, 2008, 2009, 2010 by Canonical Ltd
2
2
# Written by John Arbash Meinel <john@arbash-meinel.com>
3
3
#
4
4
# This program is free software; you can redistribute it and/or modify
13
13
#
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
17
17
 
18
18
"""A custom importer and regex compiler which logs time spent."""
19
19
 
20
 
import sre
21
20
import sys
22
21
import time
23
22
 
24
23
 
 
24
import re
 
25
 
 
26
 
25
27
_parent_stack = []
26
28
_total_stack = {}
27
29
_info = {}
28
30
_cur_id = 0
 
31
_timer = time.time
 
32
if sys.platform == 'win32':
 
33
    _timer = time.clock
29
34
 
30
35
 
31
36
def stack_add(name, frame_name, frame_lineno, scope_name=None):
55
60
 
56
61
def log_stack_info(out_file, sorted=True, hide_fast=True):
57
62
    # Find all of the roots with import = 0
58
 
    out_file.write(' cum  inline name\t\t\t\t\t\tframe\n')
 
63
    out_file.write('%5s %5s %-40s @ %s:%s\n'
 
64
        % ('cum', 'inline', 'name', 'file', 'line'))
59
65
    todo = [(value[-1], key) for key,value in _info.iteritems() if value[0] == 0]
60
66
 
61
67
    if sorted:
80
86
 
81
87
        # indent, cum_time, mod_time, name,
82
88
        # 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][:35], info[1], info[2]))
 
89
        out_file.write('%5.1f %5.1f %-40s @ %s:%d\n'
 
90
            % (info[-1]*1000., mod_time*1000.,
 
91
               ('+'*info[0] + cur[1]),
 
92
               info[1], info[2]))
86
93
 
87
94
        if sorted:
88
95
            c_times.sort()
93
100
 
94
101
_real_import = __import__
95
102
 
96
 
def timed_import(name, globals, locals, fromlist):
 
103
def timed_import(name, globals=None, locals=None, fromlist=None, level=None):
97
104
    """Wrap around standard importer to log import time"""
 
105
    # normally there are 4, but if this is called as __import__ eg by
 
106
    # /usr/lib/python2.6/email/__init__.py then there may be only one
 
107
    # parameter
 
108
    # level is only passed by python2.6
98
109
 
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()
 
110
    if globals is None:
 
111
        # can't determine the scope name afaics; we could peek up the stack to
 
112
        # see where this is being called from, but it should be a rare case.
 
113
        scope_name = None
104
114
    else:
105
 
        # Trim out paths before bzrlib
106
 
        loc = scope_name.find('bzrlib')
107
 
        if loc != -1:
108
 
            scope_name = scope_name[loc:]
109
 
        # For stdlib, trim out early paths
110
 
        loc = scope_name.find('python2.4')
111
 
        if loc != -1:
112
 
            scope_name = scope_name[loc:]
 
115
        scope_name = globals.get('__name__', None)
 
116
        if scope_name is None:
 
117
            scope_name = globals.get('__file__', None)
 
118
        if scope_name is None:
 
119
            scope_name = globals.keys()
 
120
        else:
 
121
            # Trim out paths before bzrlib
 
122
            loc = scope_name.find('bzrlib')
 
123
            if loc != -1:
 
124
                scope_name = scope_name[loc:]
 
125
            # For stdlib, trim out early paths
 
126
            loc = scope_name.find('python2.4')
 
127
            if loc != -1:
 
128
                scope_name = scope_name[loc:]
113
129
 
114
130
    # Figure out the frame that is doing the importing
115
131
    frame = sys._getframe(1)
131
147
 
132
148
    this = stack_add(extra + name, frame_name, frame_lineno, scope_name)
133
149
 
134
 
    tstart = time.time()
 
150
    tstart = _timer()
135
151
    try:
136
152
        # Do the import
137
153
        mod = _real_import(name, globals, locals, fromlist)
138
154
    finally:
139
 
        tload = time.time()-tstart
 
155
        tload = _timer()-tstart
140
156
        stack_finish(this, tload)
141
157
 
142
158
    return mod
143
159
 
144
160
 
145
 
_real_compile = sre._compile
 
161
_real_compile = re._compile
 
162
 
146
163
 
147
164
def timed_compile(*args, **kwargs):
148
165
    """Log how long it takes to compile a regex"""
160
177
    frame_lineno = frame.f_lineno
161
178
    this = stack_add(extra+repr(args[0]), frame_name, frame_lineno)
162
179
 
163
 
    tstart = time.time()
 
180
    tstart = _timer()
164
181
    try:
165
182
        # Measure the compile time
166
183
        comp = _real_compile(*args, **kwargs)
167
184
    finally:
168
 
        tcompile = time.time() - tstart
 
185
        tcompile = _timer() - tstart
169
186
        stack_finish(this, tcompile)
170
187
 
171
188
    return comp
174
191
def install():
175
192
    """Install the hooks for measuring import and regex compile time."""
176
193
    __builtins__['__import__'] = timed_import
177
 
    sre._compile = timed_compile
 
194
    re._compile = timed_compile
178
195
 
179
196
 
180
197
def uninstall():
181
198
    """Remove the import and regex compile timing hooks."""
182
199
    __builtins__['__import__'] = _real_import
183
 
    sre._compile = _real_compile
 
200
    re._compile = _real_compile
184
201