blob: 824a8a316051eb448ab5c9ae5cba408daa3e2aac [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 Melottid0fe3e52012-10-02 05:35:39 +030031 $ 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
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)
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.
Georg Brandl116aa622007-08-15 14:28:22 +000051
52
Ezio Melottid0fe3e52012-10-02 05:35:39 +030053.. _python-interface:
Georg Brandl116aa622007-08-15 14:28:22 +000054
Ezio Melottid0fe3e52012-10-02 05:35:39 +030055Python Interface
56----------------
Georg Brandl116aa622007-08-15 14:28:22 +000057
Ezio Melottid0fe3e52012-10-02 05:35:39 +030058The module defines three convenience functions and a public class:
Georg Brandl116aa622007-08-15 14:28:22 +000059
60
Ezio Melottid0fe3e52012-10-02 05:35:39 +030061.. function:: timeit(stmt='pass', setup='pass', timer=<default timer>, number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +000062
Ezio Melottid0fe3e52012-10-02 05:35:39 +030063 Create a :class:`Timer` instance with the given statement, *setup* code and
64 *timer* function and run its :meth:`.timeit` method with *number* executions.
Georg Brandl116aa622007-08-15 14:28:22 +000065
Andrew Kuchling44da19a2014-04-14 13:39:43 -040066 .. note::
67
68 Because :meth:`.timeit` is executing *stmt*, placing a return statement
69 in *stmt* will prevent :meth:`.timeit` from returning execution time.
70 It will instead return the data specified by your return statement.
71
Georg Brandl116aa622007-08-15 14:28:22 +000072
Ezio Melottid0fe3e52012-10-02 05:35:39 +030073.. function:: repeat(stmt='pass', setup='pass', timer=<default timer>, repeat=3, number=1000000)
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*
77 count and *number* executions.
Sandro Tosie6c34622012-04-24 18:11:46 +020078
79
80.. function:: default_timer()
81
Georg Brandl67c14442012-05-01 11:59:36 +020082 The default timer, which is always :func:`time.perf_counter`.
Sandro Tosie6c34622012-04-24 18:11:46 +020083
Ezio Melotti44437622012-10-02 06:01:16 +030084 .. versionchanged:: 3.3
85 :func:`time.perf_counter` is now the default timer.
Georg Brandl116aa622007-08-15 14:28:22 +000086
Georg Brandl116aa622007-08-15 14:28:22 +000087
Georg Brandl116aa622007-08-15 14:28:22 +000088.. class:: Timer(stmt='pass', setup='pass', timer=<timer function>)
Georg Brandl116aa622007-08-15 14:28:22 +000089
Georg Brandl116aa622007-08-15 14:28:22 +000090 Class for timing execution speed of small code snippets.
91
Ezio Melottid0fe3e52012-10-02 05:35:39 +030092 The constructor takes a statement to be timed, an additional statement used
93 for setup, and a timer function. Both statements default to ``'pass'``;
94 the timer function is platform-dependent (see the module doc string).
95 *stmt* and *setup* may also contain multiple statements separated by ``;``
96 or newlines, as long as they don't contain multi-line string literals.
Georg Brandl116aa622007-08-15 14:28:22 +000097
Ezio Melottid0fe3e52012-10-02 05:35:39 +030098 To measure the execution time of the first statement, use the :meth:`.timeit`
99 method. The :meth:`.repeat` method is a convenience to call :meth:`.timeit`
Georg Brandl116aa622007-08-15 14:28:22 +0000100 multiple times and return a list of results.
101
102 The *stmt* and *setup* parameters can also take objects that are callable
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300103 without arguments. This will embed calls to them in a timer function that
Ezio Melottia3ccb232012-09-20 06:13:38 +0300104 will then be executed by :meth:`.timeit`. Note that the timing overhead is a
Georg Brandl116aa622007-08-15 14:28:22 +0000105 little larger in this case because of the extra function calls.
Georg Brandl116aa622007-08-15 14:28:22 +0000106
Georg Brandl116aa622007-08-15 14:28:22 +0000107
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300108 .. method:: Timer.timeit(number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000109
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300110 Time *number* executions of the main statement. This executes the setup
111 statement once, and then returns the time it takes to execute the main
112 statement a number of times, measured in seconds as a float.
113 The argument is the number of times through the loop, defaulting to one
114 million. The main statement, the setup statement and the timer function
115 to be used are passed to the constructor.
Georg Brandl116aa622007-08-15 14:28:22 +0000116
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300117 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000118
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300119 By default, :meth:`.timeit` temporarily turns off :term:`garbage
120 collection` during the timing. The advantage of this approach is that
121 it makes independent timings more comparable. This disadvantage is
122 that GC may be an important component of the performance of the
123 function being measured. If so, GC can be re-enabled as the first
124 statement in the *setup* string. For example::
Georg Brandl116aa622007-08-15 14:28:22 +0000125
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300126 timeit.Timer('for i in range(10): oct(i)', 'gc.enable()').timeit()
Georg Brandl116aa622007-08-15 14:28:22 +0000127
128
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300129 .. method:: Timer.repeat(repeat=3, number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000130
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300131 Call :meth:`.timeit` a few times.
Georg Brandl116aa622007-08-15 14:28:22 +0000132
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300133 This is a convenience function that calls the :meth:`.timeit` repeatedly,
134 returning a list of results. The first argument specifies how many times
135 to call :meth:`.timeit`. The second argument specifies the *number*
136 argument for :meth:`.timeit`.
Georg Brandl116aa622007-08-15 14:28:22 +0000137
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300138 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000139
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300140 It's tempting to calculate mean and standard deviation from the result
141 vector and report these. However, this is not very useful.
142 In a typical case, the lowest value gives a lower bound for how fast
143 your machine can run the given code snippet; higher values in the
144 result vector are typically not caused by variability in Python's
145 speed, but by other processes interfering with your timing accuracy.
146 So the :func:`min` of the result is probably the only number you
147 should be interested in. After that, you should look at the entire
148 vector and apply common sense rather than statistics.
Georg Brandl116aa622007-08-15 14:28:22 +0000149
150
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300151 .. method:: Timer.print_exc(file=None)
Georg Brandl116aa622007-08-15 14:28:22 +0000152
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300153 Helper to print a traceback from the timed code.
Georg Brandl116aa622007-08-15 14:28:22 +0000154
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300155 Typical use::
Georg Brandl116aa622007-08-15 14:28:22 +0000156
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300157 t = Timer(...) # outside the try/except
158 try:
159 t.timeit(...) # or t.repeat(...)
Andrew Svetlov47395612012-11-02 22:07:26 +0200160 except Exception:
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300161 t.print_exc()
Georg Brandl116aa622007-08-15 14:28:22 +0000162
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300163 The advantage over the standard traceback is that source lines in the
164 compiled template will be displayed. The optional *file* argument directs
165 where the traceback is sent; it defaults to :data:`sys.stderr`.
Georg Brandl116aa622007-08-15 14:28:22 +0000166
167
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300168.. _command-line-interface:
Georg Brandl116aa622007-08-15 14:28:22 +0000169
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300170Command-Line Interface
Georg Brandl116aa622007-08-15 14:28:22 +0000171----------------------
172
173When called as a program from the command line, the following form is used::
174
175 python -m timeit [-n N] [-r N] [-s S] [-t] [-c] [-h] [statement ...]
176
Éric Araujo713d3032010-11-18 16:38:46 +0000177Where the following options are understood:
Georg Brandl116aa622007-08-15 14:28:22 +0000178
Éric Araujo713d3032010-11-18 16:38:46 +0000179.. program:: timeit
180
181.. cmdoption:: -n N, --number=N
182
Georg Brandl116aa622007-08-15 14:28:22 +0000183 how many times to execute 'statement'
184
Éric Araujo713d3032010-11-18 16:38:46 +0000185.. cmdoption:: -r N, --repeat=N
186
Georg Brandl116aa622007-08-15 14:28:22 +0000187 how many times to repeat the timer (default 3)
188
Éric Araujo713d3032010-11-18 16:38:46 +0000189.. cmdoption:: -s S, --setup=S
Georg Brandl116aa622007-08-15 14:28:22 +0000190
Éric Araujo713d3032010-11-18 16:38:46 +0000191 statement to be executed once initially (default ``pass``)
192
Georg Brandl67c14442012-05-01 11:59:36 +0200193.. cmdoption:: -p, --process
194
195 measure process time, not wallclock time, using :func:`time.process_time`
196 instead of :func:`time.perf_counter`, which is the default
197
198 .. versionadded:: 3.3
199
Éric Araujo713d3032010-11-18 16:38:46 +0000200.. cmdoption:: -t, --time
201
Georg Brandl67c14442012-05-01 11:59:36 +0200202 use :func:`time.time` (deprecated)
Georg Brandl116aa622007-08-15 14:28:22 +0000203
Éric Araujo713d3032010-11-18 16:38:46 +0000204.. cmdoption:: -c, --clock
205
Georg Brandl67c14442012-05-01 11:59:36 +0200206 use :func:`time.clock` (deprecated)
Georg Brandl116aa622007-08-15 14:28:22 +0000207
Éric Araujo713d3032010-11-18 16:38:46 +0000208.. cmdoption:: -v, --verbose
209
Georg Brandl116aa622007-08-15 14:28:22 +0000210 print raw timing results; repeat for more digits precision
211
Éric Araujo713d3032010-11-18 16:38:46 +0000212.. cmdoption:: -h, --help
213
Georg Brandl116aa622007-08-15 14:28:22 +0000214 print a short usage message and exit
215
216A multi-line statement may be given by specifying each line as a separate
217statement argument; indented lines are possible by enclosing an argument in
218quotes and using leading spaces. Multiple :option:`-s` options are treated
219similarly.
220
221If :option:`-n` is not given, a suitable number of loops is calculated by trying
222successive powers of 10 until the total time is at least 0.2 seconds.
223
Georg Brandl67c14442012-05-01 11:59:36 +0200224:func:`default_timer` measurements can be affected by other programs running on
225the same machine, so the best thing to do when accurate timing is necessary is
226to repeat the timing a few times and use the best time. The :option:`-r`
227option is good for this; the default of 3 repetitions is probably enough in
228most cases. You can use :func:`time.process_time` to measure CPU time.
Georg Brandl116aa622007-08-15 14:28:22 +0000229
230.. note::
231
232 There is a certain baseline overhead associated with executing a pass statement.
233 The code here doesn't try to hide it, but you should be aware of it. The
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300234 baseline overhead can be measured by invoking the program without arguments,
235 and it might differ between Python versions.
Georg Brandl116aa622007-08-15 14:28:22 +0000236
Georg Brandl116aa622007-08-15 14:28:22 +0000237
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300238.. _timeit-examples:
Georg Brandl116aa622007-08-15 14:28:22 +0000239
240Examples
241--------
242
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300243It is possible to provide a setup statement that is executed only once at the beginning:
244
245.. code-block:: sh
246
247 $ python -m timeit -s 'text = "sample string"; char = "g"' 'char in text'
248 10000000 loops, best of 3: 0.0877 usec per loop
249 $ python -m timeit -s 'text = "sample string"; char = "g"' 'text.find(char)'
250 1000000 loops, best of 3: 0.342 usec per loop
251
252::
253
254 >>> import timeit
255 >>> timeit.timeit('char in text', setup='text = "sample string"; char = "g"')
256 0.41440500499993504
257 >>> timeit.timeit('text.find(char)', setup='text = "sample string"; char = "g"')
258 1.7246671520006203
259
260The same can be done using the :class:`Timer` class and its methods::
261
262 >>> import timeit
263 >>> t = timeit.Timer('char in text', setup='text = "sample string"; char = "g"')
264 >>> t.timeit()
265 0.3955516149999312
266 >>> t.repeat()
267 [0.40193588800002544, 0.3960157959998014, 0.39594301399984033]
268
269
270The following examples show how to time expressions that contain multiple lines.
271Here we compare the cost of using :func:`hasattr` vs. :keyword:`try`/:keyword:`except`
272to test for missing and present object attributes:
273
274.. code-block:: sh
Georg Brandl116aa622007-08-15 14:28:22 +0000275
Senthil Kumaran2e015352011-08-06 13:37:04 +0800276 $ python -m timeit 'try:' ' str.__bool__' 'except AttributeError:' ' pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000277 100000 loops, best of 3: 15.7 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800278 $ python -m timeit 'if hasattr(str, "__bool__"): pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000279 100000 loops, best of 3: 4.26 usec per loop
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300280
Senthil Kumaran2e015352011-08-06 13:37:04 +0800281 $ python -m timeit 'try:' ' int.__bool__' 'except AttributeError:' ' pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000282 1000000 loops, best of 3: 1.43 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800283 $ python -m timeit 'if hasattr(int, "__bool__"): pass'
Georg Brandl116aa622007-08-15 14:28:22 +0000284 100000 loops, best of 3: 2.23 usec per loop
285
286::
287
288 >>> import timeit
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300289 >>> # attribute is missing
Georg Brandl116aa622007-08-15 14:28:22 +0000290 >>> s = """\
291 ... try:
292 ... str.__bool__
293 ... except AttributeError:
294 ... pass
295 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300296 >>> timeit.timeit(stmt=s, number=100000)
297 0.9138244460009446
298 >>> s = "if hasattr(str, '__bool__'): pass"
299 >>> timeit.timeit(stmt=s, number=100000)
300 0.5829014980008651
301 >>>
302 >>> # attribute is present
Georg Brandl116aa622007-08-15 14:28:22 +0000303 >>> s = """\
304 ... try:
305 ... int.__bool__
306 ... except AttributeError:
307 ... pass
308 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300309 >>> timeit.timeit(stmt=s, number=100000)
310 0.04215312199994514
311 >>> s = "if hasattr(int, '__bool__'): pass"
312 >>> timeit.timeit(stmt=s, number=100000)
313 0.08588060699912603
314
Georg Brandl116aa622007-08-15 14:28:22 +0000315
316To give the :mod:`timeit` module access to functions you define, you can pass a
Ezio Melottia3ccb232012-09-20 06:13:38 +0300317*setup* parameter which contains an import statement::
Georg Brandl116aa622007-08-15 14:28:22 +0000318
319 def test():
Senthil Kumaran2e015352011-08-06 13:37:04 +0800320 """Stupid test function"""
Collin Winterc79461b2007-09-01 23:34:30 +0000321 L = [i for i in range(100)]
Georg Brandl116aa622007-08-15 14:28:22 +0000322
Senthil Kumaran2e015352011-08-06 13:37:04 +0800323 if __name__ == '__main__':
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300324 import timeit
325 print(timeit.timeit("test()", setup="from __main__ import test"))