blob: d1051f688c9a5177dee63d28236468282af6ca36 [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
22
Ezio Melottid0fe3e52012-10-02 05:35:39 +030023Basic Examples
24--------------
Georg Brandl116aa622007-08-15 14:28:22 +000025
Ezio Melottid0fe3e52012-10-02 05:35:39 +030026The following example shows how the :ref:`command-line-interface`
27can be used to compare three different expressions:
Georg Brandl116aa622007-08-15 14:28:22 +000028
Ezio Melottid0fe3e52012-10-02 05:35:39 +030029.. code-block:: sh
Georg Brandl116aa622007-08-15 14:28:22 +000030
Ezio Melotti591176e2014-08-04 17:01:16 +030031 $ python3 -m timeit '"-".join(str(n) for n in range(100))'
32 10000 loops, best of 3: 30.2 usec per loop
33 $ python3 -m timeit '"-".join([str(n) for n in range(100)])'
34 10000 loops, best of 3: 27.5 usec per loop
35 $ python3 -m timeit '"-".join(map(str, range(100)))'
36 10000 loops, best of 3: 23.2 usec per loop
Georg Brandl116aa622007-08-15 14:28:22 +000037
Ezio Melottid0fe3e52012-10-02 05:35:39 +030038This 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)
Ezio Melotti591176e2014-08-04 17:01:16 +030042 0.3018611848820001
Ezio Melottid0fe3e52012-10-02 05:35:39 +030043 >>> timeit.timeit('"-".join([str(n) for n in range(100)])', number=10000)
Ezio Melotti591176e2014-08-04 17:01:16 +030044 0.2727368790656328
Ezio Melottid0fe3e52012-10-02 05:35:39 +030045 >>> timeit.timeit('"-".join(map(str, range(100)))', number=10000)
Ezio Melotti591176e2014-08-04 17:01:16 +030046 0.23702679807320237
47
Ezio Melottid0fe3e52012-10-02 05:35:39 +030048
49Note however that :mod:`timeit` will automatically determine the number of
50repetitions only when the command-line interface is used. In the
51:ref:`timeit-examples` section you can find more advanced examples.
Georg Brandl116aa622007-08-15 14:28:22 +000052
53
Ezio Melottid0fe3e52012-10-02 05:35:39 +030054.. _python-interface:
Georg Brandl116aa622007-08-15 14:28:22 +000055
Ezio Melottid0fe3e52012-10-02 05:35:39 +030056Python Interface
57----------------
Georg Brandl116aa622007-08-15 14:28:22 +000058
Ezio Melottid0fe3e52012-10-02 05:35:39 +030059The module defines three convenience functions and a public class:
Georg Brandl116aa622007-08-15 14:28:22 +000060
61
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040062.. function:: timeit(stmt='pass', setup='pass', timer=<default timer>, number=1000000, globals=None)
Georg Brandl116aa622007-08-15 14:28:22 +000063
Ezio Melottid0fe3e52012-10-02 05:35:39 +030064 Create a :class:`Timer` instance with the given statement, *setup* code and
65 *timer* function and run its :meth:`.timeit` method with *number* executions.
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040066 The optional *globals* argument specifies a namespace in which to execute the
67 code.
68
69 .. versionchanged:: 3.5
70 The optional *globals* parameter was added.
Georg Brandl116aa622007-08-15 14:28:22 +000071
72
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040073.. function:: repeat(stmt='pass', setup='pass', timer=<default timer>, repeat=3, number=1000000, globals=None)
Georg Brandl116aa622007-08-15 14:28:22 +000074
Ezio Melottid0fe3e52012-10-02 05:35:39 +030075 Create a :class:`Timer` instance with the given statement, *setup* code and
76 *timer* function and run its :meth:`.repeat` method with the given *repeat*
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040077 count and *number* executions. The optional *globals* argument specifies a
78 namespace in which to execute the code.
Sandro Tosie6c34622012-04-24 18:11:46 +020079
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040080 .. versionchanged:: 3.5
81 The optional *globals* parameter was added.
Sandro Tosie6c34622012-04-24 18:11:46 +020082
83.. function:: default_timer()
84
Georg Brandl67c14442012-05-01 11:59:36 +020085 The default timer, which is always :func:`time.perf_counter`.
Sandro Tosie6c34622012-04-24 18:11:46 +020086
Ezio Melotti44437622012-10-02 06:01:16 +030087 .. versionchanged:: 3.3
88 :func:`time.perf_counter` is now the default timer.
Georg Brandl116aa622007-08-15 14:28:22 +000089
Georg Brandl116aa622007-08-15 14:28:22 +000090
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040091.. class:: Timer(stmt='pass', setup='pass', timer=<timer function>, globals=None)
Georg Brandl116aa622007-08-15 14:28:22 +000092
Georg Brandl116aa622007-08-15 14:28:22 +000093 Class for timing execution speed of small code snippets.
94
Ezio Melottid0fe3e52012-10-02 05:35:39 +030095 The constructor takes a statement to be timed, an additional statement used
96 for setup, and a timer function. Both statements default to ``'pass'``;
97 the timer function is platform-dependent (see the module doc string).
98 *stmt* and *setup* may also contain multiple statements separated by ``;``
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040099 or newlines, as long as they don't contain multi-line string literals. The
100 statement will by default be executed within timeit's namespace; this behavior
101 can be controlled by passing a namespace to *globals*.
Georg Brandl116aa622007-08-15 14:28:22 +0000102
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300103 To measure the execution time of the first statement, use the :meth:`.timeit`
104 method. The :meth:`.repeat` method is a convenience to call :meth:`.timeit`
Georg Brandl116aa622007-08-15 14:28:22 +0000105 multiple times and return a list of results.
106
Andrew Kuchling333518e2015-04-21 19:43:33 -0400107 The execution time of *setup* is excluded from the overall timed execution run.
108
Georg Brandl116aa622007-08-15 14:28:22 +0000109 The *stmt* and *setup* parameters can also take objects that are callable
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300110 without arguments. This will embed calls to them in a timer function that
Ezio Melottia3ccb232012-09-20 06:13:38 +0300111 will then be executed by :meth:`.timeit`. Note that the timing overhead is a
Georg Brandl116aa622007-08-15 14:28:22 +0000112 little larger in this case because of the extra function calls.
Georg Brandl116aa622007-08-15 14:28:22 +0000113
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -0400114 .. versionchanged:: 3.5
115 The optional *globals* parameter was added.
Georg Brandl116aa622007-08-15 14:28:22 +0000116
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300117 .. method:: Timer.timeit(number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000118
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300119 Time *number* executions of the main statement. This executes the setup
120 statement once, and then returns the time it takes to execute the main
121 statement a number of times, measured in seconds as a float.
122 The argument is the number of times through the loop, defaulting to one
123 million. The main statement, the setup statement and the timer function
124 to be used are passed to the constructor.
Georg Brandl116aa622007-08-15 14:28:22 +0000125
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300126 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000127
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300128 By default, :meth:`.timeit` temporarily turns off :term:`garbage
129 collection` during the timing. The advantage of this approach is that
130 it makes independent timings more comparable. This disadvantage is
131 that GC may be an important component of the performance of the
132 function being measured. If so, GC can be re-enabled as the first
133 statement in the *setup* string. For example::
Georg Brandl116aa622007-08-15 14:28:22 +0000134
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300135 timeit.Timer('for i in range(10): oct(i)', 'gc.enable()').timeit()
Georg Brandl116aa622007-08-15 14:28:22 +0000136
137
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300138 .. method:: Timer.repeat(repeat=3, number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000139
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300140 Call :meth:`.timeit` a few times.
Georg Brandl116aa622007-08-15 14:28:22 +0000141
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300142 This is a convenience function that calls the :meth:`.timeit` repeatedly,
143 returning a list of results. The first argument specifies how many times
144 to call :meth:`.timeit`. The second argument specifies the *number*
145 argument for :meth:`.timeit`.
Georg Brandl116aa622007-08-15 14:28:22 +0000146
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300147 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000148
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300149 It's tempting to calculate mean and standard deviation from the result
150 vector and report these. However, this is not very useful.
151 In a typical case, the lowest value gives a lower bound for how fast
152 your machine can run the given code snippet; higher values in the
153 result vector are typically not caused by variability in Python's
154 speed, but by other processes interfering with your timing accuracy.
155 So the :func:`min` of the result is probably the only number you
156 should be interested in. After that, you should look at the entire
157 vector and apply common sense rather than statistics.
Georg Brandl116aa622007-08-15 14:28:22 +0000158
159
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300160 .. method:: Timer.print_exc(file=None)
Georg Brandl116aa622007-08-15 14:28:22 +0000161
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300162 Helper to print a traceback from the timed code.
Georg Brandl116aa622007-08-15 14:28:22 +0000163
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300164 Typical use::
Georg Brandl116aa622007-08-15 14:28:22 +0000165
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300166 t = Timer(...) # outside the try/except
167 try:
168 t.timeit(...) # or t.repeat(...)
Andrew Svetlov47395612012-11-02 22:07:26 +0200169 except Exception:
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300170 t.print_exc()
Georg Brandl116aa622007-08-15 14:28:22 +0000171
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300172 The advantage over the standard traceback is that source lines in the
173 compiled template will be displayed. The optional *file* argument directs
174 where the traceback is sent; it defaults to :data:`sys.stderr`.
Georg Brandl116aa622007-08-15 14:28:22 +0000175
176
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300177.. _command-line-interface:
Georg Brandl116aa622007-08-15 14:28:22 +0000178
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300179Command-Line Interface
Georg Brandl116aa622007-08-15 14:28:22 +0000180----------------------
181
182When called as a program from the command line, the following form is used::
183
Robert Collins302dbc62015-03-18 09:54:50 +1300184 python -m timeit [-n N] [-r N] [-u U] [-s S] [-t] [-c] [-h] [statement ...]
Georg Brandl116aa622007-08-15 14:28:22 +0000185
Éric Araujo713d3032010-11-18 16:38:46 +0000186Where the following options are understood:
Georg Brandl116aa622007-08-15 14:28:22 +0000187
Éric Araujo713d3032010-11-18 16:38:46 +0000188.. program:: timeit
189
190.. cmdoption:: -n N, --number=N
191
Georg Brandl116aa622007-08-15 14:28:22 +0000192 how many times to execute 'statement'
193
Éric Araujo713d3032010-11-18 16:38:46 +0000194.. cmdoption:: -r N, --repeat=N
195
Georg Brandl116aa622007-08-15 14:28:22 +0000196 how many times to repeat the timer (default 3)
197
Éric Araujo713d3032010-11-18 16:38:46 +0000198.. cmdoption:: -s S, --setup=S
Georg Brandl116aa622007-08-15 14:28:22 +0000199
Éric Araujo713d3032010-11-18 16:38:46 +0000200 statement to be executed once initially (default ``pass``)
201
Georg Brandl67c14442012-05-01 11:59:36 +0200202.. cmdoption:: -p, --process
203
204 measure process time, not wallclock time, using :func:`time.process_time`
205 instead of :func:`time.perf_counter`, which is the default
206
207 .. versionadded:: 3.3
208
Éric Araujo713d3032010-11-18 16:38:46 +0000209.. cmdoption:: -t, --time
210
Georg Brandl67c14442012-05-01 11:59:36 +0200211 use :func:`time.time` (deprecated)
Georg Brandl116aa622007-08-15 14:28:22 +0000212
Robert Collins302dbc62015-03-18 09:54:50 +1300213.. cmdoption:: -u, --unit=U
214
215 specify a time unit for timer output; can select usec, msec, or sec
216
217 .. versionadded:: 3.5
218
Éric Araujo713d3032010-11-18 16:38:46 +0000219.. cmdoption:: -c, --clock
220
Georg Brandl67c14442012-05-01 11:59:36 +0200221 use :func:`time.clock` (deprecated)
Georg Brandl116aa622007-08-15 14:28:22 +0000222
Éric Araujo713d3032010-11-18 16:38:46 +0000223.. cmdoption:: -v, --verbose
224
Georg Brandl116aa622007-08-15 14:28:22 +0000225 print raw timing results; repeat for more digits precision
226
Éric Araujo713d3032010-11-18 16:38:46 +0000227.. cmdoption:: -h, --help
228
Georg Brandl116aa622007-08-15 14:28:22 +0000229 print a short usage message and exit
230
231A multi-line statement may be given by specifying each line as a separate
232statement argument; indented lines are possible by enclosing an argument in
233quotes and using leading spaces. Multiple :option:`-s` options are treated
234similarly.
235
236If :option:`-n` is not given, a suitable number of loops is calculated by trying
237successive powers of 10 until the total time is at least 0.2 seconds.
238
Georg Brandl67c14442012-05-01 11:59:36 +0200239:func:`default_timer` measurements can be affected by other programs running on
240the same machine, so the best thing to do when accurate timing is necessary is
241to repeat the timing a few times and use the best time. The :option:`-r`
242option is good for this; the default of 3 repetitions is probably enough in
243most cases. You can use :func:`time.process_time` to measure CPU time.
Georg Brandl116aa622007-08-15 14:28:22 +0000244
245.. note::
246
247 There is a certain baseline overhead associated with executing a pass statement.
248 The code here doesn't try to hide it, but you should be aware of it. The
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300249 baseline overhead can be measured by invoking the program without arguments,
250 and it might differ between Python versions.
Georg Brandl116aa622007-08-15 14:28:22 +0000251
Georg Brandl116aa622007-08-15 14:28:22 +0000252
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300253.. _timeit-examples:
Georg Brandl116aa622007-08-15 14:28:22 +0000254
255Examples
256--------
257
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300258It is possible to provide a setup statement that is executed only once at the beginning:
259
260.. code-block:: sh
261
262 $ python -m timeit -s 'text = "sample string"; char = "g"' 'char in text'
263 10000000 loops, best of 3: 0.0877 usec per loop
264 $ python -m timeit -s 'text = "sample string"; char = "g"' 'text.find(char)'
265 1000000 loops, best of 3: 0.342 usec per loop
266
267::
268
269 >>> import timeit
270 >>> timeit.timeit('char in text', setup='text = "sample string"; char = "g"')
271 0.41440500499993504
272 >>> timeit.timeit('text.find(char)', setup='text = "sample string"; char = "g"')
273 1.7246671520006203
274
275The same can be done using the :class:`Timer` class and its methods::
276
277 >>> import timeit
278 >>> t = timeit.Timer('char in text', setup='text = "sample string"; char = "g"')
279 >>> t.timeit()
280 0.3955516149999312
281 >>> t.repeat()
282 [0.40193588800002544, 0.3960157959998014, 0.39594301399984033]
283
284
285The following examples show how to time expressions that contain multiple lines.
286Here we compare the cost of using :func:`hasattr` vs. :keyword:`try`/:keyword:`except`
287to test for missing and present object attributes:
288
289.. code-block:: sh
Georg Brandl116aa622007-08-15 14:28:22 +0000290
Senthil Kumaran2e015352011-08-06 13:37:04 +0800291 $ python -m timeit 'try:' ' str.__bool__' 'except AttributeError:' ' pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000292 100000 loops, best of 3: 15.7 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800293 $ python -m timeit 'if hasattr(str, "__bool__"): pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000294 100000 loops, best of 3: 4.26 usec per loop
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300295
Senthil Kumaran2e015352011-08-06 13:37:04 +0800296 $ python -m timeit 'try:' ' int.__bool__' 'except AttributeError:' ' pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000297 1000000 loops, best of 3: 1.43 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800298 $ python -m timeit 'if hasattr(int, "__bool__"): pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000299 100000 loops, best of 3: 2.23 usec per loop
300
301::
302
303 >>> import timeit
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300304 >>> # attribute is missing
Georg Brandl116aa622007-08-15 14:28:22 +0000305 >>> s = """\
306 ... try:
307 ... str.__bool__
308 ... except AttributeError:
309 ... pass
310 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300311 >>> timeit.timeit(stmt=s, number=100000)
312 0.9138244460009446
313 >>> s = "if hasattr(str, '__bool__'): pass"
314 >>> timeit.timeit(stmt=s, number=100000)
315 0.5829014980008651
316 >>>
317 >>> # attribute is present
Georg Brandl116aa622007-08-15 14:28:22 +0000318 >>> s = """\
319 ... try:
320 ... int.__bool__
321 ... except AttributeError:
322 ... pass
323 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300324 >>> timeit.timeit(stmt=s, number=100000)
325 0.04215312199994514
326 >>> s = "if hasattr(int, '__bool__'): pass"
327 >>> timeit.timeit(stmt=s, number=100000)
328 0.08588060699912603
329
Georg Brandl116aa622007-08-15 14:28:22 +0000330
331To give the :mod:`timeit` module access to functions you define, you can pass a
Ezio Melottia3ccb232012-09-20 06:13:38 +0300332*setup* parameter which contains an import statement::
Georg Brandl116aa622007-08-15 14:28:22 +0000333
334 def test():
Senthil Kumaran2e015352011-08-06 13:37:04 +0800335 """Stupid test function"""
Collin Winterc79461b2007-09-01 23:34:30 +0000336 L = [i for i in range(100)]
Georg Brandl116aa622007-08-15 14:28:22 +0000337
Senthil Kumaran2e015352011-08-06 13:37:04 +0800338 if __name__ == '__main__':
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300339 import timeit
340 print(timeit.timeit("test()", setup="from __main__ import test"))
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -0400341
342Another option is to pass :func:`globals` to the *globals* parameter, which will cause the code
343to be executed within your current global namespace. This can be more convenient
344than individually specifying imports::
345
346 def f(x):
347 return x**2
348 def g(x):
349 return x**4
350 def h(x):
351 return x**8
352
353 import timeit
354 print(timeit.timeit('[func(42) for func in (f,g,h)]', globals=globals()))