blob: 4065808854ecffa18641f16830428aa6459ef0bd [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
Terry Jan Reedyfa089b92016-06-11 15:02:54 -04007**Source code:** :source:`Lib/timeit.py`
Georg Brandl116aa622007-08-15 14:28:22 +00008
Georg Brandl116aa622007-08-15 14:28:22 +00009.. index::
10 single: Benchmarking
11 single: Performance
12
Raymond Hettingera1993682011-01-27 01:20:32 +000013--------------
14
Georg Brandl116aa622007-08-15 14:28:22 +000015This module provides a simple way to time small bits of Python code. It has both
Martin Panter00ccacc2016-04-16 04:59:38 +000016a :ref:`timeit-command-line-interface` as well as a :ref:`callable <python-interface>`
Ezio Melottid0fe3e52012-10-02 05:35:39 +030017one. It avoids a number of common traps for measuring execution times.
18See also Tim Peters' introduction to the "Algorithms" chapter in the *Python
19Cookbook*, published by O'Reilly.
Georg Brandl116aa622007-08-15 14:28:22 +000020
21
Ezio Melottid0fe3e52012-10-02 05:35:39 +030022Basic Examples
23--------------
Georg Brandl116aa622007-08-15 14:28:22 +000024
Martin Panter00ccacc2016-04-16 04:59:38 +000025The following example shows how the :ref:`timeit-command-line-interface`
Ezio Melottid0fe3e52012-10-02 05:35:39 +030026can be used to compare three different expressions:
Georg Brandl116aa622007-08-15 14:28:22 +000027
Ezio Melottid0fe3e52012-10-02 05:35:39 +030028.. code-block:: sh
Georg Brandl116aa622007-08-15 14:28:22 +000029
Ezio Melotti591176e2014-08-04 17:01:16 +030030 $ python3 -m timeit '"-".join(str(n) for n in range(100))'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +030031 10000 loops, best of 5: 30.2 usec per loop
Ezio Melotti591176e2014-08-04 17:01:16 +030032 $ python3 -m timeit '"-".join([str(n) for n in range(100)])'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +030033 10000 loops, best of 5: 27.5 usec per loop
Ezio Melotti591176e2014-08-04 17:01:16 +030034 $ python3 -m timeit '"-".join(map(str, range(100)))'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +030035 10000 loops, best of 5: 23.2 usec per loop
Georg Brandl116aa622007-08-15 14:28:22 +000036
Ezio Melottid0fe3e52012-10-02 05:35:39 +030037This can be achieved from the :ref:`python-interface` with::
38
39 >>> import timeit
40 >>> timeit.timeit('"-".join(str(n) for n in range(100))', number=10000)
Ezio Melotti591176e2014-08-04 17:01:16 +030041 0.3018611848820001
Ezio Melottid0fe3e52012-10-02 05:35:39 +030042 >>> timeit.timeit('"-".join([str(n) for n in range(100)])', number=10000)
Ezio Melotti591176e2014-08-04 17:01:16 +030043 0.2727368790656328
Ezio Melottid0fe3e52012-10-02 05:35:39 +030044 >>> timeit.timeit('"-".join(map(str, range(100)))', number=10000)
Ezio Melotti591176e2014-08-04 17:01:16 +030045 0.23702679807320237
46
Ezio Melottid0fe3e52012-10-02 05:35:39 +030047
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
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040061.. function:: timeit(stmt='pass', setup='pass', timer=<default timer>, number=1000000, globals=None)
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.
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040065 The optional *globals* argument specifies a namespace in which to execute the
66 code.
67
68 .. versionchanged:: 3.5
69 The optional *globals* parameter was added.
Georg Brandl116aa622007-08-15 14:28:22 +000070
71
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040072.. function:: repeat(stmt='pass', setup='pass', timer=<default timer>, repeat=3, number=1000000, globals=None)
Georg Brandl116aa622007-08-15 14:28:22 +000073
Ezio Melottid0fe3e52012-10-02 05:35:39 +030074 Create a :class:`Timer` instance with the given statement, *setup* code and
75 *timer* function and run its :meth:`.repeat` method with the given *repeat*
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040076 count and *number* executions. The optional *globals* argument specifies a
77 namespace in which to execute the code.
Sandro Tosie6c34622012-04-24 18:11:46 +020078
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040079 .. versionchanged:: 3.5
80 The optional *globals* parameter was added.
Sandro Tosie6c34622012-04-24 18:11:46 +020081
82.. function:: default_timer()
83
Georg Brandl67c14442012-05-01 11:59:36 +020084 The default timer, which is always :func:`time.perf_counter`.
Sandro Tosie6c34622012-04-24 18:11:46 +020085
Ezio Melotti44437622012-10-02 06:01:16 +030086 .. versionchanged:: 3.3
87 :func:`time.perf_counter` is now the default timer.
Georg Brandl116aa622007-08-15 14:28:22 +000088
Georg Brandl116aa622007-08-15 14:28:22 +000089
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040090.. class:: Timer(stmt='pass', setup='pass', timer=<timer function>, globals=None)
Georg Brandl116aa622007-08-15 14:28:22 +000091
Georg Brandl116aa622007-08-15 14:28:22 +000092 Class for timing execution speed of small code snippets.
93
Ezio Melottid0fe3e52012-10-02 05:35:39 +030094 The constructor takes a statement to be timed, an additional statement used
95 for setup, and a timer function. Both statements default to ``'pass'``;
96 the timer function is platform-dependent (see the module doc string).
97 *stmt* and *setup* may also contain multiple statements separated by ``;``
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -040098 or newlines, as long as they don't contain multi-line string literals. The
99 statement will by default be executed within timeit's namespace; this behavior
100 can be controlled by passing a namespace to *globals*.
Georg Brandl116aa622007-08-15 14:28:22 +0000101
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300102 To measure the execution time of the first statement, use the :meth:`.timeit`
Steven D'Aprano09f4f712016-08-15 01:27:03 +1000103 method. The :meth:`.repeat` and :meth:`.autorange` methods are convenience
104 methods to call :meth:`.timeit` multiple times.
Georg Brandl116aa622007-08-15 14:28:22 +0000105
Andrew Kuchling333518e2015-04-21 19:43:33 -0400106 The execution time of *setup* is excluded from the overall timed execution run.
107
Georg Brandl116aa622007-08-15 14:28:22 +0000108 The *stmt* and *setup* parameters can also take objects that are callable
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300109 without arguments. This will embed calls to them in a timer function that
Ezio Melottia3ccb232012-09-20 06:13:38 +0300110 will then be executed by :meth:`.timeit`. Note that the timing overhead is a
Georg Brandl116aa622007-08-15 14:28:22 +0000111 little larger in this case because of the extra function calls.
Georg Brandl116aa622007-08-15 14:28:22 +0000112
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -0400113 .. versionchanged:: 3.5
114 The optional *globals* parameter was added.
Georg Brandl116aa622007-08-15 14:28:22 +0000115
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300116 .. method:: Timer.timeit(number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000117
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300118 Time *number* executions of the main statement. This executes the setup
119 statement once, and then returns the time it takes to execute the main
120 statement a number of times, measured in seconds as a float.
121 The argument is the number of times through the loop, defaulting to one
122 million. The main statement, the setup statement and the timer function
123 to be used are passed to the constructor.
Georg Brandl116aa622007-08-15 14:28:22 +0000124
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300125 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000126
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300127 By default, :meth:`.timeit` temporarily turns off :term:`garbage
128 collection` during the timing. The advantage of this approach is that
129 it makes independent timings more comparable. This disadvantage is
130 that GC may be an important component of the performance of the
131 function being measured. If so, GC can be re-enabled as the first
132 statement in the *setup* string. For example::
Georg Brandl116aa622007-08-15 14:28:22 +0000133
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300134 timeit.Timer('for i in range(10): oct(i)', 'gc.enable()').timeit()
Georg Brandl116aa622007-08-15 14:28:22 +0000135
136
Steven D'Aprano09f4f712016-08-15 01:27:03 +1000137 .. method:: Timer.autorange(callback=None)
138
139 Automatically determine how many times to call :meth:`.timeit`.
140
141 This is a convenience function that calls :meth:`.timeit` repeatedly
142 so that the total time >= 0.2 second, returning the eventual
143 (number of loops, time taken for that number of loops). It calls
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300144 :meth:`.timeit` with increasing numbers from the sequence 1, 2, 5,
145 10, 20, 50, ... until the time taken is at least 0.2 second.
Steven D'Aprano09f4f712016-08-15 01:27:03 +1000146
Serhiy Storchaka989db5c2016-10-19 16:37:13 +0300147 If *callback* is given and is not ``None``, it will be called after
Steven D'Aprano09f4f712016-08-15 01:27:03 +1000148 each trial with two arguments: ``callback(number, time_taken)``.
149
Steven D'Apranoa0d3eef2016-08-15 02:47:49 +1000150 .. versionadded:: 3.6
151
Steven D'Aprano09f4f712016-08-15 01:27:03 +1000152
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300153 .. method:: Timer.repeat(repeat=3, number=1000000)
Georg Brandl116aa622007-08-15 14:28:22 +0000154
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300155 Call :meth:`.timeit` a few times.
Georg Brandl116aa622007-08-15 14:28:22 +0000156
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300157 This is a convenience function that calls the :meth:`.timeit` repeatedly,
158 returning a list of results. The first argument specifies how many times
159 to call :meth:`.timeit`. The second argument specifies the *number*
160 argument for :meth:`.timeit`.
Georg Brandl116aa622007-08-15 14:28:22 +0000161
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300162 .. note::
Georg Brandl116aa622007-08-15 14:28:22 +0000163
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300164 It's tempting to calculate mean and standard deviation from the result
165 vector and report these. However, this is not very useful.
166 In a typical case, the lowest value gives a lower bound for how fast
167 your machine can run the given code snippet; higher values in the
168 result vector are typically not caused by variability in Python's
169 speed, but by other processes interfering with your timing accuracy.
170 So the :func:`min` of the result is probably the only number you
171 should be interested in. After that, you should look at the entire
172 vector and apply common sense rather than statistics.
Georg Brandl116aa622007-08-15 14:28:22 +0000173
174
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300175 .. method:: Timer.print_exc(file=None)
Georg Brandl116aa622007-08-15 14:28:22 +0000176
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300177 Helper to print a traceback from the timed code.
Georg Brandl116aa622007-08-15 14:28:22 +0000178
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300179 Typical use::
Georg Brandl116aa622007-08-15 14:28:22 +0000180
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300181 t = Timer(...) # outside the try/except
182 try:
183 t.timeit(...) # or t.repeat(...)
Andrew Svetlov47395612012-11-02 22:07:26 +0200184 except Exception:
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300185 t.print_exc()
Georg Brandl116aa622007-08-15 14:28:22 +0000186
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300187 The advantage over the standard traceback is that source lines in the
188 compiled template will be displayed. The optional *file* argument directs
189 where the traceback is sent; it defaults to :data:`sys.stderr`.
Georg Brandl116aa622007-08-15 14:28:22 +0000190
191
Martin Panter00ccacc2016-04-16 04:59:38 +0000192.. _timeit-command-line-interface:
Georg Brandl116aa622007-08-15 14:28:22 +0000193
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300194Command-Line Interface
Georg Brandl116aa622007-08-15 14:28:22 +0000195----------------------
196
197When called as a program from the command line, the following form is used::
198
Victor Stinner3d7feb92016-10-18 17:18:21 +0200199 python -m timeit [-n N] [-r N] [-u U] [-s S] [-h] [statement ...]
Georg Brandl116aa622007-08-15 14:28:22 +0000200
Éric Araujo713d3032010-11-18 16:38:46 +0000201Where the following options are understood:
Georg Brandl116aa622007-08-15 14:28:22 +0000202
Éric Araujo713d3032010-11-18 16:38:46 +0000203.. program:: timeit
204
205.. cmdoption:: -n N, --number=N
206
Georg Brandl116aa622007-08-15 14:28:22 +0000207 how many times to execute 'statement'
208
Éric Araujo713d3032010-11-18 16:38:46 +0000209.. cmdoption:: -r N, --repeat=N
210
Georg Brandl116aa622007-08-15 14:28:22 +0000211 how many times to repeat the timer (default 3)
212
Éric Araujo713d3032010-11-18 16:38:46 +0000213.. cmdoption:: -s S, --setup=S
Georg Brandl116aa622007-08-15 14:28:22 +0000214
Éric Araujo713d3032010-11-18 16:38:46 +0000215 statement to be executed once initially (default ``pass``)
216
Georg Brandl67c14442012-05-01 11:59:36 +0200217.. cmdoption:: -p, --process
218
219 measure process time, not wallclock time, using :func:`time.process_time`
220 instead of :func:`time.perf_counter`, which is the default
221
222 .. versionadded:: 3.3
223
Robert Collins302dbc62015-03-18 09:54:50 +1300224.. cmdoption:: -u, --unit=U
225
Victor Stinnerc3e40f82016-10-18 17:42:48 +0200226 specify a time unit for timer output; can select nsec, usec, msec, or sec
Robert Collins302dbc62015-03-18 09:54:50 +1300227
228 .. versionadded:: 3.5
229
Éric Araujo713d3032010-11-18 16:38:46 +0000230.. cmdoption:: -v, --verbose
231
Georg Brandl116aa622007-08-15 14:28:22 +0000232 print raw timing results; repeat for more digits precision
233
Éric Araujo713d3032010-11-18 16:38:46 +0000234.. cmdoption:: -h, --help
235
Georg Brandl116aa622007-08-15 14:28:22 +0000236 print a short usage message and exit
237
238A multi-line statement may be given by specifying each line as a separate
239statement argument; indented lines are possible by enclosing an argument in
240quotes and using leading spaces. Multiple :option:`-s` options are treated
241similarly.
242
243If :option:`-n` is not given, a suitable number of loops is calculated by trying
244successive powers of 10 until the total time is at least 0.2 seconds.
245
Georg Brandl67c14442012-05-01 11:59:36 +0200246:func:`default_timer` measurements can be affected by other programs running on
247the same machine, so the best thing to do when accurate timing is necessary is
248to repeat the timing a few times and use the best time. The :option:`-r`
249option is good for this; the default of 3 repetitions is probably enough in
250most cases. You can use :func:`time.process_time` to measure CPU time.
Georg Brandl116aa622007-08-15 14:28:22 +0000251
252.. note::
253
254 There is a certain baseline overhead associated with executing a pass statement.
255 The code here doesn't try to hide it, but you should be aware of it. The
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300256 baseline overhead can be measured by invoking the program without arguments,
257 and it might differ between Python versions.
Georg Brandl116aa622007-08-15 14:28:22 +0000258
Georg Brandl116aa622007-08-15 14:28:22 +0000259
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300260.. _timeit-examples:
Georg Brandl116aa622007-08-15 14:28:22 +0000261
262Examples
263--------
264
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300265It is possible to provide a setup statement that is executed only once at the beginning:
266
267.. code-block:: sh
268
269 $ python -m timeit -s 'text = "sample string"; char = "g"' 'char in text'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300270 5000000 loops, best of 5: 0.0877 usec per loop
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300271 $ python -m timeit -s 'text = "sample string"; char = "g"' 'text.find(char)'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300272 1000000 loops, best of 5: 0.342 usec per loop
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300273
274::
275
276 >>> import timeit
277 >>> timeit.timeit('char in text', setup='text = "sample string"; char = "g"')
278 0.41440500499993504
279 >>> timeit.timeit('text.find(char)', setup='text = "sample string"; char = "g"')
280 1.7246671520006203
281
282The same can be done using the :class:`Timer` class and its methods::
283
284 >>> import timeit
285 >>> t = timeit.Timer('char in text', setup='text = "sample string"; char = "g"')
286 >>> t.timeit()
287 0.3955516149999312
288 >>> t.repeat()
289 [0.40193588800002544, 0.3960157959998014, 0.39594301399984033]
290
291
292The following examples show how to time expressions that contain multiple lines.
293Here we compare the cost of using :func:`hasattr` vs. :keyword:`try`/:keyword:`except`
294to test for missing and present object attributes:
295
296.. code-block:: sh
Georg Brandl116aa622007-08-15 14:28:22 +0000297
Senthil Kumaran2e015352011-08-06 13:37:04 +0800298 $ python -m timeit 'try:' ' str.__bool__' 'except AttributeError:' ' pass'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300299 20000 loops, best of 5: 15.7 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800300 $ python -m timeit 'if hasattr(str, "__bool__"): pass'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300301 50000 loops, best of 5: 4.26 usec per loop
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300302
Senthil Kumaran2e015352011-08-06 13:37:04 +0800303 $ python -m timeit 'try:' ' int.__bool__' 'except AttributeError:' ' pass'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300304 200000 loops, best of 5: 1.43 usec per loop
Senthil Kumaran2e015352011-08-06 13:37:04 +0800305 $ python -m timeit 'if hasattr(int, "__bool__"): pass'
Serhiy Storchakad3ff7842016-10-23 15:17:05 +0300306 100000 loops, best of 5: 2.23 usec per loop
Georg Brandl116aa622007-08-15 14:28:22 +0000307
308::
309
310 >>> import timeit
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300311 >>> # attribute is missing
Georg Brandl116aa622007-08-15 14:28:22 +0000312 >>> s = """\
313 ... try:
314 ... str.__bool__
315 ... except AttributeError:
316 ... pass
317 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300318 >>> timeit.timeit(stmt=s, number=100000)
319 0.9138244460009446
320 >>> s = "if hasattr(str, '__bool__'): pass"
321 >>> timeit.timeit(stmt=s, number=100000)
322 0.5829014980008651
323 >>>
324 >>> # attribute is present
Georg Brandl116aa622007-08-15 14:28:22 +0000325 >>> s = """\
326 ... try:
327 ... int.__bool__
328 ... except AttributeError:
329 ... pass
330 ... """
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300331 >>> timeit.timeit(stmt=s, number=100000)
332 0.04215312199994514
333 >>> s = "if hasattr(int, '__bool__'): pass"
334 >>> timeit.timeit(stmt=s, number=100000)
335 0.08588060699912603
336
Georg Brandl116aa622007-08-15 14:28:22 +0000337
338To give the :mod:`timeit` module access to functions you define, you can pass a
Ezio Melottia3ccb232012-09-20 06:13:38 +0300339*setup* parameter which contains an import statement::
Georg Brandl116aa622007-08-15 14:28:22 +0000340
341 def test():
Senthil Kumaran2e015352011-08-06 13:37:04 +0800342 """Stupid test function"""
Collin Winterc79461b2007-09-01 23:34:30 +0000343 L = [i for i in range(100)]
Georg Brandl116aa622007-08-15 14:28:22 +0000344
Senthil Kumaran2e015352011-08-06 13:37:04 +0800345 if __name__ == '__main__':
Ezio Melottid0fe3e52012-10-02 05:35:39 +0300346 import timeit
347 print(timeit.timeit("test()", setup="from __main__ import test"))
Antoine Pitrouef3b9ed2014-08-22 23:13:50 -0400348
349Another option is to pass :func:`globals` to the *globals* parameter, which will cause the code
350to be executed within your current global namespace. This can be more convenient
351than individually specifying imports::
352
353 def f(x):
354 return x**2
355 def g(x):
356 return x**4
357 def h(x):
358 return x**8
359
360 import timeit
361 print(timeit.timeit('[func(42) for func in (f,g,h)]', globals=globals()))