blob: 5d62d508f3abe38872d4060ffb30b398f2d773a4 [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
Vinay Sajip83eadd12010-09-20 10:31:18 +00001132.. method:: Logger.hasHandlers()
1133
1134 Checks to see if this logger has any handlers configured. This is done by
1135 looking for handlers in this logger and its parents in the logger hierarchy.
1136 Returns True if a handler was found, else False. The method stops searching
1137 up the hierarchy whenever a logger with the "propagate" attribute set to
1138 False is found - that will be the last logger which is checked for the
1139 existence of handlers.
1140
1141.. versionadded:: 3.2
1142
1143The :meth:`hasHandlers` method was not present in previous versions.
Georg Brandl116aa622007-08-15 14:28:22 +00001144
1145.. _minimal-example:
1146
1147Basic example
1148-------------
1149
Georg Brandl116aa622007-08-15 14:28:22 +00001150The :mod:`logging` package provides a lot of flexibility, and its configuration
1151can appear daunting. This section demonstrates that simple use of the logging
1152package is possible.
1153
1154The simplest example shows logging to the console::
1155
1156 import logging
1157
1158 logging.debug('A debug message')
1159 logging.info('Some information')
1160 logging.warning('A shot across the bows')
1161
1162If you run the above script, you'll see this::
1163
1164 WARNING:root:A shot across the bows
1165
1166Because no particular logger was specified, the system used the root logger. The
1167debug and info messages didn't appear because by default, the root logger is
1168configured to only handle messages with a severity of WARNING or above. The
1169message format is also a configuration default, as is the output destination of
1170the messages - ``sys.stderr``. The severity level, the message format and
1171destination can be easily changed, as shown in the example below::
1172
1173 import logging
1174
1175 logging.basicConfig(level=logging.DEBUG,
1176 format='%(asctime)s %(levelname)s %(message)s',
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00001177 filename='myapp.log',
Georg Brandl116aa622007-08-15 14:28:22 +00001178 filemode='w')
1179 logging.debug('A debug message')
1180 logging.info('Some information')
1181 logging.warning('A shot across the bows')
1182
1183The :meth:`basicConfig` method is used to change the configuration defaults,
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00001184which results in output (written to ``myapp.log``) which should look
Georg Brandl116aa622007-08-15 14:28:22 +00001185something like the following::
1186
1187 2004-07-02 13:00:08,743 DEBUG A debug message
1188 2004-07-02 13:00:08,743 INFO Some information
1189 2004-07-02 13:00:08,743 WARNING A shot across the bows
1190
1191This time, all messages with a severity of DEBUG or above were handled, and the
1192format of the messages was also changed, and output went to the specified file
1193rather than the console.
1194
Georg Brandl81ac1ce2007-08-31 17:17:17 +00001195.. XXX logging should probably be updated for new string formatting!
Georg Brandl4b491312007-08-31 09:22:56 +00001196
1197Formatting uses the old Python string formatting - see section
1198:ref:`old-string-formatting`. The format string takes the following common
Georg Brandl116aa622007-08-15 14:28:22 +00001199specifiers. For a complete list of specifiers, consult the :class:`Formatter`
1200documentation.
1201
1202+-------------------+-----------------------------------------------+
1203| Format | Description |
1204+===================+===============================================+
1205| ``%(name)s`` | Name of the logger (logging channel). |
1206+-------------------+-----------------------------------------------+
1207| ``%(levelname)s`` | Text logging level for the message |
1208| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
1209| | ``'ERROR'``, ``'CRITICAL'``). |
1210+-------------------+-----------------------------------------------+
1211| ``%(asctime)s`` | Human-readable time when the |
1212| | :class:`LogRecord` was created. By default |
1213| | this is of the form "2003-07-08 16:49:45,896" |
1214| | (the numbers after the comma are millisecond |
1215| | portion of the time). |
1216+-------------------+-----------------------------------------------+
1217| ``%(message)s`` | The logged message. |
1218+-------------------+-----------------------------------------------+
1219
1220To change the date/time format, you can pass an additional keyword parameter,
1221*datefmt*, as in the following::
1222
1223 import logging
1224
1225 logging.basicConfig(level=logging.DEBUG,
1226 format='%(asctime)s %(levelname)-8s %(message)s',
1227 datefmt='%a, %d %b %Y %H:%M:%S',
1228 filename='/temp/myapp.log',
1229 filemode='w')
1230 logging.debug('A debug message')
1231 logging.info('Some information')
1232 logging.warning('A shot across the bows')
1233
1234which would result in output like ::
1235
1236 Fri, 02 Jul 2004 13:06:18 DEBUG A debug message
1237 Fri, 02 Jul 2004 13:06:18 INFO Some information
1238 Fri, 02 Jul 2004 13:06:18 WARNING A shot across the bows
1239
1240The date format string follows the requirements of :func:`strftime` - see the
1241documentation for the :mod:`time` module.
1242
1243If, instead of sending logging output to the console or a file, you'd rather use
1244a file-like object which you have created separately, you can pass it to
1245:func:`basicConfig` using the *stream* keyword argument. Note that if both
1246*stream* and *filename* keyword arguments are passed, the *stream* argument is
1247ignored.
1248
1249Of course, you can put variable information in your output. To do this, simply
1250have the message be a format string and pass in additional arguments containing
1251the variable information, as in the following example::
1252
1253 import logging
1254
1255 logging.basicConfig(level=logging.DEBUG,
1256 format='%(asctime)s %(levelname)-8s %(message)s',
1257 datefmt='%a, %d %b %Y %H:%M:%S',
1258 filename='/temp/myapp.log',
1259 filemode='w')
1260 logging.error('Pack my box with %d dozen %s', 5, 'liquor jugs')
1261
1262which would result in ::
1263
1264 Wed, 21 Jul 2004 15:35:16 ERROR Pack my box with 5 dozen liquor jugs
1265
1266
1267.. _multiple-destinations:
1268
1269Logging to multiple destinations
1270--------------------------------
1271
1272Let's say you want to log to console and file with different message formats and
1273in differing circumstances. Say you want to log messages with levels of DEBUG
1274and higher to file, and those messages at level INFO and higher to the console.
1275Let's also assume that the file should contain timestamps, but the console
1276messages should not. Here's how you can achieve this::
1277
1278 import logging
1279
1280 # set up logging to file - see previous section for more details
1281 logging.basicConfig(level=logging.DEBUG,
1282 format='%(asctime)s %(name)-12s %(levelname)-8s %(message)s',
1283 datefmt='%m-%d %H:%M',
1284 filename='/temp/myapp.log',
1285 filemode='w')
1286 # define a Handler which writes INFO messages or higher to the sys.stderr
1287 console = logging.StreamHandler()
1288 console.setLevel(logging.INFO)
1289 # set a format which is simpler for console use
1290 formatter = logging.Formatter('%(name)-12s: %(levelname)-8s %(message)s')
1291 # tell the handler to use this format
1292 console.setFormatter(formatter)
1293 # add the handler to the root logger
1294 logging.getLogger('').addHandler(console)
1295
1296 # Now, we can log to the root logger, or any other logger. First the root...
1297 logging.info('Jackdaws love my big sphinx of quartz.')
1298
1299 # Now, define a couple of other loggers which might represent areas in your
1300 # application:
1301
1302 logger1 = logging.getLogger('myapp.area1')
1303 logger2 = logging.getLogger('myapp.area2')
1304
1305 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1306 logger1.info('How quickly daft jumping zebras vex.')
1307 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1308 logger2.error('The five boxing wizards jump quickly.')
1309
1310When you run this, on the console you will see ::
1311
1312 root : INFO Jackdaws love my big sphinx of quartz.
1313 myapp.area1 : INFO How quickly daft jumping zebras vex.
1314 myapp.area2 : WARNING Jail zesty vixen who grabbed pay from quack.
1315 myapp.area2 : ERROR The five boxing wizards jump quickly.
1316
1317and in the file you will see something like ::
1318
1319 10-22 22:19 root INFO Jackdaws love my big sphinx of quartz.
1320 10-22 22:19 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1321 10-22 22:19 myapp.area1 INFO How quickly daft jumping zebras vex.
1322 10-22 22:19 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1323 10-22 22:19 myapp.area2 ERROR The five boxing wizards jump quickly.
1324
1325As you can see, the DEBUG message only shows up in the file. The other messages
1326are sent to both destinations.
1327
1328This example uses console and file handlers, but you can use any number and
1329combination of handlers you choose.
1330
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001331.. _logging-exceptions:
1332
1333Exceptions raised during logging
1334--------------------------------
1335
1336The logging package is designed to swallow exceptions which occur while logging
1337in production. This is so that errors which occur while handling logging events
1338- such as logging misconfiguration, network or other similar errors - do not
1339cause the application using logging to terminate prematurely.
1340
1341:class:`SystemExit` and :class:`KeyboardInterrupt` exceptions are never
1342swallowed. Other exceptions which occur during the :meth:`emit` method of a
1343:class:`Handler` subclass are passed to its :meth:`handleError` method.
1344
1345The default implementation of :meth:`handleError` in :class:`Handler` checks
Georg Brandlef871f62010-03-12 10:06:40 +00001346to see if a module-level variable, :data:`raiseExceptions`, is set. If set, a
1347traceback is printed to :data:`sys.stderr`. If not set, the exception is swallowed.
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001348
Georg Brandlef871f62010-03-12 10:06:40 +00001349**Note:** The default value of :data:`raiseExceptions` is ``True``. This is because
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001350during development, you typically want to be notified of any exceptions that
Georg Brandlef871f62010-03-12 10:06:40 +00001351occur. It's advised that you set :data:`raiseExceptions` to ``False`` for production
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001352usage.
Georg Brandl116aa622007-08-15 14:28:22 +00001353
Christian Heimes790c8232008-01-07 21:14:23 +00001354.. _context-info:
1355
1356Adding contextual information to your logging output
1357----------------------------------------------------
1358
1359Sometimes you want logging output to contain contextual information in
1360addition to the parameters passed to the logging call. For example, in a
1361networked application, it may be desirable to log client-specific information
1362in the log (e.g. remote client's username, or IP address). Although you could
1363use the *extra* parameter to achieve this, it's not always convenient to pass
1364the information in this way. While it might be tempting to create
1365:class:`Logger` instances on a per-connection basis, this is not a good idea
1366because these instances are not garbage collected. While this is not a problem
1367in practice, when the number of :class:`Logger` instances is dependent on the
1368level of granularity you want to use in logging an application, it could
1369be hard to manage if the number of :class:`Logger` instances becomes
1370effectively unbounded.
1371
Vinay Sajipc31be632010-09-06 22:18:20 +00001372
1373Using LoggerAdapters to impart contextual information
1374^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1375
Christian Heimes04c420f2008-01-18 18:40:46 +00001376An easy way in which you can pass contextual information to be output along
1377with logging event information is to use the :class:`LoggerAdapter` class.
1378This class is designed to look like a :class:`Logger`, so that you can call
1379:meth:`debug`, :meth:`info`, :meth:`warning`, :meth:`error`,
1380:meth:`exception`, :meth:`critical` and :meth:`log`. These methods have the
1381same signatures as their counterparts in :class:`Logger`, so you can use the
1382two types of instances interchangeably.
Christian Heimes790c8232008-01-07 21:14:23 +00001383
Christian Heimes04c420f2008-01-18 18:40:46 +00001384When you create an instance of :class:`LoggerAdapter`, you pass it a
1385:class:`Logger` instance and a dict-like object which contains your contextual
1386information. When you call one of the logging methods on an instance of
1387:class:`LoggerAdapter`, it delegates the call to the underlying instance of
1388:class:`Logger` passed to its constructor, and arranges to pass the contextual
1389information in the delegated call. Here's a snippet from the code of
1390:class:`LoggerAdapter`::
Christian Heimes790c8232008-01-07 21:14:23 +00001391
Christian Heimes04c420f2008-01-18 18:40:46 +00001392 def debug(self, msg, *args, **kwargs):
1393 """
1394 Delegate a debug call to the underlying logger, after adding
1395 contextual information from this adapter instance.
1396 """
1397 msg, kwargs = self.process(msg, kwargs)
1398 self.logger.debug(msg, *args, **kwargs)
Christian Heimes790c8232008-01-07 21:14:23 +00001399
Christian Heimes04c420f2008-01-18 18:40:46 +00001400The :meth:`process` method of :class:`LoggerAdapter` is where the contextual
1401information is added to the logging output. It's passed the message and
1402keyword arguments of the logging call, and it passes back (potentially)
1403modified versions of these to use in the call to the underlying logger. The
1404default implementation of this method leaves the message alone, but inserts
1405an "extra" key in the keyword argument whose value is the dict-like object
1406passed to the constructor. Of course, if you had passed an "extra" keyword
1407argument in the call to the adapter, it will be silently overwritten.
Christian Heimes790c8232008-01-07 21:14:23 +00001408
Christian Heimes04c420f2008-01-18 18:40:46 +00001409The advantage of using "extra" is that the values in the dict-like object are
1410merged into the :class:`LogRecord` instance's __dict__, allowing you to use
1411customized strings with your :class:`Formatter` instances which know about
1412the keys of the dict-like object. If you need a different method, e.g. if you
1413want to prepend or append the contextual information to the message string,
1414you just need to subclass :class:`LoggerAdapter` and override :meth:`process`
1415to do what you need. Here's an example script which uses this class, which
1416also illustrates what dict-like behaviour is needed from an arbitrary
1417"dict-like" object for use in the constructor::
1418
Christian Heimes587c2bf2008-01-19 16:21:02 +00001419 import logging
Georg Brandl86def6c2008-01-21 20:36:10 +00001420
Christian Heimes587c2bf2008-01-19 16:21:02 +00001421 class ConnInfo:
1422 """
1423 An example class which shows how an arbitrary class can be used as
1424 the 'extra' context information repository passed to a LoggerAdapter.
1425 """
Georg Brandl86def6c2008-01-21 20:36:10 +00001426
Christian Heimes587c2bf2008-01-19 16:21:02 +00001427 def __getitem__(self, name):
1428 """
1429 To allow this instance to look like a dict.
1430 """
1431 from random import choice
1432 if name == "ip":
1433 result = choice(["127.0.0.1", "192.168.0.1"])
1434 elif name == "user":
1435 result = choice(["jim", "fred", "sheila"])
1436 else:
1437 result = self.__dict__.get(name, "?")
1438 return result
Georg Brandl86def6c2008-01-21 20:36:10 +00001439
Christian Heimes587c2bf2008-01-19 16:21:02 +00001440 def __iter__(self):
1441 """
1442 To allow iteration over keys, which will be merged into
1443 the LogRecord dict before formatting and output.
1444 """
1445 keys = ["ip", "user"]
1446 keys.extend(self.__dict__.keys())
1447 return keys.__iter__()
Georg Brandl86def6c2008-01-21 20:36:10 +00001448
Christian Heimes587c2bf2008-01-19 16:21:02 +00001449 if __name__ == "__main__":
1450 from random import choice
1451 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1452 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1453 { "ip" : "123.231.231.123", "user" : "sheila" })
1454 logging.basicConfig(level=logging.DEBUG,
1455 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1456 a1.debug("A debug message")
1457 a1.info("An info message with %s", "some parameters")
1458 a2 = logging.LoggerAdapter(logging.getLogger("d.e.f"), ConnInfo())
1459 for x in range(10):
1460 lvl = choice(levels)
1461 lvlname = logging.getLevelName(lvl)
1462 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
Christian Heimes04c420f2008-01-18 18:40:46 +00001463
1464When this script is run, the output should look something like this::
1465
Christian Heimes587c2bf2008-01-19 16:21:02 +00001466 2008-01-18 14:49:54,023 a.b.c DEBUG IP: 123.231.231.123 User: sheila A debug message
1467 2008-01-18 14:49:54,023 a.b.c INFO IP: 123.231.231.123 User: sheila An info message with some parameters
1468 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
1469 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
1470 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
1471 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
1472 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
1473 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
1474 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
1475 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
1476 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
1477 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 +00001478
Christian Heimes790c8232008-01-07 21:14:23 +00001479
Vinay Sajipac007992010-09-17 12:45:26 +00001480.. _filters-contextual:
1481
Vinay Sajipc31be632010-09-06 22:18:20 +00001482Using Filters to impart contextual information
1483^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1484
1485You can also add contextual information to log output using a user-defined
1486:class:`Filter`. ``Filter`` instances are allowed to modify the ``LogRecords``
1487passed to them, including adding additional attributes which can then be output
1488using a suitable format string, or if needed a custom :class:`Formatter`.
1489
1490For example in a web application, the request being processed (or at least,
1491the interesting parts of it) can be stored in a threadlocal
1492(:class:`threading.local`) variable, and then accessed from a ``Filter`` to
1493add, say, information from the request - say, the remote IP address and remote
1494user's username - to the ``LogRecord``, using the attribute names 'ip' and
1495'user' as in the ``LoggerAdapter`` example above. In that case, the same format
1496string can be used to get similar output to that shown above. Here's an example
1497script::
1498
1499 import logging
1500 from random import choice
1501
1502 class ContextFilter(logging.Filter):
1503 """
1504 This is a filter which injects contextual information into the log.
1505
1506 Rather than use actual contextual information, we just use random
1507 data in this demo.
1508 """
1509
1510 USERS = ['jim', 'fred', 'sheila']
1511 IPS = ['123.231.231.123', '127.0.0.1', '192.168.0.1']
1512
1513 def filter(self, record):
1514
1515 record.ip = choice(ContextFilter.IPS)
1516 record.user = choice(ContextFilter.USERS)
1517 return True
1518
1519 if __name__ == "__main__":
1520 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1521 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1522 { "ip" : "123.231.231.123", "user" : "sheila" })
1523 logging.basicConfig(level=logging.DEBUG,
1524 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1525 a1 = logging.getLogger("a.b.c")
1526 a2 = logging.getLogger("d.e.f")
1527
1528 f = ContextFilter()
1529 a1.addFilter(f)
1530 a2.addFilter(f)
1531 a1.debug("A debug message")
1532 a1.info("An info message with %s", "some parameters")
1533 for x in range(10):
1534 lvl = choice(levels)
1535 lvlname = logging.getLevelName(lvl)
1536 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
1537
1538which, when run, produces something like::
1539
1540 2010-09-06 22:38:15,292 a.b.c DEBUG IP: 123.231.231.123 User: fred A debug message
1541 2010-09-06 22:38:15,300 a.b.c INFO IP: 192.168.0.1 User: sheila An info message with some parameters
1542 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
1543 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
1544 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
1545 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
1546 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
1547 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
1548 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
1549 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
1550 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
1551 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
1552
1553
Vinay Sajipd31f3632010-06-29 15:31:15 +00001554.. _multiple-processes:
1555
Vinay Sajipa7471bf2009-08-15 23:23:37 +00001556Logging to a single file from multiple processes
1557------------------------------------------------
1558
1559Although logging is thread-safe, and logging to a single file from multiple
1560threads in a single process *is* supported, logging to a single file from
1561*multiple processes* is *not* supported, because there is no standard way to
1562serialize access to a single file across multiple processes in Python. If you
Vinay Sajip121a1c42010-09-08 10:46:15 +00001563need to log to a single file from multiple processes, one way of doing this is
1564to have all the processes log to a :class:`SocketHandler`, and have a separate
1565process which implements a socket server which reads from the socket and logs
1566to file. (If you prefer, you can dedicate one thread in one of the existing
1567processes to perform this function.) The following section documents this
1568approach in more detail and includes a working socket receiver which can be
1569used as a starting point for you to adapt in your own applications.
Vinay Sajipa7471bf2009-08-15 23:23:37 +00001570
Vinay Sajip5a92b132009-08-15 23:35:08 +00001571If you are using a recent version of Python which includes the
Vinay Sajip121a1c42010-09-08 10:46:15 +00001572:mod:`multiprocessing` module, you could write your own handler which uses the
Vinay Sajip5a92b132009-08-15 23:35:08 +00001573:class:`Lock` class from this module to serialize access to the file from
1574your processes. The existing :class:`FileHandler` and subclasses do not make
1575use of :mod:`multiprocessing` at present, though they may do so in the future.
Vinay Sajip8c6b0a52009-08-17 13:17:47 +00001576Note that at present, the :mod:`multiprocessing` module does not provide
1577working lock functionality on all platforms (see
1578http://bugs.python.org/issue3770).
Vinay Sajip5a92b132009-08-15 23:35:08 +00001579
Vinay Sajip121a1c42010-09-08 10:46:15 +00001580.. currentmodule:: logging.handlers
1581
1582Alternatively, you can use a ``Queue`` and a :class:`QueueHandler` to send
1583all logging events to one of the processes in your multi-process application.
1584The following example script demonstrates how you can do this; in the example
1585a separate listener process listens for events sent by other processes and logs
1586them according to its own logging configuration. Although the example only
1587demonstrates one way of doing it (for example, you may want to use a listener
1588thread rather than a separate listener process - the implementation would be
1589analogous) it does allow for completely different logging configurations for
1590the listener and the other processes in your application, and can be used as
1591the basis for code meeting your own specific requirements::
1592
1593 # You'll need these imports in your own code
1594 import logging
1595 import logging.handlers
1596 import multiprocessing
1597
1598 # Next two import lines for this demo only
1599 from random import choice, random
1600 import time
1601
1602 #
1603 # Because you'll want to define the logging configurations for listener and workers, the
1604 # listener and worker process functions take a configurer parameter which is a callable
1605 # for configuring logging for that process. These functions are also passed the queue,
1606 # which they use for communication.
1607 #
1608 # In practice, you can configure the listener however you want, but note that in this
1609 # simple example, the listener does not apply level or filter logic to received records.
1610 # In practice, you would probably want to do ths logic in the worker processes, to avoid
1611 # sending events which would be filtered out between processes.
1612 #
1613 # The size of the rotated files is made small so you can see the results easily.
1614 def listener_configurer():
1615 root = logging.getLogger()
1616 h = logging.handlers.RotatingFileHandler('/tmp/mptest.log', 'a', 300, 10)
1617 f = logging.Formatter('%(asctime)s %(processName)-10s %(name)s %(levelname)-8s %(message)s')
1618 h.setFormatter(f)
1619 root.addHandler(h)
1620
1621 # This is the listener process top-level loop: wait for logging events
1622 # (LogRecords)on the queue and handle them, quit when you get a None for a
1623 # LogRecord.
1624 def listener_process(queue, configurer):
1625 configurer()
1626 while True:
1627 try:
1628 record = queue.get()
1629 if record is None: # We send this as a sentinel to tell the listener to quit.
1630 break
1631 logger = logging.getLogger(record.name)
1632 logger.handle(record) # No level or filter logic applied - just do it!
1633 except (KeyboardInterrupt, SystemExit):
1634 raise
1635 except:
1636 import sys, traceback
1637 print >> sys.stderr, 'Whoops! Problem:'
1638 traceback.print_exc(file=sys.stderr)
1639
1640 # Arrays used for random selections in this demo
1641
1642 LEVELS = [logging.DEBUG, logging.INFO, logging.WARNING,
1643 logging.ERROR, logging.CRITICAL]
1644
1645 LOGGERS = ['a.b.c', 'd.e.f']
1646
1647 MESSAGES = [
1648 'Random message #1',
1649 'Random message #2',
1650 'Random message #3',
1651 ]
1652
1653 # The worker configuration is done at the start of the worker process run.
1654 # Note that on Windows you can't rely on fork semantics, so each process
1655 # will run the logging configuration code when it starts.
1656 def worker_configurer(queue):
1657 h = logging.handlers.QueueHandler(queue) # Just the one handler needed
1658 root = logging.getLogger()
1659 root.addHandler(h)
1660 root.setLevel(logging.DEBUG) # send all messages, for demo; no other level or filter logic applied.
1661
1662 # This is the worker process top-level loop, which just logs ten events with
1663 # random intervening delays before terminating.
1664 # The print messages are just so you know it's doing something!
1665 def worker_process(queue, configurer):
1666 configurer(queue)
1667 name = multiprocessing.current_process().name
1668 print('Worker started: %s' % name)
1669 for i in range(10):
1670 time.sleep(random())
1671 logger = logging.getLogger(choice(LOGGERS))
1672 level = choice(LEVELS)
1673 message = choice(MESSAGES)
1674 logger.log(level, message)
1675 print('Worker finished: %s' % name)
1676
1677 # Here's where the demo gets orchestrated. Create the queue, create and start
1678 # the listener, create ten workers and start them, wait for them to finish,
1679 # then send a None to the queue to tell the listener to finish.
1680 def main():
1681 queue = multiprocessing.Queue(-1)
1682 listener = multiprocessing.Process(target=listener_process,
1683 args=(queue, listener_configurer))
1684 listener.start()
1685 workers = []
1686 for i in range(10):
1687 worker = multiprocessing.Process(target=worker_process,
1688 args=(queue, worker_configurer))
1689 workers.append(worker)
1690 worker.start()
1691 for w in workers:
1692 w.join()
1693 queue.put_nowait(None)
1694 listener.join()
1695
1696 if __name__ == '__main__':
1697 main()
1698
1699
1700.. currentmodule:: logging
1701
Benjamin Peterson8719ad52009-09-11 22:24:02 +00001702
Georg Brandl116aa622007-08-15 14:28:22 +00001703.. _network-logging:
1704
1705Sending and receiving logging events across a network
1706-----------------------------------------------------
1707
1708Let's say you want to send logging events across a network, and handle them at
1709the receiving end. A simple way of doing this is attaching a
1710:class:`SocketHandler` instance to the root logger at the sending end::
1711
1712 import logging, logging.handlers
1713
1714 rootLogger = logging.getLogger('')
1715 rootLogger.setLevel(logging.DEBUG)
1716 socketHandler = logging.handlers.SocketHandler('localhost',
1717 logging.handlers.DEFAULT_TCP_LOGGING_PORT)
1718 # don't bother with a formatter, since a socket handler sends the event as
1719 # an unformatted pickle
1720 rootLogger.addHandler(socketHandler)
1721
1722 # Now, we can log to the root logger, or any other logger. First the root...
1723 logging.info('Jackdaws love my big sphinx of quartz.')
1724
1725 # Now, define a couple of other loggers which might represent areas in your
1726 # application:
1727
1728 logger1 = logging.getLogger('myapp.area1')
1729 logger2 = logging.getLogger('myapp.area2')
1730
1731 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1732 logger1.info('How quickly daft jumping zebras vex.')
1733 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1734 logger2.error('The five boxing wizards jump quickly.')
1735
Alexandre Vassalottice261952008-05-12 02:31:37 +00001736At the receiving end, you can set up a receiver using the :mod:`socketserver`
Georg Brandl116aa622007-08-15 14:28:22 +00001737module. Here is a basic working example::
1738
Georg Brandla35f4b92009-05-31 16:41:59 +00001739 import pickle
Georg Brandl116aa622007-08-15 14:28:22 +00001740 import logging
1741 import logging.handlers
Alexandre Vassalottice261952008-05-12 02:31:37 +00001742 import socketserver
Georg Brandl116aa622007-08-15 14:28:22 +00001743 import struct
1744
1745
Alexandre Vassalottice261952008-05-12 02:31:37 +00001746 class LogRecordStreamHandler(socketserver.StreamRequestHandler):
Georg Brandl116aa622007-08-15 14:28:22 +00001747 """Handler for a streaming logging request.
1748
1749 This basically logs the record using whatever logging policy is
1750 configured locally.
1751 """
1752
1753 def handle(self):
1754 """
1755 Handle multiple requests - each expected to be a 4-byte length,
1756 followed by the LogRecord in pickle format. Logs the record
1757 according to whatever policy is configured locally.
1758 """
Collin Winter46334482007-09-10 00:49:57 +00001759 while True:
Georg Brandl116aa622007-08-15 14:28:22 +00001760 chunk = self.connection.recv(4)
1761 if len(chunk) < 4:
1762 break
1763 slen = struct.unpack(">L", chunk)[0]
1764 chunk = self.connection.recv(slen)
1765 while len(chunk) < slen:
1766 chunk = chunk + self.connection.recv(slen - len(chunk))
1767 obj = self.unPickle(chunk)
1768 record = logging.makeLogRecord(obj)
1769 self.handleLogRecord(record)
1770
1771 def unPickle(self, data):
Georg Brandla35f4b92009-05-31 16:41:59 +00001772 return pickle.loads(data)
Georg Brandl116aa622007-08-15 14:28:22 +00001773
1774 def handleLogRecord(self, record):
1775 # if a name is specified, we use the named logger rather than the one
1776 # implied by the record.
1777 if self.server.logname is not None:
1778 name = self.server.logname
1779 else:
1780 name = record.name
1781 logger = logging.getLogger(name)
1782 # N.B. EVERY record gets logged. This is because Logger.handle
1783 # is normally called AFTER logger-level filtering. If you want
1784 # to do filtering, do it at the client end to save wasting
1785 # cycles and network bandwidth!
1786 logger.handle(record)
1787
Alexandre Vassalottice261952008-05-12 02:31:37 +00001788 class LogRecordSocketReceiver(socketserver.ThreadingTCPServer):
Georg Brandl116aa622007-08-15 14:28:22 +00001789 """simple TCP socket-based logging receiver suitable for testing.
1790 """
1791
1792 allow_reuse_address = 1
1793
1794 def __init__(self, host='localhost',
1795 port=logging.handlers.DEFAULT_TCP_LOGGING_PORT,
1796 handler=LogRecordStreamHandler):
Alexandre Vassalottice261952008-05-12 02:31:37 +00001797 socketserver.ThreadingTCPServer.__init__(self, (host, port), handler)
Georg Brandl116aa622007-08-15 14:28:22 +00001798 self.abort = 0
1799 self.timeout = 1
1800 self.logname = None
1801
1802 def serve_until_stopped(self):
1803 import select
1804 abort = 0
1805 while not abort:
1806 rd, wr, ex = select.select([self.socket.fileno()],
1807 [], [],
1808 self.timeout)
1809 if rd:
1810 self.handle_request()
1811 abort = self.abort
1812
1813 def main():
1814 logging.basicConfig(
1815 format="%(relativeCreated)5d %(name)-15s %(levelname)-8s %(message)s")
1816 tcpserver = LogRecordSocketReceiver()
Georg Brandl6911e3c2007-09-04 07:15:32 +00001817 print("About to start TCP server...")
Georg Brandl116aa622007-08-15 14:28:22 +00001818 tcpserver.serve_until_stopped()
1819
1820 if __name__ == "__main__":
1821 main()
1822
1823First run the server, and then the client. On the client side, nothing is
1824printed on the console; on the server side, you should see something like::
1825
1826 About to start TCP server...
1827 59 root INFO Jackdaws love my big sphinx of quartz.
1828 59 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1829 69 myapp.area1 INFO How quickly daft jumping zebras vex.
1830 69 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1831 69 myapp.area2 ERROR The five boxing wizards jump quickly.
1832
Vinay Sajipc15dfd62010-07-06 15:08:55 +00001833Note that there are some security issues with pickle in some scenarios. If
1834these affect you, you can use an alternative serialization scheme by overriding
1835the :meth:`makePickle` method and implementing your alternative there, as
1836well as adapting the above script to use your alternative serialization.
1837
Vinay Sajip4039aff2010-09-11 10:25:28 +00001838.. _arbitrary-object-messages:
1839
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00001840Using arbitrary objects as messages
1841-----------------------------------
1842
1843In the preceding sections and examples, it has been assumed that the message
1844passed when logging the event is a string. However, this is not the only
1845possibility. You can pass an arbitrary object as a message, and its
1846:meth:`__str__` method will be called when the logging system needs to convert
1847it to a string representation. In fact, if you want to, you can avoid
1848computing a string representation altogether - for example, the
1849:class:`SocketHandler` emits an event by pickling it and sending it over the
1850wire.
1851
1852Optimization
1853------------
1854
1855Formatting of message arguments is deferred until it cannot be avoided.
1856However, computing the arguments passed to the logging method can also be
1857expensive, and you may want to avoid doing it if the logger will just throw
1858away your event. To decide what to do, you can call the :meth:`isEnabledFor`
1859method which takes a level argument and returns true if the event would be
1860created by the Logger for that level of call. You can write code like this::
1861
1862 if logger.isEnabledFor(logging.DEBUG):
1863 logger.debug("Message with %s, %s", expensive_func1(),
1864 expensive_func2())
1865
1866so that if the logger's threshold is set above ``DEBUG``, the calls to
1867:func:`expensive_func1` and :func:`expensive_func2` are never made.
1868
1869There are other optimizations which can be made for specific applications which
1870need more precise control over what logging information is collected. Here's a
1871list of things you can do to avoid processing during logging which you don't
1872need:
1873
1874+-----------------------------------------------+----------------------------------------+
1875| What you don't want to collect | How to avoid collecting it |
1876+===============================================+========================================+
1877| Information about where calls were made from. | Set ``logging._srcfile`` to ``None``. |
1878+-----------------------------------------------+----------------------------------------+
1879| Threading information. | Set ``logging.logThreads`` to ``0``. |
1880+-----------------------------------------------+----------------------------------------+
1881| Process information. | Set ``logging.logProcesses`` to ``0``. |
1882+-----------------------------------------------+----------------------------------------+
1883
1884Also note that the core logging module only includes the basic handlers. If
1885you don't import :mod:`logging.handlers` and :mod:`logging.config`, they won't
1886take up any memory.
1887
1888.. _handler:
Georg Brandl116aa622007-08-15 14:28:22 +00001889
1890Handler Objects
1891---------------
1892
1893Handlers have the following attributes and methods. Note that :class:`Handler`
1894is never instantiated directly; this class acts as a base for more useful
1895subclasses. However, the :meth:`__init__` method in subclasses needs to call
1896:meth:`Handler.__init__`.
1897
1898
1899.. method:: Handler.__init__(level=NOTSET)
1900
1901 Initializes the :class:`Handler` instance by setting its level, setting the list
1902 of filters to the empty list and creating a lock (using :meth:`createLock`) for
1903 serializing access to an I/O mechanism.
1904
1905
1906.. method:: Handler.createLock()
1907
1908 Initializes a thread lock which can be used to serialize access to underlying
1909 I/O functionality which may not be threadsafe.
1910
1911
1912.. method:: Handler.acquire()
1913
1914 Acquires the thread lock created with :meth:`createLock`.
1915
1916
1917.. method:: Handler.release()
1918
1919 Releases the thread lock acquired with :meth:`acquire`.
1920
1921
1922.. method:: Handler.setLevel(lvl)
1923
1924 Sets the threshold for this handler to *lvl*. Logging messages which are less
1925 severe than *lvl* will be ignored. When a handler is created, the level is set
1926 to :const:`NOTSET` (which causes all messages to be processed).
1927
1928
1929.. method:: Handler.setFormatter(form)
1930
1931 Sets the :class:`Formatter` for this handler to *form*.
1932
1933
1934.. method:: Handler.addFilter(filt)
1935
1936 Adds the specified filter *filt* to this handler.
1937
1938
1939.. method:: Handler.removeFilter(filt)
1940
1941 Removes the specified filter *filt* from this handler.
1942
1943
1944.. method:: Handler.filter(record)
1945
1946 Applies this handler's filters to the record and returns a true value if the
1947 record is to be processed.
1948
1949
1950.. method:: Handler.flush()
1951
1952 Ensure all logging output has been flushed. This version does nothing and is
1953 intended to be implemented by subclasses.
1954
1955
1956.. method:: Handler.close()
1957
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00001958 Tidy up any resources used by the handler. This version does no output but
1959 removes the handler from an internal list of handlers which is closed when
1960 :func:`shutdown` is called. Subclasses should ensure that this gets called
1961 from overridden :meth:`close` methods.
Georg Brandl116aa622007-08-15 14:28:22 +00001962
1963
1964.. method:: Handler.handle(record)
1965
1966 Conditionally emits the specified logging record, depending on filters which may
1967 have been added to the handler. Wraps the actual emission of the record with
1968 acquisition/release of the I/O thread lock.
1969
1970
1971.. method:: Handler.handleError(record)
1972
1973 This method should be called from handlers when an exception is encountered
1974 during an :meth:`emit` call. By default it does nothing, which means that
1975 exceptions get silently ignored. This is what is mostly wanted for a logging
1976 system - most users will not care about errors in the logging system, they are
1977 more interested in application errors. You could, however, replace this with a
1978 custom handler if you wish. The specified record is the one which was being
1979 processed when the exception occurred.
1980
1981
1982.. method:: Handler.format(record)
1983
1984 Do formatting for a record - if a formatter is set, use it. Otherwise, use the
1985 default formatter for the module.
1986
1987
1988.. method:: Handler.emit(record)
1989
1990 Do whatever it takes to actually log the specified logging record. This version
1991 is intended to be implemented by subclasses and so raises a
1992 :exc:`NotImplementedError`.
1993
1994
Vinay Sajipd31f3632010-06-29 15:31:15 +00001995.. _stream-handler:
1996
Georg Brandl116aa622007-08-15 14:28:22 +00001997StreamHandler
1998^^^^^^^^^^^^^
1999
2000The :class:`StreamHandler` class, located in the core :mod:`logging` package,
2001sends logging output to streams such as *sys.stdout*, *sys.stderr* or any
2002file-like object (or, more precisely, any object which supports :meth:`write`
2003and :meth:`flush` methods).
2004
2005
Benjamin Peterson1baf4652009-12-31 03:11:23 +00002006.. currentmodule:: logging
2007
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00002008.. class:: StreamHandler(stream=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002009
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00002010 Returns a new instance of the :class:`StreamHandler` class. If *stream* is
Georg Brandl116aa622007-08-15 14:28:22 +00002011 specified, the instance will use it for logging output; otherwise, *sys.stderr*
2012 will be used.
2013
2014
Benjamin Petersone41251e2008-04-25 01:59:09 +00002015 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002016
Benjamin Petersone41251e2008-04-25 01:59:09 +00002017 If a formatter is specified, it is used to format the record. The record
2018 is then written to the stream with a trailing newline. If exception
2019 information is present, it is formatted using
2020 :func:`traceback.print_exception` and appended to the stream.
Georg Brandl116aa622007-08-15 14:28:22 +00002021
2022
Benjamin Petersone41251e2008-04-25 01:59:09 +00002023 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002024
Benjamin Petersone41251e2008-04-25 01:59:09 +00002025 Flushes the stream by calling its :meth:`flush` method. Note that the
2026 :meth:`close` method is inherited from :class:`Handler` and so does
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002027 no output, so an explicit :meth:`flush` call may be needed at times.
Georg Brandl116aa622007-08-15 14:28:22 +00002028
2029
Vinay Sajipd31f3632010-06-29 15:31:15 +00002030.. _file-handler:
2031
Georg Brandl116aa622007-08-15 14:28:22 +00002032FileHandler
2033^^^^^^^^^^^
2034
2035The :class:`FileHandler` class, located in the core :mod:`logging` package,
2036sends logging output to a disk file. It inherits the output functionality from
2037:class:`StreamHandler`.
2038
2039
Vinay Sajipd31f3632010-06-29 15:31:15 +00002040.. class:: FileHandler(filename, mode='a', encoding=None, delay=False)
Georg Brandl116aa622007-08-15 14:28:22 +00002041
2042 Returns a new instance of the :class:`FileHandler` class. The specified file is
2043 opened and used as the stream for logging. If *mode* is not specified,
2044 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Christian Heimese7a15bb2008-01-24 16:21:45 +00002045 with that encoding. If *delay* is true, then file opening is deferred until the
2046 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002047
2048
Benjamin Petersone41251e2008-04-25 01:59:09 +00002049 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002050
Benjamin Petersone41251e2008-04-25 01:59:09 +00002051 Closes the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002052
2053
Benjamin Petersone41251e2008-04-25 01:59:09 +00002054 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002055
Benjamin Petersone41251e2008-04-25 01:59:09 +00002056 Outputs the record to the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002057
Vinay Sajipd31f3632010-06-29 15:31:15 +00002058.. _null-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002059
Vinay Sajipaa672eb2009-01-02 18:53:45 +00002060NullHandler
2061^^^^^^^^^^^
2062
2063.. versionadded:: 3.1
2064
2065The :class:`NullHandler` class, located in the core :mod:`logging` package,
2066does not do any formatting or output. It is essentially a "no-op" handler
2067for use by library developers.
2068
2069
2070.. class:: NullHandler()
2071
2072 Returns a new instance of the :class:`NullHandler` class.
2073
2074
2075 .. method:: emit(record)
2076
2077 This method does nothing.
2078
Vinay Sajip26a2d5e2009-01-10 13:37:26 +00002079See :ref:`library-config` for more information on how to use
2080:class:`NullHandler`.
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00002081
Vinay Sajipd31f3632010-06-29 15:31:15 +00002082.. _watched-file-handler:
2083
Georg Brandl116aa622007-08-15 14:28:22 +00002084WatchedFileHandler
2085^^^^^^^^^^^^^^^^^^
2086
Benjamin Peterson058e31e2009-01-16 03:54:08 +00002087.. currentmodule:: logging.handlers
Vinay Sajipaa672eb2009-01-02 18:53:45 +00002088
Georg Brandl116aa622007-08-15 14:28:22 +00002089The :class:`WatchedFileHandler` class, located in the :mod:`logging.handlers`
2090module, is a :class:`FileHandler` which watches the file it is logging to. If
2091the file changes, it is closed and reopened using the file name.
2092
2093A file change can happen because of usage of programs such as *newsyslog* and
2094*logrotate* which perform log file rotation. This handler, intended for use
2095under Unix/Linux, watches the file to see if it has changed since the last emit.
2096(A file is deemed to have changed if its device or inode have changed.) If the
2097file has changed, the old file stream is closed, and the file opened to get a
2098new stream.
2099
2100This handler is not appropriate for use under Windows, because under Windows
2101open log files cannot be moved or renamed - logging opens the files with
2102exclusive locks - and so there is no need for such a handler. Furthermore,
2103*ST_INO* is not supported under Windows; :func:`stat` always returns zero for
2104this value.
2105
2106
Christian Heimese7a15bb2008-01-24 16:21:45 +00002107.. class:: WatchedFileHandler(filename[,mode[, encoding[, delay]]])
Georg Brandl116aa622007-08-15 14:28:22 +00002108
2109 Returns a new instance of the :class:`WatchedFileHandler` class. The specified
2110 file is opened and used as the stream for logging. If *mode* is not specified,
2111 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Christian Heimese7a15bb2008-01-24 16:21:45 +00002112 with that encoding. If *delay* is true, then file opening is deferred until the
2113 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002114
2115
Benjamin Petersone41251e2008-04-25 01:59:09 +00002116 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002117
Benjamin Petersone41251e2008-04-25 01:59:09 +00002118 Outputs the record to the file, but first checks to see if the file has
2119 changed. If it has, the existing stream is flushed and closed and the
2120 file opened again, before outputting the record to the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002121
Vinay Sajipd31f3632010-06-29 15:31:15 +00002122.. _rotating-file-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002123
2124RotatingFileHandler
2125^^^^^^^^^^^^^^^^^^^
2126
2127The :class:`RotatingFileHandler` class, located in the :mod:`logging.handlers`
2128module, supports rotation of disk log files.
2129
2130
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002131.. class:: RotatingFileHandler(filename, mode='a', maxBytes=0, backupCount=0, encoding=None, delay=0)
Georg Brandl116aa622007-08-15 14:28:22 +00002132
2133 Returns a new instance of the :class:`RotatingFileHandler` class. The specified
2134 file is opened and used as the stream for logging. If *mode* is not specified,
Christian Heimese7a15bb2008-01-24 16:21:45 +00002135 ``'a'`` is used. If *encoding* is not *None*, it is used to open the file
2136 with that encoding. If *delay* is true, then file opening is deferred until the
2137 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002138
2139 You can use the *maxBytes* and *backupCount* values to allow the file to
2140 :dfn:`rollover` at a predetermined size. When the size is about to be exceeded,
2141 the file is closed and a new file is silently opened for output. Rollover occurs
2142 whenever the current log file is nearly *maxBytes* in length; if *maxBytes* is
2143 zero, rollover never occurs. If *backupCount* is non-zero, the system will save
2144 old log files by appending the extensions ".1", ".2" etc., to the filename. For
2145 example, with a *backupCount* of 5 and a base file name of :file:`app.log`, you
2146 would get :file:`app.log`, :file:`app.log.1`, :file:`app.log.2`, up to
2147 :file:`app.log.5`. The file being written to is always :file:`app.log`. When
2148 this file is filled, it is closed and renamed to :file:`app.log.1`, and if files
2149 :file:`app.log.1`, :file:`app.log.2`, etc. exist, then they are renamed to
2150 :file:`app.log.2`, :file:`app.log.3` etc. respectively.
2151
2152
Benjamin Petersone41251e2008-04-25 01:59:09 +00002153 .. method:: doRollover()
Georg Brandl116aa622007-08-15 14:28:22 +00002154
Benjamin Petersone41251e2008-04-25 01:59:09 +00002155 Does a rollover, as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002156
2157
Benjamin Petersone41251e2008-04-25 01:59:09 +00002158 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002159
Benjamin Petersone41251e2008-04-25 01:59:09 +00002160 Outputs the record to the file, catering for rollover as described
2161 previously.
Georg Brandl116aa622007-08-15 14:28:22 +00002162
Vinay Sajipd31f3632010-06-29 15:31:15 +00002163.. _timed-rotating-file-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002164
2165TimedRotatingFileHandler
2166^^^^^^^^^^^^^^^^^^^^^^^^
2167
2168The :class:`TimedRotatingFileHandler` class, located in the
2169:mod:`logging.handlers` module, supports rotation of disk log files at certain
2170timed intervals.
2171
2172
Vinay Sajipd31f3632010-06-29 15:31:15 +00002173.. class:: TimedRotatingFileHandler(filename, when='h', interval=1, backupCount=0, encoding=None, delay=False, utc=False)
Georg Brandl116aa622007-08-15 14:28:22 +00002174
2175 Returns a new instance of the :class:`TimedRotatingFileHandler` class. The
2176 specified file is opened and used as the stream for logging. On rotating it also
2177 sets the filename suffix. Rotating happens based on the product of *when* and
2178 *interval*.
2179
2180 You can use the *when* to specify the type of *interval*. The list of possible
Georg Brandl0c77a822008-06-10 16:37:50 +00002181 values is below. Note that they are not case sensitive.
Georg Brandl116aa622007-08-15 14:28:22 +00002182
Christian Heimesb558a2e2008-03-02 22:46:37 +00002183 +----------------+-----------------------+
2184 | Value | Type of interval |
2185 +================+=======================+
2186 | ``'S'`` | Seconds |
2187 +----------------+-----------------------+
2188 | ``'M'`` | Minutes |
2189 +----------------+-----------------------+
2190 | ``'H'`` | Hours |
2191 +----------------+-----------------------+
2192 | ``'D'`` | Days |
2193 +----------------+-----------------------+
2194 | ``'W'`` | Week day (0=Monday) |
2195 +----------------+-----------------------+
2196 | ``'midnight'`` | Roll over at midnight |
2197 +----------------+-----------------------+
Georg Brandl116aa622007-08-15 14:28:22 +00002198
Christian Heimesb558a2e2008-03-02 22:46:37 +00002199 The system will save old log files by appending extensions to the filename.
2200 The extensions are date-and-time based, using the strftime format
Benjamin Petersonad9d48d2008-04-02 21:49:44 +00002201 ``%Y-%m-%d_%H-%M-%S`` or a leading portion thereof, depending on the
Georg Brandl3dbca812008-07-23 16:10:53 +00002202 rollover interval.
Benjamin Peterson9451a1c2010-03-13 22:30:34 +00002203
2204 When computing the next rollover time for the first time (when the handler
2205 is created), the last modification time of an existing log file, or else
2206 the current time, is used to compute when the next rotation will occur.
2207
Georg Brandl0c77a822008-06-10 16:37:50 +00002208 If the *utc* argument is true, times in UTC will be used; otherwise
2209 local time is used.
2210
2211 If *backupCount* is nonzero, at most *backupCount* files
Benjamin Petersonad9d48d2008-04-02 21:49:44 +00002212 will be kept, and if more would be created when rollover occurs, the oldest
2213 one is deleted. The deletion logic uses the interval to determine which
2214 files to delete, so changing the interval may leave old files lying around.
Georg Brandl116aa622007-08-15 14:28:22 +00002215
Vinay Sajipd31f3632010-06-29 15:31:15 +00002216 If *delay* is true, then file opening is deferred until the first call to
2217 :meth:`emit`.
2218
Georg Brandl116aa622007-08-15 14:28:22 +00002219
Benjamin Petersone41251e2008-04-25 01:59:09 +00002220 .. method:: doRollover()
Georg Brandl116aa622007-08-15 14:28:22 +00002221
Benjamin Petersone41251e2008-04-25 01:59:09 +00002222 Does a rollover, as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002223
2224
Benjamin Petersone41251e2008-04-25 01:59:09 +00002225 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002226
Benjamin Petersone41251e2008-04-25 01:59:09 +00002227 Outputs the record to the file, catering for rollover as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002228
2229
Vinay Sajipd31f3632010-06-29 15:31:15 +00002230.. _socket-handler:
2231
Georg Brandl116aa622007-08-15 14:28:22 +00002232SocketHandler
2233^^^^^^^^^^^^^
2234
2235The :class:`SocketHandler` class, located in the :mod:`logging.handlers` module,
2236sends logging output to a network socket. The base class uses a TCP socket.
2237
2238
2239.. class:: SocketHandler(host, port)
2240
2241 Returns a new instance of the :class:`SocketHandler` class intended to
2242 communicate with a remote machine whose address is given by *host* and *port*.
2243
2244
Benjamin Petersone41251e2008-04-25 01:59:09 +00002245 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002246
Benjamin Petersone41251e2008-04-25 01:59:09 +00002247 Closes the socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002248
2249
Benjamin Petersone41251e2008-04-25 01:59:09 +00002250 .. method:: emit()
Georg Brandl116aa622007-08-15 14:28:22 +00002251
Benjamin Petersone41251e2008-04-25 01:59:09 +00002252 Pickles the record's attribute dictionary and writes it to the socket in
2253 binary format. If there is an error with the socket, silently drops the
2254 packet. If the connection was previously lost, re-establishes the
2255 connection. To unpickle the record at the receiving end into a
2256 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl116aa622007-08-15 14:28:22 +00002257
2258
Benjamin Petersone41251e2008-04-25 01:59:09 +00002259 .. method:: handleError()
Georg Brandl116aa622007-08-15 14:28:22 +00002260
Benjamin Petersone41251e2008-04-25 01:59:09 +00002261 Handles an error which has occurred during :meth:`emit`. The most likely
2262 cause is a lost connection. Closes the socket so that we can retry on the
2263 next event.
Georg Brandl116aa622007-08-15 14:28:22 +00002264
2265
Benjamin Petersone41251e2008-04-25 01:59:09 +00002266 .. method:: makeSocket()
Georg Brandl116aa622007-08-15 14:28:22 +00002267
Benjamin Petersone41251e2008-04-25 01:59:09 +00002268 This is a factory method which allows subclasses to define the precise
2269 type of socket they want. The default implementation creates a TCP socket
2270 (:const:`socket.SOCK_STREAM`).
Georg Brandl116aa622007-08-15 14:28:22 +00002271
2272
Benjamin Petersone41251e2008-04-25 01:59:09 +00002273 .. method:: makePickle(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002274
Benjamin Petersone41251e2008-04-25 01:59:09 +00002275 Pickles the record's attribute dictionary in binary format with a length
2276 prefix, and returns it ready for transmission across the socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002277
Vinay Sajipd31f3632010-06-29 15:31:15 +00002278 Note that pickles aren't completely secure. If you are concerned about
2279 security, you may want to override this method to implement a more secure
2280 mechanism. For example, you can sign pickles using HMAC and then verify
2281 them on the receiving end, or alternatively you can disable unpickling of
2282 global objects on the receiving end.
Georg Brandl116aa622007-08-15 14:28:22 +00002283
Benjamin Petersone41251e2008-04-25 01:59:09 +00002284 .. method:: send(packet)
Georg Brandl116aa622007-08-15 14:28:22 +00002285
Benjamin Petersone41251e2008-04-25 01:59:09 +00002286 Send a pickled string *packet* to the socket. This function allows for
2287 partial sends which can happen when the network is busy.
Georg Brandl116aa622007-08-15 14:28:22 +00002288
2289
Vinay Sajipd31f3632010-06-29 15:31:15 +00002290.. _datagram-handler:
2291
Georg Brandl116aa622007-08-15 14:28:22 +00002292DatagramHandler
2293^^^^^^^^^^^^^^^
2294
2295The :class:`DatagramHandler` class, located in the :mod:`logging.handlers`
2296module, inherits from :class:`SocketHandler` to support sending logging messages
2297over UDP sockets.
2298
2299
2300.. class:: DatagramHandler(host, port)
2301
2302 Returns a new instance of the :class:`DatagramHandler` class intended to
2303 communicate with a remote machine whose address is given by *host* and *port*.
2304
2305
Benjamin Petersone41251e2008-04-25 01:59:09 +00002306 .. method:: emit()
Georg Brandl116aa622007-08-15 14:28:22 +00002307
Benjamin Petersone41251e2008-04-25 01:59:09 +00002308 Pickles the record's attribute dictionary and writes it to the socket in
2309 binary format. If there is an error with the socket, silently drops the
2310 packet. To unpickle the record at the receiving end into a
2311 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl116aa622007-08-15 14:28:22 +00002312
2313
Benjamin Petersone41251e2008-04-25 01:59:09 +00002314 .. method:: makeSocket()
Georg Brandl116aa622007-08-15 14:28:22 +00002315
Benjamin Petersone41251e2008-04-25 01:59:09 +00002316 The factory method of :class:`SocketHandler` is here overridden to create
2317 a UDP socket (:const:`socket.SOCK_DGRAM`).
Georg Brandl116aa622007-08-15 14:28:22 +00002318
2319
Benjamin Petersone41251e2008-04-25 01:59:09 +00002320 .. method:: send(s)
Georg Brandl116aa622007-08-15 14:28:22 +00002321
Benjamin Petersone41251e2008-04-25 01:59:09 +00002322 Send a pickled string to a socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002323
2324
Vinay Sajipd31f3632010-06-29 15:31:15 +00002325.. _syslog-handler:
2326
Georg Brandl116aa622007-08-15 14:28:22 +00002327SysLogHandler
2328^^^^^^^^^^^^^
2329
2330The :class:`SysLogHandler` class, located in the :mod:`logging.handlers` module,
2331supports sending logging messages to a remote or local Unix syslog.
2332
2333
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002334.. class:: SysLogHandler(address=('localhost', SYSLOG_UDP_PORT), facility=LOG_USER, socktype=socket.SOCK_DGRAM)
Georg Brandl116aa622007-08-15 14:28:22 +00002335
2336 Returns a new instance of the :class:`SysLogHandler` class intended to
2337 communicate with a remote Unix machine whose address is given by *address* in
2338 the form of a ``(host, port)`` tuple. If *address* is not specified,
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002339 ``('localhost', 514)`` is used. The address is used to open a socket. An
Georg Brandl116aa622007-08-15 14:28:22 +00002340 alternative to providing a ``(host, port)`` tuple is providing an address as a
2341 string, for example "/dev/log". In this case, a Unix domain socket is used to
2342 send the message to the syslog. If *facility* is not specified,
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002343 :const:`LOG_USER` is used. The type of socket opened depends on the
2344 *socktype* argument, which defaults to :const:`socket.SOCK_DGRAM` and thus
2345 opens a UDP socket. To open a TCP socket (for use with the newer syslog
2346 daemons such as rsyslog), specify a value of :const:`socket.SOCK_STREAM`.
2347
2348 .. versionchanged:: 3.2
2349 *socktype* was added.
Georg Brandl116aa622007-08-15 14:28:22 +00002350
2351
Benjamin Petersone41251e2008-04-25 01:59:09 +00002352 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002353
Benjamin Petersone41251e2008-04-25 01:59:09 +00002354 Closes the socket to the remote host.
Georg Brandl116aa622007-08-15 14:28:22 +00002355
2356
Benjamin Petersone41251e2008-04-25 01:59:09 +00002357 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002358
Benjamin Petersone41251e2008-04-25 01:59:09 +00002359 The record is formatted, and then sent to the syslog server. If exception
2360 information is present, it is *not* sent to the server.
Georg Brandl116aa622007-08-15 14:28:22 +00002361
2362
Benjamin Petersone41251e2008-04-25 01:59:09 +00002363 .. method:: encodePriority(facility, priority)
Georg Brandl116aa622007-08-15 14:28:22 +00002364
Benjamin Petersone41251e2008-04-25 01:59:09 +00002365 Encodes the facility and priority into an integer. You can pass in strings
2366 or integers - if strings are passed, internal mapping dictionaries are
2367 used to convert them to integers.
Georg Brandl116aa622007-08-15 14:28:22 +00002368
Benjamin Peterson22005fc2010-04-11 16:25:06 +00002369 The symbolic ``LOG_`` values are defined in :class:`SysLogHandler` and
2370 mirror the values defined in the ``sys/syslog.h`` header file.
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002371
Georg Brandl88d7dbd2010-04-18 09:50:07 +00002372 **Priorities**
2373
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002374 +--------------------------+---------------+
2375 | Name (string) | Symbolic value|
2376 +==========================+===============+
2377 | ``alert`` | LOG_ALERT |
2378 +--------------------------+---------------+
2379 | ``crit`` or ``critical`` | LOG_CRIT |
2380 +--------------------------+---------------+
2381 | ``debug`` | LOG_DEBUG |
2382 +--------------------------+---------------+
2383 | ``emerg`` or ``panic`` | LOG_EMERG |
2384 +--------------------------+---------------+
2385 | ``err`` or ``error`` | LOG_ERR |
2386 +--------------------------+---------------+
2387 | ``info`` | LOG_INFO |
2388 +--------------------------+---------------+
2389 | ``notice`` | LOG_NOTICE |
2390 +--------------------------+---------------+
2391 | ``warn`` or ``warning`` | LOG_WARNING |
2392 +--------------------------+---------------+
2393
Georg Brandl88d7dbd2010-04-18 09:50:07 +00002394 **Facilities**
2395
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002396 +---------------+---------------+
2397 | Name (string) | Symbolic value|
2398 +===============+===============+
2399 | ``auth`` | LOG_AUTH |
2400 +---------------+---------------+
2401 | ``authpriv`` | LOG_AUTHPRIV |
2402 +---------------+---------------+
2403 | ``cron`` | LOG_CRON |
2404 +---------------+---------------+
2405 | ``daemon`` | LOG_DAEMON |
2406 +---------------+---------------+
2407 | ``ftp`` | LOG_FTP |
2408 +---------------+---------------+
2409 | ``kern`` | LOG_KERN |
2410 +---------------+---------------+
2411 | ``lpr`` | LOG_LPR |
2412 +---------------+---------------+
2413 | ``mail`` | LOG_MAIL |
2414 +---------------+---------------+
2415 | ``news`` | LOG_NEWS |
2416 +---------------+---------------+
2417 | ``syslog`` | LOG_SYSLOG |
2418 +---------------+---------------+
2419 | ``user`` | LOG_USER |
2420 +---------------+---------------+
2421 | ``uucp`` | LOG_UUCP |
2422 +---------------+---------------+
2423 | ``local0`` | LOG_LOCAL0 |
2424 +---------------+---------------+
2425 | ``local1`` | LOG_LOCAL1 |
2426 +---------------+---------------+
2427 | ``local2`` | LOG_LOCAL2 |
2428 +---------------+---------------+
2429 | ``local3`` | LOG_LOCAL3 |
2430 +---------------+---------------+
2431 | ``local4`` | LOG_LOCAL4 |
2432 +---------------+---------------+
2433 | ``local5`` | LOG_LOCAL5 |
2434 +---------------+---------------+
2435 | ``local6`` | LOG_LOCAL6 |
2436 +---------------+---------------+
2437 | ``local7`` | LOG_LOCAL7 |
2438 +---------------+---------------+
2439
2440 .. method:: mapPriority(levelname)
2441
2442 Maps a logging level name to a syslog priority name.
2443 You may need to override this if you are using custom levels, or
2444 if the default algorithm is not suitable for your needs. The
2445 default algorithm maps ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and
2446 ``CRITICAL`` to the equivalent syslog names, and all other level
2447 names to "warning".
2448
2449.. _nt-eventlog-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002450
2451NTEventLogHandler
2452^^^^^^^^^^^^^^^^^
2453
2454The :class:`NTEventLogHandler` class, located in the :mod:`logging.handlers`
2455module, supports sending logging messages to a local Windows NT, Windows 2000 or
2456Windows XP event log. Before you can use it, you need Mark Hammond's Win32
2457extensions for Python installed.
2458
2459
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002460.. class:: NTEventLogHandler(appname, dllname=None, logtype='Application')
Georg Brandl116aa622007-08-15 14:28:22 +00002461
2462 Returns a new instance of the :class:`NTEventLogHandler` class. The *appname* is
2463 used to define the application name as it appears in the event log. An
2464 appropriate registry entry is created using this name. The *dllname* should give
2465 the fully qualified pathname of a .dll or .exe which contains message
2466 definitions to hold in the log (if not specified, ``'win32service.pyd'`` is used
2467 - this is installed with the Win32 extensions and contains some basic
2468 placeholder message definitions. Note that use of these placeholders will make
2469 your event logs big, as the entire message source is held in the log. If you
2470 want slimmer logs, you have to pass in the name of your own .dll or .exe which
2471 contains the message definitions you want to use in the event log). The
2472 *logtype* is one of ``'Application'``, ``'System'`` or ``'Security'``, and
2473 defaults to ``'Application'``.
2474
2475
Benjamin Petersone41251e2008-04-25 01:59:09 +00002476 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002477
Benjamin Petersone41251e2008-04-25 01:59:09 +00002478 At this point, you can remove the application name from the registry as a
2479 source of event log entries. However, if you do this, you will not be able
2480 to see the events as you intended in the Event Log Viewer - it needs to be
2481 able to access the registry to get the .dll name. The current version does
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002482 not do this.
Georg Brandl116aa622007-08-15 14:28:22 +00002483
2484
Benjamin Petersone41251e2008-04-25 01:59:09 +00002485 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002486
Benjamin Petersone41251e2008-04-25 01:59:09 +00002487 Determines the message ID, event category and event type, and then logs
2488 the message in the NT event log.
Georg Brandl116aa622007-08-15 14:28:22 +00002489
2490
Benjamin Petersone41251e2008-04-25 01:59:09 +00002491 .. method:: getEventCategory(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002492
Benjamin Petersone41251e2008-04-25 01:59:09 +00002493 Returns the event category for the record. Override this if you want to
2494 specify your own categories. This version returns 0.
Georg Brandl116aa622007-08-15 14:28:22 +00002495
2496
Benjamin Petersone41251e2008-04-25 01:59:09 +00002497 .. method:: getEventType(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002498
Benjamin Petersone41251e2008-04-25 01:59:09 +00002499 Returns the event type for the record. Override this if you want to
2500 specify your own types. This version does a mapping using the handler's
2501 typemap attribute, which is set up in :meth:`__init__` to a dictionary
2502 which contains mappings for :const:`DEBUG`, :const:`INFO`,
2503 :const:`WARNING`, :const:`ERROR` and :const:`CRITICAL`. If you are using
2504 your own levels, you will either need to override this method or place a
2505 suitable dictionary in the handler's *typemap* attribute.
Georg Brandl116aa622007-08-15 14:28:22 +00002506
2507
Benjamin Petersone41251e2008-04-25 01:59:09 +00002508 .. method:: getMessageID(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002509
Benjamin Petersone41251e2008-04-25 01:59:09 +00002510 Returns the message ID for the record. If you are using your own messages,
2511 you could do this by having the *msg* passed to the logger being an ID
2512 rather than a format string. Then, in here, you could use a dictionary
2513 lookup to get the message ID. This version returns 1, which is the base
2514 message ID in :file:`win32service.pyd`.
Georg Brandl116aa622007-08-15 14:28:22 +00002515
Vinay Sajipd31f3632010-06-29 15:31:15 +00002516.. _smtp-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002517
2518SMTPHandler
2519^^^^^^^^^^^
2520
2521The :class:`SMTPHandler` class, located in the :mod:`logging.handlers` module,
2522supports sending logging messages to an email address via SMTP.
2523
2524
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002525.. class:: SMTPHandler(mailhost, fromaddr, toaddrs, subject, credentials=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002526
2527 Returns a new instance of the :class:`SMTPHandler` class. The instance is
2528 initialized with the from and to addresses and subject line of the email. The
2529 *toaddrs* should be a list of strings. To specify a non-standard SMTP port, use
2530 the (host, port) tuple format for the *mailhost* argument. If you use a string,
2531 the standard SMTP port is used. If your SMTP server requires authentication, you
2532 can specify a (username, password) tuple for the *credentials* argument.
2533
Georg Brandl116aa622007-08-15 14:28:22 +00002534
Benjamin Petersone41251e2008-04-25 01:59:09 +00002535 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002536
Benjamin Petersone41251e2008-04-25 01:59:09 +00002537 Formats the record and sends it to the specified addressees.
Georg Brandl116aa622007-08-15 14:28:22 +00002538
2539
Benjamin Petersone41251e2008-04-25 01:59:09 +00002540 .. method:: getSubject(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002541
Benjamin Petersone41251e2008-04-25 01:59:09 +00002542 If you want to specify a subject line which is record-dependent, override
2543 this method.
Georg Brandl116aa622007-08-15 14:28:22 +00002544
Vinay Sajipd31f3632010-06-29 15:31:15 +00002545.. _memory-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002546
2547MemoryHandler
2548^^^^^^^^^^^^^
2549
2550The :class:`MemoryHandler` class, located in the :mod:`logging.handlers` module,
2551supports buffering of logging records in memory, periodically flushing them to a
2552:dfn:`target` handler. Flushing occurs whenever the buffer is full, or when an
2553event of a certain severity or greater is seen.
2554
2555:class:`MemoryHandler` is a subclass of the more general
2556:class:`BufferingHandler`, which is an abstract class. This buffers logging
2557records in memory. Whenever each record is added to the buffer, a check is made
2558by calling :meth:`shouldFlush` to see if the buffer should be flushed. If it
2559should, then :meth:`flush` is expected to do the needful.
2560
2561
2562.. class:: BufferingHandler(capacity)
2563
2564 Initializes the handler with a buffer of the specified capacity.
2565
2566
Benjamin Petersone41251e2008-04-25 01:59:09 +00002567 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002568
Benjamin Petersone41251e2008-04-25 01:59:09 +00002569 Appends the record to the buffer. If :meth:`shouldFlush` returns true,
2570 calls :meth:`flush` to process the buffer.
Georg Brandl116aa622007-08-15 14:28:22 +00002571
2572
Benjamin Petersone41251e2008-04-25 01:59:09 +00002573 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002574
Benjamin Petersone41251e2008-04-25 01:59:09 +00002575 You can override this to implement custom flushing behavior. This version
2576 just zaps the buffer to empty.
Georg Brandl116aa622007-08-15 14:28:22 +00002577
2578
Benjamin Petersone41251e2008-04-25 01:59:09 +00002579 .. method:: shouldFlush(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002580
Benjamin Petersone41251e2008-04-25 01:59:09 +00002581 Returns true if the buffer is up to capacity. This method can be
2582 overridden to implement custom flushing strategies.
Georg Brandl116aa622007-08-15 14:28:22 +00002583
2584
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002585.. class:: MemoryHandler(capacity, flushLevel=ERROR, target=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002586
2587 Returns a new instance of the :class:`MemoryHandler` class. The instance is
2588 initialized with a buffer size of *capacity*. If *flushLevel* is not specified,
2589 :const:`ERROR` is used. If no *target* is specified, the target will need to be
2590 set using :meth:`setTarget` before this handler does anything useful.
2591
2592
Benjamin Petersone41251e2008-04-25 01:59:09 +00002593 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002594
Benjamin Petersone41251e2008-04-25 01:59:09 +00002595 Calls :meth:`flush`, sets the target to :const:`None` and clears the
2596 buffer.
Georg Brandl116aa622007-08-15 14:28:22 +00002597
2598
Benjamin Petersone41251e2008-04-25 01:59:09 +00002599 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002600
Benjamin Petersone41251e2008-04-25 01:59:09 +00002601 For a :class:`MemoryHandler`, flushing means just sending the buffered
2602 records to the target, if there is one. Override if you want different
2603 behavior.
Georg Brandl116aa622007-08-15 14:28:22 +00002604
2605
Benjamin Petersone41251e2008-04-25 01:59:09 +00002606 .. method:: setTarget(target)
Georg Brandl116aa622007-08-15 14:28:22 +00002607
Benjamin Petersone41251e2008-04-25 01:59:09 +00002608 Sets the target handler for this handler.
Georg Brandl116aa622007-08-15 14:28:22 +00002609
2610
Benjamin Petersone41251e2008-04-25 01:59:09 +00002611 .. method:: shouldFlush(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002612
Benjamin Petersone41251e2008-04-25 01:59:09 +00002613 Checks for buffer full or a record at the *flushLevel* or higher.
Georg Brandl116aa622007-08-15 14:28:22 +00002614
2615
Vinay Sajipd31f3632010-06-29 15:31:15 +00002616.. _http-handler:
2617
Georg Brandl116aa622007-08-15 14:28:22 +00002618HTTPHandler
2619^^^^^^^^^^^
2620
2621The :class:`HTTPHandler` class, located in the :mod:`logging.handlers` module,
2622supports sending logging messages to a Web server, using either ``GET`` or
2623``POST`` semantics.
2624
2625
Vinay Sajip1b5646a2010-09-13 20:37:50 +00002626.. class:: HTTPHandler(host, url, method='GET', secure=False, credentials=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002627
Vinay Sajip1b5646a2010-09-13 20:37:50 +00002628 Returns a new instance of the :class:`HTTPHandler` class. The *host* can be
2629 of the form ``host:port``, should you need to use a specific port number.
2630 If no *method* is specified, ``GET`` is used. If *secure* is True, an HTTPS
2631 connection will be used. If *credentials* is specified, it should be a
2632 2-tuple consisting of userid and password, which will be placed in an HTTP
2633 'Authorization' header using Basic authentication. If you specify
2634 credentials, you should also specify secure=True so that your userid and
2635 password are not passed in cleartext across the wire.
Georg Brandl116aa622007-08-15 14:28:22 +00002636
2637
Benjamin Petersone41251e2008-04-25 01:59:09 +00002638 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002639
Senthil Kumaranf0769e82010-08-09 19:53:52 +00002640 Sends the record to the Web server as a percent-encoded dictionary.
Georg Brandl116aa622007-08-15 14:28:22 +00002641
2642
Vinay Sajip121a1c42010-09-08 10:46:15 +00002643.. _queue-handler:
2644
2645
2646QueueHandler
2647^^^^^^^^^^^^
2648
2649The :class:`QueueHandler` class, located in the :mod:`logging.handlers` module,
2650supports sending logging messages to a queue, such as those implemented in the
2651:mod:`queue` or :mod:`multiprocessing` modules.
2652
2653
2654.. class:: QueueHandler(queue)
2655
2656 Returns a new instance of the :class:`QueueHandler` class. The instance is
Vinay Sajip63891ed2010-09-13 20:02:39 +00002657 initialized with the queue to send messages to. The queue can be any queue-
2658 like object; it's passed as-is to the :meth:`enqueue` method, which needs
2659 to know how to send messages to it.
Vinay Sajip121a1c42010-09-08 10:46:15 +00002660
2661
2662 .. method:: emit(record)
2663
2664 Sends the record to the handler's queue.
2665
2666 .. method:: enqueue(record)
2667
2668 Enqueues the record on the queue using ``put_nowait()``; you may
2669 want to override this if you want to use blocking behaviour, or a
2670 timeout, or a customised queue implementation.
2671
2672
2673.. versionadded:: 3.2
2674
2675The :class:`QueueHandler` class was not present in previous versions.
2676
Vinay Sajip63891ed2010-09-13 20:02:39 +00002677.. _zeromq-handlers:
2678
2679You can use a :class:`QueueHandler` subclass to send messages to other kinds
2680of queues, for example a ZeroMQ "publish" socket. In the example below,the
2681socket is created separately and passed to the handler (as its 'queue')::
2682
2683 import zmq # using pyzmq, the Python binding for ZeroMQ
2684 import json # for serializing records portably
2685
2686 ctx = zmq.Context()
2687 sock = zmq.Socket(ctx, zmq.PUB) # or zmq.PUSH, or other suitable value
2688 sock.bind('tcp://*:5556') # or wherever
2689
2690 class ZeroMQSocketHandler(QueueHandler):
2691 def enqueue(self, record):
2692 data = json.dumps(record.__dict__)
2693 self.queue.send(data)
2694
Vinay Sajip0055c422010-09-14 09:42:39 +00002695 handler = ZeroMQSocketHandler(sock)
2696
2697
Vinay Sajip63891ed2010-09-13 20:02:39 +00002698Of course there are other ways of organizing this, for example passing in the
2699data needed by the handler to create the socket::
2700
2701 class ZeroMQSocketHandler(QueueHandler):
2702 def __init__(self, uri, socktype=zmq.PUB, ctx=None):
2703 self.ctx = ctx or zmq.Context()
2704 socket = zmq.Socket(self.ctx, socktype)
Vinay Sajip0055c422010-09-14 09:42:39 +00002705 QueueHandler.__init__(self, socket)
Vinay Sajip63891ed2010-09-13 20:02:39 +00002706
2707 def enqueue(self, record):
2708 data = json.dumps(record.__dict__)
2709 self.queue.send(data)
2710
Vinay Sajipde726922010-09-14 06:59:24 +00002711 def close(self):
2712 self.queue.close()
Vinay Sajip121a1c42010-09-08 10:46:15 +00002713
Christian Heimes8b0facf2007-12-04 19:30:01 +00002714.. _formatter-objects:
2715
Georg Brandl116aa622007-08-15 14:28:22 +00002716Formatter Objects
2717-----------------
2718
Benjamin Peterson75edad02009-01-01 15:05:06 +00002719.. currentmodule:: logging
2720
Georg Brandl116aa622007-08-15 14:28:22 +00002721:class:`Formatter`\ s have the following attributes and methods. They are
2722responsible for converting a :class:`LogRecord` to (usually) a string which can
2723be interpreted by either a human or an external system. The base
2724:class:`Formatter` allows a formatting string to be specified. If none is
2725supplied, the default value of ``'%(message)s'`` is used.
2726
2727A Formatter can be initialized with a format string which makes use of knowledge
2728of the :class:`LogRecord` attributes - such as the default value mentioned above
2729making use of the fact that the user's message and arguments are pre-formatted
2730into a :class:`LogRecord`'s *message* attribute. This format string contains
Ezio Melotti0639d5a2009-12-19 23:26:38 +00002731standard Python %-style mapping keys. See section :ref:`old-string-formatting`
Georg Brandl116aa622007-08-15 14:28:22 +00002732for more information on string formatting.
2733
2734Currently, the useful mapping keys in a :class:`LogRecord` are:
2735
2736+-------------------------+-----------------------------------------------+
2737| Format | Description |
2738+=========================+===============================================+
2739| ``%(name)s`` | Name of the logger (logging channel). |
2740+-------------------------+-----------------------------------------------+
2741| ``%(levelno)s`` | Numeric logging level for the message |
2742| | (:const:`DEBUG`, :const:`INFO`, |
2743| | :const:`WARNING`, :const:`ERROR`, |
2744| | :const:`CRITICAL`). |
2745+-------------------------+-----------------------------------------------+
2746| ``%(levelname)s`` | Text logging level for the message |
2747| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
2748| | ``'ERROR'``, ``'CRITICAL'``). |
2749+-------------------------+-----------------------------------------------+
2750| ``%(pathname)s`` | Full pathname of the source file where the |
2751| | logging call was issued (if available). |
2752+-------------------------+-----------------------------------------------+
2753| ``%(filename)s`` | Filename portion of pathname. |
2754+-------------------------+-----------------------------------------------+
2755| ``%(module)s`` | Module (name portion of filename). |
2756+-------------------------+-----------------------------------------------+
2757| ``%(funcName)s`` | Name of function containing the logging call. |
2758+-------------------------+-----------------------------------------------+
2759| ``%(lineno)d`` | Source line number where the logging call was |
2760| | issued (if available). |
2761+-------------------------+-----------------------------------------------+
2762| ``%(created)f`` | Time when the :class:`LogRecord` was created |
2763| | (as returned by :func:`time.time`). |
2764+-------------------------+-----------------------------------------------+
2765| ``%(relativeCreated)d`` | Time in milliseconds when the LogRecord was |
2766| | created, relative to the time the logging |
2767| | module was loaded. |
2768+-------------------------+-----------------------------------------------+
2769| ``%(asctime)s`` | Human-readable time when the |
2770| | :class:`LogRecord` was created. By default |
2771| | this is of the form "2003-07-08 16:49:45,896" |
2772| | (the numbers after the comma are millisecond |
2773| | portion of the time). |
2774+-------------------------+-----------------------------------------------+
2775| ``%(msecs)d`` | Millisecond portion of the time when the |
2776| | :class:`LogRecord` was created. |
2777+-------------------------+-----------------------------------------------+
2778| ``%(thread)d`` | Thread ID (if available). |
2779+-------------------------+-----------------------------------------------+
2780| ``%(threadName)s`` | Thread name (if available). |
2781+-------------------------+-----------------------------------------------+
2782| ``%(process)d`` | Process ID (if available). |
2783+-------------------------+-----------------------------------------------+
Vinay Sajip121a1c42010-09-08 10:46:15 +00002784| ``%(processName)s`` | Process name (if available). |
2785+-------------------------+-----------------------------------------------+
Georg Brandl116aa622007-08-15 14:28:22 +00002786| ``%(message)s`` | The logged message, computed as ``msg % |
2787| | args``. |
2788+-------------------------+-----------------------------------------------+
2789
Georg Brandl116aa622007-08-15 14:28:22 +00002790
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002791.. class:: Formatter(fmt=None, datefmt=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002792
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002793 Returns a new instance of the :class:`Formatter` class. The instance is
2794 initialized with a format string for the message as a whole, as well as a
2795 format string for the date/time portion of a message. If no *fmt* is
2796 specified, ``'%(message)s'`` is used. If no *datefmt* is specified, the
2797 ISO8601 date format is used.
Georg Brandl116aa622007-08-15 14:28:22 +00002798
Benjamin Petersone41251e2008-04-25 01:59:09 +00002799 .. method:: format(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002800
Benjamin Petersone41251e2008-04-25 01:59:09 +00002801 The record's attribute dictionary is used as the operand to a string
2802 formatting operation. Returns the resulting string. Before formatting the
2803 dictionary, a couple of preparatory steps are carried out. The *message*
2804 attribute of the record is computed using *msg* % *args*. If the
2805 formatting string contains ``'(asctime)'``, :meth:`formatTime` is called
2806 to format the event time. If there is exception information, it is
2807 formatted using :meth:`formatException` and appended to the message. Note
2808 that the formatted exception information is cached in attribute
2809 *exc_text*. This is useful because the exception information can be
2810 pickled and sent across the wire, but you should be careful if you have
2811 more than one :class:`Formatter` subclass which customizes the formatting
2812 of exception information. In this case, you will have to clear the cached
2813 value after a formatter has done its formatting, so that the next
2814 formatter to handle the event doesn't use the cached value but
2815 recalculates it afresh.
Georg Brandl116aa622007-08-15 14:28:22 +00002816
2817
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002818 .. method:: formatTime(record, datefmt=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002819
Benjamin Petersone41251e2008-04-25 01:59:09 +00002820 This method should be called from :meth:`format` by a formatter which
2821 wants to make use of a formatted time. This method can be overridden in
2822 formatters to provide for any specific requirement, but the basic behavior
2823 is as follows: if *datefmt* (a string) is specified, it is used with
2824 :func:`time.strftime` to format the creation time of the
2825 record. Otherwise, the ISO8601 format is used. The resulting string is
2826 returned.
Georg Brandl116aa622007-08-15 14:28:22 +00002827
2828
Benjamin Petersone41251e2008-04-25 01:59:09 +00002829 .. method:: formatException(exc_info)
Georg Brandl116aa622007-08-15 14:28:22 +00002830
Benjamin Petersone41251e2008-04-25 01:59:09 +00002831 Formats the specified exception information (a standard exception tuple as
2832 returned by :func:`sys.exc_info`) as a string. This default implementation
2833 just uses :func:`traceback.print_exception`. The resulting string is
2834 returned.
Georg Brandl116aa622007-08-15 14:28:22 +00002835
Vinay Sajipd31f3632010-06-29 15:31:15 +00002836.. _filter:
Georg Brandl116aa622007-08-15 14:28:22 +00002837
2838Filter Objects
2839--------------
2840
2841:class:`Filter`\ s can be used by :class:`Handler`\ s and :class:`Logger`\ s for
2842more sophisticated filtering than is provided by levels. The base filter class
2843only allows events which are below a certain point in the logger hierarchy. For
2844example, a filter initialized with "A.B" will allow events logged by loggers
2845"A.B", "A.B.C", "A.B.C.D", "A.B.D" etc. but not "A.BB", "B.A.B" etc. If
2846initialized with the empty string, all events are passed.
2847
2848
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002849.. class:: Filter(name='')
Georg Brandl116aa622007-08-15 14:28:22 +00002850
2851 Returns an instance of the :class:`Filter` class. If *name* is specified, it
2852 names a logger which, together with its children, will have its events allowed
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002853 through the filter. If *name* is the empty string, allows every event.
Georg Brandl116aa622007-08-15 14:28:22 +00002854
2855
Benjamin Petersone41251e2008-04-25 01:59:09 +00002856 .. method:: filter(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002857
Benjamin Petersone41251e2008-04-25 01:59:09 +00002858 Is the specified record to be logged? Returns zero for no, nonzero for
2859 yes. If deemed appropriate, the record may be modified in-place by this
2860 method.
Georg Brandl116aa622007-08-15 14:28:22 +00002861
Vinay Sajip81010212010-08-19 19:17:41 +00002862Note that filters attached to handlers are consulted whenever an event is
2863emitted by the handler, whereas filters attached to loggers are consulted
2864whenever an event is logged to the handler (using :meth:`debug`, :meth:`info`,
2865etc.) This means that events which have been generated by descendant loggers
2866will not be filtered by a logger's filter setting, unless the filter has also
2867been applied to those descendant loggers.
2868
Vinay Sajipac007992010-09-17 12:45:26 +00002869Other uses for filters
2870^^^^^^^^^^^^^^^^^^^^^^
2871
2872Although filters are used primarily to filter records based on more
2873sophisticated criteria than levels, they get to see every record which is
2874processed by the handler or logger they're attached to: this can be useful if
2875you want to do things like counting how many records were processed by a
2876particular logger or handler, or adding, changing or removing attributes in
2877the LogRecord being processed. Obviously changing the LogRecord needs to be
2878done with some care, but it does allow the injection of contextual information
2879into logs (see :ref:`filters-contextual`).
2880
Vinay Sajipd31f3632010-06-29 15:31:15 +00002881.. _log-record:
Georg Brandl116aa622007-08-15 14:28:22 +00002882
2883LogRecord Objects
2884-----------------
2885
Vinay Sajip4039aff2010-09-11 10:25:28 +00002886:class:`LogRecord` instances are created automatically by the :class:`Logger`
2887every time something is logged, and can be created manually via
2888:func:`makeLogRecord` (for example, from a pickled event received over the
2889wire).
Georg Brandl116aa622007-08-15 14:28:22 +00002890
2891
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002892.. class:: LogRecord(name, lvl, pathname, lineno, msg, args, exc_info, func=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002893
Vinay Sajip4039aff2010-09-11 10:25:28 +00002894 Contains all the information pertinent to the event being logged.
Georg Brandl116aa622007-08-15 14:28:22 +00002895
Vinay Sajip4039aff2010-09-11 10:25:28 +00002896 The primary information is passed in :attr:`msg` and :attr:`args`, which
2897 are combined using ``msg % args`` to create the :attr:`message` field of the
2898 record.
2899
2900 .. attribute:: args
2901
2902 Tuple of arguments to be used in formatting :attr:`msg`.
2903
2904 .. attribute:: exc_info
2905
2906 Exception tuple (à la `sys.exc_info`) or `None` if no exception
2907 information is availble.
2908
2909 .. attribute:: func
2910
2911 Name of the function of origin (i.e. in which the logging call was made).
2912
2913 .. attribute:: lineno
2914
2915 Line number in the source file of origin.
2916
2917 .. attribute:: lvl
2918
2919 Numeric logging level.
2920
2921 .. attribute:: message
2922
2923 Bound to the result of :meth:`getMessage` when
2924 :meth:`Formatter.format(record)<Formatter.format>` is invoked.
2925
2926 .. attribute:: msg
2927
2928 User-supplied :ref:`format string<string-formatting>` or arbitrary object
2929 (see :ref:`arbitrary-object-messages`) used in :meth:`getMessage`.
2930
2931 .. attribute:: name
2932
2933 Name of the logger that emitted the record.
2934
2935 .. attribute:: pathname
2936
2937 Absolute pathname of the source file of origin.
Georg Brandl116aa622007-08-15 14:28:22 +00002938
Benjamin Petersone41251e2008-04-25 01:59:09 +00002939 .. method:: getMessage()
Georg Brandl116aa622007-08-15 14:28:22 +00002940
Benjamin Petersone41251e2008-04-25 01:59:09 +00002941 Returns the message for this :class:`LogRecord` instance after merging any
Vinay Sajip4039aff2010-09-11 10:25:28 +00002942 user-supplied arguments with the message. If the user-supplied message
2943 argument to the logging call is not a string, :func:`str` is called on it to
2944 convert it to a string. This allows use of user-defined classes as
2945 messages, whose ``__str__`` method can return the actual format string to
2946 be used.
2947
2948 .. versionchanged:: 2.5
2949 *func* was added.
Benjamin Petersone41251e2008-04-25 01:59:09 +00002950
Vinay Sajipd31f3632010-06-29 15:31:15 +00002951.. _logger-adapter:
Georg Brandl116aa622007-08-15 14:28:22 +00002952
Christian Heimes04c420f2008-01-18 18:40:46 +00002953LoggerAdapter Objects
2954---------------------
2955
Christian Heimes04c420f2008-01-18 18:40:46 +00002956:class:`LoggerAdapter` instances are used to conveniently pass contextual
Georg Brandl86def6c2008-01-21 20:36:10 +00002957information into logging calls. For a usage example , see the section on
2958`adding contextual information to your logging output`__.
2959
2960__ context-info_
Christian Heimes04c420f2008-01-18 18:40:46 +00002961
2962.. class:: LoggerAdapter(logger, extra)
2963
2964 Returns an instance of :class:`LoggerAdapter` initialized with an
2965 underlying :class:`Logger` instance and a dict-like object.
2966
Benjamin Petersone41251e2008-04-25 01:59:09 +00002967 .. method:: process(msg, kwargs)
Christian Heimes04c420f2008-01-18 18:40:46 +00002968
Benjamin Petersone41251e2008-04-25 01:59:09 +00002969 Modifies the message and/or keyword arguments passed to a logging call in
2970 order to insert contextual information. This implementation takes the object
2971 passed as *extra* to the constructor and adds it to *kwargs* using key
2972 'extra'. The return value is a (*msg*, *kwargs*) tuple which has the
2973 (possibly modified) versions of the arguments passed in.
Christian Heimes04c420f2008-01-18 18:40:46 +00002974
2975In addition to the above, :class:`LoggerAdapter` supports all the logging
2976methods of :class:`Logger`, i.e. :meth:`debug`, :meth:`info`, :meth:`warning`,
2977:meth:`error`, :meth:`exception`, :meth:`critical` and :meth:`log`. These
2978methods have the same signatures as their counterparts in :class:`Logger`, so
2979you can use the two types of instances interchangeably.
2980
Ezio Melotti4d5195b2010-04-20 10:57:44 +00002981.. versionchanged:: 3.2
Georg Brandl67b21b72010-08-17 15:07:14 +00002982 The :meth:`isEnabledFor` method was added to :class:`LoggerAdapter`. This
2983 method delegates to the underlying logger.
Benjamin Peterson22005fc2010-04-11 16:25:06 +00002984
Georg Brandl116aa622007-08-15 14:28:22 +00002985
2986Thread Safety
2987-------------
2988
2989The logging module is intended to be thread-safe without any special work
2990needing to be done by its clients. It achieves this though using threading
2991locks; there is one lock to serialize access to the module's shared data, and
2992each handler also creates a lock to serialize access to its underlying I/O.
2993
Benjamin Petersond23f8222009-04-05 19:13:16 +00002994If you are implementing asynchronous signal handlers using the :mod:`signal`
2995module, you may not be able to use logging from within such handlers. This is
2996because lock implementations in the :mod:`threading` module are not always
2997re-entrant, and so cannot be invoked from such signal handlers.
Georg Brandl116aa622007-08-15 14:28:22 +00002998
Benjamin Peterson9451a1c2010-03-13 22:30:34 +00002999
3000Integration with the warnings module
3001------------------------------------
3002
3003The :func:`captureWarnings` function can be used to integrate :mod:`logging`
3004with the :mod:`warnings` module.
3005
3006.. function:: captureWarnings(capture)
3007
3008 This function is used to turn the capture of warnings by logging on and
3009 off.
3010
3011 If `capture` is `True`, warnings issued by the :mod:`warnings` module
3012 will be redirected to the logging system. Specifically, a warning will be
3013 formatted using :func:`warnings.formatwarning` and the resulting string
3014 logged to a logger named "py.warnings" with a severity of `WARNING`.
3015
3016 If `capture` is `False`, the redirection of warnings to the logging system
3017 will stop, and warnings will be redirected to their original destinations
3018 (i.e. those in effect before `captureWarnings(True)` was called).
3019
3020
Georg Brandl116aa622007-08-15 14:28:22 +00003021Configuration
3022-------------
3023
3024
3025.. _logging-config-api:
3026
3027Configuration functions
3028^^^^^^^^^^^^^^^^^^^^^^^
3029
Georg Brandl116aa622007-08-15 14:28:22 +00003030The following functions configure the logging module. They are located in the
3031:mod:`logging.config` module. Their use is optional --- you can configure the
3032logging module using these functions or by making calls to the main API (defined
3033in :mod:`logging` itself) and defining handlers which are declared either in
3034:mod:`logging` or :mod:`logging.handlers`.
3035
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003036.. function:: dictConfig(config)
Georg Brandl116aa622007-08-15 14:28:22 +00003037
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003038 Takes the logging configuration from a dictionary. The contents of
3039 this dictionary are described in :ref:`logging-config-dictschema`
3040 below.
3041
3042 If an error is encountered during configuration, this function will
3043 raise a :exc:`ValueError`, :exc:`TypeError`, :exc:`AttributeError`
3044 or :exc:`ImportError` with a suitably descriptive message. The
3045 following is a (possibly incomplete) list of conditions which will
3046 raise an error:
3047
3048 * A ``level`` which is not a string or which is a string not
3049 corresponding to an actual logging level.
3050 * A ``propagate`` value which is not a boolean.
3051 * An id which does not have a corresponding destination.
3052 * A non-existent handler id found during an incremental call.
3053 * An invalid logger name.
3054 * Inability to resolve to an internal or external object.
3055
3056 Parsing is performed by the :class:`DictConfigurator` class, whose
3057 constructor is passed the dictionary used for configuration, and
3058 has a :meth:`configure` method. The :mod:`logging.config` module
3059 has a callable attribute :attr:`dictConfigClass`
3060 which is initially set to :class:`DictConfigurator`.
3061 You can replace the value of :attr:`dictConfigClass` with a
3062 suitable implementation of your own.
3063
3064 :func:`dictConfig` calls :attr:`dictConfigClass` passing
3065 the specified dictionary, and then calls the :meth:`configure` method on
3066 the returned object to put the configuration into effect::
3067
3068 def dictConfig(config):
3069 dictConfigClass(config).configure()
3070
3071 For example, a subclass of :class:`DictConfigurator` could call
3072 ``DictConfigurator.__init__()`` in its own :meth:`__init__()`, then
3073 set up custom prefixes which would be usable in the subsequent
3074 :meth:`configure` call. :attr:`dictConfigClass` would be bound to
3075 this new subclass, and then :func:`dictConfig` could be called exactly as
3076 in the default, uncustomized state.
3077
3078.. function:: fileConfig(fname[, defaults])
Georg Brandl116aa622007-08-15 14:28:22 +00003079
Alexandre Vassalotti1d1eaa42008-05-14 22:59:42 +00003080 Reads the logging configuration from a :mod:`configparser`\-format file named
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00003081 *fname*. This function can be called several times from an application,
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003082 allowing an end user to select from various pre-canned
Alexandre Vassalotti1d1eaa42008-05-14 22:59:42 +00003083 configurations (if the developer provides a mechanism to present the choices
3084 and load the chosen configuration). Defaults to be passed to the ConfigParser
3085 can be specified in the *defaults* argument.
Georg Brandl116aa622007-08-15 14:28:22 +00003086
Georg Brandlcd7f32b2009-06-08 09:13:45 +00003087
3088.. function:: listen(port=DEFAULT_LOGGING_CONFIG_PORT)
Georg Brandl116aa622007-08-15 14:28:22 +00003089
3090 Starts up a socket server on the specified port, and listens for new
3091 configurations. If no port is specified, the module's default
3092 :const:`DEFAULT_LOGGING_CONFIG_PORT` is used. Logging configurations will be
3093 sent as a file suitable for processing by :func:`fileConfig`. Returns a
3094 :class:`Thread` instance on which you can call :meth:`start` to start the
3095 server, and which you can :meth:`join` when appropriate. To stop the server,
Christian Heimes8b0facf2007-12-04 19:30:01 +00003096 call :func:`stopListening`.
3097
3098 To send a configuration to the socket, read in the configuration file and
3099 send it to the socket as a string of bytes preceded by a four-byte length
3100 string packed in binary using ``struct.pack('>L', n)``.
Georg Brandl116aa622007-08-15 14:28:22 +00003101
3102
3103.. function:: stopListening()
3104
Christian Heimes8b0facf2007-12-04 19:30:01 +00003105 Stops the listening server which was created with a call to :func:`listen`.
3106 This is typically called before calling :meth:`join` on the return value from
Georg Brandl116aa622007-08-15 14:28:22 +00003107 :func:`listen`.
3108
3109
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003110.. _logging-config-dictschema:
3111
3112Configuration dictionary schema
3113^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3114
3115Describing a logging configuration requires listing the various
3116objects to create and the connections between them; for example, you
3117may create a handler named "console" and then say that the logger
3118named "startup" will send its messages to the "console" handler.
3119These objects aren't limited to those provided by the :mod:`logging`
3120module because you might write your own formatter or handler class.
3121The parameters to these classes may also need to include external
3122objects such as ``sys.stderr``. The syntax for describing these
3123objects and connections is defined in :ref:`logging-config-dict-connections`
3124below.
3125
3126Dictionary Schema Details
3127"""""""""""""""""""""""""
3128
3129The dictionary passed to :func:`dictConfig` must contain the following
3130keys:
3131
3132* `version` - to be set to an integer value representing the schema
3133 version. The only valid value at present is 1, but having this key
3134 allows the schema to evolve while still preserving backwards
3135 compatibility.
3136
3137All other keys are optional, but if present they will be interpreted
3138as described below. In all cases below where a 'configuring dict' is
3139mentioned, it will be checked for the special ``'()'`` key to see if a
3140custom instantiation is required. If so, the mechanism described in
3141:ref:`logging-config-dict-userdef` below is used to create an instance;
3142otherwise, the context is used to determine what to instantiate.
3143
3144* `formatters` - the corresponding value will be a dict in which each
3145 key is a formatter id and each value is a dict describing how to
3146 configure the corresponding Formatter instance.
3147
3148 The configuring dict is searched for keys ``format`` and ``datefmt``
3149 (with defaults of ``None``) and these are used to construct a
3150 :class:`logging.Formatter` instance.
3151
3152* `filters` - the corresponding value will be a dict in which each key
3153 is a filter id and each value is a dict describing how to configure
3154 the corresponding Filter instance.
3155
3156 The configuring dict is searched for the key ``name`` (defaulting to the
3157 empty string) and this is used to construct a :class:`logging.Filter`
3158 instance.
3159
3160* `handlers` - the corresponding value will be a dict in which each
3161 key is a handler id and each value is a dict describing how to
3162 configure the corresponding Handler instance.
3163
3164 The configuring dict is searched for the following keys:
3165
3166 * ``class`` (mandatory). This is the fully qualified name of the
3167 handler class.
3168
3169 * ``level`` (optional). The level of the handler.
3170
3171 * ``formatter`` (optional). The id of the formatter for this
3172 handler.
3173
3174 * ``filters`` (optional). A list of ids of the filters for this
3175 handler.
3176
3177 All *other* keys are passed through as keyword arguments to the
3178 handler's constructor. For example, given the snippet::
3179
3180 handlers:
3181 console:
3182 class : logging.StreamHandler
3183 formatter: brief
3184 level : INFO
3185 filters: [allow_foo]
3186 stream : ext://sys.stdout
3187 file:
3188 class : logging.handlers.RotatingFileHandler
3189 formatter: precise
3190 filename: logconfig.log
3191 maxBytes: 1024
3192 backupCount: 3
3193
3194 the handler with id ``console`` is instantiated as a
3195 :class:`logging.StreamHandler`, using ``sys.stdout`` as the underlying
3196 stream. The handler with id ``file`` is instantiated as a
3197 :class:`logging.handlers.RotatingFileHandler` with the keyword arguments
3198 ``filename='logconfig.log', maxBytes=1024, backupCount=3``.
3199
3200* `loggers` - the corresponding value will be a dict in which each key
3201 is a logger name and each value is a dict describing how to
3202 configure the corresponding Logger instance.
3203
3204 The configuring dict is searched for the following keys:
3205
3206 * ``level`` (optional). The level of the logger.
3207
3208 * ``propagate`` (optional). The propagation setting of the logger.
3209
3210 * ``filters`` (optional). A list of ids of the filters for this
3211 logger.
3212
3213 * ``handlers`` (optional). A list of ids of the handlers for this
3214 logger.
3215
3216 The specified loggers will be configured according to the level,
3217 propagation, filters and handlers specified.
3218
3219* `root` - this will be the configuration for the root logger.
3220 Processing of the configuration will be as for any logger, except
3221 that the ``propagate`` setting will not be applicable.
3222
3223* `incremental` - whether the configuration is to be interpreted as
3224 incremental to the existing configuration. This value defaults to
3225 ``False``, which means that the specified configuration replaces the
3226 existing configuration with the same semantics as used by the
3227 existing :func:`fileConfig` API.
3228
3229 If the specified value is ``True``, the configuration is processed
3230 as described in the section on :ref:`logging-config-dict-incremental`.
3231
3232* `disable_existing_loggers` - whether any existing loggers are to be
3233 disabled. This setting mirrors the parameter of the same name in
3234 :func:`fileConfig`. If absent, this parameter defaults to ``True``.
3235 This value is ignored if `incremental` is ``True``.
3236
3237.. _logging-config-dict-incremental:
3238
3239Incremental Configuration
3240"""""""""""""""""""""""""
3241
3242It is difficult to provide complete flexibility for incremental
3243configuration. For example, because objects such as filters
3244and formatters are anonymous, once a configuration is set up, it is
3245not possible to refer to such anonymous objects when augmenting a
3246configuration.
3247
3248Furthermore, there is not a compelling case for arbitrarily altering
3249the object graph of loggers, handlers, filters, formatters at
3250run-time, once a configuration is set up; the verbosity of loggers and
3251handlers can be controlled just by setting levels (and, in the case of
3252loggers, propagation flags). Changing the object graph arbitrarily in
3253a safe way is problematic in a multi-threaded environment; while not
3254impossible, the benefits are not worth the complexity it adds to the
3255implementation.
3256
3257Thus, when the ``incremental`` key of a configuration dict is present
3258and is ``True``, the system will completely ignore any ``formatters`` and
3259``filters`` entries, and process only the ``level``
3260settings in the ``handlers`` entries, and the ``level`` and
3261``propagate`` settings in the ``loggers`` and ``root`` entries.
3262
3263Using a value in the configuration dict lets configurations to be sent
3264over the wire as pickled dicts to a socket listener. Thus, the logging
3265verbosity of a long-running application can be altered over time with
3266no need to stop and restart the application.
3267
3268.. _logging-config-dict-connections:
3269
3270Object connections
3271""""""""""""""""""
3272
3273The schema describes a set of logging objects - loggers,
3274handlers, formatters, filters - which are connected to each other in
3275an object graph. Thus, the schema needs to represent connections
3276between the objects. For example, say that, once configured, a
3277particular logger has attached to it a particular handler. For the
3278purposes of this discussion, we can say that the logger represents the
3279source, and the handler the destination, of a connection between the
3280two. Of course in the configured objects this is represented by the
3281logger holding a reference to the handler. In the configuration dict,
3282this is done by giving each destination object an id which identifies
3283it unambiguously, and then using the id in the source object's
3284configuration to indicate that a connection exists between the source
3285and the destination object with that id.
3286
3287So, for example, consider the following YAML snippet::
3288
3289 formatters:
3290 brief:
3291 # configuration for formatter with id 'brief' goes here
3292 precise:
3293 # configuration for formatter with id 'precise' goes here
3294 handlers:
3295 h1: #This is an id
3296 # configuration of handler with id 'h1' goes here
3297 formatter: brief
3298 h2: #This is another id
3299 # configuration of handler with id 'h2' goes here
3300 formatter: precise
3301 loggers:
3302 foo.bar.baz:
3303 # other configuration for logger 'foo.bar.baz'
3304 handlers: [h1, h2]
3305
3306(Note: YAML used here because it's a little more readable than the
3307equivalent Python source form for the dictionary.)
3308
3309The ids for loggers are the logger names which would be used
3310programmatically to obtain a reference to those loggers, e.g.
3311``foo.bar.baz``. The ids for Formatters and Filters can be any string
3312value (such as ``brief``, ``precise`` above) and they are transient,
3313in that they are only meaningful for processing the configuration
3314dictionary and used to determine connections between objects, and are
3315not persisted anywhere when the configuration call is complete.
3316
3317The above snippet indicates that logger named ``foo.bar.baz`` should
3318have two handlers attached to it, which are described by the handler
3319ids ``h1`` and ``h2``. The formatter for ``h1`` is that described by id
3320``brief``, and the formatter for ``h2`` is that described by id
3321``precise``.
3322
3323
3324.. _logging-config-dict-userdef:
3325
3326User-defined objects
3327""""""""""""""""""""
3328
3329The schema supports user-defined objects for handlers, filters and
3330formatters. (Loggers do not need to have different types for
3331different instances, so there is no support in this configuration
3332schema for user-defined logger classes.)
3333
3334Objects to be configured are described by dictionaries
3335which detail their configuration. In some places, the logging system
3336will be able to infer from the context how an object is to be
3337instantiated, but when a user-defined object is to be instantiated,
3338the system will not know how to do this. In order to provide complete
3339flexibility for user-defined object instantiation, the user needs
3340to provide a 'factory' - a callable which is called with a
3341configuration dictionary and which returns the instantiated object.
3342This is signalled by an absolute import path to the factory being
3343made available under the special key ``'()'``. Here's a concrete
3344example::
3345
3346 formatters:
3347 brief:
3348 format: '%(message)s'
3349 default:
3350 format: '%(asctime)s %(levelname)-8s %(name)-15s %(message)s'
3351 datefmt: '%Y-%m-%d %H:%M:%S'
3352 custom:
3353 (): my.package.customFormatterFactory
3354 bar: baz
3355 spam: 99.9
3356 answer: 42
3357
3358The above YAML snippet defines three formatters. The first, with id
3359``brief``, is a standard :class:`logging.Formatter` instance with the
3360specified format string. The second, with id ``default``, has a
3361longer format and also defines the time format explicitly, and will
3362result in a :class:`logging.Formatter` initialized with those two format
3363strings. Shown in Python source form, the ``brief`` and ``default``
3364formatters have configuration sub-dictionaries::
3365
3366 {
3367 'format' : '%(message)s'
3368 }
3369
3370and::
3371
3372 {
3373 'format' : '%(asctime)s %(levelname)-8s %(name)-15s %(message)s',
3374 'datefmt' : '%Y-%m-%d %H:%M:%S'
3375 }
3376
3377respectively, and as these dictionaries do not contain the special key
3378``'()'``, the instantiation is inferred from the context: as a result,
3379standard :class:`logging.Formatter` instances are created. The
3380configuration sub-dictionary for the third formatter, with id
3381``custom``, is::
3382
3383 {
3384 '()' : 'my.package.customFormatterFactory',
3385 'bar' : 'baz',
3386 'spam' : 99.9,
3387 'answer' : 42
3388 }
3389
3390and this contains the special key ``'()'``, which means that
3391user-defined instantiation is wanted. In this case, the specified
3392factory callable will be used. If it is an actual callable it will be
3393used directly - otherwise, if you specify a string (as in the example)
3394the actual callable will be located using normal import mechanisms.
3395The callable will be called with the **remaining** items in the
3396configuration sub-dictionary as keyword arguments. In the above
3397example, the formatter with id ``custom`` will be assumed to be
3398returned by the call::
3399
3400 my.package.customFormatterFactory(bar='baz', spam=99.9, answer=42)
3401
3402The key ``'()'`` has been used as the special key because it is not a
3403valid keyword parameter name, and so will not clash with the names of
3404the keyword arguments used in the call. The ``'()'`` also serves as a
3405mnemonic that the corresponding value is a callable.
3406
3407
3408.. _logging-config-dict-externalobj:
3409
3410Access to external objects
3411""""""""""""""""""""""""""
3412
3413There are times where a configuration needs to refer to objects
3414external to the configuration, for example ``sys.stderr``. If the
3415configuration dict is constructed using Python code, this is
3416straightforward, but a problem arises when the configuration is
3417provided via a text file (e.g. JSON, YAML). In a text file, there is
3418no standard way to distinguish ``sys.stderr`` from the literal string
3419``'sys.stderr'``. To facilitate this distinction, the configuration
3420system looks for certain special prefixes in string values and
3421treat them specially. For example, if the literal string
3422``'ext://sys.stderr'`` is provided as a value in the configuration,
3423then the ``ext://`` will be stripped off and the remainder of the
3424value processed using normal import mechanisms.
3425
3426The handling of such prefixes is done in a way analogous to protocol
3427handling: there is a generic mechanism to look for prefixes which
3428match the regular expression ``^(?P<prefix>[a-z]+)://(?P<suffix>.*)$``
3429whereby, if the ``prefix`` is recognised, the ``suffix`` is processed
3430in a prefix-dependent manner and the result of the processing replaces
3431the string value. If the prefix is not recognised, then the string
3432value will be left as-is.
3433
3434
3435.. _logging-config-dict-internalobj:
3436
3437Access to internal objects
3438""""""""""""""""""""""""""
3439
3440As well as external objects, there is sometimes also a need to refer
3441to objects in the configuration. This will be done implicitly by the
3442configuration system for things that it knows about. For example, the
3443string value ``'DEBUG'`` for a ``level`` in a logger or handler will
3444automatically be converted to the value ``logging.DEBUG``, and the
3445``handlers``, ``filters`` and ``formatter`` entries will take an
3446object id and resolve to the appropriate destination object.
3447
3448However, a more generic mechanism is needed for user-defined
3449objects which are not known to the :mod:`logging` module. For
3450example, consider :class:`logging.handlers.MemoryHandler`, which takes
3451a ``target`` argument which is another handler to delegate to. Since
3452the system already knows about this class, then in the configuration,
3453the given ``target`` just needs to be the object id of the relevant
3454target handler, and the system will resolve to the handler from the
3455id. If, however, a user defines a ``my.package.MyHandler`` which has
3456an ``alternate`` handler, the configuration system would not know that
3457the ``alternate`` referred to a handler. To cater for this, a generic
3458resolution system allows the user to specify::
3459
3460 handlers:
3461 file:
3462 # configuration of file handler goes here
3463
3464 custom:
3465 (): my.package.MyHandler
3466 alternate: cfg://handlers.file
3467
3468The literal string ``'cfg://handlers.file'`` will be resolved in an
3469analogous way to strings with the ``ext://`` prefix, but looking
3470in the configuration itself rather than the import namespace. The
3471mechanism allows access by dot or by index, in a similar way to
3472that provided by ``str.format``. Thus, given the following snippet::
3473
3474 handlers:
3475 email:
3476 class: logging.handlers.SMTPHandler
3477 mailhost: localhost
3478 fromaddr: my_app@domain.tld
3479 toaddrs:
3480 - support_team@domain.tld
3481 - dev_team@domain.tld
3482 subject: Houston, we have a problem.
3483
3484in the configuration, the string ``'cfg://handlers'`` would resolve to
3485the dict with key ``handlers``, the string ``'cfg://handlers.email``
3486would resolve to the dict with key ``email`` in the ``handlers`` dict,
3487and so on. The string ``'cfg://handlers.email.toaddrs[1]`` would
3488resolve to ``'dev_team.domain.tld'`` and the string
3489``'cfg://handlers.email.toaddrs[0]'`` would resolve to the value
3490``'support_team@domain.tld'``. The ``subject`` value could be accessed
3491using either ``'cfg://handlers.email.subject'`` or, equivalently,
3492``'cfg://handlers.email[subject]'``. The latter form only needs to be
3493used if the key contains spaces or non-alphanumeric characters. If an
3494index value consists only of decimal digits, access will be attempted
3495using the corresponding integer value, falling back to the string
3496value if needed.
3497
3498Given a string ``cfg://handlers.myhandler.mykey.123``, this will
3499resolve to ``config_dict['handlers']['myhandler']['mykey']['123']``.
3500If the string is specified as ``cfg://handlers.myhandler.mykey[123]``,
3501the system will attempt to retrieve the value from
3502``config_dict['handlers']['myhandler']['mykey'][123]``, and fall back
3503to ``config_dict['handlers']['myhandler']['mykey']['123']`` if that
3504fails.
3505
Georg Brandl116aa622007-08-15 14:28:22 +00003506.. _logging-config-fileformat:
3507
3508Configuration file format
3509^^^^^^^^^^^^^^^^^^^^^^^^^
3510
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00003511The configuration file format understood by :func:`fileConfig` is based on
3512:mod:`configparser` functionality. The file must contain sections called
3513``[loggers]``, ``[handlers]`` and ``[formatters]`` which identify by name the
3514entities of each type which are defined in the file. For each such entity, there
3515is a separate section which identifies how that entity is configured. Thus, for
3516a logger named ``log01`` in the ``[loggers]`` section, the relevant
3517configuration details are held in a section ``[logger_log01]``. Similarly, a
3518handler called ``hand01`` in the ``[handlers]`` section will have its
3519configuration held in a section called ``[handler_hand01]``, while a formatter
3520called ``form01`` in the ``[formatters]`` section will have its configuration
3521specified in a section called ``[formatter_form01]``. The root logger
3522configuration must be specified in a section called ``[logger_root]``.
Georg Brandl116aa622007-08-15 14:28:22 +00003523
3524Examples of these sections in the file are given below. ::
3525
3526 [loggers]
3527 keys=root,log02,log03,log04,log05,log06,log07
3528
3529 [handlers]
3530 keys=hand01,hand02,hand03,hand04,hand05,hand06,hand07,hand08,hand09
3531
3532 [formatters]
3533 keys=form01,form02,form03,form04,form05,form06,form07,form08,form09
3534
3535The root logger must specify a level and a list of handlers. An example of a
3536root logger section is given below. ::
3537
3538 [logger_root]
3539 level=NOTSET
3540 handlers=hand01
3541
3542The ``level`` entry can be one of ``DEBUG, INFO, WARNING, ERROR, CRITICAL`` or
3543``NOTSET``. For the root logger only, ``NOTSET`` means that all messages will be
3544logged. Level values are :func:`eval`\ uated in the context of the ``logging``
3545package's namespace.
3546
3547The ``handlers`` entry is a comma-separated list of handler names, which must
3548appear in the ``[handlers]`` section. These names must appear in the
3549``[handlers]`` section and have corresponding sections in the configuration
3550file.
3551
3552For loggers other than the root logger, some additional information is required.
3553This is illustrated by the following example. ::
3554
3555 [logger_parser]
3556 level=DEBUG
3557 handlers=hand01
3558 propagate=1
3559 qualname=compiler.parser
3560
3561The ``level`` and ``handlers`` entries are interpreted as for the root logger,
3562except that if a non-root logger's level is specified as ``NOTSET``, the system
3563consults loggers higher up the hierarchy to determine the effective level of the
3564logger. The ``propagate`` entry is set to 1 to indicate that messages must
3565propagate to handlers higher up the logger hierarchy from this logger, or 0 to
3566indicate that messages are **not** propagated to handlers up the hierarchy. The
3567``qualname`` entry is the hierarchical channel name of the logger, that is to
3568say the name used by the application to get the logger.
3569
3570Sections which specify handler configuration are exemplified by the following.
3571::
3572
3573 [handler_hand01]
3574 class=StreamHandler
3575 level=NOTSET
3576 formatter=form01
3577 args=(sys.stdout,)
3578
3579The ``class`` entry indicates the handler's class (as determined by :func:`eval`
3580in the ``logging`` package's namespace). The ``level`` is interpreted as for
3581loggers, and ``NOTSET`` is taken to mean "log everything".
3582
3583The ``formatter`` entry indicates the key name of the formatter for this
3584handler. If blank, a default formatter (``logging._defaultFormatter``) is used.
3585If a name is specified, it must appear in the ``[formatters]`` section and have
3586a corresponding section in the configuration file.
3587
3588The ``args`` entry, when :func:`eval`\ uated in the context of the ``logging``
3589package's namespace, is the list of arguments to the constructor for the handler
3590class. Refer to the constructors for the relevant handlers, or to the examples
3591below, to see how typical entries are constructed. ::
3592
3593 [handler_hand02]
3594 class=FileHandler
3595 level=DEBUG
3596 formatter=form02
3597 args=('python.log', 'w')
3598
3599 [handler_hand03]
3600 class=handlers.SocketHandler
3601 level=INFO
3602 formatter=form03
3603 args=('localhost', handlers.DEFAULT_TCP_LOGGING_PORT)
3604
3605 [handler_hand04]
3606 class=handlers.DatagramHandler
3607 level=WARN
3608 formatter=form04
3609 args=('localhost', handlers.DEFAULT_UDP_LOGGING_PORT)
3610
3611 [handler_hand05]
3612 class=handlers.SysLogHandler
3613 level=ERROR
3614 formatter=form05
3615 args=(('localhost', handlers.SYSLOG_UDP_PORT), handlers.SysLogHandler.LOG_USER)
3616
3617 [handler_hand06]
3618 class=handlers.NTEventLogHandler
3619 level=CRITICAL
3620 formatter=form06
3621 args=('Python Application', '', 'Application')
3622
3623 [handler_hand07]
3624 class=handlers.SMTPHandler
3625 level=WARN
3626 formatter=form07
3627 args=('localhost', 'from@abc', ['user1@abc', 'user2@xyz'], 'Logger Subject')
3628
3629 [handler_hand08]
3630 class=handlers.MemoryHandler
3631 level=NOTSET
3632 formatter=form08
3633 target=
3634 args=(10, ERROR)
3635
3636 [handler_hand09]
3637 class=handlers.HTTPHandler
3638 level=NOTSET
3639 formatter=form09
3640 args=('localhost:9022', '/log', 'GET')
3641
3642Sections which specify formatter configuration are typified by the following. ::
3643
3644 [formatter_form01]
3645 format=F1 %(asctime)s %(levelname)s %(message)s
3646 datefmt=
3647 class=logging.Formatter
3648
3649The ``format`` entry is the overall format string, and the ``datefmt`` entry is
Christian Heimes5b5e81c2007-12-31 16:14:33 +00003650the :func:`strftime`\ -compatible date/time format string. If empty, the
3651package substitutes ISO8601 format date/times, which is almost equivalent to
3652specifying the date format string ``"%Y-%m-%d %H:%M:%S"``. The ISO8601 format
3653also specifies milliseconds, which are appended to the result of using the above
3654format string, with a comma separator. An example time in ISO8601 format is
3655``2003-01-23 00:29:50,411``.
Georg Brandl116aa622007-08-15 14:28:22 +00003656
3657The ``class`` entry is optional. It indicates the name of the formatter's class
3658(as a dotted module and class name.) This option is useful for instantiating a
3659:class:`Formatter` subclass. Subclasses of :class:`Formatter` can present
3660exception tracebacks in an expanded or condensed format.
3661
Christian Heimes8b0facf2007-12-04 19:30:01 +00003662
3663Configuration server example
3664^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3665
3666Here is an example of a module using the logging configuration server::
3667
3668 import logging
3669 import logging.config
3670 import time
3671 import os
3672
3673 # read initial config file
3674 logging.config.fileConfig("logging.conf")
3675
3676 # create and start listener on port 9999
3677 t = logging.config.listen(9999)
3678 t.start()
3679
3680 logger = logging.getLogger("simpleExample")
3681
3682 try:
3683 # loop through logging calls to see the difference
3684 # new configurations make, until Ctrl+C is pressed
3685 while True:
3686 logger.debug("debug message")
3687 logger.info("info message")
3688 logger.warn("warn message")
3689 logger.error("error message")
3690 logger.critical("critical message")
3691 time.sleep(5)
3692 except KeyboardInterrupt:
3693 # cleanup
3694 logging.config.stopListening()
3695 t.join()
3696
3697And here is a script that takes a filename and sends that file to the server,
3698properly preceded with the binary-encoded length, as the new logging
3699configuration::
3700
3701 #!/usr/bin/env python
3702 import socket, sys, struct
3703
3704 data_to_send = open(sys.argv[1], "r").read()
3705
3706 HOST = 'localhost'
3707 PORT = 9999
3708 s = socket.socket(socket.AF_INET, socket.SOCK_STREAM)
Georg Brandlf6945182008-02-01 11:56:49 +00003709 print("connecting...")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003710 s.connect((HOST, PORT))
Georg Brandlf6945182008-02-01 11:56:49 +00003711 print("sending config...")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003712 s.send(struct.pack(">L", len(data_to_send)))
3713 s.send(data_to_send)
3714 s.close()
Georg Brandlf6945182008-02-01 11:56:49 +00003715 print("complete")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003716
3717
3718More examples
3719-------------
3720
3721Multiple handlers and formatters
3722^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3723
3724Loggers are plain Python objects. The :func:`addHandler` method has no minimum
3725or maximum quota for the number of handlers you may add. Sometimes it will be
3726beneficial for an application to log all messages of all severities to a text
3727file while simultaneously logging errors or above to the console. To set this
3728up, simply configure the appropriate handlers. The logging calls in the
3729application code will remain unchanged. Here is a slight modification to the
3730previous simple module-based configuration example::
3731
3732 import logging
3733
3734 logger = logging.getLogger("simple_example")
3735 logger.setLevel(logging.DEBUG)
3736 # create file handler which logs even debug messages
3737 fh = logging.FileHandler("spam.log")
3738 fh.setLevel(logging.DEBUG)
3739 # create console handler with a higher log level
3740 ch = logging.StreamHandler()
3741 ch.setLevel(logging.ERROR)
3742 # create formatter and add it to the handlers
3743 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3744 ch.setFormatter(formatter)
3745 fh.setFormatter(formatter)
3746 # add the handlers to logger
3747 logger.addHandler(ch)
3748 logger.addHandler(fh)
3749
3750 # "application" code
3751 logger.debug("debug message")
3752 logger.info("info message")
3753 logger.warn("warn message")
3754 logger.error("error message")
3755 logger.critical("critical message")
3756
3757Notice that the "application" code does not care about multiple handlers. All
3758that changed was the addition and configuration of a new handler named *fh*.
3759
3760The ability to create new handlers with higher- or lower-severity filters can be
3761very helpful when writing and testing an application. Instead of using many
3762``print`` statements for debugging, use ``logger.debug``: Unlike the print
3763statements, which you will have to delete or comment out later, the logger.debug
3764statements can remain intact in the source code and remain dormant until you
3765need them again. At that time, the only change that needs to happen is to
3766modify the severity level of the logger and/or handler to debug.
3767
3768
3769Using logging in multiple modules
3770^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3771
3772It was mentioned above that multiple calls to
3773``logging.getLogger('someLogger')`` return a reference to the same logger
3774object. This is true not only within the same module, but also across modules
3775as long as it is in the same Python interpreter process. It is true for
3776references to the same object; additionally, application code can define and
3777configure a parent logger in one module and create (but not configure) a child
3778logger in a separate module, and all logger calls to the child will pass up to
3779the parent. Here is a main module::
3780
3781 import logging
3782 import auxiliary_module
3783
3784 # create logger with "spam_application"
3785 logger = logging.getLogger("spam_application")
3786 logger.setLevel(logging.DEBUG)
3787 # create file handler which logs even debug messages
3788 fh = logging.FileHandler("spam.log")
3789 fh.setLevel(logging.DEBUG)
3790 # create console handler with a higher log level
3791 ch = logging.StreamHandler()
3792 ch.setLevel(logging.ERROR)
3793 # create formatter and add it to the handlers
3794 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3795 fh.setFormatter(formatter)
3796 ch.setFormatter(formatter)
3797 # add the handlers to the logger
3798 logger.addHandler(fh)
3799 logger.addHandler(ch)
3800
3801 logger.info("creating an instance of auxiliary_module.Auxiliary")
3802 a = auxiliary_module.Auxiliary()
3803 logger.info("created an instance of auxiliary_module.Auxiliary")
3804 logger.info("calling auxiliary_module.Auxiliary.do_something")
3805 a.do_something()
3806 logger.info("finished auxiliary_module.Auxiliary.do_something")
3807 logger.info("calling auxiliary_module.some_function()")
3808 auxiliary_module.some_function()
3809 logger.info("done with auxiliary_module.some_function()")
3810
3811Here is the auxiliary module::
3812
3813 import logging
3814
3815 # create logger
3816 module_logger = logging.getLogger("spam_application.auxiliary")
3817
3818 class Auxiliary:
3819 def __init__(self):
3820 self.logger = logging.getLogger("spam_application.auxiliary.Auxiliary")
3821 self.logger.info("creating an instance of Auxiliary")
3822 def do_something(self):
3823 self.logger.info("doing something")
3824 a = 1 + 1
3825 self.logger.info("done doing something")
3826
3827 def some_function():
3828 module_logger.info("received a call to \"some_function\"")
3829
3830The output looks like this::
3831
Christian Heimes043d6f62008-01-07 17:19:16 +00003832 2005-03-23 23:47:11,663 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003833 creating an instance of auxiliary_module.Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00003834 2005-03-23 23:47:11,665 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003835 creating an instance of Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00003836 2005-03-23 23:47:11,665 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003837 created an instance of auxiliary_module.Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00003838 2005-03-23 23:47:11,668 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003839 calling auxiliary_module.Auxiliary.do_something
Christian Heimes043d6f62008-01-07 17:19:16 +00003840 2005-03-23 23:47:11,668 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003841 doing something
Christian Heimes043d6f62008-01-07 17:19:16 +00003842 2005-03-23 23:47:11,669 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003843 done doing something
Christian Heimes043d6f62008-01-07 17:19:16 +00003844 2005-03-23 23:47:11,670 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003845 finished auxiliary_module.Auxiliary.do_something
Christian Heimes043d6f62008-01-07 17:19:16 +00003846 2005-03-23 23:47:11,671 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003847 calling auxiliary_module.some_function()
Christian Heimes043d6f62008-01-07 17:19:16 +00003848 2005-03-23 23:47:11,672 - spam_application.auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003849 received a call to "some_function"
Christian Heimes043d6f62008-01-07 17:19:16 +00003850 2005-03-23 23:47:11,673 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00003851 done with auxiliary_module.some_function()
3852