Guido van Rossum | f137f75 | 2001-10-04 00:58:24 +0000 | [diff] [blame] | 1 | """Test suite for the profile module.""" |
| 2 | |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 3 | import sys |
| 4 | import pstats |
| 5 | import unittest |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 6 | import os |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 7 | from difflib import unified_diff |
| 8 | from io import StringIO |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 9 | from test.support import TESTFN, run_unittest, unlink |
| 10 | from contextlib import contextmanager |
Guido van Rossum | f137f75 | 2001-10-04 00:58:24 +0000 | [diff] [blame] | 11 | |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 12 | import profile |
| 13 | from test.profilee import testfunc, timer |
Mario Corchero | ad1a25f | 2018-11-05 15:03:46 +0300 | [diff] [blame] | 14 | from test.support.script_helper import assert_python_failure, assert_python_ok |
Tim Peters | 527e64f | 2001-10-04 05:36:56 +0000 | [diff] [blame] | 15 | |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 16 | |
| 17 | class ProfileTest(unittest.TestCase): |
| 18 | |
| 19 | profilerclass = profile.Profile |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 20 | profilermodule = profile |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 21 | methodnames = ['print_stats', 'print_callers', 'print_callees'] |
Antoine Pitrou | 8e124f3 | 2009-05-30 21:41:10 +0000 | [diff] [blame] | 22 | expected_max_output = ':0(max)' |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 23 | |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 24 | def tearDown(self): |
| 25 | unlink(TESTFN) |
| 26 | |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 27 | def get_expected_output(self): |
| 28 | return _ProfileOutput |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 29 | |
| 30 | @classmethod |
| 31 | def do_profiling(cls): |
| 32 | results = [] |
| 33 | prof = cls.profilerclass(timer, 0.001) |
Christian Heimes | dae2a89 | 2008-04-19 00:55:37 +0000 | [diff] [blame] | 34 | start_timer = timer() |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 35 | prof.runctx("testfunc()", globals(), locals()) |
Christian Heimes | dae2a89 | 2008-04-19 00:55:37 +0000 | [diff] [blame] | 36 | results.append(timer() - start_timer) |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 37 | for methodname in cls.methodnames: |
| 38 | s = StringIO() |
| 39 | stats = pstats.Stats(prof, stream=s) |
| 40 | stats.strip_dirs().sort_stats("stdname") |
| 41 | getattr(stats, methodname)() |
Brett Cannon | c17b35c | 2008-03-01 04:28:23 +0000 | [diff] [blame] | 42 | output = s.getvalue().splitlines() |
| 43 | mod_name = testfunc.__module__.rsplit('.', 1)[1] |
| 44 | # Only compare against stats originating from the test file. |
| 45 | # Prevents outside code (e.g., the io module) from causing |
| 46 | # unexpected output. |
| 47 | output = [line.rstrip() for line in output if mod_name in line] |
| 48 | results.append('\n'.join(output)) |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 49 | return results |
| 50 | |
| 51 | def test_cprofile(self): |
| 52 | results = self.do_profiling() |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 53 | expected = self.get_expected_output() |
Christian Heimes | dae2a89 | 2008-04-19 00:55:37 +0000 | [diff] [blame] | 54 | self.assertEqual(results[0], 1000) |
jdemeyer | ac9240b | 2018-05-09 06:16:35 +0200 | [diff] [blame] | 55 | fail = [] |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 56 | for i, method in enumerate(self.methodnames): |
jdemeyer | ac9240b | 2018-05-09 06:16:35 +0200 | [diff] [blame] | 57 | a = expected[method] |
| 58 | b = results[i+1] |
| 59 | if a != b: |
| 60 | fail.append(f"\nStats.{method} output for " |
| 61 | f"{self.profilerclass.__name__} " |
| 62 | "does not fit expectation:") |
| 63 | fail.extend(unified_diff(a.split('\n'), b.split('\n'), |
| 64 | lineterm="")) |
| 65 | if fail: |
| 66 | self.fail("\n".join(fail)) |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 67 | |
Antoine Pitrou | 8e124f3 | 2009-05-30 21:41:10 +0000 | [diff] [blame] | 68 | def test_calling_conventions(self): |
| 69 | # Issue #5330: profile and cProfile wouldn't report C functions called |
| 70 | # with keyword arguments. We test all calling conventions. |
| 71 | stmts = [ |
| 72 | "max([0])", |
| 73 | "max([0], key=int)", |
| 74 | "max([0], **dict(key=int))", |
| 75 | "max(*([0],))", |
| 76 | "max(*([0],), key=int)", |
| 77 | "max(*([0],), **dict(key=int))", |
| 78 | ] |
| 79 | for stmt in stmts: |
| 80 | s = StringIO() |
| 81 | prof = self.profilerclass(timer, 0.001) |
| 82 | prof.runctx(stmt, globals(), locals()) |
| 83 | stats = pstats.Stats(prof, stream=s) |
| 84 | stats.print_stats() |
| 85 | res = s.getvalue() |
Ezio Melotti | b58e0bd | 2010-01-23 15:40:09 +0000 | [diff] [blame] | 86 | self.assertIn(self.expected_max_output, res, |
Antoine Pitrou | 8e124f3 | 2009-05-30 21:41:10 +0000 | [diff] [blame] | 87 | "Profiling {0!r} didn't report max:\n{1}".format(stmt, res)) |
| 88 | |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 89 | def test_run(self): |
| 90 | with silent(): |
Giampaolo Rodola' | 58cf453 | 2013-02-12 15:23:21 +0100 | [diff] [blame] | 91 | self.profilermodule.run("int('1')") |
| 92 | self.profilermodule.run("int('1')", filename=TESTFN) |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 93 | self.assertTrue(os.path.exists(TESTFN)) |
| 94 | |
| 95 | def test_runctx(self): |
| 96 | with silent(): |
| 97 | self.profilermodule.runctx("testfunc()", globals(), locals()) |
| 98 | self.profilermodule.runctx("testfunc()", globals(), locals(), |
| 99 | filename=TESTFN) |
| 100 | self.assertTrue(os.path.exists(TESTFN)) |
| 101 | |
Mario Corchero | ad1a25f | 2018-11-05 15:03:46 +0300 | [diff] [blame] | 102 | def test_run_profile_as_module(self): |
| 103 | # Test that -m switch needs an argument |
| 104 | assert_python_failure('-m', self.profilermodule.__name__, '-m') |
| 105 | |
| 106 | # Test failure for not-existent module |
| 107 | assert_python_failure('-m', self.profilermodule.__name__, |
| 108 | '-m', 'random_module_xyz') |
| 109 | |
| 110 | # Test successful run |
| 111 | assert_python_ok('-m', self.profilermodule.__name__, |
| 112 | '-m', 'timeit', '-n', '1') |
| 113 | |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 114 | |
| 115 | def regenerate_expected_output(filename, cls): |
| 116 | filename = filename.rstrip('co') |
| 117 | print('Regenerating %s...' % filename) |
| 118 | results = cls.do_profiling() |
| 119 | |
| 120 | newfile = [] |
| 121 | with open(filename, 'r') as f: |
| 122 | for line in f: |
| 123 | newfile.append(line) |
Brett Cannon | c17b35c | 2008-03-01 04:28:23 +0000 | [diff] [blame] | 124 | if line.startswith('#--cut'): |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 125 | break |
| 126 | |
| 127 | with open(filename, 'w') as f: |
| 128 | f.writelines(newfile) |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 129 | f.write("_ProfileOutput = {}\n") |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 130 | for i, method in enumerate(cls.methodnames): |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 131 | f.write('_ProfileOutput[%r] = """\\\n%s"""\n' % ( |
| 132 | method, results[i+1])) |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 133 | f.write('\nif __name__ == "__main__":\n main()\n') |
| 134 | |
Giampaolo Rodola' | b071d4f | 2013-02-12 14:31:06 +0100 | [diff] [blame] | 135 | @contextmanager |
| 136 | def silent(): |
| 137 | stdout = sys.stdout |
| 138 | try: |
| 139 | sys.stdout = StringIO() |
| 140 | yield |
| 141 | finally: |
| 142 | sys.stdout = stdout |
Armin Rigo | a871ef2 | 2006-02-08 12:53:56 +0000 | [diff] [blame] | 143 | |
| 144 | def test_main(): |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 145 | run_unittest(ProfileTest) |
Guido van Rossum | f137f75 | 2001-10-04 00:58:24 +0000 | [diff] [blame] | 146 | |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 147 | def main(): |
| 148 | if '-r' not in sys.argv: |
| 149 | test_main() |
Armin Rigo | a871ef2 | 2006-02-08 12:53:56 +0000 | [diff] [blame] | 150 | else: |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 151 | regenerate_expected_output(__file__, ProfileTest) |
Armin Rigo | a871ef2 | 2006-02-08 12:53:56 +0000 | [diff] [blame] | 152 | |
Guido van Rossum | f137f75 | 2001-10-04 00:58:24 +0000 | [diff] [blame] | 153 | |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 154 | # Don't remove this comment. Everything below it is auto-generated. |
| 155 | #--cut-------------------------------------------------------------------------- |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 156 | _ProfileOutput = {} |
| 157 | _ProfileOutput['print_stats'] = """\ |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 158 | 28 27.972 0.999 27.972 0.999 profilee.py:110(__getattr__) |
| 159 | 1 269.996 269.996 999.769 999.769 profilee.py:25(testfunc) |
| 160 | 23/3 149.937 6.519 169.917 56.639 profilee.py:35(factorial) |
| 161 | 20 19.980 0.999 19.980 0.999 profilee.py:48(mul) |
| 162 | 2 39.986 19.993 599.830 299.915 profilee.py:55(helper) |
| 163 | 4 115.984 28.996 119.964 29.991 profilee.py:73(helper1) |
| 164 | 2 -0.006 -0.003 139.946 69.973 profilee.py:84(helper2_indirect) |
| 165 | 8 311.976 38.997 399.912 49.989 profilee.py:88(helper2) |
Brett Cannon | c17b35c | 2008-03-01 04:28:23 +0000 | [diff] [blame] | 166 | 8 63.976 7.997 79.960 9.995 profilee.py:98(subhelper)""" |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 167 | _ProfileOutput['print_callers'] = """\ |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 168 | :0(append) <- profilee.py:73(helper1)(4) 119.964 |
| 169 | :0(exc_info) <- profilee.py:73(helper1)(4) 119.964 |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 170 | :0(hasattr) <- profilee.py:73(helper1)(4) 119.964 |
| 171 | profilee.py:88(helper2)(8) 399.912 |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 172 | profilee.py:110(__getattr__) <- :0(hasattr)(12) 11.964 |
| 173 | profilee.py:98(subhelper)(16) 79.960 |
| 174 | profilee.py:25(testfunc) <- <string>:1(<module>)(1) 999.767 |
| 175 | profilee.py:35(factorial) <- profilee.py:25(testfunc)(1) 999.769 |
| 176 | profilee.py:35(factorial)(20) 169.917 |
| 177 | profilee.py:84(helper2_indirect)(2) 139.946 |
| 178 | profilee.py:48(mul) <- profilee.py:35(factorial)(20) 169.917 |
| 179 | profilee.py:55(helper) <- profilee.py:25(testfunc)(2) 999.769 |
| 180 | profilee.py:73(helper1) <- profilee.py:55(helper)(4) 599.830 |
| 181 | profilee.py:84(helper2_indirect) <- profilee.py:55(helper)(2) 599.830 |
| 182 | profilee.py:88(helper2) <- profilee.py:55(helper)(6) 599.830 |
| 183 | profilee.py:84(helper2_indirect)(2) 139.946 |
Brett Cannon | c17b35c | 2008-03-01 04:28:23 +0000 | [diff] [blame] | 184 | profilee.py:98(subhelper) <- profilee.py:88(helper2)(8) 399.912""" |
Benjamin Peterson | 7d76653 | 2008-10-06 22:05:00 +0000 | [diff] [blame] | 185 | _ProfileOutput['print_callees'] = """\ |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 186 | :0(hasattr) -> profilee.py:110(__getattr__)(12) 27.972 |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 187 | <string>:1(<module>) -> profilee.py:25(testfunc)(1) 999.769 |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 188 | profilee.py:110(__getattr__) -> |
| 189 | profilee.py:25(testfunc) -> profilee.py:35(factorial)(1) 169.917 |
| 190 | profilee.py:55(helper)(2) 599.830 |
| 191 | profilee.py:35(factorial) -> profilee.py:35(factorial)(20) 169.917 |
| 192 | profilee.py:48(mul)(20) 19.980 |
| 193 | profilee.py:48(mul) -> |
| 194 | profilee.py:55(helper) -> profilee.py:73(helper1)(4) 119.964 |
| 195 | profilee.py:84(helper2_indirect)(2) 139.946 |
| 196 | profilee.py:88(helper2)(6) 399.912 |
| 197 | profilee.py:73(helper1) -> :0(append)(4) -0.004 |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 198 | profilee.py:84(helper2_indirect) -> profilee.py:35(factorial)(2) 169.917 |
| 199 | profilee.py:88(helper2)(2) 399.912 |
| 200 | profilee.py:88(helper2) -> :0(hasattr)(8) 11.964 |
| 201 | profilee.py:98(subhelper)(8) 79.960 |
Brett Cannon | c17b35c | 2008-03-01 04:28:23 +0000 | [diff] [blame] | 202 | profilee.py:98(subhelper) -> profilee.py:110(__getattr__)(16) 27.972""" |
Guido van Rossum | f137f75 | 2001-10-04 00:58:24 +0000 | [diff] [blame] | 203 | |
| 204 | if __name__ == "__main__": |
Christian Heimes | 2202f87 | 2008-02-06 14:31:34 +0000 | [diff] [blame] | 205 | main() |