blob: a3ec66f2fbea0264ee20bb0b368038832ea1760d [file] [log] [blame]
Georg Brandl116aa622007-08-15 14:28:22 +00001:mod:`timeit` --- Measure execution time of small code snippets
2===============================================================
3
4.. module:: timeit
5 :synopsis: Measure the execution time of small code snippets.
6
7
Georg Brandl116aa622007-08-15 14:28:22 +00008.. index::
9 single: Benchmarking
10 single: Performance
11
Raymond Hettingera1993682011-01-27 01:20:32 +000012**Source code:** :source:`Lib/timeit.py`
13
14--------------
15
Georg Brandl116aa622007-08-15 14:28:22 +000016This module provides a simple way to time small bits of Python code. It has both
Ezio Melottid0fe3e52012-10-02 05:35:39 +030017a :ref:`command-line-interface` as well as a :ref:`callable <python-interface>`
18one. It avoids a number of common traps for measuring execution times.
19See also Tim Peters' introduction to the "Algorithms" chapter in the *Python
20Cookbook*, published by O'Reilly.
Georg Brandl116aa622007-08-15 14:28:22 +000021
Ezio Melottid0fe3e52012-10-02 05:35:39 +030022
23Basic Examples
24--------------
25
26The following example shows how the :ref:`command-line-interface`
27can be used to compare three different expressions:
28
29.. code-block:: sh
30
31 $ python -m timeit '"-".join(str(n) for n in range(100))'
32 10000 loops, best of 3: 40.3 usec per loop
33 $ python -m timeit '"-".join([str(n) for n in range(100)])'
34 10000 loops, best of 3: 33.4 usec per loop
35 $ python -m timeit '"-".join(map(str, range(100)))'
36 10000 loops, best of 3: 25.2 usec per loop
37
38This can be achieved from the :ref:`python-interface` with::
39
40 >>> import timeit
41 >>> timeit.timeit('"-".join(str(n) for n in range(100))', number=10000)
42 0.8187260627746582
43 >>> timeit.timeit('"-".join([str(n) for n in range(100)])', number=10000)
44 0.7288308143615723
45 >>> timeit.timeit('"-".join(map(str, range(100)))', number=10000)
46 0.5858950614929199
47
48Note however that :mod:`timeit` will automatically determine the number of
49repetitions only when the command-line interface is used. In the
50:ref:`timeit-examples` section you can find more advanced examples.
51
52
53.. _python-interface:
54
55Python Interface
56----------------
57
58The module defines three convenience functions and a public class:
59
60
61.. function:: timeit(stmt='pass', setup='pass', timer=<default timer>, number=1000000)
62
63 Create a :class:`Timer` instance with the given statement, *setup* code and
64 *timer* function and run its :meth:`.timeit` method with *number* executions.
65
66
67.. function:: repeat(stmt='pass', setup='pass', timer=<default timer>, repeat=3, number=1000000)
68
69 Create a :class:`Timer` instance with the given statement, *setup* code and
70 *timer* function and run its :meth:`.repeat` method with the given *repeat*
71 count and *number* executions.
72
73
74.. function:: default_timer()
75
76 Define a default timer, in a platform-specific manner. On Windows,
77 :func:`time.clock` has microsecond granularity, but :func:`time.time`'s
78 granularity is 1/60th of a second. On Unix, :func:`time.clock` has 1/100th of
79 a second granularity, and :func:`time.time` is much more precise. On either
80 platform, :func:`default_timer` measures wall clock time, not the CPU
81 time. This means that other processes running on the same computer may
82 interfere with the timing.
Georg Brandl116aa622007-08-15 14:28:22 +000083
84
Georg Brandl7f01a132009-09-16 15:58:14 +000085.. class:: Timer(stmt='pass', setup='pass', timer=<timer function>)
Georg Brandl116aa622007-08-15 14:28:22 +000086
87 Class for timing execution speed of small code snippets.
88
Ezio Melottid0fe3e52012-10-02 05:35:39 +030089 The constructor takes a statement to be timed, an additional statement used
90 for setup, and a timer function. Both statements default to ``'pass'``;
91 the timer function is platform-dependent (see the module doc string).
92 *stmt* and *setup* may also contain multiple statements separated by ``;``
93 or newlines, as long as they don't contain multi-line string literals.
Georg Brandl116aa622007-08-15 14:28:22 +000094
Ezio Melottid0fe3e52012-10-02 05:35:39 +030095 To measure the execution time of the first statement, use the :meth:`.timeit`
96 method. The :meth:`.repeat` method is a convenience to call :meth:`.timeit`
Georg Brandl116aa622007-08-15 14:28:22 +000097 multiple times and return a list of results.
98
Georg Brandl55ac8f02007-09-01 13:51:09 +000099 The *stmt* and *setup* parameters can also take objects that are callable
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300100 without arguments. This will embed calls to them in a timer function that
Ezio Melottia3ccb232012-09-20 06:13:38 +0300101 will then be executed by :meth:`.timeit`. Note that the timing overhead is a
Georg Brandl55ac8f02007-09-01 13:51:09 +0000102 little larger in this case because of the extra function calls.
Georg Brandl116aa622007-08-15 14:28:22 +0000103
104
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300105 .. method:: Timer.timeit(number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000106
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300107 Time *number* executions of the main statement. This executes the setup
108 statement once, and then returns the time it takes to execute the main
109 statement a number of times, measured in seconds as a float.
110 The argument is the number of times through the loop, defaulting to one
111 million. The main statement, the setup statement and the timer function
112 to be used are passed to the constructor.
Georg Brandl116aa622007-08-15 14:28:22 +0000113
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300114 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000115
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300116 By default, :meth:`.timeit` temporarily turns off :term:`garbage
117 collection` during the timing. The advantage of this approach is that
118 it makes independent timings more comparable. This disadvantage is
119 that GC may be an important component of the performance of the
120 function being measured. If so, GC can be re-enabled as the first
121 statement in the *setup* string. For example::
Georg Brandl116aa622007-08-15 14:28:22 +0000122
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300123 timeit.Timer('for i in range(10): oct(i)', 'gc.enable()').timeit()
Georg Brandl116aa622007-08-15 14:28:22 +0000124
125
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300126 .. method:: Timer.repeat(repeat=3, number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000127
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300128 Call :meth:`.timeit` a few times.
Georg Brandl116aa622007-08-15 14:28:22 +0000129
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300130 This is a convenience function that calls the :meth:`.timeit` repeatedly,
131 returning a list of results. The first argument specifies how many times
132 to call :meth:`.timeit`. The second argument specifies the *number*
133 argument for :meth:`.timeit`.
Georg Brandl116aa622007-08-15 14:28:22 +0000134
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300135 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000136
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300137 It's tempting to calculate mean and standard deviation from the result
138 vector and report these. However, this is not very useful.
139 In a typical case, the lowest value gives a lower bound for how fast
140 your machine can run the given code snippet; higher values in the
141 result vector are typically not caused by variability in Python's
142 speed, but by other processes interfering with your timing accuracy.
143 So the :func:`min` of the result is probably the only number you
144 should be interested in. After that, you should look at the entire
145 vector and apply common sense rather than statistics.
Georg Brandl116aa622007-08-15 14:28:22 +0000146
147
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300148 .. method:: Timer.print_exc(file=None)
Georg Brandl116aa622007-08-15 14:28:22 +0000149
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300150 Helper to print a traceback from the timed code.
Georg Brandl116aa622007-08-15 14:28:22 +0000151
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300152 Typical use::
Georg Brandl116aa622007-08-15 14:28:22 +0000153
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300154 t = Timer(...) # outside the try/except
155 try:
156 t.timeit(...) # or t.repeat(...)
157 except:
158 t.print_exc()
Georg Brandl116aa622007-08-15 14:28:22 +0000159
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300160 The advantage over the standard traceback is that source lines in the
161 compiled template will be displayed. The optional *file* argument directs
162 where the traceback is sent; it defaults to :data:`sys.stderr`.
Georg Brandl116aa622007-08-15 14:28:22 +0000163
Georg Brandl116aa622007-08-15 14:28:22 +0000164
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300165.. _command-line-interface:
Sandro Tosie6c34622012-04-24 18:11:46 +0200166
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300167Command-Line Interface
Georg Brandl116aa622007-08-15 14:28:22 +0000168----------------------
169
170When called as a program from the command line, the following form is used::
171
172 python -m timeit [-n N] [-r N] [-s S] [-t] [-c] [-h] [statement ...]
173
Éric Araujo713d3032010-11-18 16:38:46 +0000174Where the following options are understood:
Georg Brandl116aa622007-08-15 14:28:22 +0000175
Éric Araujo713d3032010-11-18 16:38:46 +0000176.. program:: timeit
177
178.. cmdoption:: -n N, --number=N
179
Georg Brandl116aa622007-08-15 14:28:22 +0000180 how many times to execute 'statement'
181
Éric Araujo713d3032010-11-18 16:38:46 +0000182.. cmdoption:: -r N, --repeat=N
183
Georg Brandl116aa622007-08-15 14:28:22 +0000184 how many times to repeat the timer (default 3)
185
Éric Araujo713d3032010-11-18 16:38:46 +0000186.. cmdoption:: -s S, --setup=S
Georg Brandl116aa622007-08-15 14:28:22 +0000187
Éric Araujo713d3032010-11-18 16:38:46 +0000188 statement to be executed once initially (default ``pass``)
189
190.. cmdoption:: -t, --time
191
Georg Brandl116aa622007-08-15 14:28:22 +0000192 use :func:`time.time` (default on all platforms but Windows)
193
Éric Araujo713d3032010-11-18 16:38:46 +0000194.. cmdoption:: -c, --clock
195
Georg Brandl116aa622007-08-15 14:28:22 +0000196 use :func:`time.clock` (default on Windows)
197
Éric Araujo713d3032010-11-18 16:38:46 +0000198.. cmdoption:: -v, --verbose
199
Georg Brandl116aa622007-08-15 14:28:22 +0000200 print raw timing results; repeat for more digits precision
201
Éric Araujo713d3032010-11-18 16:38:46 +0000202.. cmdoption:: -h, --help
203
Georg Brandl116aa622007-08-15 14:28:22 +0000204 print a short usage message and exit
205
206A multi-line statement may be given by specifying each line as a separate
207statement argument; indented lines are possible by enclosing an argument in
208quotes and using leading spaces. Multiple :option:`-s` options are treated
209similarly.
210
211If :option:`-n` is not given, a suitable number of loops is calculated by trying
212successive powers of 10 until the total time is at least 0.2 seconds.
213
Sandro Tosie6c34622012-04-24 18:11:46 +0200214:func:`default_timer` measurations can be affected by other programs running on
215the same machine, so
216the best thing to do when accurate timing is necessary is to repeat
Georg Brandl116aa622007-08-15 14:28:22 +0000217the timing a few times and use the best time. The :option:`-r` option is good
218for this; the default of 3 repetitions is probably enough in most cases. On
219Unix, you can use :func:`time.clock` to measure CPU time.
220
221.. note::
222
223 There is a certain baseline overhead associated with executing a pass statement.
224 The code here doesn't try to hide it, but you should be aware of it. The
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300225 baseline overhead can be measured by invoking the program without arguments,
226 and it might differ between Python versions.
Georg Brandl116aa622007-08-15 14:28:22 +0000227
Georg Brandl116aa622007-08-15 14:28:22 +0000228
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300229.. _timeit-examples:
Georg Brandl116aa622007-08-15 14:28:22 +0000230
231Examples
232--------
233
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300234It is possible to provide a setup statement that is executed only once at the beginning:
235
236.. code-block:: sh
237
238 $ python -m timeit -s 'text = "sample string"; char = "g"' 'char in text'
239 10000000 loops, best of 3: 0.0877 usec per loop
240 $ python -m timeit -s 'text = "sample string"; char = "g"' 'text.find(char)'
241 1000000 loops, best of 3: 0.342 usec per loop
242
243::
244
245 >>> import timeit
246 >>> timeit.timeit('char in text', setup='text = "sample string"; char = "g"')
247 0.41440500499993504
248 >>> timeit.timeit('text.find(char)', setup='text = "sample string"; char = "g"')
249 1.7246671520006203
250
251The same can be done using the :class:`Timer` class and its methods::
252
253 >>> import timeit
254 >>> t = timeit.Timer('char in text', setup='text = "sample string"; char = "g"')
255 >>> t.timeit()
256 0.3955516149999312
257 >>> t.repeat()
258 [0.40193588800002544, 0.3960157959998014, 0.39594301399984033]
259
260
261The following examples show how to time expressions that contain multiple lines.
262Here we compare the cost of using :func:`hasattr` vs. :keyword:`try`/:keyword:`except`
263to test for missing and present object attributes:
264
265.. code-block:: sh
Georg Brandl116aa622007-08-15 14:28:22 +0000266
Senthil Kumaran2e015352011-08-06 13:37:04 +0800267 $ python -m timeit 'try:' ' str.__bool__' 'except AttributeError:' ' pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000268 100000 loops, best of 3: 15.7 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800269 $ python -m timeit 'if hasattr(str, "__bool__"): pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000270 100000 loops, best of 3: 4.26 usec per loop
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300271
Senthil Kumaran2e015352011-08-06 13:37:04 +0800272 $ python -m timeit 'try:' ' int.__bool__' 'except AttributeError:' ' pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000273 1000000 loops, best of 3: 1.43 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800274 $ python -m timeit 'if hasattr(int, "__bool__"): pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000275 100000 loops, best of 3: 2.23 usec per loop
276
277::
278
279 >>> import timeit
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300280 >>> # attribute is missing
Georg Brandl116aa622007-08-15 14:28:22 +0000281 >>> s = """\
282 ... try:
283 ... str.__bool__
284 ... except AttributeError:
285 ... pass
286 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300287 >>> timeit.timeit(stmt=s, number=100000)
288 0.9138244460009446
289 >>> s = "if hasattr(str, '__bool__'): pass"
290 >>> timeit.timeit(stmt=s, number=100000)
291 0.5829014980008651
292 >>>
293 >>> # attribute is present
Georg Brandl116aa622007-08-15 14:28:22 +0000294 >>> s = """\
295 ... try:
296 ... int.__bool__
297 ... except AttributeError:
298 ... pass
299 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300300 >>> timeit.timeit(stmt=s, number=100000)
301 0.04215312199994514
302 >>> s = "if hasattr(int, '__bool__'): pass"
303 >>> timeit.timeit(stmt=s, number=100000)
304 0.08588060699912603
305
Georg Brandl116aa622007-08-15 14:28:22 +0000306
307To give the :mod:`timeit` module access to functions you define, you can pass a
Ezio Melottia3ccb232012-09-20 06:13:38 +0300308*setup* parameter which contains an import statement::
Georg Brandl116aa622007-08-15 14:28:22 +0000309
310 def test():
Senthil Kumaran2e015352011-08-06 13:37:04 +0800311 """Stupid test function"""
Collin Winterc79461b2007-09-01 23:34:30 +0000312 L = [i for i in range(100)]
Georg Brandl116aa622007-08-15 14:28:22 +0000313
Senthil Kumaran2e015352011-08-06 13:37:04 +0800314 if __name__ == '__main__':
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300315 import timeit
316 print(timeit.timeit("test()", setup="from __main__ import test"))