blob: 4feae2d6f549ad8d6ced63a0e32cc5e1e7aadc46 [file] [log] [blame]
Georg Brandl8ec7f652007-08-15 14:28:01 +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 Brandl8ec7f652007-08-15 14:28:01 +000012.. index:: pair: Errors; logging
13
14.. versionadded:: 2.3
15
16This module defines functions and classes which implement a flexible error
17logging system for applications.
18
19Logging is performed by calling methods on instances of the :class:`Logger`
20class (hereafter called :dfn:`loggers`). Each instance has a name, and they are
Georg Brandla7395032007-10-21 12:15:05 +000021conceptually arranged in a namespace hierarchy using dots (periods) as
Georg Brandl8ec7f652007-08-15 14:28:01 +000022separators. For example, a logger named "scan" is the parent of loggers
23"scan.text", "scan.html" and "scan.pdf". Logger names can be anything you want,
24and indicate the area of an application in which a logged message originates.
25
26Logged messages also have levels of importance associated with them. The default
27levels provided are :const:`DEBUG`, :const:`INFO`, :const:`WARNING`,
28:const:`ERROR` and :const:`CRITICAL`. As a convenience, you indicate the
29importance of a logged message by calling an appropriate method of
30:class:`Logger`. The methods are :meth:`debug`, :meth:`info`, :meth:`warning`,
31:meth:`error` and :meth:`critical`, which mirror the default levels. You are not
32constrained to use these levels: you can specify your own and use a more general
33:class:`Logger` method, :meth:`log`, which takes an explicit level argument.
34
Georg Brandlc37f2882007-12-04 17:46:27 +000035
36Logging tutorial
37----------------
38
39The key benefit of having the logging API provided by a standard library module
40is that all Python modules can participate in logging, so your application log
41can include messages from third-party modules.
42
43It is, of course, possible to log messages with different verbosity levels or to
44different destinations. Support for writing log messages to files, HTTP
45GET/POST locations, email via SMTP, generic sockets, or OS-specific logging
Georg Brandl907a7202008-02-22 12:31:45 +000046mechanisms are all supported by the standard module. You can also create your
Georg Brandlc37f2882007-12-04 17:46:27 +000047own log destination class if you have special requirements not met by any of the
48built-in classes.
49
50Simple examples
51^^^^^^^^^^^^^^^
52
53.. sectionauthor:: Doug Hellmann
54.. (see <http://blog.doughellmann.com/2007/05/pymotw-logging.html>)
55
56Most applications are probably going to want to log to a file, so let's start
57with that case. Using the :func:`basicConfig` function, we can set up the
Vinay Sajip9a26aab2010-06-03 22:34:42 +000058default handler so that debug messages are written to a file (in the example,
59we assume that you have the appropriate permissions to create a file called
Vinay Sajip998cc242010-06-04 13:41:02 +000060*example.log* in the current directory)::
Georg Brandlc37f2882007-12-04 17:46:27 +000061
62 import logging
Vinay Sajip9a26aab2010-06-03 22:34:42 +000063 LOG_FILENAME = 'example.log'
Vinay Sajipf778bec2009-09-22 17:23:41 +000064 logging.basicConfig(filename=LOG_FILENAME,level=logging.DEBUG)
Georg Brandlc37f2882007-12-04 17:46:27 +000065
66 logging.debug('This message should go to the log file')
67
68And now if we open the file and look at what we have, we should find the log
69message::
70
71 DEBUG:root:This message should go to the log file
72
73If you run the script repeatedly, the additional log messages are appended to
Eric Smithe7dbebb2009-06-04 17:58:15 +000074the file. To create a new file each time, you can pass a *filemode* argument to
Georg Brandlc37f2882007-12-04 17:46:27 +000075:func:`basicConfig` with a value of ``'w'``. Rather than managing the file size
76yourself, though, it is simpler to use a :class:`RotatingFileHandler`::
77
78 import glob
79 import logging
80 import logging.handlers
81
Vinay Sajip998cc242010-06-04 13:41:02 +000082 LOG_FILENAME = 'logging_rotatingfile_example.out'
Georg Brandlc37f2882007-12-04 17:46:27 +000083
84 # Set up a specific logger with our desired output level
85 my_logger = logging.getLogger('MyLogger')
86 my_logger.setLevel(logging.DEBUG)
87
88 # Add the log message handler to the logger
89 handler = logging.handlers.RotatingFileHandler(
90 LOG_FILENAME, maxBytes=20, backupCount=5)
91
92 my_logger.addHandler(handler)
93
94 # Log some messages
95 for i in range(20):
96 my_logger.debug('i = %d' % i)
97
98 # See what files are created
99 logfiles = glob.glob('%s*' % LOG_FILENAME)
100
101 for filename in logfiles:
102 print filename
103
104The result should be 6 separate files, each with part of the log history for the
105application::
106
Vinay Sajip998cc242010-06-04 13:41:02 +0000107 logging_rotatingfile_example.out
108 logging_rotatingfile_example.out.1
109 logging_rotatingfile_example.out.2
110 logging_rotatingfile_example.out.3
111 logging_rotatingfile_example.out.4
112 logging_rotatingfile_example.out.5
Georg Brandlc37f2882007-12-04 17:46:27 +0000113
Vinay Sajip998cc242010-06-04 13:41:02 +0000114The most current file is always :file:`logging_rotatingfile_example.out`,
Georg Brandlc37f2882007-12-04 17:46:27 +0000115and each time it reaches the size limit it is renamed with the suffix
116``.1``. Each of the existing backup files is renamed to increment the suffix
Eric Smithe7dbebb2009-06-04 17:58:15 +0000117(``.1`` becomes ``.2``, etc.) and the ``.6`` file is erased.
Georg Brandlc37f2882007-12-04 17:46:27 +0000118
119Obviously this example sets the log length much much too small as an extreme
120example. You would want to set *maxBytes* to an appropriate value.
121
122Another useful feature of the logging API is the ability to produce different
123messages at different log levels. This allows you to instrument your code with
124debug messages, for example, but turning the log level down so that those debug
125messages are not written for your production system. The default levels are
Vinay Sajipa7d44002009-10-28 23:28:16 +0000126``NOTSET``, ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and ``CRITICAL``.
Georg Brandlc37f2882007-12-04 17:46:27 +0000127
128The logger, handler, and log message call each specify a level. The log message
129is only emitted if the handler and logger are configured to emit messages of
130that level or lower. For example, if a message is ``CRITICAL``, and the logger
131is set to ``ERROR``, the message is emitted. If a message is a ``WARNING``, and
132the logger is set to produce only ``ERROR``\s, the message is not emitted::
133
134 import logging
135 import sys
136
137 LEVELS = {'debug': logging.DEBUG,
138 'info': logging.INFO,
139 'warning': logging.WARNING,
140 'error': logging.ERROR,
141 'critical': logging.CRITICAL}
142
143 if len(sys.argv) > 1:
144 level_name = sys.argv[1]
145 level = LEVELS.get(level_name, logging.NOTSET)
146 logging.basicConfig(level=level)
147
148 logging.debug('This is a debug message')
149 logging.info('This is an info message')
150 logging.warning('This is a warning message')
151 logging.error('This is an error message')
152 logging.critical('This is a critical error message')
153
154Run the script with an argument like 'debug' or 'warning' to see which messages
155show up at different levels::
156
157 $ python logging_level_example.py debug
158 DEBUG:root:This is a debug message
159 INFO:root:This is an info message
160 WARNING:root:This is a warning message
161 ERROR:root:This is an error message
162 CRITICAL:root:This is a critical error message
163
164 $ python logging_level_example.py info
165 INFO:root:This is an info message
166 WARNING:root:This is a warning message
167 ERROR:root:This is an error message
168 CRITICAL:root:This is a critical error message
169
170You will notice that these log messages all have ``root`` embedded in them. The
171logging module supports a hierarchy of loggers with different names. An easy
172way to tell where a specific log message comes from is to use a separate logger
173object for each of your modules. Each new logger "inherits" the configuration
174of its parent, and log messages sent to a logger include the name of that
175logger. Optionally, each logger can be configured differently, so that messages
176from different modules are handled in different ways. Let's look at a simple
177example of how to log from different modules so it is easy to trace the source
178of the message::
179
180 import logging
181
182 logging.basicConfig(level=logging.WARNING)
183
184 logger1 = logging.getLogger('package1.module1')
185 logger2 = logging.getLogger('package2.module2')
186
187 logger1.warning('This message comes from one module')
188 logger2.warning('And this message comes from another module')
189
190And the output::
191
192 $ python logging_modules_example.py
193 WARNING:package1.module1:This message comes from one module
194 WARNING:package2.module2:And this message comes from another module
195
196There are many more options for configuring logging, including different log
197message formatting options, having messages delivered to multiple destinations,
198and changing the configuration of a long-running application on the fly using a
199socket interface. All of these options are covered in depth in the library
200module documentation.
201
202Loggers
203^^^^^^^
204
205The logging library takes a modular approach and offers the several categories
206of components: loggers, handlers, filters, and formatters. Loggers expose the
207interface that application code directly uses. Handlers send the log records to
208the appropriate destination. Filters provide a finer grained facility for
209determining which log records to send on to a handler. Formatters specify the
210layout of the resultant log record.
211
212:class:`Logger` objects have a threefold job. First, they expose several
213methods to application code so that applications can log messages at runtime.
214Second, logger objects determine which log messages to act upon based upon
215severity (the default filtering facility) or filter objects. Third, logger
216objects pass along relevant log messages to all interested log handlers.
217
218The most widely used methods on logger objects fall into two categories:
219configuration and message sending.
220
221* :meth:`Logger.setLevel` specifies the lowest-severity log message a logger
222 will handle, where debug is the lowest built-in severity level and critical is
223 the highest built-in severity. For example, if the severity level is info,
224 the logger will handle only info, warning, error, and critical messages and
225 will ignore debug messages.
226
227* :meth:`Logger.addFilter` and :meth:`Logger.removeFilter` add and remove filter
228 objects from the logger object. This tutorial does not address filters.
229
230With the logger object configured, the following methods create log messages:
231
232* :meth:`Logger.debug`, :meth:`Logger.info`, :meth:`Logger.warning`,
233 :meth:`Logger.error`, and :meth:`Logger.critical` all create log records with
234 a message and a level that corresponds to their respective method names. The
235 message is actually a format string, which may contain the standard string
236 substitution syntax of :const:`%s`, :const:`%d`, :const:`%f`, and so on. The
237 rest of their arguments is a list of objects that correspond with the
238 substitution fields in the message. With regard to :const:`**kwargs`, the
239 logging methods care only about a keyword of :const:`exc_info` and use it to
240 determine whether to log exception information.
241
242* :meth:`Logger.exception` creates a log message similar to
243 :meth:`Logger.error`. The difference is that :meth:`Logger.exception` dumps a
244 stack trace along with it. Call this method only from an exception handler.
245
246* :meth:`Logger.log` takes a log level as an explicit argument. This is a
247 little more verbose for logging messages than using the log level convenience
248 methods listed above, but this is how to log at custom log levels.
249
Brett Cannon499969a2008-02-25 05:33:07 +0000250:func:`getLogger` returns a reference to a logger instance with the specified
Vinay Sajip80eed3e2010-07-06 15:08:55 +0000251name if it is provided, or ``root`` if not. The names are period-separated
Georg Brandlc37f2882007-12-04 17:46:27 +0000252hierarchical structures. Multiple calls to :func:`getLogger` with the same name
253will return a reference to the same logger object. Loggers that are further
254down in the hierarchical list are children of loggers higher up in the list.
255For example, given a logger with a name of ``foo``, loggers with names of
Vinay Sajipccd8bc82010-04-06 22:32:37 +0000256``foo.bar``, ``foo.bar.baz``, and ``foo.bam`` are all descendants of ``foo``.
257Child loggers propagate messages up to the handlers associated with their
258ancestor loggers. Because of this, it is unnecessary to define and configure
259handlers for all the loggers an application uses. It is sufficient to
260configure handlers for a top-level logger and create child loggers as needed.
Georg Brandlc37f2882007-12-04 17:46:27 +0000261
262
263Handlers
264^^^^^^^^
265
266:class:`Handler` objects are responsible for dispatching the appropriate log
267messages (based on the log messages' severity) to the handler's specified
268destination. Logger objects can add zero or more handler objects to themselves
269with an :func:`addHandler` method. As an example scenario, an application may
270want to send all log messages to a log file, all log messages of error or higher
271to stdout, and all messages of critical to an email address. This scenario
Georg Brandl907a7202008-02-22 12:31:45 +0000272requires three individual handlers where each handler is responsible for sending
Georg Brandlc37f2882007-12-04 17:46:27 +0000273messages of a specific severity to a specific location.
274
275The standard library includes quite a few handler types; this tutorial uses only
276:class:`StreamHandler` and :class:`FileHandler` in its examples.
277
278There are very few methods in a handler for application developers to concern
279themselves with. The only handler methods that seem relevant for application
280developers who are using the built-in handler objects (that is, not creating
281custom handlers) are the following configuration methods:
282
283* The :meth:`Handler.setLevel` method, just as in logger objects, specifies the
284 lowest severity that will be dispatched to the appropriate destination. Why
285 are there two :func:`setLevel` methods? The level set in the logger
286 determines which severity of messages it will pass to its handlers. The level
287 set in each handler determines which messages that handler will send on.
Vinay Sajipccd8bc82010-04-06 22:32:37 +0000288
289* :func:`setFormatter` selects a Formatter object for this handler to use.
Georg Brandlc37f2882007-12-04 17:46:27 +0000290
291* :func:`addFilter` and :func:`removeFilter` respectively configure and
292 deconfigure filter objects on handlers.
293
Vinay Sajipccd8bc82010-04-06 22:32:37 +0000294Application code should not directly instantiate and use instances of
295:class:`Handler`. Instead, the :class:`Handler` class is a base class that
Vinay Sajip497256b2010-04-07 09:40:52 +0000296defines the interface that all handlers should have and establishes some
Vinay Sajipccd8bc82010-04-06 22:32:37 +0000297default behavior that child classes can use (or override).
Georg Brandlc37f2882007-12-04 17:46:27 +0000298
299
300Formatters
301^^^^^^^^^^
302
303Formatter objects configure the final order, structure, and contents of the log
Brett Cannon499969a2008-02-25 05:33:07 +0000304message. Unlike the base :class:`logging.Handler` class, application code may
Georg Brandlc37f2882007-12-04 17:46:27 +0000305instantiate formatter classes, although you could likely subclass the formatter
306if your application needs special behavior. The constructor takes two optional
307arguments: a message format string and a date format string. If there is no
308message format string, the default is to use the raw message. If there is no
309date format string, the default date format is::
310
311 %Y-%m-%d %H:%M:%S
312
313with the milliseconds tacked on at the end.
314
315The message format string uses ``%(<dictionary key>)s`` styled string
Vinay Sajip4b782332009-01-19 06:49:19 +0000316substitution; the possible keys are documented in :ref:`formatter`.
Georg Brandlc37f2882007-12-04 17:46:27 +0000317
318The following message format string will log the time in a human-readable
319format, the severity of the message, and the contents of the message, in that
320order::
321
322 "%(asctime)s - %(levelname)s - %(message)s"
323
Vinay Sajip8d8e6152010-08-30 18:10:03 +0000324Formatters use a user-configurable function to convert the creation time of a
325record to a tuple. By default, :func:`time.localtime` is used; to change this
326for a particular formatter instance, set the ``converter`` attribute of the
327instance to a function with the same signature as :func:`time.localtime` or
328:func:`time.gmtime`. To change it for all formatters, for example if you want
329all logging times to be shown in GMT, set the ``converter`` attribute in the
330Formatter class (to ``time.gmtime`` for GMT display).
331
Georg Brandlc37f2882007-12-04 17:46:27 +0000332
333Configuring Logging
334^^^^^^^^^^^^^^^^^^^
335
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +0000336Programmers can configure logging in three ways:
337
3381. Creating loggers, handlers, and formatters explicitly using Python
339 code that calls the configuration methods listed above.
3402. Creating a logging config file and reading it using the :func:`fileConfig`
341 function.
3423. Creating a dictionary of configuration information and passing it
343 to the :func:`dictConfig` function.
344
345The following example configures a very simple logger, a console
Vinay Sajipa38cd522010-05-18 08:16:27 +0000346handler, and a simple formatter using Python code::
Georg Brandlc37f2882007-12-04 17:46:27 +0000347
348 import logging
349
350 # create logger
351 logger = logging.getLogger("simple_example")
352 logger.setLevel(logging.DEBUG)
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +0000353
Georg Brandlc37f2882007-12-04 17:46:27 +0000354 # create console handler and set level to debug
355 ch = logging.StreamHandler()
356 ch.setLevel(logging.DEBUG)
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +0000357
Georg Brandlc37f2882007-12-04 17:46:27 +0000358 # create formatter
359 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +0000360
Georg Brandlc37f2882007-12-04 17:46:27 +0000361 # add formatter to ch
362 ch.setFormatter(formatter)
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +0000363
Georg Brandlc37f2882007-12-04 17:46:27 +0000364 # add ch to logger
365 logger.addHandler(ch)
366
367 # "application" code
368 logger.debug("debug message")
369 logger.info("info message")
370 logger.warn("warn message")
371 logger.error("error message")
372 logger.critical("critical message")
373
374Running this module from the command line produces the following output::
375
376 $ python simple_logging_module.py
377 2005-03-19 15:10:26,618 - simple_example - DEBUG - debug message
378 2005-03-19 15:10:26,620 - simple_example - INFO - info message
379 2005-03-19 15:10:26,695 - simple_example - WARNING - warn message
380 2005-03-19 15:10:26,697 - simple_example - ERROR - error message
381 2005-03-19 15:10:26,773 - simple_example - CRITICAL - critical message
382
383The following Python module creates a logger, handler, and formatter nearly
384identical to those in the example listed above, with the only difference being
385the names of the objects::
386
387 import logging
388 import logging.config
389
390 logging.config.fileConfig("logging.conf")
391
392 # create logger
393 logger = logging.getLogger("simpleExample")
394
395 # "application" code
396 logger.debug("debug message")
397 logger.info("info message")
398 logger.warn("warn message")
399 logger.error("error message")
400 logger.critical("critical message")
401
402Here is the logging.conf file::
403
404 [loggers]
405 keys=root,simpleExample
406
407 [handlers]
408 keys=consoleHandler
409
410 [formatters]
411 keys=simpleFormatter
412
413 [logger_root]
414 level=DEBUG
415 handlers=consoleHandler
416
417 [logger_simpleExample]
418 level=DEBUG
419 handlers=consoleHandler
420 qualname=simpleExample
421 propagate=0
422
423 [handler_consoleHandler]
424 class=StreamHandler
425 level=DEBUG
426 formatter=simpleFormatter
427 args=(sys.stdout,)
428
429 [formatter_simpleFormatter]
430 format=%(asctime)s - %(name)s - %(levelname)s - %(message)s
431 datefmt=
432
433The output is nearly identical to that of the non-config-file-based example::
434
435 $ python simple_logging_config.py
436 2005-03-19 15:38:55,977 - simpleExample - DEBUG - debug message
437 2005-03-19 15:38:55,979 - simpleExample - INFO - info message
438 2005-03-19 15:38:56,054 - simpleExample - WARNING - warn message
439 2005-03-19 15:38:56,055 - simpleExample - ERROR - error message
440 2005-03-19 15:38:56,130 - simpleExample - CRITICAL - critical message
441
442You can see that the config file approach has a few advantages over the Python
443code approach, mainly separation of configuration and code and the ability of
444noncoders to easily modify the logging properties.
445
Vinay Sajip0e6e97d2010-02-04 20:23:45 +0000446Note that the class names referenced in config files need to be either relative
447to the logging module, or absolute values which can be resolved using normal
Georg Brandlf6d367452010-03-12 10:02:03 +0000448import mechanisms. Thus, you could use either :class:`handlers.WatchedFileHandler`
449(relative to the logging module) or :class:`mypackage.mymodule.MyHandler` (for a
450class defined in package :mod:`mypackage` and module :mod:`mymodule`, where
451:mod:`mypackage` is available on the Python import path).
Vinay Sajip0e6e97d2010-02-04 20:23:45 +0000452
Vinay Sajipc76defc2010-05-21 17:41:34 +0000453.. versionchanged:: 2.7
454
455In Python 2.7, a new means of configuring logging has been introduced, using
456dictionaries to hold configuration information. This provides a superset of the
457functionality of the config-file-based approach outlined above, and is the
458recommended configuration method for new applications and deployments. Because
459a Python dictionary is used to hold configuration information, and since you
460can populate that dictionary using different means, you have more options for
461configuration. For example, you can use a configuration file in JSON format,
462or, if you have access to YAML processing functionality, a file in YAML
463format, to populate the configuration dictionary. Or, of course, you can
464construct the dictionary in Python code, receive it in pickled form over a
465socket, or use whatever approach makes sense for your application.
466
467Here's an example of the same configuration as above, in YAML format for
468the new dictionary-based approach::
469
470 version: 1
471 formatters:
472 simple:
473 format: format=%(asctime)s - %(name)s - %(levelname)s - %(message)s
474 handlers:
475 console:
476 class: logging.StreamHandler
477 level: DEBUG
478 formatter: simple
479 stream: ext://sys.stdout
480 loggers:
481 simpleExample:
482 level: DEBUG
483 handlers: [console]
484 propagate: no
485 root:
486 level: DEBUG
487 handlers: [console]
488
489For more information about logging using a dictionary, see
490:ref:`logging-config-api`.
491
Vinay Sajip99505c82009-01-10 13:38:04 +0000492.. _library-config:
493
Vinay Sajip34bfda52008-09-01 15:08:07 +0000494Configuring Logging for a Library
495^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
496
497When developing a library which uses logging, some consideration needs to be
498given to its configuration. If the using application does not use logging, and
499library code makes logging calls, then a one-off message "No handlers could be
500found for logger X.Y.Z" is printed to the console. This message is intended
501to catch mistakes in logging configuration, but will confuse an application
502developer who is not aware of logging by the library.
503
504In addition to documenting how a library uses logging, a good way to configure
505library logging so that it does not cause a spurious message is to add a
506handler which does nothing. This avoids the message being printed, since a
507handler will be found: it just doesn't produce any output. If the library user
508configures logging for application use, presumably that configuration will add
509some handlers, and if levels are suitably configured then logging calls made
510in library code will send output to those handlers, as normal.
511
512A do-nothing handler can be simply defined as follows::
513
514 import logging
515
516 class NullHandler(logging.Handler):
517 def emit(self, record):
518 pass
519
520An instance of this handler should be added to the top-level logger of the
521logging namespace used by the library. If all logging by a library *foo* is
522done using loggers with names matching "foo.x.y", then the code::
523
524 import logging
525
526 h = NullHandler()
527 logging.getLogger("foo").addHandler(h)
528
529should have the desired effect. If an organisation produces a number of
530libraries, then the logger name specified can be "orgname.foo" rather than
531just "foo".
532
Vinay Sajip47ca1222010-09-27 13:53:47 +0000533**PLEASE NOTE:** It is strongly advised that you *do not add any handlers other
534than* :class:`NullHandler` *to your library's loggers*. This is because the
535configuration of handlers is the prerogative of the application developer who
536uses your library. The application developer knows their target audience and
537what handlers are most appropriate for their application: if you add handlers
538"under the hood", you might well interfere with their ability to carry out
539unit tests and deliver logs which suit their requirements.
540
Vinay Sajip213faca2008-12-03 23:22:58 +0000541.. versionadded:: 2.7
Georg Brandl52f83952011-02-25 10:39:23 +0000542 The :class:`NullHandler` class.
Vinay Sajip213faca2008-12-03 23:22:58 +0000543
Georg Brandlc37f2882007-12-04 17:46:27 +0000544
545Logging Levels
546--------------
547
Georg Brandl8ec7f652007-08-15 14:28:01 +0000548The numeric values of logging levels are given in the following table. These are
549primarily of interest if you want to define your own levels, and need them to
550have specific values relative to the predefined levels. If you define a level
551with the same numeric value, it overwrites the predefined value; the predefined
552name is lost.
553
554+--------------+---------------+
555| Level | Numeric value |
556+==============+===============+
557| ``CRITICAL`` | 50 |
558+--------------+---------------+
559| ``ERROR`` | 40 |
560+--------------+---------------+
561| ``WARNING`` | 30 |
562+--------------+---------------+
563| ``INFO`` | 20 |
564+--------------+---------------+
565| ``DEBUG`` | 10 |
566+--------------+---------------+
567| ``NOTSET`` | 0 |
568+--------------+---------------+
569
570Levels can also be associated with loggers, being set either by the developer or
571through loading a saved logging configuration. When a logging method is called
572on a logger, the logger compares its own level with the level associated with
573the method call. If the logger's level is higher than the method call's, no
574logging message is actually generated. This is the basic mechanism controlling
575the verbosity of logging output.
576
577Logging messages are encoded as instances of the :class:`LogRecord` class. When
578a logger decides to actually log an event, a :class:`LogRecord` instance is
579created from the logging message.
580
581Logging messages are subjected to a dispatch mechanism through the use of
582:dfn:`handlers`, which are instances of subclasses of the :class:`Handler`
583class. Handlers are responsible for ensuring that a logged message (in the form
584of a :class:`LogRecord`) ends up in a particular location (or set of locations)
585which is useful for the target audience for that message (such as end users,
586support desk staff, system administrators, developers). Handlers are passed
587:class:`LogRecord` instances intended for particular destinations. Each logger
588can have zero, one or more handlers associated with it (via the
589:meth:`addHandler` method of :class:`Logger`). In addition to any handlers
590directly associated with a logger, *all handlers associated with all ancestors
Vinay Sajipccd8bc82010-04-06 22:32:37 +0000591of the logger* are called to dispatch the message (unless the *propagate* flag
592for a logger is set to a false value, at which point the passing to ancestor
593handlers stops).
Georg Brandl8ec7f652007-08-15 14:28:01 +0000594
595Just as for loggers, handlers can have levels associated with them. A handler's
596level acts as a filter in the same way as a logger's level does. If a handler
597decides to actually dispatch an event, the :meth:`emit` method is used to send
598the message to its destination. Most user-defined subclasses of :class:`Handler`
599will need to override this :meth:`emit`.
600
Vinay Sajip89e1ae22010-09-17 10:09:04 +0000601.. _custom-levels:
602
603Custom Levels
604^^^^^^^^^^^^^
605
606Defining your own levels is possible, but should not be necessary, as the
607existing levels have been chosen on the basis of practical experience.
608However, if you are convinced that you need custom levels, great care should
609be exercised when doing this, and it is possibly *a very bad idea to define
610custom levels if you are developing a library*. That's because if multiple
611library authors all define their own custom levels, there is a chance that
612the logging output from such multiple libraries used together will be
613difficult for the using developer to control and/or interpret, because a
614given numeric value might mean different things for different libraries.
615
616
Vinay Sajipb5902e62009-01-15 22:48:13 +0000617Useful Handlers
618---------------
619
Georg Brandl8ec7f652007-08-15 14:28:01 +0000620In addition to the base :class:`Handler` class, many useful subclasses are
621provided:
622
Vinay Sajip4b782332009-01-19 06:49:19 +0000623#. :ref:`stream-handler` instances send error messages to streams (file-like
Georg Brandl8ec7f652007-08-15 14:28:01 +0000624 objects).
625
Vinay Sajip4b782332009-01-19 06:49:19 +0000626#. :ref:`file-handler` instances send error messages to disk files.
Vinay Sajipb1a15e42009-01-15 23:04:47 +0000627
Vinay Sajipb5902e62009-01-15 22:48:13 +0000628#. :class:`BaseRotatingHandler` is the base class for handlers that
Vinay Sajip99234c52009-01-12 20:36:18 +0000629 rotate log files at a certain point. It is not meant to be instantiated
Vinay Sajip4b782332009-01-19 06:49:19 +0000630 directly. Instead, use :ref:`rotating-file-handler` or
631 :ref:`timed-rotating-file-handler`.
Vinay Sajipc2211ad2009-01-10 19:22:57 +0000632
Vinay Sajip4b782332009-01-19 06:49:19 +0000633#. :ref:`rotating-file-handler` instances send error messages to disk
Vinay Sajipb5902e62009-01-15 22:48:13 +0000634 files, with support for maximum log file sizes and log file rotation.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000635
Vinay Sajip4b782332009-01-19 06:49:19 +0000636#. :ref:`timed-rotating-file-handler` instances send error messages to
Vinay Sajipb5902e62009-01-15 22:48:13 +0000637 disk files, rotating the log file at certain timed intervals.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000638
Vinay Sajip4b782332009-01-19 06:49:19 +0000639#. :ref:`socket-handler` instances send error messages to TCP/IP
Vinay Sajipb5902e62009-01-15 22:48:13 +0000640 sockets.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000641
Vinay Sajip4b782332009-01-19 06:49:19 +0000642#. :ref:`datagram-handler` instances send error messages to UDP
Vinay Sajipb5902e62009-01-15 22:48:13 +0000643 sockets.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000644
Vinay Sajip4b782332009-01-19 06:49:19 +0000645#. :ref:`smtp-handler` instances send error messages to a designated
Vinay Sajipb5902e62009-01-15 22:48:13 +0000646 email address.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000647
Vinay Sajip4b782332009-01-19 06:49:19 +0000648#. :ref:`syslog-handler` instances send error messages to a Unix
Vinay Sajipb5902e62009-01-15 22:48:13 +0000649 syslog daemon, possibly on a remote machine.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000650
Vinay Sajip4b782332009-01-19 06:49:19 +0000651#. :ref:`nt-eventlog-handler` instances send error messages to a
Vinay Sajipb5902e62009-01-15 22:48:13 +0000652 Windows NT/2000/XP event log.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000653
Vinay Sajip4b782332009-01-19 06:49:19 +0000654#. :ref:`memory-handler` instances send error messages to a buffer
Vinay Sajipb5902e62009-01-15 22:48:13 +0000655 in memory, which is flushed whenever specific criteria are met.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000656
Vinay Sajip4b782332009-01-19 06:49:19 +0000657#. :ref:`http-handler` instances send error messages to an HTTP
Vinay Sajipb5902e62009-01-15 22:48:13 +0000658 server using either ``GET`` or ``POST`` semantics.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000659
Vinay Sajip4b782332009-01-19 06:49:19 +0000660#. :ref:`watched-file-handler` instances watch the file they are
Vinay Sajipb5902e62009-01-15 22:48:13 +0000661 logging to. If the file changes, it is closed and reopened using the file
662 name. This handler is only useful on Unix-like systems; Windows does not
663 support the underlying mechanism used.
Vinay Sajipc2211ad2009-01-10 19:22:57 +0000664
Vinay Sajip4b782332009-01-19 06:49:19 +0000665#. :ref:`null-handler` instances do nothing with error messages. They are used
Vinay Sajip213faca2008-12-03 23:22:58 +0000666 by library developers who want to use logging, but want to avoid the "No
667 handlers could be found for logger XXX" message which can be displayed if
Vinay Sajip99505c82009-01-10 13:38:04 +0000668 the library user has not configured logging. See :ref:`library-config` for
669 more information.
Vinay Sajip213faca2008-12-03 23:22:58 +0000670
671.. versionadded:: 2.7
Georg Brandl52f83952011-02-25 10:39:23 +0000672 The :class:`NullHandler` class.
Vinay Sajip213faca2008-12-03 23:22:58 +0000673
Vinay Sajip7cc97552008-12-30 07:01:25 +0000674The :class:`NullHandler`, :class:`StreamHandler` and :class:`FileHandler`
675classes are defined in the core logging package. The other handlers are
676defined in a sub- module, :mod:`logging.handlers`. (There is also another
677sub-module, :mod:`logging.config`, for configuration functionality.)
Georg Brandl8ec7f652007-08-15 14:28:01 +0000678
679Logged messages are formatted for presentation through instances of the
680:class:`Formatter` class. They are initialized with a format string suitable for
681use with the % operator and a dictionary.
682
683For formatting multiple messages in a batch, instances of
684:class:`BufferingFormatter` can be used. In addition to the format string (which
685is applied to each message in the batch), there is provision for header and
686trailer format strings.
687
688When filtering based on logger level and/or handler level is not enough,
689instances of :class:`Filter` can be added to both :class:`Logger` and
690:class:`Handler` instances (through their :meth:`addFilter` method). Before
691deciding to process a message further, both loggers and handlers consult all
692their filters for permission. If any filter returns a false value, the message
693is not processed further.
694
695The basic :class:`Filter` functionality allows filtering by specific logger
696name. If this feature is used, messages sent to the named logger and its
697children are allowed through the filter, and all others dropped.
698
Vinay Sajipb5902e62009-01-15 22:48:13 +0000699Module-Level Functions
700----------------------
701
Georg Brandl8ec7f652007-08-15 14:28:01 +0000702In addition to the classes described above, there are a number of module- level
703functions.
704
705
706.. function:: getLogger([name])
707
708 Return a logger with the specified name or, if no name is specified, return a
709 logger which is the root logger of the hierarchy. If specified, the name is
710 typically a dot-separated hierarchical name like *"a"*, *"a.b"* or *"a.b.c.d"*.
711 Choice of these names is entirely up to the developer who is using logging.
712
713 All calls to this function with a given name return the same logger instance.
714 This means that logger instances never need to be passed between different parts
715 of an application.
716
717
718.. function:: getLoggerClass()
719
720 Return either the standard :class:`Logger` class, or the last class passed to
721 :func:`setLoggerClass`. This function may be called from within a new class
722 definition, to ensure that installing a customised :class:`Logger` class will
723 not undo customisations already applied by other code. For example::
724
725 class MyLogger(logging.getLoggerClass()):
726 # ... override behaviour here
727
728
729.. function:: debug(msg[, *args[, **kwargs]])
730
731 Logs a message with level :const:`DEBUG` on the root logger. The *msg* is the
732 message format string, and the *args* are the arguments which are merged into
733 *msg* using the string formatting operator. (Note that this means that you can
734 use keywords in the format string, together with a single dictionary argument.)
735
736 There are two keyword arguments in *kwargs* which are inspected: *exc_info*
737 which, if it does not evaluate as false, causes exception information to be
738 added to the logging message. If an exception tuple (in the format returned by
739 :func:`sys.exc_info`) is provided, it is used; otherwise, :func:`sys.exc_info`
740 is called to get the exception information.
741
742 The other optional keyword argument is *extra* which can be used to pass a
743 dictionary which is used to populate the __dict__ of the LogRecord created for
744 the logging event with user-defined attributes. These custom attributes can then
745 be used as you like. For example, they could be incorporated into logged
746 messages. For example::
747
748 FORMAT = "%(asctime)-15s %(clientip)s %(user)-8s %(message)s"
749 logging.basicConfig(format=FORMAT)
750 d = {'clientip': '192.168.0.1', 'user': 'fbloggs'}
751 logging.warning("Protocol problem: %s", "connection reset", extra=d)
752
Vinay Sajipfe08e6f2010-09-11 10:25:28 +0000753 would print something like::
Georg Brandl8ec7f652007-08-15 14:28:01 +0000754
755 2006-02-08 22:20:02,165 192.168.0.1 fbloggs Protocol problem: connection reset
756
757 The keys in the dictionary passed in *extra* should not clash with the keys used
758 by the logging system. (See the :class:`Formatter` documentation for more
759 information on which keys are used by the logging system.)
760
761 If you choose to use these attributes in logged messages, you need to exercise
762 some care. In the above example, for instance, the :class:`Formatter` has been
763 set up with a format string which expects 'clientip' and 'user' in the attribute
764 dictionary of the LogRecord. If these are missing, the message will not be
765 logged because a string formatting exception will occur. So in this case, you
766 always need to pass the *extra* dictionary with these keys.
767
768 While this might be annoying, this feature is intended for use in specialized
769 circumstances, such as multi-threaded servers where the same code executes in
770 many contexts, and interesting conditions which arise are dependent on this
771 context (such as remote client IP address and authenticated user name, in the
772 above example). In such circumstances, it is likely that specialized
773 :class:`Formatter`\ s would be used with particular :class:`Handler`\ s.
774
775 .. versionchanged:: 2.5
776 *extra* was added.
777
778
779.. function:: info(msg[, *args[, **kwargs]])
780
781 Logs a message with level :const:`INFO` on the root logger. The arguments are
782 interpreted as for :func:`debug`.
783
784
785.. function:: warning(msg[, *args[, **kwargs]])
786
787 Logs a message with level :const:`WARNING` on the root logger. The arguments are
788 interpreted as for :func:`debug`.
789
790
791.. function:: error(msg[, *args[, **kwargs]])
792
793 Logs a message with level :const:`ERROR` on the root logger. The arguments are
794 interpreted as for :func:`debug`.
795
796
797.. function:: critical(msg[, *args[, **kwargs]])
798
799 Logs a message with level :const:`CRITICAL` on the root logger. The arguments
800 are interpreted as for :func:`debug`.
801
802
803.. function:: exception(msg[, *args])
804
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
809
810.. function:: log(level, msg[, *args[, **kwargs]])
811
812 Logs a message with level *level* on the root logger. The other arguments are
813 interpreted as for :func:`debug`.
814
Vinay Sajip89e1ae22010-09-17 10:09:04 +0000815 PLEASE NOTE: The above module-level functions which delegate to the root
816 logger should *not* be used in threads, in versions of Python earlier than
817 2.7.1 and 3.2, unless at least one handler has been added to the root
818 logger *before* the threads are started. These convenience functions call
819 :func:`basicConfig` to ensure that at least one handler is available; in
820 earlier versions of Python, this can (under rare circumstances) lead to
821 handlers being added multiple times to the root logger, which can in turn
822 lead to multiple messages for the same event.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000823
824.. function:: disable(lvl)
825
826 Provides an overriding level *lvl* for all loggers which takes precedence over
827 the logger's own level. When the need arises to temporarily throttle logging
Vinay Sajip2060e422010-03-17 15:05:57 +0000828 output down across the whole application, this function can be useful. Its
829 effect is to disable all logging calls of severity *lvl* and below, so that
830 if you call it with a value of INFO, then all INFO and DEBUG events would be
831 discarded, whereas those of severity WARNING and above would be processed
832 according to the logger's effective level.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000833
834
835.. function:: addLevelName(lvl, levelName)
836
837 Associates level *lvl* with text *levelName* in an internal dictionary, which is
838 used to map numeric levels to a textual representation, for example when a
839 :class:`Formatter` formats a message. This function can also be used to define
840 your own levels. The only constraints are that all levels used must be
841 registered using this function, levels should be positive integers and they
842 should increase in increasing order of severity.
843
Vinay Sajip89e1ae22010-09-17 10:09:04 +0000844 NOTE: If you are thinking of defining your own levels, please see the section
845 on :ref:`custom-levels`.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000846
847.. function:: getLevelName(lvl)
848
849 Returns the textual representation of logging level *lvl*. If the level is one
850 of the predefined levels :const:`CRITICAL`, :const:`ERROR`, :const:`WARNING`,
851 :const:`INFO` or :const:`DEBUG` then you get the corresponding string. If you
852 have associated levels with names using :func:`addLevelName` then the name you
853 have associated with *lvl* is returned. If a numeric value corresponding to one
854 of the defined levels is passed in, the corresponding string representation is
855 returned. Otherwise, the string "Level %s" % lvl is returned.
856
857
858.. function:: makeLogRecord(attrdict)
859
860 Creates and returns a new :class:`LogRecord` instance whose attributes are
861 defined by *attrdict*. This function is useful for taking a pickled
862 :class:`LogRecord` attribute dictionary, sent over a socket, and reconstituting
863 it as a :class:`LogRecord` instance at the receiving end.
864
865
866.. function:: basicConfig([**kwargs])
867
868 Does basic configuration for the logging system by creating a
869 :class:`StreamHandler` with a default :class:`Formatter` and adding it to the
Vinay Sajip1c77b7f2009-10-10 20:32:36 +0000870 root logger. The functions :func:`debug`, :func:`info`, :func:`warning`,
Georg Brandl8ec7f652007-08-15 14:28:01 +0000871 :func:`error` and :func:`critical` will call :func:`basicConfig` automatically
872 if no handlers are defined for the root logger.
873
Vinay Sajip1c77b7f2009-10-10 20:32:36 +0000874 This function does nothing if the root logger already has handlers
875 configured for it.
Georg Brandldfb5bbd2008-05-09 06:18:27 +0000876
Georg Brandl8ec7f652007-08-15 14:28:01 +0000877 .. versionchanged:: 2.4
878 Formerly, :func:`basicConfig` did not take any keyword arguments.
879
Vinay Sajip89e1ae22010-09-17 10:09:04 +0000880 PLEASE NOTE: This function should be called from the main thread
881 before other threads are started. In versions of Python prior to
882 2.7.1 and 3.2, if this function is called from multiple threads,
883 it is possible (in rare circumstances) that a handler will be added
884 to the root logger more than once, leading to unexpected results
885 such as messages being duplicated in the log.
886
Georg Brandl8ec7f652007-08-15 14:28:01 +0000887 The following keyword arguments are supported.
888
889 +--------------+---------------------------------------------+
890 | Format | Description |
891 +==============+=============================================+
892 | ``filename`` | Specifies that a FileHandler be created, |
893 | | using the specified filename, rather than a |
894 | | StreamHandler. |
895 +--------------+---------------------------------------------+
896 | ``filemode`` | Specifies the mode to open the file, if |
897 | | filename is specified (if filemode is |
898 | | unspecified, it defaults to 'a'). |
899 +--------------+---------------------------------------------+
900 | ``format`` | Use the specified format string for the |
901 | | handler. |
902 +--------------+---------------------------------------------+
903 | ``datefmt`` | Use the specified date/time format. |
904 +--------------+---------------------------------------------+
905 | ``level`` | Set the root logger level to the specified |
906 | | level. |
907 +--------------+---------------------------------------------+
908 | ``stream`` | Use the specified stream to initialize the |
909 | | StreamHandler. Note that this argument is |
910 | | incompatible with 'filename' - if both are |
911 | | present, 'stream' is ignored. |
912 +--------------+---------------------------------------------+
913
914
915.. function:: shutdown()
916
917 Informs the logging system to perform an orderly shutdown by flushing and
Vinay Sajip91f0ee42008-03-16 21:35:58 +0000918 closing all handlers. This should be called at application exit and no
919 further use of the logging system should be made after this call.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000920
921
922.. function:: setLoggerClass(klass)
923
924 Tells the logging system to use the class *klass* when instantiating a logger.
925 The class should define :meth:`__init__` such that only a name argument is
926 required, and the :meth:`__init__` should call :meth:`Logger.__init__`. This
927 function is typically called before any loggers are instantiated by applications
928 which need to use custom logger behavior.
929
930
Georg Brandl52f83952011-02-25 10:39:23 +0000931
Georg Brandl8ec7f652007-08-15 14:28:01 +0000932.. seealso::
933
934 :pep:`282` - A Logging System
935 The proposal which described this feature for inclusion in the Python standard
936 library.
937
Georg Brandl2b92f6b2007-12-06 01:52:24 +0000938 `Original Python logging package <http://www.red-dove.com/python_logging.html>`_
Georg Brandl8ec7f652007-08-15 14:28:01 +0000939 This is the original source for the :mod:`logging` package. The version of the
940 package available from this site is suitable for use with Python 1.5.2, 2.1.x
941 and 2.2.x, which do not include the :mod:`logging` package in the standard
942 library.
943
Vinay Sajip4b782332009-01-19 06:49:19 +0000944.. _logger:
Georg Brandl8ec7f652007-08-15 14:28:01 +0000945
946Logger Objects
947--------------
948
949Loggers have the following attributes and methods. Note that Loggers are never
950instantiated directly, but always through the module-level function
951``logging.getLogger(name)``.
952
953
954.. attribute:: Logger.propagate
955
956 If this evaluates to false, logging messages are not passed by this logger or by
Vinay Sajipccd8bc82010-04-06 22:32:37 +0000957 its child loggers to the handlers of higher level (ancestor) loggers. The
958 constructor sets this attribute to 1.
Georg Brandl8ec7f652007-08-15 14:28:01 +0000959
960
961.. method:: Logger.setLevel(lvl)
962
963 Sets the threshold for this logger to *lvl*. Logging messages which are less
964 severe than *lvl* will be ignored. When a logger is created, the level is set to
965 :const:`NOTSET` (which causes all messages to be processed when the logger is
966 the root logger, or delegation to the parent when the logger is a non-root
967 logger). Note that the root logger is created with level :const:`WARNING`.
968
969 The term "delegation to the parent" means that if a logger has a level of
970 NOTSET, its chain of ancestor loggers is traversed until either an ancestor with
971 a level other than NOTSET is found, or the root is reached.
972
973 If an ancestor is found with a level other than NOTSET, then that ancestor's
974 level is treated as the effective level of the logger where the ancestor search
975 began, and is used to determine how a logging event is handled.
976
977 If the root is reached, and it has a level of NOTSET, then all messages will be
978 processed. Otherwise, the root's level will be used as the effective level.
979
980
981.. method:: Logger.isEnabledFor(lvl)
982
983 Indicates if a message of severity *lvl* would be processed by this logger.
984 This method checks first the module-level level set by
985 ``logging.disable(lvl)`` and then the logger's effective level as determined
986 by :meth:`getEffectiveLevel`.
987
988
989.. method:: Logger.getEffectiveLevel()
990
991 Indicates the effective level for this logger. If a value other than
992 :const:`NOTSET` has been set using :meth:`setLevel`, it is returned. Otherwise,
993 the hierarchy is traversed towards the root until a value other than
994 :const:`NOTSET` is found, and that value is returned.
995
996
Vinay Sajip804899b2010-03-22 15:29:01 +0000997.. method:: Logger.getChild(suffix)
998
999 Returns a logger which is a descendant to this logger, as determined by the suffix.
1000 Thus, ``logging.getLogger('abc').getChild('def.ghi')`` would return the same
1001 logger as would be returned by ``logging.getLogger('abc.def.ghi')``. This is a
1002 convenience method, useful when the parent logger is named using e.g. ``__name__``
1003 rather than a literal string.
1004
1005 .. versionadded:: 2.7
1006
Georg Brandl8ec7f652007-08-15 14:28:01 +00001007.. method:: Logger.debug(msg[, *args[, **kwargs]])
1008
1009 Logs a message with level :const:`DEBUG` on this logger. The *msg* is the
1010 message format string, and the *args* are the arguments which are merged into
1011 *msg* using the string formatting operator. (Note that this means that you can
1012 use keywords in the format string, together with a single dictionary argument.)
1013
1014 There are two keyword arguments in *kwargs* which are inspected: *exc_info*
1015 which, if it does not evaluate as false, causes exception information to be
1016 added to the logging message. If an exception tuple (in the format returned by
1017 :func:`sys.exc_info`) is provided, it is used; otherwise, :func:`sys.exc_info`
1018 is called to get the exception information.
1019
1020 The other optional keyword argument is *extra* which can be used to pass a
1021 dictionary which is used to populate the __dict__ of the LogRecord created for
1022 the logging event with user-defined attributes. These custom attributes can then
1023 be used as you like. For example, they could be incorporated into logged
1024 messages. For example::
1025
1026 FORMAT = "%(asctime)-15s %(clientip)s %(user)-8s %(message)s"
1027 logging.basicConfig(format=FORMAT)
Neal Norwitz53004282007-10-23 05:44:27 +00001028 d = { 'clientip' : '192.168.0.1', 'user' : 'fbloggs' }
Georg Brandl8ec7f652007-08-15 14:28:01 +00001029 logger = logging.getLogger("tcpserver")
1030 logger.warning("Protocol problem: %s", "connection reset", extra=d)
1031
1032 would print something like ::
1033
1034 2006-02-08 22:20:02,165 192.168.0.1 fbloggs Protocol problem: connection reset
1035
1036 The keys in the dictionary passed in *extra* should not clash with the keys used
1037 by the logging system. (See the :class:`Formatter` documentation for more
1038 information on which keys are used by the logging system.)
1039
1040 If you choose to use these attributes in logged messages, you need to exercise
1041 some care. In the above example, for instance, the :class:`Formatter` has been
1042 set up with a format string which expects 'clientip' and 'user' in the attribute
1043 dictionary of the LogRecord. If these are missing, the message will not be
1044 logged because a string formatting exception will occur. So in this case, you
1045 always need to pass the *extra* dictionary with these keys.
1046
1047 While this might be annoying, this feature is intended for use in specialized
1048 circumstances, such as multi-threaded servers where the same code executes in
1049 many contexts, and interesting conditions which arise are dependent on this
1050 context (such as remote client IP address and authenticated user name, in the
1051 above example). In such circumstances, it is likely that specialized
1052 :class:`Formatter`\ s would be used with particular :class:`Handler`\ s.
1053
1054 .. versionchanged:: 2.5
1055 *extra* was added.
1056
1057
1058.. method:: Logger.info(msg[, *args[, **kwargs]])
1059
1060 Logs a message with level :const:`INFO` on this logger. The arguments are
1061 interpreted as for :meth:`debug`.
1062
1063
1064.. method:: Logger.warning(msg[, *args[, **kwargs]])
1065
1066 Logs a message with level :const:`WARNING` on this logger. The arguments are
1067 interpreted as for :meth:`debug`.
1068
1069
1070.. method:: Logger.error(msg[, *args[, **kwargs]])
1071
1072 Logs a message with level :const:`ERROR` on this logger. The arguments are
1073 interpreted as for :meth:`debug`.
1074
1075
1076.. method:: Logger.critical(msg[, *args[, **kwargs]])
1077
1078 Logs a message with level :const:`CRITICAL` on this logger. The arguments are
1079 interpreted as for :meth:`debug`.
1080
1081
1082.. method:: Logger.log(lvl, msg[, *args[, **kwargs]])
1083
1084 Logs a message with integer level *lvl* on this logger. The other arguments are
1085 interpreted as for :meth:`debug`.
1086
1087
1088.. method:: Logger.exception(msg[, *args])
1089
1090 Logs a message with level :const:`ERROR` on this logger. The arguments are
1091 interpreted as for :meth:`debug`. Exception info is added to the logging
1092 message. This method should only be called from an exception handler.
1093
1094
1095.. method:: Logger.addFilter(filt)
1096
1097 Adds the specified filter *filt* to this logger.
1098
1099
1100.. method:: Logger.removeFilter(filt)
1101
1102 Removes the specified filter *filt* from this logger.
1103
1104
1105.. method:: Logger.filter(record)
1106
1107 Applies this logger's filters to the record and returns a true value if the
1108 record is to be processed.
1109
1110
1111.. method:: Logger.addHandler(hdlr)
1112
1113 Adds the specified handler *hdlr* to this logger.
1114
1115
1116.. method:: Logger.removeHandler(hdlr)
1117
1118 Removes the specified handler *hdlr* from this logger.
1119
1120
1121.. method:: Logger.findCaller()
1122
1123 Finds the caller's source filename and line number. Returns the filename, line
1124 number and function name as a 3-element tuple.
1125
Matthias Klosef0e29182007-08-16 12:03:44 +00001126 .. versionchanged:: 2.4
Georg Brandl8ec7f652007-08-15 14:28:01 +00001127 The function name was added. In earlier versions, the filename and line number
1128 were returned as a 2-element tuple..
1129
1130
1131.. method:: Logger.handle(record)
1132
1133 Handles a record by passing it to all handlers associated with this logger and
1134 its ancestors (until a false value of *propagate* is found). This method is used
1135 for unpickled records received from a socket, as well as those created locally.
Georg Brandl9fa61bb2009-07-26 14:19:57 +00001136 Logger-level filtering is applied using :meth:`~Logger.filter`.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001137
1138
1139.. method:: Logger.makeRecord(name, lvl, fn, lno, msg, args, exc_info [, func, extra])
1140
1141 This is a factory method which can be overridden in subclasses to create
1142 specialized :class:`LogRecord` instances.
1143
1144 .. versionchanged:: 2.5
1145 *func* and *extra* were added.
1146
1147
1148.. _minimal-example:
1149
1150Basic example
1151-------------
1152
1153.. versionchanged:: 2.4
1154 formerly :func:`basicConfig` did not take any keyword arguments.
1155
1156The :mod:`logging` package provides a lot of flexibility, and its configuration
1157can appear daunting. This section demonstrates that simple use of the logging
1158package is possible.
1159
1160The simplest example shows logging to the console::
1161
1162 import logging
1163
1164 logging.debug('A debug message')
1165 logging.info('Some information')
1166 logging.warning('A shot across the bows')
1167
1168If you run the above script, you'll see this::
1169
1170 WARNING:root:A shot across the bows
1171
1172Because no particular logger was specified, the system used the root logger. The
1173debug and info messages didn't appear because by default, the root logger is
1174configured to only handle messages with a severity of WARNING or above. The
1175message format is also a configuration default, as is the output destination of
1176the messages - ``sys.stderr``. The severity level, the message format and
1177destination can be easily changed, as shown in the example below::
1178
1179 import logging
1180
1181 logging.basicConfig(level=logging.DEBUG,
1182 format='%(asctime)s %(levelname)s %(message)s',
Vinay Sajip998cc242010-06-04 13:41:02 +00001183 filename='myapp.log',
Georg Brandl8ec7f652007-08-15 14:28:01 +00001184 filemode='w')
1185 logging.debug('A debug message')
1186 logging.info('Some information')
1187 logging.warning('A shot across the bows')
1188
1189The :meth:`basicConfig` method is used to change the configuration defaults,
Vinay Sajip998cc242010-06-04 13:41:02 +00001190which results in output (written to ``myapp.log``) which should look
Georg Brandl8ec7f652007-08-15 14:28:01 +00001191something like the following::
1192
1193 2004-07-02 13:00:08,743 DEBUG A debug message
1194 2004-07-02 13:00:08,743 INFO Some information
1195 2004-07-02 13:00:08,743 WARNING A shot across the bows
1196
1197This time, all messages with a severity of DEBUG or above were handled, and the
1198format of the messages was also changed, and output went to the specified file
1199rather than the console.
1200
1201Formatting uses standard Python string formatting - see section
1202:ref:`string-formatting`. The format string takes the following common
1203specifiers. For a complete list of specifiers, consult the :class:`Formatter`
1204documentation.
1205
1206+-------------------+-----------------------------------------------+
1207| Format | Description |
1208+===================+===============================================+
1209| ``%(name)s`` | Name of the logger (logging channel). |
1210+-------------------+-----------------------------------------------+
1211| ``%(levelname)s`` | Text logging level for the message |
1212| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
1213| | ``'ERROR'``, ``'CRITICAL'``). |
1214+-------------------+-----------------------------------------------+
1215| ``%(asctime)s`` | Human-readable time when the |
1216| | :class:`LogRecord` was created. By default |
1217| | this is of the form "2003-07-08 16:49:45,896" |
1218| | (the numbers after the comma are millisecond |
1219| | portion of the time). |
1220+-------------------+-----------------------------------------------+
1221| ``%(message)s`` | The logged message. |
1222+-------------------+-----------------------------------------------+
1223
1224To change the date/time format, you can pass an additional keyword parameter,
1225*datefmt*, as in the following::
1226
1227 import logging
1228
1229 logging.basicConfig(level=logging.DEBUG,
1230 format='%(asctime)s %(levelname)-8s %(message)s',
1231 datefmt='%a, %d %b %Y %H:%M:%S',
1232 filename='/temp/myapp.log',
1233 filemode='w')
1234 logging.debug('A debug message')
1235 logging.info('Some information')
1236 logging.warning('A shot across the bows')
1237
1238which would result in output like ::
1239
1240 Fri, 02 Jul 2004 13:06:18 DEBUG A debug message
1241 Fri, 02 Jul 2004 13:06:18 INFO Some information
1242 Fri, 02 Jul 2004 13:06:18 WARNING A shot across the bows
1243
1244The date format string follows the requirements of :func:`strftime` - see the
1245documentation for the :mod:`time` module.
1246
1247If, instead of sending logging output to the console or a file, you'd rather use
1248a file-like object which you have created separately, you can pass it to
1249:func:`basicConfig` using the *stream* keyword argument. Note that if both
1250*stream* and *filename* keyword arguments are passed, the *stream* argument is
1251ignored.
1252
1253Of course, you can put variable information in your output. To do this, simply
1254have the message be a format string and pass in additional arguments containing
1255the variable information, as in the following example::
1256
1257 import logging
1258
1259 logging.basicConfig(level=logging.DEBUG,
1260 format='%(asctime)s %(levelname)-8s %(message)s',
1261 datefmt='%a, %d %b %Y %H:%M:%S',
1262 filename='/temp/myapp.log',
1263 filemode='w')
1264 logging.error('Pack my box with %d dozen %s', 5, 'liquor jugs')
1265
1266which would result in ::
1267
1268 Wed, 21 Jul 2004 15:35:16 ERROR Pack my box with 5 dozen liquor jugs
1269
1270
1271.. _multiple-destinations:
1272
1273Logging to multiple destinations
1274--------------------------------
1275
1276Let's say you want to log to console and file with different message formats and
1277in differing circumstances. Say you want to log messages with levels of DEBUG
1278and higher to file, and those messages at level INFO and higher to the console.
1279Let's also assume that the file should contain timestamps, but the console
1280messages should not. Here's how you can achieve this::
1281
1282 import logging
1283
1284 # set up logging to file - see previous section for more details
1285 logging.basicConfig(level=logging.DEBUG,
1286 format='%(asctime)s %(name)-12s %(levelname)-8s %(message)s',
1287 datefmt='%m-%d %H:%M',
1288 filename='/temp/myapp.log',
1289 filemode='w')
1290 # define a Handler which writes INFO messages or higher to the sys.stderr
1291 console = logging.StreamHandler()
1292 console.setLevel(logging.INFO)
1293 # set a format which is simpler for console use
1294 formatter = logging.Formatter('%(name)-12s: %(levelname)-8s %(message)s')
1295 # tell the handler to use this format
1296 console.setFormatter(formatter)
1297 # add the handler to the root logger
1298 logging.getLogger('').addHandler(console)
1299
1300 # Now, we can log to the root logger, or any other logger. First the root...
1301 logging.info('Jackdaws love my big sphinx of quartz.')
1302
1303 # Now, define a couple of other loggers which might represent areas in your
1304 # application:
1305
1306 logger1 = logging.getLogger('myapp.area1')
1307 logger2 = logging.getLogger('myapp.area2')
1308
1309 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1310 logger1.info('How quickly daft jumping zebras vex.')
1311 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1312 logger2.error('The five boxing wizards jump quickly.')
1313
1314When you run this, on the console you will see ::
1315
1316 root : INFO Jackdaws love my big sphinx of quartz.
1317 myapp.area1 : INFO How quickly daft jumping zebras vex.
1318 myapp.area2 : WARNING Jail zesty vixen who grabbed pay from quack.
1319 myapp.area2 : ERROR The five boxing wizards jump quickly.
1320
1321and in the file you will see something like ::
1322
1323 10-22 22:19 root INFO Jackdaws love my big sphinx of quartz.
1324 10-22 22:19 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1325 10-22 22:19 myapp.area1 INFO How quickly daft jumping zebras vex.
1326 10-22 22:19 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1327 10-22 22:19 myapp.area2 ERROR The five boxing wizards jump quickly.
1328
1329As you can see, the DEBUG message only shows up in the file. The other messages
1330are sent to both destinations.
1331
1332This example uses console and file handlers, but you can use any number and
1333combination of handlers you choose.
1334
Vinay Sajip333c6e72009-08-20 22:04:32 +00001335.. _logging-exceptions:
1336
1337Exceptions raised during logging
1338--------------------------------
1339
1340The logging package is designed to swallow exceptions which occur while logging
1341in production. This is so that errors which occur while handling logging events
1342- such as logging misconfiguration, network or other similar errors - do not
1343cause the application using logging to terminate prematurely.
1344
1345:class:`SystemExit` and :class:`KeyboardInterrupt` exceptions are never
1346swallowed. Other exceptions which occur during the :meth:`emit` method of a
1347:class:`Handler` subclass are passed to its :meth:`handleError` method.
1348
1349The default implementation of :meth:`handleError` in :class:`Handler` checks
Georg Brandlf6d367452010-03-12 10:02:03 +00001350to see if a module-level variable, :data:`raiseExceptions`, is set. If set, a
1351traceback is printed to :data:`sys.stderr`. If not set, the exception is swallowed.
Vinay Sajip333c6e72009-08-20 22:04:32 +00001352
Georg Brandlf6d367452010-03-12 10:02:03 +00001353**Note:** The default value of :data:`raiseExceptions` is ``True``. This is because
Vinay Sajip333c6e72009-08-20 22:04:32 +00001354during development, you typically want to be notified of any exceptions that
Georg Brandlf6d367452010-03-12 10:02:03 +00001355occur. It's advised that you set :data:`raiseExceptions` to ``False`` for production
Vinay Sajip333c6e72009-08-20 22:04:32 +00001356usage.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001357
Vinay Sajipaa0665b2008-01-07 19:40:10 +00001358.. _context-info:
1359
1360Adding contextual information to your logging output
1361----------------------------------------------------
1362
1363Sometimes you want logging output to contain contextual information in
1364addition to the parameters passed to the logging call. For example, in a
1365networked application, it may be desirable to log client-specific information
1366in the log (e.g. remote client's username, or IP address). Although you could
1367use the *extra* parameter to achieve this, it's not always convenient to pass
1368the information in this way. While it might be tempting to create
1369:class:`Logger` instances on a per-connection basis, this is not a good idea
1370because these instances are not garbage collected. While this is not a problem
1371in practice, when the number of :class:`Logger` instances is dependent on the
1372level of granularity you want to use in logging an application, it could
1373be hard to manage if the number of :class:`Logger` instances becomes
1374effectively unbounded.
1375
Vinay Sajip957a47c2010-09-06 22:18:20 +00001376
1377Using LoggerAdapters to impart contextual information
1378^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1379
Vinay Sajipc7403352008-01-18 15:54:14 +00001380An easy way in which you can pass contextual information to be output along
1381with logging event information is to use the :class:`LoggerAdapter` class.
1382This class is designed to look like a :class:`Logger`, so that you can call
1383:meth:`debug`, :meth:`info`, :meth:`warning`, :meth:`error`,
1384:meth:`exception`, :meth:`critical` and :meth:`log`. These methods have the
1385same signatures as their counterparts in :class:`Logger`, so you can use the
1386two types of instances interchangeably.
Vinay Sajipaa0665b2008-01-07 19:40:10 +00001387
Vinay Sajipc7403352008-01-18 15:54:14 +00001388When you create an instance of :class:`LoggerAdapter`, you pass it a
1389:class:`Logger` instance and a dict-like object which contains your contextual
1390information. When you call one of the logging methods on an instance of
1391:class:`LoggerAdapter`, it delegates the call to the underlying instance of
1392:class:`Logger` passed to its constructor, and arranges to pass the contextual
1393information in the delegated call. Here's a snippet from the code of
1394:class:`LoggerAdapter`::
Vinay Sajipaa0665b2008-01-07 19:40:10 +00001395
Vinay Sajipc7403352008-01-18 15:54:14 +00001396 def debug(self, msg, *args, **kwargs):
1397 """
1398 Delegate a debug call to the underlying logger, after adding
1399 contextual information from this adapter instance.
1400 """
1401 msg, kwargs = self.process(msg, kwargs)
1402 self.logger.debug(msg, *args, **kwargs)
Vinay Sajipaa0665b2008-01-07 19:40:10 +00001403
Vinay Sajipc7403352008-01-18 15:54:14 +00001404The :meth:`process` method of :class:`LoggerAdapter` is where the contextual
1405information is added to the logging output. It's passed the message and
1406keyword arguments of the logging call, and it passes back (potentially)
1407modified versions of these to use in the call to the underlying logger. The
1408default implementation of this method leaves the message alone, but inserts
1409an "extra" key in the keyword argument whose value is the dict-like object
1410passed to the constructor. Of course, if you had passed an "extra" keyword
1411argument in the call to the adapter, it will be silently overwritten.
Vinay Sajipaa0665b2008-01-07 19:40:10 +00001412
Vinay Sajipc7403352008-01-18 15:54:14 +00001413The advantage of using "extra" is that the values in the dict-like object are
1414merged into the :class:`LogRecord` instance's __dict__, allowing you to use
1415customized strings with your :class:`Formatter` instances which know about
1416the keys of the dict-like object. If you need a different method, e.g. if you
1417want to prepend or append the contextual information to the message string,
1418you just need to subclass :class:`LoggerAdapter` and override :meth:`process`
1419to do what you need. Here's an example script which uses this class, which
1420also illustrates what dict-like behaviour is needed from an arbitrary
1421"dict-like" object for use in the constructor::
1422
Georg Brandlf8e6afb2008-01-19 10:11:27 +00001423 import logging
Vinay Sajip733024a2008-01-21 17:39:22 +00001424
Georg Brandlf8e6afb2008-01-19 10:11:27 +00001425 class ConnInfo:
1426 """
1427 An example class which shows how an arbitrary class can be used as
1428 the 'extra' context information repository passed to a LoggerAdapter.
1429 """
Vinay Sajip733024a2008-01-21 17:39:22 +00001430
Georg Brandlf8e6afb2008-01-19 10:11:27 +00001431 def __getitem__(self, name):
1432 """
1433 To allow this instance to look like a dict.
1434 """
1435 from random import choice
1436 if name == "ip":
1437 result = choice(["127.0.0.1", "192.168.0.1"])
1438 elif name == "user":
1439 result = choice(["jim", "fred", "sheila"])
1440 else:
1441 result = self.__dict__.get(name, "?")
1442 return result
Vinay Sajip733024a2008-01-21 17:39:22 +00001443
Georg Brandlf8e6afb2008-01-19 10:11:27 +00001444 def __iter__(self):
1445 """
1446 To allow iteration over keys, which will be merged into
1447 the LogRecord dict before formatting and output.
1448 """
1449 keys = ["ip", "user"]
1450 keys.extend(self.__dict__.keys())
1451 return keys.__iter__()
Vinay Sajip733024a2008-01-21 17:39:22 +00001452
Georg Brandlf8e6afb2008-01-19 10:11:27 +00001453 if __name__ == "__main__":
1454 from random import choice
1455 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1456 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1457 { "ip" : "123.231.231.123", "user" : "sheila" })
1458 logging.basicConfig(level=logging.DEBUG,
1459 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1460 a1.debug("A debug message")
1461 a1.info("An info message with %s", "some parameters")
1462 a2 = logging.LoggerAdapter(logging.getLogger("d.e.f"), ConnInfo())
1463 for x in range(10):
1464 lvl = choice(levels)
1465 lvlname = logging.getLevelName(lvl)
1466 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
Vinay Sajipc7403352008-01-18 15:54:14 +00001467
1468When this script is run, the output should look something like this::
1469
Georg Brandlf8e6afb2008-01-19 10:11:27 +00001470 2008-01-18 14:49:54,023 a.b.c DEBUG IP: 123.231.231.123 User: sheila A debug message
1471 2008-01-18 14:49:54,023 a.b.c INFO IP: 123.231.231.123 User: sheila An info message with some parameters
1472 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
1473 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
1474 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
1475 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
1476 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
1477 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
1478 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
1479 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
1480 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
1481 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
Vinay Sajipc7403352008-01-18 15:54:14 +00001482
1483.. versionadded:: 2.6
1484
1485The :class:`LoggerAdapter` class was not present in previous versions.
1486
Vinay Sajipfb7b5052010-09-17 12:45:26 +00001487.. _filters-contextual:
1488
Vinay Sajip957a47c2010-09-06 22:18:20 +00001489Using Filters to impart contextual information
1490^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1491
1492You can also add contextual information to log output using a user-defined
1493:class:`Filter`. ``Filter`` instances are allowed to modify the ``LogRecords``
1494passed to them, including adding additional attributes which can then be output
1495using a suitable format string, or if needed a custom :class:`Formatter`.
1496
1497For example in a web application, the request being processed (or at least,
1498the interesting parts of it) can be stored in a threadlocal
1499(:class:`threading.local`) variable, and then accessed from a ``Filter`` to
1500add, say, information from the request - say, the remote IP address and remote
1501user's username - to the ``LogRecord``, using the attribute names 'ip' and
1502'user' as in the ``LoggerAdapter`` example above. In that case, the same format
1503string can be used to get similar output to that shown above. Here's an example
1504script::
1505
1506 import logging
1507 from random import choice
1508
1509 class ContextFilter(logging.Filter):
1510 """
1511 This is a filter which injects contextual information into the log.
1512
1513 Rather than use actual contextual information, we just use random
1514 data in this demo.
1515 """
1516
1517 USERS = ['jim', 'fred', 'sheila']
1518 IPS = ['123.231.231.123', '127.0.0.1', '192.168.0.1']
1519
1520 def filter(self, record):
1521
1522 record.ip = choice(ContextFilter.IPS)
1523 record.user = choice(ContextFilter.USERS)
1524 return True
1525
1526 if __name__ == "__main__":
1527 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1528 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1529 { "ip" : "123.231.231.123", "user" : "sheila" })
1530 logging.basicConfig(level=logging.DEBUG,
1531 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1532 a1 = logging.getLogger("a.b.c")
1533 a2 = logging.getLogger("d.e.f")
1534
1535 f = ContextFilter()
1536 a1.addFilter(f)
1537 a2.addFilter(f)
1538 a1.debug("A debug message")
1539 a1.info("An info message with %s", "some parameters")
1540 for x in range(10):
1541 lvl = choice(levels)
1542 lvlname = logging.getLevelName(lvl)
1543 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
1544
1545which, when run, produces something like::
1546
1547 2010-09-06 22:38:15,292 a.b.c DEBUG IP: 123.231.231.123 User: fred A debug message
1548 2010-09-06 22:38:15,300 a.b.c INFO IP: 192.168.0.1 User: sheila An info message with some parameters
1549 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
1550 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
1551 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
1552 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
1553 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
1554 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
1555 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
1556 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
1557 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
1558 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
1559
1560
Vinay Sajip3a0dc302009-08-15 23:23:12 +00001561.. _multiple-processes:
1562
1563Logging to a single file from multiple processes
1564------------------------------------------------
1565
1566Although logging is thread-safe, and logging to a single file from multiple
1567threads in a single process *is* supported, logging to a single file from
1568*multiple processes* is *not* supported, because there is no standard way to
1569serialize access to a single file across multiple processes in Python. If you
1570need to log to a single file from multiple processes, the best way of doing
1571this is to have all the processes log to a :class:`SocketHandler`, and have a
1572separate process which implements a socket server which reads from the socket
1573and logs to file. (If you prefer, you can dedicate one thread in one of the
1574existing processes to perform this function.) The following section documents
1575this approach in more detail and includes a working socket receiver which can
1576be used as a starting point for you to adapt in your own applications.
Vinay Sajipaa0665b2008-01-07 19:40:10 +00001577
Vinay Sajip1c0b24f2009-08-15 23:34:47 +00001578If you are using a recent version of Python which includes the
1579:mod:`multiprocessing` module, you can write your own handler which uses the
1580:class:`Lock` class from this module to serialize access to the file from
1581your processes. The existing :class:`FileHandler` and subclasses do not make
1582use of :mod:`multiprocessing` at present, though they may do so in the future.
Vinay Sajip5e7f6452009-08-17 13:14:37 +00001583Note that at present, the :mod:`multiprocessing` module does not provide
1584working lock functionality on all platforms (see
1585http://bugs.python.org/issue3770).
Vinay Sajip1c0b24f2009-08-15 23:34:47 +00001586
Georg Brandl8ec7f652007-08-15 14:28:01 +00001587.. _network-logging:
1588
1589Sending and receiving logging events across a network
1590-----------------------------------------------------
1591
1592Let's say you want to send logging events across a network, and handle them at
1593the receiving end. A simple way of doing this is attaching a
1594:class:`SocketHandler` instance to the root logger at the sending end::
1595
Benjamin Petersona7b55a32009-02-20 03:31:23 +00001596 import logging, logging.handlers
Georg Brandl8ec7f652007-08-15 14:28:01 +00001597
1598 rootLogger = logging.getLogger('')
1599 rootLogger.setLevel(logging.DEBUG)
1600 socketHandler = logging.handlers.SocketHandler('localhost',
1601 logging.handlers.DEFAULT_TCP_LOGGING_PORT)
1602 # don't bother with a formatter, since a socket handler sends the event as
1603 # an unformatted pickle
1604 rootLogger.addHandler(socketHandler)
1605
1606 # Now, we can log to the root logger, or any other logger. First the root...
1607 logging.info('Jackdaws love my big sphinx of quartz.')
1608
1609 # Now, define a couple of other loggers which might represent areas in your
1610 # application:
1611
1612 logger1 = logging.getLogger('myapp.area1')
1613 logger2 = logging.getLogger('myapp.area2')
1614
1615 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1616 logger1.info('How quickly daft jumping zebras vex.')
1617 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1618 logger2.error('The five boxing wizards jump quickly.')
1619
Georg Brandle152a772008-05-24 18:31:28 +00001620At the receiving end, you can set up a receiver using the :mod:`SocketServer`
Georg Brandl8ec7f652007-08-15 14:28:01 +00001621module. Here is a basic working example::
1622
1623 import cPickle
1624 import logging
1625 import logging.handlers
Georg Brandle152a772008-05-24 18:31:28 +00001626 import SocketServer
Georg Brandl8ec7f652007-08-15 14:28:01 +00001627 import struct
1628
1629
Georg Brandle152a772008-05-24 18:31:28 +00001630 class LogRecordStreamHandler(SocketServer.StreamRequestHandler):
Georg Brandl8ec7f652007-08-15 14:28:01 +00001631 """Handler for a streaming logging request.
1632
1633 This basically logs the record using whatever logging policy is
1634 configured locally.
1635 """
1636
1637 def handle(self):
1638 """
1639 Handle multiple requests - each expected to be a 4-byte length,
1640 followed by the LogRecord in pickle format. Logs the record
1641 according to whatever policy is configured locally.
1642 """
1643 while 1:
1644 chunk = self.connection.recv(4)
1645 if len(chunk) < 4:
1646 break
1647 slen = struct.unpack(">L", chunk)[0]
1648 chunk = self.connection.recv(slen)
1649 while len(chunk) < slen:
1650 chunk = chunk + self.connection.recv(slen - len(chunk))
1651 obj = self.unPickle(chunk)
1652 record = logging.makeLogRecord(obj)
1653 self.handleLogRecord(record)
1654
1655 def unPickle(self, data):
1656 return cPickle.loads(data)
1657
1658 def handleLogRecord(self, record):
1659 # if a name is specified, we use the named logger rather than the one
1660 # implied by the record.
1661 if self.server.logname is not None:
1662 name = self.server.logname
1663 else:
1664 name = record.name
1665 logger = logging.getLogger(name)
1666 # N.B. EVERY record gets logged. This is because Logger.handle
1667 # is normally called AFTER logger-level filtering. If you want
1668 # to do filtering, do it at the client end to save wasting
1669 # cycles and network bandwidth!
1670 logger.handle(record)
1671
Georg Brandle152a772008-05-24 18:31:28 +00001672 class LogRecordSocketReceiver(SocketServer.ThreadingTCPServer):
Georg Brandl8ec7f652007-08-15 14:28:01 +00001673 """simple TCP socket-based logging receiver suitable for testing.
1674 """
1675
1676 allow_reuse_address = 1
1677
1678 def __init__(self, host='localhost',
1679 port=logging.handlers.DEFAULT_TCP_LOGGING_PORT,
1680 handler=LogRecordStreamHandler):
Georg Brandle152a772008-05-24 18:31:28 +00001681 SocketServer.ThreadingTCPServer.__init__(self, (host, port), handler)
Georg Brandl8ec7f652007-08-15 14:28:01 +00001682 self.abort = 0
1683 self.timeout = 1
1684 self.logname = None
1685
1686 def serve_until_stopped(self):
1687 import select
1688 abort = 0
1689 while not abort:
1690 rd, wr, ex = select.select([self.socket.fileno()],
1691 [], [],
1692 self.timeout)
1693 if rd:
1694 self.handle_request()
1695 abort = self.abort
1696
1697 def main():
1698 logging.basicConfig(
1699 format="%(relativeCreated)5d %(name)-15s %(levelname)-8s %(message)s")
1700 tcpserver = LogRecordSocketReceiver()
1701 print "About to start TCP server..."
1702 tcpserver.serve_until_stopped()
1703
1704 if __name__ == "__main__":
1705 main()
1706
1707First run the server, and then the client. On the client side, nothing is
1708printed on the console; on the server side, you should see something like::
1709
1710 About to start TCP server...
1711 59 root INFO Jackdaws love my big sphinx of quartz.
1712 59 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1713 69 myapp.area1 INFO How quickly daft jumping zebras vex.
1714 69 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1715 69 myapp.area2 ERROR The five boxing wizards jump quickly.
1716
Vinay Sajip80eed3e2010-07-06 15:08:55 +00001717Note that there are some security issues with pickle in some scenarios. If
1718these affect you, you can use an alternative serialization scheme by overriding
1719the :meth:`makePickle` method and implementing your alternative there, as
1720well as adapting the above script to use your alternative serialization.
1721
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00001722.. _arbitrary-object-messages:
1723
Vinay Sajipf778bec2009-09-22 17:23:41 +00001724Using arbitrary objects as messages
1725-----------------------------------
1726
1727In the preceding sections and examples, it has been assumed that the message
1728passed when logging the event is a string. However, this is not the only
1729possibility. You can pass an arbitrary object as a message, and its
1730:meth:`__str__` method will be called when the logging system needs to convert
1731it to a string representation. In fact, if you want to, you can avoid
1732computing a string representation altogether - for example, the
1733:class:`SocketHandler` emits an event by pickling it and sending it over the
1734wire.
1735
1736Optimization
1737------------
1738
1739Formatting of message arguments is deferred until it cannot be avoided.
1740However, computing the arguments passed to the logging method can also be
1741expensive, and you may want to avoid doing it if the logger will just throw
1742away your event. To decide what to do, you can call the :meth:`isEnabledFor`
1743method which takes a level argument and returns true if the event would be
1744created by the Logger for that level of call. You can write code like this::
1745
1746 if logger.isEnabledFor(logging.DEBUG):
1747 logger.debug("Message with %s, %s", expensive_func1(),
1748 expensive_func2())
1749
1750so that if the logger's threshold is set above ``DEBUG``, the calls to
1751:func:`expensive_func1` and :func:`expensive_func2` are never made.
1752
1753There are other optimizations which can be made for specific applications which
1754need more precise control over what logging information is collected. Here's a
1755list of things you can do to avoid processing during logging which you don't
1756need:
1757
1758+-----------------------------------------------+----------------------------------------+
1759| What you don't want to collect | How to avoid collecting it |
1760+===============================================+========================================+
1761| Information about where calls were made from. | Set ``logging._srcfile`` to ``None``. |
1762+-----------------------------------------------+----------------------------------------+
1763| Threading information. | Set ``logging.logThreads`` to ``0``. |
1764+-----------------------------------------------+----------------------------------------+
1765| Process information. | Set ``logging.logProcesses`` to ``0``. |
1766+-----------------------------------------------+----------------------------------------+
1767
1768Also note that the core logging module only includes the basic handlers. If
1769you don't import :mod:`logging.handlers` and :mod:`logging.config`, they won't
1770take up any memory.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001771
Vinay Sajip4b782332009-01-19 06:49:19 +00001772.. _handler:
1773
Georg Brandl8ec7f652007-08-15 14:28:01 +00001774Handler Objects
1775---------------
1776
1777Handlers have the following attributes and methods. Note that :class:`Handler`
1778is never instantiated directly; this class acts as a base for more useful
1779subclasses. However, the :meth:`__init__` method in subclasses needs to call
1780:meth:`Handler.__init__`.
1781
1782
1783.. method:: Handler.__init__(level=NOTSET)
1784
1785 Initializes the :class:`Handler` instance by setting its level, setting the list
1786 of filters to the empty list and creating a lock (using :meth:`createLock`) for
1787 serializing access to an I/O mechanism.
1788
1789
1790.. method:: Handler.createLock()
1791
1792 Initializes a thread lock which can be used to serialize access to underlying
1793 I/O functionality which may not be threadsafe.
1794
1795
1796.. method:: Handler.acquire()
1797
1798 Acquires the thread lock created with :meth:`createLock`.
1799
1800
1801.. method:: Handler.release()
1802
1803 Releases the thread lock acquired with :meth:`acquire`.
1804
1805
1806.. method:: Handler.setLevel(lvl)
1807
1808 Sets the threshold for this handler to *lvl*. Logging messages which are less
1809 severe than *lvl* will be ignored. When a handler is created, the level is set
1810 to :const:`NOTSET` (which causes all messages to be processed).
1811
1812
1813.. method:: Handler.setFormatter(form)
1814
1815 Sets the :class:`Formatter` for this handler to *form*.
1816
1817
1818.. method:: Handler.addFilter(filt)
1819
1820 Adds the specified filter *filt* to this handler.
1821
1822
1823.. method:: Handler.removeFilter(filt)
1824
1825 Removes the specified filter *filt* from this handler.
1826
1827
1828.. method:: Handler.filter(record)
1829
1830 Applies this handler's filters to the record and returns a true value if the
1831 record is to be processed.
1832
1833
1834.. method:: Handler.flush()
1835
1836 Ensure all logging output has been flushed. This version does nothing and is
1837 intended to be implemented by subclasses.
1838
1839
1840.. method:: Handler.close()
1841
Vinay Sajipaa5f8732008-09-01 17:44:14 +00001842 Tidy up any resources used by the handler. This version does no output but
1843 removes the handler from an internal list of handlers which is closed when
1844 :func:`shutdown` is called. Subclasses should ensure that this gets called
1845 from overridden :meth:`close` methods.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001846
1847
1848.. method:: Handler.handle(record)
1849
1850 Conditionally emits the specified logging record, depending on filters which may
1851 have been added to the handler. Wraps the actual emission of the record with
1852 acquisition/release of the I/O thread lock.
1853
1854
1855.. method:: Handler.handleError(record)
1856
1857 This method should be called from handlers when an exception is encountered
1858 during an :meth:`emit` call. By default it does nothing, which means that
1859 exceptions get silently ignored. This is what is mostly wanted for a logging
1860 system - most users will not care about errors in the logging system, they are
1861 more interested in application errors. You could, however, replace this with a
1862 custom handler if you wish. The specified record is the one which was being
1863 processed when the exception occurred.
1864
1865
1866.. method:: Handler.format(record)
1867
1868 Do formatting for a record - if a formatter is set, use it. Otherwise, use the
1869 default formatter for the module.
1870
1871
1872.. method:: Handler.emit(record)
1873
1874 Do whatever it takes to actually log the specified logging record. This version
1875 is intended to be implemented by subclasses and so raises a
1876 :exc:`NotImplementedError`.
1877
1878
Vinay Sajip4b782332009-01-19 06:49:19 +00001879.. _stream-handler:
1880
Georg Brandl8ec7f652007-08-15 14:28:01 +00001881StreamHandler
1882^^^^^^^^^^^^^
1883
1884The :class:`StreamHandler` class, located in the core :mod:`logging` package,
1885sends logging output to streams such as *sys.stdout*, *sys.stderr* or any
1886file-like object (or, more precisely, any object which supports :meth:`write`
1887and :meth:`flush` methods).
1888
1889
Vinay Sajip0c6a0e32009-12-17 14:52:00 +00001890.. currentmodule:: logging
1891
Vinay Sajip4780c9a2009-09-26 14:53:32 +00001892.. class:: StreamHandler([stream])
Georg Brandl8ec7f652007-08-15 14:28:01 +00001893
Vinay Sajip4780c9a2009-09-26 14:53:32 +00001894 Returns a new instance of the :class:`StreamHandler` class. If *stream* is
Georg Brandl8ec7f652007-08-15 14:28:01 +00001895 specified, the instance will use it for logging output; otherwise, *sys.stderr*
1896 will be used.
1897
Vinay Sajip5ddf51f2011-03-12 22:43:23 +00001898 .. versionchanged:: 2.7
1899 The ``stream`` parameter was called ``strm`` in earlier versions.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001900
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001901 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00001902
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001903 If a formatter is specified, it is used to format the record. The record
1904 is then written to the stream with a trailing newline. If exception
1905 information is present, it is formatted using
1906 :func:`traceback.print_exception` and appended to the stream.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001907
1908
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001909 .. method:: flush()
Georg Brandl8ec7f652007-08-15 14:28:01 +00001910
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001911 Flushes the stream by calling its :meth:`flush` method. Note that the
1912 :meth:`close` method is inherited from :class:`Handler` and so does
Vinay Sajipaa5f8732008-09-01 17:44:14 +00001913 no output, so an explicit :meth:`flush` call may be needed at times.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001914
1915
Vinay Sajip4b782332009-01-19 06:49:19 +00001916.. _file-handler:
1917
Georg Brandl8ec7f652007-08-15 14:28:01 +00001918FileHandler
1919^^^^^^^^^^^
1920
1921The :class:`FileHandler` class, located in the core :mod:`logging` package,
1922sends logging output to a disk file. It inherits the output functionality from
1923:class:`StreamHandler`.
1924
1925
Vinay Sajipf38ba782008-01-24 12:38:30 +00001926.. class:: FileHandler(filename[, mode[, encoding[, delay]]])
Georg Brandl8ec7f652007-08-15 14:28:01 +00001927
1928 Returns a new instance of the :class:`FileHandler` class. The specified file is
1929 opened and used as the stream for logging. If *mode* is not specified,
1930 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Vinay Sajipf38ba782008-01-24 12:38:30 +00001931 with that encoding. If *delay* is true, then file opening is deferred until the
1932 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001933
Vinay Sajip59584c42009-08-14 11:33:54 +00001934 .. versionchanged:: 2.6
1935 *delay* was added.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001936
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001937 .. method:: close()
Georg Brandl8ec7f652007-08-15 14:28:01 +00001938
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001939 Closes the file.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001940
1941
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001942 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00001943
Benjamin Petersonc7b05922008-04-25 01:29:10 +00001944 Outputs the record to the file.
Georg Brandl8ec7f652007-08-15 14:28:01 +00001945
Georg Brandl52f83952011-02-25 10:39:23 +00001946
Vinay Sajip4b782332009-01-19 06:49:19 +00001947.. _null-handler:
Georg Brandl8ec7f652007-08-15 14:28:01 +00001948
Vinay Sajip51104862009-01-02 18:53:04 +00001949NullHandler
1950^^^^^^^^^^^
1951
1952.. versionadded:: 2.7
1953
1954The :class:`NullHandler` class, located in the core :mod:`logging` package,
1955does not do any formatting or output. It is essentially a "no-op" handler
1956for use by library developers.
1957
Vinay Sajip51104862009-01-02 18:53:04 +00001958.. class:: NullHandler()
1959
1960 Returns a new instance of the :class:`NullHandler` class.
1961
Vinay Sajip51104862009-01-02 18:53:04 +00001962 .. method:: emit(record)
1963
1964 This method does nothing.
1965
Vinay Sajip47ca1222010-09-27 13:53:47 +00001966 .. method:: handle(record)
1967
1968 This method does nothing.
1969
1970 .. method:: createLock()
1971
1972 This method returns `None` for the lock, since there is no
1973 underlying I/O to which access needs to be serialized.
1974
1975
Vinay Sajip99505c82009-01-10 13:38:04 +00001976See :ref:`library-config` for more information on how to use
1977:class:`NullHandler`.
1978
Vinay Sajip4b782332009-01-19 06:49:19 +00001979.. _watched-file-handler:
1980
Georg Brandl8ec7f652007-08-15 14:28:01 +00001981WatchedFileHandler
1982^^^^^^^^^^^^^^^^^^
1983
1984.. versionadded:: 2.6
1985
Vinay Sajipb1a15e42009-01-15 23:04:47 +00001986.. currentmodule:: logging.handlers
Vinay Sajip51104862009-01-02 18:53:04 +00001987
Georg Brandl8ec7f652007-08-15 14:28:01 +00001988The :class:`WatchedFileHandler` class, located in the :mod:`logging.handlers`
1989module, is a :class:`FileHandler` which watches the file it is logging to. If
1990the file changes, it is closed and reopened using the file name.
1991
1992A file change can happen because of usage of programs such as *newsyslog* and
1993*logrotate* which perform log file rotation. This handler, intended for use
1994under Unix/Linux, watches the file to see if it has changed since the last emit.
1995(A file is deemed to have changed if its device or inode have changed.) If the
1996file has changed, the old file stream is closed, and the file opened to get a
1997new stream.
1998
1999This handler is not appropriate for use under Windows, because under Windows
2000open log files cannot be moved or renamed - logging opens the files with
2001exclusive locks - and so there is no need for such a handler. Furthermore,
2002*ST_INO* is not supported under Windows; :func:`stat` always returns zero for
2003this value.
2004
2005
Vinay Sajipf38ba782008-01-24 12:38:30 +00002006.. class:: WatchedFileHandler(filename[,mode[, encoding[, delay]]])
Georg Brandl8ec7f652007-08-15 14:28:01 +00002007
2008 Returns a new instance of the :class:`WatchedFileHandler` class. The specified
2009 file is opened and used as the stream for logging. If *mode* is not specified,
2010 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Vinay Sajipf38ba782008-01-24 12:38:30 +00002011 with that encoding. If *delay* is true, then file opening is deferred until the
2012 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002013
Vinay Sajip59584c42009-08-14 11:33:54 +00002014 .. versionchanged:: 2.6
2015 *delay* was added.
2016
Georg Brandl8ec7f652007-08-15 14:28:01 +00002017
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002018 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002019
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002020 Outputs the record to the file, but first checks to see if the file has
2021 changed. If it has, the existing stream is flushed and closed and the
2022 file opened again, before outputting the record to the file.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002023
Vinay Sajip4b782332009-01-19 06:49:19 +00002024.. _rotating-file-handler:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002025
2026RotatingFileHandler
2027^^^^^^^^^^^^^^^^^^^
2028
2029The :class:`RotatingFileHandler` class, located in the :mod:`logging.handlers`
2030module, supports rotation of disk log files.
2031
2032
Vinay Sajipf38ba782008-01-24 12:38:30 +00002033.. class:: RotatingFileHandler(filename[, mode[, maxBytes[, backupCount[, encoding[, delay]]]]])
Georg Brandl8ec7f652007-08-15 14:28:01 +00002034
2035 Returns a new instance of the :class:`RotatingFileHandler` class. The specified
2036 file is opened and used as the stream for logging. If *mode* is not specified,
Vinay Sajipf38ba782008-01-24 12:38:30 +00002037 ``'a'`` is used. If *encoding* is not *None*, it is used to open the file
2038 with that encoding. If *delay* is true, then file opening is deferred until the
2039 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002040
2041 You can use the *maxBytes* and *backupCount* values to allow the file to
2042 :dfn:`rollover` at a predetermined size. When the size is about to be exceeded,
2043 the file is closed and a new file is silently opened for output. Rollover occurs
2044 whenever the current log file is nearly *maxBytes* in length; if *maxBytes* is
2045 zero, rollover never occurs. If *backupCount* is non-zero, the system will save
2046 old log files by appending the extensions ".1", ".2" etc., to the filename. For
2047 example, with a *backupCount* of 5 and a base file name of :file:`app.log`, you
2048 would get :file:`app.log`, :file:`app.log.1`, :file:`app.log.2`, up to
2049 :file:`app.log.5`. The file being written to is always :file:`app.log`. When
2050 this file is filled, it is closed and renamed to :file:`app.log.1`, and if files
2051 :file:`app.log.1`, :file:`app.log.2`, etc. exist, then they are renamed to
2052 :file:`app.log.2`, :file:`app.log.3` etc. respectively.
2053
Vinay Sajip59584c42009-08-14 11:33:54 +00002054 .. versionchanged:: 2.6
2055 *delay* was added.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002056
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002057 .. method:: doRollover()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002058
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002059 Does a rollover, as described above.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002060
2061
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002062 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002063
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002064 Outputs the record to the file, catering for rollover as described
2065 previously.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002066
Vinay Sajip4b782332009-01-19 06:49:19 +00002067.. _timed-rotating-file-handler:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002068
2069TimedRotatingFileHandler
2070^^^^^^^^^^^^^^^^^^^^^^^^
2071
2072The :class:`TimedRotatingFileHandler` class, located in the
2073:mod:`logging.handlers` module, supports rotation of disk log files at certain
2074timed intervals.
2075
2076
Andrew M. Kuchling6dd8cca2008-06-05 23:33:54 +00002077.. class:: TimedRotatingFileHandler(filename [,when [,interval [,backupCount[, encoding[, delay[, utc]]]]]])
Georg Brandl8ec7f652007-08-15 14:28:01 +00002078
2079 Returns a new instance of the :class:`TimedRotatingFileHandler` class. The
2080 specified file is opened and used as the stream for logging. On rotating it also
2081 sets the filename suffix. Rotating happens based on the product of *when* and
2082 *interval*.
2083
2084 You can use the *when* to specify the type of *interval*. The list of possible
Georg Brandld77554f2008-06-06 07:34:50 +00002085 values is below. Note that they are not case sensitive.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002086
Georg Brandl72780a42008-03-02 13:41:39 +00002087 +----------------+-----------------------+
2088 | Value | Type of interval |
2089 +================+=======================+
2090 | ``'S'`` | Seconds |
2091 +----------------+-----------------------+
2092 | ``'M'`` | Minutes |
2093 +----------------+-----------------------+
2094 | ``'H'`` | Hours |
2095 +----------------+-----------------------+
2096 | ``'D'`` | Days |
2097 +----------------+-----------------------+
2098 | ``'W'`` | Week day (0=Monday) |
2099 +----------------+-----------------------+
2100 | ``'midnight'`` | Roll over at midnight |
2101 +----------------+-----------------------+
Georg Brandl8ec7f652007-08-15 14:28:01 +00002102
Georg Brandle6dab2a2008-03-02 14:15:04 +00002103 The system will save old log files by appending extensions to the filename.
2104 The extensions are date-and-time based, using the strftime format
Vinay Sajip89a01cd2008-04-02 21:17:25 +00002105 ``%Y-%m-%d_%H-%M-%S`` or a leading portion thereof, depending on the
Vinay Sajip2a649f92008-07-18 09:00:35 +00002106 rollover interval.
Vinay Sajipecfa08f2010-03-12 09:16:10 +00002107
2108 When computing the next rollover time for the first time (when the handler
2109 is created), the last modification time of an existing log file, or else
2110 the current time, is used to compute when the next rotation will occur.
2111
Georg Brandld77554f2008-06-06 07:34:50 +00002112 If the *utc* argument is true, times in UTC will be used; otherwise
Andrew M. Kuchling6dd8cca2008-06-05 23:33:54 +00002113 local time is used.
2114
2115 If *backupCount* is nonzero, at most *backupCount* files
Vinay Sajip89a01cd2008-04-02 21:17:25 +00002116 will be kept, and if more would be created when rollover occurs, the oldest
2117 one is deleted. The deletion logic uses the interval to determine which
2118 files to delete, so changing the interval may leave old files lying around.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002119
Vinay Sajip59584c42009-08-14 11:33:54 +00002120 If *delay* is true, then file opening is deferred until the first call to
2121 :meth:`emit`.
2122
2123 .. versionchanged:: 2.6
2124 *delay* was added.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002125
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002126 .. method:: doRollover()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002127
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002128 Does a rollover, as described above.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002129
2130
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002131 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002132
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002133 Outputs the record to the file, catering for rollover as described above.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002134
2135
Vinay Sajip4b782332009-01-19 06:49:19 +00002136.. _socket-handler:
2137
Georg Brandl8ec7f652007-08-15 14:28:01 +00002138SocketHandler
2139^^^^^^^^^^^^^
2140
2141The :class:`SocketHandler` class, located in the :mod:`logging.handlers` module,
2142sends logging output to a network socket. The base class uses a TCP socket.
2143
2144
2145.. class:: SocketHandler(host, port)
2146
2147 Returns a new instance of the :class:`SocketHandler` class intended to
2148 communicate with a remote machine whose address is given by *host* and *port*.
2149
2150
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002151 .. method:: close()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002152
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002153 Closes the socket.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002154
2155
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002156 .. method:: emit()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002157
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002158 Pickles the record's attribute dictionary and writes it to the socket in
2159 binary format. If there is an error with the socket, silently drops the
2160 packet. If the connection was previously lost, re-establishes the
2161 connection. To unpickle the record at the receiving end into a
2162 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002163
2164
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002165 .. method:: handleError()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002166
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002167 Handles an error which has occurred during :meth:`emit`. The most likely
2168 cause is a lost connection. Closes the socket so that we can retry on the
2169 next event.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002170
2171
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002172 .. method:: makeSocket()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002173
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002174 This is a factory method which allows subclasses to define the precise
2175 type of socket they want. The default implementation creates a TCP socket
2176 (:const:`socket.SOCK_STREAM`).
Georg Brandl8ec7f652007-08-15 14:28:01 +00002177
2178
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002179 .. method:: makePickle(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002180
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002181 Pickles the record's attribute dictionary in binary format with a length
2182 prefix, and returns it ready for transmission across the socket.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002183
Vinay Sajip86aa9052010-06-29 15:13:14 +00002184 Note that pickles aren't completely secure. If you are concerned about
2185 security, you may want to override this method to implement a more secure
2186 mechanism. For example, you can sign pickles using HMAC and then verify
2187 them on the receiving end, or alternatively you can disable unpickling of
2188 global objects on the receiving end.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002189
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002190 .. method:: send(packet)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002191
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002192 Send a pickled string *packet* to the socket. This function allows for
2193 partial sends which can happen when the network is busy.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002194
2195
Vinay Sajip4b782332009-01-19 06:49:19 +00002196.. _datagram-handler:
2197
Georg Brandl8ec7f652007-08-15 14:28:01 +00002198DatagramHandler
2199^^^^^^^^^^^^^^^
2200
2201The :class:`DatagramHandler` class, located in the :mod:`logging.handlers`
2202module, inherits from :class:`SocketHandler` to support sending logging messages
2203over UDP sockets.
2204
2205
2206.. class:: DatagramHandler(host, port)
2207
2208 Returns a new instance of the :class:`DatagramHandler` class intended to
2209 communicate with a remote machine whose address is given by *host* and *port*.
2210
2211
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002212 .. method:: emit()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002213
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002214 Pickles the record's attribute dictionary and writes it to the socket in
2215 binary format. If there is an error with the socket, silently drops the
2216 packet. To unpickle the record at the receiving end into a
2217 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002218
2219
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002220 .. method:: makeSocket()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002221
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002222 The factory method of :class:`SocketHandler` is here overridden to create
2223 a UDP socket (:const:`socket.SOCK_DGRAM`).
Georg Brandl8ec7f652007-08-15 14:28:01 +00002224
2225
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002226 .. method:: send(s)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002227
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002228 Send a pickled string to a socket.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002229
2230
Vinay Sajip4b782332009-01-19 06:49:19 +00002231.. _syslog-handler:
2232
Georg Brandl8ec7f652007-08-15 14:28:01 +00002233SysLogHandler
2234^^^^^^^^^^^^^
2235
2236The :class:`SysLogHandler` class, located in the :mod:`logging.handlers` module,
2237supports sending logging messages to a remote or local Unix syslog.
2238
2239
Vinay Sajip1c77b7f2009-10-10 20:32:36 +00002240.. class:: SysLogHandler([address[, facility[, socktype]]])
Georg Brandl8ec7f652007-08-15 14:28:01 +00002241
2242 Returns a new instance of the :class:`SysLogHandler` class intended to
2243 communicate with a remote Unix machine whose address is given by *address* in
2244 the form of a ``(host, port)`` tuple. If *address* is not specified,
Vinay Sajip1c77b7f2009-10-10 20:32:36 +00002245 ``('localhost', 514)`` is used. The address is used to open a socket. An
Georg Brandl8ec7f652007-08-15 14:28:01 +00002246 alternative to providing a ``(host, port)`` tuple is providing an address as a
2247 string, for example "/dev/log". In this case, a Unix domain socket is used to
2248 send the message to the syslog. If *facility* is not specified,
Vinay Sajip1c77b7f2009-10-10 20:32:36 +00002249 :const:`LOG_USER` is used. The type of socket opened depends on the
2250 *socktype* argument, which defaults to :const:`socket.SOCK_DGRAM` and thus
2251 opens a UDP socket. To open a TCP socket (for use with the newer syslog
2252 daemons such as rsyslog), specify a value of :const:`socket.SOCK_STREAM`.
2253
2254 .. versionchanged:: 2.7
2255 *socktype* was added.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002256
2257
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002258 .. method:: close()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002259
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002260 Closes the socket to the remote host.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002261
2262
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002263 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002264
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002265 The record is formatted, and then sent to the syslog server. If exception
2266 information is present, it is *not* sent to the server.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002267
2268
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002269 .. method:: encodePriority(facility, priority)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002270
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002271 Encodes the facility and priority into an integer. You can pass in strings
2272 or integers - if strings are passed, internal mapping dictionaries are
2273 used to convert them to integers.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002274
Vinay Sajipa3c39c02010-03-24 15:10:40 +00002275 The symbolic ``LOG_`` values are defined in :class:`SysLogHandler` and
2276 mirror the values defined in the ``sys/syslog.h`` header file.
Vinay Sajipb0623d62010-03-24 14:31:21 +00002277
Georg Brandld3bab6a2010-04-02 09:03:18 +00002278 **Priorities**
2279
Vinay Sajipb0623d62010-03-24 14:31:21 +00002280 +--------------------------+---------------+
2281 | Name (string) | Symbolic value|
2282 +==========================+===============+
2283 | ``alert`` | LOG_ALERT |
2284 +--------------------------+---------------+
2285 | ``crit`` or ``critical`` | LOG_CRIT |
2286 +--------------------------+---------------+
2287 | ``debug`` | LOG_DEBUG |
2288 +--------------------------+---------------+
2289 | ``emerg`` or ``panic`` | LOG_EMERG |
2290 +--------------------------+---------------+
2291 | ``err`` or ``error`` | LOG_ERR |
2292 +--------------------------+---------------+
2293 | ``info`` | LOG_INFO |
2294 +--------------------------+---------------+
2295 | ``notice`` | LOG_NOTICE |
2296 +--------------------------+---------------+
2297 | ``warn`` or ``warning`` | LOG_WARNING |
2298 +--------------------------+---------------+
2299
Georg Brandld3bab6a2010-04-02 09:03:18 +00002300 **Facilities**
2301
Vinay Sajipb0623d62010-03-24 14:31:21 +00002302 +---------------+---------------+
2303 | Name (string) | Symbolic value|
2304 +===============+===============+
2305 | ``auth`` | LOG_AUTH |
2306 +---------------+---------------+
2307 | ``authpriv`` | LOG_AUTHPRIV |
2308 +---------------+---------------+
2309 | ``cron`` | LOG_CRON |
2310 +---------------+---------------+
2311 | ``daemon`` | LOG_DAEMON |
2312 +---------------+---------------+
2313 | ``ftp`` | LOG_FTP |
2314 +---------------+---------------+
2315 | ``kern`` | LOG_KERN |
2316 +---------------+---------------+
2317 | ``lpr`` | LOG_LPR |
2318 +---------------+---------------+
2319 | ``mail`` | LOG_MAIL |
2320 +---------------+---------------+
2321 | ``news`` | LOG_NEWS |
2322 +---------------+---------------+
2323 | ``syslog`` | LOG_SYSLOG |
2324 +---------------+---------------+
2325 | ``user`` | LOG_USER |
2326 +---------------+---------------+
2327 | ``uucp`` | LOG_UUCP |
2328 +---------------+---------------+
2329 | ``local0`` | LOG_LOCAL0 |
2330 +---------------+---------------+
2331 | ``local1`` | LOG_LOCAL1 |
2332 +---------------+---------------+
2333 | ``local2`` | LOG_LOCAL2 |
2334 +---------------+---------------+
2335 | ``local3`` | LOG_LOCAL3 |
2336 +---------------+---------------+
2337 | ``local4`` | LOG_LOCAL4 |
2338 +---------------+---------------+
2339 | ``local5`` | LOG_LOCAL5 |
2340 +---------------+---------------+
2341 | ``local6`` | LOG_LOCAL6 |
2342 +---------------+---------------+
2343 | ``local7`` | LOG_LOCAL7 |
2344 +---------------+---------------+
2345
Vinay Sajip66d19e22010-03-24 17:36:35 +00002346 .. method:: mapPriority(levelname)
2347
2348 Maps a logging level name to a syslog priority name.
2349 You may need to override this if you are using custom levels, or
2350 if the default algorithm is not suitable for your needs. The
2351 default algorithm maps ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and
2352 ``CRITICAL`` to the equivalent syslog names, and all other level
2353 names to "warning".
Georg Brandl8ec7f652007-08-15 14:28:01 +00002354
Vinay Sajip4b782332009-01-19 06:49:19 +00002355.. _nt-eventlog-handler:
2356
Georg Brandl8ec7f652007-08-15 14:28:01 +00002357NTEventLogHandler
2358^^^^^^^^^^^^^^^^^
2359
2360The :class:`NTEventLogHandler` class, located in the :mod:`logging.handlers`
2361module, supports sending logging messages to a local Windows NT, Windows 2000 or
2362Windows XP event log. Before you can use it, you need Mark Hammond's Win32
2363extensions for Python installed.
2364
2365
2366.. class:: NTEventLogHandler(appname[, dllname[, logtype]])
2367
2368 Returns a new instance of the :class:`NTEventLogHandler` class. The *appname* is
2369 used to define the application name as it appears in the event log. An
2370 appropriate registry entry is created using this name. The *dllname* should give
2371 the fully qualified pathname of a .dll or .exe which contains message
2372 definitions to hold in the log (if not specified, ``'win32service.pyd'`` is used
2373 - this is installed with the Win32 extensions and contains some basic
2374 placeholder message definitions. Note that use of these placeholders will make
2375 your event logs big, as the entire message source is held in the log. If you
2376 want slimmer logs, you have to pass in the name of your own .dll or .exe which
2377 contains the message definitions you want to use in the event log). The
2378 *logtype* is one of ``'Application'``, ``'System'`` or ``'Security'``, and
2379 defaults to ``'Application'``.
2380
2381
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002382 .. method:: close()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002383
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002384 At this point, you can remove the application name from the registry as a
2385 source of event log entries. However, if you do this, you will not be able
2386 to see the events as you intended in the Event Log Viewer - it needs to be
2387 able to access the registry to get the .dll name. The current version does
Vinay Sajipaa5f8732008-09-01 17:44:14 +00002388 not do this.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002389
2390
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002391 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002392
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002393 Determines the message ID, event category and event type, and then logs
2394 the message in the NT event log.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002395
2396
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002397 .. method:: getEventCategory(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002398
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002399 Returns the event category for the record. Override this if you want to
2400 specify your own categories. This version returns 0.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002401
2402
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002403 .. method:: getEventType(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002404
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002405 Returns the event type for the record. Override this if you want to
2406 specify your own types. This version does a mapping using the handler's
2407 typemap attribute, which is set up in :meth:`__init__` to a dictionary
2408 which contains mappings for :const:`DEBUG`, :const:`INFO`,
2409 :const:`WARNING`, :const:`ERROR` and :const:`CRITICAL`. If you are using
2410 your own levels, you will either need to override this method or place a
2411 suitable dictionary in the handler's *typemap* attribute.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002412
2413
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002414 .. method:: getMessageID(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002415
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002416 Returns the message ID for the record. If you are using your own messages,
2417 you could do this by having the *msg* passed to the logger being an ID
2418 rather than a format string. Then, in here, you could use a dictionary
2419 lookup to get the message ID. This version returns 1, which is the base
2420 message ID in :file:`win32service.pyd`.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002421
Vinay Sajip4b782332009-01-19 06:49:19 +00002422.. _smtp-handler:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002423
2424SMTPHandler
2425^^^^^^^^^^^
2426
2427The :class:`SMTPHandler` class, located in the :mod:`logging.handlers` module,
2428supports sending logging messages to an email address via SMTP.
2429
2430
2431.. class:: SMTPHandler(mailhost, fromaddr, toaddrs, subject[, credentials])
2432
2433 Returns a new instance of the :class:`SMTPHandler` class. The instance is
2434 initialized with the from and to addresses and subject line of the email. The
2435 *toaddrs* should be a list of strings. To specify a non-standard SMTP port, use
2436 the (host, port) tuple format for the *mailhost* argument. If you use a string,
2437 the standard SMTP port is used. If your SMTP server requires authentication, you
2438 can specify a (username, password) tuple for the *credentials* argument.
2439
2440 .. versionchanged:: 2.6
2441 *credentials* was added.
2442
2443
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002444 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002445
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002446 Formats the record and sends it to the specified addressees.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002447
2448
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002449 .. method:: getSubject(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002450
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002451 If you want to specify a subject line which is record-dependent, override
2452 this method.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002453
Vinay Sajip4b782332009-01-19 06:49:19 +00002454.. _memory-handler:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002455
2456MemoryHandler
2457^^^^^^^^^^^^^
2458
2459The :class:`MemoryHandler` class, located in the :mod:`logging.handlers` module,
2460supports buffering of logging records in memory, periodically flushing them to a
2461:dfn:`target` handler. Flushing occurs whenever the buffer is full, or when an
2462event of a certain severity or greater is seen.
2463
2464:class:`MemoryHandler` is a subclass of the more general
2465:class:`BufferingHandler`, which is an abstract class. This buffers logging
2466records in memory. Whenever each record is added to the buffer, a check is made
2467by calling :meth:`shouldFlush` to see if the buffer should be flushed. If it
2468should, then :meth:`flush` is expected to do the needful.
2469
2470
2471.. class:: BufferingHandler(capacity)
2472
2473 Initializes the handler with a buffer of the specified capacity.
2474
2475
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002476 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002477
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002478 Appends the record to the buffer. If :meth:`shouldFlush` returns true,
2479 calls :meth:`flush` to process the buffer.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002480
2481
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002482 .. method:: flush()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002483
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002484 You can override this to implement custom flushing behavior. This version
2485 just zaps the buffer to empty.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002486
2487
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002488 .. method:: shouldFlush(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002489
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002490 Returns true if the buffer is up to capacity. This method can be
2491 overridden to implement custom flushing strategies.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002492
2493
2494.. class:: MemoryHandler(capacity[, flushLevel [, target]])
2495
2496 Returns a new instance of the :class:`MemoryHandler` class. The instance is
2497 initialized with a buffer size of *capacity*. If *flushLevel* is not specified,
2498 :const:`ERROR` is used. If no *target* is specified, the target will need to be
2499 set using :meth:`setTarget` before this handler does anything useful.
2500
2501
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002502 .. method:: close()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002503
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002504 Calls :meth:`flush`, sets the target to :const:`None` and clears the
2505 buffer.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002506
2507
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002508 .. method:: flush()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002509
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002510 For a :class:`MemoryHandler`, flushing means just sending the buffered
2511 records to the target, if there is one. Override if you want different
2512 behavior.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002513
2514
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002515 .. method:: setTarget(target)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002516
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002517 Sets the target handler for this handler.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002518
2519
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002520 .. method:: shouldFlush(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002521
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002522 Checks for buffer full or a record at the *flushLevel* or higher.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002523
2524
Vinay Sajip4b782332009-01-19 06:49:19 +00002525.. _http-handler:
2526
Georg Brandl8ec7f652007-08-15 14:28:01 +00002527HTTPHandler
2528^^^^^^^^^^^
2529
2530The :class:`HTTPHandler` class, located in the :mod:`logging.handlers` module,
2531supports sending logging messages to a Web server, using either ``GET`` or
2532``POST`` semantics.
2533
2534
2535.. class:: HTTPHandler(host, url[, method])
2536
2537 Returns a new instance of the :class:`HTTPHandler` class. The instance is
2538 initialized with a host address, url and HTTP method. The *host* can be of the
2539 form ``host:port``, should you need to use a specific port number. If no
2540 *method* is specified, ``GET`` is used.
2541
2542
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002543 .. method:: emit(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002544
Senthil Kumaranbd13f452010-08-09 20:14:11 +00002545 Sends the record to the Web server as a percent-encoded dictionary.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002546
2547
Vinay Sajip4b782332009-01-19 06:49:19 +00002548.. _formatter:
Georg Brandlc37f2882007-12-04 17:46:27 +00002549
Georg Brandl8ec7f652007-08-15 14:28:01 +00002550Formatter Objects
2551-----------------
2552
Georg Brandl430effb2009-01-01 13:05:13 +00002553.. currentmodule:: logging
2554
Georg Brandl8ec7f652007-08-15 14:28:01 +00002555:class:`Formatter`\ s have the following attributes and methods. They are
2556responsible for converting a :class:`LogRecord` to (usually) a string which can
2557be interpreted by either a human or an external system. The base
2558:class:`Formatter` allows a formatting string to be specified. If none is
2559supplied, the default value of ``'%(message)s'`` is used.
2560
2561A Formatter can be initialized with a format string which makes use of knowledge
2562of the :class:`LogRecord` attributes - such as the default value mentioned above
2563making use of the fact that the user's message and arguments are pre-formatted
2564into a :class:`LogRecord`'s *message* attribute. This format string contains
Ezio Melotti062d2b52009-12-19 22:41:49 +00002565standard Python %-style mapping keys. See section :ref:`string-formatting`
Georg Brandl8ec7f652007-08-15 14:28:01 +00002566for more information on string formatting.
2567
2568Currently, the useful mapping keys in a :class:`LogRecord` are:
2569
2570+-------------------------+-----------------------------------------------+
2571| Format | Description |
2572+=========================+===============================================+
2573| ``%(name)s`` | Name of the logger (logging channel). |
2574+-------------------------+-----------------------------------------------+
2575| ``%(levelno)s`` | Numeric logging level for the message |
2576| | (:const:`DEBUG`, :const:`INFO`, |
2577| | :const:`WARNING`, :const:`ERROR`, |
2578| | :const:`CRITICAL`). |
2579+-------------------------+-----------------------------------------------+
2580| ``%(levelname)s`` | Text logging level for the message |
2581| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
2582| | ``'ERROR'``, ``'CRITICAL'``). |
2583+-------------------------+-----------------------------------------------+
2584| ``%(pathname)s`` | Full pathname of the source file where the |
2585| | logging call was issued (if available). |
2586+-------------------------+-----------------------------------------------+
2587| ``%(filename)s`` | Filename portion of pathname. |
2588+-------------------------+-----------------------------------------------+
2589| ``%(module)s`` | Module (name portion of filename). |
2590+-------------------------+-----------------------------------------------+
2591| ``%(funcName)s`` | Name of function containing the logging call. |
2592+-------------------------+-----------------------------------------------+
2593| ``%(lineno)d`` | Source line number where the logging call was |
2594| | issued (if available). |
2595+-------------------------+-----------------------------------------------+
2596| ``%(created)f`` | Time when the :class:`LogRecord` was created |
2597| | (as returned by :func:`time.time`). |
2598+-------------------------+-----------------------------------------------+
2599| ``%(relativeCreated)d`` | Time in milliseconds when the LogRecord was |
2600| | created, relative to the time the logging |
2601| | module was loaded. |
2602+-------------------------+-----------------------------------------------+
2603| ``%(asctime)s`` | Human-readable time when the |
2604| | :class:`LogRecord` was created. By default |
2605| | this is of the form "2003-07-08 16:49:45,896" |
2606| | (the numbers after the comma are millisecond |
2607| | portion of the time). |
2608+-------------------------+-----------------------------------------------+
2609| ``%(msecs)d`` | Millisecond portion of the time when the |
2610| | :class:`LogRecord` was created. |
2611+-------------------------+-----------------------------------------------+
2612| ``%(thread)d`` | Thread ID (if available). |
2613+-------------------------+-----------------------------------------------+
2614| ``%(threadName)s`` | Thread name (if available). |
2615+-------------------------+-----------------------------------------------+
2616| ``%(process)d`` | Process ID (if available). |
2617+-------------------------+-----------------------------------------------+
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002618| ``%(processName)s`` | Process name (if available). |
2619+-------------------------+-----------------------------------------------+
Georg Brandl8ec7f652007-08-15 14:28:01 +00002620| ``%(message)s`` | The logged message, computed as ``msg % |
2621| | args``. |
2622+-------------------------+-----------------------------------------------+
2623
2624.. versionchanged:: 2.5
2625 *funcName* was added.
2626
Georg Brandl9855ddf2010-10-17 11:27:00 +00002627.. versionchanged:: 2.6
2628 *processName* was added.
2629
Georg Brandl8ec7f652007-08-15 14:28:01 +00002630
2631.. class:: Formatter([fmt[, datefmt]])
2632
2633 Returns a new instance of the :class:`Formatter` class. The instance is
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002634 initialized with a format string for the message as a whole, as well as a
2635 format string for the date/time portion of a message. If no *fmt* is
2636 specified, ``'%(message)s'`` is used. If no *datefmt* is specified, the
2637 ISO8601 date format is used.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002638
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002639 .. method:: format(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002640
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002641 The record's attribute dictionary is used as the operand to a string
2642 formatting operation. Returns the resulting string. Before formatting the
2643 dictionary, a couple of preparatory steps are carried out. The *message*
2644 attribute of the record is computed using *msg* % *args*. If the
2645 formatting string contains ``'(asctime)'``, :meth:`formatTime` is called
2646 to format the event time. If there is exception information, it is
2647 formatted using :meth:`formatException` and appended to the message. Note
2648 that the formatted exception information is cached in attribute
2649 *exc_text*. This is useful because the exception information can be
2650 pickled and sent across the wire, but you should be careful if you have
2651 more than one :class:`Formatter` subclass which customizes the formatting
2652 of exception information. In this case, you will have to clear the cached
2653 value after a formatter has done its formatting, so that the next
2654 formatter to handle the event doesn't use the cached value but
2655 recalculates it afresh.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002656
2657
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002658 .. method:: formatTime(record[, datefmt])
Georg Brandl8ec7f652007-08-15 14:28:01 +00002659
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002660 This method should be called from :meth:`format` by a formatter which
2661 wants to make use of a formatted time. This method can be overridden in
2662 formatters to provide for any specific requirement, but the basic behavior
2663 is as follows: if *datefmt* (a string) is specified, it is used with
2664 :func:`time.strftime` to format the creation time of the
2665 record. Otherwise, the ISO8601 format is used. The resulting string is
2666 returned.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002667
2668
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002669 .. method:: formatException(exc_info)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002670
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002671 Formats the specified exception information (a standard exception tuple as
2672 returned by :func:`sys.exc_info`) as a string. This default implementation
2673 just uses :func:`traceback.print_exception`. The resulting string is
2674 returned.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002675
Vinay Sajip4b782332009-01-19 06:49:19 +00002676.. _filter:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002677
2678Filter Objects
2679--------------
2680
Vinay Sajipfb7b5052010-09-17 12:45:26 +00002681:class:`Filter`\ s can be used by :class:`Handler`\ s and :class:`Logger`\ s for
Georg Brandl8ec7f652007-08-15 14:28:01 +00002682more sophisticated filtering than is provided by levels. The base filter class
2683only allows events which are below a certain point in the logger hierarchy. For
2684example, a filter initialized with "A.B" will allow events logged by loggers
2685"A.B", "A.B.C", "A.B.C.D", "A.B.D" etc. but not "A.BB", "B.A.B" etc. If
2686initialized with the empty string, all events are passed.
2687
2688
2689.. class:: Filter([name])
2690
2691 Returns an instance of the :class:`Filter` class. If *name* is specified, it
2692 names a logger which, together with its children, will have its events allowed
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002693 through the filter. If *name* is the empty string, allows every event.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002694
2695
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002696 .. method:: filter(record)
Georg Brandl8ec7f652007-08-15 14:28:01 +00002697
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002698 Is the specified record to be logged? Returns zero for no, nonzero for
2699 yes. If deemed appropriate, the record may be modified in-place by this
2700 method.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002701
Vinay Sajip3478ac02010-08-19 19:17:41 +00002702Note that filters attached to handlers are consulted whenever an event is
2703emitted by the handler, whereas filters attached to loggers are consulted
2704whenever an event is logged to the handler (using :meth:`debug`, :meth:`info`,
2705etc.) This means that events which have been generated by descendant loggers
2706will not be filtered by a logger's filter setting, unless the filter has also
2707been applied to those descendant loggers.
2708
Vinay Sajip7fc38242010-10-20 11:40:02 +00002709You don't actually need to subclass ``Filter``: you can pass any instance
2710which has a ``filter`` method with the same semantics.
2711
Vinay Sajipfb7b5052010-09-17 12:45:26 +00002712Other uses for filters
2713^^^^^^^^^^^^^^^^^^^^^^
2714
2715Although filters are used primarily to filter records based on more
2716sophisticated criteria than levels, they get to see every record which is
2717processed by the handler or logger they're attached to: this can be useful if
2718you want to do things like counting how many records were processed by a
2719particular logger or handler, or adding, changing or removing attributes in
2720the LogRecord being processed. Obviously changing the LogRecord needs to be
2721done with some care, but it does allow the injection of contextual information
2722into logs (see :ref:`filters-contextual`).
2723
Vinay Sajip4b782332009-01-19 06:49:19 +00002724.. _log-record:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002725
2726LogRecord Objects
2727-----------------
2728
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002729:class:`LogRecord` instances are created automatically by the :class:`Logger`
2730every time something is logged, and can be created manually via
2731:func:`makeLogRecord` (for example, from a pickled event received over the
2732wire).
Georg Brandl8ec7f652007-08-15 14:28:01 +00002733
2734
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002735.. class::
2736 LogRecord(name, lvl, pathname, lineno, msg, args, exc_info [, func=None])
Georg Brandl8ec7f652007-08-15 14:28:01 +00002737
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002738 Contains all the information pertinent to the event being logged.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002739
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002740 The primary information is passed in :attr:`msg` and :attr:`args`, which
2741 are combined using ``msg % args`` to create the :attr:`message` field of the
2742 record.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002743
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002744 .. attribute:: args
2745
2746 Tuple of arguments to be used in formatting :attr:`msg`.
2747
2748 .. attribute:: exc_info
2749
2750 Exception tuple (à la `sys.exc_info`) or `None` if no exception
Georg Brandl09302282010-10-06 09:32:48 +00002751 information is available.
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002752
2753 .. attribute:: func
2754
2755 Name of the function of origin (i.e. in which the logging call was made).
2756
2757 .. attribute:: lineno
2758
2759 Line number in the source file of origin.
2760
2761 .. attribute:: lvl
2762
2763 Numeric logging level.
2764
2765 .. attribute:: message
2766
2767 Bound to the result of :meth:`getMessage` when
2768 :meth:`Formatter.format(record)<Formatter.format>` is invoked.
2769
2770 .. attribute:: msg
2771
2772 User-supplied :ref:`format string<string-formatting>` or arbitrary object
2773 (see :ref:`arbitrary-object-messages`) used in :meth:`getMessage`.
2774
2775 .. attribute:: name
2776
2777 Name of the logger that emitted the record.
2778
2779 .. attribute:: pathname
2780
2781 Absolute pathname of the source file of origin.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002782
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002783 .. method:: getMessage()
Georg Brandl8ec7f652007-08-15 14:28:01 +00002784
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002785 Returns the message for this :class:`LogRecord` instance after merging any
Vinay Sajipfe08e6f2010-09-11 10:25:28 +00002786 user-supplied arguments with the message. If the user-supplied message
2787 argument to the logging call is not a string, :func:`str` is called on it to
2788 convert it to a string. This allows use of user-defined classes as
2789 messages, whose ``__str__`` method can return the actual format string to
2790 be used.
2791
2792 .. versionchanged:: 2.5
2793 *func* was added.
Benjamin Petersonc7b05922008-04-25 01:29:10 +00002794
Georg Brandl52f83952011-02-25 10:39:23 +00002795
Vinay Sajip4b782332009-01-19 06:49:19 +00002796.. _logger-adapter:
Georg Brandl8ec7f652007-08-15 14:28:01 +00002797
Vinay Sajipc7403352008-01-18 15:54:14 +00002798LoggerAdapter Objects
2799---------------------
2800
2801.. versionadded:: 2.6
2802
2803:class:`LoggerAdapter` instances are used to conveniently pass contextual
Vinay Sajip733024a2008-01-21 17:39:22 +00002804information into logging calls. For a usage example , see the section on
Georg Brandl52f83952011-02-25 10:39:23 +00002805:ref:`adding contextual information to your logging output <context-info>`.
Vinay Sajip733024a2008-01-21 17:39:22 +00002806
Vinay Sajipc7403352008-01-18 15:54:14 +00002807
2808.. class:: LoggerAdapter(logger, extra)
2809
Georg Brandl52f83952011-02-25 10:39:23 +00002810 Returns an instance of :class:`LoggerAdapter` initialized with an
2811 underlying :class:`Logger` instance and a dict-like object.
Vinay Sajipc7403352008-01-18 15:54:14 +00002812
Georg Brandl52f83952011-02-25 10:39:23 +00002813 .. method:: process(msg, kwargs)
Vinay Sajipc7403352008-01-18 15:54:14 +00002814
Georg Brandl52f83952011-02-25 10:39:23 +00002815 Modifies the message and/or keyword arguments passed to a logging call in
2816 order to insert contextual information. This implementation takes the object
2817 passed as *extra* to the constructor and adds it to *kwargs* using key
2818 'extra'. The return value is a (*msg*, *kwargs*) tuple which has the
2819 (possibly modified) versions of the arguments passed in.
Vinay Sajipc7403352008-01-18 15:54:14 +00002820
2821In addition to the above, :class:`LoggerAdapter` supports all the logging
2822methods of :class:`Logger`, i.e. :meth:`debug`, :meth:`info`, :meth:`warning`,
2823:meth:`error`, :meth:`exception`, :meth:`critical` and :meth:`log`. These
2824methods have the same signatures as their counterparts in :class:`Logger`, so
2825you can use the two types of instances interchangeably.
2826
Vinay Sajip804899b2010-03-22 15:29:01 +00002827.. versionchanged:: 2.7
2828
2829The :meth:`isEnabledFor` method was added to :class:`LoggerAdapter`. This method
2830delegates to the underlying logger.
2831
Georg Brandl8ec7f652007-08-15 14:28:01 +00002832
2833Thread Safety
2834-------------
2835
2836The logging module is intended to be thread-safe without any special work
2837needing to be done by its clients. It achieves this though using threading
2838locks; there is one lock to serialize access to the module's shared data, and
2839each handler also creates a lock to serialize access to its underlying I/O.
2840
Vinay Sajip353a85f2009-04-03 21:58:16 +00002841If you are implementing asynchronous signal handlers using the :mod:`signal`
2842module, you may not be able to use logging from within such handlers. This is
2843because lock implementations in the :mod:`threading` module are not always
2844re-entrant, and so cannot be invoked from such signal handlers.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002845
Vinay Sajip61afd262010-02-19 23:53:17 +00002846
2847Integration with the warnings module
2848------------------------------------
2849
2850The :func:`captureWarnings` function can be used to integrate :mod:`logging`
2851with the :mod:`warnings` module.
2852
2853.. function:: captureWarnings(capture)
2854
2855 This function is used to turn the capture of warnings by logging on and
2856 off.
2857
Georg Brandlf6d367452010-03-12 10:02:03 +00002858 If *capture* is ``True``, warnings issued by the :mod:`warnings` module
Vinay Sajip61afd262010-02-19 23:53:17 +00002859 will be redirected to the logging system. Specifically, a warning will be
2860 formatted using :func:`warnings.formatwarning` and the resulting string
Georg Brandlf6d367452010-03-12 10:02:03 +00002861 logged to a logger named "py.warnings" with a severity of ``WARNING``.
Vinay Sajip61afd262010-02-19 23:53:17 +00002862
Georg Brandlf6d367452010-03-12 10:02:03 +00002863 If *capture* is ``False``, the redirection of warnings to the logging system
Vinay Sajip61afd262010-02-19 23:53:17 +00002864 will stop, and warnings will be redirected to their original destinations
Georg Brandlf6d367452010-03-12 10:02:03 +00002865 (i.e. those in effect before ``captureWarnings(True)`` was called).
Vinay Sajip61afd262010-02-19 23:53:17 +00002866
2867
Georg Brandl8ec7f652007-08-15 14:28:01 +00002868Configuration
2869-------------
2870
2871
2872.. _logging-config-api:
2873
2874Configuration functions
2875^^^^^^^^^^^^^^^^^^^^^^^
2876
Georg Brandl8ec7f652007-08-15 14:28:01 +00002877The following functions configure the logging module. They are located in the
2878:mod:`logging.config` module. Their use is optional --- you can configure the
2879logging module using these functions or by making calls to the main API (defined
2880in :mod:`logging` itself) and defining handlers which are declared either in
2881:mod:`logging` or :mod:`logging.handlers`.
2882
Vinay Sajipaf1fee02011-03-29 01:07:50 +01002883.. currentmodule:: logging.config
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +00002884
Vinay Sajip3c599bd2011-03-29 01:21:48 +01002885.. function:: dictConfig(config)
2886
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +00002887 Takes the logging configuration from a dictionary. The contents of
2888 this dictionary are described in :ref:`logging-config-dictschema`
2889 below.
2890
2891 If an error is encountered during configuration, this function will
2892 raise a :exc:`ValueError`, :exc:`TypeError`, :exc:`AttributeError`
2893 or :exc:`ImportError` with a suitably descriptive message. The
2894 following is a (possibly incomplete) list of conditions which will
2895 raise an error:
2896
2897 * A ``level`` which is not a string or which is a string not
2898 corresponding to an actual logging level.
2899 * A ``propagate`` value which is not a boolean.
2900 * An id which does not have a corresponding destination.
2901 * A non-existent handler id found during an incremental call.
2902 * An invalid logger name.
2903 * Inability to resolve to an internal or external object.
2904
2905 Parsing is performed by the :class:`DictConfigurator` class, whose
2906 constructor is passed the dictionary used for configuration, and
2907 has a :meth:`configure` method. The :mod:`logging.config` module
2908 has a callable attribute :attr:`dictConfigClass`
2909 which is initially set to :class:`DictConfigurator`.
2910 You can replace the value of :attr:`dictConfigClass` with a
2911 suitable implementation of your own.
2912
2913 :func:`dictConfig` calls :attr:`dictConfigClass` passing
2914 the specified dictionary, and then calls the :meth:`configure` method on
2915 the returned object to put the configuration into effect::
2916
2917 def dictConfig(config):
2918 dictConfigClass(config).configure()
2919
2920 For example, a subclass of :class:`DictConfigurator` could call
2921 ``DictConfigurator.__init__()`` in its own :meth:`__init__()`, then
2922 set up custom prefixes which would be usable in the subsequent
2923 :meth:`configure` call. :attr:`dictConfigClass` would be bound to
2924 this new subclass, and then :func:`dictConfig` could be called exactly as
2925 in the default, uncustomized state.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002926
Vinay Sajip2e881042011-01-27 19:14:16 +00002927 .. versionadded:: 2.7
2928
Georg Brandl8ec7f652007-08-15 14:28:01 +00002929.. function:: fileConfig(fname[, defaults])
2930
Vinay Sajip51104862009-01-02 18:53:04 +00002931 Reads the logging configuration from a :mod:`ConfigParser`\-format file named
2932 *fname*. This function can be called several times from an application,
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +00002933 allowing an end user to select from various pre-canned
Vinay Sajip51104862009-01-02 18:53:04 +00002934 configurations (if the developer provides a mechanism to present the choices
2935 and load the chosen configuration). Defaults to be passed to the ConfigParser
2936 can be specified in the *defaults* argument.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002937
Georg Brandl8ec7f652007-08-15 14:28:01 +00002938.. function:: listen([port])
2939
2940 Starts up a socket server on the specified port, and listens for new
2941 configurations. If no port is specified, the module's default
2942 :const:`DEFAULT_LOGGING_CONFIG_PORT` is used. Logging configurations will be
2943 sent as a file suitable for processing by :func:`fileConfig`. Returns a
2944 :class:`Thread` instance on which you can call :meth:`start` to start the
2945 server, and which you can :meth:`join` when appropriate. To stop the server,
Georg Brandlc37f2882007-12-04 17:46:27 +00002946 call :func:`stopListening`.
2947
2948 To send a configuration to the socket, read in the configuration file and
2949 send it to the socket as a string of bytes preceded by a four-byte length
2950 string packed in binary using ``struct.pack('>L', n)``.
Georg Brandl8ec7f652007-08-15 14:28:01 +00002951
2952
2953.. function:: stopListening()
2954
Georg Brandlc37f2882007-12-04 17:46:27 +00002955 Stops the listening server which was created with a call to :func:`listen`.
2956 This is typically called before calling :meth:`join` on the return value from
Georg Brandl8ec7f652007-08-15 14:28:01 +00002957 :func:`listen`.
2958
Vinay Sajipaf1fee02011-03-29 01:07:50 +01002959.. currentmodule:: logging
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +00002960
Vinay Sajip3c599bd2011-03-29 01:21:48 +01002961.. _logging-config-dictschema:
2962
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +00002963Configuration dictionary schema
2964^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
2965
2966Describing a logging configuration requires listing the various
2967objects to create and the connections between them; for example, you
2968may create a handler named "console" and then say that the logger
2969named "startup" will send its messages to the "console" handler.
2970These objects aren't limited to those provided by the :mod:`logging`
2971module because you might write your own formatter or handler class.
2972The parameters to these classes may also need to include external
2973objects such as ``sys.stderr``. The syntax for describing these
2974objects and connections is defined in :ref:`logging-config-dict-connections`
2975below.
2976
2977Dictionary Schema Details
2978"""""""""""""""""""""""""
2979
2980The dictionary passed to :func:`dictConfig` must contain the following
2981keys:
2982
2983* `version` - to be set to an integer value representing the schema
2984 version. The only valid value at present is 1, but having this key
2985 allows the schema to evolve while still preserving backwards
2986 compatibility.
2987
2988All other keys are optional, but if present they will be interpreted
2989as described below. In all cases below where a 'configuring dict' is
2990mentioned, it will be checked for the special ``'()'`` key to see if a
Andrew M. Kuchling1b553472010-05-16 23:31:16 +00002991custom instantiation is required. If so, the mechanism described in
2992:ref:`logging-config-dict-userdef` below is used to create an instance;
2993otherwise, the context is used to determine what to instantiate.
Andrew M. Kuchlingf09bc662010-05-12 18:56:48 +00002994
2995* `formatters` - the corresponding value will be a dict in which each
2996 key is a formatter id and each value is a dict describing how to
2997 configure the corresponding Formatter instance.
2998
2999 The configuring dict is searched for keys ``format`` and ``datefmt``
3000 (with defaults of ``None``) and these are used to construct a
3001 :class:`logging.Formatter` instance.
3002
3003* `filters` - the corresponding value will be a dict in which each key
3004 is a filter id and each value is a dict describing how to configure
3005 the corresponding Filter instance.
3006
3007 The configuring dict is searched for the key ``name`` (defaulting to the
3008 empty string) and this is used to construct a :class:`logging.Filter`
3009 instance.
3010
3011* `handlers` - the corresponding value will be a dict in which each
3012 key is a handler id and each value is a dict describing how to
3013 configure the corresponding Handler instance.
3014
3015 The configuring dict is searched for the following keys:
3016
3017 * ``class`` (mandatory). This is the fully qualified name of the
3018 handler class.
3019
3020 * ``level`` (optional). The level of the handler.
3021
3022 * ``formatter`` (optional). The id of the formatter for this
3023 handler.
3024
3025 * ``filters`` (optional). A list of ids of the filters for this
3026 handler.
3027
3028 All *other* keys are passed through as keyword arguments to the
3029 handler's constructor. For example, given the snippet::
3030
3031 handlers:
3032 console:
3033 class : logging.StreamHandler
3034 formatter: brief
3035 level : INFO
3036 filters: [allow_foo]
3037 stream : ext://sys.stdout
3038 file:
3039 class : logging.handlers.RotatingFileHandler
3040 formatter: precise
3041 filename: logconfig.log
3042 maxBytes: 1024
3043 backupCount: 3
3044
3045 the handler with id ``console`` is instantiated as a
3046 :class:`logging.StreamHandler`, using ``sys.stdout`` as the underlying
3047 stream. The handler with id ``file`` is instantiated as a
3048 :class:`logging.handlers.RotatingFileHandler` with the keyword arguments
3049 ``filename='logconfig.log', maxBytes=1024, backupCount=3``.
3050
3051* `loggers` - the corresponding value will be a dict in which each key
3052 is a logger name and each value is a dict describing how to
3053 configure the corresponding Logger instance.
3054
3055 The configuring dict is searched for the following keys:
3056
3057 * ``level`` (optional). The level of the logger.
3058
3059 * ``propagate`` (optional). The propagation setting of the logger.
3060
3061 * ``filters`` (optional). A list of ids of the filters for this
3062 logger.
3063
3064 * ``handlers`` (optional). A list of ids of the handlers for this
3065 logger.
3066
3067 The specified loggers will be configured according to the level,
3068 propagation, filters and handlers specified.
3069
3070* `root` - this will be the configuration for the root logger.
3071 Processing of the configuration will be as for any logger, except
3072 that the ``propagate`` setting will not be applicable.
3073
3074* `incremental` - whether the configuration is to be interpreted as
3075 incremental to the existing configuration. This value defaults to
3076 ``False``, which means that the specified configuration replaces the
3077 existing configuration with the same semantics as used by the
3078 existing :func:`fileConfig` API.
3079
3080 If the specified value is ``True``, the configuration is processed
3081 as described in the section on :ref:`logging-config-dict-incremental`.
3082
3083* `disable_existing_loggers` - whether any existing loggers are to be
3084 disabled. This setting mirrors the parameter of the same name in
3085 :func:`fileConfig`. If absent, this parameter defaults to ``True``.
3086 This value is ignored if `incremental` is ``True``.
3087
3088.. _logging-config-dict-incremental:
3089
3090Incremental Configuration
3091"""""""""""""""""""""""""
3092
3093It is difficult to provide complete flexibility for incremental
3094configuration. For example, because objects such as filters
3095and formatters are anonymous, once a configuration is set up, it is
3096not possible to refer to such anonymous objects when augmenting a
3097configuration.
3098
3099Furthermore, there is not a compelling case for arbitrarily altering
3100the object graph of loggers, handlers, filters, formatters at
3101run-time, once a configuration is set up; the verbosity of loggers and
3102handlers can be controlled just by setting levels (and, in the case of
3103loggers, propagation flags). Changing the object graph arbitrarily in
3104a safe way is problematic in a multi-threaded environment; while not
3105impossible, the benefits are not worth the complexity it adds to the
3106implementation.
3107
3108Thus, when the ``incremental`` key of a configuration dict is present
3109and is ``True``, the system will completely ignore any ``formatters`` and
3110``filters`` entries, and process only the ``level``
3111settings in the ``handlers`` entries, and the ``level`` and
3112``propagate`` settings in the ``loggers`` and ``root`` entries.
3113
3114Using a value in the configuration dict lets configurations to be sent
3115over the wire as pickled dicts to a socket listener. Thus, the logging
3116verbosity of a long-running application can be altered over time with
3117no need to stop and restart the application.
3118
3119.. _logging-config-dict-connections:
3120
3121Object connections
3122""""""""""""""""""
3123
3124The schema describes a set of logging objects - loggers,
3125handlers, formatters, filters - which are connected to each other in
3126an object graph. Thus, the schema needs to represent connections
3127between the objects. For example, say that, once configured, a
3128particular logger has attached to it a particular handler. For the
3129purposes of this discussion, we can say that the logger represents the
3130source, and the handler the destination, of a connection between the
3131two. Of course in the configured objects this is represented by the
3132logger holding a reference to the handler. In the configuration dict,
3133this is done by giving each destination object an id which identifies
3134it unambiguously, and then using the id in the source object's
3135configuration to indicate that a connection exists between the source
3136and the destination object with that id.
3137
3138So, for example, consider the following YAML snippet::
3139
3140 formatters:
3141 brief:
3142 # configuration for formatter with id 'brief' goes here
3143 precise:
3144 # configuration for formatter with id 'precise' goes here
3145 handlers:
3146 h1: #This is an id
3147 # configuration of handler with id 'h1' goes here
3148 formatter: brief
3149 h2: #This is another id
3150 # configuration of handler with id 'h2' goes here
3151 formatter: precise
3152 loggers:
3153 foo.bar.baz:
3154 # other configuration for logger 'foo.bar.baz'
3155 handlers: [h1, h2]
3156
3157(Note: YAML used here because it's a little more readable than the
3158equivalent Python source form for the dictionary.)
3159
3160The ids for loggers are the logger names which would be used
3161programmatically to obtain a reference to those loggers, e.g.
3162``foo.bar.baz``. The ids for Formatters and Filters can be any string
3163value (such as ``brief``, ``precise`` above) and they are transient,
3164in that they are only meaningful for processing the configuration
3165dictionary and used to determine connections between objects, and are
3166not persisted anywhere when the configuration call is complete.
3167
3168The above snippet indicates that logger named ``foo.bar.baz`` should
3169have two handlers attached to it, which are described by the handler
3170ids ``h1`` and ``h2``. The formatter for ``h1`` is that described by id
3171``brief``, and the formatter for ``h2`` is that described by id
3172``precise``.
3173
3174
3175.. _logging-config-dict-userdef:
3176
3177User-defined objects
3178""""""""""""""""""""
3179
3180The schema supports user-defined objects for handlers, filters and
3181formatters. (Loggers do not need to have different types for
3182different instances, so there is no support in this configuration
3183schema for user-defined logger classes.)
3184
3185Objects to be configured are described by dictionaries
3186which detail their configuration. In some places, the logging system
3187will be able to infer from the context how an object is to be
3188instantiated, but when a user-defined object is to be instantiated,
3189the system will not know how to do this. In order to provide complete
3190flexibility for user-defined object instantiation, the user needs
3191to provide a 'factory' - a callable which is called with a
3192configuration dictionary and which returns the instantiated object.
3193This is signalled by an absolute import path to the factory being
3194made available under the special key ``'()'``. Here's a concrete
3195example::
3196
3197 formatters:
3198 brief:
3199 format: '%(message)s'
3200 default:
3201 format: '%(asctime)s %(levelname)-8s %(name)-15s %(message)s'
3202 datefmt: '%Y-%m-%d %H:%M:%S'
3203 custom:
3204 (): my.package.customFormatterFactory
3205 bar: baz
3206 spam: 99.9
3207 answer: 42
3208
3209The above YAML snippet defines three formatters. The first, with id
3210``brief``, is a standard :class:`logging.Formatter` instance with the
3211specified format string. The second, with id ``default``, has a
3212longer format and also defines the time format explicitly, and will
3213result in a :class:`logging.Formatter` initialized with those two format
3214strings. Shown in Python source form, the ``brief`` and ``default``
3215formatters have configuration sub-dictionaries::
3216
3217 {
3218 'format' : '%(message)s'
3219 }
3220
3221and::
3222
3223 {
3224 'format' : '%(asctime)s %(levelname)-8s %(name)-15s %(message)s',
3225 'datefmt' : '%Y-%m-%d %H:%M:%S'
3226 }
3227
3228respectively, and as these dictionaries do not contain the special key
3229``'()'``, the instantiation is inferred from the context: as a result,
3230standard :class:`logging.Formatter` instances are created. The
3231configuration sub-dictionary for the third formatter, with id
3232``custom``, is::
3233
3234 {
3235 '()' : 'my.package.customFormatterFactory',
3236 'bar' : 'baz',
3237 'spam' : 99.9,
3238 'answer' : 42
3239 }
3240
3241and this contains the special key ``'()'``, which means that
3242user-defined instantiation is wanted. In this case, the specified
3243factory callable will be used. If it is an actual callable it will be
3244used directly - otherwise, if you specify a string (as in the example)
3245the actual callable will be located using normal import mechanisms.
3246The callable will be called with the **remaining** items in the
3247configuration sub-dictionary as keyword arguments. In the above
3248example, the formatter with id ``custom`` will be assumed to be
3249returned by the call::
3250
3251 my.package.customFormatterFactory(bar='baz', spam=99.9, answer=42)
3252
3253The key ``'()'`` has been used as the special key because it is not a
3254valid keyword parameter name, and so will not clash with the names of
3255the keyword arguments used in the call. The ``'()'`` also serves as a
3256mnemonic that the corresponding value is a callable.
3257
3258
3259.. _logging-config-dict-externalobj:
3260
3261Access to external objects
3262""""""""""""""""""""""""""
3263
3264There are times where a configuration needs to refer to objects
3265external to the configuration, for example ``sys.stderr``. If the
3266configuration dict is constructed using Python code, this is
3267straightforward, but a problem arises when the configuration is
3268provided via a text file (e.g. JSON, YAML). In a text file, there is
3269no standard way to distinguish ``sys.stderr`` from the literal string
3270``'sys.stderr'``. To facilitate this distinction, the configuration
3271system looks for certain special prefixes in string values and
3272treat them specially. For example, if the literal string
3273``'ext://sys.stderr'`` is provided as a value in the configuration,
3274then the ``ext://`` will be stripped off and the remainder of the
3275value processed using normal import mechanisms.
3276
3277The handling of such prefixes is done in a way analogous to protocol
3278handling: there is a generic mechanism to look for prefixes which
3279match the regular expression ``^(?P<prefix>[a-z]+)://(?P<suffix>.*)$``
3280whereby, if the ``prefix`` is recognised, the ``suffix`` is processed
3281in a prefix-dependent manner and the result of the processing replaces
3282the string value. If the prefix is not recognised, then the string
3283value will be left as-is.
3284
3285
3286.. _logging-config-dict-internalobj:
3287
3288Access to internal objects
3289""""""""""""""""""""""""""
3290
3291As well as external objects, there is sometimes also a need to refer
3292to objects in the configuration. This will be done implicitly by the
3293configuration system for things that it knows about. For example, the
3294string value ``'DEBUG'`` for a ``level`` in a logger or handler will
3295automatically be converted to the value ``logging.DEBUG``, and the
3296``handlers``, ``filters`` and ``formatter`` entries will take an
3297object id and resolve to the appropriate destination object.
3298
3299However, a more generic mechanism is needed for user-defined
3300objects which are not known to the :mod:`logging` module. For
3301example, consider :class:`logging.handlers.MemoryHandler`, which takes
3302a ``target`` argument which is another handler to delegate to. Since
3303the system already knows about this class, then in the configuration,
3304the given ``target`` just needs to be the object id of the relevant
3305target handler, and the system will resolve to the handler from the
3306id. If, however, a user defines a ``my.package.MyHandler`` which has
3307an ``alternate`` handler, the configuration system would not know that
3308the ``alternate`` referred to a handler. To cater for this, a generic
3309resolution system allows the user to specify::
3310
3311 handlers:
3312 file:
3313 # configuration of file handler goes here
3314
3315 custom:
3316 (): my.package.MyHandler
3317 alternate: cfg://handlers.file
3318
3319The literal string ``'cfg://handlers.file'`` will be resolved in an
3320analogous way to strings with the ``ext://`` prefix, but looking
3321in the configuration itself rather than the import namespace. The
3322mechanism allows access by dot or by index, in a similar way to
3323that provided by ``str.format``. Thus, given the following snippet::
3324
3325 handlers:
3326 email:
3327 class: logging.handlers.SMTPHandler
3328 mailhost: localhost
3329 fromaddr: my_app@domain.tld
3330 toaddrs:
3331 - support_team@domain.tld
3332 - dev_team@domain.tld
3333 subject: Houston, we have a problem.
3334
3335in the configuration, the string ``'cfg://handlers'`` would resolve to
3336the dict with key ``handlers``, the string ``'cfg://handlers.email``
3337would resolve to the dict with key ``email`` in the ``handlers`` dict,
3338and so on. The string ``'cfg://handlers.email.toaddrs[1]`` would
3339resolve to ``'dev_team.domain.tld'`` and the string
3340``'cfg://handlers.email.toaddrs[0]'`` would resolve to the value
3341``'support_team@domain.tld'``. The ``subject`` value could be accessed
3342using either ``'cfg://handlers.email.subject'`` or, equivalently,
3343``'cfg://handlers.email[subject]'``. The latter form only needs to be
3344used if the key contains spaces or non-alphanumeric characters. If an
3345index value consists only of decimal digits, access will be attempted
3346using the corresponding integer value, falling back to the string
3347value if needed.
3348
3349Given a string ``cfg://handlers.myhandler.mykey.123``, this will
3350resolve to ``config_dict['handlers']['myhandler']['mykey']['123']``.
3351If the string is specified as ``cfg://handlers.myhandler.mykey[123]``,
3352the system will attempt to retrieve the value from
3353``config_dict['handlers']['myhandler']['mykey'][123]``, and fall back
3354to ``config_dict['handlers']['myhandler']['mykey']['123']`` if that
3355fails.
3356
Georg Brandl8ec7f652007-08-15 14:28:01 +00003357.. _logging-config-fileformat:
3358
3359Configuration file format
3360^^^^^^^^^^^^^^^^^^^^^^^^^
3361
Georg Brandl392c6fc2008-05-25 07:25:25 +00003362The configuration file format understood by :func:`fileConfig` is based on
Vinay Sajip51104862009-01-02 18:53:04 +00003363:mod:`ConfigParser` functionality. The file must contain sections called
3364``[loggers]``, ``[handlers]`` and ``[formatters]`` which identify by name the
3365entities of each type which are defined in the file. For each such entity,
3366there is a separate section which identifies how that entity is configured.
3367Thus, for a logger named ``log01`` in the ``[loggers]`` section, the relevant
3368configuration details are held in a section ``[logger_log01]``. Similarly, a
3369handler called ``hand01`` in the ``[handlers]`` section will have its
3370configuration held in a section called ``[handler_hand01]``, while a formatter
3371called ``form01`` in the ``[formatters]`` section will have its configuration
3372specified in a section called ``[formatter_form01]``. The root logger
3373configuration must be specified in a section called ``[logger_root]``.
Georg Brandl8ec7f652007-08-15 14:28:01 +00003374
3375Examples of these sections in the file are given below. ::
3376
3377 [loggers]
3378 keys=root,log02,log03,log04,log05,log06,log07
3379
3380 [handlers]
3381 keys=hand01,hand02,hand03,hand04,hand05,hand06,hand07,hand08,hand09
3382
3383 [formatters]
3384 keys=form01,form02,form03,form04,form05,form06,form07,form08,form09
3385
3386The root logger must specify a level and a list of handlers. An example of a
3387root logger section is given below. ::
3388
3389 [logger_root]
3390 level=NOTSET
3391 handlers=hand01
3392
3393The ``level`` entry can be one of ``DEBUG, INFO, WARNING, ERROR, CRITICAL`` or
3394``NOTSET``. For the root logger only, ``NOTSET`` means that all messages will be
3395logged. Level values are :func:`eval`\ uated in the context of the ``logging``
3396package's namespace.
3397
3398The ``handlers`` entry is a comma-separated list of handler names, which must
3399appear in the ``[handlers]`` section. These names must appear in the
3400``[handlers]`` section and have corresponding sections in the configuration
3401file.
3402
3403For loggers other than the root logger, some additional information is required.
3404This is illustrated by the following example. ::
3405
3406 [logger_parser]
3407 level=DEBUG
3408 handlers=hand01
3409 propagate=1
3410 qualname=compiler.parser
3411
3412The ``level`` and ``handlers`` entries are interpreted as for the root logger,
3413except that if a non-root logger's level is specified as ``NOTSET``, the system
3414consults loggers higher up the hierarchy to determine the effective level of the
3415logger. The ``propagate`` entry is set to 1 to indicate that messages must
3416propagate to handlers higher up the logger hierarchy from this logger, or 0 to
3417indicate that messages are **not** propagated to handlers up the hierarchy. The
3418``qualname`` entry is the hierarchical channel name of the logger, that is to
3419say the name used by the application to get the logger.
3420
3421Sections which specify handler configuration are exemplified by the following.
3422::
3423
3424 [handler_hand01]
3425 class=StreamHandler
3426 level=NOTSET
3427 formatter=form01
3428 args=(sys.stdout,)
3429
3430The ``class`` entry indicates the handler's class (as determined by :func:`eval`
3431in the ``logging`` package's namespace). The ``level`` is interpreted as for
3432loggers, and ``NOTSET`` is taken to mean "log everything".
3433
Vinay Sajip2a649f92008-07-18 09:00:35 +00003434.. versionchanged:: 2.6
3435 Added support for resolving the handler's class as a dotted module and class
3436 name.
3437
Georg Brandl8ec7f652007-08-15 14:28:01 +00003438The ``formatter`` entry indicates the key name of the formatter for this
3439handler. If blank, a default formatter (``logging._defaultFormatter``) is used.
3440If a name is specified, it must appear in the ``[formatters]`` section and have
3441a corresponding section in the configuration file.
3442
3443The ``args`` entry, when :func:`eval`\ uated in the context of the ``logging``
3444package's namespace, is the list of arguments to the constructor for the handler
3445class. Refer to the constructors for the relevant handlers, or to the examples
3446below, to see how typical entries are constructed. ::
3447
3448 [handler_hand02]
3449 class=FileHandler
3450 level=DEBUG
3451 formatter=form02
3452 args=('python.log', 'w')
3453
3454 [handler_hand03]
3455 class=handlers.SocketHandler
3456 level=INFO
3457 formatter=form03
3458 args=('localhost', handlers.DEFAULT_TCP_LOGGING_PORT)
3459
3460 [handler_hand04]
3461 class=handlers.DatagramHandler
3462 level=WARN
3463 formatter=form04
3464 args=('localhost', handlers.DEFAULT_UDP_LOGGING_PORT)
3465
3466 [handler_hand05]
3467 class=handlers.SysLogHandler
3468 level=ERROR
3469 formatter=form05
3470 args=(('localhost', handlers.SYSLOG_UDP_PORT), handlers.SysLogHandler.LOG_USER)
3471
3472 [handler_hand06]
3473 class=handlers.NTEventLogHandler
3474 level=CRITICAL
3475 formatter=form06
3476 args=('Python Application', '', 'Application')
3477
3478 [handler_hand07]
3479 class=handlers.SMTPHandler
3480 level=WARN
3481 formatter=form07
3482 args=('localhost', 'from@abc', ['user1@abc', 'user2@xyz'], 'Logger Subject')
3483
3484 [handler_hand08]
3485 class=handlers.MemoryHandler
3486 level=NOTSET
3487 formatter=form08
3488 target=
3489 args=(10, ERROR)
3490
3491 [handler_hand09]
3492 class=handlers.HTTPHandler
3493 level=NOTSET
3494 formatter=form09
3495 args=('localhost:9022', '/log', 'GET')
3496
3497Sections which specify formatter configuration are typified by the following. ::
3498
3499 [formatter_form01]
3500 format=F1 %(asctime)s %(levelname)s %(message)s
3501 datefmt=
3502 class=logging.Formatter
3503
3504The ``format`` entry is the overall format string, and the ``datefmt`` entry is
Georg Brandlb19be572007-12-29 10:57:00 +00003505the :func:`strftime`\ -compatible date/time format string. If empty, the
3506package substitutes ISO8601 format date/times, which is almost equivalent to
3507specifying the date format string ``"%Y-%m-%d %H:%M:%S"``. The ISO8601 format
3508also specifies milliseconds, which are appended to the result of using the above
3509format string, with a comma separator. An example time in ISO8601 format is
3510``2003-01-23 00:29:50,411``.
Georg Brandl8ec7f652007-08-15 14:28:01 +00003511
3512The ``class`` entry is optional. It indicates the name of the formatter's class
3513(as a dotted module and class name.) This option is useful for instantiating a
3514:class:`Formatter` subclass. Subclasses of :class:`Formatter` can present
3515exception tracebacks in an expanded or condensed format.
3516
Georg Brandlc37f2882007-12-04 17:46:27 +00003517
3518Configuration server example
3519^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3520
3521Here is an example of a module using the logging configuration server::
3522
3523 import logging
3524 import logging.config
3525 import time
3526 import os
3527
3528 # read initial config file
3529 logging.config.fileConfig("logging.conf")
3530
3531 # create and start listener on port 9999
3532 t = logging.config.listen(9999)
3533 t.start()
3534
3535 logger = logging.getLogger("simpleExample")
3536
3537 try:
3538 # loop through logging calls to see the difference
3539 # new configurations make, until Ctrl+C is pressed
3540 while True:
3541 logger.debug("debug message")
3542 logger.info("info message")
3543 logger.warn("warn message")
3544 logger.error("error message")
3545 logger.critical("critical message")
3546 time.sleep(5)
3547 except KeyboardInterrupt:
3548 # cleanup
3549 logging.config.stopListening()
3550 t.join()
3551
3552And here is a script that takes a filename and sends that file to the server,
3553properly preceded with the binary-encoded length, as the new logging
3554configuration::
3555
3556 #!/usr/bin/env python
Benjamin Petersona7b55a32009-02-20 03:31:23 +00003557 import socket, sys, struct
Georg Brandlc37f2882007-12-04 17:46:27 +00003558
3559 data_to_send = open(sys.argv[1], "r").read()
3560
3561 HOST = 'localhost'
3562 PORT = 9999
3563 s = socket.socket(socket.AF_INET, socket.SOCK_STREAM)
3564 print "connecting..."
3565 s.connect((HOST, PORT))
3566 print "sending config..."
3567 s.send(struct.pack(">L", len(data_to_send)))
3568 s.send(data_to_send)
3569 s.close()
3570 print "complete"
3571
3572
3573More examples
3574-------------
3575
3576Multiple handlers and formatters
3577^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3578
3579Loggers are plain Python objects. The :func:`addHandler` method has no minimum
3580or maximum quota for the number of handlers you may add. Sometimes it will be
3581beneficial for an application to log all messages of all severities to a text
3582file while simultaneously logging errors or above to the console. To set this
3583up, simply configure the appropriate handlers. The logging calls in the
3584application code will remain unchanged. Here is a slight modification to the
3585previous simple module-based configuration example::
3586
3587 import logging
3588
3589 logger = logging.getLogger("simple_example")
3590 logger.setLevel(logging.DEBUG)
3591 # create file handler which logs even debug messages
3592 fh = logging.FileHandler("spam.log")
3593 fh.setLevel(logging.DEBUG)
3594 # create console handler with a higher log level
3595 ch = logging.StreamHandler()
3596 ch.setLevel(logging.ERROR)
3597 # create formatter and add it to the handlers
3598 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3599 ch.setFormatter(formatter)
3600 fh.setFormatter(formatter)
3601 # add the handlers to logger
3602 logger.addHandler(ch)
3603 logger.addHandler(fh)
3604
3605 # "application" code
3606 logger.debug("debug message")
3607 logger.info("info message")
3608 logger.warn("warn message")
3609 logger.error("error message")
3610 logger.critical("critical message")
3611
3612Notice that the "application" code does not care about multiple handlers. All
3613that changed was the addition and configuration of a new handler named *fh*.
3614
3615The ability to create new handlers with higher- or lower-severity filters can be
3616very helpful when writing and testing an application. Instead of using many
3617``print`` statements for debugging, use ``logger.debug``: Unlike the print
3618statements, which you will have to delete or comment out later, the logger.debug
3619statements can remain intact in the source code and remain dormant until you
3620need them again. At that time, the only change that needs to happen is to
3621modify the severity level of the logger and/or handler to debug.
3622
3623
3624Using logging in multiple modules
3625^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3626
3627It was mentioned above that multiple calls to
3628``logging.getLogger('someLogger')`` return a reference to the same logger
3629object. This is true not only within the same module, but also across modules
3630as long as it is in the same Python interpreter process. It is true for
3631references to the same object; additionally, application code can define and
3632configure a parent logger in one module and create (but not configure) a child
3633logger in a separate module, and all logger calls to the child will pass up to
3634the parent. Here is a main module::
3635
3636 import logging
3637 import auxiliary_module
3638
3639 # create logger with "spam_application"
3640 logger = logging.getLogger("spam_application")
3641 logger.setLevel(logging.DEBUG)
3642 # create file handler which logs even debug messages
3643 fh = logging.FileHandler("spam.log")
3644 fh.setLevel(logging.DEBUG)
3645 # create console handler with a higher log level
3646 ch = logging.StreamHandler()
3647 ch.setLevel(logging.ERROR)
3648 # create formatter and add it to the handlers
3649 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3650 fh.setFormatter(formatter)
3651 ch.setFormatter(formatter)
3652 # add the handlers to the logger
3653 logger.addHandler(fh)
3654 logger.addHandler(ch)
3655
3656 logger.info("creating an instance of auxiliary_module.Auxiliary")
3657 a = auxiliary_module.Auxiliary()
3658 logger.info("created an instance of auxiliary_module.Auxiliary")
3659 logger.info("calling auxiliary_module.Auxiliary.do_something")
3660 a.do_something()
3661 logger.info("finished auxiliary_module.Auxiliary.do_something")
3662 logger.info("calling auxiliary_module.some_function()")
3663 auxiliary_module.some_function()
3664 logger.info("done with auxiliary_module.some_function()")
3665
3666Here is the auxiliary module::
3667
3668 import logging
3669
3670 # create logger
3671 module_logger = logging.getLogger("spam_application.auxiliary")
3672
3673 class Auxiliary:
3674 def __init__(self):
3675 self.logger = logging.getLogger("spam_application.auxiliary.Auxiliary")
3676 self.logger.info("creating an instance of Auxiliary")
3677 def do_something(self):
3678 self.logger.info("doing something")
3679 a = 1 + 1
3680 self.logger.info("done doing something")
3681
3682 def some_function():
3683 module_logger.info("received a call to \"some_function\"")
3684
3685The output looks like this::
3686
Vinay Sajipe28fa292008-01-07 15:30:36 +00003687 2005-03-23 23:47:11,663 - spam_application - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003688 creating an instance of auxiliary_module.Auxiliary
Vinay Sajipe28fa292008-01-07 15:30:36 +00003689 2005-03-23 23:47:11,665 - spam_application.auxiliary.Auxiliary - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003690 creating an instance of Auxiliary
Vinay Sajipe28fa292008-01-07 15:30:36 +00003691 2005-03-23 23:47:11,665 - spam_application - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003692 created an instance of auxiliary_module.Auxiliary
Vinay Sajipe28fa292008-01-07 15:30:36 +00003693 2005-03-23 23:47:11,668 - spam_application - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003694 calling auxiliary_module.Auxiliary.do_something
Vinay Sajipe28fa292008-01-07 15:30:36 +00003695 2005-03-23 23:47:11,668 - spam_application.auxiliary.Auxiliary - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003696 doing something
Vinay Sajipe28fa292008-01-07 15:30:36 +00003697 2005-03-23 23:47:11,669 - spam_application.auxiliary.Auxiliary - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003698 done doing something
Vinay Sajipe28fa292008-01-07 15:30:36 +00003699 2005-03-23 23:47:11,670 - spam_application - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003700 finished auxiliary_module.Auxiliary.do_something
Vinay Sajipe28fa292008-01-07 15:30:36 +00003701 2005-03-23 23:47:11,671 - spam_application - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003702 calling auxiliary_module.some_function()
Vinay Sajipe28fa292008-01-07 15:30:36 +00003703 2005-03-23 23:47:11,672 - spam_application.auxiliary - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003704 received a call to "some_function"
Vinay Sajipe28fa292008-01-07 15:30:36 +00003705 2005-03-23 23:47:11,673 - spam_application - INFO -
Georg Brandlc37f2882007-12-04 17:46:27 +00003706 done with auxiliary_module.some_function()
3707