2010-10-16 22:36:15 -03:00
|
|
|
import gc
|
2001-09-22 01:28:19 -03:00
|
|
|
import pprint
|
|
|
|
import sys
|
|
|
|
import unittest
|
|
|
|
|
2010-07-28 11:26:06 -03:00
|
|
|
from test import test_support
|
2001-09-22 01:28:19 -03:00
|
|
|
|
2008-01-20 09:59:46 -04:00
|
|
|
class TestGetProfile(unittest.TestCase):
|
|
|
|
def setUp(self):
|
|
|
|
sys.setprofile(None)
|
|
|
|
|
|
|
|
def tearDown(self):
|
|
|
|
sys.setprofile(None)
|
|
|
|
|
|
|
|
def test_empty(self):
|
2010-10-16 22:36:15 -03:00
|
|
|
self.assertIsNone(sys.getprofile())
|
2008-01-20 09:59:46 -04:00
|
|
|
|
|
|
|
def test_setget(self):
|
|
|
|
def fn(*args):
|
|
|
|
pass
|
|
|
|
|
|
|
|
sys.setprofile(fn)
|
2010-10-16 22:36:15 -03:00
|
|
|
self.assertIs(sys.getprofile(), fn)
|
2001-09-22 01:28:19 -03:00
|
|
|
|
|
|
|
class HookWatcher:
|
|
|
|
def __init__(self):
|
|
|
|
self.frames = []
|
|
|
|
self.events = []
|
|
|
|
|
|
|
|
def callback(self, frame, event, arg):
|
2004-03-24 17:57:10 -04:00
|
|
|
if (event == "call"
|
|
|
|
or event == "return"
|
|
|
|
or event == "exception"):
|
|
|
|
self.add_event(event, frame)
|
2001-09-22 01:28:19 -03:00
|
|
|
|
|
|
|
def add_event(self, event, frame=None):
|
|
|
|
"""Add an event to the log."""
|
|
|
|
if frame is None:
|
|
|
|
frame = sys._getframe(1)
|
|
|
|
|
|
|
|
try:
|
|
|
|
frameno = self.frames.index(frame)
|
|
|
|
except ValueError:
|
|
|
|
frameno = len(self.frames)
|
|
|
|
self.frames.append(frame)
|
|
|
|
|
|
|
|
self.events.append((frameno, event, ident(frame)))
|
|
|
|
|
|
|
|
def get_events(self):
|
|
|
|
"""Remove calls to add_event()."""
|
2010-07-28 11:26:06 -03:00
|
|
|
disallowed = [ident(self.add_event.im_func), ident(ident)]
|
2001-09-24 15:44:11 -03:00
|
|
|
self.frames = None
|
2001-09-22 01:28:19 -03:00
|
|
|
|
2001-09-24 15:44:11 -03:00
|
|
|
return [item for item in self.events if item[2] not in disallowed]
|
2001-09-22 01:28:19 -03:00
|
|
|
|
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
class ProfileSimulator(HookWatcher):
|
2001-10-04 11:49:46 -03:00
|
|
|
def __init__(self, testcase):
|
|
|
|
self.testcase = testcase
|
2001-09-26 18:00:33 -03:00
|
|
|
self.stack = []
|
|
|
|
HookWatcher.__init__(self)
|
|
|
|
|
|
|
|
def callback(self, frame, event, arg):
|
2001-10-03 18:15:32 -03:00
|
|
|
# Callback registered with sys.setprofile()/sys.settrace()
|
2001-09-26 18:00:33 -03:00
|
|
|
self.dispatch[event](self, frame)
|
|
|
|
|
|
|
|
def trace_call(self, frame):
|
|
|
|
self.add_event('call', frame)
|
|
|
|
self.stack.append(frame)
|
|
|
|
|
|
|
|
def trace_return(self, frame):
|
|
|
|
self.add_event('return', frame)
|
|
|
|
self.stack.pop()
|
|
|
|
|
|
|
|
def trace_exception(self, frame):
|
2001-10-04 11:49:46 -03:00
|
|
|
self.testcase.fail(
|
|
|
|
"the profiler should never receive exception events")
|
2001-09-26 18:00:33 -03:00
|
|
|
|
2004-03-24 17:57:10 -04:00
|
|
|
def trace_pass(self, frame):
|
|
|
|
pass
|
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
dispatch = {
|
|
|
|
'call': trace_call,
|
|
|
|
'exception': trace_exception,
|
|
|
|
'return': trace_return,
|
2004-03-24 17:57:10 -04:00
|
|
|
'c_call': trace_pass,
|
|
|
|
'c_return': trace_pass,
|
|
|
|
'c_exception': trace_pass,
|
2001-09-26 18:00:33 -03:00
|
|
|
}
|
2001-09-22 01:28:19 -03:00
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
|
|
|
|
class TestCaseBase(unittest.TestCase):
|
2001-09-22 01:28:19 -03:00
|
|
|
def check_events(self, callable, expected):
|
2001-09-26 18:00:33 -03:00
|
|
|
events = capture_events(callable, self.new_watcher())
|
2001-09-22 01:28:19 -03:00
|
|
|
if events != expected:
|
|
|
|
self.fail("Expected events:\n%s\nReceived events:\n%s"
|
|
|
|
% (pprint.pformat(expected), pprint.pformat(events)))
|
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
|
|
|
|
class ProfileHookTestCase(TestCaseBase):
|
|
|
|
def new_watcher(self):
|
|
|
|
return HookWatcher()
|
|
|
|
|
2001-09-22 01:28:19 -03:00
|
|
|
def test_simple(self):
|
|
|
|
def f(p):
|
|
|
|
pass
|
|
|
|
f_ident = ident(f)
|
2001-09-25 17:48:14 -03:00
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
|
|
|
(1, 'return', f_ident),
|
2001-09-22 01:28:19 -03:00
|
|
|
])
|
|
|
|
|
|
|
|
def test_exception(self):
|
2001-09-25 17:48:14 -03:00
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-09-25 17:48:14 -03:00
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', f_ident),
|
2001-09-25 17:48:14 -03:00
|
|
|
])
|
|
|
|
|
|
|
|
def test_caught_exception(self):
|
2001-09-22 01:28:19 -03:00
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
try: 1./0
|
2001-09-24 15:44:11 -03:00
|
|
|
except: pass
|
2001-09-22 01:28:19 -03:00
|
|
|
f_ident = ident(f)
|
2001-09-25 17:48:14 -03:00
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
|
|
|
(1, 'return', f_ident),
|
2001-09-22 01:28:19 -03:00
|
|
|
])
|
|
|
|
|
2001-09-24 15:44:11 -03:00
|
|
|
def test_caught_nested_exception(self):
|
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
try: 1./0
|
2001-09-24 15:44:11 -03:00
|
|
|
except: pass
|
|
|
|
f_ident = ident(f)
|
2001-09-25 17:48:14 -03:00
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
(1, 'return', f_ident),
|
|
|
|
])
|
|
|
|
|
2001-09-22 01:28:19 -03:00
|
|
|
def test_nested_exception(self):
|
2001-09-24 15:44:11 -03:00
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-09-24 15:44:11 -03:00
|
|
|
f_ident = ident(f)
|
2001-09-25 17:48:14 -03:00
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
# This isn't what I expected:
|
2001-10-04 11:49:46 -03:00
|
|
|
# (0, 'exception', protect_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
# I expected this again:
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', f_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
])
|
|
|
|
|
|
|
|
def test_exception_in_except_clause(self):
|
2001-09-22 01:28:19 -03:00
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-09-22 01:28:19 -03:00
|
|
|
def g(p):
|
|
|
|
try:
|
|
|
|
f(p)
|
|
|
|
except:
|
2001-09-24 15:44:11 -03:00
|
|
|
try: f(p)
|
|
|
|
except: pass
|
2001-09-22 01:28:19 -03:00
|
|
|
f_ident = ident(f)
|
|
|
|
g_ident = ident(g)
|
2001-09-25 17:48:14 -03:00
|
|
|
self.check_events(g, [(1, 'call', g_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
(2, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(2, 'return', f_ident),
|
2001-09-25 17:48:14 -03:00
|
|
|
(3, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(3, 'return', f_ident),
|
2001-09-25 17:48:14 -03:00
|
|
|
(1, 'return', g_ident),
|
2001-09-22 01:28:19 -03:00
|
|
|
])
|
|
|
|
|
2001-09-24 15:44:11 -03:00
|
|
|
def test_exception_propogation(self):
|
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-09-24 15:44:11 -03:00
|
|
|
def g(p):
|
|
|
|
try: f(p)
|
|
|
|
finally: p.add_event("falling through")
|
|
|
|
f_ident = ident(f)
|
|
|
|
g_ident = ident(g)
|
2001-09-25 17:48:14 -03:00
|
|
|
self.check_events(g, [(1, 'call', g_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
(2, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(2, 'return', f_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
(1, 'falling through', g_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', g_ident),
|
2001-09-24 15:44:11 -03:00
|
|
|
])
|
2001-09-22 01:28:19 -03:00
|
|
|
|
2001-09-25 17:48:14 -03:00
|
|
|
def test_raise_twice(self):
|
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
try: 1./0
|
|
|
|
except: 1./0
|
2001-09-25 17:48:14 -03:00
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', f_ident),
|
2001-09-25 17:48:14 -03:00
|
|
|
])
|
|
|
|
|
|
|
|
def test_raise_reraise(self):
|
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
try: 1./0
|
2001-09-25 17:48:14 -03:00
|
|
|
except: raise
|
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', f_ident),
|
2001-09-25 17:48:14 -03:00
|
|
|
])
|
|
|
|
|
|
|
|
def test_raise(self):
|
|
|
|
def f(p):
|
|
|
|
raise Exception()
|
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', f_ident),
|
2001-09-25 17:48:14 -03:00
|
|
|
])
|
|
|
|
|
2001-10-03 18:15:32 -03:00
|
|
|
def test_distant_exception(self):
|
|
|
|
def f():
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-10-03 18:15:32 -03:00
|
|
|
def g():
|
|
|
|
f()
|
|
|
|
def h():
|
|
|
|
g()
|
|
|
|
def i():
|
|
|
|
h()
|
|
|
|
def j(p):
|
|
|
|
i()
|
|
|
|
f_ident = ident(f)
|
|
|
|
g_ident = ident(g)
|
|
|
|
h_ident = ident(h)
|
|
|
|
i_ident = ident(i)
|
|
|
|
j_ident = ident(j)
|
|
|
|
self.check_events(j, [(1, 'call', j_ident),
|
|
|
|
(2, 'call', i_ident),
|
|
|
|
(3, 'call', h_ident),
|
|
|
|
(4, 'call', g_ident),
|
|
|
|
(5, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(5, 'return', f_ident),
|
|
|
|
(4, 'return', g_ident),
|
|
|
|
(3, 'return', h_ident),
|
|
|
|
(2, 'return', i_ident),
|
|
|
|
(1, 'return', j_ident),
|
2001-10-03 18:15:32 -03:00
|
|
|
])
|
|
|
|
|
|
|
|
def test_generator(self):
|
|
|
|
def f():
|
|
|
|
for i in range(2):
|
|
|
|
yield i
|
|
|
|
def g(p):
|
|
|
|
for i in f():
|
|
|
|
pass
|
|
|
|
f_ident = ident(f)
|
|
|
|
g_ident = ident(g)
|
|
|
|
self.check_events(g, [(1, 'call', g_ident),
|
|
|
|
# call the iterator twice to generate values
|
|
|
|
(2, 'call', f_ident),
|
|
|
|
(2, 'return', f_ident),
|
|
|
|
(2, 'call', f_ident),
|
|
|
|
(2, 'return', f_ident),
|
|
|
|
# once more; returns end-of-iteration with
|
|
|
|
# actually raising an exception
|
|
|
|
(2, 'call', f_ident),
|
|
|
|
(2, 'return', f_ident),
|
|
|
|
(1, 'return', g_ident),
|
|
|
|
])
|
|
|
|
|
|
|
|
def test_stop_iteration(self):
|
|
|
|
def f():
|
|
|
|
for i in range(2):
|
|
|
|
yield i
|
|
|
|
raise StopIteration
|
|
|
|
def g(p):
|
|
|
|
for i in f():
|
|
|
|
pass
|
|
|
|
f_ident = ident(f)
|
|
|
|
g_ident = ident(g)
|
|
|
|
self.check_events(g, [(1, 'call', g_ident),
|
|
|
|
# call the iterator twice to generate values
|
|
|
|
(2, 'call', f_ident),
|
|
|
|
(2, 'return', f_ident),
|
|
|
|
(2, 'call', f_ident),
|
|
|
|
(2, 'return', f_ident),
|
|
|
|
# once more to hit the raise:
|
|
|
|
(2, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(2, 'return', f_ident),
|
2001-10-03 18:15:32 -03:00
|
|
|
(1, 'return', g_ident),
|
|
|
|
])
|
|
|
|
|
2001-09-25 17:48:14 -03:00
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
class ProfileSimulatorTestCase(TestCaseBase):
|
|
|
|
def new_watcher(self):
|
2001-10-04 11:49:46 -03:00
|
|
|
return ProfileSimulator(self)
|
2001-09-26 18:00:33 -03:00
|
|
|
|
|
|
|
def test_simple(self):
|
|
|
|
def f(p):
|
|
|
|
pass
|
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
|
|
|
(1, 'return', f_ident),
|
|
|
|
])
|
|
|
|
|
|
|
|
def test_basic_exception(self):
|
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-09-26 18:00:33 -03:00
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(1, 'return', f_ident),
|
2001-09-26 18:00:33 -03:00
|
|
|
])
|
|
|
|
|
2001-10-03 18:15:32 -03:00
|
|
|
def test_caught_exception(self):
|
|
|
|
def f(p):
|
2010-07-28 11:26:06 -03:00
|
|
|
try: 1./0
|
2001-10-03 18:15:32 -03:00
|
|
|
except: pass
|
|
|
|
f_ident = ident(f)
|
|
|
|
self.check_events(f, [(1, 'call', f_ident),
|
|
|
|
(1, 'return', f_ident),
|
|
|
|
])
|
|
|
|
|
|
|
|
def test_distant_exception(self):
|
|
|
|
def f():
|
2010-07-28 11:26:06 -03:00
|
|
|
1./0
|
2001-10-03 18:15:32 -03:00
|
|
|
def g():
|
|
|
|
f()
|
|
|
|
def h():
|
|
|
|
g()
|
|
|
|
def i():
|
|
|
|
h()
|
|
|
|
def j(p):
|
|
|
|
i()
|
|
|
|
f_ident = ident(f)
|
|
|
|
g_ident = ident(g)
|
|
|
|
h_ident = ident(h)
|
|
|
|
i_ident = ident(i)
|
|
|
|
j_ident = ident(j)
|
|
|
|
self.check_events(j, [(1, 'call', j_ident),
|
|
|
|
(2, 'call', i_ident),
|
|
|
|
(3, 'call', h_ident),
|
|
|
|
(4, 'call', g_ident),
|
|
|
|
(5, 'call', f_ident),
|
2001-10-04 11:49:46 -03:00
|
|
|
(5, 'return', f_ident),
|
|
|
|
(4, 'return', g_ident),
|
|
|
|
(3, 'return', h_ident),
|
|
|
|
(2, 'return', i_ident),
|
|
|
|
(1, 'return', j_ident),
|
2001-10-03 18:15:32 -03:00
|
|
|
])
|
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
|
2001-09-22 01:28:19 -03:00
|
|
|
def ident(function):
|
|
|
|
if hasattr(function, "f_code"):
|
|
|
|
code = function.f_code
|
|
|
|
else:
|
2010-07-28 11:26:06 -03:00
|
|
|
code = function.func_code
|
2001-09-22 01:28:19 -03:00
|
|
|
return code.co_firstlineno, code.co_name
|
|
|
|
|
|
|
|
|
2001-09-25 17:48:14 -03:00
|
|
|
def protect(f, p):
|
|
|
|
try: f(p)
|
|
|
|
except: pass
|
|
|
|
|
|
|
|
protect_ident = ident(protect)
|
|
|
|
|
|
|
|
|
2001-09-26 18:00:33 -03:00
|
|
|
def capture_events(callable, p=None):
|
|
|
|
if p is None:
|
|
|
|
p = HookWatcher()
|
2010-10-16 22:36:15 -03:00
|
|
|
# Disable the garbage collector. This prevents __del__s from showing up in
|
|
|
|
# traces.
|
|
|
|
old_gc = gc.isenabled()
|
|
|
|
gc.disable()
|
|
|
|
try:
|
|
|
|
sys.setprofile(p.callback)
|
|
|
|
protect(callable, p)
|
|
|
|
sys.setprofile(None)
|
|
|
|
finally:
|
|
|
|
if old_gc:
|
|
|
|
gc.enable()
|
2001-09-25 17:48:14 -03:00
|
|
|
return p.get_events()[1:-1]
|
2001-09-22 01:28:19 -03:00
|
|
|
|
|
|
|
|
|
|
|
def show_events(callable):
|
|
|
|
import pprint
|
|
|
|
pprint.pprint(capture_events(callable))
|
|
|
|
|
|
|
|
|
|
|
|
def test_main():
|
2010-07-28 11:26:06 -03:00
|
|
|
test_support.run_unittest(
|
2008-01-20 09:59:46 -04:00
|
|
|
TestGetProfile,
|
2003-05-01 14:45:56 -03:00
|
|
|
ProfileHookTestCase,
|
|
|
|
ProfileSimulatorTestCase
|
|
|
|
)
|
2001-09-22 01:28:19 -03:00
|
|
|
|
|
|
|
|
|
|
|
if __name__ == "__main__":
|
|
|
|
test_main()
|