blob: 7b579f15c51d502bcb8d61cc5a041f81edb2fd66 [file] [log] [blame]
Georg Brandl116aa622007-08-15 14:28:22 +00001:mod:`logging` --- Logging facility for Python
2==============================================
3
4.. module:: logging
5 :synopsis: Flexible error logging system for applications.
6
7
8.. moduleauthor:: Vinay Sajip <vinay_sajip@red-dove.com>
9.. sectionauthor:: Vinay Sajip <vinay_sajip@red-dove.com>
10
11
Georg Brandl116aa622007-08-15 14:28:22 +000012.. index:: pair: Errors; logging
13
Georg Brandl116aa622007-08-15 14:28:22 +000014This module defines functions and classes which implement a flexible error
15logging system for applications.
16
17Logging is performed by calling methods on instances of the :class:`Logger`
18class (hereafter called :dfn:`loggers`). Each instance has a name, and they are
Georg Brandl9afde1c2007-11-01 20:32:30 +000019conceptually arranged in a namespace hierarchy using dots (periods) as
Georg Brandl116aa622007-08-15 14:28:22 +000020separators. For example, a logger named "scan" is the parent of loggers
21"scan.text", "scan.html" and "scan.pdf". Logger names can be anything you want,
22and indicate the area of an application in which a logged message originates.
23
24Logged messages also have levels of importance associated with them. The default
25levels provided are :const:`DEBUG`, :const:`INFO`, :const:`WARNING`,
26:const:`ERROR` and :const:`CRITICAL`. As a convenience, you indicate the
27importance of a logged message by calling an appropriate method of
28:class:`Logger`. The methods are :meth:`debug`, :meth:`info`, :meth:`warning`,
29:meth:`error` and :meth:`critical`, which mirror the default levels. You are not
30constrained to use these levels: you can specify your own and use a more general
31:class:`Logger` method, :meth:`log`, which takes an explicit level argument.
32
Christian Heimes8b0facf2007-12-04 19:30:01 +000033
34Logging tutorial
35----------------
36
37The key benefit of having the logging API provided by a standard library module
38is that all Python modules can participate in logging, so your application log
39can include messages from third-party modules.
40
41It is, of course, possible to log messages with different verbosity levels or to
42different destinations. Support for writing log messages to files, HTTP
43GET/POST locations, email via SMTP, generic sockets, or OS-specific logging
Christian Heimesc3f30c42008-02-22 16:37:40 +000044mechanisms are all supported by the standard module. You can also create your
Christian Heimes8b0facf2007-12-04 19:30:01 +000045own log destination class if you have special requirements not met by any of the
46built-in classes.
47
48Simple examples
49^^^^^^^^^^^^^^^
50
51.. sectionauthor:: Doug Hellmann
52.. (see <http://blog.doughellmann.com/2007/05/pymotw-logging.html>)
53
54Most applications are probably going to want to log to a file, so let's start
55with that case. Using the :func:`basicConfig` function, we can set up the
Benjamin Petersond7c3ed52010-06-27 22:32:30 +000056default handler so that debug messages are written to a file (in the example,
57we assume that you have the appropriate permissions to create a file called
58*example.log* in the current directory)::
Christian Heimes8b0facf2007-12-04 19:30:01 +000059
60 import logging
Benjamin Petersond7c3ed52010-06-27 22:32:30 +000061 LOG_FILENAME = 'example.log'
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +000062 logging.basicConfig(filename=LOG_FILENAME,level=logging.DEBUG)
Christian Heimes8b0facf2007-12-04 19:30:01 +000063
64 logging.debug('This message should go to the log file')
65
66And now if we open the file and look at what we have, we should find the log
67message::
68
69 DEBUG:root:This message should go to the log file
70
71If you run the script repeatedly, the additional log messages are appended to
Eric Smith5c01a8d2009-06-04 18:20:51 +000072the file. To create a new file each time, you can pass a *filemode* argument to
Christian Heimes8b0facf2007-12-04 19:30:01 +000073:func:`basicConfig` with a value of ``'w'``. Rather than managing the file size
74yourself, though, it is simpler to use a :class:`RotatingFileHandler`::
75
76 import glob
77 import logging
78 import logging.handlers
79
Benjamin Petersond7c3ed52010-06-27 22:32:30 +000080 LOG_FILENAME = 'logging_rotatingfile_example.out'
Christian Heimes8b0facf2007-12-04 19:30:01 +000081
82 # Set up a specific logger with our desired output level
83 my_logger = logging.getLogger('MyLogger')
84 my_logger.setLevel(logging.DEBUG)
85
86 # Add the log message handler to the logger
87 handler = logging.handlers.RotatingFileHandler(
88 LOG_FILENAME, maxBytes=20, backupCount=5)
89
90 my_logger.addHandler(handler)
91
92 # Log some messages
93 for i in range(20):
94 my_logger.debug('i = %d' % i)
95
96 # See what files are created
97 logfiles = glob.glob('%s*' % LOG_FILENAME)
98
99 for filename in logfiles:
Georg Brandlf6945182008-02-01 11:56:49 +0000100 print(filename)
Christian Heimes8b0facf2007-12-04 19:30:01 +0000101
102The result should be 6 separate files, each with part of the log history for the
103application::
104
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000105 logging_rotatingfile_example.out
106 logging_rotatingfile_example.out.1
107 logging_rotatingfile_example.out.2
108 logging_rotatingfile_example.out.3
109 logging_rotatingfile_example.out.4
110 logging_rotatingfile_example.out.5
Christian Heimes8b0facf2007-12-04 19:30:01 +0000111
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000112The most current file is always :file:`logging_rotatingfile_example.out`,
Christian Heimes8b0facf2007-12-04 19:30:01 +0000113and each time it reaches the size limit it is renamed with the suffix
114``.1``. Each of the existing backup files is renamed to increment the suffix
Eric Smith5c01a8d2009-06-04 18:20:51 +0000115(``.1`` becomes ``.2``, etc.) and the ``.6`` file is erased.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000116
117Obviously this example sets the log length much much too small as an extreme
118example. You would want to set *maxBytes* to an appropriate value.
119
120Another useful feature of the logging API is the ability to produce different
121messages at different log levels. This allows you to instrument your code with
122debug messages, for example, but turning the log level down so that those debug
123messages are not written for your production system. The default levels are
Vinay Sajipb6d065f2009-10-28 23:28:16 +0000124``NOTSET``, ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and ``CRITICAL``.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000125
126The logger, handler, and log message call each specify a level. The log message
127is only emitted if the handler and logger are configured to emit messages of
128that level or lower. For example, if a message is ``CRITICAL``, and the logger
129is set to ``ERROR``, the message is emitted. If a message is a ``WARNING``, and
130the logger is set to produce only ``ERROR``\s, the message is not emitted::
131
132 import logging
133 import sys
134
135 LEVELS = {'debug': logging.DEBUG,
136 'info': logging.INFO,
137 'warning': logging.WARNING,
138 'error': logging.ERROR,
139 'critical': logging.CRITICAL}
140
141 if len(sys.argv) > 1:
142 level_name = sys.argv[1]
143 level = LEVELS.get(level_name, logging.NOTSET)
144 logging.basicConfig(level=level)
145
146 logging.debug('This is a debug message')
147 logging.info('This is an info message')
148 logging.warning('This is a warning message')
149 logging.error('This is an error message')
150 logging.critical('This is a critical error message')
151
152Run the script with an argument like 'debug' or 'warning' to see which messages
153show up at different levels::
154
155 $ python logging_level_example.py debug
156 DEBUG:root:This is a debug message
157 INFO:root:This is an info message
158 WARNING:root:This is a warning message
159 ERROR:root:This is an error message
160 CRITICAL:root:This is a critical error message
161
162 $ python logging_level_example.py info
163 INFO:root:This is an info message
164 WARNING:root:This is a warning message
165 ERROR:root:This is an error message
166 CRITICAL:root:This is a critical error message
167
168You will notice that these log messages all have ``root`` embedded in them. The
169logging module supports a hierarchy of loggers with different names. An easy
170way to tell where a specific log message comes from is to use a separate logger
171object for each of your modules. Each new logger "inherits" the configuration
172of its parent, and log messages sent to a logger include the name of that
173logger. Optionally, each logger can be configured differently, so that messages
174from different modules are handled in different ways. Let's look at a simple
175example of how to log from different modules so it is easy to trace the source
176of the message::
177
178 import logging
179
180 logging.basicConfig(level=logging.WARNING)
181
182 logger1 = logging.getLogger('package1.module1')
183 logger2 = logging.getLogger('package2.module2')
184
185 logger1.warning('This message comes from one module')
186 logger2.warning('And this message comes from another module')
187
188And the output::
189
190 $ python logging_modules_example.py
191 WARNING:package1.module1:This message comes from one module
192 WARNING:package2.module2:And this message comes from another module
193
194There are many more options for configuring logging, including different log
195message formatting options, having messages delivered to multiple destinations,
196and changing the configuration of a long-running application on the fly using a
197socket interface. All of these options are covered in depth in the library
198module documentation.
199
200Loggers
201^^^^^^^
202
203The logging library takes a modular approach and offers the several categories
204of components: loggers, handlers, filters, and formatters. Loggers expose the
205interface that application code directly uses. Handlers send the log records to
206the appropriate destination. Filters provide a finer grained facility for
207determining which log records to send on to a handler. Formatters specify the
208layout of the resultant log record.
209
210:class:`Logger` objects have a threefold job. First, they expose several
211methods to application code so that applications can log messages at runtime.
212Second, logger objects determine which log messages to act upon based upon
213severity (the default filtering facility) or filter objects. Third, logger
214objects pass along relevant log messages to all interested log handlers.
215
216The most widely used methods on logger objects fall into two categories:
217configuration and message sending.
218
219* :meth:`Logger.setLevel` specifies the lowest-severity log message a logger
220 will handle, where debug is the lowest built-in severity level and critical is
221 the highest built-in severity. For example, if the severity level is info,
222 the logger will handle only info, warning, error, and critical messages and
223 will ignore debug messages.
224
225* :meth:`Logger.addFilter` and :meth:`Logger.removeFilter` add and remove filter
226 objects from the logger object. This tutorial does not address filters.
227
228With the logger object configured, the following methods create log messages:
229
230* :meth:`Logger.debug`, :meth:`Logger.info`, :meth:`Logger.warning`,
231 :meth:`Logger.error`, and :meth:`Logger.critical` all create log records with
232 a message and a level that corresponds to their respective method names. The
233 message is actually a format string, which may contain the standard string
234 substitution syntax of :const:`%s`, :const:`%d`, :const:`%f`, and so on. The
235 rest of their arguments is a list of objects that correspond with the
236 substitution fields in the message. With regard to :const:`**kwargs`, the
237 logging methods care only about a keyword of :const:`exc_info` and use it to
238 determine whether to log exception information.
239
240* :meth:`Logger.exception` creates a log message similar to
241 :meth:`Logger.error`. The difference is that :meth:`Logger.exception` dumps a
242 stack trace along with it. Call this method only from an exception handler.
243
244* :meth:`Logger.log` takes a log level as an explicit argument. This is a
245 little more verbose for logging messages than using the log level convenience
246 methods listed above, but this is how to log at custom log levels.
247
Christian Heimesdcca98d2008-02-25 13:19:43 +0000248:func:`getLogger` returns a reference to a logger instance with the specified
Vinay Sajipc15dfd62010-07-06 15:08:55 +0000249name if it is provided, or ``root`` if not. The names are period-separated
Christian Heimes8b0facf2007-12-04 19:30:01 +0000250hierarchical structures. Multiple calls to :func:`getLogger` with the same name
251will return a reference to the same logger object. Loggers that are further
252down in the hierarchical list are children of loggers higher up in the list.
253For example, given a logger with a name of ``foo``, loggers with names of
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000254``foo.bar``, ``foo.bar.baz``, and ``foo.bam`` are all descendants of ``foo``.
255Child loggers propagate messages up to the handlers associated with their
256ancestor loggers. Because of this, it is unnecessary to define and configure
257handlers for all the loggers an application uses. It is sufficient to
258configure handlers for a top-level logger and create child loggers as needed.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000259
260
261Handlers
262^^^^^^^^
263
264:class:`Handler` objects are responsible for dispatching the appropriate log
265messages (based on the log messages' severity) to the handler's specified
266destination. Logger objects can add zero or more handler objects to themselves
267with an :func:`addHandler` method. As an example scenario, an application may
268want to send all log messages to a log file, all log messages of error or higher
269to stdout, and all messages of critical to an email address. This scenario
Christian Heimesc3f30c42008-02-22 16:37:40 +0000270requires three individual handlers where each handler is responsible for sending
Christian Heimes8b0facf2007-12-04 19:30:01 +0000271messages of a specific severity to a specific location.
272
273The standard library includes quite a few handler types; this tutorial uses only
274:class:`StreamHandler` and :class:`FileHandler` in its examples.
275
276There are very few methods in a handler for application developers to concern
277themselves with. The only handler methods that seem relevant for application
278developers who are using the built-in handler objects (that is, not creating
279custom handlers) are the following configuration methods:
280
281* The :meth:`Handler.setLevel` method, just as in logger objects, specifies the
282 lowest severity that will be dispatched to the appropriate destination. Why
283 are there two :func:`setLevel` methods? The level set in the logger
284 determines which severity of messages it will pass to its handlers. The level
285 set in each handler determines which messages that handler will send on.
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000286
287* :func:`setFormatter` selects a Formatter object for this handler to use.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000288
289* :func:`addFilter` and :func:`removeFilter` respectively configure and
290 deconfigure filter objects on handlers.
291
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000292Application code should not directly instantiate and use instances of
293:class:`Handler`. Instead, the :class:`Handler` class is a base class that
294defines the interface that all handlers should have and establishes some
295default behavior that child classes can use (or override).
Christian Heimes8b0facf2007-12-04 19:30:01 +0000296
297
298Formatters
299^^^^^^^^^^
300
301Formatter objects configure the final order, structure, and contents of the log
Christian Heimesdcca98d2008-02-25 13:19:43 +0000302message. Unlike the base :class:`logging.Handler` class, application code may
Christian Heimes8b0facf2007-12-04 19:30:01 +0000303instantiate formatter classes, although you could likely subclass the formatter
304if your application needs special behavior. The constructor takes two optional
305arguments: a message format string and a date format string. If there is no
306message format string, the default is to use the raw message. If there is no
307date format string, the default date format is::
308
309 %Y-%m-%d %H:%M:%S
310
311with the milliseconds tacked on at the end.
312
313The message format string uses ``%(<dictionary key>)s`` styled string
314substitution; the possible keys are documented in :ref:`formatter-objects`.
315
316The following message format string will log the time in a human-readable
317format, the severity of the message, and the contents of the message, in that
318order::
319
320 "%(asctime)s - %(levelname)s - %(message)s"
321
Vinay Sajip40d9a4e2010-08-30 18:10:03 +0000322Formatters use a user-configurable function to convert the creation time of a
323record to a tuple. By default, :func:`time.localtime` is used; to change this
324for a particular formatter instance, set the ``converter`` attribute of the
325instance to a function with the same signature as :func:`time.localtime` or
326:func:`time.gmtime`. To change it for all formatters, for example if you want
327all logging times to be shown in GMT, set the ``converter`` attribute in the
328Formatter class (to ``time.gmtime`` for GMT display).
329
Christian Heimes8b0facf2007-12-04 19:30:01 +0000330
331Configuring Logging
332^^^^^^^^^^^^^^^^^^^
333
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000334Programmers can configure logging in three ways:
335
3361. Creating loggers, handlers, and formatters explicitly using Python
337 code that calls the configuration methods listed above.
3382. Creating a logging config file and reading it using the :func:`fileConfig`
339 function.
3403. Creating a dictionary of configuration information and passing it
341 to the :func:`dictConfig` function.
342
343The following example configures a very simple logger, a console
344handler, and a simple formatter using Python code::
Christian Heimes8b0facf2007-12-04 19:30:01 +0000345
346 import logging
347
348 # create logger
349 logger = logging.getLogger("simple_example")
350 logger.setLevel(logging.DEBUG)
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000351
Christian Heimes8b0facf2007-12-04 19:30:01 +0000352 # create console handler and set level to debug
353 ch = logging.StreamHandler()
354 ch.setLevel(logging.DEBUG)
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000355
Christian Heimes8b0facf2007-12-04 19:30:01 +0000356 # create formatter
357 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000358
Christian Heimes8b0facf2007-12-04 19:30:01 +0000359 # add formatter to ch
360 ch.setFormatter(formatter)
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000361
Christian Heimes8b0facf2007-12-04 19:30:01 +0000362 # add ch to logger
363 logger.addHandler(ch)
364
365 # "application" code
366 logger.debug("debug message")
367 logger.info("info message")
368 logger.warn("warn message")
369 logger.error("error message")
370 logger.critical("critical message")
371
372Running this module from the command line produces the following output::
373
374 $ python simple_logging_module.py
375 2005-03-19 15:10:26,618 - simple_example - DEBUG - debug message
376 2005-03-19 15:10:26,620 - simple_example - INFO - info message
377 2005-03-19 15:10:26,695 - simple_example - WARNING - warn message
378 2005-03-19 15:10:26,697 - simple_example - ERROR - error message
379 2005-03-19 15:10:26,773 - simple_example - CRITICAL - critical message
380
381The following Python module creates a logger, handler, and formatter nearly
382identical to those in the example listed above, with the only difference being
383the names of the objects::
384
385 import logging
386 import logging.config
387
388 logging.config.fileConfig("logging.conf")
389
390 # create logger
391 logger = logging.getLogger("simpleExample")
392
393 # "application" code
394 logger.debug("debug message")
395 logger.info("info message")
396 logger.warn("warn message")
397 logger.error("error message")
398 logger.critical("critical message")
399
400Here is the logging.conf file::
401
402 [loggers]
403 keys=root,simpleExample
404
405 [handlers]
406 keys=consoleHandler
407
408 [formatters]
409 keys=simpleFormatter
410
411 [logger_root]
412 level=DEBUG
413 handlers=consoleHandler
414
415 [logger_simpleExample]
416 level=DEBUG
417 handlers=consoleHandler
418 qualname=simpleExample
419 propagate=0
420
421 [handler_consoleHandler]
422 class=StreamHandler
423 level=DEBUG
424 formatter=simpleFormatter
425 args=(sys.stdout,)
426
427 [formatter_simpleFormatter]
428 format=%(asctime)s - %(name)s - %(levelname)s - %(message)s
429 datefmt=
430
431The output is nearly identical to that of the non-config-file-based example::
432
433 $ python simple_logging_config.py
434 2005-03-19 15:38:55,977 - simpleExample - DEBUG - debug message
435 2005-03-19 15:38:55,979 - simpleExample - INFO - info message
436 2005-03-19 15:38:56,054 - simpleExample - WARNING - warn message
437 2005-03-19 15:38:56,055 - simpleExample - ERROR - error message
438 2005-03-19 15:38:56,130 - simpleExample - CRITICAL - critical message
439
440You can see that the config file approach has a few advantages over the Python
441code approach, mainly separation of configuration and code and the ability of
442noncoders to easily modify the logging properties.
443
Benjamin Peterson9451a1c2010-03-13 22:30:34 +0000444Note that the class names referenced in config files need to be either relative
445to the logging module, or absolute values which can be resolved using normal
446import mechanisms. Thus, you could use either `handlers.WatchedFileHandler`
447(relative to the logging module) or `mypackage.mymodule.MyHandler` (for a
448class defined in package `mypackage` and module `mymodule`, where `mypackage`
449is available on the Python import path).
450
Benjamin Peterson56894b52010-06-28 00:16:12 +0000451In Python 3.2, a new means of configuring logging has been introduced, using
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000452dictionaries to hold configuration information. This provides a superset of the
453functionality of the config-file-based approach outlined above, and is the
454recommended configuration method for new applications and deployments. Because
455a Python dictionary is used to hold configuration information, and since you
456can populate that dictionary using different means, you have more options for
457configuration. For example, you can use a configuration file in JSON format,
458or, if you have access to YAML processing functionality, a file in YAML
459format, to populate the configuration dictionary. Or, of course, you can
460construct the dictionary in Python code, receive it in pickled form over a
461socket, or use whatever approach makes sense for your application.
462
463Here's an example of the same configuration as above, in YAML format for
464the new dictionary-based approach::
465
466 version: 1
467 formatters:
468 simple:
469 format: format=%(asctime)s - %(name)s - %(levelname)s - %(message)s
470 handlers:
471 console:
472 class: logging.StreamHandler
473 level: DEBUG
474 formatter: simple
475 stream: ext://sys.stdout
476 loggers:
477 simpleExample:
478 level: DEBUG
479 handlers: [console]
480 propagate: no
481 root:
482 level: DEBUG
483 handlers: [console]
484
485For more information about logging using a dictionary, see
486:ref:`logging-config-api`.
487
Vinay Sajip26a2d5e2009-01-10 13:37:26 +0000488.. _library-config:
Vinay Sajip30bf1222009-01-10 19:23:34 +0000489
Benjamin Peterson3e4f0552008-09-02 00:31:15 +0000490Configuring Logging for a Library
491^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
492
493When developing a library which uses logging, some consideration needs to be
494given to its configuration. If the using application does not use logging, and
495library code makes logging calls, then a one-off message "No handlers could be
496found for logger X.Y.Z" is printed to the console. This message is intended
497to catch mistakes in logging configuration, but will confuse an application
498developer who is not aware of logging by the library.
499
500In addition to documenting how a library uses logging, a good way to configure
501library logging so that it does not cause a spurious message is to add a
502handler which does nothing. This avoids the message being printed, since a
503handler will be found: it just doesn't produce any output. If the library user
504configures logging for application use, presumably that configuration will add
505some handlers, and if levels are suitably configured then logging calls made
506in library code will send output to those handlers, as normal.
507
508A do-nothing handler can be simply defined as follows::
509
510 import logging
511
512 class NullHandler(logging.Handler):
513 def emit(self, record):
514 pass
515
516An instance of this handler should be added to the top-level logger of the
517logging namespace used by the library. If all logging by a library *foo* is
518done using loggers with names matching "foo.x.y", then the code::
519
520 import logging
521
522 h = NullHandler()
523 logging.getLogger("foo").addHandler(h)
524
525should have the desired effect. If an organisation produces a number of
526libraries, then the logger name specified can be "orgname.foo" rather than
527just "foo".
528
Georg Brandlf9734072008-12-07 15:30:06 +0000529.. versionadded:: 3.1
Vinay Sajip4039aff2010-09-11 10:25:28 +0000530
Georg Brandl67b21b72010-08-17 15:07:14 +0000531 The :class:`NullHandler` class was not present in previous versions, but is
532 now included, so that it need not be defined in library code.
Georg Brandlf9734072008-12-07 15:30:06 +0000533
534
Christian Heimes8b0facf2007-12-04 19:30:01 +0000535
536Logging Levels
537--------------
538
Georg Brandl116aa622007-08-15 14:28:22 +0000539The numeric values of logging levels are given in the following table. These are
540primarily of interest if you want to define your own levels, and need them to
541have specific values relative to the predefined levels. If you define a level
542with the same numeric value, it overwrites the predefined value; the predefined
543name is lost.
544
545+--------------+---------------+
546| Level | Numeric value |
547+==============+===============+
548| ``CRITICAL`` | 50 |
549+--------------+---------------+
550| ``ERROR`` | 40 |
551+--------------+---------------+
552| ``WARNING`` | 30 |
553+--------------+---------------+
554| ``INFO`` | 20 |
555+--------------+---------------+
556| ``DEBUG`` | 10 |
557+--------------+---------------+
558| ``NOTSET`` | 0 |
559+--------------+---------------+
560
561Levels can also be associated with loggers, being set either by the developer or
562through loading a saved logging configuration. When a logging method is called
563on a logger, the logger compares its own level with the level associated with
564the method call. If the logger's level is higher than the method call's, no
565logging message is actually generated. This is the basic mechanism controlling
566the verbosity of logging output.
567
568Logging messages are encoded as instances of the :class:`LogRecord` class. When
569a logger decides to actually log an event, a :class:`LogRecord` instance is
570created from the logging message.
571
572Logging messages are subjected to a dispatch mechanism through the use of
573:dfn:`handlers`, which are instances of subclasses of the :class:`Handler`
574class. Handlers are responsible for ensuring that a logged message (in the form
575of a :class:`LogRecord`) ends up in a particular location (or set of locations)
576which is useful for the target audience for that message (such as end users,
577support desk staff, system administrators, developers). Handlers are passed
578:class:`LogRecord` instances intended for particular destinations. Each logger
579can have zero, one or more handlers associated with it (via the
580:meth:`addHandler` method of :class:`Logger`). In addition to any handlers
581directly associated with a logger, *all handlers associated with all ancestors
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000582of the logger* are called to dispatch the message (unless the *propagate* flag
583for a logger is set to a false value, at which point the passing to ancestor
584handlers stops).
Georg Brandl116aa622007-08-15 14:28:22 +0000585
586Just as for loggers, handlers can have levels associated with them. A handler's
587level acts as a filter in the same way as a logger's level does. If a handler
588decides to actually dispatch an event, the :meth:`emit` method is used to send
589the message to its destination. Most user-defined subclasses of :class:`Handler`
590will need to override this :meth:`emit`.
591
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000592.. _custom-levels:
593
594Custom Levels
595^^^^^^^^^^^^^
596
597Defining your own levels is possible, but should not be necessary, as the
598existing levels have been chosen on the basis of practical experience.
599However, if you are convinced that you need custom levels, great care should
600be exercised when doing this, and it is possibly *a very bad idea to define
601custom levels if you are developing a library*. That's because if multiple
602library authors all define their own custom levels, there is a chance that
603the logging output from such multiple libraries used together will be
604difficult for the using developer to control and/or interpret, because a
605given numeric value might mean different things for different libraries.
606
607
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000608Useful Handlers
609---------------
610
Georg Brandl116aa622007-08-15 14:28:22 +0000611In addition to the base :class:`Handler` class, many useful subclasses are
612provided:
613
Vinay Sajip121a1c42010-09-08 10:46:15 +0000614#. :class:`StreamHandler` instances send messages to streams (file-like
Georg Brandl116aa622007-08-15 14:28:22 +0000615 objects).
616
Vinay Sajip121a1c42010-09-08 10:46:15 +0000617#. :class:`FileHandler` instances send messages to disk files.
Georg Brandl116aa622007-08-15 14:28:22 +0000618
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000619.. module:: logging.handlers
Vinay Sajip30bf1222009-01-10 19:23:34 +0000620
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000621#. :class:`BaseRotatingHandler` is the base class for handlers that
622 rotate log files at a certain point. It is not meant to be instantiated
623 directly. Instead, use :class:`RotatingFileHandler` or
624 :class:`TimedRotatingFileHandler`.
Georg Brandl116aa622007-08-15 14:28:22 +0000625
Vinay Sajip121a1c42010-09-08 10:46:15 +0000626#. :class:`RotatingFileHandler` instances send messages to disk
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000627 files, with support for maximum log file sizes and log file rotation.
Georg Brandl116aa622007-08-15 14:28:22 +0000628
Vinay Sajip121a1c42010-09-08 10:46:15 +0000629#. :class:`TimedRotatingFileHandler` instances send messages to
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000630 disk files, rotating the log file at certain timed intervals.
Georg Brandl116aa622007-08-15 14:28:22 +0000631
Vinay Sajip121a1c42010-09-08 10:46:15 +0000632#. :class:`SocketHandler` instances send messages to TCP/IP
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000633 sockets.
Georg Brandl116aa622007-08-15 14:28:22 +0000634
Vinay Sajip121a1c42010-09-08 10:46:15 +0000635#. :class:`DatagramHandler` instances send messages to UDP
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000636 sockets.
Georg Brandl116aa622007-08-15 14:28:22 +0000637
Vinay Sajip121a1c42010-09-08 10:46:15 +0000638#. :class:`SMTPHandler` instances send messages to a designated
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000639 email address.
Georg Brandl116aa622007-08-15 14:28:22 +0000640
Vinay Sajip121a1c42010-09-08 10:46:15 +0000641#. :class:`SysLogHandler` instances send messages to a Unix
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000642 syslog daemon, possibly on a remote machine.
Georg Brandl116aa622007-08-15 14:28:22 +0000643
Vinay Sajip121a1c42010-09-08 10:46:15 +0000644#. :class:`NTEventLogHandler` instances send messages to a
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000645 Windows NT/2000/XP event log.
Georg Brandl116aa622007-08-15 14:28:22 +0000646
Vinay Sajip121a1c42010-09-08 10:46:15 +0000647#. :class:`MemoryHandler` instances send messages to a buffer
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000648 in memory, which is flushed whenever specific criteria are met.
Georg Brandl116aa622007-08-15 14:28:22 +0000649
Vinay Sajip121a1c42010-09-08 10:46:15 +0000650#. :class:`HTTPHandler` instances send messages to an HTTP
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000651 server using either ``GET`` or ``POST`` semantics.
Georg Brandl116aa622007-08-15 14:28:22 +0000652
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000653#. :class:`WatchedFileHandler` instances watch the file they are
654 logging to. If the file changes, it is closed and reopened using the file
655 name. This handler is only useful on Unix-like systems; Windows does not
656 support the underlying mechanism used.
Vinay Sajip30bf1222009-01-10 19:23:34 +0000657
Vinay Sajip121a1c42010-09-08 10:46:15 +0000658#. :class:`QueueHandler` instances send messages to a queue, such as
659 those implemented in the :mod:`queue` or :mod:`multiprocessing` modules.
660
Vinay Sajip30bf1222009-01-10 19:23:34 +0000661.. currentmodule:: logging
662
Georg Brandlf9734072008-12-07 15:30:06 +0000663#. :class:`NullHandler` instances do nothing with error messages. They are used
664 by library developers who want to use logging, but want to avoid the "No
665 handlers could be found for logger XXX" message which can be displayed if
Vinay Sajip26a2d5e2009-01-10 13:37:26 +0000666 the library user has not configured logging. See :ref:`library-config` for
667 more information.
Georg Brandlf9734072008-12-07 15:30:06 +0000668
669.. versionadded:: 3.1
670
671The :class:`NullHandler` class was not present in previous versions.
672
Vinay Sajip121a1c42010-09-08 10:46:15 +0000673.. versionadded:: 3.2
674
675The :class:`QueueHandler` class was not present in previous versions.
676
Vinay Sajipa17775f2008-12-30 07:32:59 +0000677The :class:`NullHandler`, :class:`StreamHandler` and :class:`FileHandler`
678classes are defined in the core logging package. The other handlers are
679defined in a sub- module, :mod:`logging.handlers`. (There is also another
680sub-module, :mod:`logging.config`, for configuration functionality.)
Georg Brandl116aa622007-08-15 14:28:22 +0000681
682Logged messages are formatted for presentation through instances of the
683:class:`Formatter` class. They are initialized with a format string suitable for
684use with the % operator and a dictionary.
685
686For formatting multiple messages in a batch, instances of
687:class:`BufferingFormatter` can be used. In addition to the format string (which
688is applied to each message in the batch), there is provision for header and
689trailer format strings.
690
691When filtering based on logger level and/or handler level is not enough,
692instances of :class:`Filter` can be added to both :class:`Logger` and
693:class:`Handler` instances (through their :meth:`addFilter` method). Before
694deciding to process a message further, both loggers and handlers consult all
695their filters for permission. If any filter returns a false value, the message
696is not processed further.
697
698The basic :class:`Filter` functionality allows filtering by specific logger
699name. If this feature is used, messages sent to the named logger and its
700children are allowed through the filter, and all others dropped.
701
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000702Module-Level Functions
703----------------------
704
Georg Brandl116aa622007-08-15 14:28:22 +0000705In addition to the classes described above, there are a number of module- level
706functions.
707
708
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000709.. function:: getLogger(name=None)
Georg Brandl116aa622007-08-15 14:28:22 +0000710
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000711 Return a logger with the specified name or, if name is ``None``, return a
Georg Brandl116aa622007-08-15 14:28:22 +0000712 logger which is the root logger of the hierarchy. If specified, the name is
713 typically a dot-separated hierarchical name like *"a"*, *"a.b"* or *"a.b.c.d"*.
714 Choice of these names is entirely up to the developer who is using logging.
715
716 All calls to this function with a given name return the same logger instance.
717 This means that logger instances never need to be passed between different parts
718 of an application.
719
720
721.. function:: getLoggerClass()
722
723 Return either the standard :class:`Logger` class, or the last class passed to
724 :func:`setLoggerClass`. This function may be called from within a new class
725 definition, to ensure that installing a customised :class:`Logger` class will
726 not undo customisations already applied by other code. For example::
727
728 class MyLogger(logging.getLoggerClass()):
729 # ... override behaviour here
730
731
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000732.. function:: debug(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000733
734 Logs a message with level :const:`DEBUG` on the root logger. The *msg* is the
735 message format string, and the *args* are the arguments which are merged into
736 *msg* using the string formatting operator. (Note that this means that you can
737 use keywords in the format string, together with a single dictionary argument.)
738
739 There are two keyword arguments in *kwargs* which are inspected: *exc_info*
740 which, if it does not evaluate as false, causes exception information to be
741 added to the logging message. If an exception tuple (in the format returned by
742 :func:`sys.exc_info`) is provided, it is used; otherwise, :func:`sys.exc_info`
743 is called to get the exception information.
744
745 The other optional keyword argument is *extra* which can be used to pass a
746 dictionary which is used to populate the __dict__ of the LogRecord created for
747 the logging event with user-defined attributes. These custom attributes can then
748 be used as you like. For example, they could be incorporated into logged
749 messages. For example::
750
751 FORMAT = "%(asctime)-15s %(clientip)s %(user)-8s %(message)s"
752 logging.basicConfig(format=FORMAT)
753 d = {'clientip': '192.168.0.1', 'user': 'fbloggs'}
754 logging.warning("Protocol problem: %s", "connection reset", extra=d)
755
Vinay Sajip4039aff2010-09-11 10:25:28 +0000756 would print something like::
Georg Brandl116aa622007-08-15 14:28:22 +0000757
758 2006-02-08 22:20:02,165 192.168.0.1 fbloggs Protocol problem: connection reset
759
760 The keys in the dictionary passed in *extra* should not clash with the keys used
761 by the logging system. (See the :class:`Formatter` documentation for more
762 information on which keys are used by the logging system.)
763
764 If you choose to use these attributes in logged messages, you need to exercise
765 some care. In the above example, for instance, the :class:`Formatter` has been
766 set up with a format string which expects 'clientip' and 'user' in the attribute
767 dictionary of the LogRecord. If these are missing, the message will not be
768 logged because a string formatting exception will occur. So in this case, you
769 always need to pass the *extra* dictionary with these keys.
770
771 While this might be annoying, this feature is intended for use in specialized
772 circumstances, such as multi-threaded servers where the same code executes in
773 many contexts, and interesting conditions which arise are dependent on this
774 context (such as remote client IP address and authenticated user name, in the
775 above example). In such circumstances, it is likely that specialized
776 :class:`Formatter`\ s would be used with particular :class:`Handler`\ s.
777
Georg Brandl116aa622007-08-15 14:28:22 +0000778
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000779.. function:: info(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000780
781 Logs a message with level :const:`INFO` on the root logger. The arguments are
782 interpreted as for :func:`debug`.
783
784
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000785.. function:: warning(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000786
787 Logs a message with level :const:`WARNING` on the root logger. The arguments are
788 interpreted as for :func:`debug`.
789
790
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000791.. function:: error(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000792
793 Logs a message with level :const:`ERROR` on the root logger. The arguments are
794 interpreted as for :func:`debug`.
795
796
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000797.. function:: critical(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000798
799 Logs a message with level :const:`CRITICAL` on the root logger. The arguments
800 are interpreted as for :func:`debug`.
801
802
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000803.. function:: exception(msg, *args)
Georg Brandl116aa622007-08-15 14:28:22 +0000804
805 Logs a message with level :const:`ERROR` on the root logger. The arguments are
806 interpreted as for :func:`debug`. Exception info is added to the logging
807 message. This function should only be called from an exception handler.
808
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000809.. function:: log(level, msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000810
811 Logs a message with level *level* on the root logger. The other arguments are
812 interpreted as for :func:`debug`.
813
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000814 PLEASE NOTE: The above module-level functions which delegate to the root
815 logger should *not* be used in threads, in versions of Python earlier than
816 2.7.1 and 3.2, unless at least one handler has been added to the root
817 logger *before* the threads are started. These convenience functions call
818 :func:`basicConfig` to ensure that at least one handler is available; in
819 earlier versions of Python, this can (under rare circumstances) lead to
820 handlers being added multiple times to the root logger, which can in turn
821 lead to multiple messages for the same event.
Georg Brandl116aa622007-08-15 14:28:22 +0000822
823.. function:: disable(lvl)
824
825 Provides an overriding level *lvl* for all loggers which takes precedence over
826 the logger's own level. When the need arises to temporarily throttle logging
Benjamin Peterson886af962010-03-21 23:13:07 +0000827 output down across the whole application, this function can be useful. Its
828 effect is to disable all logging calls of severity *lvl* and below, so that
829 if you call it with a value of INFO, then all INFO and DEBUG events would be
830 discarded, whereas those of severity WARNING and above would be processed
831 according to the logger's effective level.
Georg Brandl116aa622007-08-15 14:28:22 +0000832
833
834.. function:: addLevelName(lvl, levelName)
835
836 Associates level *lvl* with text *levelName* in an internal dictionary, which is
837 used to map numeric levels to a textual representation, for example when a
838 :class:`Formatter` formats a message. This function can also be used to define
839 your own levels. The only constraints are that all levels used must be
840 registered using this function, levels should be positive integers and they
841 should increase in increasing order of severity.
842
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000843 NOTE: If you are thinking of defining your own levels, please see the section
844 on :ref:`custom-levels`.
Georg Brandl116aa622007-08-15 14:28:22 +0000845
846.. function:: getLevelName(lvl)
847
848 Returns the textual representation of logging level *lvl*. If the level is one
849 of the predefined levels :const:`CRITICAL`, :const:`ERROR`, :const:`WARNING`,
850 :const:`INFO` or :const:`DEBUG` then you get the corresponding string. If you
851 have associated levels with names using :func:`addLevelName` then the name you
852 have associated with *lvl* is returned. If a numeric value corresponding to one
853 of the defined levels is passed in, the corresponding string representation is
854 returned. Otherwise, the string "Level %s" % lvl is returned.
855
856
857.. function:: makeLogRecord(attrdict)
858
859 Creates and returns a new :class:`LogRecord` instance whose attributes are
860 defined by *attrdict*. This function is useful for taking a pickled
861 :class:`LogRecord` attribute dictionary, sent over a socket, and reconstituting
862 it as a :class:`LogRecord` instance at the receiving end.
863
864
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000865.. function:: basicConfig(**kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000866
867 Does basic configuration for the logging system by creating a
868 :class:`StreamHandler` with a default :class:`Formatter` and adding it to the
Vinay Sajipcbabd7e2009-10-10 20:32:36 +0000869 root logger. The functions :func:`debug`, :func:`info`, :func:`warning`,
Georg Brandl116aa622007-08-15 14:28:22 +0000870 :func:`error` and :func:`critical` will call :func:`basicConfig` automatically
871 if no handlers are defined for the root logger.
872
Vinay Sajipcbabd7e2009-10-10 20:32:36 +0000873 This function does nothing if the root logger already has handlers
874 configured for it.
875
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000876 PLEASE NOTE: This function should be called from the main thread
877 before other threads are started. In versions of Python prior to
878 2.7.1 and 3.2, if this function is called from multiple threads,
879 it is possible (in rare circumstances) that a handler will be added
880 to the root logger more than once, leading to unexpected results
881 such as messages being duplicated in the log.
882
Georg Brandl116aa622007-08-15 14:28:22 +0000883 The following keyword arguments are supported.
884
885 +--------------+---------------------------------------------+
886 | Format | Description |
887 +==============+=============================================+
888 | ``filename`` | Specifies that a FileHandler be created, |
889 | | using the specified filename, rather than a |
890 | | StreamHandler. |
891 +--------------+---------------------------------------------+
892 | ``filemode`` | Specifies the mode to open the file, if |
893 | | filename is specified (if filemode is |
894 | | unspecified, it defaults to 'a'). |
895 +--------------+---------------------------------------------+
896 | ``format`` | Use the specified format string for the |
897 | | handler. |
898 +--------------+---------------------------------------------+
899 | ``datefmt`` | Use the specified date/time format. |
900 +--------------+---------------------------------------------+
901 | ``level`` | Set the root logger level to the specified |
902 | | level. |
903 +--------------+---------------------------------------------+
904 | ``stream`` | Use the specified stream to initialize the |
905 | | StreamHandler. Note that this argument is |
906 | | incompatible with 'filename' - if both are |
907 | | present, 'stream' is ignored. |
908 +--------------+---------------------------------------------+
909
Georg Brandl116aa622007-08-15 14:28:22 +0000910.. function:: shutdown()
911
912 Informs the logging system to perform an orderly shutdown by flushing and
Christian Heimesb186d002008-03-18 15:15:01 +0000913 closing all handlers. This should be called at application exit and no
914 further use of the logging system should be made after this call.
Georg Brandl116aa622007-08-15 14:28:22 +0000915
916
917.. function:: setLoggerClass(klass)
918
919 Tells the logging system to use the class *klass* when instantiating a logger.
920 The class should define :meth:`__init__` such that only a name argument is
921 required, and the :meth:`__init__` should call :meth:`Logger.__init__`. This
922 function is typically called before any loggers are instantiated by applications
923 which need to use custom logger behavior.
924
925
926.. seealso::
927
928 :pep:`282` - A Logging System
929 The proposal which described this feature for inclusion in the Python standard
930 library.
931
Christian Heimes255f53b2007-12-08 15:33:56 +0000932 `Original Python logging package <http://www.red-dove.com/python_logging.html>`_
Georg Brandl116aa622007-08-15 14:28:22 +0000933 This is the original source for the :mod:`logging` package. The version of the
934 package available from this site is suitable for use with Python 1.5.2, 2.1.x
935 and 2.2.x, which do not include the :mod:`logging` package in the standard
936 library.
937
Vinay Sajip4039aff2010-09-11 10:25:28 +0000938.. _logger:
Georg Brandl116aa622007-08-15 14:28:22 +0000939
940Logger Objects
941--------------
942
943Loggers have the following attributes and methods. Note that Loggers are never
944instantiated directly, but always through the module-level function
945``logging.getLogger(name)``.
946
947
948.. attribute:: Logger.propagate
949
950 If this evaluates to false, logging messages are not passed by this logger or by
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000951 its child loggers to the handlers of higher level (ancestor) loggers. The
952 constructor sets this attribute to 1.
Georg Brandl116aa622007-08-15 14:28:22 +0000953
954
955.. method:: Logger.setLevel(lvl)
956
957 Sets the threshold for this logger to *lvl*. Logging messages which are less
958 severe than *lvl* will be ignored. When a logger is created, the level is set to
959 :const:`NOTSET` (which causes all messages to be processed when the logger is
960 the root logger, or delegation to the parent when the logger is a non-root
961 logger). Note that the root logger is created with level :const:`WARNING`.
962
963 The term "delegation to the parent" means that if a logger has a level of
964 NOTSET, its chain of ancestor loggers is traversed until either an ancestor with
965 a level other than NOTSET is found, or the root is reached.
966
967 If an ancestor is found with a level other than NOTSET, then that ancestor's
968 level is treated as the effective level of the logger where the ancestor search
969 began, and is used to determine how a logging event is handled.
970
971 If the root is reached, and it has a level of NOTSET, then all messages will be
972 processed. Otherwise, the root's level will be used as the effective level.
973
974
975.. method:: Logger.isEnabledFor(lvl)
976
977 Indicates if a message of severity *lvl* would be processed by this logger.
978 This method checks first the module-level level set by
979 ``logging.disable(lvl)`` and then the logger's effective level as determined
980 by :meth:`getEffectiveLevel`.
981
982
983.. method:: Logger.getEffectiveLevel()
984
985 Indicates the effective level for this logger. If a value other than
986 :const:`NOTSET` has been set using :meth:`setLevel`, it is returned. Otherwise,
987 the hierarchy is traversed towards the root until a value other than
988 :const:`NOTSET` is found, and that value is returned.
989
990
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000991.. method:: Logger.getChild(suffix)
992
993 Returns a logger which is a descendant to this logger, as determined by the suffix.
994 Thus, ``logging.getLogger('abc').getChild('def.ghi')`` would return the same
995 logger as would be returned by ``logging.getLogger('abc.def.ghi')``. This is a
996 convenience method, useful when the parent logger is named using e.g. ``__name__``
997 rather than a literal string.
998
999 .. versionadded:: 3.2
1000
Georg Brandl67b21b72010-08-17 15:07:14 +00001001
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001002.. method:: Logger.debug(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001003
1004 Logs a message with level :const:`DEBUG` on this logger. The *msg* is the
1005 message format string, and the *args* are the arguments which are merged into
1006 *msg* using the string formatting operator. (Note that this means that you can
1007 use keywords in the format string, together with a single dictionary argument.)
1008
1009 There are two keyword arguments in *kwargs* which are inspected: *exc_info*
1010 which, if it does not evaluate as false, causes exception information to be
1011 added to the logging message. If an exception tuple (in the format returned by
1012 :func:`sys.exc_info`) is provided, it is used; otherwise, :func:`sys.exc_info`
1013 is called to get the exception information.
1014
1015 The other optional keyword argument is *extra* which can be used to pass a
1016 dictionary which is used to populate the __dict__ of the LogRecord created for
1017 the logging event with user-defined attributes. These custom attributes can then
1018 be used as you like. For example, they could be incorporated into logged
1019 messages. For example::
1020
1021 FORMAT = "%(asctime)-15s %(clientip)s %(user)-8s %(message)s"
1022 logging.basicConfig(format=FORMAT)
Georg Brandl9afde1c2007-11-01 20:32:30 +00001023 d = { 'clientip' : '192.168.0.1', 'user' : 'fbloggs' }
Georg Brandl116aa622007-08-15 14:28:22 +00001024 logger = logging.getLogger("tcpserver")
1025 logger.warning("Protocol problem: %s", "connection reset", extra=d)
1026
1027 would print something like ::
1028
1029 2006-02-08 22:20:02,165 192.168.0.1 fbloggs Protocol problem: connection reset
1030
1031 The keys in the dictionary passed in *extra* should not clash with the keys used
1032 by the logging system. (See the :class:`Formatter` documentation for more
1033 information on which keys are used by the logging system.)
1034
1035 If you choose to use these attributes in logged messages, you need to exercise
1036 some care. In the above example, for instance, the :class:`Formatter` has been
1037 set up with a format string which expects 'clientip' and 'user' in the attribute
1038 dictionary of the LogRecord. If these are missing, the message will not be
1039 logged because a string formatting exception will occur. So in this case, you
1040 always need to pass the *extra* dictionary with these keys.
1041
1042 While this might be annoying, this feature is intended for use in specialized
1043 circumstances, such as multi-threaded servers where the same code executes in
1044 many contexts, and interesting conditions which arise are dependent on this
1045 context (such as remote client IP address and authenticated user name, in the
1046 above example). In such circumstances, it is likely that specialized
1047 :class:`Formatter`\ s would be used with particular :class:`Handler`\ s.
1048
Georg Brandl116aa622007-08-15 14:28:22 +00001049
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001050.. method:: Logger.info(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001051
1052 Logs a message with level :const:`INFO` on this logger. The arguments are
1053 interpreted as for :meth:`debug`.
1054
1055
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001056.. method:: Logger.warning(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001057
1058 Logs a message with level :const:`WARNING` on this logger. The arguments are
1059 interpreted as for :meth:`debug`.
1060
1061
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001062.. method:: Logger.error(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001063
1064 Logs a message with level :const:`ERROR` on this logger. The arguments are
1065 interpreted as for :meth:`debug`.
1066
1067
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001068.. method:: Logger.critical(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001069
1070 Logs a message with level :const:`CRITICAL` on this logger. The arguments are
1071 interpreted as for :meth:`debug`.
1072
1073
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001074.. method:: Logger.log(lvl, msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001075
1076 Logs a message with integer level *lvl* on this logger. The other arguments are
1077 interpreted as for :meth:`debug`.
1078
1079
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001080.. method:: Logger.exception(msg, *args)
Georg Brandl116aa622007-08-15 14:28:22 +00001081
1082 Logs a message with level :const:`ERROR` on this logger. The arguments are
1083 interpreted as for :meth:`debug`. Exception info is added to the logging
1084 message. This method should only be called from an exception handler.
1085
1086
1087.. method:: Logger.addFilter(filt)
1088
1089 Adds the specified filter *filt* to this logger.
1090
1091
1092.. method:: Logger.removeFilter(filt)
1093
1094 Removes the specified filter *filt* from this logger.
1095
1096
1097.. method:: Logger.filter(record)
1098
1099 Applies this logger's filters to the record and returns a true value if the
1100 record is to be processed.
1101
1102
1103.. method:: Logger.addHandler(hdlr)
1104
1105 Adds the specified handler *hdlr* to this logger.
1106
1107
1108.. method:: Logger.removeHandler(hdlr)
1109
1110 Removes the specified handler *hdlr* from this logger.
1111
1112
1113.. method:: Logger.findCaller()
1114
1115 Finds the caller's source filename and line number. Returns the filename, line
1116 number and function name as a 3-element tuple.
1117
Georg Brandl116aa622007-08-15 14:28:22 +00001118
1119.. method:: Logger.handle(record)
1120
1121 Handles a record by passing it to all handlers associated with this logger and
1122 its ancestors (until a false value of *propagate* is found). This method is used
1123 for unpickled records received from a socket, as well as those created locally.
Georg Brandl502d9a52009-07-26 15:02:41 +00001124 Logger-level filtering is applied using :meth:`~Logger.filter`.
Georg Brandl116aa622007-08-15 14:28:22 +00001125
1126
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001127.. method:: Logger.makeRecord(name, lvl, fn, lno, msg, args, exc_info, func=None, extra=None)
Georg Brandl116aa622007-08-15 14:28:22 +00001128
1129 This is a factory method which can be overridden in subclasses to create
1130 specialized :class:`LogRecord` instances.
1131
Georg Brandl116aa622007-08-15 14:28:22 +00001132
1133.. _minimal-example:
1134
1135Basic example
1136-------------
1137
Georg Brandl116aa622007-08-15 14:28:22 +00001138The :mod:`logging` package provides a lot of flexibility, and its configuration
1139can appear daunting. This section demonstrates that simple use of the logging
1140package is possible.
1141
1142The simplest example shows logging to the console::
1143
1144 import logging
1145
1146 logging.debug('A debug message')
1147 logging.info('Some information')
1148 logging.warning('A shot across the bows')
1149
1150If you run the above script, you'll see this::
1151
1152 WARNING:root:A shot across the bows
1153
1154Because no particular logger was specified, the system used the root logger. The
1155debug and info messages didn't appear because by default, the root logger is
1156configured to only handle messages with a severity of WARNING or above. The
1157message format is also a configuration default, as is the output destination of
1158the messages - ``sys.stderr``. The severity level, the message format and
1159destination can be easily changed, as shown in the example below::
1160
1161 import logging
1162
1163 logging.basicConfig(level=logging.DEBUG,
1164 format='%(asctime)s %(levelname)s %(message)s',
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00001165 filename='myapp.log',
Georg Brandl116aa622007-08-15 14:28:22 +00001166 filemode='w')
1167 logging.debug('A debug message')
1168 logging.info('Some information')
1169 logging.warning('A shot across the bows')
1170
1171The :meth:`basicConfig` method is used to change the configuration defaults,
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00001172which results in output (written to ``myapp.log``) which should look
Georg Brandl116aa622007-08-15 14:28:22 +00001173something like the following::
1174
1175 2004-07-02 13:00:08,743 DEBUG A debug message
1176 2004-07-02 13:00:08,743 INFO Some information
1177 2004-07-02 13:00:08,743 WARNING A shot across the bows
1178
1179This time, all messages with a severity of DEBUG or above were handled, and the
1180format of the messages was also changed, and output went to the specified file
1181rather than the console.
1182
Georg Brandl81ac1ce2007-08-31 17:17:17 +00001183.. XXX logging should probably be updated for new string formatting!
Georg Brandl4b491312007-08-31 09:22:56 +00001184
1185Formatting uses the old Python string formatting - see section
1186:ref:`old-string-formatting`. The format string takes the following common
Georg Brandl116aa622007-08-15 14:28:22 +00001187specifiers. For a complete list of specifiers, consult the :class:`Formatter`
1188documentation.
1189
1190+-------------------+-----------------------------------------------+
1191| Format | Description |
1192+===================+===============================================+
1193| ``%(name)s`` | Name of the logger (logging channel). |
1194+-------------------+-----------------------------------------------+
1195| ``%(levelname)s`` | Text logging level for the message |
1196| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
1197| | ``'ERROR'``, ``'CRITICAL'``). |
1198+-------------------+-----------------------------------------------+
1199| ``%(asctime)s`` | Human-readable time when the |
1200| | :class:`LogRecord` was created. By default |
1201| | this is of the form "2003-07-08 16:49:45,896" |
1202| | (the numbers after the comma are millisecond |
1203| | portion of the time). |
1204+-------------------+-----------------------------------------------+
1205| ``%(message)s`` | The logged message. |
1206+-------------------+-----------------------------------------------+
1207
1208To change the date/time format, you can pass an additional keyword parameter,
1209*datefmt*, as in the following::
1210
1211 import logging
1212
1213 logging.basicConfig(level=logging.DEBUG,
1214 format='%(asctime)s %(levelname)-8s %(message)s',
1215 datefmt='%a, %d %b %Y %H:%M:%S',
1216 filename='/temp/myapp.log',
1217 filemode='w')
1218 logging.debug('A debug message')
1219 logging.info('Some information')
1220 logging.warning('A shot across the bows')
1221
1222which would result in output like ::
1223
1224 Fri, 02 Jul 2004 13:06:18 DEBUG A debug message
1225 Fri, 02 Jul 2004 13:06:18 INFO Some information
1226 Fri, 02 Jul 2004 13:06:18 WARNING A shot across the bows
1227
1228The date format string follows the requirements of :func:`strftime` - see the
1229documentation for the :mod:`time` module.
1230
1231If, instead of sending logging output to the console or a file, you'd rather use
1232a file-like object which you have created separately, you can pass it to
1233:func:`basicConfig` using the *stream* keyword argument. Note that if both
1234*stream* and *filename* keyword arguments are passed, the *stream* argument is
1235ignored.
1236
1237Of course, you can put variable information in your output. To do this, simply
1238have the message be a format string and pass in additional arguments containing
1239the variable information, as in the following example::
1240
1241 import logging
1242
1243 logging.basicConfig(level=logging.DEBUG,
1244 format='%(asctime)s %(levelname)-8s %(message)s',
1245 datefmt='%a, %d %b %Y %H:%M:%S',
1246 filename='/temp/myapp.log',
1247 filemode='w')
1248 logging.error('Pack my box with %d dozen %s', 5, 'liquor jugs')
1249
1250which would result in ::
1251
1252 Wed, 21 Jul 2004 15:35:16 ERROR Pack my box with 5 dozen liquor jugs
1253
1254
1255.. _multiple-destinations:
1256
1257Logging to multiple destinations
1258--------------------------------
1259
1260Let's say you want to log to console and file with different message formats and
1261in differing circumstances. Say you want to log messages with levels of DEBUG
1262and higher to file, and those messages at level INFO and higher to the console.
1263Let's also assume that the file should contain timestamps, but the console
1264messages should not. Here's how you can achieve this::
1265
1266 import logging
1267
1268 # set up logging to file - see previous section for more details
1269 logging.basicConfig(level=logging.DEBUG,
1270 format='%(asctime)s %(name)-12s %(levelname)-8s %(message)s',
1271 datefmt='%m-%d %H:%M',
1272 filename='/temp/myapp.log',
1273 filemode='w')
1274 # define a Handler which writes INFO messages or higher to the sys.stderr
1275 console = logging.StreamHandler()
1276 console.setLevel(logging.INFO)
1277 # set a format which is simpler for console use
1278 formatter = logging.Formatter('%(name)-12s: %(levelname)-8s %(message)s')
1279 # tell the handler to use this format
1280 console.setFormatter(formatter)
1281 # add the handler to the root logger
1282 logging.getLogger('').addHandler(console)
1283
1284 # Now, we can log to the root logger, or any other logger. First the root...
1285 logging.info('Jackdaws love my big sphinx of quartz.')
1286
1287 # Now, define a couple of other loggers which might represent areas in your
1288 # application:
1289
1290 logger1 = logging.getLogger('myapp.area1')
1291 logger2 = logging.getLogger('myapp.area2')
1292
1293 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1294 logger1.info('How quickly daft jumping zebras vex.')
1295 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1296 logger2.error('The five boxing wizards jump quickly.')
1297
1298When you run this, on the console you will see ::
1299
1300 root : INFO Jackdaws love my big sphinx of quartz.
1301 myapp.area1 : INFO How quickly daft jumping zebras vex.
1302 myapp.area2 : WARNING Jail zesty vixen who grabbed pay from quack.
1303 myapp.area2 : ERROR The five boxing wizards jump quickly.
1304
1305and in the file you will see something like ::
1306
1307 10-22 22:19 root INFO Jackdaws love my big sphinx of quartz.
1308 10-22 22:19 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1309 10-22 22:19 myapp.area1 INFO How quickly daft jumping zebras vex.
1310 10-22 22:19 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1311 10-22 22:19 myapp.area2 ERROR The five boxing wizards jump quickly.
1312
1313As you can see, the DEBUG message only shows up in the file. The other messages
1314are sent to both destinations.
1315
1316This example uses console and file handlers, but you can use any number and
1317combination of handlers you choose.
1318
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001319.. _logging-exceptions:
1320
1321Exceptions raised during logging
1322--------------------------------
1323
1324The logging package is designed to swallow exceptions which occur while logging
1325in production. This is so that errors which occur while handling logging events
1326- such as logging misconfiguration, network or other similar errors - do not
1327cause the application using logging to terminate prematurely.
1328
1329:class:`SystemExit` and :class:`KeyboardInterrupt` exceptions are never
1330swallowed. Other exceptions which occur during the :meth:`emit` method of a
1331:class:`Handler` subclass are passed to its :meth:`handleError` method.
1332
1333The default implementation of :meth:`handleError` in :class:`Handler` checks
Georg Brandlef871f62010-03-12 10:06:40 +00001334to see if a module-level variable, :data:`raiseExceptions`, is set. If set, a
1335traceback is printed to :data:`sys.stderr`. If not set, the exception is swallowed.
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001336
Georg Brandlef871f62010-03-12 10:06:40 +00001337**Note:** The default value of :data:`raiseExceptions` is ``True``. This is because
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001338during development, you typically want to be notified of any exceptions that
Georg Brandlef871f62010-03-12 10:06:40 +00001339occur. It's advised that you set :data:`raiseExceptions` to ``False`` for production
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001340usage.
Georg Brandl116aa622007-08-15 14:28:22 +00001341
Christian Heimes790c8232008-01-07 21:14:23 +00001342.. _context-info:
1343
1344Adding contextual information to your logging output
1345----------------------------------------------------
1346
1347Sometimes you want logging output to contain contextual information in
1348addition to the parameters passed to the logging call. For example, in a
1349networked application, it may be desirable to log client-specific information
1350in the log (e.g. remote client's username, or IP address). Although you could
1351use the *extra* parameter to achieve this, it's not always convenient to pass
1352the information in this way. While it might be tempting to create
1353:class:`Logger` instances on a per-connection basis, this is not a good idea
1354because these instances are not garbage collected. While this is not a problem
1355in practice, when the number of :class:`Logger` instances is dependent on the
1356level of granularity you want to use in logging an application, it could
1357be hard to manage if the number of :class:`Logger` instances becomes
1358effectively unbounded.
1359
Vinay Sajipc31be632010-09-06 22:18:20 +00001360
1361Using LoggerAdapters to impart contextual information
1362^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1363
Christian Heimes04c420f2008-01-18 18:40:46 +00001364An easy way in which you can pass contextual information to be output along
1365with logging event information is to use the :class:`LoggerAdapter` class.
1366This class is designed to look like a :class:`Logger`, so that you can call
1367:meth:`debug`, :meth:`info`, :meth:`warning`, :meth:`error`,
1368:meth:`exception`, :meth:`critical` and :meth:`log`. These methods have the
1369same signatures as their counterparts in :class:`Logger`, so you can use the
1370two types of instances interchangeably.
Christian Heimes790c8232008-01-07 21:14:23 +00001371
Christian Heimes04c420f2008-01-18 18:40:46 +00001372When you create an instance of :class:`LoggerAdapter`, you pass it a
1373:class:`Logger` instance and a dict-like object which contains your contextual
1374information. When you call one of the logging methods on an instance of
1375:class:`LoggerAdapter`, it delegates the call to the underlying instance of
1376:class:`Logger` passed to its constructor, and arranges to pass the contextual
1377information in the delegated call. Here's a snippet from the code of
1378:class:`LoggerAdapter`::
Christian Heimes790c8232008-01-07 21:14:23 +00001379
Christian Heimes04c420f2008-01-18 18:40:46 +00001380 def debug(self, msg, *args, **kwargs):
1381 """
1382 Delegate a debug call to the underlying logger, after adding
1383 contextual information from this adapter instance.
1384 """
1385 msg, kwargs = self.process(msg, kwargs)
1386 self.logger.debug(msg, *args, **kwargs)
Christian Heimes790c8232008-01-07 21:14:23 +00001387
Christian Heimes04c420f2008-01-18 18:40:46 +00001388The :meth:`process` method of :class:`LoggerAdapter` is where the contextual
1389information is added to the logging output. It's passed the message and
1390keyword arguments of the logging call, and it passes back (potentially)
1391modified versions of these to use in the call to the underlying logger. The
1392default implementation of this method leaves the message alone, but inserts
1393an "extra" key in the keyword argument whose value is the dict-like object
1394passed to the constructor. Of course, if you had passed an "extra" keyword
1395argument in the call to the adapter, it will be silently overwritten.
Christian Heimes790c8232008-01-07 21:14:23 +00001396
Christian Heimes04c420f2008-01-18 18:40:46 +00001397The advantage of using "extra" is that the values in the dict-like object are
1398merged into the :class:`LogRecord` instance's __dict__, allowing you to use
1399customized strings with your :class:`Formatter` instances which know about
1400the keys of the dict-like object. If you need a different method, e.g. if you
1401want to prepend or append the contextual information to the message string,
1402you just need to subclass :class:`LoggerAdapter` and override :meth:`process`
1403to do what you need. Here's an example script which uses this class, which
1404also illustrates what dict-like behaviour is needed from an arbitrary
1405"dict-like" object for use in the constructor::
1406
Christian Heimes587c2bf2008-01-19 16:21:02 +00001407 import logging
Georg Brandl86def6c2008-01-21 20:36:10 +00001408
Christian Heimes587c2bf2008-01-19 16:21:02 +00001409 class ConnInfo:
1410 """
1411 An example class which shows how an arbitrary class can be used as
1412 the 'extra' context information repository passed to a LoggerAdapter.
1413 """
Georg Brandl86def6c2008-01-21 20:36:10 +00001414
Christian Heimes587c2bf2008-01-19 16:21:02 +00001415 def __getitem__(self, name):
1416 """
1417 To allow this instance to look like a dict.
1418 """
1419 from random import choice
1420 if name == "ip":
1421 result = choice(["127.0.0.1", "192.168.0.1"])
1422 elif name == "user":
1423 result = choice(["jim", "fred", "sheila"])
1424 else:
1425 result = self.__dict__.get(name, "?")
1426 return result
Georg Brandl86def6c2008-01-21 20:36:10 +00001427
Christian Heimes587c2bf2008-01-19 16:21:02 +00001428 def __iter__(self):
1429 """
1430 To allow iteration over keys, which will be merged into
1431 the LogRecord dict before formatting and output.
1432 """
1433 keys = ["ip", "user"]
1434 keys.extend(self.__dict__.keys())
1435 return keys.__iter__()
Georg Brandl86def6c2008-01-21 20:36:10 +00001436
Christian Heimes587c2bf2008-01-19 16:21:02 +00001437 if __name__ == "__main__":
1438 from random import choice
1439 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1440 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1441 { "ip" : "123.231.231.123", "user" : "sheila" })
1442 logging.basicConfig(level=logging.DEBUG,
1443 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1444 a1.debug("A debug message")
1445 a1.info("An info message with %s", "some parameters")
1446 a2 = logging.LoggerAdapter(logging.getLogger("d.e.f"), ConnInfo())
1447 for x in range(10):
1448 lvl = choice(levels)
1449 lvlname = logging.getLevelName(lvl)
1450 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
Christian Heimes04c420f2008-01-18 18:40:46 +00001451
1452When this script is run, the output should look something like this::
1453
Christian Heimes587c2bf2008-01-19 16:21:02 +00001454 2008-01-18 14:49:54,023 a.b.c DEBUG IP: 123.231.231.123 User: sheila A debug message
1455 2008-01-18 14:49:54,023 a.b.c INFO IP: 123.231.231.123 User: sheila An info message with some parameters
1456 2008-01-18 14:49:54,023 d.e.f CRITICAL IP: 192.168.0.1 User: jim A message at CRITICAL level with 2 parameters
1457 2008-01-18 14:49:54,033 d.e.f INFO IP: 192.168.0.1 User: jim A message at INFO level with 2 parameters
1458 2008-01-18 14:49:54,033 d.e.f WARNING IP: 192.168.0.1 User: sheila A message at WARNING level with 2 parameters
1459 2008-01-18 14:49:54,033 d.e.f ERROR IP: 127.0.0.1 User: fred A message at ERROR level with 2 parameters
1460 2008-01-18 14:49:54,033 d.e.f ERROR IP: 127.0.0.1 User: sheila A message at ERROR level with 2 parameters
1461 2008-01-18 14:49:54,033 d.e.f WARNING IP: 192.168.0.1 User: sheila A message at WARNING level with 2 parameters
1462 2008-01-18 14:49:54,033 d.e.f WARNING IP: 192.168.0.1 User: jim A message at WARNING level with 2 parameters
1463 2008-01-18 14:49:54,033 d.e.f INFO IP: 192.168.0.1 User: fred A message at INFO level with 2 parameters
1464 2008-01-18 14:49:54,033 d.e.f WARNING IP: 192.168.0.1 User: sheila A message at WARNING level with 2 parameters
1465 2008-01-18 14:49:54,033 d.e.f WARNING IP: 127.0.0.1 User: jim A message at WARNING level with 2 parameters
Christian Heimes04c420f2008-01-18 18:40:46 +00001466
Christian Heimes790c8232008-01-07 21:14:23 +00001467
Vinay Sajipac007992010-09-17 12:45:26 +00001468.. _filters-contextual:
1469
Vinay Sajipc31be632010-09-06 22:18:20 +00001470Using Filters to impart contextual information
1471^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1472
1473You can also add contextual information to log output using a user-defined
1474:class:`Filter`. ``Filter`` instances are allowed to modify the ``LogRecords``
1475passed to them, including adding additional attributes which can then be output
1476using a suitable format string, or if needed a custom :class:`Formatter`.
1477
1478For example in a web application, the request being processed (or at least,
1479the interesting parts of it) can be stored in a threadlocal
1480(:class:`threading.local`) variable, and then accessed from a ``Filter`` to
1481add, say, information from the request - say, the remote IP address and remote
1482user's username - to the ``LogRecord``, using the attribute names 'ip' and
1483'user' as in the ``LoggerAdapter`` example above. In that case, the same format
1484string can be used to get similar output to that shown above. Here's an example
1485script::
1486
1487 import logging
1488 from random import choice
1489
1490 class ContextFilter(logging.Filter):
1491 """
1492 This is a filter which injects contextual information into the log.
1493
1494 Rather than use actual contextual information, we just use random
1495 data in this demo.
1496 """
1497
1498 USERS = ['jim', 'fred', 'sheila']
1499 IPS = ['123.231.231.123', '127.0.0.1', '192.168.0.1']
1500
1501 def filter(self, record):
1502
1503 record.ip = choice(ContextFilter.IPS)
1504 record.user = choice(ContextFilter.USERS)
1505 return True
1506
1507 if __name__ == "__main__":
1508 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1509 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1510 { "ip" : "123.231.231.123", "user" : "sheila" })
1511 logging.basicConfig(level=logging.DEBUG,
1512 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1513 a1 = logging.getLogger("a.b.c")
1514 a2 = logging.getLogger("d.e.f")
1515
1516 f = ContextFilter()
1517 a1.addFilter(f)
1518 a2.addFilter(f)
1519 a1.debug("A debug message")
1520 a1.info("An info message with %s", "some parameters")
1521 for x in range(10):
1522 lvl = choice(levels)
1523 lvlname = logging.getLevelName(lvl)
1524 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
1525
1526which, when run, produces something like::
1527
1528 2010-09-06 22:38:15,292 a.b.c DEBUG IP: 123.231.231.123 User: fred A debug message
1529 2010-09-06 22:38:15,300 a.b.c INFO IP: 192.168.0.1 User: sheila An info message with some parameters
1530 2010-09-06 22:38:15,300 d.e.f CRITICAL IP: 127.0.0.1 User: sheila A message at CRITICAL level with 2 parameters
1531 2010-09-06 22:38:15,300 d.e.f ERROR IP: 127.0.0.1 User: jim A message at ERROR level with 2 parameters
1532 2010-09-06 22:38:15,300 d.e.f DEBUG IP: 127.0.0.1 User: sheila A message at DEBUG level with 2 parameters
1533 2010-09-06 22:38:15,300 d.e.f ERROR IP: 123.231.231.123 User: fred A message at ERROR level with 2 parameters
1534 2010-09-06 22:38:15,300 d.e.f CRITICAL IP: 192.168.0.1 User: jim A message at CRITICAL level with 2 parameters
1535 2010-09-06 22:38:15,300 d.e.f CRITICAL IP: 127.0.0.1 User: sheila A message at CRITICAL level with 2 parameters
1536 2010-09-06 22:38:15,300 d.e.f DEBUG IP: 192.168.0.1 User: jim A message at DEBUG level with 2 parameters
1537 2010-09-06 22:38:15,301 d.e.f ERROR IP: 127.0.0.1 User: sheila A message at ERROR level with 2 parameters
1538 2010-09-06 22:38:15,301 d.e.f DEBUG IP: 123.231.231.123 User: fred A message at DEBUG level with 2 parameters
1539 2010-09-06 22:38:15,301 d.e.f INFO IP: 123.231.231.123 User: fred A message at INFO level with 2 parameters
1540
1541
Vinay Sajipd31f3632010-06-29 15:31:15 +00001542.. _multiple-processes:
1543
Vinay Sajipa7471bf2009-08-15 23:23:37 +00001544Logging to a single file from multiple processes
1545------------------------------------------------
1546
1547Although logging is thread-safe, and logging to a single file from multiple
1548threads in a single process *is* supported, logging to a single file from
1549*multiple processes* is *not* supported, because there is no standard way to
1550serialize access to a single file across multiple processes in Python. If you
Vinay Sajip121a1c42010-09-08 10:46:15 +00001551need to log to a single file from multiple processes, one way of doing this is
1552to have all the processes log to a :class:`SocketHandler`, and have a separate
1553process which implements a socket server which reads from the socket and logs
1554to file. (If you prefer, you can dedicate one thread in one of the existing
1555processes to perform this function.) The following section documents this
1556approach in more detail and includes a working socket receiver which can be
1557used as a starting point for you to adapt in your own applications.
Vinay Sajipa7471bf2009-08-15 23:23:37 +00001558
Vinay Sajip5a92b132009-08-15 23:35:08 +00001559If you are using a recent version of Python which includes the
Vinay Sajip121a1c42010-09-08 10:46:15 +00001560:mod:`multiprocessing` module, you could write your own handler which uses the
Vinay Sajip5a92b132009-08-15 23:35:08 +00001561:class:`Lock` class from this module to serialize access to the file from
1562your processes. The existing :class:`FileHandler` and subclasses do not make
1563use of :mod:`multiprocessing` at present, though they may do so in the future.
Vinay Sajip8c6b0a52009-08-17 13:17:47 +00001564Note that at present, the :mod:`multiprocessing` module does not provide
1565working lock functionality on all platforms (see
1566http://bugs.python.org/issue3770).
Vinay Sajip5a92b132009-08-15 23:35:08 +00001567
Vinay Sajip121a1c42010-09-08 10:46:15 +00001568.. currentmodule:: logging.handlers
1569
1570Alternatively, you can use a ``Queue`` and a :class:`QueueHandler` to send
1571all logging events to one of the processes in your multi-process application.
1572The following example script demonstrates how you can do this; in the example
1573a separate listener process listens for events sent by other processes and logs
1574them according to its own logging configuration. Although the example only
1575demonstrates one way of doing it (for example, you may want to use a listener
1576thread rather than a separate listener process - the implementation would be
1577analogous) it does allow for completely different logging configurations for
1578the listener and the other processes in your application, and can be used as
1579the basis for code meeting your own specific requirements::
1580
1581 # You'll need these imports in your own code
1582 import logging
1583 import logging.handlers
1584 import multiprocessing
1585
1586 # Next two import lines for this demo only
1587 from random import choice, random
1588 import time
1589
1590 #
1591 # Because you'll want to define the logging configurations for listener and workers, the
1592 # listener and worker process functions take a configurer parameter which is a callable
1593 # for configuring logging for that process. These functions are also passed the queue,
1594 # which they use for communication.
1595 #
1596 # In practice, you can configure the listener however you want, but note that in this
1597 # simple example, the listener does not apply level or filter logic to received records.
1598 # In practice, you would probably want to do ths logic in the worker processes, to avoid
1599 # sending events which would be filtered out between processes.
1600 #
1601 # The size of the rotated files is made small so you can see the results easily.
1602 def listener_configurer():
1603 root = logging.getLogger()
1604 h = logging.handlers.RotatingFileHandler('/tmp/mptest.log', 'a', 300, 10)
1605 f = logging.Formatter('%(asctime)s %(processName)-10s %(name)s %(levelname)-8s %(message)s')
1606 h.setFormatter(f)
1607 root.addHandler(h)
1608
1609 # This is the listener process top-level loop: wait for logging events
1610 # (LogRecords)on the queue and handle them, quit when you get a None for a
1611 # LogRecord.
1612 def listener_process(queue, configurer):
1613 configurer()
1614 while True:
1615 try:
1616 record = queue.get()
1617 if record is None: # We send this as a sentinel to tell the listener to quit.
1618 break
1619 logger = logging.getLogger(record.name)
1620 logger.handle(record) # No level or filter logic applied - just do it!
1621 except (KeyboardInterrupt, SystemExit):
1622 raise
1623 except:
1624 import sys, traceback
1625 print >> sys.stderr, 'Whoops! Problem:'
1626 traceback.print_exc(file=sys.stderr)
1627
1628 # Arrays used for random selections in this demo
1629
1630 LEVELS = [logging.DEBUG, logging.INFO, logging.WARNING,
1631 logging.ERROR, logging.CRITICAL]
1632
1633 LOGGERS = ['a.b.c', 'd.e.f']
1634
1635 MESSAGES = [
1636 'Random message #1',
1637 'Random message #2',
1638 'Random message #3',
1639 ]
1640
1641 # The worker configuration is done at the start of the worker process run.
1642 # Note that on Windows you can't rely on fork semantics, so each process
1643 # will run the logging configuration code when it starts.
1644 def worker_configurer(queue):
1645 h = logging.handlers.QueueHandler(queue) # Just the one handler needed
1646 root = logging.getLogger()
1647 root.addHandler(h)
1648 root.setLevel(logging.DEBUG) # send all messages, for demo; no other level or filter logic applied.
1649
1650 # This is the worker process top-level loop, which just logs ten events with
1651 # random intervening delays before terminating.
1652 # The print messages are just so you know it's doing something!
1653 def worker_process(queue, configurer):
1654 configurer(queue)
1655 name = multiprocessing.current_process().name
1656 print('Worker started: %s' % name)
1657 for i in range(10):
1658 time.sleep(random())
1659 logger = logging.getLogger(choice(LOGGERS))
1660 level = choice(LEVELS)
1661 message = choice(MESSAGES)
1662 logger.log(level, message)
1663 print('Worker finished: %s' % name)
1664
1665 # Here's where the demo gets orchestrated. Create the queue, create and start
1666 # the listener, create ten workers and start them, wait for them to finish,
1667 # then send a None to the queue to tell the listener to finish.
1668 def main():
1669 queue = multiprocessing.Queue(-1)
1670 listener = multiprocessing.Process(target=listener_process,
1671 args=(queue, listener_configurer))
1672 listener.start()
1673 workers = []
1674 for i in range(10):
1675 worker = multiprocessing.Process(target=worker_process,
1676 args=(queue, worker_configurer))
1677 workers.append(worker)
1678 worker.start()
1679 for w in workers:
1680 w.join()
1681 queue.put_nowait(None)
1682 listener.join()
1683
1684 if __name__ == '__main__':
1685 main()
1686
1687
1688.. currentmodule:: logging
1689
Benjamin Peterson8719ad52009-09-11 22:24:02 +00001690
Georg Brandl116aa622007-08-15 14:28:22 +00001691.. _network-logging:
1692
1693Sending and receiving logging events across a network
1694-----------------------------------------------------
1695
1696Let's say you want to send logging events across a network, and handle them at
1697the receiving end. A simple way of doing this is attaching a
1698:class:`SocketHandler` instance to the root logger at the sending end::
1699
1700 import logging, logging.handlers
1701
1702 rootLogger = logging.getLogger('')
1703 rootLogger.setLevel(logging.DEBUG)
1704 socketHandler = logging.handlers.SocketHandler('localhost',
1705 logging.handlers.DEFAULT_TCP_LOGGING_PORT)
1706 # don't bother with a formatter, since a socket handler sends the event as
1707 # an unformatted pickle
1708 rootLogger.addHandler(socketHandler)
1709
1710 # Now, we can log to the root logger, or any other logger. First the root...
1711 logging.info('Jackdaws love my big sphinx of quartz.')
1712
1713 # Now, define a couple of other loggers which might represent areas in your
1714 # application:
1715
1716 logger1 = logging.getLogger('myapp.area1')
1717 logger2 = logging.getLogger('myapp.area2')
1718
1719 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1720 logger1.info('How quickly daft jumping zebras vex.')
1721 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1722 logger2.error('The five boxing wizards jump quickly.')
1723
Alexandre Vassalottice261952008-05-12 02:31:37 +00001724At the receiving end, you can set up a receiver using the :mod:`socketserver`
Georg Brandl116aa622007-08-15 14:28:22 +00001725module. Here is a basic working example::
1726
Georg Brandla35f4b92009-05-31 16:41:59 +00001727 import pickle
Georg Brandl116aa622007-08-15 14:28:22 +00001728 import logging
1729 import logging.handlers
Alexandre Vassalottice261952008-05-12 02:31:37 +00001730 import socketserver
Georg Brandl116aa622007-08-15 14:28:22 +00001731 import struct
1732
1733
Alexandre Vassalottice261952008-05-12 02:31:37 +00001734 class LogRecordStreamHandler(socketserver.StreamRequestHandler):
Georg Brandl116aa622007-08-15 14:28:22 +00001735 """Handler for a streaming logging request.
1736
1737 This basically logs the record using whatever logging policy is
1738 configured locally.
1739 """
1740
1741 def handle(self):
1742 """
1743 Handle multiple requests - each expected to be a 4-byte length,
1744 followed by the LogRecord in pickle format. Logs the record
1745 according to whatever policy is configured locally.
1746 """
Collin Winter46334482007-09-10 00:49:57 +00001747 while True:
Georg Brandl116aa622007-08-15 14:28:22 +00001748 chunk = self.connection.recv(4)
1749 if len(chunk) < 4:
1750 break
1751 slen = struct.unpack(">L", chunk)[0]
1752 chunk = self.connection.recv(slen)
1753 while len(chunk) < slen:
1754 chunk = chunk + self.connection.recv(slen - len(chunk))
1755 obj = self.unPickle(chunk)
1756 record = logging.makeLogRecord(obj)
1757 self.handleLogRecord(record)
1758
1759 def unPickle(self, data):
Georg Brandla35f4b92009-05-31 16:41:59 +00001760 return pickle.loads(data)
Georg Brandl116aa622007-08-15 14:28:22 +00001761
1762 def handleLogRecord(self, record):
1763 # if a name is specified, we use the named logger rather than the one
1764 # implied by the record.
1765 if self.server.logname is not None:
1766 name = self.server.logname
1767 else:
1768 name = record.name
1769 logger = logging.getLogger(name)
1770 # N.B. EVERY record gets logged. This is because Logger.handle
1771 # is normally called AFTER logger-level filtering. If you want
1772 # to do filtering, do it at the client end to save wasting
1773 # cycles and network bandwidth!
1774 logger.handle(record)
1775
Alexandre Vassalottice261952008-05-12 02:31:37 +00001776 class LogRecordSocketReceiver(socketserver.ThreadingTCPServer):
Georg Brandl116aa622007-08-15 14:28:22 +00001777 """simple TCP socket-based logging receiver suitable for testing.
1778 """
1779
1780 allow_reuse_address = 1
1781
1782 def __init__(self, host='localhost',
1783 port=logging.handlers.DEFAULT_TCP_LOGGING_PORT,
1784 handler=LogRecordStreamHandler):
Alexandre Vassalottice261952008-05-12 02:31:37 +00001785 socketserver.ThreadingTCPServer.__init__(self, (host, port), handler)
Georg Brandl116aa622007-08-15 14:28:22 +00001786 self.abort = 0
1787 self.timeout = 1
1788 self.logname = None
1789
1790 def serve_until_stopped(self):
1791 import select
1792 abort = 0
1793 while not abort:
1794 rd, wr, ex = select.select([self.socket.fileno()],
1795 [], [],
1796 self.timeout)
1797 if rd:
1798 self.handle_request()
1799 abort = self.abort
1800
1801 def main():
1802 logging.basicConfig(
1803 format="%(relativeCreated)5d %(name)-15s %(levelname)-8s %(message)s")
1804 tcpserver = LogRecordSocketReceiver()
Georg Brandl6911e3c2007-09-04 07:15:32 +00001805 print("About to start TCP server...")
Georg Brandl116aa622007-08-15 14:28:22 +00001806 tcpserver.serve_until_stopped()
1807
1808 if __name__ == "__main__":
1809 main()
1810
1811First run the server, and then the client. On the client side, nothing is
1812printed on the console; on the server side, you should see something like::
1813
1814 About to start TCP server...
1815 59 root INFO Jackdaws love my big sphinx of quartz.
1816 59 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1817 69 myapp.area1 INFO How quickly daft jumping zebras vex.
1818 69 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1819 69 myapp.area2 ERROR The five boxing wizards jump quickly.
1820
Vinay Sajipc15dfd62010-07-06 15:08:55 +00001821Note that there are some security issues with pickle in some scenarios. If
1822these affect you, you can use an alternative serialization scheme by overriding
1823the :meth:`makePickle` method and implementing your alternative there, as
1824well as adapting the above script to use your alternative serialization.
1825
Vinay Sajip4039aff2010-09-11 10:25:28 +00001826.. _arbitrary-object-messages:
1827
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00001828Using arbitrary objects as messages
1829-----------------------------------
1830
1831In the preceding sections and examples, it has been assumed that the message
1832passed when logging the event is a string. However, this is not the only
1833possibility. You can pass an arbitrary object as a message, and its
1834:meth:`__str__` method will be called when the logging system needs to convert
1835it to a string representation. In fact, if you want to, you can avoid
1836computing a string representation altogether - for example, the
1837:class:`SocketHandler` emits an event by pickling it and sending it over the
1838wire.
1839
1840Optimization
1841------------
1842
1843Formatting of message arguments is deferred until it cannot be avoided.
1844However, computing the arguments passed to the logging method can also be
1845expensive, and you may want to avoid doing it if the logger will just throw
1846away your event. To decide what to do, you can call the :meth:`isEnabledFor`
1847method which takes a level argument and returns true if the event would be
1848created by the Logger for that level of call. You can write code like this::
1849
1850 if logger.isEnabledFor(logging.DEBUG):
1851 logger.debug("Message with %s, %s", expensive_func1(),
1852 expensive_func2())
1853
1854so that if the logger's threshold is set above ``DEBUG``, the calls to
1855:func:`expensive_func1` and :func:`expensive_func2` are never made.
1856
1857There are other optimizations which can be made for specific applications which
1858need more precise control over what logging information is collected. Here's a
1859list of things you can do to avoid processing during logging which you don't
1860need:
1861
1862+-----------------------------------------------+----------------------------------------+
1863| What you don't want to collect | How to avoid collecting it |
1864+===============================================+========================================+
1865| Information about where calls were made from. | Set ``logging._srcfile`` to ``None``. |
1866+-----------------------------------------------+----------------------------------------+
1867| Threading information. | Set ``logging.logThreads`` to ``0``. |
1868+-----------------------------------------------+----------------------------------------+
1869| Process information. | Set ``logging.logProcesses`` to ``0``. |
1870+-----------------------------------------------+----------------------------------------+
1871
1872Also note that the core logging module only includes the basic handlers. If
1873you don't import :mod:`logging.handlers` and :mod:`logging.config`, they won't
1874take up any memory.
1875
1876.. _handler:
Georg Brandl116aa622007-08-15 14:28:22 +00001877
1878Handler Objects
1879---------------
1880
1881Handlers have the following attributes and methods. Note that :class:`Handler`
1882is never instantiated directly; this class acts as a base for more useful
1883subclasses. However, the :meth:`__init__` method in subclasses needs to call
1884:meth:`Handler.__init__`.
1885
1886
1887.. method:: Handler.__init__(level=NOTSET)
1888
1889 Initializes the :class:`Handler` instance by setting its level, setting the list
1890 of filters to the empty list and creating a lock (using :meth:`createLock`) for
1891 serializing access to an I/O mechanism.
1892
1893
1894.. method:: Handler.createLock()
1895
1896 Initializes a thread lock which can be used to serialize access to underlying
1897 I/O functionality which may not be threadsafe.
1898
1899
1900.. method:: Handler.acquire()
1901
1902 Acquires the thread lock created with :meth:`createLock`.
1903
1904
1905.. method:: Handler.release()
1906
1907 Releases the thread lock acquired with :meth:`acquire`.
1908
1909
1910.. method:: Handler.setLevel(lvl)
1911
1912 Sets the threshold for this handler to *lvl*. Logging messages which are less
1913 severe than *lvl* will be ignored. When a handler is created, the level is set
1914 to :const:`NOTSET` (which causes all messages to be processed).
1915
1916
1917.. method:: Handler.setFormatter(form)
1918
1919 Sets the :class:`Formatter` for this handler to *form*.
1920
1921
1922.. method:: Handler.addFilter(filt)
1923
1924 Adds the specified filter *filt* to this handler.
1925
1926
1927.. method:: Handler.removeFilter(filt)
1928
1929 Removes the specified filter *filt* from this handler.
1930
1931
1932.. method:: Handler.filter(record)
1933
1934 Applies this handler's filters to the record and returns a true value if the
1935 record is to be processed.
1936
1937
1938.. method:: Handler.flush()
1939
1940 Ensure all logging output has been flushed. This version does nothing and is
1941 intended to be implemented by subclasses.
1942
1943
1944.. method:: Handler.close()
1945
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00001946 Tidy up any resources used by the handler. This version does no output but
1947 removes the handler from an internal list of handlers which is closed when
1948 :func:`shutdown` is called. Subclasses should ensure that this gets called
1949 from overridden :meth:`close` methods.
Georg Brandl116aa622007-08-15 14:28:22 +00001950
1951
1952.. method:: Handler.handle(record)
1953
1954 Conditionally emits the specified logging record, depending on filters which may
1955 have been added to the handler. Wraps the actual emission of the record with
1956 acquisition/release of the I/O thread lock.
1957
1958
1959.. method:: Handler.handleError(record)
1960
1961 This method should be called from handlers when an exception is encountered
1962 during an :meth:`emit` call. By default it does nothing, which means that
1963 exceptions get silently ignored. This is what is mostly wanted for a logging
1964 system - most users will not care about errors in the logging system, they are
1965 more interested in application errors. You could, however, replace this with a
1966 custom handler if you wish. The specified record is the one which was being
1967 processed when the exception occurred.
1968
1969
1970.. method:: Handler.format(record)
1971
1972 Do formatting for a record - if a formatter is set, use it. Otherwise, use the
1973 default formatter for the module.
1974
1975
1976.. method:: Handler.emit(record)
1977
1978 Do whatever it takes to actually log the specified logging record. This version
1979 is intended to be implemented by subclasses and so raises a
1980 :exc:`NotImplementedError`.
1981
1982
Vinay Sajipd31f3632010-06-29 15:31:15 +00001983.. _stream-handler:
1984
Georg Brandl116aa622007-08-15 14:28:22 +00001985StreamHandler
1986^^^^^^^^^^^^^
1987
1988The :class:`StreamHandler` class, located in the core :mod:`logging` package,
1989sends logging output to streams such as *sys.stdout*, *sys.stderr* or any
1990file-like object (or, more precisely, any object which supports :meth:`write`
1991and :meth:`flush` methods).
1992
1993
Benjamin Peterson1baf4652009-12-31 03:11:23 +00001994.. currentmodule:: logging
1995
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00001996.. class:: StreamHandler(stream=None)
Georg Brandl116aa622007-08-15 14:28:22 +00001997
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00001998 Returns a new instance of the :class:`StreamHandler` class. If *stream* is
Georg Brandl116aa622007-08-15 14:28:22 +00001999 specified, the instance will use it for logging output; otherwise, *sys.stderr*
2000 will be used.
2001
2002
Benjamin Petersone41251e2008-04-25 01:59:09 +00002003 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002004
Benjamin Petersone41251e2008-04-25 01:59:09 +00002005 If a formatter is specified, it is used to format the record. The record
2006 is then written to the stream with a trailing newline. If exception
2007 information is present, it is formatted using
2008 :func:`traceback.print_exception` and appended to the stream.
Georg Brandl116aa622007-08-15 14:28:22 +00002009
2010
Benjamin Petersone41251e2008-04-25 01:59:09 +00002011 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002012
Benjamin Petersone41251e2008-04-25 01:59:09 +00002013 Flushes the stream by calling its :meth:`flush` method. Note that the
2014 :meth:`close` method is inherited from :class:`Handler` and so does
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002015 no output, so an explicit :meth:`flush` call may be needed at times.
Georg Brandl116aa622007-08-15 14:28:22 +00002016
2017
Vinay Sajipd31f3632010-06-29 15:31:15 +00002018.. _file-handler:
2019
Georg Brandl116aa622007-08-15 14:28:22 +00002020FileHandler
2021^^^^^^^^^^^
2022
2023The :class:`FileHandler` class, located in the core :mod:`logging` package,
2024sends logging output to a disk file. It inherits the output functionality from
2025:class:`StreamHandler`.
2026
2027
Vinay Sajipd31f3632010-06-29 15:31:15 +00002028.. class:: FileHandler(filename, mode='a', encoding=None, delay=False)
Georg Brandl116aa622007-08-15 14:28:22 +00002029
2030 Returns a new instance of the :class:`FileHandler` class. The specified file is
2031 opened and used as the stream for logging. If *mode* is not specified,
2032 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Christian Heimese7a15bb2008-01-24 16:21:45 +00002033 with that encoding. If *delay* is true, then file opening is deferred until the
2034 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002035
2036
Benjamin Petersone41251e2008-04-25 01:59:09 +00002037 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002038
Benjamin Petersone41251e2008-04-25 01:59:09 +00002039 Closes the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002040
2041
Benjamin Petersone41251e2008-04-25 01:59:09 +00002042 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002043
Benjamin Petersone41251e2008-04-25 01:59:09 +00002044 Outputs the record to the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002045
Vinay Sajipd31f3632010-06-29 15:31:15 +00002046.. _null-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002047
Vinay Sajipaa672eb2009-01-02 18:53:45 +00002048NullHandler
2049^^^^^^^^^^^
2050
2051.. versionadded:: 3.1
2052
2053The :class:`NullHandler` class, located in the core :mod:`logging` package,
2054does not do any formatting or output. It is essentially a "no-op" handler
2055for use by library developers.
2056
2057
2058.. class:: NullHandler()
2059
2060 Returns a new instance of the :class:`NullHandler` class.
2061
2062
2063 .. method:: emit(record)
2064
2065 This method does nothing.
2066
Vinay Sajip26a2d5e2009-01-10 13:37:26 +00002067See :ref:`library-config` for more information on how to use
2068:class:`NullHandler`.
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00002069
Vinay Sajipd31f3632010-06-29 15:31:15 +00002070.. _watched-file-handler:
2071
Georg Brandl116aa622007-08-15 14:28:22 +00002072WatchedFileHandler
2073^^^^^^^^^^^^^^^^^^
2074
Benjamin Peterson058e31e2009-01-16 03:54:08 +00002075.. currentmodule:: logging.handlers
Vinay Sajipaa672eb2009-01-02 18:53:45 +00002076
Georg Brandl116aa622007-08-15 14:28:22 +00002077The :class:`WatchedFileHandler` class, located in the :mod:`logging.handlers`
2078module, is a :class:`FileHandler` which watches the file it is logging to. If
2079the file changes, it is closed and reopened using the file name.
2080
2081A file change can happen because of usage of programs such as *newsyslog* and
2082*logrotate* which perform log file rotation. This handler, intended for use
2083under Unix/Linux, watches the file to see if it has changed since the last emit.
2084(A file is deemed to have changed if its device or inode have changed.) If the
2085file has changed, the old file stream is closed, and the file opened to get a
2086new stream.
2087
2088This handler is not appropriate for use under Windows, because under Windows
2089open log files cannot be moved or renamed - logging opens the files with
2090exclusive locks - and so there is no need for such a handler. Furthermore,
2091*ST_INO* is not supported under Windows; :func:`stat` always returns zero for
2092this value.
2093
2094
Christian Heimese7a15bb2008-01-24 16:21:45 +00002095.. class:: WatchedFileHandler(filename[,mode[, encoding[, delay]]])
Georg Brandl116aa622007-08-15 14:28:22 +00002096
2097 Returns a new instance of the :class:`WatchedFileHandler` class. The specified
2098 file is opened and used as the stream for logging. If *mode* is not specified,
2099 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Christian Heimese7a15bb2008-01-24 16:21:45 +00002100 with that encoding. If *delay* is true, then file opening is deferred until the
2101 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002102
2103
Benjamin Petersone41251e2008-04-25 01:59:09 +00002104 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002105
Benjamin Petersone41251e2008-04-25 01:59:09 +00002106 Outputs the record to the file, but first checks to see if the file has
2107 changed. If it has, the existing stream is flushed and closed and the
2108 file opened again, before outputting the record to the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002109
Vinay Sajipd31f3632010-06-29 15:31:15 +00002110.. _rotating-file-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002111
2112RotatingFileHandler
2113^^^^^^^^^^^^^^^^^^^
2114
2115The :class:`RotatingFileHandler` class, located in the :mod:`logging.handlers`
2116module, supports rotation of disk log files.
2117
2118
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002119.. class:: RotatingFileHandler(filename, mode='a', maxBytes=0, backupCount=0, encoding=None, delay=0)
Georg Brandl116aa622007-08-15 14:28:22 +00002120
2121 Returns a new instance of the :class:`RotatingFileHandler` class. The specified
2122 file is opened and used as the stream for logging. If *mode* is not specified,
Christian Heimese7a15bb2008-01-24 16:21:45 +00002123 ``'a'`` is used. If *encoding* is not *None*, it is used to open the file
2124 with that encoding. If *delay* is true, then file opening is deferred until the
2125 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002126
2127 You can use the *maxBytes* and *backupCount* values to allow the file to
2128 :dfn:`rollover` at a predetermined size. When the size is about to be exceeded,
2129 the file is closed and a new file is silently opened for output. Rollover occurs
2130 whenever the current log file is nearly *maxBytes* in length; if *maxBytes* is
2131 zero, rollover never occurs. If *backupCount* is non-zero, the system will save
2132 old log files by appending the extensions ".1", ".2" etc., to the filename. For
2133 example, with a *backupCount* of 5 and a base file name of :file:`app.log`, you
2134 would get :file:`app.log`, :file:`app.log.1`, :file:`app.log.2`, up to
2135 :file:`app.log.5`. The file being written to is always :file:`app.log`. When
2136 this file is filled, it is closed and renamed to :file:`app.log.1`, and if files
2137 :file:`app.log.1`, :file:`app.log.2`, etc. exist, then they are renamed to
2138 :file:`app.log.2`, :file:`app.log.3` etc. respectively.
2139
2140
Benjamin Petersone41251e2008-04-25 01:59:09 +00002141 .. method:: doRollover()
Georg Brandl116aa622007-08-15 14:28:22 +00002142
Benjamin Petersone41251e2008-04-25 01:59:09 +00002143 Does a rollover, as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002144
2145
Benjamin Petersone41251e2008-04-25 01:59:09 +00002146 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002147
Benjamin Petersone41251e2008-04-25 01:59:09 +00002148 Outputs the record to the file, catering for rollover as described
2149 previously.
Georg Brandl116aa622007-08-15 14:28:22 +00002150
Vinay Sajipd31f3632010-06-29 15:31:15 +00002151.. _timed-rotating-file-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002152
2153TimedRotatingFileHandler
2154^^^^^^^^^^^^^^^^^^^^^^^^
2155
2156The :class:`TimedRotatingFileHandler` class, located in the
2157:mod:`logging.handlers` module, supports rotation of disk log files at certain
2158timed intervals.
2159
2160
Vinay Sajipd31f3632010-06-29 15:31:15 +00002161.. class:: TimedRotatingFileHandler(filename, when='h', interval=1, backupCount=0, encoding=None, delay=False, utc=False)
Georg Brandl116aa622007-08-15 14:28:22 +00002162
2163 Returns a new instance of the :class:`TimedRotatingFileHandler` class. The
2164 specified file is opened and used as the stream for logging. On rotating it also
2165 sets the filename suffix. Rotating happens based on the product of *when* and
2166 *interval*.
2167
2168 You can use the *when* to specify the type of *interval*. The list of possible
Georg Brandl0c77a822008-06-10 16:37:50 +00002169 values is below. Note that they are not case sensitive.
Georg Brandl116aa622007-08-15 14:28:22 +00002170
Christian Heimesb558a2e2008-03-02 22:46:37 +00002171 +----------------+-----------------------+
2172 | Value | Type of interval |
2173 +================+=======================+
2174 | ``'S'`` | Seconds |
2175 +----------------+-----------------------+
2176 | ``'M'`` | Minutes |
2177 +----------------+-----------------------+
2178 | ``'H'`` | Hours |
2179 +----------------+-----------------------+
2180 | ``'D'`` | Days |
2181 +----------------+-----------------------+
2182 | ``'W'`` | Week day (0=Monday) |
2183 +----------------+-----------------------+
2184 | ``'midnight'`` | Roll over at midnight |
2185 +----------------+-----------------------+
Georg Brandl116aa622007-08-15 14:28:22 +00002186
Christian Heimesb558a2e2008-03-02 22:46:37 +00002187 The system will save old log files by appending extensions to the filename.
2188 The extensions are date-and-time based, using the strftime format
Benjamin Petersonad9d48d2008-04-02 21:49:44 +00002189 ``%Y-%m-%d_%H-%M-%S`` or a leading portion thereof, depending on the
Georg Brandl3dbca812008-07-23 16:10:53 +00002190 rollover interval.
Benjamin Peterson9451a1c2010-03-13 22:30:34 +00002191
2192 When computing the next rollover time for the first time (when the handler
2193 is created), the last modification time of an existing log file, or else
2194 the current time, is used to compute when the next rotation will occur.
2195
Georg Brandl0c77a822008-06-10 16:37:50 +00002196 If the *utc* argument is true, times in UTC will be used; otherwise
2197 local time is used.
2198
2199 If *backupCount* is nonzero, at most *backupCount* files
Benjamin Petersonad9d48d2008-04-02 21:49:44 +00002200 will be kept, and if more would be created when rollover occurs, the oldest
2201 one is deleted. The deletion logic uses the interval to determine which
2202 files to delete, so changing the interval may leave old files lying around.
Georg Brandl116aa622007-08-15 14:28:22 +00002203
Vinay Sajipd31f3632010-06-29 15:31:15 +00002204 If *delay* is true, then file opening is deferred until the first call to
2205 :meth:`emit`.
2206
Georg Brandl116aa622007-08-15 14:28:22 +00002207
Benjamin Petersone41251e2008-04-25 01:59:09 +00002208 .. method:: doRollover()
Georg Brandl116aa622007-08-15 14:28:22 +00002209
Benjamin Petersone41251e2008-04-25 01:59:09 +00002210 Does a rollover, as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002211
2212
Benjamin Petersone41251e2008-04-25 01:59:09 +00002213 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002214
Benjamin Petersone41251e2008-04-25 01:59:09 +00002215 Outputs the record to the file, catering for rollover as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002216
2217
Vinay Sajipd31f3632010-06-29 15:31:15 +00002218.. _socket-handler:
2219
Georg Brandl116aa622007-08-15 14:28:22 +00002220SocketHandler
2221^^^^^^^^^^^^^
2222
2223The :class:`SocketHandler` class, located in the :mod:`logging.handlers` module,
2224sends logging output to a network socket. The base class uses a TCP socket.
2225
2226
2227.. class:: SocketHandler(host, port)
2228
2229 Returns a new instance of the :class:`SocketHandler` class intended to
2230 communicate with a remote machine whose address is given by *host* and *port*.
2231
2232
Benjamin Petersone41251e2008-04-25 01:59:09 +00002233 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002234
Benjamin Petersone41251e2008-04-25 01:59:09 +00002235 Closes the socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002236
2237
Benjamin Petersone41251e2008-04-25 01:59:09 +00002238 .. method:: emit()
Georg Brandl116aa622007-08-15 14:28:22 +00002239
Benjamin Petersone41251e2008-04-25 01:59:09 +00002240 Pickles the record's attribute dictionary and writes it to the socket in
2241 binary format. If there is an error with the socket, silently drops the
2242 packet. If the connection was previously lost, re-establishes the
2243 connection. To unpickle the record at the receiving end into a
2244 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl116aa622007-08-15 14:28:22 +00002245
2246
Benjamin Petersone41251e2008-04-25 01:59:09 +00002247 .. method:: handleError()
Georg Brandl116aa622007-08-15 14:28:22 +00002248
Benjamin Petersone41251e2008-04-25 01:59:09 +00002249 Handles an error which has occurred during :meth:`emit`. The most likely
2250 cause is a lost connection. Closes the socket so that we can retry on the
2251 next event.
Georg Brandl116aa622007-08-15 14:28:22 +00002252
2253
Benjamin Petersone41251e2008-04-25 01:59:09 +00002254 .. method:: makeSocket()
Georg Brandl116aa622007-08-15 14:28:22 +00002255
Benjamin Petersone41251e2008-04-25 01:59:09 +00002256 This is a factory method which allows subclasses to define the precise
2257 type of socket they want. The default implementation creates a TCP socket
2258 (:const:`socket.SOCK_STREAM`).
Georg Brandl116aa622007-08-15 14:28:22 +00002259
2260
Benjamin Petersone41251e2008-04-25 01:59:09 +00002261 .. method:: makePickle(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002262
Benjamin Petersone41251e2008-04-25 01:59:09 +00002263 Pickles the record's attribute dictionary in binary format with a length
2264 prefix, and returns it ready for transmission across the socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002265
Vinay Sajipd31f3632010-06-29 15:31:15 +00002266 Note that pickles aren't completely secure. If you are concerned about
2267 security, you may want to override this method to implement a more secure
2268 mechanism. For example, you can sign pickles using HMAC and then verify
2269 them on the receiving end, or alternatively you can disable unpickling of
2270 global objects on the receiving end.
Georg Brandl116aa622007-08-15 14:28:22 +00002271
Benjamin Petersone41251e2008-04-25 01:59:09 +00002272 .. method:: send(packet)
Georg Brandl116aa622007-08-15 14:28:22 +00002273
Benjamin Petersone41251e2008-04-25 01:59:09 +00002274 Send a pickled string *packet* to the socket. This function allows for
2275 partial sends which can happen when the network is busy.
Georg Brandl116aa622007-08-15 14:28:22 +00002276
2277
Vinay Sajipd31f3632010-06-29 15:31:15 +00002278.. _datagram-handler:
2279
Georg Brandl116aa622007-08-15 14:28:22 +00002280DatagramHandler
2281^^^^^^^^^^^^^^^
2282
2283The :class:`DatagramHandler` class, located in the :mod:`logging.handlers`
2284module, inherits from :class:`SocketHandler` to support sending logging messages
2285over UDP sockets.
2286
2287
2288.. class:: DatagramHandler(host, port)
2289
2290 Returns a new instance of the :class:`DatagramHandler` class intended to
2291 communicate with a remote machine whose address is given by *host* and *port*.
2292
2293
Benjamin Petersone41251e2008-04-25 01:59:09 +00002294 .. method:: emit()
Georg Brandl116aa622007-08-15 14:28:22 +00002295
Benjamin Petersone41251e2008-04-25 01:59:09 +00002296 Pickles the record's attribute dictionary and writes it to the socket in
2297 binary format. If there is an error with the socket, silently drops the
2298 packet. To unpickle the record at the receiving end into a
2299 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl116aa622007-08-15 14:28:22 +00002300
2301
Benjamin Petersone41251e2008-04-25 01:59:09 +00002302 .. method:: makeSocket()
Georg Brandl116aa622007-08-15 14:28:22 +00002303
Benjamin Petersone41251e2008-04-25 01:59:09 +00002304 The factory method of :class:`SocketHandler` is here overridden to create
2305 a UDP socket (:const:`socket.SOCK_DGRAM`).
Georg Brandl116aa622007-08-15 14:28:22 +00002306
2307
Benjamin Petersone41251e2008-04-25 01:59:09 +00002308 .. method:: send(s)
Georg Brandl116aa622007-08-15 14:28:22 +00002309
Benjamin Petersone41251e2008-04-25 01:59:09 +00002310 Send a pickled string to a socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002311
2312
Vinay Sajipd31f3632010-06-29 15:31:15 +00002313.. _syslog-handler:
2314
Georg Brandl116aa622007-08-15 14:28:22 +00002315SysLogHandler
2316^^^^^^^^^^^^^
2317
2318The :class:`SysLogHandler` class, located in the :mod:`logging.handlers` module,
2319supports sending logging messages to a remote or local Unix syslog.
2320
2321
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002322.. class:: SysLogHandler(address=('localhost', SYSLOG_UDP_PORT), facility=LOG_USER, socktype=socket.SOCK_DGRAM)
Georg Brandl116aa622007-08-15 14:28:22 +00002323
2324 Returns a new instance of the :class:`SysLogHandler` class intended to
2325 communicate with a remote Unix machine whose address is given by *address* in
2326 the form of a ``(host, port)`` tuple. If *address* is not specified,
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002327 ``('localhost', 514)`` is used. The address is used to open a socket. An
Georg Brandl116aa622007-08-15 14:28:22 +00002328 alternative to providing a ``(host, port)`` tuple is providing an address as a
2329 string, for example "/dev/log". In this case, a Unix domain socket is used to
2330 send the message to the syslog. If *facility* is not specified,
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002331 :const:`LOG_USER` is used. The type of socket opened depends on the
2332 *socktype* argument, which defaults to :const:`socket.SOCK_DGRAM` and thus
2333 opens a UDP socket. To open a TCP socket (for use with the newer syslog
2334 daemons such as rsyslog), specify a value of :const:`socket.SOCK_STREAM`.
2335
2336 .. versionchanged:: 3.2
2337 *socktype* was added.
Georg Brandl116aa622007-08-15 14:28:22 +00002338
2339
Benjamin Petersone41251e2008-04-25 01:59:09 +00002340 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002341
Benjamin Petersone41251e2008-04-25 01:59:09 +00002342 Closes the socket to the remote host.
Georg Brandl116aa622007-08-15 14:28:22 +00002343
2344
Benjamin Petersone41251e2008-04-25 01:59:09 +00002345 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002346
Benjamin Petersone41251e2008-04-25 01:59:09 +00002347 The record is formatted, and then sent to the syslog server. If exception
2348 information is present, it is *not* sent to the server.
Georg Brandl116aa622007-08-15 14:28:22 +00002349
2350
Benjamin Petersone41251e2008-04-25 01:59:09 +00002351 .. method:: encodePriority(facility, priority)
Georg Brandl116aa622007-08-15 14:28:22 +00002352
Benjamin Petersone41251e2008-04-25 01:59:09 +00002353 Encodes the facility and priority into an integer. You can pass in strings
2354 or integers - if strings are passed, internal mapping dictionaries are
2355 used to convert them to integers.
Georg Brandl116aa622007-08-15 14:28:22 +00002356
Benjamin Peterson22005fc2010-04-11 16:25:06 +00002357 The symbolic ``LOG_`` values are defined in :class:`SysLogHandler` and
2358 mirror the values defined in the ``sys/syslog.h`` header file.
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002359
Georg Brandl88d7dbd2010-04-18 09:50:07 +00002360 **Priorities**
2361
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002362 +--------------------------+---------------+
2363 | Name (string) | Symbolic value|
2364 +==========================+===============+
2365 | ``alert`` | LOG_ALERT |
2366 +--------------------------+---------------+
2367 | ``crit`` or ``critical`` | LOG_CRIT |
2368 +--------------------------+---------------+
2369 | ``debug`` | LOG_DEBUG |
2370 +--------------------------+---------------+
2371 | ``emerg`` or ``panic`` | LOG_EMERG |
2372 +--------------------------+---------------+
2373 | ``err`` or ``error`` | LOG_ERR |
2374 +--------------------------+---------------+
2375 | ``info`` | LOG_INFO |
2376 +--------------------------+---------------+
2377 | ``notice`` | LOG_NOTICE |
2378 +--------------------------+---------------+
2379 | ``warn`` or ``warning`` | LOG_WARNING |
2380 +--------------------------+---------------+
2381
Georg Brandl88d7dbd2010-04-18 09:50:07 +00002382 **Facilities**
2383
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002384 +---------------+---------------+
2385 | Name (string) | Symbolic value|
2386 +===============+===============+
2387 | ``auth`` | LOG_AUTH |
2388 +---------------+---------------+
2389 | ``authpriv`` | LOG_AUTHPRIV |
2390 +---------------+---------------+
2391 | ``cron`` | LOG_CRON |
2392 +---------------+---------------+
2393 | ``daemon`` | LOG_DAEMON |
2394 +---------------+---------------+
2395 | ``ftp`` | LOG_FTP |
2396 +---------------+---------------+
2397 | ``kern`` | LOG_KERN |
2398 +---------------+---------------+
2399 | ``lpr`` | LOG_LPR |
2400 +---------------+---------------+
2401 | ``mail`` | LOG_MAIL |
2402 +---------------+---------------+
2403 | ``news`` | LOG_NEWS |
2404 +---------------+---------------+
2405 | ``syslog`` | LOG_SYSLOG |
2406 +---------------+---------------+
2407 | ``user`` | LOG_USER |
2408 +---------------+---------------+
2409 | ``uucp`` | LOG_UUCP |
2410 +---------------+---------------+
2411 | ``local0`` | LOG_LOCAL0 |
2412 +---------------+---------------+
2413 | ``local1`` | LOG_LOCAL1 |
2414 +---------------+---------------+
2415 | ``local2`` | LOG_LOCAL2 |
2416 +---------------+---------------+
2417 | ``local3`` | LOG_LOCAL3 |
2418 +---------------+---------------+
2419 | ``local4`` | LOG_LOCAL4 |
2420 +---------------+---------------+
2421 | ``local5`` | LOG_LOCAL5 |
2422 +---------------+---------------+
2423 | ``local6`` | LOG_LOCAL6 |
2424 +---------------+---------------+
2425 | ``local7`` | LOG_LOCAL7 |
2426 +---------------+---------------+
2427
2428 .. method:: mapPriority(levelname)
2429
2430 Maps a logging level name to a syslog priority name.
2431 You may need to override this if you are using custom levels, or
2432 if the default algorithm is not suitable for your needs. The
2433 default algorithm maps ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and
2434 ``CRITICAL`` to the equivalent syslog names, and all other level
2435 names to "warning".
2436
2437.. _nt-eventlog-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002438
2439NTEventLogHandler
2440^^^^^^^^^^^^^^^^^
2441
2442The :class:`NTEventLogHandler` class, located in the :mod:`logging.handlers`
2443module, supports sending logging messages to a local Windows NT, Windows 2000 or
2444Windows XP event log. Before you can use it, you need Mark Hammond's Win32
2445extensions for Python installed.
2446
2447
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002448.. class:: NTEventLogHandler(appname, dllname=None, logtype='Application')
Georg Brandl116aa622007-08-15 14:28:22 +00002449
2450 Returns a new instance of the :class:`NTEventLogHandler` class. The *appname* is
2451 used to define the application name as it appears in the event log. An
2452 appropriate registry entry is created using this name. The *dllname* should give
2453 the fully qualified pathname of a .dll or .exe which contains message
2454 definitions to hold in the log (if not specified, ``'win32service.pyd'`` is used
2455 - this is installed with the Win32 extensions and contains some basic
2456 placeholder message definitions. Note that use of these placeholders will make
2457 your event logs big, as the entire message source is held in the log. If you
2458 want slimmer logs, you have to pass in the name of your own .dll or .exe which
2459 contains the message definitions you want to use in the event log). The
2460 *logtype* is one of ``'Application'``, ``'System'`` or ``'Security'``, and
2461 defaults to ``'Application'``.
2462
2463
Benjamin Petersone41251e2008-04-25 01:59:09 +00002464 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002465
Benjamin Petersone41251e2008-04-25 01:59:09 +00002466 At this point, you can remove the application name from the registry as a
2467 source of event log entries. However, if you do this, you will not be able
2468 to see the events as you intended in the Event Log Viewer - it needs to be
2469 able to access the registry to get the .dll name. The current version does
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002470 not do this.
Georg Brandl116aa622007-08-15 14:28:22 +00002471
2472
Benjamin Petersone41251e2008-04-25 01:59:09 +00002473 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002474
Benjamin Petersone41251e2008-04-25 01:59:09 +00002475 Determines the message ID, event category and event type, and then logs
2476 the message in the NT event log.
Georg Brandl116aa622007-08-15 14:28:22 +00002477
2478
Benjamin Petersone41251e2008-04-25 01:59:09 +00002479 .. method:: getEventCategory(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002480
Benjamin Petersone41251e2008-04-25 01:59:09 +00002481 Returns the event category for the record. Override this if you want to
2482 specify your own categories. This version returns 0.
Georg Brandl116aa622007-08-15 14:28:22 +00002483
2484
Benjamin Petersone41251e2008-04-25 01:59:09 +00002485 .. method:: getEventType(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002486
Benjamin Petersone41251e2008-04-25 01:59:09 +00002487 Returns the event type for the record. Override this if you want to
2488 specify your own types. This version does a mapping using the handler's
2489 typemap attribute, which is set up in :meth:`__init__` to a dictionary
2490 which contains mappings for :const:`DEBUG`, :const:`INFO`,
2491 :const:`WARNING`, :const:`ERROR` and :const:`CRITICAL`. If you are using
2492 your own levels, you will either need to override this method or place a
2493 suitable dictionary in the handler's *typemap* attribute.
Georg Brandl116aa622007-08-15 14:28:22 +00002494
2495
Benjamin Petersone41251e2008-04-25 01:59:09 +00002496 .. method:: getMessageID(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002497
Benjamin Petersone41251e2008-04-25 01:59:09 +00002498 Returns the message ID for the record. If you are using your own messages,
2499 you could do this by having the *msg* passed to the logger being an ID
2500 rather than a format string. Then, in here, you could use a dictionary
2501 lookup to get the message ID. This version returns 1, which is the base
2502 message ID in :file:`win32service.pyd`.
Georg Brandl116aa622007-08-15 14:28:22 +00002503
Vinay Sajipd31f3632010-06-29 15:31:15 +00002504.. _smtp-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002505
2506SMTPHandler
2507^^^^^^^^^^^
2508
2509The :class:`SMTPHandler` class, located in the :mod:`logging.handlers` module,
2510supports sending logging messages to an email address via SMTP.
2511
2512
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002513.. class:: SMTPHandler(mailhost, fromaddr, toaddrs, subject, credentials=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002514
2515 Returns a new instance of the :class:`SMTPHandler` class. The instance is
2516 initialized with the from and to addresses and subject line of the email. The
2517 *toaddrs* should be a list of strings. To specify a non-standard SMTP port, use
2518 the (host, port) tuple format for the *mailhost* argument. If you use a string,
2519 the standard SMTP port is used. If your SMTP server requires authentication, you
2520 can specify a (username, password) tuple for the *credentials* argument.
2521
Georg Brandl116aa622007-08-15 14:28:22 +00002522
Benjamin Petersone41251e2008-04-25 01:59:09 +00002523 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002524
Benjamin Petersone41251e2008-04-25 01:59:09 +00002525 Formats the record and sends it to the specified addressees.
Georg Brandl116aa622007-08-15 14:28:22 +00002526
2527
Benjamin Petersone41251e2008-04-25 01:59:09 +00002528 .. method:: getSubject(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002529
Benjamin Petersone41251e2008-04-25 01:59:09 +00002530 If you want to specify a subject line which is record-dependent, override
2531 this method.
Georg Brandl116aa622007-08-15 14:28:22 +00002532
Vinay Sajipd31f3632010-06-29 15:31:15 +00002533.. _memory-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002534
2535MemoryHandler
2536^^^^^^^^^^^^^
2537
2538The :class:`MemoryHandler` class, located in the :mod:`logging.handlers` module,
2539supports buffering of logging records in memory, periodically flushing them to a
2540:dfn:`target` handler. Flushing occurs whenever the buffer is full, or when an
2541event of a certain severity or greater is seen.
2542
2543:class:`MemoryHandler` is a subclass of the more general
2544:class:`BufferingHandler`, which is an abstract class. This buffers logging
2545records in memory. Whenever each record is added to the buffer, a check is made
2546by calling :meth:`shouldFlush` to see if the buffer should be flushed. If it
2547should, then :meth:`flush` is expected to do the needful.
2548
2549
2550.. class:: BufferingHandler(capacity)
2551
2552 Initializes the handler with a buffer of the specified capacity.
2553
2554
Benjamin Petersone41251e2008-04-25 01:59:09 +00002555 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002556
Benjamin Petersone41251e2008-04-25 01:59:09 +00002557 Appends the record to the buffer. If :meth:`shouldFlush` returns true,
2558 calls :meth:`flush` to process the buffer.
Georg Brandl116aa622007-08-15 14:28:22 +00002559
2560
Benjamin Petersone41251e2008-04-25 01:59:09 +00002561 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002562
Benjamin Petersone41251e2008-04-25 01:59:09 +00002563 You can override this to implement custom flushing behavior. This version
2564 just zaps the buffer to empty.
Georg Brandl116aa622007-08-15 14:28:22 +00002565
2566
Benjamin Petersone41251e2008-04-25 01:59:09 +00002567 .. method:: shouldFlush(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002568
Benjamin Petersone41251e2008-04-25 01:59:09 +00002569 Returns true if the buffer is up to capacity. This method can be
2570 overridden to implement custom flushing strategies.
Georg Brandl116aa622007-08-15 14:28:22 +00002571
2572
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002573.. class:: MemoryHandler(capacity, flushLevel=ERROR, target=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002574
2575 Returns a new instance of the :class:`MemoryHandler` class. The instance is
2576 initialized with a buffer size of *capacity*. If *flushLevel* is not specified,
2577 :const:`ERROR` is used. If no *target* is specified, the target will need to be
2578 set using :meth:`setTarget` before this handler does anything useful.
2579
2580
Benjamin Petersone41251e2008-04-25 01:59:09 +00002581 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002582
Benjamin Petersone41251e2008-04-25 01:59:09 +00002583 Calls :meth:`flush`, sets the target to :const:`None` and clears the
2584 buffer.
Georg Brandl116aa622007-08-15 14:28:22 +00002585
2586
Benjamin Petersone41251e2008-04-25 01:59:09 +00002587 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002588
Benjamin Petersone41251e2008-04-25 01:59:09 +00002589 For a :class:`MemoryHandler`, flushing means just sending the buffered
2590 records to the target, if there is one. Override if you want different
2591 behavior.
Georg Brandl116aa622007-08-15 14:28:22 +00002592
2593
Benjamin Petersone41251e2008-04-25 01:59:09 +00002594 .. method:: setTarget(target)
Georg Brandl116aa622007-08-15 14:28:22 +00002595
Benjamin Petersone41251e2008-04-25 01:59:09 +00002596 Sets the target handler for this handler.
Georg Brandl116aa622007-08-15 14:28:22 +00002597
2598
Benjamin Petersone41251e2008-04-25 01:59:09 +00002599 .. method:: shouldFlush(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002600
Benjamin Petersone41251e2008-04-25 01:59:09 +00002601 Checks for buffer full or a record at the *flushLevel* or higher.
Georg Brandl116aa622007-08-15 14:28:22 +00002602
2603
Vinay Sajipd31f3632010-06-29 15:31:15 +00002604.. _http-handler:
2605
Georg Brandl116aa622007-08-15 14:28:22 +00002606HTTPHandler
2607^^^^^^^^^^^
2608
2609The :class:`HTTPHandler` class, located in the :mod:`logging.handlers` module,
2610supports sending logging messages to a Web server, using either ``GET`` or
2611``POST`` semantics.
2612
2613
Vinay Sajip1b5646a2010-09-13 20:37:50 +00002614.. class:: HTTPHandler(host, url, method='GET', secure=False, credentials=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002615
Vinay Sajip1b5646a2010-09-13 20:37:50 +00002616 Returns a new instance of the :class:`HTTPHandler` class. The *host* can be
2617 of the form ``host:port``, should you need to use a specific port number.
2618 If no *method* is specified, ``GET`` is used. If *secure* is True, an HTTPS
2619 connection will be used. If *credentials* is specified, it should be a
2620 2-tuple consisting of userid and password, which will be placed in an HTTP
2621 'Authorization' header using Basic authentication. If you specify
2622 credentials, you should also specify secure=True so that your userid and
2623 password are not passed in cleartext across the wire.
Georg Brandl116aa622007-08-15 14:28:22 +00002624
2625
Benjamin Petersone41251e2008-04-25 01:59:09 +00002626 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002627
Senthil Kumaranf0769e82010-08-09 19:53:52 +00002628 Sends the record to the Web server as a percent-encoded dictionary.
Georg Brandl116aa622007-08-15 14:28:22 +00002629
2630
Vinay Sajip121a1c42010-09-08 10:46:15 +00002631.. _queue-handler:
2632
2633
2634QueueHandler
2635^^^^^^^^^^^^
2636
2637The :class:`QueueHandler` class, located in the :mod:`logging.handlers` module,
2638supports sending logging messages to a queue, such as those implemented in the
2639:mod:`queue` or :mod:`multiprocessing` modules.
2640
2641
2642.. class:: QueueHandler(queue)
2643
2644 Returns a new instance of the :class:`QueueHandler` class. The instance is
Vinay Sajip63891ed2010-09-13 20:02:39 +00002645 initialized with the queue to send messages to. The queue can be any queue-
2646 like object; it's passed as-is to the :meth:`enqueue` method, which needs
2647 to know how to send messages to it.
Vinay Sajip121a1c42010-09-08 10:46:15 +00002648
2649
2650 .. method:: emit(record)
2651
2652 Sends the record to the handler's queue.
2653
2654 .. method:: enqueue(record)
2655
2656 Enqueues the record on the queue using ``put_nowait()``; you may
2657 want to override this if you want to use blocking behaviour, or a
2658 timeout, or a customised queue implementation.
2659
2660
2661.. versionadded:: 3.2
2662
2663The :class:`QueueHandler` class was not present in previous versions.
2664
Vinay Sajip63891ed2010-09-13 20:02:39 +00002665.. _zeromq-handlers:
2666
2667You can use a :class:`QueueHandler` subclass to send messages to other kinds
2668of queues, for example a ZeroMQ "publish" socket. In the example below,the
2669socket is created separately and passed to the handler (as its 'queue')::
2670
2671 import zmq # using pyzmq, the Python binding for ZeroMQ
2672 import json # for serializing records portably
2673
2674 ctx = zmq.Context()
2675 sock = zmq.Socket(ctx, zmq.PUB) # or zmq.PUSH, or other suitable value
2676 sock.bind('tcp://*:5556') # or wherever
2677
2678 class ZeroMQSocketHandler(QueueHandler):
2679 def enqueue(self, record):
2680 data = json.dumps(record.__dict__)
2681 self.queue.send(data)
2682
Vinay Sajip0055c422010-09-14 09:42:39 +00002683 handler = ZeroMQSocketHandler(sock)
2684
2685
Vinay Sajip63891ed2010-09-13 20:02:39 +00002686Of course there are other ways of organizing this, for example passing in the
2687data needed by the handler to create the socket::
2688
2689 class ZeroMQSocketHandler(QueueHandler):
2690 def __init__(self, uri, socktype=zmq.PUB, ctx=None):
2691 self.ctx = ctx or zmq.Context()
2692 socket = zmq.Socket(self.ctx, socktype)
Vinay Sajip0055c422010-09-14 09:42:39 +00002693 QueueHandler.__init__(self, socket)
Vinay Sajip63891ed2010-09-13 20:02:39 +00002694
2695 def enqueue(self, record):
2696 data = json.dumps(record.__dict__)
2697 self.queue.send(data)
2698
Vinay Sajipde726922010-09-14 06:59:24 +00002699 def close(self):
2700 self.queue.close()
Vinay Sajip121a1c42010-09-08 10:46:15 +00002701
Christian Heimes8b0facf2007-12-04 19:30:01 +00002702.. _formatter-objects:
2703
Georg Brandl116aa622007-08-15 14:28:22 +00002704Formatter Objects
2705-----------------
2706
Benjamin Peterson75edad02009-01-01 15:05:06 +00002707.. currentmodule:: logging
2708
Georg Brandl116aa622007-08-15 14:28:22 +00002709:class:`Formatter`\ s have the following attributes and methods. They are
2710responsible for converting a :class:`LogRecord` to (usually) a string which can
2711be interpreted by either a human or an external system. The base
2712:class:`Formatter` allows a formatting string to be specified. If none is
2713supplied, the default value of ``'%(message)s'`` is used.
2714
2715A Formatter can be initialized with a format string which makes use of knowledge
2716of the :class:`LogRecord` attributes - such as the default value mentioned above
2717making use of the fact that the user's message and arguments are pre-formatted
2718into a :class:`LogRecord`'s *message* attribute. This format string contains
Ezio Melotti0639d5a2009-12-19 23:26:38 +00002719standard Python %-style mapping keys. See section :ref:`old-string-formatting`
Georg Brandl116aa622007-08-15 14:28:22 +00002720for more information on string formatting.
2721
2722Currently, the useful mapping keys in a :class:`LogRecord` are:
2723
2724+-------------------------+-----------------------------------------------+
2725| Format | Description |
2726+=========================+===============================================+
2727| ``%(name)s`` | Name of the logger (logging channel). |
2728+-------------------------+-----------------------------------------------+
2729| ``%(levelno)s`` | Numeric logging level for the message |
2730| | (:const:`DEBUG`, :const:`INFO`, |
2731| | :const:`WARNING`, :const:`ERROR`, |
2732| | :const:`CRITICAL`). |
2733+-------------------------+-----------------------------------------------+
2734| ``%(levelname)s`` | Text logging level for the message |
2735| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
2736| | ``'ERROR'``, ``'CRITICAL'``). |
2737+-------------------------+-----------------------------------------------+
2738| ``%(pathname)s`` | Full pathname of the source file where the |
2739| | logging call was issued (if available). |
2740+-------------------------+-----------------------------------------------+
2741| ``%(filename)s`` | Filename portion of pathname. |
2742+-------------------------+-----------------------------------------------+
2743| ``%(module)s`` | Module (name portion of filename). |
2744+-------------------------+-----------------------------------------------+
2745| ``%(funcName)s`` | Name of function containing the logging call. |
2746+-------------------------+-----------------------------------------------+
2747| ``%(lineno)d`` | Source line number where the logging call was |
2748| | issued (if available). |
2749+-------------------------+-----------------------------------------------+
2750| ``%(created)f`` | Time when the :class:`LogRecord` was created |
2751| | (as returned by :func:`time.time`). |
2752+-------------------------+-----------------------------------------------+
2753| ``%(relativeCreated)d`` | Time in milliseconds when the LogRecord was |
2754| | created, relative to the time the logging |
2755| | module was loaded. |
2756+-------------------------+-----------------------------------------------+
2757| ``%(asctime)s`` | Human-readable time when the |
2758| | :class:`LogRecord` was created. By default |
2759| | this is of the form "2003-07-08 16:49:45,896" |
2760| | (the numbers after the comma are millisecond |
2761| | portion of the time). |
2762+-------------------------+-----------------------------------------------+
2763| ``%(msecs)d`` | Millisecond portion of the time when the |
2764| | :class:`LogRecord` was created. |
2765+-------------------------+-----------------------------------------------+
2766| ``%(thread)d`` | Thread ID (if available). |
2767+-------------------------+-----------------------------------------------+
2768| ``%(threadName)s`` | Thread name (if available). |
2769+-------------------------+-----------------------------------------------+
2770| ``%(process)d`` | Process ID (if available). |
2771+-------------------------+-----------------------------------------------+
Vinay Sajip121a1c42010-09-08 10:46:15 +00002772| ``%(processName)s`` | Process name (if available). |
2773+-------------------------+-----------------------------------------------+
Georg Brandl116aa622007-08-15 14:28:22 +00002774| ``%(message)s`` | The logged message, computed as ``msg % |
2775| | args``. |
2776+-------------------------+-----------------------------------------------+
2777
Georg Brandl116aa622007-08-15 14:28:22 +00002778
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002779.. class:: Formatter(fmt=None, datefmt=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002780
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002781 Returns a new instance of the :class:`Formatter` class. The instance is
2782 initialized with a format string for the message as a whole, as well as a
2783 format string for the date/time portion of a message. If no *fmt* is
2784 specified, ``'%(message)s'`` is used. If no *datefmt* is specified, the
2785 ISO8601 date format is used.
Georg Brandl116aa622007-08-15 14:28:22 +00002786
Benjamin Petersone41251e2008-04-25 01:59:09 +00002787 .. method:: format(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002788
Benjamin Petersone41251e2008-04-25 01:59:09 +00002789 The record's attribute dictionary is used as the operand to a string
2790 formatting operation. Returns the resulting string. Before formatting the
2791 dictionary, a couple of preparatory steps are carried out. The *message*
2792 attribute of the record is computed using *msg* % *args*. If the
2793 formatting string contains ``'(asctime)'``, :meth:`formatTime` is called
2794 to format the event time. If there is exception information, it is
2795 formatted using :meth:`formatException` and appended to the message. Note
2796 that the formatted exception information is cached in attribute
2797 *exc_text*. This is useful because the exception information can be
2798 pickled and sent across the wire, but you should be careful if you have
2799 more than one :class:`Formatter` subclass which customizes the formatting
2800 of exception information. In this case, you will have to clear the cached
2801 value after a formatter has done its formatting, so that the next
2802 formatter to handle the event doesn't use the cached value but
2803 recalculates it afresh.
Georg Brandl116aa622007-08-15 14:28:22 +00002804
2805
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002806 .. method:: formatTime(record, datefmt=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002807
Benjamin Petersone41251e2008-04-25 01:59:09 +00002808 This method should be called from :meth:`format` by a formatter which
2809 wants to make use of a formatted time. This method can be overridden in
2810 formatters to provide for any specific requirement, but the basic behavior
2811 is as follows: if *datefmt* (a string) is specified, it is used with
2812 :func:`time.strftime` to format the creation time of the
2813 record. Otherwise, the ISO8601 format is used. The resulting string is
2814 returned.
Georg Brandl116aa622007-08-15 14:28:22 +00002815
2816
Benjamin Petersone41251e2008-04-25 01:59:09 +00002817 .. method:: formatException(exc_info)
Georg Brandl116aa622007-08-15 14:28:22 +00002818
Benjamin Petersone41251e2008-04-25 01:59:09 +00002819 Formats the specified exception information (a standard exception tuple as
2820 returned by :func:`sys.exc_info`) as a string. This default implementation
2821 just uses :func:`traceback.print_exception`. The resulting string is
2822 returned.
Georg Brandl116aa622007-08-15 14:28:22 +00002823
Vinay Sajipd31f3632010-06-29 15:31:15 +00002824.. _filter:
Georg Brandl116aa622007-08-15 14:28:22 +00002825
2826Filter Objects
2827--------------
2828
2829:class:`Filter`\ s can be used by :class:`Handler`\ s and :class:`Logger`\ s for
2830more sophisticated filtering than is provided by levels. The base filter class
2831only allows events which are below a certain point in the logger hierarchy. For
2832example, a filter initialized with "A.B" will allow events logged by loggers
2833"A.B", "A.B.C", "A.B.C.D", "A.B.D" etc. but not "A.BB", "B.A.B" etc. If
2834initialized with the empty string, all events are passed.
2835
2836
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002837.. class:: Filter(name='')
Georg Brandl116aa622007-08-15 14:28:22 +00002838
2839 Returns an instance of the :class:`Filter` class. If *name* is specified, it
2840 names a logger which, together with its children, will have its events allowed
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002841 through the filter. If *name* is the empty string, allows every event.
Georg Brandl116aa622007-08-15 14:28:22 +00002842
2843
Benjamin Petersone41251e2008-04-25 01:59:09 +00002844 .. method:: filter(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002845
Benjamin Petersone41251e2008-04-25 01:59:09 +00002846 Is the specified record to be logged? Returns zero for no, nonzero for
2847 yes. If deemed appropriate, the record may be modified in-place by this
2848 method.
Georg Brandl116aa622007-08-15 14:28:22 +00002849
Vinay Sajip81010212010-08-19 19:17:41 +00002850Note that filters attached to handlers are consulted whenever an event is
2851emitted by the handler, whereas filters attached to loggers are consulted
2852whenever an event is logged to the handler (using :meth:`debug`, :meth:`info`,
2853etc.) This means that events which have been generated by descendant loggers
2854will not be filtered by a logger's filter setting, unless the filter has also
2855been applied to those descendant loggers.
2856
Vinay Sajipac007992010-09-17 12:45:26 +00002857Other uses for filters
2858^^^^^^^^^^^^^^^^^^^^^^
2859
2860Although filters are used primarily to filter records based on more
2861sophisticated criteria than levels, they get to see every record which is
2862processed by the handler or logger they're attached to: this can be useful if
2863you want to do things like counting how many records were processed by a
2864particular logger or handler, or adding, changing or removing attributes in
2865the LogRecord being processed. Obviously changing the LogRecord needs to be
2866done with some care, but it does allow the injection of contextual information
2867into logs (see :ref:`filters-contextual`).
2868
Vinay Sajipd31f3632010-06-29 15:31:15 +00002869.. _log-record:
Georg Brandl116aa622007-08-15 14:28:22 +00002870
2871LogRecord Objects
2872-----------------
2873
Vinay Sajip4039aff2010-09-11 10:25:28 +00002874:class:`LogRecord` instances are created automatically by the :class:`Logger`
2875every time something is logged, and can be created manually via
2876:func:`makeLogRecord` (for example, from a pickled event received over the
2877wire).
Georg Brandl116aa622007-08-15 14:28:22 +00002878
2879
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002880.. class:: LogRecord(name, lvl, pathname, lineno, msg, args, exc_info, func=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002881
Vinay Sajip4039aff2010-09-11 10:25:28 +00002882 Contains all the information pertinent to the event being logged.
Georg Brandl116aa622007-08-15 14:28:22 +00002883
Vinay Sajip4039aff2010-09-11 10:25:28 +00002884 The primary information is passed in :attr:`msg` and :attr:`args`, which
2885 are combined using ``msg % args`` to create the :attr:`message` field of the
2886 record.
2887
2888 .. attribute:: args
2889
2890 Tuple of arguments to be used in formatting :attr:`msg`.
2891
2892 .. attribute:: exc_info
2893
2894 Exception tuple (à la `sys.exc_info`) or `None` if no exception
2895 information is availble.
2896
2897 .. attribute:: func
2898
2899 Name of the function of origin (i.e. in which the logging call was made).
2900
2901 .. attribute:: lineno
2902
2903 Line number in the source file of origin.
2904
2905 .. attribute:: lvl
2906
2907 Numeric logging level.
2908
2909 .. attribute:: message
2910
2911 Bound to the result of :meth:`getMessage` when
2912 :meth:`Formatter.format(record)<Formatter.format>` is invoked.
2913
2914 .. attribute:: msg
2915
2916 User-supplied :ref:`format string<string-formatting>` or arbitrary object
2917 (see :ref:`arbitrary-object-messages`) used in :meth:`getMessage`.
2918
2919 .. attribute:: name
2920
2921 Name of the logger that emitted the record.
2922
2923 .. attribute:: pathname
2924
2925 Absolute pathname of the source file of origin.
Georg Brandl116aa622007-08-15 14:28:22 +00002926
Benjamin Petersone41251e2008-04-25 01:59:09 +00002927 .. method:: getMessage()
Georg Brandl116aa622007-08-15 14:28:22 +00002928
Benjamin Petersone41251e2008-04-25 01:59:09 +00002929 Returns the message for this :class:`LogRecord` instance after merging any
Vinay Sajip4039aff2010-09-11 10:25:28 +00002930 user-supplied arguments with the message. If the user-supplied message
2931 argument to the logging call is not a string, :func:`str` is called on it to
2932 convert it to a string. This allows use of user-defined classes as
2933 messages, whose ``__str__`` method can return the actual format string to
2934 be used.
2935
2936 .. versionchanged:: 2.5
2937 *func* was added.
Benjamin Petersone41251e2008-04-25 01:59:09 +00002938
Vinay Sajipd31f3632010-06-29 15:31:15 +00002939.. _logger-adapter:
Georg Brandl116aa622007-08-15 14:28:22 +00002940
Christian Heimes04c420f2008-01-18 18:40:46 +00002941LoggerAdapter Objects
2942---------------------
2943
Christian Heimes04c420f2008-01-18 18:40:46 +00002944:class:`LoggerAdapter` instances are used to conveniently pass contextual
Georg Brandl86def6c2008-01-21 20:36:10 +00002945information into logging calls. For a usage example , see the section on
2946`adding contextual information to your logging output`__.
2947
2948__ context-info_
Christian Heimes04c420f2008-01-18 18:40:46 +00002949
2950.. class:: LoggerAdapter(logger, extra)
2951
2952 Returns an instance of :class:`LoggerAdapter` initialized with an
2953 underlying :class:`Logger` instance and a dict-like object.
2954
Benjamin Petersone41251e2008-04-25 01:59:09 +00002955 .. method:: process(msg, kwargs)
Christian Heimes04c420f2008-01-18 18:40:46 +00002956
Benjamin Petersone41251e2008-04-25 01:59:09 +00002957 Modifies the message and/or keyword arguments passed to a logging call in
2958 order to insert contextual information. This implementation takes the object
2959 passed as *extra* to the constructor and adds it to *kwargs* using key
2960 'extra'. The return value is a (*msg*, *kwargs*) tuple which has the
2961 (possibly modified) versions of the arguments passed in.
Christian Heimes04c420f2008-01-18 18:40:46 +00002962
2963In addition to the above, :class:`LoggerAdapter` supports all the logging
2964methods of :class:`Logger`, i.e. :meth:`debug`, :meth:`info`, :meth:`warning`,
2965:meth:`error`, :meth:`exception`, :meth:`critical` and :meth:`log`. These
2966methods have the same signatures as their counterparts in :class:`Logger`, so
2967you can use the two types of instances interchangeably.
2968
Ezio Melotti4d5195b2010-04-20 10:57:44 +00002969.. versionchanged:: 3.2
Georg Brandl67b21b72010-08-17 15:07:14 +00002970 The :meth:`isEnabledFor` method was added to :class:`LoggerAdapter`. This
2971 method delegates to the underlying logger.
Benjamin Peterson22005fc2010-04-11 16:25:06 +00002972
Georg Brandl116aa622007-08-15 14:28:22 +00002973
2974Thread Safety
2975-------------
2976
2977The logging module is intended to be thread-safe without any special work
2978needing to be done by its clients. It achieves this though using threading
2979locks; there is one lock to serialize access to the module's shared data, and
2980each handler also creates a lock to serialize access to its underlying I/O.
2981
Benjamin Petersond23f8222009-04-05 19:13:16 +00002982If you are implementing asynchronous signal handlers using the :mod:`signal`
2983module, you may not be able to use logging from within such handlers. This is
2984because lock implementations in the :mod:`threading` module are not always
2985re-entrant, and so cannot be invoked from such signal handlers.
Georg Brandl116aa622007-08-15 14:28:22 +00002986
Benjamin Peterson9451a1c2010-03-13 22:30:34 +00002987
2988Integration with the warnings module
2989------------------------------------
2990
2991The :func:`captureWarnings` function can be used to integrate :mod:`logging`
2992with the :mod:`warnings` module.
2993
2994.. function:: captureWarnings(capture)
2995
2996 This function is used to turn the capture of warnings by logging on and
2997 off.
2998
2999 If `capture` is `True`, warnings issued by the :mod:`warnings` module
3000 will be redirected to the logging system. Specifically, a warning will be
3001 formatted using :func:`warnings.formatwarning` and the resulting string
3002 logged to a logger named "py.warnings" with a severity of `WARNING`.
3003
3004 If `capture` is `False`, the redirection of warnings to the logging system
3005 will stop, and warnings will be redirected to their original destinations
3006 (i.e. those in effect before `captureWarnings(True)` was called).
3007
3008
Georg Brandl116aa622007-08-15 14:28:22 +00003009Configuration
3010-------------
3011
3012
3013.. _logging-config-api:
3014
3015Configuration functions
3016^^^^^^^^^^^^^^^^^^^^^^^
3017
Georg Brandl116aa622007-08-15 14:28:22 +00003018The following functions configure the logging module. They are located in the
3019:mod:`logging.config` module. Their use is optional --- you can configure the
3020logging module using these functions or by making calls to the main API (defined
3021in :mod:`logging` itself) and defining handlers which are declared either in
3022:mod:`logging` or :mod:`logging.handlers`.
3023
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003024.. function:: dictConfig(config)
Georg Brandl116aa622007-08-15 14:28:22 +00003025
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003026 Takes the logging configuration from a dictionary. The contents of
3027 this dictionary are described in :ref:`logging-config-dictschema`
3028 below.
3029
3030 If an error is encountered during configuration, this function will
3031 raise a :exc:`ValueError`, :exc:`TypeError`, :exc:`AttributeError`
3032 or :exc:`ImportError` with a suitably descriptive message. The
3033 following is a (possibly incomplete) list of conditions which will
3034 raise an error:
3035
3036 * A ``level`` which is not a string or which is a string not
3037 corresponding to an actual logging level.
3038 * A ``propagate`` value which is not a boolean.
3039 * An id which does not have a corresponding destination.
3040 * A non-existent handler id found during an incremental call.
3041 * An invalid logger name.
3042 * Inability to resolve to an internal or external object.
3043
3044 Parsing is performed by the :class:`DictConfigurator` class, whose
3045 constructor is passed the dictionary used for configuration, and
3046 has a :meth:`configure` method. The :mod:`logging.config` module
3047 has a callable attribute :attr:`dictConfigClass`
3048 which is initially set to :class:`DictConfigurator`.
3049 You can replace the value of :attr:`dictConfigClass` with a
3050 suitable implementation of your own.
3051
3052 :func:`dictConfig` calls :attr:`dictConfigClass` passing
3053 the specified dictionary, and then calls the :meth:`configure` method on
3054 the returned object to put the configuration into effect::
3055
3056 def dictConfig(config):
3057 dictConfigClass(config).configure()
3058
3059 For example, a subclass of :class:`DictConfigurator` could call
3060 ``DictConfigurator.__init__()`` in its own :meth:`__init__()`, then
3061 set up custom prefixes which would be usable in the subsequent
3062 :meth:`configure` call. :attr:`dictConfigClass` would be bound to
3063 this new subclass, and then :func:`dictConfig` could be called exactly as
3064 in the default, uncustomized state.
3065
3066.. function:: fileConfig(fname[, defaults])
Georg Brandl116aa622007-08-15 14:28:22 +00003067
Alexandre Vassalotti1d1eaa42008-05-14 22:59:42 +00003068 Reads the logging configuration from a :mod:`configparser`\-format file named
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00003069 *fname*. This function can be called several times from an application,
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003070 allowing an end user to select from various pre-canned
Alexandre Vassalotti1d1eaa42008-05-14 22:59:42 +00003071 configurations (if the developer provides a mechanism to present the choices
3072 and load the chosen configuration). Defaults to be passed to the ConfigParser
3073 can be specified in the *defaults* argument.
Georg Brandl116aa622007-08-15 14:28:22 +00003074
Georg Brandlcd7f32b2009-06-08 09:13:45 +00003075
3076.. function:: listen(port=DEFAULT_LOGGING_CONFIG_PORT)
Georg Brandl116aa622007-08-15 14:28:22 +00003077
3078 Starts up a socket server on the specified port, and listens for new
3079 configurations. If no port is specified, the module's default
3080 :const:`DEFAULT_LOGGING_CONFIG_PORT` is used. Logging configurations will be
3081 sent as a file suitable for processing by :func:`fileConfig`. Returns a
3082 :class:`Thread` instance on which you can call :meth:`start` to start the
3083 server, and which you can :meth:`join` when appropriate. To stop the server,
Christian Heimes8b0facf2007-12-04 19:30:01 +00003084 call :func:`stopListening`.
3085
3086 To send a configuration to the socket, read in the configuration file and
3087 send it to the socket as a string of bytes preceded by a four-byte length
3088 string packed in binary using ``struct.pack('>L', n)``.
Georg Brandl116aa622007-08-15 14:28:22 +00003089
3090
3091.. function:: stopListening()
3092
Christian Heimes8b0facf2007-12-04 19:30:01 +00003093 Stops the listening server which was created with a call to :func:`listen`.
3094 This is typically called before calling :meth:`join` on the return value from
Georg Brandl116aa622007-08-15 14:28:22 +00003095 :func:`listen`.
3096
3097
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003098.. _logging-config-dictschema:
3099
3100Configuration dictionary schema
3101^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3102
3103Describing a logging configuration requires listing the various
3104objects to create and the connections between them; for example, you
3105may create a handler named "console" and then say that the logger
3106named "startup" will send its messages to the "console" handler.
3107These objects aren't limited to those provided by the :mod:`logging`
3108module because you might write your own formatter or handler class.
3109The parameters to these classes may also need to include external
3110objects such as ``sys.stderr``. The syntax for describing these
3111objects and connections is defined in :ref:`logging-config-dict-connections`
3112below.
3113
3114Dictionary Schema Details
3115"""""""""""""""""""""""""
3116
3117The dictionary passed to :func:`dictConfig` must contain the following
3118keys:
3119
3120* `version` - to be set to an integer value representing the schema
3121 version. The only valid value at present is 1, but having this key
3122 allows the schema to evolve while still preserving backwards
3123 compatibility.
3124
3125All other keys are optional, but if present they will be interpreted
3126as described below. In all cases below where a 'configuring dict' is
3127mentioned, it will be checked for the special ``'()'`` key to see if a
3128custom instantiation is required. If so, the mechanism described in
3129:ref:`logging-config-dict-userdef` below is used to create an instance;
3130otherwise, the context is used to determine what to instantiate.
3131
3132* `formatters` - the corresponding value will be a dict in which each
3133 key is a formatter id and each value is a dict describing how to
3134 configure the corresponding Formatter instance.
3135
3136 The configuring dict is searched for keys ``format`` and ``datefmt``
3137 (with defaults of ``None``) and these are used to construct a
3138 :class:`logging.Formatter` instance.
3139
3140* `filters` - the corresponding value will be a dict in which each key
3141 is a filter id and each value is a dict describing how to configure
3142 the corresponding Filter instance.
3143
3144 The configuring dict is searched for the key ``name`` (defaulting to the
3145 empty string) and this is used to construct a :class:`logging.Filter`
3146 instance.
3147
3148* `handlers` - the corresponding value will be a dict in which each
3149 key is a handler id and each value is a dict describing how to
3150 configure the corresponding Handler instance.
3151
3152 The configuring dict is searched for the following keys:
3153
3154 * ``class`` (mandatory). This is the fully qualified name of the
3155 handler class.
3156
3157 * ``level`` (optional). The level of the handler.
3158
3159 * ``formatter`` (optional). The id of the formatter for this
3160 handler.
3161
3162 * ``filters`` (optional). A list of ids of the filters for this
3163 handler.
3164
3165 All *other* keys are passed through as keyword arguments to the
3166 handler's constructor. For example, given the snippet::
3167
3168 handlers:
3169 console:
3170 class : logging.StreamHandler
3171 formatter: brief
3172 level : INFO
3173 filters: [allow_foo]
3174 stream : ext://sys.stdout
3175 file:
3176 class : logging.handlers.RotatingFileHandler
3177 formatter: precise
3178 filename: logconfig.log
3179 maxBytes: 1024
3180 backupCount: 3
3181
3182 the handler with id ``console`` is instantiated as a
3183 :class:`logging.StreamHandler`, using ``sys.stdout`` as the underlying
3184 stream. The handler with id ``file`` is instantiated as a
3185 :class:`logging.handlers.RotatingFileHandler` with the keyword arguments
3186 ``filename='logconfig.log', maxBytes=1024, backupCount=3``.
3187
3188* `loggers` - the corresponding value will be a dict in which each key
3189 is a logger name and each value is a dict describing how to
3190 configure the corresponding Logger instance.
3191
3192 The configuring dict is searched for the following keys:
3193
3194 * ``level`` (optional). The level of the logger.
3195
3196 * ``propagate`` (optional). The propagation setting of the logger.
3197
3198 * ``filters`` (optional). A list of ids of the filters for this
3199 logger.
3200
3201 * ``handlers`` (optional). A list of ids of the handlers for this
3202 logger.
3203
3204 The specified loggers will be configured according to the level,
3205 propagation, filters and handlers specified.
3206
3207* `root` - this will be the configuration for the root logger.
3208 Processing of the configuration will be as for any logger, except
3209 that the ``propagate`` setting will not be applicable.
3210
3211* `incremental` - whether the configuration is to be interpreted as
3212 incremental to the existing configuration. This value defaults to
3213 ``False``, which means that the specified configuration replaces the
3214 existing configuration with the same semantics as used by the
3215 existing :func:`fileConfig` API.
3216
3217 If the specified value is ``True``, the configuration is processed
3218 as described in the section on :ref:`logging-config-dict-incremental`.
3219
3220* `disable_existing_loggers` - whether any existing loggers are to be
3221 disabled. This setting mirrors the parameter of the same name in
3222 :func:`fileConfig`. If absent, this parameter defaults to ``True``.
3223 This value is ignored if `incremental` is ``True``.
3224
3225.. _logging-config-dict-incremental:
3226
3227Incremental Configuration
3228"""""""""""""""""""""""""
3229
3230It is difficult to provide complete flexibility for incremental
3231configuration. For example, because objects such as filters
3232and formatters are anonymous, once a configuration is set up, it is
3233not possible to refer to such anonymous objects when augmenting a
3234configuration.
3235
3236Furthermore, there is not a compelling case for arbitrarily altering
3237the object graph of loggers, handlers, filters, formatters at
3238run-time, once a configuration is set up; the verbosity of loggers and
3239handlers can be controlled just by setting levels (and, in the case of
3240loggers, propagation flags). Changing the object graph arbitrarily in
3241a safe way is problematic in a multi-threaded environment; while not
3242impossible, the benefits are not worth the complexity it adds to the
3243implementation.
3244
3245Thus, when the ``incremental`` key of a configuration dict is present
3246and is ``True``, the system will completely ignore any ``formatters`` and
3247``filters`` entries, and process only the ``level``
3248settings in the ``handlers`` entries, and the ``level`` and
3249``propagate`` settings in the ``loggers`` and ``root`` entries.
3250
3251Using a value in the configuration dict lets configurations to be sent
3252over the wire as pickled dicts to a socket listener. Thus, the logging
3253verbosity of a long-running application can be altered over time with
3254no need to stop and restart the application.
3255
3256.. _logging-config-dict-connections:
3257
3258Object connections
3259""""""""""""""""""
3260
3261The schema describes a set of logging objects - loggers,
3262handlers, formatters, filters - which are connected to each other in
3263an object graph. Thus, the schema needs to represent connections
3264between the objects. For example, say that, once configured, a
3265particular logger has attached to it a particular handler. For the
3266purposes of this discussion, we can say that the logger represents the
3267source, and the handler the destination, of a connection between the
3268two. Of course in the configured objects this is represented by the
3269logger holding a reference to the handler. In the configuration dict,
3270this is done by giving each destination object an id which identifies
3271it unambiguously, and then using the id in the source object's
3272configuration to indicate that a connection exists between the source
3273and the destination object with that id.
3274
3275So, for example, consider the following YAML snippet::
3276
3277 formatters:
3278 brief:
3279 # configuration for formatter with id 'brief' goes here
3280 precise:
3281 # configuration for formatter with id 'precise' goes here
3282 handlers:
3283 h1: #This is an id
3284 # configuration of handler with id 'h1' goes here
3285 formatter: brief
3286 h2: #This is another id
3287 # configuration of handler with id 'h2' goes here
3288 formatter: precise
3289 loggers:
3290 foo.bar.baz:
3291 # other configuration for logger 'foo.bar.baz'
3292 handlers: [h1, h2]
3293
3294(Note: YAML used here because it's a little more readable than the
3295equivalent Python source form for the dictionary.)
3296
3297The ids for loggers are the logger names which would be used
3298programmatically to obtain a reference to those loggers, e.g.
3299``foo.bar.baz``. The ids for Formatters and Filters can be any string
3300value (such as ``brief``, ``precise`` above) and they are transient,
3301in that they are only meaningful for processing the configuration
3302dictionary and used to determine connections between objects, and are
3303not persisted anywhere when the configuration call is complete.
3304
3305The above snippet indicates that logger named ``foo.bar.baz`` should
3306have two handlers attached to it, which are described by the handler
3307ids ``h1`` and ``h2``. The formatter for ``h1`` is that described by id
3308``brief``, and the formatter for ``h2`` is that described by id
3309``precise``.
3310
3311
3312.. _logging-config-dict-userdef:
3313
3314User-defined objects
3315""""""""""""""""""""
3316
3317The schema supports user-defined objects for handlers, filters and
3318formatters. (Loggers do not need to have different types for
3319different instances, so there is no support in this configuration
3320schema for user-defined logger classes.)
3321
3322Objects to be configured are described by dictionaries
3323which detail their configuration. In some places, the logging system
3324will be able to infer from the context how an object is to be
3325instantiated, but when a user-defined object is to be instantiated,
3326the system will not know how to do this. In order to provide complete
3327flexibility for user-defined object instantiation, the user needs
3328to provide a 'factory' - a callable which is called with a
3329configuration dictionary and which returns the instantiated object.
3330This is signalled by an absolute import path to the factory being
3331made available under the special key ``'()'``. Here's a concrete
3332example::
3333
3334 formatters:
3335 brief:
3336 format: '%(message)s'
3337 default:
3338 format: '%(asctime)s %(levelname)-8s %(name)-15s %(message)s'
3339 datefmt: '%Y-%m-%d %H:%M:%S'
3340 custom:
3341 (): my.package.customFormatterFactory
3342 bar: baz
3343 spam: 99.9
3344 answer: 42
3345
3346The above YAML snippet defines three formatters. The first, with id
3347``brief``, is a standard :class:`logging.Formatter` instance with the
3348specified format string. The second, with id ``default``, has a
3349longer format and also defines the time format explicitly, and will
3350result in a :class:`logging.Formatter` initialized with those two format
3351strings. Shown in Python source form, the ``brief`` and ``default``
3352formatters have configuration sub-dictionaries::
3353
3354 {
3355 'format' : '%(message)s'
3356 }
3357
3358and::
3359
3360 {
3361 'format' : '%(asctime)s %(levelname)-8s %(name)-15s %(message)s',
3362 'datefmt' : '%Y-%m-%d %H:%M:%S'
3363 }
3364
3365respectively, and as these dictionaries do not contain the special key
3366``'()'``, the instantiation is inferred from the context: as a result,
3367standard :class:`logging.Formatter` instances are created. The
3368configuration sub-dictionary for the third formatter, with id
3369``custom``, is::
3370
3371 {
3372 '()' : 'my.package.customFormatterFactory',
3373 'bar' : 'baz',
3374 'spam' : 99.9,
3375 'answer' : 42
3376 }
3377
3378and this contains the special key ``'()'``, which means that
3379user-defined instantiation is wanted. In this case, the specified
3380factory callable will be used. If it is an actual callable it will be
3381used directly - otherwise, if you specify a string (as in the example)
3382the actual callable will be located using normal import mechanisms.
3383The callable will be called with the **remaining** items in the
3384configuration sub-dictionary as keyword arguments. In the above
3385example, the formatter with id ``custom`` will be assumed to be
3386returned by the call::
3387
3388 my.package.customFormatterFactory(bar='baz', spam=99.9, answer=42)
3389
3390The key ``'()'`` has been used as the special key because it is not a
3391valid keyword parameter name, and so will not clash with the names of
3392the keyword arguments used in the call. The ``'()'`` also serves as a
3393mnemonic that the corresponding value is a callable.
3394
3395
3396.. _logging-config-dict-externalobj:
3397
3398Access to external objects
3399""""""""""""""""""""""""""
3400
3401There are times where a configuration needs to refer to objects
3402external to the configuration, for example ``sys.stderr``. If the
3403configuration dict is constructed using Python code, this is
3404straightforward, but a problem arises when the configuration is
3405provided via a text file (e.g. JSON, YAML). In a text file, there is
3406no standard way to distinguish ``sys.stderr`` from the literal string
3407``'sys.stderr'``. To facilitate this distinction, the configuration
3408system looks for certain special prefixes in string values and
3409treat them specially. For example, if the literal string
3410``'ext://sys.stderr'`` is provided as a value in the configuration,
3411then the ``ext://`` will be stripped off and the remainder of the
3412value processed using normal import mechanisms.
3413
3414The handling of such prefixes is done in a way analogous to protocol
3415handling: there is a generic mechanism to look for prefixes which
3416match the regular expression ``^(?P<prefix>[a-z]+)://(?P<suffix>.*)$``
3417whereby, if the ``prefix`` is recognised, the ``suffix`` is processed
3418in a prefix-dependent manner and the result of the processing replaces
3419the string value. If the prefix is not recognised, then the string
3420value will be left as-is.
3421
3422
3423.. _logging-config-dict-internalobj:
3424
3425Access to internal objects
3426""""""""""""""""""""""""""
3427
3428As well as external objects, there is sometimes also a need to refer
3429to objects in the configuration. This will be done implicitly by the
3430configuration system for things that it knows about. For example, the
3431string value ``'DEBUG'`` for a ``level`` in a logger or handler will
3432automatically be converted to the value ``logging.DEBUG``, and the
3433``handlers``, ``filters`` and ``formatter`` entries will take an
3434object id and resolve to the appropriate destination object.
3435
3436However, a more generic mechanism is needed for user-defined
3437objects which are not known to the :mod:`logging` module. For
3438example, consider :class:`logging.handlers.MemoryHandler`, which takes
3439a ``target`` argument which is another handler to delegate to. Since
3440the system already knows about this class, then in the configuration,
3441the given ``target`` just needs to be the object id of the relevant
3442target handler, and the system will resolve to the handler from the
3443id. If, however, a user defines a ``my.package.MyHandler`` which has
3444an ``alternate`` handler, the configuration system would not know that
3445the ``alternate`` referred to a handler. To cater for this, a generic
3446resolution system allows the user to specify::
3447
3448 handlers:
3449 file:
3450 # configuration of file handler goes here
3451
3452 custom:
3453 (): my.package.MyHandler
3454 alternate: cfg://handlers.file
3455
3456The literal string ``'cfg://handlers.file'`` will be resolved in an
3457analogous way to strings with the ``ext://`` prefix, but looking
3458in the configuration itself rather than the import namespace. The
3459mechanism allows access by dot or by index, in a similar way to
3460that provided by ``str.format``. Thus, given the following snippet::
3461
3462 handlers:
3463 email:
3464 class: logging.handlers.SMTPHandler
3465 mailhost: localhost
3466 fromaddr: my_app@domain.tld
3467 toaddrs:
3468 - support_team@domain.tld
3469 - dev_team@domain.tld
3470 subject: Houston, we have a problem.
3471
3472in the configuration, the string ``'cfg://handlers'`` would resolve to
3473the dict with key ``handlers``, the string ``'cfg://handlers.email``
3474would resolve to the dict with key ``email`` in the ``handlers`` dict,
3475and so on. The string ``'cfg://handlers.email.toaddrs[1]`` would
3476resolve to ``'dev_team.domain.tld'`` and the string
3477``'cfg://handlers.email.toaddrs[0]'`` would resolve to the value
3478``'support_team@domain.tld'``. The ``subject`` value could be accessed
3479using either ``'cfg://handlers.email.subject'`` or, equivalently,
3480``'cfg://handlers.email[subject]'``. The latter form only needs to be
3481used if the key contains spaces or non-alphanumeric characters. If an
3482index value consists only of decimal digits, access will be attempted
3483using the corresponding integer value, falling back to the string
3484value if needed.
3485
3486Given a string ``cfg://handlers.myhandler.mykey.123``, this will
3487resolve to ``config_dict['handlers']['myhandler']['mykey']['123']``.
3488If the string is specified as ``cfg://handlers.myhandler.mykey[123]``,
3489the system will attempt to retrieve the value from
3490``config_dict['handlers']['myhandler']['mykey'][123]``, and fall back
3491to ``config_dict['handlers']['myhandler']['mykey']['123']`` if that
3492fails.
3493
Georg Brandl116aa622007-08-15 14:28:22 +00003494.. _logging-config-fileformat:
3495
3496Configuration file format
3497^^^^^^^^^^^^^^^^^^^^^^^^^
3498
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00003499The configuration file format understood by :func:`fileConfig` is based on
3500:mod:`configparser` functionality. The file must contain sections called
3501``[loggers]``, ``[handlers]`` and ``[formatters]`` which identify by name the
3502entities of each type which are defined in the file. For each such entity, there
3503is a separate section which identifies how that entity is configured. Thus, for
3504a logger named ``log01`` in the ``[loggers]`` section, the relevant
3505configuration details are held in a section ``[logger_log01]``. Similarly, a
3506handler called ``hand01`` in the ``[handlers]`` section will have its
3507configuration held in a section called ``[handler_hand01]``, while a formatter
3508called ``form01`` in the ``[formatters]`` section will have its configuration
3509specified in a section called ``[formatter_form01]``. The root logger
3510configuration must be specified in a section called ``[logger_root]``.
Georg Brandl116aa622007-08-15 14:28:22 +00003511
3512Examples of these sections in the file are given below. ::
3513
3514 [loggers]
3515 keys=root,log02,log03,log04,log05,log06,log07
3516
3517 [handlers]
3518 keys=hand01,hand02,hand03,hand04,hand05,hand06,hand07,hand08,hand09
3519
3520 [formatters]
3521 keys=form01,form02,form03,form04,form05,form06,form07,form08,form09
3522
3523The root logger must specify a level and a list of handlers. An example of a
3524root logger section is given below. ::
3525
3526 [logger_root]
3527 level=NOTSET
3528 handlers=hand01
3529
3530The ``level`` entry can be one of ``DEBUG, INFO, WARNING, ERROR, CRITICAL`` or
3531``NOTSET``. For the root logger only, ``NOTSET`` means that all messages will be
3532logged. Level values are :func:`eval`\ uated in the context of the ``logging``
3533package's namespace.
3534
3535The ``handlers`` entry is a comma-separated list of handler names, which must
3536appear in the ``[handlers]`` section. These names must appear in the
3537``[handlers]`` section and have corresponding sections in the configuration
3538file.
3539
3540For loggers other than the root logger, some additional information is required.
3541This is illustrated by the following example. ::
3542
3543 [logger_parser]
3544 level=DEBUG
3545 handlers=hand01
3546 propagate=1
3547 qualname=compiler.parser
3548
3549The ``level`` and ``handlers`` entries are interpreted as for the root logger,
3550except that if a non-root logger's level is specified as ``NOTSET``, the system
3551consults loggers higher up the hierarchy to determine the effective level of the
3552logger. The ``propagate`` entry is set to 1 to indicate that messages must
3553propagate to handlers higher up the logger hierarchy from this logger, or 0 to
3554indicate that messages are **not** propagated to handlers up the hierarchy. The
3555``qualname`` entry is the hierarchical channel name of the logger, that is to
3556say the name used by the application to get the logger.
3557
3558Sections which specify handler configuration are exemplified by the following.
3559::
3560
3561 [handler_hand01]
3562 class=StreamHandler
3563 level=NOTSET
3564 formatter=form01
3565 args=(sys.stdout,)
3566
3567The ``class`` entry indicates the handler's class (as determined by :func:`eval`
3568in the ``logging`` package's namespace). The ``level`` is interpreted as for
3569loggers, and ``NOTSET`` is taken to mean "log everything".
3570
3571The ``formatter`` entry indicates the key name of the formatter for this
3572handler. If blank, a default formatter (``logging._defaultFormatter``) is used.
3573If a name is specified, it must appear in the ``[formatters]`` section and have
3574a corresponding section in the configuration file.
3575
3576The ``args`` entry, when :func:`eval`\ uated in the context of the ``logging``
3577package's namespace, is the list of arguments to the constructor for the handler
3578class. Refer to the constructors for the relevant handlers, or to the examples
3579below, to see how typical entries are constructed. ::
3580
3581 [handler_hand02]
3582 class=FileHandler
3583 level=DEBUG
3584 formatter=form02
3585 args=('python.log', 'w')
3586
3587 [handler_hand03]
3588 class=handlers.SocketHandler
3589 level=INFO
3590 formatter=form03
3591 args=('localhost', handlers.DEFAULT_TCP_LOGGING_PORT)
3592
3593 [handler_hand04]
3594 class=handlers.DatagramHandler
3595 level=WARN
3596 formatter=form04
3597 args=('localhost', handlers.DEFAULT_UDP_LOGGING_PORT)
3598
3599 [handler_hand05]
3600 class=handlers.SysLogHandler
3601 level=ERROR
3602 formatter=form05
3603 args=(('localhost', handlers.SYSLOG_UDP_PORT), handlers.SysLogHandler.LOG_USER)
3604
3605 [handler_hand06]
3606 class=handlers.NTEventLogHandler
3607 level=CRITICAL
3608 formatter=form06
3609 args=('Python Application', '', 'Application')
3610
3611 [handler_hand07]
3612 class=handlers.SMTPHandler
3613 level=WARN
3614 formatter=form07
3615 args=('localhost', 'from@abc', ['user1@abc', 'user2@xyz'], 'Logger Subject')
3616
3617 [handler_hand08]
3618 class=handlers.MemoryHandler
3619 level=NOTSET
3620 formatter=form08
3621 target=
3622 args=(10, ERROR)
3623
3624 [handler_hand09]
3625 class=handlers.HTTPHandler
3626 level=NOTSET
3627 formatter=form09
3628 args=('localhost:9022', '/log', 'GET')
3629
3630Sections which specify formatter configuration are typified by the following. ::
3631
3632 [formatter_form01]
3633 format=F1 %(asctime)s %(levelname)s %(message)s
3634 datefmt=
3635 class=logging.Formatter
3636
3637The ``format`` entry is the overall format string, and the ``datefmt`` entry is
Christian Heimes5b5e81c2007-12-31 16:14:33 +00003638the :func:`strftime`\ -compatible date/time format string. If empty, the
3639package substitutes ISO8601 format date/times, which is almost equivalent to
3640specifying the date format string ``"%Y-%m-%d %H:%M:%S"``. The ISO8601 format
3641also specifies milliseconds, which are appended to the result of using the above
3642format string, with a comma separator. An example time in ISO8601 format is
3643``2003-01-23 00:29:50,411``.
Georg Brandl116aa622007-08-15 14:28:22 +00003644
3645The ``class`` entry is optional. It indicates the name of the formatter's class
3646(as a dotted module and class name.) This option is useful for instantiating a
3647:class:`Formatter` subclass. Subclasses of :class:`Formatter` can present
3648exception tracebacks in an expanded or condensed format.
3649
Christian Heimes8b0facf2007-12-04 19:30:01 +00003650
3651Configuration server example
3652^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3653
3654Here is an example of a module using the logging configuration server::
3655
3656 import logging
3657 import logging.config
3658 import time
3659 import os
3660
3661 # read initial config file
3662 logging.config.fileConfig("logging.conf")
3663
3664 # create and start listener on port 9999
3665 t = logging.config.listen(9999)
3666 t.start()
3667
3668 logger = logging.getLogger("simpleExample")
3669
3670 try:
3671 # loop through logging calls to see the difference
3672 # new configurations make, until Ctrl+C is pressed
3673 while True:
3674 logger.debug("debug message")
3675 logger.info("info message")
3676 logger.warn("warn message")
3677 logger.error("error message")
3678 logger.critical("critical message")
3679 time.sleep(5)
3680 except KeyboardInterrupt:
3681 # cleanup
3682 logging.config.stopListening()
3683 t.join()
3684
3685And here is a script that takes a filename and sends that file to the server,
3686properly preceded with the binary-encoded length, as the new logging
3687configuration::
3688
3689 #!/usr/bin/env python
3690 import socket, sys, struct
3691
3692 data_to_send = open(sys.argv[1], "r").read()
3693
3694 HOST = 'localhost'
3695 PORT = 9999
3696 s = socket.socket(socket.AF_INET, socket.SOCK_STREAM)
Georg Brandlf6945182008-02-01 11:56:49 +00003697 print("connecting...")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003698 s.connect((HOST, PORT))
Georg Brandlf6945182008-02-01 11:56:49 +00003699 print("sending config...")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003700 s.send(struct.pack(">L", len(data_to_send)))
3701 s.send(data_to_send)
3702 s.close()
Georg Brandlf6945182008-02-01 11:56:49 +00003703 print("complete")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003704
3705
3706More examples
3707-------------
3708
3709Multiple handlers and formatters
3710^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3711
3712Loggers are plain Python objects. The :func:`addHandler` method has no minimum
3713or maximum quota for the number of handlers you may add. Sometimes it will be
3714beneficial for an application to log all messages of all severities to a text
3715file while simultaneously logging errors or above to the console. To set this
3716up, simply configure the appropriate handlers. The logging calls in the
3717application code will remain unchanged. Here is a slight modification to the
3718previous simple module-based configuration example::
3719
3720 import logging
3721
3722 logger = logging.getLogger("simple_example")
3723 logger.setLevel(logging.DEBUG)
3724 # create file handler which logs even debug messages
3725 fh = logging.FileHandler("spam.log")
3726 fh.setLevel(logging.DEBUG)
3727 # create console handler with a higher log level
3728 ch = logging.StreamHandler()
3729 ch.setLevel(logging.ERROR)
3730 # create formatter and add it to the handlers
3731 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3732 ch.setFormatter(formatter)
3733 fh.setFormatter(formatter)
3734 # add the handlers to logger
3735 logger.addHandler(ch)
3736 logger.addHandler(fh)
3737
3738 # "application" code
3739 logger.debug("debug message")
3740 logger.info("info message")
3741 logger.warn("warn message")
3742 logger.error("error message")
3743 logger.critical("critical message")
3744
3745Notice that the "application" code does not care about multiple handlers. All
3746that changed was the addition and configuration of a new handler named *fh*.
3747
3748The ability to create new handlers with higher- or lower-severity filters can be
3749very helpful when writing and testing an application. Instead of using many
3750``print`` statements for debugging, use ``logger.debug``: Unlike the print
3751statements, which you will have to delete or comment out later, the logger.debug
3752statements can remain intact in the source code and remain dormant until you
3753need them again. At that time, the only change that needs to happen is to
3754modify the severity level of the logger and/or handler to debug.
3755
3756
3757Using logging in multiple modules
3758^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3759
3760It was mentioned above that multiple calls to
3761``logging.getLogger('someLogger')`` return a reference to the same logger
3762object. This is true not only within the same module, but also across modules
3763as long as it is in the same Python interpreter process. It is true for
3764references to the same object; additionally, application code can define and
3765configure a parent logger in one module and create (but not configure) a child
3766logger in a separate module, and all logger calls to the child will pass up to
3767the parent. Here is a main module::
3768
3769 import logging
3770 import auxiliary_module
3771
3772 # create logger with "spam_application"
3773 logger = logging.getLogger("spam_application")
3774 logger.setLevel(logging.DEBUG)
3775 # create file handler which logs even debug messages
3776 fh = logging.FileHandler("spam.log")
3777 fh.setLevel(logging.DEBUG)
3778 # create console handler with a higher log level
3779 ch = logging.StreamHandler()
3780 ch.setLevel(logging.ERROR)
3781 # create formatter and add it to the handlers
3782 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3783 fh.setFormatter(formatter)
3784 ch.setFormatter(formatter)
3785 # add the handlers to the logger
3786 logger.addHandler(fh)
3787 logger.addHandler(ch)
3788
3789 logger.info("creating an instance of auxiliary_module.Auxiliary")
3790 a = auxiliary_module.Auxiliary()
3791 logger.info("created an instance of auxiliary_module.Auxiliary")
3792 logger.info("calling auxiliary_module.Auxiliary.do_something")
3793 a.do_something()
3794 logger.info("finished auxiliary_module.Auxiliary.do_something")
3795 logger.info("calling auxiliary_module.some_function()")
3796 auxiliary_module.some_function()
3797 logger.info("done with auxiliary_module.some_function()")
3798
3799Here is the auxiliary module::
3800
3801 import logging
3802
3803 # create logger
3804 module_logger = logging.getLogger("spam_application.auxiliary")
3805
3806 class Auxiliary:
3807 def __init__(self):
3808 self.logger = logging.getLogger("spam_application.auxiliary.Auxiliary")
3809 self.logger.info("creating an instance of Auxiliary")
3810 def do_something(self):
3811 self.logger.info("doing something")
3812 a = 1 + 1
3813 self.logger.info("done doing something")
3814
3815 def some_function():
3816 module_logger.info("received a call to \"some_function\"")
3817
3818The output looks like this::
3819
Christian Heimes043d6f62008-01-07 17:19:16 +00003820 2005-03-23 23:47:11,663 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003821 creating an instance of auxiliary_module.Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00003822 2005-03-23 23:47:11,665 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003823 creating an instance of Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00003824 2005-03-23 23:47:11,665 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003825 created an instance of auxiliary_module.Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00003826 2005-03-23 23:47:11,668 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003827 calling auxiliary_module.Auxiliary.do_something
Christian Heimes043d6f62008-01-07 17:19:16 +00003828 2005-03-23 23:47:11,668 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003829 doing something
Christian Heimes043d6f62008-01-07 17:19:16 +00003830 2005-03-23 23:47:11,669 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003831 done doing something
Christian Heimes043d6f62008-01-07 17:19:16 +00003832 2005-03-23 23:47:11,670 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003833 finished auxiliary_module.Auxiliary.do_something
Christian Heimes043d6f62008-01-07 17:19:16 +00003834 2005-03-23 23:47:11,671 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003835 calling auxiliary_module.some_function()
Christian Heimes043d6f62008-01-07 17:19:16 +00003836 2005-03-23 23:47:11,672 - spam_application.auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003837 received a call to "some_function"
Christian Heimes043d6f62008-01-07 17:19:16 +00003838 2005-03-23 23:47:11,673 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003839 done with auxiliary_module.some_function()
3840