blob: 43dacaf515b4eb400fd22eec7feb38e25cc22c8a [file] [log] [blame]
Georg Brandl116aa622007-08-15 14:28:22 +00001:mod:`logging` --- Logging facility for Python
2==============================================
3
4.. module:: logging
5 :synopsis: Flexible error logging system for applications.
6
7
8.. moduleauthor:: Vinay Sajip <vinay_sajip@red-dove.com>
9.. sectionauthor:: Vinay Sajip <vinay_sajip@red-dove.com>
10
11
Georg Brandl116aa622007-08-15 14:28:22 +000012.. index:: pair: Errors; logging
13
Georg Brandl116aa622007-08-15 14:28:22 +000014This module defines functions and classes which implement a flexible error
15logging system for applications.
16
17Logging is performed by calling methods on instances of the :class:`Logger`
18class (hereafter called :dfn:`loggers`). Each instance has a name, and they are
Georg Brandl9afde1c2007-11-01 20:32:30 +000019conceptually arranged in a namespace hierarchy using dots (periods) as
Georg Brandl116aa622007-08-15 14:28:22 +000020separators. For example, a logger named "scan" is the parent of loggers
21"scan.text", "scan.html" and "scan.pdf". Logger names can be anything you want,
22and indicate the area of an application in which a logged message originates.
23
24Logged messages also have levels of importance associated with them. The default
25levels provided are :const:`DEBUG`, :const:`INFO`, :const:`WARNING`,
26:const:`ERROR` and :const:`CRITICAL`. As a convenience, you indicate the
27importance of a logged message by calling an appropriate method of
28:class:`Logger`. The methods are :meth:`debug`, :meth:`info`, :meth:`warning`,
29:meth:`error` and :meth:`critical`, which mirror the default levels. You are not
30constrained to use these levels: you can specify your own and use a more general
31:class:`Logger` method, :meth:`log`, which takes an explicit level argument.
32
Christian Heimes8b0facf2007-12-04 19:30:01 +000033
34Logging tutorial
35----------------
36
37The key benefit of having the logging API provided by a standard library module
38is that all Python modules can participate in logging, so your application log
39can include messages from third-party modules.
40
41It is, of course, possible to log messages with different verbosity levels or to
42different destinations. Support for writing log messages to files, HTTP
43GET/POST locations, email via SMTP, generic sockets, or OS-specific logging
Christian Heimesc3f30c42008-02-22 16:37:40 +000044mechanisms are all supported by the standard module. You can also create your
Christian Heimes8b0facf2007-12-04 19:30:01 +000045own log destination class if you have special requirements not met by any of the
46built-in classes.
47
48Simple examples
49^^^^^^^^^^^^^^^
50
51.. sectionauthor:: Doug Hellmann
52.. (see <http://blog.doughellmann.com/2007/05/pymotw-logging.html>)
53
54Most applications are probably going to want to log to a file, so let's start
55with that case. Using the :func:`basicConfig` function, we can set up the
Benjamin Petersond7c3ed52010-06-27 22:32:30 +000056default handler so that debug messages are written to a file (in the example,
57we assume that you have the appropriate permissions to create a file called
58*example.log* in the current directory)::
Christian Heimes8b0facf2007-12-04 19:30:01 +000059
60 import logging
Benjamin Petersond7c3ed52010-06-27 22:32:30 +000061 LOG_FILENAME = 'example.log'
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +000062 logging.basicConfig(filename=LOG_FILENAME,level=logging.DEBUG)
Christian Heimes8b0facf2007-12-04 19:30:01 +000063
64 logging.debug('This message should go to the log file')
65
66And now if we open the file and look at what we have, we should find the log
67message::
68
69 DEBUG:root:This message should go to the log file
70
71If you run the script repeatedly, the additional log messages are appended to
Eric Smith5c01a8d2009-06-04 18:20:51 +000072the file. To create a new file each time, you can pass a *filemode* argument to
Christian Heimes8b0facf2007-12-04 19:30:01 +000073:func:`basicConfig` with a value of ``'w'``. Rather than managing the file size
74yourself, though, it is simpler to use a :class:`RotatingFileHandler`::
75
76 import glob
77 import logging
78 import logging.handlers
79
Benjamin Petersond7c3ed52010-06-27 22:32:30 +000080 LOG_FILENAME = 'logging_rotatingfile_example.out'
Christian Heimes8b0facf2007-12-04 19:30:01 +000081
82 # Set up a specific logger with our desired output level
83 my_logger = logging.getLogger('MyLogger')
84 my_logger.setLevel(logging.DEBUG)
85
86 # Add the log message handler to the logger
87 handler = logging.handlers.RotatingFileHandler(
88 LOG_FILENAME, maxBytes=20, backupCount=5)
89
90 my_logger.addHandler(handler)
91
92 # Log some messages
93 for i in range(20):
94 my_logger.debug('i = %d' % i)
95
96 # See what files are created
97 logfiles = glob.glob('%s*' % LOG_FILENAME)
98
99 for filename in logfiles:
Georg Brandlf6945182008-02-01 11:56:49 +0000100 print(filename)
Christian Heimes8b0facf2007-12-04 19:30:01 +0000101
102The result should be 6 separate files, each with part of the log history for the
103application::
104
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000105 logging_rotatingfile_example.out
106 logging_rotatingfile_example.out.1
107 logging_rotatingfile_example.out.2
108 logging_rotatingfile_example.out.3
109 logging_rotatingfile_example.out.4
110 logging_rotatingfile_example.out.5
Christian Heimes8b0facf2007-12-04 19:30:01 +0000111
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000112The most current file is always :file:`logging_rotatingfile_example.out`,
Christian Heimes8b0facf2007-12-04 19:30:01 +0000113and each time it reaches the size limit it is renamed with the suffix
114``.1``. Each of the existing backup files is renamed to increment the suffix
Eric Smith5c01a8d2009-06-04 18:20:51 +0000115(``.1`` becomes ``.2``, etc.) and the ``.6`` file is erased.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000116
117Obviously this example sets the log length much much too small as an extreme
118example. You would want to set *maxBytes* to an appropriate value.
119
120Another useful feature of the logging API is the ability to produce different
121messages at different log levels. This allows you to instrument your code with
122debug messages, for example, but turning the log level down so that those debug
123messages are not written for your production system. The default levels are
Vinay Sajipb6d065f2009-10-28 23:28:16 +0000124``NOTSET``, ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and ``CRITICAL``.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000125
126The logger, handler, and log message call each specify a level. The log message
127is only emitted if the handler and logger are configured to emit messages of
128that level or lower. For example, if a message is ``CRITICAL``, and the logger
129is set to ``ERROR``, the message is emitted. If a message is a ``WARNING``, and
130the logger is set to produce only ``ERROR``\s, the message is not emitted::
131
132 import logging
133 import sys
134
135 LEVELS = {'debug': logging.DEBUG,
136 'info': logging.INFO,
137 'warning': logging.WARNING,
138 'error': logging.ERROR,
139 'critical': logging.CRITICAL}
140
141 if len(sys.argv) > 1:
142 level_name = sys.argv[1]
143 level = LEVELS.get(level_name, logging.NOTSET)
144 logging.basicConfig(level=level)
145
146 logging.debug('This is a debug message')
147 logging.info('This is an info message')
148 logging.warning('This is a warning message')
149 logging.error('This is an error message')
150 logging.critical('This is a critical error message')
151
152Run the script with an argument like 'debug' or 'warning' to see which messages
153show up at different levels::
154
155 $ python logging_level_example.py debug
156 DEBUG:root:This is a debug message
157 INFO:root:This is an info message
158 WARNING:root:This is a warning message
159 ERROR:root:This is an error message
160 CRITICAL:root:This is a critical error message
161
162 $ python logging_level_example.py info
163 INFO:root:This is an info message
164 WARNING:root:This is a warning message
165 ERROR:root:This is an error message
166 CRITICAL:root:This is a critical error message
167
168You will notice that these log messages all have ``root`` embedded in them. The
169logging module supports a hierarchy of loggers with different names. An easy
170way to tell where a specific log message comes from is to use a separate logger
171object for each of your modules. Each new logger "inherits" the configuration
172of its parent, and log messages sent to a logger include the name of that
173logger. Optionally, each logger can be configured differently, so that messages
174from different modules are handled in different ways. Let's look at a simple
175example of how to log from different modules so it is easy to trace the source
176of the message::
177
178 import logging
179
180 logging.basicConfig(level=logging.WARNING)
181
182 logger1 = logging.getLogger('package1.module1')
183 logger2 = logging.getLogger('package2.module2')
184
185 logger1.warning('This message comes from one module')
186 logger2.warning('And this message comes from another module')
187
188And the output::
189
190 $ python logging_modules_example.py
191 WARNING:package1.module1:This message comes from one module
192 WARNING:package2.module2:And this message comes from another module
193
194There are many more options for configuring logging, including different log
195message formatting options, having messages delivered to multiple destinations,
196and changing the configuration of a long-running application on the fly using a
197socket interface. All of these options are covered in depth in the library
198module documentation.
199
200Loggers
201^^^^^^^
202
203The logging library takes a modular approach and offers the several categories
204of components: loggers, handlers, filters, and formatters. Loggers expose the
205interface that application code directly uses. Handlers send the log records to
206the appropriate destination. Filters provide a finer grained facility for
207determining which log records to send on to a handler. Formatters specify the
208layout of the resultant log record.
209
210:class:`Logger` objects have a threefold job. First, they expose several
211methods to application code so that applications can log messages at runtime.
212Second, logger objects determine which log messages to act upon based upon
213severity (the default filtering facility) or filter objects. Third, logger
214objects pass along relevant log messages to all interested log handlers.
215
216The most widely used methods on logger objects fall into two categories:
217configuration and message sending.
218
219* :meth:`Logger.setLevel` specifies the lowest-severity log message a logger
220 will handle, where debug is the lowest built-in severity level and critical is
221 the highest built-in severity. For example, if the severity level is info,
222 the logger will handle only info, warning, error, and critical messages and
223 will ignore debug messages.
224
225* :meth:`Logger.addFilter` and :meth:`Logger.removeFilter` add and remove filter
226 objects from the logger object. This tutorial does not address filters.
227
228With the logger object configured, the following methods create log messages:
229
230* :meth:`Logger.debug`, :meth:`Logger.info`, :meth:`Logger.warning`,
231 :meth:`Logger.error`, and :meth:`Logger.critical` all create log records with
232 a message and a level that corresponds to their respective method names. The
233 message is actually a format string, which may contain the standard string
234 substitution syntax of :const:`%s`, :const:`%d`, :const:`%f`, and so on. The
235 rest of their arguments is a list of objects that correspond with the
236 substitution fields in the message. With regard to :const:`**kwargs`, the
237 logging methods care only about a keyword of :const:`exc_info` and use it to
238 determine whether to log exception information.
239
240* :meth:`Logger.exception` creates a log message similar to
241 :meth:`Logger.error`. The difference is that :meth:`Logger.exception` dumps a
242 stack trace along with it. Call this method only from an exception handler.
243
244* :meth:`Logger.log` takes a log level as an explicit argument. This is a
245 little more verbose for logging messages than using the log level convenience
246 methods listed above, but this is how to log at custom log levels.
247
Christian Heimesdcca98d2008-02-25 13:19:43 +0000248:func:`getLogger` returns a reference to a logger instance with the specified
Vinay Sajipc15dfd62010-07-06 15:08:55 +0000249name if it is provided, or ``root`` if not. The names are period-separated
Christian Heimes8b0facf2007-12-04 19:30:01 +0000250hierarchical structures. Multiple calls to :func:`getLogger` with the same name
251will return a reference to the same logger object. Loggers that are further
252down in the hierarchical list are children of loggers higher up in the list.
253For example, given a logger with a name of ``foo``, loggers with names of
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000254``foo.bar``, ``foo.bar.baz``, and ``foo.bam`` are all descendants of ``foo``.
255Child loggers propagate messages up to the handlers associated with their
256ancestor loggers. Because of this, it is unnecessary to define and configure
257handlers for all the loggers an application uses. It is sufficient to
258configure handlers for a top-level logger and create child loggers as needed.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000259
260
261Handlers
262^^^^^^^^
263
264:class:`Handler` objects are responsible for dispatching the appropriate log
265messages (based on the log messages' severity) to the handler's specified
266destination. Logger objects can add zero or more handler objects to themselves
267with an :func:`addHandler` method. As an example scenario, an application may
268want to send all log messages to a log file, all log messages of error or higher
269to stdout, and all messages of critical to an email address. This scenario
Christian Heimesc3f30c42008-02-22 16:37:40 +0000270requires three individual handlers where each handler is responsible for sending
Christian Heimes8b0facf2007-12-04 19:30:01 +0000271messages of a specific severity to a specific location.
272
273The standard library includes quite a few handler types; this tutorial uses only
274:class:`StreamHandler` and :class:`FileHandler` in its examples.
275
276There are very few methods in a handler for application developers to concern
277themselves with. The only handler methods that seem relevant for application
278developers who are using the built-in handler objects (that is, not creating
279custom handlers) are the following configuration methods:
280
281* The :meth:`Handler.setLevel` method, just as in logger objects, specifies the
282 lowest severity that will be dispatched to the appropriate destination. Why
283 are there two :func:`setLevel` methods? The level set in the logger
284 determines which severity of messages it will pass to its handlers. The level
285 set in each handler determines which messages that handler will send on.
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000286
287* :func:`setFormatter` selects a Formatter object for this handler to use.
Christian Heimes8b0facf2007-12-04 19:30:01 +0000288
289* :func:`addFilter` and :func:`removeFilter` respectively configure and
290 deconfigure filter objects on handlers.
291
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000292Application code should not directly instantiate and use instances of
293:class:`Handler`. Instead, the :class:`Handler` class is a base class that
294defines the interface that all handlers should have and establishes some
295default behavior that child classes can use (or override).
Christian Heimes8b0facf2007-12-04 19:30:01 +0000296
297
298Formatters
299^^^^^^^^^^
300
301Formatter objects configure the final order, structure, and contents of the log
Christian Heimesdcca98d2008-02-25 13:19:43 +0000302message. Unlike the base :class:`logging.Handler` class, application code may
Christian Heimes8b0facf2007-12-04 19:30:01 +0000303instantiate formatter classes, although you could likely subclass the formatter
304if your application needs special behavior. The constructor takes two optional
305arguments: a message format string and a date format string. If there is no
306message format string, the default is to use the raw message. If there is no
307date format string, the default date format is::
308
309 %Y-%m-%d %H:%M:%S
310
311with the milliseconds tacked on at the end.
312
313The message format string uses ``%(<dictionary key>)s`` styled string
314substitution; the possible keys are documented in :ref:`formatter-objects`.
315
316The following message format string will log the time in a human-readable
317format, the severity of the message, and the contents of the message, in that
318order::
319
320 "%(asctime)s - %(levelname)s - %(message)s"
321
Vinay Sajip40d9a4e2010-08-30 18:10:03 +0000322Formatters use a user-configurable function to convert the creation time of a
323record to a tuple. By default, :func:`time.localtime` is used; to change this
324for a particular formatter instance, set the ``converter`` attribute of the
325instance to a function with the same signature as :func:`time.localtime` or
326:func:`time.gmtime`. To change it for all formatters, for example if you want
327all logging times to be shown in GMT, set the ``converter`` attribute in the
328Formatter class (to ``time.gmtime`` for GMT display).
329
Christian Heimes8b0facf2007-12-04 19:30:01 +0000330
331Configuring Logging
332^^^^^^^^^^^^^^^^^^^
333
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000334Programmers can configure logging in three ways:
335
3361. Creating loggers, handlers, and formatters explicitly using Python
337 code that calls the configuration methods listed above.
3382. Creating a logging config file and reading it using the :func:`fileConfig`
339 function.
3403. Creating a dictionary of configuration information and passing it
341 to the :func:`dictConfig` function.
342
343The following example configures a very simple logger, a console
344handler, and a simple formatter using Python code::
Christian Heimes8b0facf2007-12-04 19:30:01 +0000345
346 import logging
347
348 # create logger
349 logger = logging.getLogger("simple_example")
350 logger.setLevel(logging.DEBUG)
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000351
Christian Heimes8b0facf2007-12-04 19:30:01 +0000352 # create console handler and set level to debug
353 ch = logging.StreamHandler()
354 ch.setLevel(logging.DEBUG)
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000355
Christian Heimes8b0facf2007-12-04 19:30:01 +0000356 # create formatter
357 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000358
Christian Heimes8b0facf2007-12-04 19:30:01 +0000359 # add formatter to ch
360 ch.setFormatter(formatter)
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000361
Christian Heimes8b0facf2007-12-04 19:30:01 +0000362 # add ch to logger
363 logger.addHandler(ch)
364
365 # "application" code
366 logger.debug("debug message")
367 logger.info("info message")
368 logger.warn("warn message")
369 logger.error("error message")
370 logger.critical("critical message")
371
372Running this module from the command line produces the following output::
373
374 $ python simple_logging_module.py
375 2005-03-19 15:10:26,618 - simple_example - DEBUG - debug message
376 2005-03-19 15:10:26,620 - simple_example - INFO - info message
377 2005-03-19 15:10:26,695 - simple_example - WARNING - warn message
378 2005-03-19 15:10:26,697 - simple_example - ERROR - error message
379 2005-03-19 15:10:26,773 - simple_example - CRITICAL - critical message
380
381The following Python module creates a logger, handler, and formatter nearly
382identical to those in the example listed above, with the only difference being
383the names of the objects::
384
385 import logging
386 import logging.config
387
388 logging.config.fileConfig("logging.conf")
389
390 # create logger
391 logger = logging.getLogger("simpleExample")
392
393 # "application" code
394 logger.debug("debug message")
395 logger.info("info message")
396 logger.warn("warn message")
397 logger.error("error message")
398 logger.critical("critical message")
399
400Here is the logging.conf file::
401
402 [loggers]
403 keys=root,simpleExample
404
405 [handlers]
406 keys=consoleHandler
407
408 [formatters]
409 keys=simpleFormatter
410
411 [logger_root]
412 level=DEBUG
413 handlers=consoleHandler
414
415 [logger_simpleExample]
416 level=DEBUG
417 handlers=consoleHandler
418 qualname=simpleExample
419 propagate=0
420
421 [handler_consoleHandler]
422 class=StreamHandler
423 level=DEBUG
424 formatter=simpleFormatter
425 args=(sys.stdout,)
426
427 [formatter_simpleFormatter]
428 format=%(asctime)s - %(name)s - %(levelname)s - %(message)s
429 datefmt=
430
431The output is nearly identical to that of the non-config-file-based example::
432
433 $ python simple_logging_config.py
434 2005-03-19 15:38:55,977 - simpleExample - DEBUG - debug message
435 2005-03-19 15:38:55,979 - simpleExample - INFO - info message
436 2005-03-19 15:38:56,054 - simpleExample - WARNING - warn message
437 2005-03-19 15:38:56,055 - simpleExample - ERROR - error message
438 2005-03-19 15:38:56,130 - simpleExample - CRITICAL - critical message
439
440You can see that the config file approach has a few advantages over the Python
441code approach, mainly separation of configuration and code and the ability of
442noncoders to easily modify the logging properties.
443
Benjamin Peterson9451a1c2010-03-13 22:30:34 +0000444Note that the class names referenced in config files need to be either relative
445to the logging module, or absolute values which can be resolved using normal
446import mechanisms. Thus, you could use either `handlers.WatchedFileHandler`
447(relative to the logging module) or `mypackage.mymodule.MyHandler` (for a
448class defined in package `mypackage` and module `mymodule`, where `mypackage`
449is available on the Python import path).
450
Benjamin Peterson56894b52010-06-28 00:16:12 +0000451In Python 3.2, a new means of configuring logging has been introduced, using
Benjamin Petersond7c3ed52010-06-27 22:32:30 +0000452dictionaries to hold configuration information. This provides a superset of the
453functionality of the config-file-based approach outlined above, and is the
454recommended configuration method for new applications and deployments. Because
455a Python dictionary is used to hold configuration information, and since you
456can populate that dictionary using different means, you have more options for
457configuration. For example, you can use a configuration file in JSON format,
458or, if you have access to YAML processing functionality, a file in YAML
459format, to populate the configuration dictionary. Or, of course, you can
460construct the dictionary in Python code, receive it in pickled form over a
461socket, or use whatever approach makes sense for your application.
462
463Here's an example of the same configuration as above, in YAML format for
464the new dictionary-based approach::
465
466 version: 1
467 formatters:
468 simple:
469 format: format=%(asctime)s - %(name)s - %(levelname)s - %(message)s
470 handlers:
471 console:
472 class: logging.StreamHandler
473 level: DEBUG
474 formatter: simple
475 stream: ext://sys.stdout
476 loggers:
477 simpleExample:
478 level: DEBUG
479 handlers: [console]
480 propagate: no
481 root:
482 level: DEBUG
483 handlers: [console]
484
485For more information about logging using a dictionary, see
486:ref:`logging-config-api`.
487
Vinay Sajip26a2d5e2009-01-10 13:37:26 +0000488.. _library-config:
Vinay Sajip30bf1222009-01-10 19:23:34 +0000489
Benjamin Peterson3e4f0552008-09-02 00:31:15 +0000490Configuring Logging for a Library
491^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
492
493When developing a library which uses logging, some consideration needs to be
494given to its configuration. If the using application does not use logging, and
495library code makes logging calls, then a one-off message "No handlers could be
496found for logger X.Y.Z" is printed to the console. This message is intended
497to catch mistakes in logging configuration, but will confuse an application
498developer who is not aware of logging by the library.
499
500In addition to documenting how a library uses logging, a good way to configure
501library logging so that it does not cause a spurious message is to add a
502handler which does nothing. This avoids the message being printed, since a
503handler will be found: it just doesn't produce any output. If the library user
504configures logging for application use, presumably that configuration will add
505some handlers, and if levels are suitably configured then logging calls made
506in library code will send output to those handlers, as normal.
507
508A do-nothing handler can be simply defined as follows::
509
510 import logging
511
512 class NullHandler(logging.Handler):
513 def emit(self, record):
514 pass
515
516An instance of this handler should be added to the top-level logger of the
517logging namespace used by the library. If all logging by a library *foo* is
518done using loggers with names matching "foo.x.y", then the code::
519
520 import logging
521
522 h = NullHandler()
523 logging.getLogger("foo").addHandler(h)
524
525should have the desired effect. If an organisation produces a number of
526libraries, then the logger name specified can be "orgname.foo" rather than
527just "foo".
528
Georg Brandlf9734072008-12-07 15:30:06 +0000529.. versionadded:: 3.1
Vinay Sajip4039aff2010-09-11 10:25:28 +0000530
Georg Brandl67b21b72010-08-17 15:07:14 +0000531 The :class:`NullHandler` class was not present in previous versions, but is
532 now included, so that it need not be defined in library code.
Georg Brandlf9734072008-12-07 15:30:06 +0000533
534
Christian Heimes8b0facf2007-12-04 19:30:01 +0000535
536Logging Levels
537--------------
538
Georg Brandl116aa622007-08-15 14:28:22 +0000539The numeric values of logging levels are given in the following table. These are
540primarily of interest if you want to define your own levels, and need them to
541have specific values relative to the predefined levels. If you define a level
542with the same numeric value, it overwrites the predefined value; the predefined
543name is lost.
544
545+--------------+---------------+
546| Level | Numeric value |
547+==============+===============+
548| ``CRITICAL`` | 50 |
549+--------------+---------------+
550| ``ERROR`` | 40 |
551+--------------+---------------+
552| ``WARNING`` | 30 |
553+--------------+---------------+
554| ``INFO`` | 20 |
555+--------------+---------------+
556| ``DEBUG`` | 10 |
557+--------------+---------------+
558| ``NOTSET`` | 0 |
559+--------------+---------------+
560
561Levels can also be associated with loggers, being set either by the developer or
562through loading a saved logging configuration. When a logging method is called
563on a logger, the logger compares its own level with the level associated with
564the method call. If the logger's level is higher than the method call's, no
565logging message is actually generated. This is the basic mechanism controlling
566the verbosity of logging output.
567
568Logging messages are encoded as instances of the :class:`LogRecord` class. When
569a logger decides to actually log an event, a :class:`LogRecord` instance is
570created from the logging message.
571
572Logging messages are subjected to a dispatch mechanism through the use of
573:dfn:`handlers`, which are instances of subclasses of the :class:`Handler`
574class. Handlers are responsible for ensuring that a logged message (in the form
575of a :class:`LogRecord`) ends up in a particular location (or set of locations)
576which is useful for the target audience for that message (such as end users,
577support desk staff, system administrators, developers). Handlers are passed
578:class:`LogRecord` instances intended for particular destinations. Each logger
579can have zero, one or more handlers associated with it (via the
580:meth:`addHandler` method of :class:`Logger`). In addition to any handlers
581directly associated with a logger, *all handlers associated with all ancestors
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000582of the logger* are called to dispatch the message (unless the *propagate* flag
583for a logger is set to a false value, at which point the passing to ancestor
584handlers stops).
Georg Brandl116aa622007-08-15 14:28:22 +0000585
586Just as for loggers, handlers can have levels associated with them. A handler's
587level acts as a filter in the same way as a logger's level does. If a handler
588decides to actually dispatch an event, the :meth:`emit` method is used to send
589the message to its destination. Most user-defined subclasses of :class:`Handler`
590will need to override this :meth:`emit`.
591
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000592.. _custom-levels:
593
594Custom Levels
595^^^^^^^^^^^^^
596
597Defining your own levels is possible, but should not be necessary, as the
598existing levels have been chosen on the basis of practical experience.
599However, if you are convinced that you need custom levels, great care should
600be exercised when doing this, and it is possibly *a very bad idea to define
601custom levels if you are developing a library*. That's because if multiple
602library authors all define their own custom levels, there is a chance that
603the logging output from such multiple libraries used together will be
604difficult for the using developer to control and/or interpret, because a
605given numeric value might mean different things for different libraries.
606
607
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000608Useful Handlers
609---------------
610
Georg Brandl116aa622007-08-15 14:28:22 +0000611In addition to the base :class:`Handler` class, many useful subclasses are
612provided:
613
Vinay Sajip121a1c42010-09-08 10:46:15 +0000614#. :class:`StreamHandler` instances send messages to streams (file-like
Georg Brandl116aa622007-08-15 14:28:22 +0000615 objects).
616
Vinay Sajip121a1c42010-09-08 10:46:15 +0000617#. :class:`FileHandler` instances send messages to disk files.
Georg Brandl116aa622007-08-15 14:28:22 +0000618
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000619.. module:: logging.handlers
Vinay Sajip30bf1222009-01-10 19:23:34 +0000620
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000621#. :class:`BaseRotatingHandler` is the base class for handlers that
622 rotate log files at a certain point. It is not meant to be instantiated
623 directly. Instead, use :class:`RotatingFileHandler` or
624 :class:`TimedRotatingFileHandler`.
Georg Brandl116aa622007-08-15 14:28:22 +0000625
Vinay Sajip121a1c42010-09-08 10:46:15 +0000626#. :class:`RotatingFileHandler` instances send messages to disk
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000627 files, with support for maximum log file sizes and log file rotation.
Georg Brandl116aa622007-08-15 14:28:22 +0000628
Vinay Sajip121a1c42010-09-08 10:46:15 +0000629#. :class:`TimedRotatingFileHandler` instances send messages to
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000630 disk files, rotating the log file at certain timed intervals.
Georg Brandl116aa622007-08-15 14:28:22 +0000631
Vinay Sajip121a1c42010-09-08 10:46:15 +0000632#. :class:`SocketHandler` instances send messages to TCP/IP
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000633 sockets.
Georg Brandl116aa622007-08-15 14:28:22 +0000634
Vinay Sajip121a1c42010-09-08 10:46:15 +0000635#. :class:`DatagramHandler` instances send messages to UDP
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000636 sockets.
Georg Brandl116aa622007-08-15 14:28:22 +0000637
Vinay Sajip121a1c42010-09-08 10:46:15 +0000638#. :class:`SMTPHandler` instances send messages to a designated
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000639 email address.
Georg Brandl116aa622007-08-15 14:28:22 +0000640
Vinay Sajip121a1c42010-09-08 10:46:15 +0000641#. :class:`SysLogHandler` instances send messages to a Unix
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000642 syslog daemon, possibly on a remote machine.
Georg Brandl116aa622007-08-15 14:28:22 +0000643
Vinay Sajip121a1c42010-09-08 10:46:15 +0000644#. :class:`NTEventLogHandler` instances send messages to a
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000645 Windows NT/2000/XP event log.
Georg Brandl116aa622007-08-15 14:28:22 +0000646
Vinay Sajip121a1c42010-09-08 10:46:15 +0000647#. :class:`MemoryHandler` instances send messages to a buffer
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000648 in memory, which is flushed whenever specific criteria are met.
Georg Brandl116aa622007-08-15 14:28:22 +0000649
Vinay Sajip121a1c42010-09-08 10:46:15 +0000650#. :class:`HTTPHandler` instances send messages to an HTTP
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000651 server using either ``GET`` or ``POST`` semantics.
Georg Brandl116aa622007-08-15 14:28:22 +0000652
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000653#. :class:`WatchedFileHandler` instances watch the file they are
654 logging to. If the file changes, it is closed and reopened using the file
655 name. This handler is only useful on Unix-like systems; Windows does not
656 support the underlying mechanism used.
Vinay Sajip30bf1222009-01-10 19:23:34 +0000657
Vinay Sajip121a1c42010-09-08 10:46:15 +0000658#. :class:`QueueHandler` instances send messages to a queue, such as
659 those implemented in the :mod:`queue` or :mod:`multiprocessing` modules.
660
Vinay Sajip30bf1222009-01-10 19:23:34 +0000661.. currentmodule:: logging
662
Georg Brandlf9734072008-12-07 15:30:06 +0000663#. :class:`NullHandler` instances do nothing with error messages. They are used
664 by library developers who want to use logging, but want to avoid the "No
665 handlers could be found for logger XXX" message which can be displayed if
Vinay Sajip26a2d5e2009-01-10 13:37:26 +0000666 the library user has not configured logging. See :ref:`library-config` for
667 more information.
Georg Brandlf9734072008-12-07 15:30:06 +0000668
669.. versionadded:: 3.1
670
671The :class:`NullHandler` class was not present in previous versions.
672
Vinay Sajip121a1c42010-09-08 10:46:15 +0000673.. versionadded:: 3.2
674
675The :class:`QueueHandler` class was not present in previous versions.
676
Vinay Sajipa17775f2008-12-30 07:32:59 +0000677The :class:`NullHandler`, :class:`StreamHandler` and :class:`FileHandler`
678classes are defined in the core logging package. The other handlers are
679defined in a sub- module, :mod:`logging.handlers`. (There is also another
680sub-module, :mod:`logging.config`, for configuration functionality.)
Georg Brandl116aa622007-08-15 14:28:22 +0000681
682Logged messages are formatted for presentation through instances of the
683:class:`Formatter` class. They are initialized with a format string suitable for
684use with the % operator and a dictionary.
685
686For formatting multiple messages in a batch, instances of
687:class:`BufferingFormatter` can be used. In addition to the format string (which
688is applied to each message in the batch), there is provision for header and
689trailer format strings.
690
691When filtering based on logger level and/or handler level is not enough,
692instances of :class:`Filter` can be added to both :class:`Logger` and
693:class:`Handler` instances (through their :meth:`addFilter` method). Before
694deciding to process a message further, both loggers and handlers consult all
695their filters for permission. If any filter returns a false value, the message
696is not processed further.
697
698The basic :class:`Filter` functionality allows filtering by specific logger
699name. If this feature is used, messages sent to the named logger and its
700children are allowed through the filter, and all others dropped.
701
Benjamin Peterson058e31e2009-01-16 03:54:08 +0000702Module-Level Functions
703----------------------
704
Georg Brandl116aa622007-08-15 14:28:22 +0000705In addition to the classes described above, there are a number of module- level
706functions.
707
708
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000709.. function:: getLogger(name=None)
Georg Brandl116aa622007-08-15 14:28:22 +0000710
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000711 Return a logger with the specified name or, if name is ``None``, return a
Georg Brandl116aa622007-08-15 14:28:22 +0000712 logger which is the root logger of the hierarchy. If specified, the name is
713 typically a dot-separated hierarchical name like *"a"*, *"a.b"* or *"a.b.c.d"*.
714 Choice of these names is entirely up to the developer who is using logging.
715
716 All calls to this function with a given name return the same logger instance.
717 This means that logger instances never need to be passed between different parts
718 of an application.
719
720
721.. function:: getLoggerClass()
722
723 Return either the standard :class:`Logger` class, or the last class passed to
724 :func:`setLoggerClass`. This function may be called from within a new class
725 definition, to ensure that installing a customised :class:`Logger` class will
726 not undo customisations already applied by other code. For example::
727
728 class MyLogger(logging.getLoggerClass()):
729 # ... override behaviour here
730
731
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000732.. function:: debug(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000733
734 Logs a message with level :const:`DEBUG` on the root logger. The *msg* is the
735 message format string, and the *args* are the arguments which are merged into
736 *msg* using the string formatting operator. (Note that this means that you can
737 use keywords in the format string, together with a single dictionary argument.)
738
739 There are two keyword arguments in *kwargs* which are inspected: *exc_info*
740 which, if it does not evaluate as false, causes exception information to be
741 added to the logging message. If an exception tuple (in the format returned by
742 :func:`sys.exc_info`) is provided, it is used; otherwise, :func:`sys.exc_info`
743 is called to get the exception information.
744
745 The other optional keyword argument is *extra* which can be used to pass a
746 dictionary which is used to populate the __dict__ of the LogRecord created for
747 the logging event with user-defined attributes. These custom attributes can then
748 be used as you like. For example, they could be incorporated into logged
749 messages. For example::
750
751 FORMAT = "%(asctime)-15s %(clientip)s %(user)-8s %(message)s"
752 logging.basicConfig(format=FORMAT)
753 d = {'clientip': '192.168.0.1', 'user': 'fbloggs'}
754 logging.warning("Protocol problem: %s", "connection reset", extra=d)
755
Vinay Sajip4039aff2010-09-11 10:25:28 +0000756 would print something like::
Georg Brandl116aa622007-08-15 14:28:22 +0000757
758 2006-02-08 22:20:02,165 192.168.0.1 fbloggs Protocol problem: connection reset
759
760 The keys in the dictionary passed in *extra* should not clash with the keys used
761 by the logging system. (See the :class:`Formatter` documentation for more
762 information on which keys are used by the logging system.)
763
764 If you choose to use these attributes in logged messages, you need to exercise
765 some care. In the above example, for instance, the :class:`Formatter` has been
766 set up with a format string which expects 'clientip' and 'user' in the attribute
767 dictionary of the LogRecord. If these are missing, the message will not be
768 logged because a string formatting exception will occur. So in this case, you
769 always need to pass the *extra* dictionary with these keys.
770
771 While this might be annoying, this feature is intended for use in specialized
772 circumstances, such as multi-threaded servers where the same code executes in
773 many contexts, and interesting conditions which arise are dependent on this
774 context (such as remote client IP address and authenticated user name, in the
775 above example). In such circumstances, it is likely that specialized
776 :class:`Formatter`\ s would be used with particular :class:`Handler`\ s.
777
Georg Brandl116aa622007-08-15 14:28:22 +0000778
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000779.. function:: info(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000780
781 Logs a message with level :const:`INFO` on the root logger. The arguments are
782 interpreted as for :func:`debug`.
783
784
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000785.. function:: warning(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000786
787 Logs a message with level :const:`WARNING` on the root logger. The arguments are
788 interpreted as for :func:`debug`.
789
790
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000791.. function:: error(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000792
793 Logs a message with level :const:`ERROR` on the root logger. The arguments are
794 interpreted as for :func:`debug`.
795
796
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000797.. function:: critical(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000798
799 Logs a message with level :const:`CRITICAL` on the root logger. The arguments
800 are interpreted as for :func:`debug`.
801
802
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000803.. function:: exception(msg, *args)
Georg Brandl116aa622007-08-15 14:28:22 +0000804
805 Logs a message with level :const:`ERROR` on the root logger. The arguments are
806 interpreted as for :func:`debug`. Exception info is added to the logging
807 message. This function should only be called from an exception handler.
808
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000809.. function:: log(level, msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000810
811 Logs a message with level *level* on the root logger. The other arguments are
812 interpreted as for :func:`debug`.
813
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000814 PLEASE NOTE: The above module-level functions which delegate to the root
815 logger should *not* be used in threads, in versions of Python earlier than
816 2.7.1 and 3.2, unless at least one handler has been added to the root
817 logger *before* the threads are started. These convenience functions call
818 :func:`basicConfig` to ensure that at least one handler is available; in
819 earlier versions of Python, this can (under rare circumstances) lead to
820 handlers being added multiple times to the root logger, which can in turn
821 lead to multiple messages for the same event.
Georg Brandl116aa622007-08-15 14:28:22 +0000822
823.. function:: disable(lvl)
824
825 Provides an overriding level *lvl* for all loggers which takes precedence over
826 the logger's own level. When the need arises to temporarily throttle logging
Benjamin Peterson886af962010-03-21 23:13:07 +0000827 output down across the whole application, this function can be useful. Its
828 effect is to disable all logging calls of severity *lvl* and below, so that
829 if you call it with a value of INFO, then all INFO and DEBUG events would be
830 discarded, whereas those of severity WARNING and above would be processed
831 according to the logger's effective level.
Georg Brandl116aa622007-08-15 14:28:22 +0000832
833
834.. function:: addLevelName(lvl, levelName)
835
836 Associates level *lvl* with text *levelName* in an internal dictionary, which is
837 used to map numeric levels to a textual representation, for example when a
838 :class:`Formatter` formats a message. This function can also be used to define
839 your own levels. The only constraints are that all levels used must be
840 registered using this function, levels should be positive integers and they
841 should increase in increasing order of severity.
842
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000843 NOTE: If you are thinking of defining your own levels, please see the section
844 on :ref:`custom-levels`.
Georg Brandl116aa622007-08-15 14:28:22 +0000845
846.. function:: getLevelName(lvl)
847
848 Returns the textual representation of logging level *lvl*. If the level is one
849 of the predefined levels :const:`CRITICAL`, :const:`ERROR`, :const:`WARNING`,
850 :const:`INFO` or :const:`DEBUG` then you get the corresponding string. If you
851 have associated levels with names using :func:`addLevelName` then the name you
852 have associated with *lvl* is returned. If a numeric value corresponding to one
853 of the defined levels is passed in, the corresponding string representation is
854 returned. Otherwise, the string "Level %s" % lvl is returned.
855
856
857.. function:: makeLogRecord(attrdict)
858
859 Creates and returns a new :class:`LogRecord` instance whose attributes are
860 defined by *attrdict*. This function is useful for taking a pickled
861 :class:`LogRecord` attribute dictionary, sent over a socket, and reconstituting
862 it as a :class:`LogRecord` instance at the receiving end.
863
864
Georg Brandlcd7f32b2009-06-08 09:13:45 +0000865.. function:: basicConfig(**kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +0000866
867 Does basic configuration for the logging system by creating a
868 :class:`StreamHandler` with a default :class:`Formatter` and adding it to the
Vinay Sajipcbabd7e2009-10-10 20:32:36 +0000869 root logger. The functions :func:`debug`, :func:`info`, :func:`warning`,
Georg Brandl116aa622007-08-15 14:28:22 +0000870 :func:`error` and :func:`critical` will call :func:`basicConfig` automatically
871 if no handlers are defined for the root logger.
872
Vinay Sajipcbabd7e2009-10-10 20:32:36 +0000873 This function does nothing if the root logger already has handlers
874 configured for it.
875
Vinay Sajipc8c8c692010-09-17 10:09:04 +0000876 PLEASE NOTE: This function should be called from the main thread
877 before other threads are started. In versions of Python prior to
878 2.7.1 and 3.2, if this function is called from multiple threads,
879 it is possible (in rare circumstances) that a handler will be added
880 to the root logger more than once, leading to unexpected results
881 such as messages being duplicated in the log.
882
Georg Brandl116aa622007-08-15 14:28:22 +0000883 The following keyword arguments are supported.
884
885 +--------------+---------------------------------------------+
886 | Format | Description |
887 +==============+=============================================+
888 | ``filename`` | Specifies that a FileHandler be created, |
889 | | using the specified filename, rather than a |
890 | | StreamHandler. |
891 +--------------+---------------------------------------------+
892 | ``filemode`` | Specifies the mode to open the file, if |
893 | | filename is specified (if filemode is |
894 | | unspecified, it defaults to 'a'). |
895 +--------------+---------------------------------------------+
896 | ``format`` | Use the specified format string for the |
897 | | handler. |
898 +--------------+---------------------------------------------+
899 | ``datefmt`` | Use the specified date/time format. |
900 +--------------+---------------------------------------------+
901 | ``level`` | Set the root logger level to the specified |
902 | | level. |
903 +--------------+---------------------------------------------+
904 | ``stream`` | Use the specified stream to initialize the |
905 | | StreamHandler. Note that this argument is |
906 | | incompatible with 'filename' - if both are |
907 | | present, 'stream' is ignored. |
908 +--------------+---------------------------------------------+
909
Georg Brandl116aa622007-08-15 14:28:22 +0000910.. function:: shutdown()
911
912 Informs the logging system to perform an orderly shutdown by flushing and
Christian Heimesb186d002008-03-18 15:15:01 +0000913 closing all handlers. This should be called at application exit and no
914 further use of the logging system should be made after this call.
Georg Brandl116aa622007-08-15 14:28:22 +0000915
916
917.. function:: setLoggerClass(klass)
918
919 Tells the logging system to use the class *klass* when instantiating a logger.
920 The class should define :meth:`__init__` such that only a name argument is
921 required, and the :meth:`__init__` should call :meth:`Logger.__init__`. This
922 function is typically called before any loggers are instantiated by applications
923 which need to use custom logger behavior.
924
925
926.. seealso::
927
928 :pep:`282` - A Logging System
929 The proposal which described this feature for inclusion in the Python standard
930 library.
931
Christian Heimes255f53b2007-12-08 15:33:56 +0000932 `Original Python logging package <http://www.red-dove.com/python_logging.html>`_
Georg Brandl116aa622007-08-15 14:28:22 +0000933 This is the original source for the :mod:`logging` package. The version of the
934 package available from this site is suitable for use with Python 1.5.2, 2.1.x
935 and 2.2.x, which do not include the :mod:`logging` package in the standard
936 library.
937
Vinay Sajip4039aff2010-09-11 10:25:28 +0000938.. _logger:
Georg Brandl116aa622007-08-15 14:28:22 +0000939
940Logger Objects
941--------------
942
943Loggers have the following attributes and methods. Note that Loggers are never
944instantiated directly, but always through the module-level function
945``logging.getLogger(name)``.
946
Vinay Sajip0258ce82010-09-22 20:34:53 +0000947.. class:: Logger
Georg Brandl116aa622007-08-15 14:28:22 +0000948
949.. attribute:: Logger.propagate
950
951 If this evaluates to false, logging messages are not passed by this logger or by
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000952 its child loggers to the handlers of higher level (ancestor) loggers. The
953 constructor sets this attribute to 1.
Georg Brandl116aa622007-08-15 14:28:22 +0000954
955
956.. method:: Logger.setLevel(lvl)
957
958 Sets the threshold for this logger to *lvl*. Logging messages which are less
959 severe than *lvl* will be ignored. When a logger is created, the level is set to
960 :const:`NOTSET` (which causes all messages to be processed when the logger is
961 the root logger, or delegation to the parent when the logger is a non-root
962 logger). Note that the root logger is created with level :const:`WARNING`.
963
964 The term "delegation to the parent" means that if a logger has a level of
965 NOTSET, its chain of ancestor loggers is traversed until either an ancestor with
966 a level other than NOTSET is found, or the root is reached.
967
968 If an ancestor is found with a level other than NOTSET, then that ancestor's
969 level is treated as the effective level of the logger where the ancestor search
970 began, and is used to determine how a logging event is handled.
971
972 If the root is reached, and it has a level of NOTSET, then all messages will be
973 processed. Otherwise, the root's level will be used as the effective level.
974
975
976.. method:: Logger.isEnabledFor(lvl)
977
978 Indicates if a message of severity *lvl* would be processed by this logger.
979 This method checks first the module-level level set by
980 ``logging.disable(lvl)`` and then the logger's effective level as determined
981 by :meth:`getEffectiveLevel`.
982
983
984.. method:: Logger.getEffectiveLevel()
985
986 Indicates the effective level for this logger. If a value other than
987 :const:`NOTSET` has been set using :meth:`setLevel`, it is returned. Otherwise,
988 the hierarchy is traversed towards the root until a value other than
989 :const:`NOTSET` is found, and that value is returned.
990
991
Benjamin Peterson22005fc2010-04-11 16:25:06 +0000992.. method:: Logger.getChild(suffix)
993
994 Returns a logger which is a descendant to this logger, as determined by the suffix.
995 Thus, ``logging.getLogger('abc').getChild('def.ghi')`` would return the same
996 logger as would be returned by ``logging.getLogger('abc.def.ghi')``. This is a
997 convenience method, useful when the parent logger is named using e.g. ``__name__``
998 rather than a literal string.
999
1000 .. versionadded:: 3.2
1001
Georg Brandl67b21b72010-08-17 15:07:14 +00001002
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001003.. method:: Logger.debug(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001004
1005 Logs a message with level :const:`DEBUG` on this logger. The *msg* is the
1006 message format string, and the *args* are the arguments which are merged into
1007 *msg* using the string formatting operator. (Note that this means that you can
1008 use keywords in the format string, together with a single dictionary argument.)
1009
1010 There are two keyword arguments in *kwargs* which are inspected: *exc_info*
1011 which, if it does not evaluate as false, causes exception information to be
1012 added to the logging message. If an exception tuple (in the format returned by
1013 :func:`sys.exc_info`) is provided, it is used; otherwise, :func:`sys.exc_info`
1014 is called to get the exception information.
1015
1016 The other optional keyword argument is *extra* which can be used to pass a
1017 dictionary which is used to populate the __dict__ of the LogRecord created for
1018 the logging event with user-defined attributes. These custom attributes can then
1019 be used as you like. For example, they could be incorporated into logged
1020 messages. For example::
1021
1022 FORMAT = "%(asctime)-15s %(clientip)s %(user)-8s %(message)s"
1023 logging.basicConfig(format=FORMAT)
Georg Brandl9afde1c2007-11-01 20:32:30 +00001024 d = { 'clientip' : '192.168.0.1', 'user' : 'fbloggs' }
Georg Brandl116aa622007-08-15 14:28:22 +00001025 logger = logging.getLogger("tcpserver")
1026 logger.warning("Protocol problem: %s", "connection reset", extra=d)
1027
1028 would print something like ::
1029
1030 2006-02-08 22:20:02,165 192.168.0.1 fbloggs Protocol problem: connection reset
1031
1032 The keys in the dictionary passed in *extra* should not clash with the keys used
1033 by the logging system. (See the :class:`Formatter` documentation for more
1034 information on which keys are used by the logging system.)
1035
1036 If you choose to use these attributes in logged messages, you need to exercise
1037 some care. In the above example, for instance, the :class:`Formatter` has been
1038 set up with a format string which expects 'clientip' and 'user' in the attribute
1039 dictionary of the LogRecord. If these are missing, the message will not be
1040 logged because a string formatting exception will occur. So in this case, you
1041 always need to pass the *extra* dictionary with these keys.
1042
1043 While this might be annoying, this feature is intended for use in specialized
1044 circumstances, such as multi-threaded servers where the same code executes in
1045 many contexts, and interesting conditions which arise are dependent on this
1046 context (such as remote client IP address and authenticated user name, in the
1047 above example). In such circumstances, it is likely that specialized
1048 :class:`Formatter`\ s would be used with particular :class:`Handler`\ s.
1049
Georg Brandl116aa622007-08-15 14:28:22 +00001050
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001051.. method:: Logger.info(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001052
1053 Logs a message with level :const:`INFO` on this logger. The arguments are
1054 interpreted as for :meth:`debug`.
1055
1056
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001057.. method:: Logger.warning(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001058
1059 Logs a message with level :const:`WARNING` on this logger. The arguments are
1060 interpreted as for :meth:`debug`.
1061
1062
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001063.. method:: Logger.error(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001064
1065 Logs a message with level :const:`ERROR` on this logger. The arguments are
1066 interpreted as for :meth:`debug`.
1067
1068
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001069.. method:: Logger.critical(msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001070
1071 Logs a message with level :const:`CRITICAL` on this logger. The arguments are
1072 interpreted as for :meth:`debug`.
1073
1074
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001075.. method:: Logger.log(lvl, msg, *args, **kwargs)
Georg Brandl116aa622007-08-15 14:28:22 +00001076
1077 Logs a message with integer level *lvl* on this logger. The other arguments are
1078 interpreted as for :meth:`debug`.
1079
1080
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001081.. method:: Logger.exception(msg, *args)
Georg Brandl116aa622007-08-15 14:28:22 +00001082
1083 Logs a message with level :const:`ERROR` on this logger. The arguments are
1084 interpreted as for :meth:`debug`. Exception info is added to the logging
1085 message. This method should only be called from an exception handler.
1086
1087
1088.. method:: Logger.addFilter(filt)
1089
1090 Adds the specified filter *filt* to this logger.
1091
1092
1093.. method:: Logger.removeFilter(filt)
1094
1095 Removes the specified filter *filt* from this logger.
1096
1097
1098.. method:: Logger.filter(record)
1099
1100 Applies this logger's filters to the record and returns a true value if the
1101 record is to be processed.
1102
1103
1104.. method:: Logger.addHandler(hdlr)
1105
1106 Adds the specified handler *hdlr* to this logger.
1107
1108
1109.. method:: Logger.removeHandler(hdlr)
1110
1111 Removes the specified handler *hdlr* from this logger.
1112
1113
1114.. method:: Logger.findCaller()
1115
1116 Finds the caller's source filename and line number. Returns the filename, line
1117 number and function name as a 3-element tuple.
1118
Georg Brandl116aa622007-08-15 14:28:22 +00001119
1120.. method:: Logger.handle(record)
1121
1122 Handles a record by passing it to all handlers associated with this logger and
1123 its ancestors (until a false value of *propagate* is found). This method is used
1124 for unpickled records received from a socket, as well as those created locally.
Georg Brandl502d9a52009-07-26 15:02:41 +00001125 Logger-level filtering is applied using :meth:`~Logger.filter`.
Georg Brandl116aa622007-08-15 14:28:22 +00001126
1127
Georg Brandlcd7f32b2009-06-08 09:13:45 +00001128.. method:: Logger.makeRecord(name, lvl, fn, lno, msg, args, exc_info, func=None, extra=None)
Georg Brandl116aa622007-08-15 14:28:22 +00001129
1130 This is a factory method which can be overridden in subclasses to create
1131 specialized :class:`LogRecord` instances.
1132
Vinay Sajip83eadd12010-09-20 10:31:18 +00001133.. method:: Logger.hasHandlers()
1134
1135 Checks to see if this logger has any handlers configured. This is done by
1136 looking for handlers in this logger and its parents in the logger hierarchy.
1137 Returns True if a handler was found, else False. The method stops searching
1138 up the hierarchy whenever a logger with the "propagate" attribute set to
1139 False is found - that will be the last logger which is checked for the
1140 existence of handlers.
1141
1142.. versionadded:: 3.2
1143
1144The :meth:`hasHandlers` method was not present in previous versions.
Georg Brandl116aa622007-08-15 14:28:22 +00001145
1146.. _minimal-example:
1147
1148Basic example
1149-------------
1150
Georg Brandl116aa622007-08-15 14:28:22 +00001151The :mod:`logging` package provides a lot of flexibility, and its configuration
1152can appear daunting. This section demonstrates that simple use of the logging
1153package is possible.
1154
1155The simplest example shows logging to the console::
1156
1157 import logging
1158
1159 logging.debug('A debug message')
1160 logging.info('Some information')
1161 logging.warning('A shot across the bows')
1162
1163If you run the above script, you'll see this::
1164
1165 WARNING:root:A shot across the bows
1166
1167Because no particular logger was specified, the system used the root logger. The
1168debug and info messages didn't appear because by default, the root logger is
1169configured to only handle messages with a severity of WARNING or above. The
1170message format is also a configuration default, as is the output destination of
1171the messages - ``sys.stderr``. The severity level, the message format and
1172destination can be easily changed, as shown in the example below::
1173
1174 import logging
1175
1176 logging.basicConfig(level=logging.DEBUG,
1177 format='%(asctime)s %(levelname)s %(message)s',
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00001178 filename='myapp.log',
Georg Brandl116aa622007-08-15 14:28:22 +00001179 filemode='w')
1180 logging.debug('A debug message')
1181 logging.info('Some information')
1182 logging.warning('A shot across the bows')
1183
1184The :meth:`basicConfig` method is used to change the configuration defaults,
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00001185which results in output (written to ``myapp.log``) which should look
Georg Brandl116aa622007-08-15 14:28:22 +00001186something like the following::
1187
1188 2004-07-02 13:00:08,743 DEBUG A debug message
1189 2004-07-02 13:00:08,743 INFO Some information
1190 2004-07-02 13:00:08,743 WARNING A shot across the bows
1191
1192This time, all messages with a severity of DEBUG or above were handled, and the
1193format of the messages was also changed, and output went to the specified file
1194rather than the console.
1195
Georg Brandl81ac1ce2007-08-31 17:17:17 +00001196.. XXX logging should probably be updated for new string formatting!
Georg Brandl4b491312007-08-31 09:22:56 +00001197
1198Formatting uses the old Python string formatting - see section
1199:ref:`old-string-formatting`. The format string takes the following common
Georg Brandl116aa622007-08-15 14:28:22 +00001200specifiers. For a complete list of specifiers, consult the :class:`Formatter`
1201documentation.
1202
1203+-------------------+-----------------------------------------------+
1204| Format | Description |
1205+===================+===============================================+
1206| ``%(name)s`` | Name of the logger (logging channel). |
1207+-------------------+-----------------------------------------------+
1208| ``%(levelname)s`` | Text logging level for the message |
1209| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
1210| | ``'ERROR'``, ``'CRITICAL'``). |
1211+-------------------+-----------------------------------------------+
1212| ``%(asctime)s`` | Human-readable time when the |
1213| | :class:`LogRecord` was created. By default |
1214| | this is of the form "2003-07-08 16:49:45,896" |
1215| | (the numbers after the comma are millisecond |
1216| | portion of the time). |
1217+-------------------+-----------------------------------------------+
1218| ``%(message)s`` | The logged message. |
1219+-------------------+-----------------------------------------------+
1220
1221To change the date/time format, you can pass an additional keyword parameter,
1222*datefmt*, as in the following::
1223
1224 import logging
1225
1226 logging.basicConfig(level=logging.DEBUG,
1227 format='%(asctime)s %(levelname)-8s %(message)s',
1228 datefmt='%a, %d %b %Y %H:%M:%S',
1229 filename='/temp/myapp.log',
1230 filemode='w')
1231 logging.debug('A debug message')
1232 logging.info('Some information')
1233 logging.warning('A shot across the bows')
1234
1235which would result in output like ::
1236
1237 Fri, 02 Jul 2004 13:06:18 DEBUG A debug message
1238 Fri, 02 Jul 2004 13:06:18 INFO Some information
1239 Fri, 02 Jul 2004 13:06:18 WARNING A shot across the bows
1240
1241The date format string follows the requirements of :func:`strftime` - see the
1242documentation for the :mod:`time` module.
1243
1244If, instead of sending logging output to the console or a file, you'd rather use
1245a file-like object which you have created separately, you can pass it to
1246:func:`basicConfig` using the *stream* keyword argument. Note that if both
1247*stream* and *filename* keyword arguments are passed, the *stream* argument is
1248ignored.
1249
1250Of course, you can put variable information in your output. To do this, simply
1251have the message be a format string and pass in additional arguments containing
1252the variable information, as in the following example::
1253
1254 import logging
1255
1256 logging.basicConfig(level=logging.DEBUG,
1257 format='%(asctime)s %(levelname)-8s %(message)s',
1258 datefmt='%a, %d %b %Y %H:%M:%S',
1259 filename='/temp/myapp.log',
1260 filemode='w')
1261 logging.error('Pack my box with %d dozen %s', 5, 'liquor jugs')
1262
1263which would result in ::
1264
1265 Wed, 21 Jul 2004 15:35:16 ERROR Pack my box with 5 dozen liquor jugs
1266
1267
1268.. _multiple-destinations:
1269
1270Logging to multiple destinations
1271--------------------------------
1272
1273Let's say you want to log to console and file with different message formats and
1274in differing circumstances. Say you want to log messages with levels of DEBUG
1275and higher to file, and those messages at level INFO and higher to the console.
1276Let's also assume that the file should contain timestamps, but the console
1277messages should not. Here's how you can achieve this::
1278
1279 import logging
1280
1281 # set up logging to file - see previous section for more details
1282 logging.basicConfig(level=logging.DEBUG,
1283 format='%(asctime)s %(name)-12s %(levelname)-8s %(message)s',
1284 datefmt='%m-%d %H:%M',
1285 filename='/temp/myapp.log',
1286 filemode='w')
1287 # define a Handler which writes INFO messages or higher to the sys.stderr
1288 console = logging.StreamHandler()
1289 console.setLevel(logging.INFO)
1290 # set a format which is simpler for console use
1291 formatter = logging.Formatter('%(name)-12s: %(levelname)-8s %(message)s')
1292 # tell the handler to use this format
1293 console.setFormatter(formatter)
1294 # add the handler to the root logger
1295 logging.getLogger('').addHandler(console)
1296
1297 # Now, we can log to the root logger, or any other logger. First the root...
1298 logging.info('Jackdaws love my big sphinx of quartz.')
1299
1300 # Now, define a couple of other loggers which might represent areas in your
1301 # application:
1302
1303 logger1 = logging.getLogger('myapp.area1')
1304 logger2 = logging.getLogger('myapp.area2')
1305
1306 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1307 logger1.info('How quickly daft jumping zebras vex.')
1308 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1309 logger2.error('The five boxing wizards jump quickly.')
1310
1311When you run this, on the console you will see ::
1312
1313 root : INFO Jackdaws love my big sphinx of quartz.
1314 myapp.area1 : INFO How quickly daft jumping zebras vex.
1315 myapp.area2 : WARNING Jail zesty vixen who grabbed pay from quack.
1316 myapp.area2 : ERROR The five boxing wizards jump quickly.
1317
1318and in the file you will see something like ::
1319
1320 10-22 22:19 root INFO Jackdaws love my big sphinx of quartz.
1321 10-22 22:19 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1322 10-22 22:19 myapp.area1 INFO How quickly daft jumping zebras vex.
1323 10-22 22:19 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1324 10-22 22:19 myapp.area2 ERROR The five boxing wizards jump quickly.
1325
1326As you can see, the DEBUG message only shows up in the file. The other messages
1327are sent to both destinations.
1328
1329This example uses console and file handlers, but you can use any number and
1330combination of handlers you choose.
1331
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001332.. _logging-exceptions:
1333
1334Exceptions raised during logging
1335--------------------------------
1336
1337The logging package is designed to swallow exceptions which occur while logging
1338in production. This is so that errors which occur while handling logging events
1339- such as logging misconfiguration, network or other similar errors - do not
1340cause the application using logging to terminate prematurely.
1341
1342:class:`SystemExit` and :class:`KeyboardInterrupt` exceptions are never
1343swallowed. Other exceptions which occur during the :meth:`emit` method of a
1344:class:`Handler` subclass are passed to its :meth:`handleError` method.
1345
1346The default implementation of :meth:`handleError` in :class:`Handler` checks
Georg Brandlef871f62010-03-12 10:06:40 +00001347to see if a module-level variable, :data:`raiseExceptions`, is set. If set, a
1348traceback is printed to :data:`sys.stderr`. If not set, the exception is swallowed.
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001349
Georg Brandlef871f62010-03-12 10:06:40 +00001350**Note:** The default value of :data:`raiseExceptions` is ``True``. This is because
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001351during development, you typically want to be notified of any exceptions that
Georg Brandlef871f62010-03-12 10:06:40 +00001352occur. It's advised that you set :data:`raiseExceptions` to ``False`` for production
Vinay Sajip3ee22ec2009-08-20 22:05:10 +00001353usage.
Georg Brandl116aa622007-08-15 14:28:22 +00001354
Christian Heimes790c8232008-01-07 21:14:23 +00001355.. _context-info:
1356
1357Adding contextual information to your logging output
1358----------------------------------------------------
1359
1360Sometimes you want logging output to contain contextual information in
1361addition to the parameters passed to the logging call. For example, in a
1362networked application, it may be desirable to log client-specific information
1363in the log (e.g. remote client's username, or IP address). Although you could
1364use the *extra* parameter to achieve this, it's not always convenient to pass
1365the information in this way. While it might be tempting to create
1366:class:`Logger` instances on a per-connection basis, this is not a good idea
1367because these instances are not garbage collected. While this is not a problem
1368in practice, when the number of :class:`Logger` instances is dependent on the
1369level of granularity you want to use in logging an application, it could
1370be hard to manage if the number of :class:`Logger` instances becomes
1371effectively unbounded.
1372
Vinay Sajipc31be632010-09-06 22:18:20 +00001373
1374Using LoggerAdapters to impart contextual information
1375^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1376
Christian Heimes04c420f2008-01-18 18:40:46 +00001377An easy way in which you can pass contextual information to be output along
1378with logging event information is to use the :class:`LoggerAdapter` class.
1379This class is designed to look like a :class:`Logger`, so that you can call
1380:meth:`debug`, :meth:`info`, :meth:`warning`, :meth:`error`,
1381:meth:`exception`, :meth:`critical` and :meth:`log`. These methods have the
1382same signatures as their counterparts in :class:`Logger`, so you can use the
1383two types of instances interchangeably.
Christian Heimes790c8232008-01-07 21:14:23 +00001384
Christian Heimes04c420f2008-01-18 18:40:46 +00001385When you create an instance of :class:`LoggerAdapter`, you pass it a
1386:class:`Logger` instance and a dict-like object which contains your contextual
1387information. When you call one of the logging methods on an instance of
1388:class:`LoggerAdapter`, it delegates the call to the underlying instance of
1389:class:`Logger` passed to its constructor, and arranges to pass the contextual
1390information in the delegated call. Here's a snippet from the code of
1391:class:`LoggerAdapter`::
Christian Heimes790c8232008-01-07 21:14:23 +00001392
Christian Heimes04c420f2008-01-18 18:40:46 +00001393 def debug(self, msg, *args, **kwargs):
1394 """
1395 Delegate a debug call to the underlying logger, after adding
1396 contextual information from this adapter instance.
1397 """
1398 msg, kwargs = self.process(msg, kwargs)
1399 self.logger.debug(msg, *args, **kwargs)
Christian Heimes790c8232008-01-07 21:14:23 +00001400
Christian Heimes04c420f2008-01-18 18:40:46 +00001401The :meth:`process` method of :class:`LoggerAdapter` is where the contextual
1402information is added to the logging output. It's passed the message and
1403keyword arguments of the logging call, and it passes back (potentially)
1404modified versions of these to use in the call to the underlying logger. The
1405default implementation of this method leaves the message alone, but inserts
1406an "extra" key in the keyword argument whose value is the dict-like object
1407passed to the constructor. Of course, if you had passed an "extra" keyword
1408argument in the call to the adapter, it will be silently overwritten.
Christian Heimes790c8232008-01-07 21:14:23 +00001409
Christian Heimes04c420f2008-01-18 18:40:46 +00001410The advantage of using "extra" is that the values in the dict-like object are
1411merged into the :class:`LogRecord` instance's __dict__, allowing you to use
1412customized strings with your :class:`Formatter` instances which know about
1413the keys of the dict-like object. If you need a different method, e.g. if you
1414want to prepend or append the contextual information to the message string,
1415you just need to subclass :class:`LoggerAdapter` and override :meth:`process`
1416to do what you need. Here's an example script which uses this class, which
1417also illustrates what dict-like behaviour is needed from an arbitrary
1418"dict-like" object for use in the constructor::
1419
Christian Heimes587c2bf2008-01-19 16:21:02 +00001420 import logging
Georg Brandl86def6c2008-01-21 20:36:10 +00001421
Christian Heimes587c2bf2008-01-19 16:21:02 +00001422 class ConnInfo:
1423 """
1424 An example class which shows how an arbitrary class can be used as
1425 the 'extra' context information repository passed to a LoggerAdapter.
1426 """
Georg Brandl86def6c2008-01-21 20:36:10 +00001427
Christian Heimes587c2bf2008-01-19 16:21:02 +00001428 def __getitem__(self, name):
1429 """
1430 To allow this instance to look like a dict.
1431 """
1432 from random import choice
1433 if name == "ip":
1434 result = choice(["127.0.0.1", "192.168.0.1"])
1435 elif name == "user":
1436 result = choice(["jim", "fred", "sheila"])
1437 else:
1438 result = self.__dict__.get(name, "?")
1439 return result
Georg Brandl86def6c2008-01-21 20:36:10 +00001440
Christian Heimes587c2bf2008-01-19 16:21:02 +00001441 def __iter__(self):
1442 """
1443 To allow iteration over keys, which will be merged into
1444 the LogRecord dict before formatting and output.
1445 """
1446 keys = ["ip", "user"]
1447 keys.extend(self.__dict__.keys())
1448 return keys.__iter__()
Georg Brandl86def6c2008-01-21 20:36:10 +00001449
Christian Heimes587c2bf2008-01-19 16:21:02 +00001450 if __name__ == "__main__":
1451 from random import choice
1452 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1453 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1454 { "ip" : "123.231.231.123", "user" : "sheila" })
1455 logging.basicConfig(level=logging.DEBUG,
1456 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1457 a1.debug("A debug message")
1458 a1.info("An info message with %s", "some parameters")
1459 a2 = logging.LoggerAdapter(logging.getLogger("d.e.f"), ConnInfo())
1460 for x in range(10):
1461 lvl = choice(levels)
1462 lvlname = logging.getLevelName(lvl)
1463 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
Christian Heimes04c420f2008-01-18 18:40:46 +00001464
1465When this script is run, the output should look something like this::
1466
Christian Heimes587c2bf2008-01-19 16:21:02 +00001467 2008-01-18 14:49:54,023 a.b.c DEBUG IP: 123.231.231.123 User: sheila A debug message
1468 2008-01-18 14:49:54,023 a.b.c INFO IP: 123.231.231.123 User: sheila An info message with some parameters
1469 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
1470 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
1471 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
1472 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
1473 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
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 WARNING IP: 192.168.0.1 User: jim A message at WARNING level with 2 parameters
1476 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
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: 127.0.0.1 User: jim A message at WARNING level with 2 parameters
Christian Heimes04c420f2008-01-18 18:40:46 +00001479
Christian Heimes790c8232008-01-07 21:14:23 +00001480
Vinay Sajipac007992010-09-17 12:45:26 +00001481.. _filters-contextual:
1482
Vinay Sajipc31be632010-09-06 22:18:20 +00001483Using Filters to impart contextual information
1484^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
1485
1486You can also add contextual information to log output using a user-defined
1487:class:`Filter`. ``Filter`` instances are allowed to modify the ``LogRecords``
1488passed to them, including adding additional attributes which can then be output
1489using a suitable format string, or if needed a custom :class:`Formatter`.
1490
1491For example in a web application, the request being processed (or at least,
1492the interesting parts of it) can be stored in a threadlocal
1493(:class:`threading.local`) variable, and then accessed from a ``Filter`` to
1494add, say, information from the request - say, the remote IP address and remote
1495user's username - to the ``LogRecord``, using the attribute names 'ip' and
1496'user' as in the ``LoggerAdapter`` example above. In that case, the same format
1497string can be used to get similar output to that shown above. Here's an example
1498script::
1499
1500 import logging
1501 from random import choice
1502
1503 class ContextFilter(logging.Filter):
1504 """
1505 This is a filter which injects contextual information into the log.
1506
1507 Rather than use actual contextual information, we just use random
1508 data in this demo.
1509 """
1510
1511 USERS = ['jim', 'fred', 'sheila']
1512 IPS = ['123.231.231.123', '127.0.0.1', '192.168.0.1']
1513
1514 def filter(self, record):
1515
1516 record.ip = choice(ContextFilter.IPS)
1517 record.user = choice(ContextFilter.USERS)
1518 return True
1519
1520 if __name__ == "__main__":
1521 levels = (logging.DEBUG, logging.INFO, logging.WARNING, logging.ERROR, logging.CRITICAL)
1522 a1 = logging.LoggerAdapter(logging.getLogger("a.b.c"),
1523 { "ip" : "123.231.231.123", "user" : "sheila" })
1524 logging.basicConfig(level=logging.DEBUG,
1525 format="%(asctime)-15s %(name)-5s %(levelname)-8s IP: %(ip)-15s User: %(user)-8s %(message)s")
1526 a1 = logging.getLogger("a.b.c")
1527 a2 = logging.getLogger("d.e.f")
1528
1529 f = ContextFilter()
1530 a1.addFilter(f)
1531 a2.addFilter(f)
1532 a1.debug("A debug message")
1533 a1.info("An info message with %s", "some parameters")
1534 for x in range(10):
1535 lvl = choice(levels)
1536 lvlname = logging.getLevelName(lvl)
1537 a2.log(lvl, "A message at %s level with %d %s", lvlname, 2, "parameters")
1538
1539which, when run, produces something like::
1540
1541 2010-09-06 22:38:15,292 a.b.c DEBUG IP: 123.231.231.123 User: fred A debug message
1542 2010-09-06 22:38:15,300 a.b.c INFO IP: 192.168.0.1 User: sheila An info message with some parameters
1543 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
1544 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
1545 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
1546 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
1547 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
1548 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
1549 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
1550 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
1551 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
1552 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
1553
1554
Vinay Sajipd31f3632010-06-29 15:31:15 +00001555.. _multiple-processes:
1556
Vinay Sajipa7471bf2009-08-15 23:23:37 +00001557Logging to a single file from multiple processes
1558------------------------------------------------
1559
1560Although logging is thread-safe, and logging to a single file from multiple
1561threads in a single process *is* supported, logging to a single file from
1562*multiple processes* is *not* supported, because there is no standard way to
1563serialize access to a single file across multiple processes in Python. If you
Vinay Sajip121a1c42010-09-08 10:46:15 +00001564need to log to a single file from multiple processes, one way of doing this is
1565to have all the processes log to a :class:`SocketHandler`, and have a separate
1566process which implements a socket server which reads from the socket and logs
1567to file. (If you prefer, you can dedicate one thread in one of the existing
1568processes to perform this function.) The following section documents this
1569approach in more detail and includes a working socket receiver which can be
1570used as a starting point for you to adapt in your own applications.
Vinay Sajipa7471bf2009-08-15 23:23:37 +00001571
Vinay Sajip5a92b132009-08-15 23:35:08 +00001572If you are using a recent version of Python which includes the
Vinay Sajip121a1c42010-09-08 10:46:15 +00001573:mod:`multiprocessing` module, you could write your own handler which uses the
Vinay Sajip5a92b132009-08-15 23:35:08 +00001574:class:`Lock` class from this module to serialize access to the file from
1575your processes. The existing :class:`FileHandler` and subclasses do not make
1576use of :mod:`multiprocessing` at present, though they may do so in the future.
Vinay Sajip8c6b0a52009-08-17 13:17:47 +00001577Note that at present, the :mod:`multiprocessing` module does not provide
1578working lock functionality on all platforms (see
1579http://bugs.python.org/issue3770).
Vinay Sajip5a92b132009-08-15 23:35:08 +00001580
Vinay Sajip121a1c42010-09-08 10:46:15 +00001581.. currentmodule:: logging.handlers
1582
1583Alternatively, you can use a ``Queue`` and a :class:`QueueHandler` to send
1584all logging events to one of the processes in your multi-process application.
1585The following example script demonstrates how you can do this; in the example
1586a separate listener process listens for events sent by other processes and logs
1587them according to its own logging configuration. Although the example only
1588demonstrates one way of doing it (for example, you may want to use a listener
1589thread rather than a separate listener process - the implementation would be
1590analogous) it does allow for completely different logging configurations for
1591the listener and the other processes in your application, and can be used as
1592the basis for code meeting your own specific requirements::
1593
1594 # You'll need these imports in your own code
1595 import logging
1596 import logging.handlers
1597 import multiprocessing
1598
1599 # Next two import lines for this demo only
1600 from random import choice, random
1601 import time
1602
1603 #
1604 # Because you'll want to define the logging configurations for listener and workers, the
1605 # listener and worker process functions take a configurer parameter which is a callable
1606 # for configuring logging for that process. These functions are also passed the queue,
1607 # which they use for communication.
1608 #
1609 # In practice, you can configure the listener however you want, but note that in this
1610 # simple example, the listener does not apply level or filter logic to received records.
1611 # In practice, you would probably want to do ths logic in the worker processes, to avoid
1612 # sending events which would be filtered out between processes.
1613 #
1614 # The size of the rotated files is made small so you can see the results easily.
1615 def listener_configurer():
1616 root = logging.getLogger()
1617 h = logging.handlers.RotatingFileHandler('/tmp/mptest.log', 'a', 300, 10)
1618 f = logging.Formatter('%(asctime)s %(processName)-10s %(name)s %(levelname)-8s %(message)s')
1619 h.setFormatter(f)
1620 root.addHandler(h)
1621
1622 # This is the listener process top-level loop: wait for logging events
1623 # (LogRecords)on the queue and handle them, quit when you get a None for a
1624 # LogRecord.
1625 def listener_process(queue, configurer):
1626 configurer()
1627 while True:
1628 try:
1629 record = queue.get()
1630 if record is None: # We send this as a sentinel to tell the listener to quit.
1631 break
1632 logger = logging.getLogger(record.name)
1633 logger.handle(record) # No level or filter logic applied - just do it!
1634 except (KeyboardInterrupt, SystemExit):
1635 raise
1636 except:
1637 import sys, traceback
1638 print >> sys.stderr, 'Whoops! Problem:'
1639 traceback.print_exc(file=sys.stderr)
1640
1641 # Arrays used for random selections in this demo
1642
1643 LEVELS = [logging.DEBUG, logging.INFO, logging.WARNING,
1644 logging.ERROR, logging.CRITICAL]
1645
1646 LOGGERS = ['a.b.c', 'd.e.f']
1647
1648 MESSAGES = [
1649 'Random message #1',
1650 'Random message #2',
1651 'Random message #3',
1652 ]
1653
1654 # The worker configuration is done at the start of the worker process run.
1655 # Note that on Windows you can't rely on fork semantics, so each process
1656 # will run the logging configuration code when it starts.
1657 def worker_configurer(queue):
1658 h = logging.handlers.QueueHandler(queue) # Just the one handler needed
1659 root = logging.getLogger()
1660 root.addHandler(h)
1661 root.setLevel(logging.DEBUG) # send all messages, for demo; no other level or filter logic applied.
1662
1663 # This is the worker process top-level loop, which just logs ten events with
1664 # random intervening delays before terminating.
1665 # The print messages are just so you know it's doing something!
1666 def worker_process(queue, configurer):
1667 configurer(queue)
1668 name = multiprocessing.current_process().name
1669 print('Worker started: %s' % name)
1670 for i in range(10):
1671 time.sleep(random())
1672 logger = logging.getLogger(choice(LOGGERS))
1673 level = choice(LEVELS)
1674 message = choice(MESSAGES)
1675 logger.log(level, message)
1676 print('Worker finished: %s' % name)
1677
1678 # Here's where the demo gets orchestrated. Create the queue, create and start
1679 # the listener, create ten workers and start them, wait for them to finish,
1680 # then send a None to the queue to tell the listener to finish.
1681 def main():
1682 queue = multiprocessing.Queue(-1)
1683 listener = multiprocessing.Process(target=listener_process,
1684 args=(queue, listener_configurer))
1685 listener.start()
1686 workers = []
1687 for i in range(10):
1688 worker = multiprocessing.Process(target=worker_process,
1689 args=(queue, worker_configurer))
1690 workers.append(worker)
1691 worker.start()
1692 for w in workers:
1693 w.join()
1694 queue.put_nowait(None)
1695 listener.join()
1696
1697 if __name__ == '__main__':
1698 main()
1699
1700
1701.. currentmodule:: logging
1702
Benjamin Peterson8719ad52009-09-11 22:24:02 +00001703
Georg Brandl116aa622007-08-15 14:28:22 +00001704.. _network-logging:
1705
1706Sending and receiving logging events across a network
1707-----------------------------------------------------
1708
1709Let's say you want to send logging events across a network, and handle them at
1710the receiving end. A simple way of doing this is attaching a
1711:class:`SocketHandler` instance to the root logger at the sending end::
1712
1713 import logging, logging.handlers
1714
1715 rootLogger = logging.getLogger('')
1716 rootLogger.setLevel(logging.DEBUG)
1717 socketHandler = logging.handlers.SocketHandler('localhost',
1718 logging.handlers.DEFAULT_TCP_LOGGING_PORT)
1719 # don't bother with a formatter, since a socket handler sends the event as
1720 # an unformatted pickle
1721 rootLogger.addHandler(socketHandler)
1722
1723 # Now, we can log to the root logger, or any other logger. First the root...
1724 logging.info('Jackdaws love my big sphinx of quartz.')
1725
1726 # Now, define a couple of other loggers which might represent areas in your
1727 # application:
1728
1729 logger1 = logging.getLogger('myapp.area1')
1730 logger2 = logging.getLogger('myapp.area2')
1731
1732 logger1.debug('Quick zephyrs blow, vexing daft Jim.')
1733 logger1.info('How quickly daft jumping zebras vex.')
1734 logger2.warning('Jail zesty vixen who grabbed pay from quack.')
1735 logger2.error('The five boxing wizards jump quickly.')
1736
Alexandre Vassalottice261952008-05-12 02:31:37 +00001737At the receiving end, you can set up a receiver using the :mod:`socketserver`
Georg Brandl116aa622007-08-15 14:28:22 +00001738module. Here is a basic working example::
1739
Georg Brandla35f4b92009-05-31 16:41:59 +00001740 import pickle
Georg Brandl116aa622007-08-15 14:28:22 +00001741 import logging
1742 import logging.handlers
Alexandre Vassalottice261952008-05-12 02:31:37 +00001743 import socketserver
Georg Brandl116aa622007-08-15 14:28:22 +00001744 import struct
1745
1746
Alexandre Vassalottice261952008-05-12 02:31:37 +00001747 class LogRecordStreamHandler(socketserver.StreamRequestHandler):
Georg Brandl116aa622007-08-15 14:28:22 +00001748 """Handler for a streaming logging request.
1749
1750 This basically logs the record using whatever logging policy is
1751 configured locally.
1752 """
1753
1754 def handle(self):
1755 """
1756 Handle multiple requests - each expected to be a 4-byte length,
1757 followed by the LogRecord in pickle format. Logs the record
1758 according to whatever policy is configured locally.
1759 """
Collin Winter46334482007-09-10 00:49:57 +00001760 while True:
Georg Brandl116aa622007-08-15 14:28:22 +00001761 chunk = self.connection.recv(4)
1762 if len(chunk) < 4:
1763 break
1764 slen = struct.unpack(">L", chunk)[0]
1765 chunk = self.connection.recv(slen)
1766 while len(chunk) < slen:
1767 chunk = chunk + self.connection.recv(slen - len(chunk))
1768 obj = self.unPickle(chunk)
1769 record = logging.makeLogRecord(obj)
1770 self.handleLogRecord(record)
1771
1772 def unPickle(self, data):
Georg Brandla35f4b92009-05-31 16:41:59 +00001773 return pickle.loads(data)
Georg Brandl116aa622007-08-15 14:28:22 +00001774
1775 def handleLogRecord(self, record):
1776 # if a name is specified, we use the named logger rather than the one
1777 # implied by the record.
1778 if self.server.logname is not None:
1779 name = self.server.logname
1780 else:
1781 name = record.name
1782 logger = logging.getLogger(name)
1783 # N.B. EVERY record gets logged. This is because Logger.handle
1784 # is normally called AFTER logger-level filtering. If you want
1785 # to do filtering, do it at the client end to save wasting
1786 # cycles and network bandwidth!
1787 logger.handle(record)
1788
Alexandre Vassalottice261952008-05-12 02:31:37 +00001789 class LogRecordSocketReceiver(socketserver.ThreadingTCPServer):
Georg Brandl116aa622007-08-15 14:28:22 +00001790 """simple TCP socket-based logging receiver suitable for testing.
1791 """
1792
1793 allow_reuse_address = 1
1794
1795 def __init__(self, host='localhost',
1796 port=logging.handlers.DEFAULT_TCP_LOGGING_PORT,
1797 handler=LogRecordStreamHandler):
Alexandre Vassalottice261952008-05-12 02:31:37 +00001798 socketserver.ThreadingTCPServer.__init__(self, (host, port), handler)
Georg Brandl116aa622007-08-15 14:28:22 +00001799 self.abort = 0
1800 self.timeout = 1
1801 self.logname = None
1802
1803 def serve_until_stopped(self):
1804 import select
1805 abort = 0
1806 while not abort:
1807 rd, wr, ex = select.select([self.socket.fileno()],
1808 [], [],
1809 self.timeout)
1810 if rd:
1811 self.handle_request()
1812 abort = self.abort
1813
1814 def main():
1815 logging.basicConfig(
1816 format="%(relativeCreated)5d %(name)-15s %(levelname)-8s %(message)s")
1817 tcpserver = LogRecordSocketReceiver()
Georg Brandl6911e3c2007-09-04 07:15:32 +00001818 print("About to start TCP server...")
Georg Brandl116aa622007-08-15 14:28:22 +00001819 tcpserver.serve_until_stopped()
1820
1821 if __name__ == "__main__":
1822 main()
1823
1824First run the server, and then the client. On the client side, nothing is
1825printed on the console; on the server side, you should see something like::
1826
1827 About to start TCP server...
1828 59 root INFO Jackdaws love my big sphinx of quartz.
1829 59 myapp.area1 DEBUG Quick zephyrs blow, vexing daft Jim.
1830 69 myapp.area1 INFO How quickly daft jumping zebras vex.
1831 69 myapp.area2 WARNING Jail zesty vixen who grabbed pay from quack.
1832 69 myapp.area2 ERROR The five boxing wizards jump quickly.
1833
Vinay Sajipc15dfd62010-07-06 15:08:55 +00001834Note that there are some security issues with pickle in some scenarios. If
1835these affect you, you can use an alternative serialization scheme by overriding
1836the :meth:`makePickle` method and implementing your alternative there, as
1837well as adapting the above script to use your alternative serialization.
1838
Vinay Sajip4039aff2010-09-11 10:25:28 +00001839.. _arbitrary-object-messages:
1840
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00001841Using arbitrary objects as messages
1842-----------------------------------
1843
1844In the preceding sections and examples, it has been assumed that the message
1845passed when logging the event is a string. However, this is not the only
1846possibility. You can pass an arbitrary object as a message, and its
1847:meth:`__str__` method will be called when the logging system needs to convert
1848it to a string representation. In fact, if you want to, you can avoid
1849computing a string representation altogether - for example, the
1850:class:`SocketHandler` emits an event by pickling it and sending it over the
1851wire.
1852
Vinay Sajip55778922010-09-23 09:09:15 +00001853Dealing with handlers that block
1854--------------------------------
1855
1856.. currentmodule:: logging.handlers
1857
1858Sometimes you have to get your logging handlers to do their work without
1859blocking the thread you’re logging from. This is common in Web applications,
1860though of course it also occurs in other scenarios.
1861
1862A common culprit which demonstrates sluggish behaviour is the
1863:class:`SMTPHandler`: sending emails can take a long time, for a
1864number of reasons outside the developer’s control (for example, a poorly
1865performing mail or network infrastructure). But almost any network-based
1866handler can block: Even a :class:`SocketHandler` operation may do a
1867DNS query under the hood which is too slow (and this query can be deep in the
1868socket library code, below the Python layer, and outside your control).
1869
1870One solution is to use a two-part approach. For the first part, attach only a
1871:class:`QueueHandler` to those loggers which are accessed from
1872performance-critical threads. They simply write to their queue, which can be
1873sized to a large enough capacity or initialized with no upper bound to their
1874size. The write to the queue will typically be accepted quickly, though you
1875will probably need to catch the :ref:`queue.Full` exception as a precaution
1876in your code. If you are a library developer who has performance-critical
1877threads in their code, be sure to document this (together with a suggestion to
1878attach only ``QueueHandlers`` to your loggers) for the benefit of other
1879developers who will use your code.
1880
1881The second part of the solution is :class:`QueueListener`, which has been
1882designed as the counterpart to :class:`QueueHandler`. A
1883:class:`QueueListener` is very simple: it’s passed a queue and some handlers,
1884and it fires up an internal thread which listens to its queue for LogRecords
1885sent from ``QueueHandlers`` (or any other source of ``LogRecords``, for that
1886matter). The ``LogRecords`` are removed from the queue and passed to the
1887handlers for processing.
1888
1889The advantage of having a separate :class:`QueueListener` class is that you
1890can use the same instance to service multiple ``QueueHandlers``. This is more
1891resource-friendly than, say, having threaded versions of the existing handler
1892classes, which would eat up one thread per handler for no particular benefit.
1893
1894An example of using these two classes follows (imports omitted)::
1895
1896 que = queue.Queue(-1) # no limit on size
1897 queue_handler = QueueHandler(que)
1898 handler = logging.StreamHandler()
1899 listener = QueueListener(que, handler)
1900 root = logging.getLogger()
1901 root.addHandler(queue_handler)
1902 formatter = logging.Formatter('%(threadName)s: %(message)s')
1903 handler.setFormatter(formatter)
1904 listener.start()
1905 # The log output will display the thread which generated
1906 # the event (the main thread) rather than the internal
1907 # thread which monitors the internal queue. This is what
1908 # you want to happen.
1909 root.warning('Look out!')
1910 listener.stop()
1911
1912which, when run, will produce::
1913
1914 MainThread: Look out!
1915
1916
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00001917Optimization
1918------------
1919
1920Formatting of message arguments is deferred until it cannot be avoided.
1921However, computing the arguments passed to the logging method can also be
1922expensive, and you may want to avoid doing it if the logger will just throw
1923away your event. To decide what to do, you can call the :meth:`isEnabledFor`
1924method which takes a level argument and returns true if the event would be
1925created by the Logger for that level of call. You can write code like this::
1926
1927 if logger.isEnabledFor(logging.DEBUG):
1928 logger.debug("Message with %s, %s", expensive_func1(),
1929 expensive_func2())
1930
1931so that if the logger's threshold is set above ``DEBUG``, the calls to
1932:func:`expensive_func1` and :func:`expensive_func2` are never made.
1933
1934There are other optimizations which can be made for specific applications which
1935need more precise control over what logging information is collected. Here's a
1936list of things you can do to avoid processing during logging which you don't
1937need:
1938
1939+-----------------------------------------------+----------------------------------------+
1940| What you don't want to collect | How to avoid collecting it |
1941+===============================================+========================================+
1942| Information about where calls were made from. | Set ``logging._srcfile`` to ``None``. |
1943+-----------------------------------------------+----------------------------------------+
1944| Threading information. | Set ``logging.logThreads`` to ``0``. |
1945+-----------------------------------------------+----------------------------------------+
1946| Process information. | Set ``logging.logProcesses`` to ``0``. |
1947+-----------------------------------------------+----------------------------------------+
1948
1949Also note that the core logging module only includes the basic handlers. If
1950you don't import :mod:`logging.handlers` and :mod:`logging.config`, they won't
1951take up any memory.
1952
1953.. _handler:
Georg Brandl116aa622007-08-15 14:28:22 +00001954
1955Handler Objects
1956---------------
1957
1958Handlers have the following attributes and methods. Note that :class:`Handler`
1959is never instantiated directly; this class acts as a base for more useful
1960subclasses. However, the :meth:`__init__` method in subclasses needs to call
1961:meth:`Handler.__init__`.
1962
1963
1964.. method:: Handler.__init__(level=NOTSET)
1965
1966 Initializes the :class:`Handler` instance by setting its level, setting the list
1967 of filters to the empty list and creating a lock (using :meth:`createLock`) for
1968 serializing access to an I/O mechanism.
1969
1970
1971.. method:: Handler.createLock()
1972
1973 Initializes a thread lock which can be used to serialize access to underlying
1974 I/O functionality which may not be threadsafe.
1975
1976
1977.. method:: Handler.acquire()
1978
1979 Acquires the thread lock created with :meth:`createLock`.
1980
1981
1982.. method:: Handler.release()
1983
1984 Releases the thread lock acquired with :meth:`acquire`.
1985
1986
1987.. method:: Handler.setLevel(lvl)
1988
1989 Sets the threshold for this handler to *lvl*. Logging messages which are less
1990 severe than *lvl* will be ignored. When a handler is created, the level is set
1991 to :const:`NOTSET` (which causes all messages to be processed).
1992
1993
1994.. method:: Handler.setFormatter(form)
1995
1996 Sets the :class:`Formatter` for this handler to *form*.
1997
1998
1999.. method:: Handler.addFilter(filt)
2000
2001 Adds the specified filter *filt* to this handler.
2002
2003
2004.. method:: Handler.removeFilter(filt)
2005
2006 Removes the specified filter *filt* from this handler.
2007
2008
2009.. method:: Handler.filter(record)
2010
2011 Applies this handler's filters to the record and returns a true value if the
2012 record is to be processed.
2013
2014
2015.. method:: Handler.flush()
2016
2017 Ensure all logging output has been flushed. This version does nothing and is
2018 intended to be implemented by subclasses.
2019
2020
2021.. method:: Handler.close()
2022
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002023 Tidy up any resources used by the handler. This version does no output but
2024 removes the handler from an internal list of handlers which is closed when
2025 :func:`shutdown` is called. Subclasses should ensure that this gets called
2026 from overridden :meth:`close` methods.
Georg Brandl116aa622007-08-15 14:28:22 +00002027
2028
2029.. method:: Handler.handle(record)
2030
2031 Conditionally emits the specified logging record, depending on filters which may
2032 have been added to the handler. Wraps the actual emission of the record with
2033 acquisition/release of the I/O thread lock.
2034
2035
2036.. method:: Handler.handleError(record)
2037
2038 This method should be called from handlers when an exception is encountered
2039 during an :meth:`emit` call. By default it does nothing, which means that
2040 exceptions get silently ignored. This is what is mostly wanted for a logging
2041 system - most users will not care about errors in the logging system, they are
2042 more interested in application errors. You could, however, replace this with a
2043 custom handler if you wish. The specified record is the one which was being
2044 processed when the exception occurred.
2045
2046
2047.. method:: Handler.format(record)
2048
2049 Do formatting for a record - if a formatter is set, use it. Otherwise, use the
2050 default formatter for the module.
2051
2052
2053.. method:: Handler.emit(record)
2054
2055 Do whatever it takes to actually log the specified logging record. This version
2056 is intended to be implemented by subclasses and so raises a
2057 :exc:`NotImplementedError`.
2058
2059
Vinay Sajipd31f3632010-06-29 15:31:15 +00002060.. _stream-handler:
2061
Georg Brandl116aa622007-08-15 14:28:22 +00002062StreamHandler
2063^^^^^^^^^^^^^
2064
2065The :class:`StreamHandler` class, located in the core :mod:`logging` package,
2066sends logging output to streams such as *sys.stdout*, *sys.stderr* or any
2067file-like object (or, more precisely, any object which supports :meth:`write`
2068and :meth:`flush` methods).
2069
2070
Benjamin Peterson1baf4652009-12-31 03:11:23 +00002071.. currentmodule:: logging
2072
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00002073.. class:: StreamHandler(stream=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002074
Benjamin Peterson4ac9ce42009-10-04 14:49:41 +00002075 Returns a new instance of the :class:`StreamHandler` class. If *stream* is
Georg Brandl116aa622007-08-15 14:28:22 +00002076 specified, the instance will use it for logging output; otherwise, *sys.stderr*
2077 will be used.
2078
2079
Benjamin Petersone41251e2008-04-25 01:59:09 +00002080 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002081
Benjamin Petersone41251e2008-04-25 01:59:09 +00002082 If a formatter is specified, it is used to format the record. The record
2083 is then written to the stream with a trailing newline. If exception
2084 information is present, it is formatted using
2085 :func:`traceback.print_exception` and appended to the stream.
Georg Brandl116aa622007-08-15 14:28:22 +00002086
2087
Benjamin Petersone41251e2008-04-25 01:59:09 +00002088 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002089
Benjamin Petersone41251e2008-04-25 01:59:09 +00002090 Flushes the stream by calling its :meth:`flush` method. Note that the
2091 :meth:`close` method is inherited from :class:`Handler` and so does
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002092 no output, so an explicit :meth:`flush` call may be needed at times.
Georg Brandl116aa622007-08-15 14:28:22 +00002093
2094
Vinay Sajipd31f3632010-06-29 15:31:15 +00002095.. _file-handler:
2096
Georg Brandl116aa622007-08-15 14:28:22 +00002097FileHandler
2098^^^^^^^^^^^
2099
2100The :class:`FileHandler` class, located in the core :mod:`logging` package,
2101sends logging output to a disk file. It inherits the output functionality from
2102:class:`StreamHandler`.
2103
2104
Vinay Sajipd31f3632010-06-29 15:31:15 +00002105.. class:: FileHandler(filename, mode='a', encoding=None, delay=False)
Georg Brandl116aa622007-08-15 14:28:22 +00002106
2107 Returns a new instance of the :class:`FileHandler` class. The specified file is
2108 opened and used as the stream for logging. If *mode* is not specified,
2109 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Christian Heimese7a15bb2008-01-24 16:21:45 +00002110 with that encoding. If *delay* is true, then file opening is deferred until the
2111 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002112
2113
Benjamin Petersone41251e2008-04-25 01:59:09 +00002114 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002115
Benjamin Petersone41251e2008-04-25 01:59:09 +00002116 Closes the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002117
2118
Benjamin Petersone41251e2008-04-25 01:59:09 +00002119 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002120
Benjamin Petersone41251e2008-04-25 01:59:09 +00002121 Outputs the record to the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002122
Vinay Sajipd31f3632010-06-29 15:31:15 +00002123.. _null-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002124
Vinay Sajipaa672eb2009-01-02 18:53:45 +00002125NullHandler
2126^^^^^^^^^^^
2127
2128.. versionadded:: 3.1
2129
2130The :class:`NullHandler` class, located in the core :mod:`logging` package,
2131does not do any formatting or output. It is essentially a "no-op" handler
2132for use by library developers.
2133
2134
2135.. class:: NullHandler()
2136
2137 Returns a new instance of the :class:`NullHandler` class.
2138
2139
2140 .. method:: emit(record)
2141
2142 This method does nothing.
2143
Vinay Sajip26a2d5e2009-01-10 13:37:26 +00002144See :ref:`library-config` for more information on how to use
2145:class:`NullHandler`.
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00002146
Vinay Sajipd31f3632010-06-29 15:31:15 +00002147.. _watched-file-handler:
2148
Georg Brandl116aa622007-08-15 14:28:22 +00002149WatchedFileHandler
2150^^^^^^^^^^^^^^^^^^
2151
Benjamin Peterson058e31e2009-01-16 03:54:08 +00002152.. currentmodule:: logging.handlers
Vinay Sajipaa672eb2009-01-02 18:53:45 +00002153
Georg Brandl116aa622007-08-15 14:28:22 +00002154The :class:`WatchedFileHandler` class, located in the :mod:`logging.handlers`
2155module, is a :class:`FileHandler` which watches the file it is logging to. If
2156the file changes, it is closed and reopened using the file name.
2157
2158A file change can happen because of usage of programs such as *newsyslog* and
2159*logrotate* which perform log file rotation. This handler, intended for use
2160under Unix/Linux, watches the file to see if it has changed since the last emit.
2161(A file is deemed to have changed if its device or inode have changed.) If the
2162file has changed, the old file stream is closed, and the file opened to get a
2163new stream.
2164
2165This handler is not appropriate for use under Windows, because under Windows
2166open log files cannot be moved or renamed - logging opens the files with
2167exclusive locks - and so there is no need for such a handler. Furthermore,
2168*ST_INO* is not supported under Windows; :func:`stat` always returns zero for
2169this value.
2170
2171
Christian Heimese7a15bb2008-01-24 16:21:45 +00002172.. class:: WatchedFileHandler(filename[,mode[, encoding[, delay]]])
Georg Brandl116aa622007-08-15 14:28:22 +00002173
2174 Returns a new instance of the :class:`WatchedFileHandler` class. The specified
2175 file is opened and used as the stream for logging. If *mode* is not specified,
2176 :const:`'a'` is used. If *encoding* is not *None*, it is used to open the file
Christian Heimese7a15bb2008-01-24 16:21:45 +00002177 with that encoding. If *delay* is true, then file opening is deferred until the
2178 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002179
2180
Benjamin Petersone41251e2008-04-25 01:59:09 +00002181 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002182
Benjamin Petersone41251e2008-04-25 01:59:09 +00002183 Outputs the record to the file, but first checks to see if the file has
2184 changed. If it has, the existing stream is flushed and closed and the
2185 file opened again, before outputting the record to the file.
Georg Brandl116aa622007-08-15 14:28:22 +00002186
Vinay Sajipd31f3632010-06-29 15:31:15 +00002187.. _rotating-file-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002188
2189RotatingFileHandler
2190^^^^^^^^^^^^^^^^^^^
2191
2192The :class:`RotatingFileHandler` class, located in the :mod:`logging.handlers`
2193module, supports rotation of disk log files.
2194
2195
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002196.. class:: RotatingFileHandler(filename, mode='a', maxBytes=0, backupCount=0, encoding=None, delay=0)
Georg Brandl116aa622007-08-15 14:28:22 +00002197
2198 Returns a new instance of the :class:`RotatingFileHandler` class. The specified
2199 file is opened and used as the stream for logging. If *mode* is not specified,
Christian Heimese7a15bb2008-01-24 16:21:45 +00002200 ``'a'`` is used. If *encoding* is not *None*, it is used to open the file
2201 with that encoding. If *delay* is true, then file opening is deferred until the
2202 first call to :meth:`emit`. By default, the file grows indefinitely.
Georg Brandl116aa622007-08-15 14:28:22 +00002203
2204 You can use the *maxBytes* and *backupCount* values to allow the file to
2205 :dfn:`rollover` at a predetermined size. When the size is about to be exceeded,
2206 the file is closed and a new file is silently opened for output. Rollover occurs
2207 whenever the current log file is nearly *maxBytes* in length; if *maxBytes* is
2208 zero, rollover never occurs. If *backupCount* is non-zero, the system will save
2209 old log files by appending the extensions ".1", ".2" etc., to the filename. For
2210 example, with a *backupCount* of 5 and a base file name of :file:`app.log`, you
2211 would get :file:`app.log`, :file:`app.log.1`, :file:`app.log.2`, up to
2212 :file:`app.log.5`. The file being written to is always :file:`app.log`. When
2213 this file is filled, it is closed and renamed to :file:`app.log.1`, and if files
2214 :file:`app.log.1`, :file:`app.log.2`, etc. exist, then they are renamed to
2215 :file:`app.log.2`, :file:`app.log.3` etc. respectively.
2216
2217
Benjamin Petersone41251e2008-04-25 01:59:09 +00002218 .. method:: doRollover()
Georg Brandl116aa622007-08-15 14:28:22 +00002219
Benjamin Petersone41251e2008-04-25 01:59:09 +00002220 Does a rollover, as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002221
2222
Benjamin Petersone41251e2008-04-25 01:59:09 +00002223 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002224
Benjamin Petersone41251e2008-04-25 01:59:09 +00002225 Outputs the record to the file, catering for rollover as described
2226 previously.
Georg Brandl116aa622007-08-15 14:28:22 +00002227
Vinay Sajipd31f3632010-06-29 15:31:15 +00002228.. _timed-rotating-file-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002229
2230TimedRotatingFileHandler
2231^^^^^^^^^^^^^^^^^^^^^^^^
2232
2233The :class:`TimedRotatingFileHandler` class, located in the
2234:mod:`logging.handlers` module, supports rotation of disk log files at certain
2235timed intervals.
2236
2237
Vinay Sajipd31f3632010-06-29 15:31:15 +00002238.. class:: TimedRotatingFileHandler(filename, when='h', interval=1, backupCount=0, encoding=None, delay=False, utc=False)
Georg Brandl116aa622007-08-15 14:28:22 +00002239
2240 Returns a new instance of the :class:`TimedRotatingFileHandler` class. The
2241 specified file is opened and used as the stream for logging. On rotating it also
2242 sets the filename suffix. Rotating happens based on the product of *when* and
2243 *interval*.
2244
2245 You can use the *when* to specify the type of *interval*. The list of possible
Georg Brandl0c77a822008-06-10 16:37:50 +00002246 values is below. Note that they are not case sensitive.
Georg Brandl116aa622007-08-15 14:28:22 +00002247
Christian Heimesb558a2e2008-03-02 22:46:37 +00002248 +----------------+-----------------------+
2249 | Value | Type of interval |
2250 +================+=======================+
2251 | ``'S'`` | Seconds |
2252 +----------------+-----------------------+
2253 | ``'M'`` | Minutes |
2254 +----------------+-----------------------+
2255 | ``'H'`` | Hours |
2256 +----------------+-----------------------+
2257 | ``'D'`` | Days |
2258 +----------------+-----------------------+
2259 | ``'W'`` | Week day (0=Monday) |
2260 +----------------+-----------------------+
2261 | ``'midnight'`` | Roll over at midnight |
2262 +----------------+-----------------------+
Georg Brandl116aa622007-08-15 14:28:22 +00002263
Christian Heimesb558a2e2008-03-02 22:46:37 +00002264 The system will save old log files by appending extensions to the filename.
2265 The extensions are date-and-time based, using the strftime format
Benjamin Petersonad9d48d2008-04-02 21:49:44 +00002266 ``%Y-%m-%d_%H-%M-%S`` or a leading portion thereof, depending on the
Georg Brandl3dbca812008-07-23 16:10:53 +00002267 rollover interval.
Benjamin Peterson9451a1c2010-03-13 22:30:34 +00002268
2269 When computing the next rollover time for the first time (when the handler
2270 is created), the last modification time of an existing log file, or else
2271 the current time, is used to compute when the next rotation will occur.
2272
Georg Brandl0c77a822008-06-10 16:37:50 +00002273 If the *utc* argument is true, times in UTC will be used; otherwise
2274 local time is used.
2275
2276 If *backupCount* is nonzero, at most *backupCount* files
Benjamin Petersonad9d48d2008-04-02 21:49:44 +00002277 will be kept, and if more would be created when rollover occurs, the oldest
2278 one is deleted. The deletion logic uses the interval to determine which
2279 files to delete, so changing the interval may leave old files lying around.
Georg Brandl116aa622007-08-15 14:28:22 +00002280
Vinay Sajipd31f3632010-06-29 15:31:15 +00002281 If *delay* is true, then file opening is deferred until the first call to
2282 :meth:`emit`.
2283
Georg Brandl116aa622007-08-15 14:28:22 +00002284
Benjamin Petersone41251e2008-04-25 01:59:09 +00002285 .. method:: doRollover()
Georg Brandl116aa622007-08-15 14:28:22 +00002286
Benjamin Petersone41251e2008-04-25 01:59:09 +00002287 Does a rollover, as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002288
2289
Benjamin Petersone41251e2008-04-25 01:59:09 +00002290 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002291
Benjamin Petersone41251e2008-04-25 01:59:09 +00002292 Outputs the record to the file, catering for rollover as described above.
Georg Brandl116aa622007-08-15 14:28:22 +00002293
2294
Vinay Sajipd31f3632010-06-29 15:31:15 +00002295.. _socket-handler:
2296
Georg Brandl116aa622007-08-15 14:28:22 +00002297SocketHandler
2298^^^^^^^^^^^^^
2299
2300The :class:`SocketHandler` class, located in the :mod:`logging.handlers` module,
2301sends logging output to a network socket. The base class uses a TCP socket.
2302
2303
2304.. class:: SocketHandler(host, port)
2305
2306 Returns a new instance of the :class:`SocketHandler` class intended to
2307 communicate with a remote machine whose address is given by *host* and *port*.
2308
2309
Benjamin Petersone41251e2008-04-25 01:59:09 +00002310 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002311
Benjamin Petersone41251e2008-04-25 01:59:09 +00002312 Closes the socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002313
2314
Benjamin Petersone41251e2008-04-25 01:59:09 +00002315 .. method:: emit()
Georg Brandl116aa622007-08-15 14:28:22 +00002316
Benjamin Petersone41251e2008-04-25 01:59:09 +00002317 Pickles the record's attribute dictionary and writes it to the socket in
2318 binary format. If there is an error with the socket, silently drops the
2319 packet. If the connection was previously lost, re-establishes the
2320 connection. To unpickle the record at the receiving end into a
2321 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl116aa622007-08-15 14:28:22 +00002322
2323
Benjamin Petersone41251e2008-04-25 01:59:09 +00002324 .. method:: handleError()
Georg Brandl116aa622007-08-15 14:28:22 +00002325
Benjamin Petersone41251e2008-04-25 01:59:09 +00002326 Handles an error which has occurred during :meth:`emit`. The most likely
2327 cause is a lost connection. Closes the socket so that we can retry on the
2328 next event.
Georg Brandl116aa622007-08-15 14:28:22 +00002329
2330
Benjamin Petersone41251e2008-04-25 01:59:09 +00002331 .. method:: makeSocket()
Georg Brandl116aa622007-08-15 14:28:22 +00002332
Benjamin Petersone41251e2008-04-25 01:59:09 +00002333 This is a factory method which allows subclasses to define the precise
2334 type of socket they want. The default implementation creates a TCP socket
2335 (:const:`socket.SOCK_STREAM`).
Georg Brandl116aa622007-08-15 14:28:22 +00002336
2337
Benjamin Petersone41251e2008-04-25 01:59:09 +00002338 .. method:: makePickle(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002339
Benjamin Petersone41251e2008-04-25 01:59:09 +00002340 Pickles the record's attribute dictionary in binary format with a length
2341 prefix, and returns it ready for transmission across the socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002342
Vinay Sajipd31f3632010-06-29 15:31:15 +00002343 Note that pickles aren't completely secure. If you are concerned about
2344 security, you may want to override this method to implement a more secure
2345 mechanism. For example, you can sign pickles using HMAC and then verify
2346 them on the receiving end, or alternatively you can disable unpickling of
2347 global objects on the receiving end.
Georg Brandl116aa622007-08-15 14:28:22 +00002348
Benjamin Petersone41251e2008-04-25 01:59:09 +00002349 .. method:: send(packet)
Georg Brandl116aa622007-08-15 14:28:22 +00002350
Benjamin Petersone41251e2008-04-25 01:59:09 +00002351 Send a pickled string *packet* to the socket. This function allows for
2352 partial sends which can happen when the network is busy.
Georg Brandl116aa622007-08-15 14:28:22 +00002353
2354
Vinay Sajipd31f3632010-06-29 15:31:15 +00002355.. _datagram-handler:
2356
Georg Brandl116aa622007-08-15 14:28:22 +00002357DatagramHandler
2358^^^^^^^^^^^^^^^
2359
2360The :class:`DatagramHandler` class, located in the :mod:`logging.handlers`
2361module, inherits from :class:`SocketHandler` to support sending logging messages
2362over UDP sockets.
2363
2364
2365.. class:: DatagramHandler(host, port)
2366
2367 Returns a new instance of the :class:`DatagramHandler` class intended to
2368 communicate with a remote machine whose address is given by *host* and *port*.
2369
2370
Benjamin Petersone41251e2008-04-25 01:59:09 +00002371 .. method:: emit()
Georg Brandl116aa622007-08-15 14:28:22 +00002372
Benjamin Petersone41251e2008-04-25 01:59:09 +00002373 Pickles the record's attribute dictionary and writes it to the socket in
2374 binary format. If there is an error with the socket, silently drops the
2375 packet. To unpickle the record at the receiving end into a
2376 :class:`LogRecord`, use the :func:`makeLogRecord` function.
Georg Brandl116aa622007-08-15 14:28:22 +00002377
2378
Benjamin Petersone41251e2008-04-25 01:59:09 +00002379 .. method:: makeSocket()
Georg Brandl116aa622007-08-15 14:28:22 +00002380
Benjamin Petersone41251e2008-04-25 01:59:09 +00002381 The factory method of :class:`SocketHandler` is here overridden to create
2382 a UDP socket (:const:`socket.SOCK_DGRAM`).
Georg Brandl116aa622007-08-15 14:28:22 +00002383
2384
Benjamin Petersone41251e2008-04-25 01:59:09 +00002385 .. method:: send(s)
Georg Brandl116aa622007-08-15 14:28:22 +00002386
Benjamin Petersone41251e2008-04-25 01:59:09 +00002387 Send a pickled string to a socket.
Georg Brandl116aa622007-08-15 14:28:22 +00002388
2389
Vinay Sajipd31f3632010-06-29 15:31:15 +00002390.. _syslog-handler:
2391
Georg Brandl116aa622007-08-15 14:28:22 +00002392SysLogHandler
2393^^^^^^^^^^^^^
2394
2395The :class:`SysLogHandler` class, located in the :mod:`logging.handlers` module,
2396supports sending logging messages to a remote or local Unix syslog.
2397
2398
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002399.. class:: SysLogHandler(address=('localhost', SYSLOG_UDP_PORT), facility=LOG_USER, socktype=socket.SOCK_DGRAM)
Georg Brandl116aa622007-08-15 14:28:22 +00002400
2401 Returns a new instance of the :class:`SysLogHandler` class intended to
2402 communicate with a remote Unix machine whose address is given by *address* in
2403 the form of a ``(host, port)`` tuple. If *address* is not specified,
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002404 ``('localhost', 514)`` is used. The address is used to open a socket. An
Georg Brandl116aa622007-08-15 14:28:22 +00002405 alternative to providing a ``(host, port)`` tuple is providing an address as a
2406 string, for example "/dev/log". In this case, a Unix domain socket is used to
2407 send the message to the syslog. If *facility* is not specified,
Vinay Sajipcbabd7e2009-10-10 20:32:36 +00002408 :const:`LOG_USER` is used. The type of socket opened depends on the
2409 *socktype* argument, which defaults to :const:`socket.SOCK_DGRAM` and thus
2410 opens a UDP socket. To open a TCP socket (for use with the newer syslog
2411 daemons such as rsyslog), specify a value of :const:`socket.SOCK_STREAM`.
2412
2413 .. versionchanged:: 3.2
2414 *socktype* was added.
Georg Brandl116aa622007-08-15 14:28:22 +00002415
2416
Benjamin Petersone41251e2008-04-25 01:59:09 +00002417 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002418
Benjamin Petersone41251e2008-04-25 01:59:09 +00002419 Closes the socket to the remote host.
Georg Brandl116aa622007-08-15 14:28:22 +00002420
2421
Benjamin Petersone41251e2008-04-25 01:59:09 +00002422 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002423
Benjamin Petersone41251e2008-04-25 01:59:09 +00002424 The record is formatted, and then sent to the syslog server. If exception
2425 information is present, it is *not* sent to the server.
Georg Brandl116aa622007-08-15 14:28:22 +00002426
2427
Benjamin Petersone41251e2008-04-25 01:59:09 +00002428 .. method:: encodePriority(facility, priority)
Georg Brandl116aa622007-08-15 14:28:22 +00002429
Benjamin Petersone41251e2008-04-25 01:59:09 +00002430 Encodes the facility and priority into an integer. You can pass in strings
2431 or integers - if strings are passed, internal mapping dictionaries are
2432 used to convert them to integers.
Georg Brandl116aa622007-08-15 14:28:22 +00002433
Benjamin Peterson22005fc2010-04-11 16:25:06 +00002434 The symbolic ``LOG_`` values are defined in :class:`SysLogHandler` and
2435 mirror the values defined in the ``sys/syslog.h`` header file.
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002436
Georg Brandl88d7dbd2010-04-18 09:50:07 +00002437 **Priorities**
2438
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002439 +--------------------------+---------------+
2440 | Name (string) | Symbolic value|
2441 +==========================+===============+
2442 | ``alert`` | LOG_ALERT |
2443 +--------------------------+---------------+
2444 | ``crit`` or ``critical`` | LOG_CRIT |
2445 +--------------------------+---------------+
2446 | ``debug`` | LOG_DEBUG |
2447 +--------------------------+---------------+
2448 | ``emerg`` or ``panic`` | LOG_EMERG |
2449 +--------------------------+---------------+
2450 | ``err`` or ``error`` | LOG_ERR |
2451 +--------------------------+---------------+
2452 | ``info`` | LOG_INFO |
2453 +--------------------------+---------------+
2454 | ``notice`` | LOG_NOTICE |
2455 +--------------------------+---------------+
2456 | ``warn`` or ``warning`` | LOG_WARNING |
2457 +--------------------------+---------------+
2458
Georg Brandl88d7dbd2010-04-18 09:50:07 +00002459 **Facilities**
2460
Benjamin Peterson08bf91c2010-04-11 16:12:57 +00002461 +---------------+---------------+
2462 | Name (string) | Symbolic value|
2463 +===============+===============+
2464 | ``auth`` | LOG_AUTH |
2465 +---------------+---------------+
2466 | ``authpriv`` | LOG_AUTHPRIV |
2467 +---------------+---------------+
2468 | ``cron`` | LOG_CRON |
2469 +---------------+---------------+
2470 | ``daemon`` | LOG_DAEMON |
2471 +---------------+---------------+
2472 | ``ftp`` | LOG_FTP |
2473 +---------------+---------------+
2474 | ``kern`` | LOG_KERN |
2475 +---------------+---------------+
2476 | ``lpr`` | LOG_LPR |
2477 +---------------+---------------+
2478 | ``mail`` | LOG_MAIL |
2479 +---------------+---------------+
2480 | ``news`` | LOG_NEWS |
2481 +---------------+---------------+
2482 | ``syslog`` | LOG_SYSLOG |
2483 +---------------+---------------+
2484 | ``user`` | LOG_USER |
2485 +---------------+---------------+
2486 | ``uucp`` | LOG_UUCP |
2487 +---------------+---------------+
2488 | ``local0`` | LOG_LOCAL0 |
2489 +---------------+---------------+
2490 | ``local1`` | LOG_LOCAL1 |
2491 +---------------+---------------+
2492 | ``local2`` | LOG_LOCAL2 |
2493 +---------------+---------------+
2494 | ``local3`` | LOG_LOCAL3 |
2495 +---------------+---------------+
2496 | ``local4`` | LOG_LOCAL4 |
2497 +---------------+---------------+
2498 | ``local5`` | LOG_LOCAL5 |
2499 +---------------+---------------+
2500 | ``local6`` | LOG_LOCAL6 |
2501 +---------------+---------------+
2502 | ``local7`` | LOG_LOCAL7 |
2503 +---------------+---------------+
2504
2505 .. method:: mapPriority(levelname)
2506
2507 Maps a logging level name to a syslog priority name.
2508 You may need to override this if you are using custom levels, or
2509 if the default algorithm is not suitable for your needs. The
2510 default algorithm maps ``DEBUG``, ``INFO``, ``WARNING``, ``ERROR`` and
2511 ``CRITICAL`` to the equivalent syslog names, and all other level
2512 names to "warning".
2513
2514.. _nt-eventlog-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002515
2516NTEventLogHandler
2517^^^^^^^^^^^^^^^^^
2518
2519The :class:`NTEventLogHandler` class, located in the :mod:`logging.handlers`
2520module, supports sending logging messages to a local Windows NT, Windows 2000 or
2521Windows XP event log. Before you can use it, you need Mark Hammond's Win32
2522extensions for Python installed.
2523
2524
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002525.. class:: NTEventLogHandler(appname, dllname=None, logtype='Application')
Georg Brandl116aa622007-08-15 14:28:22 +00002526
2527 Returns a new instance of the :class:`NTEventLogHandler` class. The *appname* is
2528 used to define the application name as it appears in the event log. An
2529 appropriate registry entry is created using this name. The *dllname* should give
2530 the fully qualified pathname of a .dll or .exe which contains message
2531 definitions to hold in the log (if not specified, ``'win32service.pyd'`` is used
2532 - this is installed with the Win32 extensions and contains some basic
2533 placeholder message definitions. Note that use of these placeholders will make
2534 your event logs big, as the entire message source is held in the log. If you
2535 want slimmer logs, you have to pass in the name of your own .dll or .exe which
2536 contains the message definitions you want to use in the event log). The
2537 *logtype* is one of ``'Application'``, ``'System'`` or ``'Security'``, and
2538 defaults to ``'Application'``.
2539
2540
Benjamin Petersone41251e2008-04-25 01:59:09 +00002541 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002542
Benjamin Petersone41251e2008-04-25 01:59:09 +00002543 At this point, you can remove the application name from the registry as a
2544 source of event log entries. However, if you do this, you will not be able
2545 to see the events as you intended in the Event Log Viewer - it needs to be
2546 able to access the registry to get the .dll name. The current version does
Benjamin Peterson3e4f0552008-09-02 00:31:15 +00002547 not do this.
Georg Brandl116aa622007-08-15 14:28:22 +00002548
2549
Benjamin Petersone41251e2008-04-25 01:59:09 +00002550 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002551
Benjamin Petersone41251e2008-04-25 01:59:09 +00002552 Determines the message ID, event category and event type, and then logs
2553 the message in the NT event log.
Georg Brandl116aa622007-08-15 14:28:22 +00002554
2555
Benjamin Petersone41251e2008-04-25 01:59:09 +00002556 .. method:: getEventCategory(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002557
Benjamin Petersone41251e2008-04-25 01:59:09 +00002558 Returns the event category for the record. Override this if you want to
2559 specify your own categories. This version returns 0.
Georg Brandl116aa622007-08-15 14:28:22 +00002560
2561
Benjamin Petersone41251e2008-04-25 01:59:09 +00002562 .. method:: getEventType(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002563
Benjamin Petersone41251e2008-04-25 01:59:09 +00002564 Returns the event type for the record. Override this if you want to
2565 specify your own types. This version does a mapping using the handler's
2566 typemap attribute, which is set up in :meth:`__init__` to a dictionary
2567 which contains mappings for :const:`DEBUG`, :const:`INFO`,
2568 :const:`WARNING`, :const:`ERROR` and :const:`CRITICAL`. If you are using
2569 your own levels, you will either need to override this method or place a
2570 suitable dictionary in the handler's *typemap* attribute.
Georg Brandl116aa622007-08-15 14:28:22 +00002571
2572
Benjamin Petersone41251e2008-04-25 01:59:09 +00002573 .. method:: getMessageID(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002574
Benjamin Petersone41251e2008-04-25 01:59:09 +00002575 Returns the message ID for the record. If you are using your own messages,
2576 you could do this by having the *msg* passed to the logger being an ID
2577 rather than a format string. Then, in here, you could use a dictionary
2578 lookup to get the message ID. This version returns 1, which is the base
2579 message ID in :file:`win32service.pyd`.
Georg Brandl116aa622007-08-15 14:28:22 +00002580
Vinay Sajipd31f3632010-06-29 15:31:15 +00002581.. _smtp-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002582
2583SMTPHandler
2584^^^^^^^^^^^
2585
2586The :class:`SMTPHandler` class, located in the :mod:`logging.handlers` module,
2587supports sending logging messages to an email address via SMTP.
2588
2589
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002590.. class:: SMTPHandler(mailhost, fromaddr, toaddrs, subject, credentials=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002591
2592 Returns a new instance of the :class:`SMTPHandler` class. The instance is
2593 initialized with the from and to addresses and subject line of the email. The
2594 *toaddrs* should be a list of strings. To specify a non-standard SMTP port, use
2595 the (host, port) tuple format for the *mailhost* argument. If you use a string,
2596 the standard SMTP port is used. If your SMTP server requires authentication, you
2597 can specify a (username, password) tuple for the *credentials* argument.
2598
Georg Brandl116aa622007-08-15 14:28:22 +00002599
Benjamin Petersone41251e2008-04-25 01:59:09 +00002600 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002601
Benjamin Petersone41251e2008-04-25 01:59:09 +00002602 Formats the record and sends it to the specified addressees.
Georg Brandl116aa622007-08-15 14:28:22 +00002603
2604
Benjamin Petersone41251e2008-04-25 01:59:09 +00002605 .. method:: getSubject(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002606
Benjamin Petersone41251e2008-04-25 01:59:09 +00002607 If you want to specify a subject line which is record-dependent, override
2608 this method.
Georg Brandl116aa622007-08-15 14:28:22 +00002609
Vinay Sajipd31f3632010-06-29 15:31:15 +00002610.. _memory-handler:
Georg Brandl116aa622007-08-15 14:28:22 +00002611
2612MemoryHandler
2613^^^^^^^^^^^^^
2614
2615The :class:`MemoryHandler` class, located in the :mod:`logging.handlers` module,
2616supports buffering of logging records in memory, periodically flushing them to a
2617:dfn:`target` handler. Flushing occurs whenever the buffer is full, or when an
2618event of a certain severity or greater is seen.
2619
2620:class:`MemoryHandler` is a subclass of the more general
2621:class:`BufferingHandler`, which is an abstract class. This buffers logging
2622records in memory. Whenever each record is added to the buffer, a check is made
2623by calling :meth:`shouldFlush` to see if the buffer should be flushed. If it
2624should, then :meth:`flush` is expected to do the needful.
2625
2626
2627.. class:: BufferingHandler(capacity)
2628
2629 Initializes the handler with a buffer of the specified capacity.
2630
2631
Benjamin Petersone41251e2008-04-25 01:59:09 +00002632 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002633
Benjamin Petersone41251e2008-04-25 01:59:09 +00002634 Appends the record to the buffer. If :meth:`shouldFlush` returns true,
2635 calls :meth:`flush` to process the buffer.
Georg Brandl116aa622007-08-15 14:28:22 +00002636
2637
Benjamin Petersone41251e2008-04-25 01:59:09 +00002638 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002639
Benjamin Petersone41251e2008-04-25 01:59:09 +00002640 You can override this to implement custom flushing behavior. This version
2641 just zaps the buffer to empty.
Georg Brandl116aa622007-08-15 14:28:22 +00002642
2643
Benjamin Petersone41251e2008-04-25 01:59:09 +00002644 .. method:: shouldFlush(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002645
Benjamin Petersone41251e2008-04-25 01:59:09 +00002646 Returns true if the buffer is up to capacity. This method can be
2647 overridden to implement custom flushing strategies.
Georg Brandl116aa622007-08-15 14:28:22 +00002648
2649
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002650.. class:: MemoryHandler(capacity, flushLevel=ERROR, target=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002651
2652 Returns a new instance of the :class:`MemoryHandler` class. The instance is
2653 initialized with a buffer size of *capacity*. If *flushLevel* is not specified,
2654 :const:`ERROR` is used. If no *target* is specified, the target will need to be
2655 set using :meth:`setTarget` before this handler does anything useful.
2656
2657
Benjamin Petersone41251e2008-04-25 01:59:09 +00002658 .. method:: close()
Georg Brandl116aa622007-08-15 14:28:22 +00002659
Benjamin Petersone41251e2008-04-25 01:59:09 +00002660 Calls :meth:`flush`, sets the target to :const:`None` and clears the
2661 buffer.
Georg Brandl116aa622007-08-15 14:28:22 +00002662
2663
Benjamin Petersone41251e2008-04-25 01:59:09 +00002664 .. method:: flush()
Georg Brandl116aa622007-08-15 14:28:22 +00002665
Benjamin Petersone41251e2008-04-25 01:59:09 +00002666 For a :class:`MemoryHandler`, flushing means just sending the buffered
Vinay Sajipc84f0162010-09-21 11:25:39 +00002667 records to the target, if there is one. The buffer is also cleared when
2668 this happens. Override if you want different behavior.
Georg Brandl116aa622007-08-15 14:28:22 +00002669
2670
Benjamin Petersone41251e2008-04-25 01:59:09 +00002671 .. method:: setTarget(target)
Georg Brandl116aa622007-08-15 14:28:22 +00002672
Benjamin Petersone41251e2008-04-25 01:59:09 +00002673 Sets the target handler for this handler.
Georg Brandl116aa622007-08-15 14:28:22 +00002674
2675
Benjamin Petersone41251e2008-04-25 01:59:09 +00002676 .. method:: shouldFlush(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002677
Benjamin Petersone41251e2008-04-25 01:59:09 +00002678 Checks for buffer full or a record at the *flushLevel* or higher.
Georg Brandl116aa622007-08-15 14:28:22 +00002679
2680
Vinay Sajipd31f3632010-06-29 15:31:15 +00002681.. _http-handler:
2682
Georg Brandl116aa622007-08-15 14:28:22 +00002683HTTPHandler
2684^^^^^^^^^^^
2685
2686The :class:`HTTPHandler` class, located in the :mod:`logging.handlers` module,
2687supports sending logging messages to a Web server, using either ``GET`` or
2688``POST`` semantics.
2689
2690
Vinay Sajip1b5646a2010-09-13 20:37:50 +00002691.. class:: HTTPHandler(host, url, method='GET', secure=False, credentials=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002692
Vinay Sajip1b5646a2010-09-13 20:37:50 +00002693 Returns a new instance of the :class:`HTTPHandler` class. The *host* can be
2694 of the form ``host:port``, should you need to use a specific port number.
2695 If no *method* is specified, ``GET`` is used. If *secure* is True, an HTTPS
2696 connection will be used. If *credentials* is specified, it should be a
2697 2-tuple consisting of userid and password, which will be placed in an HTTP
2698 'Authorization' header using Basic authentication. If you specify
2699 credentials, you should also specify secure=True so that your userid and
2700 password are not passed in cleartext across the wire.
Georg Brandl116aa622007-08-15 14:28:22 +00002701
2702
Benjamin Petersone41251e2008-04-25 01:59:09 +00002703 .. method:: emit(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002704
Senthil Kumaranf0769e82010-08-09 19:53:52 +00002705 Sends the record to the Web server as a percent-encoded dictionary.
Georg Brandl116aa622007-08-15 14:28:22 +00002706
2707
Vinay Sajip121a1c42010-09-08 10:46:15 +00002708.. _queue-handler:
2709
2710
2711QueueHandler
2712^^^^^^^^^^^^
2713
2714The :class:`QueueHandler` class, located in the :mod:`logging.handlers` module,
2715supports sending logging messages to a queue, such as those implemented in the
2716:mod:`queue` or :mod:`multiprocessing` modules.
2717
Vinay Sajip0637d492010-09-23 08:15:54 +00002718Along with the :class:`QueueListener` class, :class:`QueueHandler` can be used
2719to let handlers do their work on a separate thread from the one which does the
2720logging. This is important in Web applications and also other service
2721applications where threads servicing clients need to respond as quickly as
2722possible, while any potentially slow operations (such as sending an email via
2723:class:`SMTPHandler`) are done on a separate thread.
Vinay Sajip121a1c42010-09-08 10:46:15 +00002724
2725.. class:: QueueHandler(queue)
2726
2727 Returns a new instance of the :class:`QueueHandler` class. The instance is
Vinay Sajip63891ed2010-09-13 20:02:39 +00002728 initialized with the queue to send messages to. The queue can be any queue-
Vinay Sajip0637d492010-09-23 08:15:54 +00002729 like object; it's used as-is by the :meth:`enqueue` method, which needs
Vinay Sajip63891ed2010-09-13 20:02:39 +00002730 to know how to send messages to it.
Vinay Sajip121a1c42010-09-08 10:46:15 +00002731
2732
2733 .. method:: emit(record)
2734
Vinay Sajip0258ce82010-09-22 20:34:53 +00002735 Enqueues the result of preparing the LogRecord.
2736
2737 .. method:: prepare(record)
2738
2739 Prepares a record for queuing. The object returned by this
2740 method is enqueued.
2741
2742 The base implementation formats the record to merge the message
2743 and arguments, and removes unpickleable items from the record
2744 in-place.
2745
2746 You might want to override this method if you want to convert
2747 the record to a dict or JSON string, or send a modified copy
2748 of the record while leaving the original intact.
Vinay Sajip121a1c42010-09-08 10:46:15 +00002749
2750 .. method:: enqueue(record)
2751
2752 Enqueues the record on the queue using ``put_nowait()``; you may
2753 want to override this if you want to use blocking behaviour, or a
2754 timeout, or a customised queue implementation.
2755
2756
2757.. versionadded:: 3.2
2758
2759The :class:`QueueHandler` class was not present in previous versions.
2760
Vinay Sajip0637d492010-09-23 08:15:54 +00002761.. queue-listener:
2762
2763QueueListener
2764^^^^^^^^^^^^^
2765
2766The :class:`QueueListener` class, located in the :mod:`logging.handlers`
2767module, supports receiving logging messages from a queue, such as those
2768implemented in the :mod:`queue` or :mod:`multiprocessing` modules. The
2769messages are received from a queue in an internal thread and passed, on
2770the same thread, to one or more handlers for processing.
2771
2772Along with the :class:`QueueHandler` class, :class:`QueueListener` can be used
2773to let handlers do their work on a separate thread from the one which does the
2774logging. This is important in Web applications and also other service
2775applications where threads servicing clients need to respond as quickly as
2776possible, while any potentially slow operations (such as sending an email via
2777:class:`SMTPHandler`) are done on a separate thread.
2778
2779.. class:: QueueListener(queue, *handlers)
2780
2781 Returns a new instance of the :class:`QueueListener` class. The instance is
2782 initialized with the queue to send messages to and a list of handlers which
2783 will handle entries placed on the queue. The queue can be any queue-
2784 like object; it's passed as-is to the :meth:`dequeue` method, which needs
2785 to know how to get messages from it.
2786
2787 .. method:: dequeue(block)
2788
2789 Dequeues a record and return it, optionally blocking.
2790
2791 The base implementation uses ``get()``. You may want to override this
2792 method if you want to use timeouts or work with custom queue
2793 implementations.
2794
2795 .. method:: prepare(record)
2796
2797 Prepare a record for handling.
2798
2799 This implementation just returns the passed-in record. You may want to
2800 override this method if you need to do any custom marshalling or
2801 manipulation of the record before passing it to the handlers.
2802
2803 .. method:: handle(record)
2804
2805 Handle a record.
2806
2807 This just loops through the handlers offering them the record
2808 to handle. The actual object passed to the handlers is that which
2809 is returned from :meth:`prepare`.
2810
2811 .. method:: start()
2812
2813 Starts the listener.
2814
2815 This starts up a background thread to monitor the queue for
2816 LogRecords to process.
2817
2818 .. method:: stop()
2819
2820 Stops the listener.
2821
2822 This asks the thread to terminate, and then waits for it to do so.
2823 Note that if you don't call this before your application exits, there
2824 may be some records still left on the queue, which won't be processed.
2825
2826.. versionadded:: 3.2
2827
2828The :class:`QueueListener` class was not present in previous versions.
2829
Vinay Sajip63891ed2010-09-13 20:02:39 +00002830.. _zeromq-handlers:
2831
Vinay Sajip0637d492010-09-23 08:15:54 +00002832Subclassing QueueHandler
2833^^^^^^^^^^^^^^^^^^^^^^^^
2834
Vinay Sajip63891ed2010-09-13 20:02:39 +00002835You can use a :class:`QueueHandler` subclass to send messages to other kinds
2836of queues, for example a ZeroMQ "publish" socket. In the example below,the
2837socket is created separately and passed to the handler (as its 'queue')::
2838
2839 import zmq # using pyzmq, the Python binding for ZeroMQ
2840 import json # for serializing records portably
2841
2842 ctx = zmq.Context()
2843 sock = zmq.Socket(ctx, zmq.PUB) # or zmq.PUSH, or other suitable value
2844 sock.bind('tcp://*:5556') # or wherever
2845
2846 class ZeroMQSocketHandler(QueueHandler):
2847 def enqueue(self, record):
2848 data = json.dumps(record.__dict__)
2849 self.queue.send(data)
2850
Vinay Sajip0055c422010-09-14 09:42:39 +00002851 handler = ZeroMQSocketHandler(sock)
2852
2853
Vinay Sajip63891ed2010-09-13 20:02:39 +00002854Of course there are other ways of organizing this, for example passing in the
2855data needed by the handler to create the socket::
2856
2857 class ZeroMQSocketHandler(QueueHandler):
2858 def __init__(self, uri, socktype=zmq.PUB, ctx=None):
2859 self.ctx = ctx or zmq.Context()
2860 socket = zmq.Socket(self.ctx, socktype)
Vinay Sajip0637d492010-09-23 08:15:54 +00002861 socket.bind(uri)
Vinay Sajip0055c422010-09-14 09:42:39 +00002862 QueueHandler.__init__(self, socket)
Vinay Sajip63891ed2010-09-13 20:02:39 +00002863
2864 def enqueue(self, record):
2865 data = json.dumps(record.__dict__)
2866 self.queue.send(data)
2867
Vinay Sajipde726922010-09-14 06:59:24 +00002868 def close(self):
2869 self.queue.close()
Vinay Sajip121a1c42010-09-08 10:46:15 +00002870
Vinay Sajip0637d492010-09-23 08:15:54 +00002871Subclassing QueueListener
2872^^^^^^^^^^^^^^^^^^^^^^^^^
2873
2874You can also subclass :class:`QueueListener` to get messages from other kinds
2875of queues, for example a ZeroMQ "subscribe" socket. Here's an example::
2876
2877 class ZeroMQSocketListener(QueueListener):
2878 def __init__(self, uri, *handlers, **kwargs):
2879 self.ctx = kwargs.get('ctx') or zmq.Context()
2880 socket = zmq.Socket(self.ctx, zmq.SUB)
2881 socket.setsockopt(zmq.SUBSCRIBE, '') # subscribe to everything
2882 socket.connect(uri)
2883
2884 def dequeue(self):
2885 msg = self.queue.recv()
2886 return logging.makeLogRecord(json.loads(msg))
2887
Christian Heimes8b0facf2007-12-04 19:30:01 +00002888.. _formatter-objects:
2889
Georg Brandl116aa622007-08-15 14:28:22 +00002890Formatter Objects
2891-----------------
2892
Benjamin Peterson75edad02009-01-01 15:05:06 +00002893.. currentmodule:: logging
2894
Georg Brandl116aa622007-08-15 14:28:22 +00002895:class:`Formatter`\ s have the following attributes and methods. They are
2896responsible for converting a :class:`LogRecord` to (usually) a string which can
2897be interpreted by either a human or an external system. The base
2898:class:`Formatter` allows a formatting string to be specified. If none is
2899supplied, the default value of ``'%(message)s'`` is used.
2900
2901A Formatter can be initialized with a format string which makes use of knowledge
2902of the :class:`LogRecord` attributes - such as the default value mentioned above
2903making use of the fact that the user's message and arguments are pre-formatted
2904into a :class:`LogRecord`'s *message* attribute. This format string contains
Ezio Melotti0639d5a2009-12-19 23:26:38 +00002905standard Python %-style mapping keys. See section :ref:`old-string-formatting`
Georg Brandl116aa622007-08-15 14:28:22 +00002906for more information on string formatting.
2907
2908Currently, the useful mapping keys in a :class:`LogRecord` are:
2909
2910+-------------------------+-----------------------------------------------+
2911| Format | Description |
2912+=========================+===============================================+
2913| ``%(name)s`` | Name of the logger (logging channel). |
2914+-------------------------+-----------------------------------------------+
2915| ``%(levelno)s`` | Numeric logging level for the message |
2916| | (:const:`DEBUG`, :const:`INFO`, |
2917| | :const:`WARNING`, :const:`ERROR`, |
2918| | :const:`CRITICAL`). |
2919+-------------------------+-----------------------------------------------+
2920| ``%(levelname)s`` | Text logging level for the message |
2921| | (``'DEBUG'``, ``'INFO'``, ``'WARNING'``, |
2922| | ``'ERROR'``, ``'CRITICAL'``). |
2923+-------------------------+-----------------------------------------------+
2924| ``%(pathname)s`` | Full pathname of the source file where the |
2925| | logging call was issued (if available). |
2926+-------------------------+-----------------------------------------------+
2927| ``%(filename)s`` | Filename portion of pathname. |
2928+-------------------------+-----------------------------------------------+
2929| ``%(module)s`` | Module (name portion of filename). |
2930+-------------------------+-----------------------------------------------+
2931| ``%(funcName)s`` | Name of function containing the logging call. |
2932+-------------------------+-----------------------------------------------+
2933| ``%(lineno)d`` | Source line number where the logging call was |
2934| | issued (if available). |
2935+-------------------------+-----------------------------------------------+
2936| ``%(created)f`` | Time when the :class:`LogRecord` was created |
2937| | (as returned by :func:`time.time`). |
2938+-------------------------+-----------------------------------------------+
2939| ``%(relativeCreated)d`` | Time in milliseconds when the LogRecord was |
2940| | created, relative to the time the logging |
2941| | module was loaded. |
2942+-------------------------+-----------------------------------------------+
2943| ``%(asctime)s`` | Human-readable time when the |
2944| | :class:`LogRecord` was created. By default |
2945| | this is of the form "2003-07-08 16:49:45,896" |
2946| | (the numbers after the comma are millisecond |
2947| | portion of the time). |
2948+-------------------------+-----------------------------------------------+
2949| ``%(msecs)d`` | Millisecond portion of the time when the |
2950| | :class:`LogRecord` was created. |
2951+-------------------------+-----------------------------------------------+
2952| ``%(thread)d`` | Thread ID (if available). |
2953+-------------------------+-----------------------------------------------+
2954| ``%(threadName)s`` | Thread name (if available). |
2955+-------------------------+-----------------------------------------------+
2956| ``%(process)d`` | Process ID (if available). |
2957+-------------------------+-----------------------------------------------+
Vinay Sajip121a1c42010-09-08 10:46:15 +00002958| ``%(processName)s`` | Process name (if available). |
2959+-------------------------+-----------------------------------------------+
Georg Brandl116aa622007-08-15 14:28:22 +00002960| ``%(message)s`` | The logged message, computed as ``msg % |
2961| | args``. |
2962+-------------------------+-----------------------------------------------+
2963
Georg Brandl116aa622007-08-15 14:28:22 +00002964
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002965.. class:: Formatter(fmt=None, datefmt=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002966
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002967 Returns a new instance of the :class:`Formatter` class. The instance is
2968 initialized with a format string for the message as a whole, as well as a
2969 format string for the date/time portion of a message. If no *fmt* is
2970 specified, ``'%(message)s'`` is used. If no *datefmt* is specified, the
2971 ISO8601 date format is used.
Georg Brandl116aa622007-08-15 14:28:22 +00002972
Benjamin Petersone41251e2008-04-25 01:59:09 +00002973 .. method:: format(record)
Georg Brandl116aa622007-08-15 14:28:22 +00002974
Benjamin Petersone41251e2008-04-25 01:59:09 +00002975 The record's attribute dictionary is used as the operand to a string
2976 formatting operation. Returns the resulting string. Before formatting the
2977 dictionary, a couple of preparatory steps are carried out. The *message*
2978 attribute of the record is computed using *msg* % *args*. If the
2979 formatting string contains ``'(asctime)'``, :meth:`formatTime` is called
2980 to format the event time. If there is exception information, it is
2981 formatted using :meth:`formatException` and appended to the message. Note
2982 that the formatted exception information is cached in attribute
2983 *exc_text*. This is useful because the exception information can be
2984 pickled and sent across the wire, but you should be careful if you have
2985 more than one :class:`Formatter` subclass which customizes the formatting
2986 of exception information. In this case, you will have to clear the cached
2987 value after a formatter has done its formatting, so that the next
2988 formatter to handle the event doesn't use the cached value but
2989 recalculates it afresh.
Georg Brandl116aa622007-08-15 14:28:22 +00002990
2991
Georg Brandlcd7f32b2009-06-08 09:13:45 +00002992 .. method:: formatTime(record, datefmt=None)
Georg Brandl116aa622007-08-15 14:28:22 +00002993
Benjamin Petersone41251e2008-04-25 01:59:09 +00002994 This method should be called from :meth:`format` by a formatter which
2995 wants to make use of a formatted time. This method can be overridden in
2996 formatters to provide for any specific requirement, but the basic behavior
2997 is as follows: if *datefmt* (a string) is specified, it is used with
2998 :func:`time.strftime` to format the creation time of the
2999 record. Otherwise, the ISO8601 format is used. The resulting string is
3000 returned.
Georg Brandl116aa622007-08-15 14:28:22 +00003001
3002
Benjamin Petersone41251e2008-04-25 01:59:09 +00003003 .. method:: formatException(exc_info)
Georg Brandl116aa622007-08-15 14:28:22 +00003004
Benjamin Petersone41251e2008-04-25 01:59:09 +00003005 Formats the specified exception information (a standard exception tuple as
3006 returned by :func:`sys.exc_info`) as a string. This default implementation
3007 just uses :func:`traceback.print_exception`. The resulting string is
3008 returned.
Georg Brandl116aa622007-08-15 14:28:22 +00003009
Vinay Sajipd31f3632010-06-29 15:31:15 +00003010.. _filter:
Georg Brandl116aa622007-08-15 14:28:22 +00003011
3012Filter Objects
3013--------------
3014
3015:class:`Filter`\ s can be used by :class:`Handler`\ s and :class:`Logger`\ s for
3016more sophisticated filtering than is provided by levels. The base filter class
3017only allows events which are below a certain point in the logger hierarchy. For
3018example, a filter initialized with "A.B" will allow events logged by loggers
3019"A.B", "A.B.C", "A.B.C.D", "A.B.D" etc. but not "A.BB", "B.A.B" etc. If
3020initialized with the empty string, all events are passed.
3021
3022
Georg Brandlcd7f32b2009-06-08 09:13:45 +00003023.. class:: Filter(name='')
Georg Brandl116aa622007-08-15 14:28:22 +00003024
3025 Returns an instance of the :class:`Filter` class. If *name* is specified, it
3026 names a logger which, together with its children, will have its events allowed
Georg Brandlcd7f32b2009-06-08 09:13:45 +00003027 through the filter. If *name* is the empty string, allows every event.
Georg Brandl116aa622007-08-15 14:28:22 +00003028
3029
Benjamin Petersone41251e2008-04-25 01:59:09 +00003030 .. method:: filter(record)
Georg Brandl116aa622007-08-15 14:28:22 +00003031
Benjamin Petersone41251e2008-04-25 01:59:09 +00003032 Is the specified record to be logged? Returns zero for no, nonzero for
3033 yes. If deemed appropriate, the record may be modified in-place by this
3034 method.
Georg Brandl116aa622007-08-15 14:28:22 +00003035
Vinay Sajip81010212010-08-19 19:17:41 +00003036Note that filters attached to handlers are consulted whenever an event is
3037emitted by the handler, whereas filters attached to loggers are consulted
3038whenever an event is logged to the handler (using :meth:`debug`, :meth:`info`,
3039etc.) This means that events which have been generated by descendant loggers
3040will not be filtered by a logger's filter setting, unless the filter has also
3041been applied to those descendant loggers.
3042
Vinay Sajipac007992010-09-17 12:45:26 +00003043Other uses for filters
3044^^^^^^^^^^^^^^^^^^^^^^
3045
3046Although filters are used primarily to filter records based on more
3047sophisticated criteria than levels, they get to see every record which is
3048processed by the handler or logger they're attached to: this can be useful if
3049you want to do things like counting how many records were processed by a
3050particular logger or handler, or adding, changing or removing attributes in
3051the LogRecord being processed. Obviously changing the LogRecord needs to be
3052done with some care, but it does allow the injection of contextual information
3053into logs (see :ref:`filters-contextual`).
3054
Vinay Sajipd31f3632010-06-29 15:31:15 +00003055.. _log-record:
Georg Brandl116aa622007-08-15 14:28:22 +00003056
3057LogRecord Objects
3058-----------------
3059
Vinay Sajip4039aff2010-09-11 10:25:28 +00003060:class:`LogRecord` instances are created automatically by the :class:`Logger`
3061every time something is logged, and can be created manually via
3062:func:`makeLogRecord` (for example, from a pickled event received over the
3063wire).
Georg Brandl116aa622007-08-15 14:28:22 +00003064
3065
Georg Brandlcd7f32b2009-06-08 09:13:45 +00003066.. class:: LogRecord(name, lvl, pathname, lineno, msg, args, exc_info, func=None)
Georg Brandl116aa622007-08-15 14:28:22 +00003067
Vinay Sajip4039aff2010-09-11 10:25:28 +00003068 Contains all the information pertinent to the event being logged.
Georg Brandl116aa622007-08-15 14:28:22 +00003069
Vinay Sajip4039aff2010-09-11 10:25:28 +00003070 The primary information is passed in :attr:`msg` and :attr:`args`, which
3071 are combined using ``msg % args`` to create the :attr:`message` field of the
3072 record.
3073
3074 .. attribute:: args
3075
3076 Tuple of arguments to be used in formatting :attr:`msg`.
3077
3078 .. attribute:: exc_info
3079
3080 Exception tuple (à la `sys.exc_info`) or `None` if no exception
Georg Brandl6faee4e2010-09-21 14:48:28 +00003081 information is available.
Vinay Sajip4039aff2010-09-11 10:25:28 +00003082
3083 .. attribute:: func
3084
3085 Name of the function of origin (i.e. in which the logging call was made).
3086
3087 .. attribute:: lineno
3088
3089 Line number in the source file of origin.
3090
3091 .. attribute:: lvl
3092
3093 Numeric logging level.
3094
3095 .. attribute:: message
3096
3097 Bound to the result of :meth:`getMessage` when
3098 :meth:`Formatter.format(record)<Formatter.format>` is invoked.
3099
3100 .. attribute:: msg
3101
3102 User-supplied :ref:`format string<string-formatting>` or arbitrary object
3103 (see :ref:`arbitrary-object-messages`) used in :meth:`getMessage`.
3104
3105 .. attribute:: name
3106
3107 Name of the logger that emitted the record.
3108
3109 .. attribute:: pathname
3110
3111 Absolute pathname of the source file of origin.
Georg Brandl116aa622007-08-15 14:28:22 +00003112
Benjamin Petersone41251e2008-04-25 01:59:09 +00003113 .. method:: getMessage()
Georg Brandl116aa622007-08-15 14:28:22 +00003114
Benjamin Petersone41251e2008-04-25 01:59:09 +00003115 Returns the message for this :class:`LogRecord` instance after merging any
Vinay Sajip4039aff2010-09-11 10:25:28 +00003116 user-supplied arguments with the message. If the user-supplied message
3117 argument to the logging call is not a string, :func:`str` is called on it to
3118 convert it to a string. This allows use of user-defined classes as
3119 messages, whose ``__str__`` method can return the actual format string to
3120 be used.
3121
3122 .. versionchanged:: 2.5
3123 *func* was added.
Benjamin Petersone41251e2008-04-25 01:59:09 +00003124
Vinay Sajipd31f3632010-06-29 15:31:15 +00003125.. _logger-adapter:
Georg Brandl116aa622007-08-15 14:28:22 +00003126
Christian Heimes04c420f2008-01-18 18:40:46 +00003127LoggerAdapter Objects
3128---------------------
3129
Christian Heimes04c420f2008-01-18 18:40:46 +00003130:class:`LoggerAdapter` instances are used to conveniently pass contextual
Georg Brandl86def6c2008-01-21 20:36:10 +00003131information into logging calls. For a usage example , see the section on
3132`adding contextual information to your logging output`__.
3133
3134__ context-info_
Christian Heimes04c420f2008-01-18 18:40:46 +00003135
3136.. class:: LoggerAdapter(logger, extra)
3137
3138 Returns an instance of :class:`LoggerAdapter` initialized with an
3139 underlying :class:`Logger` instance and a dict-like object.
3140
Benjamin Petersone41251e2008-04-25 01:59:09 +00003141 .. method:: process(msg, kwargs)
Christian Heimes04c420f2008-01-18 18:40:46 +00003142
Benjamin Petersone41251e2008-04-25 01:59:09 +00003143 Modifies the message and/or keyword arguments passed to a logging call in
3144 order to insert contextual information. This implementation takes the object
3145 passed as *extra* to the constructor and adds it to *kwargs* using key
3146 'extra'. The return value is a (*msg*, *kwargs*) tuple which has the
3147 (possibly modified) versions of the arguments passed in.
Christian Heimes04c420f2008-01-18 18:40:46 +00003148
Vinay Sajipc84f0162010-09-21 11:25:39 +00003149In addition to the above, :class:`LoggerAdapter` supports the following
Christian Heimes04c420f2008-01-18 18:40:46 +00003150methods of :class:`Logger`, i.e. :meth:`debug`, :meth:`info`, :meth:`warning`,
Vinay Sajipc84f0162010-09-21 11:25:39 +00003151:meth:`error`, :meth:`exception`, :meth:`critical`, :meth:`log`,
3152:meth:`isEnabledFor`, :meth:`getEffectiveLevel`, :meth:`setLevel`,
3153:meth:`hasHandlers`. These methods have the same signatures as their
3154counterparts in :class:`Logger`, so you can use the two types of instances
3155interchangeably.
Christian Heimes04c420f2008-01-18 18:40:46 +00003156
Ezio Melotti4d5195b2010-04-20 10:57:44 +00003157.. versionchanged:: 3.2
Vinay Sajipc84f0162010-09-21 11:25:39 +00003158 The :meth:`isEnabledFor`, :meth:`getEffectiveLevel`, :meth:`setLevel` and
3159 :meth:`hasHandlers` methods were added to :class:`LoggerAdapter`. These
3160 methods delegate to the underlying logger.
Benjamin Peterson22005fc2010-04-11 16:25:06 +00003161
Georg Brandl116aa622007-08-15 14:28:22 +00003162
3163Thread Safety
3164-------------
3165
3166The logging module is intended to be thread-safe without any special work
3167needing to be done by its clients. It achieves this though using threading
3168locks; there is one lock to serialize access to the module's shared data, and
3169each handler also creates a lock to serialize access to its underlying I/O.
3170
Benjamin Petersond23f8222009-04-05 19:13:16 +00003171If you are implementing asynchronous signal handlers using the :mod:`signal`
3172module, you may not be able to use logging from within such handlers. This is
3173because lock implementations in the :mod:`threading` module are not always
3174re-entrant, and so cannot be invoked from such signal handlers.
Georg Brandl116aa622007-08-15 14:28:22 +00003175
Benjamin Peterson9451a1c2010-03-13 22:30:34 +00003176
3177Integration with the warnings module
3178------------------------------------
3179
3180The :func:`captureWarnings` function can be used to integrate :mod:`logging`
3181with the :mod:`warnings` module.
3182
3183.. function:: captureWarnings(capture)
3184
3185 This function is used to turn the capture of warnings by logging on and
3186 off.
3187
3188 If `capture` is `True`, warnings issued by the :mod:`warnings` module
3189 will be redirected to the logging system. Specifically, a warning will be
3190 formatted using :func:`warnings.formatwarning` and the resulting string
3191 logged to a logger named "py.warnings" with a severity of `WARNING`.
3192
3193 If `capture` is `False`, the redirection of warnings to the logging system
3194 will stop, and warnings will be redirected to their original destinations
3195 (i.e. those in effect before `captureWarnings(True)` was called).
3196
3197
Georg Brandl116aa622007-08-15 14:28:22 +00003198Configuration
3199-------------
3200
3201
3202.. _logging-config-api:
3203
3204Configuration functions
3205^^^^^^^^^^^^^^^^^^^^^^^
3206
Georg Brandl116aa622007-08-15 14:28:22 +00003207The following functions configure the logging module. They are located in the
3208:mod:`logging.config` module. Their use is optional --- you can configure the
3209logging module using these functions or by making calls to the main API (defined
3210in :mod:`logging` itself) and defining handlers which are declared either in
3211:mod:`logging` or :mod:`logging.handlers`.
3212
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003213.. function:: dictConfig(config)
Georg Brandl116aa622007-08-15 14:28:22 +00003214
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003215 Takes the logging configuration from a dictionary. The contents of
3216 this dictionary are described in :ref:`logging-config-dictschema`
3217 below.
3218
3219 If an error is encountered during configuration, this function will
3220 raise a :exc:`ValueError`, :exc:`TypeError`, :exc:`AttributeError`
3221 or :exc:`ImportError` with a suitably descriptive message. The
3222 following is a (possibly incomplete) list of conditions which will
3223 raise an error:
3224
3225 * A ``level`` which is not a string or which is a string not
3226 corresponding to an actual logging level.
3227 * A ``propagate`` value which is not a boolean.
3228 * An id which does not have a corresponding destination.
3229 * A non-existent handler id found during an incremental call.
3230 * An invalid logger name.
3231 * Inability to resolve to an internal or external object.
3232
3233 Parsing is performed by the :class:`DictConfigurator` class, whose
3234 constructor is passed the dictionary used for configuration, and
3235 has a :meth:`configure` method. The :mod:`logging.config` module
3236 has a callable attribute :attr:`dictConfigClass`
3237 which is initially set to :class:`DictConfigurator`.
3238 You can replace the value of :attr:`dictConfigClass` with a
3239 suitable implementation of your own.
3240
3241 :func:`dictConfig` calls :attr:`dictConfigClass` passing
3242 the specified dictionary, and then calls the :meth:`configure` method on
3243 the returned object to put the configuration into effect::
3244
3245 def dictConfig(config):
3246 dictConfigClass(config).configure()
3247
3248 For example, a subclass of :class:`DictConfigurator` could call
3249 ``DictConfigurator.__init__()`` in its own :meth:`__init__()`, then
3250 set up custom prefixes which would be usable in the subsequent
3251 :meth:`configure` call. :attr:`dictConfigClass` would be bound to
3252 this new subclass, and then :func:`dictConfig` could be called exactly as
3253 in the default, uncustomized state.
3254
3255.. function:: fileConfig(fname[, defaults])
Georg Brandl116aa622007-08-15 14:28:22 +00003256
Alexandre Vassalotti1d1eaa42008-05-14 22:59:42 +00003257 Reads the logging configuration from a :mod:`configparser`\-format file named
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00003258 *fname*. This function can be called several times from an application,
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003259 allowing an end user to select from various pre-canned
Alexandre Vassalotti1d1eaa42008-05-14 22:59:42 +00003260 configurations (if the developer provides a mechanism to present the choices
3261 and load the chosen configuration). Defaults to be passed to the ConfigParser
3262 can be specified in the *defaults* argument.
Georg Brandl116aa622007-08-15 14:28:22 +00003263
Georg Brandlcd7f32b2009-06-08 09:13:45 +00003264
3265.. function:: listen(port=DEFAULT_LOGGING_CONFIG_PORT)
Georg Brandl116aa622007-08-15 14:28:22 +00003266
3267 Starts up a socket server on the specified port, and listens for new
3268 configurations. If no port is specified, the module's default
3269 :const:`DEFAULT_LOGGING_CONFIG_PORT` is used. Logging configurations will be
3270 sent as a file suitable for processing by :func:`fileConfig`. Returns a
3271 :class:`Thread` instance on which you can call :meth:`start` to start the
3272 server, and which you can :meth:`join` when appropriate. To stop the server,
Christian Heimes8b0facf2007-12-04 19:30:01 +00003273 call :func:`stopListening`.
3274
3275 To send a configuration to the socket, read in the configuration file and
3276 send it to the socket as a string of bytes preceded by a four-byte length
3277 string packed in binary using ``struct.pack('>L', n)``.
Georg Brandl116aa622007-08-15 14:28:22 +00003278
3279
3280.. function:: stopListening()
3281
Christian Heimes8b0facf2007-12-04 19:30:01 +00003282 Stops the listening server which was created with a call to :func:`listen`.
3283 This is typically called before calling :meth:`join` on the return value from
Georg Brandl116aa622007-08-15 14:28:22 +00003284 :func:`listen`.
3285
3286
Benjamin Petersond7c3ed52010-06-27 22:32:30 +00003287.. _logging-config-dictschema:
3288
3289Configuration dictionary schema
3290^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3291
3292Describing a logging configuration requires listing the various
3293objects to create and the connections between them; for example, you
3294may create a handler named "console" and then say that the logger
3295named "startup" will send its messages to the "console" handler.
3296These objects aren't limited to those provided by the :mod:`logging`
3297module because you might write your own formatter or handler class.
3298The parameters to these classes may also need to include external
3299objects such as ``sys.stderr``. The syntax for describing these
3300objects and connections is defined in :ref:`logging-config-dict-connections`
3301below.
3302
3303Dictionary Schema Details
3304"""""""""""""""""""""""""
3305
3306The dictionary passed to :func:`dictConfig` must contain the following
3307keys:
3308
3309* `version` - to be set to an integer value representing the schema
3310 version. The only valid value at present is 1, but having this key
3311 allows the schema to evolve while still preserving backwards
3312 compatibility.
3313
3314All other keys are optional, but if present they will be interpreted
3315as described below. In all cases below where a 'configuring dict' is
3316mentioned, it will be checked for the special ``'()'`` key to see if a
3317custom instantiation is required. If so, the mechanism described in
3318:ref:`logging-config-dict-userdef` below is used to create an instance;
3319otherwise, the context is used to determine what to instantiate.
3320
3321* `formatters` - the corresponding value will be a dict in which each
3322 key is a formatter id and each value is a dict describing how to
3323 configure the corresponding Formatter instance.
3324
3325 The configuring dict is searched for keys ``format`` and ``datefmt``
3326 (with defaults of ``None``) and these are used to construct a
3327 :class:`logging.Formatter` instance.
3328
3329* `filters` - the corresponding value will be a dict in which each key
3330 is a filter id and each value is a dict describing how to configure
3331 the corresponding Filter instance.
3332
3333 The configuring dict is searched for the key ``name`` (defaulting to the
3334 empty string) and this is used to construct a :class:`logging.Filter`
3335 instance.
3336
3337* `handlers` - the corresponding value will be a dict in which each
3338 key is a handler id and each value is a dict describing how to
3339 configure the corresponding Handler instance.
3340
3341 The configuring dict is searched for the following keys:
3342
3343 * ``class`` (mandatory). This is the fully qualified name of the
3344 handler class.
3345
3346 * ``level`` (optional). The level of the handler.
3347
3348 * ``formatter`` (optional). The id of the formatter for this
3349 handler.
3350
3351 * ``filters`` (optional). A list of ids of the filters for this
3352 handler.
3353
3354 All *other* keys are passed through as keyword arguments to the
3355 handler's constructor. For example, given the snippet::
3356
3357 handlers:
3358 console:
3359 class : logging.StreamHandler
3360 formatter: brief
3361 level : INFO
3362 filters: [allow_foo]
3363 stream : ext://sys.stdout
3364 file:
3365 class : logging.handlers.RotatingFileHandler
3366 formatter: precise
3367 filename: logconfig.log
3368 maxBytes: 1024
3369 backupCount: 3
3370
3371 the handler with id ``console`` is instantiated as a
3372 :class:`logging.StreamHandler`, using ``sys.stdout`` as the underlying
3373 stream. The handler with id ``file`` is instantiated as a
3374 :class:`logging.handlers.RotatingFileHandler` with the keyword arguments
3375 ``filename='logconfig.log', maxBytes=1024, backupCount=3``.
3376
3377* `loggers` - the corresponding value will be a dict in which each key
3378 is a logger name and each value is a dict describing how to
3379 configure the corresponding Logger instance.
3380
3381 The configuring dict is searched for the following keys:
3382
3383 * ``level`` (optional). The level of the logger.
3384
3385 * ``propagate`` (optional). The propagation setting of the logger.
3386
3387 * ``filters`` (optional). A list of ids of the filters for this
3388 logger.
3389
3390 * ``handlers`` (optional). A list of ids of the handlers for this
3391 logger.
3392
3393 The specified loggers will be configured according to the level,
3394 propagation, filters and handlers specified.
3395
3396* `root` - this will be the configuration for the root logger.
3397 Processing of the configuration will be as for any logger, except
3398 that the ``propagate`` setting will not be applicable.
3399
3400* `incremental` - whether the configuration is to be interpreted as
3401 incremental to the existing configuration. This value defaults to
3402 ``False``, which means that the specified configuration replaces the
3403 existing configuration with the same semantics as used by the
3404 existing :func:`fileConfig` API.
3405
3406 If the specified value is ``True``, the configuration is processed
3407 as described in the section on :ref:`logging-config-dict-incremental`.
3408
3409* `disable_existing_loggers` - whether any existing loggers are to be
3410 disabled. This setting mirrors the parameter of the same name in
3411 :func:`fileConfig`. If absent, this parameter defaults to ``True``.
3412 This value is ignored if `incremental` is ``True``.
3413
3414.. _logging-config-dict-incremental:
3415
3416Incremental Configuration
3417"""""""""""""""""""""""""
3418
3419It is difficult to provide complete flexibility for incremental
3420configuration. For example, because objects such as filters
3421and formatters are anonymous, once a configuration is set up, it is
3422not possible to refer to such anonymous objects when augmenting a
3423configuration.
3424
3425Furthermore, there is not a compelling case for arbitrarily altering
3426the object graph of loggers, handlers, filters, formatters at
3427run-time, once a configuration is set up; the verbosity of loggers and
3428handlers can be controlled just by setting levels (and, in the case of
3429loggers, propagation flags). Changing the object graph arbitrarily in
3430a safe way is problematic in a multi-threaded environment; while not
3431impossible, the benefits are not worth the complexity it adds to the
3432implementation.
3433
3434Thus, when the ``incremental`` key of a configuration dict is present
3435and is ``True``, the system will completely ignore any ``formatters`` and
3436``filters`` entries, and process only the ``level``
3437settings in the ``handlers`` entries, and the ``level`` and
3438``propagate`` settings in the ``loggers`` and ``root`` entries.
3439
3440Using a value in the configuration dict lets configurations to be sent
3441over the wire as pickled dicts to a socket listener. Thus, the logging
3442verbosity of a long-running application can be altered over time with
3443no need to stop and restart the application.
3444
3445.. _logging-config-dict-connections:
3446
3447Object connections
3448""""""""""""""""""
3449
3450The schema describes a set of logging objects - loggers,
3451handlers, formatters, filters - which are connected to each other in
3452an object graph. Thus, the schema needs to represent connections
3453between the objects. For example, say that, once configured, a
3454particular logger has attached to it a particular handler. For the
3455purposes of this discussion, we can say that the logger represents the
3456source, and the handler the destination, of a connection between the
3457two. Of course in the configured objects this is represented by the
3458logger holding a reference to the handler. In the configuration dict,
3459this is done by giving each destination object an id which identifies
3460it unambiguously, and then using the id in the source object's
3461configuration to indicate that a connection exists between the source
3462and the destination object with that id.
3463
3464So, for example, consider the following YAML snippet::
3465
3466 formatters:
3467 brief:
3468 # configuration for formatter with id 'brief' goes here
3469 precise:
3470 # configuration for formatter with id 'precise' goes here
3471 handlers:
3472 h1: #This is an id
3473 # configuration of handler with id 'h1' goes here
3474 formatter: brief
3475 h2: #This is another id
3476 # configuration of handler with id 'h2' goes here
3477 formatter: precise
3478 loggers:
3479 foo.bar.baz:
3480 # other configuration for logger 'foo.bar.baz'
3481 handlers: [h1, h2]
3482
3483(Note: YAML used here because it's a little more readable than the
3484equivalent Python source form for the dictionary.)
3485
3486The ids for loggers are the logger names which would be used
3487programmatically to obtain a reference to those loggers, e.g.
3488``foo.bar.baz``. The ids for Formatters and Filters can be any string
3489value (such as ``brief``, ``precise`` above) and they are transient,
3490in that they are only meaningful for processing the configuration
3491dictionary and used to determine connections between objects, and are
3492not persisted anywhere when the configuration call is complete.
3493
3494The above snippet indicates that logger named ``foo.bar.baz`` should
3495have two handlers attached to it, which are described by the handler
3496ids ``h1`` and ``h2``. The formatter for ``h1`` is that described by id
3497``brief``, and the formatter for ``h2`` is that described by id
3498``precise``.
3499
3500
3501.. _logging-config-dict-userdef:
3502
3503User-defined objects
3504""""""""""""""""""""
3505
3506The schema supports user-defined objects for handlers, filters and
3507formatters. (Loggers do not need to have different types for
3508different instances, so there is no support in this configuration
3509schema for user-defined logger classes.)
3510
3511Objects to be configured are described by dictionaries
3512which detail their configuration. In some places, the logging system
3513will be able to infer from the context how an object is to be
3514instantiated, but when a user-defined object is to be instantiated,
3515the system will not know how to do this. In order to provide complete
3516flexibility for user-defined object instantiation, the user needs
3517to provide a 'factory' - a callable which is called with a
3518configuration dictionary and which returns the instantiated object.
3519This is signalled by an absolute import path to the factory being
3520made available under the special key ``'()'``. Here's a concrete
3521example::
3522
3523 formatters:
3524 brief:
3525 format: '%(message)s'
3526 default:
3527 format: '%(asctime)s %(levelname)-8s %(name)-15s %(message)s'
3528 datefmt: '%Y-%m-%d %H:%M:%S'
3529 custom:
3530 (): my.package.customFormatterFactory
3531 bar: baz
3532 spam: 99.9
3533 answer: 42
3534
3535The above YAML snippet defines three formatters. The first, with id
3536``brief``, is a standard :class:`logging.Formatter` instance with the
3537specified format string. The second, with id ``default``, has a
3538longer format and also defines the time format explicitly, and will
3539result in a :class:`logging.Formatter` initialized with those two format
3540strings. Shown in Python source form, the ``brief`` and ``default``
3541formatters have configuration sub-dictionaries::
3542
3543 {
3544 'format' : '%(message)s'
3545 }
3546
3547and::
3548
3549 {
3550 'format' : '%(asctime)s %(levelname)-8s %(name)-15s %(message)s',
3551 'datefmt' : '%Y-%m-%d %H:%M:%S'
3552 }
3553
3554respectively, and as these dictionaries do not contain the special key
3555``'()'``, the instantiation is inferred from the context: as a result,
3556standard :class:`logging.Formatter` instances are created. The
3557configuration sub-dictionary for the third formatter, with id
3558``custom``, is::
3559
3560 {
3561 '()' : 'my.package.customFormatterFactory',
3562 'bar' : 'baz',
3563 'spam' : 99.9,
3564 'answer' : 42
3565 }
3566
3567and this contains the special key ``'()'``, which means that
3568user-defined instantiation is wanted. In this case, the specified
3569factory callable will be used. If it is an actual callable it will be
3570used directly - otherwise, if you specify a string (as in the example)
3571the actual callable will be located using normal import mechanisms.
3572The callable will be called with the **remaining** items in the
3573configuration sub-dictionary as keyword arguments. In the above
3574example, the formatter with id ``custom`` will be assumed to be
3575returned by the call::
3576
3577 my.package.customFormatterFactory(bar='baz', spam=99.9, answer=42)
3578
3579The key ``'()'`` has been used as the special key because it is not a
3580valid keyword parameter name, and so will not clash with the names of
3581the keyword arguments used in the call. The ``'()'`` also serves as a
3582mnemonic that the corresponding value is a callable.
3583
3584
3585.. _logging-config-dict-externalobj:
3586
3587Access to external objects
3588""""""""""""""""""""""""""
3589
3590There are times where a configuration needs to refer to objects
3591external to the configuration, for example ``sys.stderr``. If the
3592configuration dict is constructed using Python code, this is
3593straightforward, but a problem arises when the configuration is
3594provided via a text file (e.g. JSON, YAML). In a text file, there is
3595no standard way to distinguish ``sys.stderr`` from the literal string
3596``'sys.stderr'``. To facilitate this distinction, the configuration
3597system looks for certain special prefixes in string values and
3598treat them specially. For example, if the literal string
3599``'ext://sys.stderr'`` is provided as a value in the configuration,
3600then the ``ext://`` will be stripped off and the remainder of the
3601value processed using normal import mechanisms.
3602
3603The handling of such prefixes is done in a way analogous to protocol
3604handling: there is a generic mechanism to look for prefixes which
3605match the regular expression ``^(?P<prefix>[a-z]+)://(?P<suffix>.*)$``
3606whereby, if the ``prefix`` is recognised, the ``suffix`` is processed
3607in a prefix-dependent manner and the result of the processing replaces
3608the string value. If the prefix is not recognised, then the string
3609value will be left as-is.
3610
3611
3612.. _logging-config-dict-internalobj:
3613
3614Access to internal objects
3615""""""""""""""""""""""""""
3616
3617As well as external objects, there is sometimes also a need to refer
3618to objects in the configuration. This will be done implicitly by the
3619configuration system for things that it knows about. For example, the
3620string value ``'DEBUG'`` for a ``level`` in a logger or handler will
3621automatically be converted to the value ``logging.DEBUG``, and the
3622``handlers``, ``filters`` and ``formatter`` entries will take an
3623object id and resolve to the appropriate destination object.
3624
3625However, a more generic mechanism is needed for user-defined
3626objects which are not known to the :mod:`logging` module. For
3627example, consider :class:`logging.handlers.MemoryHandler`, which takes
3628a ``target`` argument which is another handler to delegate to. Since
3629the system already knows about this class, then in the configuration,
3630the given ``target`` just needs to be the object id of the relevant
3631target handler, and the system will resolve to the handler from the
3632id. If, however, a user defines a ``my.package.MyHandler`` which has
3633an ``alternate`` handler, the configuration system would not know that
3634the ``alternate`` referred to a handler. To cater for this, a generic
3635resolution system allows the user to specify::
3636
3637 handlers:
3638 file:
3639 # configuration of file handler goes here
3640
3641 custom:
3642 (): my.package.MyHandler
3643 alternate: cfg://handlers.file
3644
3645The literal string ``'cfg://handlers.file'`` will be resolved in an
3646analogous way to strings with the ``ext://`` prefix, but looking
3647in the configuration itself rather than the import namespace. The
3648mechanism allows access by dot or by index, in a similar way to
3649that provided by ``str.format``. Thus, given the following snippet::
3650
3651 handlers:
3652 email:
3653 class: logging.handlers.SMTPHandler
3654 mailhost: localhost
3655 fromaddr: my_app@domain.tld
3656 toaddrs:
3657 - support_team@domain.tld
3658 - dev_team@domain.tld
3659 subject: Houston, we have a problem.
3660
3661in the configuration, the string ``'cfg://handlers'`` would resolve to
3662the dict with key ``handlers``, the string ``'cfg://handlers.email``
3663would resolve to the dict with key ``email`` in the ``handlers`` dict,
3664and so on. The string ``'cfg://handlers.email.toaddrs[1]`` would
3665resolve to ``'dev_team.domain.tld'`` and the string
3666``'cfg://handlers.email.toaddrs[0]'`` would resolve to the value
3667``'support_team@domain.tld'``. The ``subject`` value could be accessed
3668using either ``'cfg://handlers.email.subject'`` or, equivalently,
3669``'cfg://handlers.email[subject]'``. The latter form only needs to be
3670used if the key contains spaces or non-alphanumeric characters. If an
3671index value consists only of decimal digits, access will be attempted
3672using the corresponding integer value, falling back to the string
3673value if needed.
3674
3675Given a string ``cfg://handlers.myhandler.mykey.123``, this will
3676resolve to ``config_dict['handlers']['myhandler']['mykey']['123']``.
3677If the string is specified as ``cfg://handlers.myhandler.mykey[123]``,
3678the system will attempt to retrieve the value from
3679``config_dict['handlers']['myhandler']['mykey'][123]``, and fall back
3680to ``config_dict['handlers']['myhandler']['mykey']['123']`` if that
3681fails.
3682
Georg Brandl116aa622007-08-15 14:28:22 +00003683.. _logging-config-fileformat:
3684
3685Configuration file format
3686^^^^^^^^^^^^^^^^^^^^^^^^^
3687
Benjamin Peterson960cf0f2009-01-09 04:11:44 +00003688The configuration file format understood by :func:`fileConfig` is based on
3689:mod:`configparser` functionality. The file must contain sections called
3690``[loggers]``, ``[handlers]`` and ``[formatters]`` which identify by name the
3691entities of each type which are defined in the file. For each such entity, there
3692is a separate section which identifies how that entity is configured. Thus, for
3693a logger named ``log01`` in the ``[loggers]`` section, the relevant
3694configuration details are held in a section ``[logger_log01]``. Similarly, a
3695handler called ``hand01`` in the ``[handlers]`` section will have its
3696configuration held in a section called ``[handler_hand01]``, while a formatter
3697called ``form01`` in the ``[formatters]`` section will have its configuration
3698specified in a section called ``[formatter_form01]``. The root logger
3699configuration must be specified in a section called ``[logger_root]``.
Georg Brandl116aa622007-08-15 14:28:22 +00003700
3701Examples of these sections in the file are given below. ::
3702
3703 [loggers]
3704 keys=root,log02,log03,log04,log05,log06,log07
3705
3706 [handlers]
3707 keys=hand01,hand02,hand03,hand04,hand05,hand06,hand07,hand08,hand09
3708
3709 [formatters]
3710 keys=form01,form02,form03,form04,form05,form06,form07,form08,form09
3711
3712The root logger must specify a level and a list of handlers. An example of a
3713root logger section is given below. ::
3714
3715 [logger_root]
3716 level=NOTSET
3717 handlers=hand01
3718
3719The ``level`` entry can be one of ``DEBUG, INFO, WARNING, ERROR, CRITICAL`` or
3720``NOTSET``. For the root logger only, ``NOTSET`` means that all messages will be
3721logged. Level values are :func:`eval`\ uated in the context of the ``logging``
3722package's namespace.
3723
3724The ``handlers`` entry is a comma-separated list of handler names, which must
3725appear in the ``[handlers]`` section. These names must appear in the
3726``[handlers]`` section and have corresponding sections in the configuration
3727file.
3728
3729For loggers other than the root logger, some additional information is required.
3730This is illustrated by the following example. ::
3731
3732 [logger_parser]
3733 level=DEBUG
3734 handlers=hand01
3735 propagate=1
3736 qualname=compiler.parser
3737
3738The ``level`` and ``handlers`` entries are interpreted as for the root logger,
3739except that if a non-root logger's level is specified as ``NOTSET``, the system
3740consults loggers higher up the hierarchy to determine the effective level of the
3741logger. The ``propagate`` entry is set to 1 to indicate that messages must
3742propagate to handlers higher up the logger hierarchy from this logger, or 0 to
3743indicate that messages are **not** propagated to handlers up the hierarchy. The
3744``qualname`` entry is the hierarchical channel name of the logger, that is to
3745say the name used by the application to get the logger.
3746
3747Sections which specify handler configuration are exemplified by the following.
3748::
3749
3750 [handler_hand01]
3751 class=StreamHandler
3752 level=NOTSET
3753 formatter=form01
3754 args=(sys.stdout,)
3755
3756The ``class`` entry indicates the handler's class (as determined by :func:`eval`
3757in the ``logging`` package's namespace). The ``level`` is interpreted as for
3758loggers, and ``NOTSET`` is taken to mean "log everything".
3759
3760The ``formatter`` entry indicates the key name of the formatter for this
3761handler. If blank, a default formatter (``logging._defaultFormatter``) is used.
3762If a name is specified, it must appear in the ``[formatters]`` section and have
3763a corresponding section in the configuration file.
3764
3765The ``args`` entry, when :func:`eval`\ uated in the context of the ``logging``
3766package's namespace, is the list of arguments to the constructor for the handler
3767class. Refer to the constructors for the relevant handlers, or to the examples
3768below, to see how typical entries are constructed. ::
3769
3770 [handler_hand02]
3771 class=FileHandler
3772 level=DEBUG
3773 formatter=form02
3774 args=('python.log', 'w')
3775
3776 [handler_hand03]
3777 class=handlers.SocketHandler
3778 level=INFO
3779 formatter=form03
3780 args=('localhost', handlers.DEFAULT_TCP_LOGGING_PORT)
3781
3782 [handler_hand04]
3783 class=handlers.DatagramHandler
3784 level=WARN
3785 formatter=form04
3786 args=('localhost', handlers.DEFAULT_UDP_LOGGING_PORT)
3787
3788 [handler_hand05]
3789 class=handlers.SysLogHandler
3790 level=ERROR
3791 formatter=form05
3792 args=(('localhost', handlers.SYSLOG_UDP_PORT), handlers.SysLogHandler.LOG_USER)
3793
3794 [handler_hand06]
3795 class=handlers.NTEventLogHandler
3796 level=CRITICAL
3797 formatter=form06
3798 args=('Python Application', '', 'Application')
3799
3800 [handler_hand07]
3801 class=handlers.SMTPHandler
3802 level=WARN
3803 formatter=form07
3804 args=('localhost', 'from@abc', ['user1@abc', 'user2@xyz'], 'Logger Subject')
3805
3806 [handler_hand08]
3807 class=handlers.MemoryHandler
3808 level=NOTSET
3809 formatter=form08
3810 target=
3811 args=(10, ERROR)
3812
3813 [handler_hand09]
3814 class=handlers.HTTPHandler
3815 level=NOTSET
3816 formatter=form09
3817 args=('localhost:9022', '/log', 'GET')
3818
3819Sections which specify formatter configuration are typified by the following. ::
3820
3821 [formatter_form01]
3822 format=F1 %(asctime)s %(levelname)s %(message)s
3823 datefmt=
3824 class=logging.Formatter
3825
3826The ``format`` entry is the overall format string, and the ``datefmt`` entry is
Christian Heimes5b5e81c2007-12-31 16:14:33 +00003827the :func:`strftime`\ -compatible date/time format string. If empty, the
3828package substitutes ISO8601 format date/times, which is almost equivalent to
3829specifying the date format string ``"%Y-%m-%d %H:%M:%S"``. The ISO8601 format
3830also specifies milliseconds, which are appended to the result of using the above
3831format string, with a comma separator. An example time in ISO8601 format is
3832``2003-01-23 00:29:50,411``.
Georg Brandl116aa622007-08-15 14:28:22 +00003833
3834The ``class`` entry is optional. It indicates the name of the formatter's class
3835(as a dotted module and class name.) This option is useful for instantiating a
3836:class:`Formatter` subclass. Subclasses of :class:`Formatter` can present
3837exception tracebacks in an expanded or condensed format.
3838
Christian Heimes8b0facf2007-12-04 19:30:01 +00003839
3840Configuration server example
3841^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3842
3843Here is an example of a module using the logging configuration server::
3844
3845 import logging
3846 import logging.config
3847 import time
3848 import os
3849
3850 # read initial config file
3851 logging.config.fileConfig("logging.conf")
3852
3853 # create and start listener on port 9999
3854 t = logging.config.listen(9999)
3855 t.start()
3856
3857 logger = logging.getLogger("simpleExample")
3858
3859 try:
3860 # loop through logging calls to see the difference
3861 # new configurations make, until Ctrl+C is pressed
3862 while True:
3863 logger.debug("debug message")
3864 logger.info("info message")
3865 logger.warn("warn message")
3866 logger.error("error message")
3867 logger.critical("critical message")
3868 time.sleep(5)
3869 except KeyboardInterrupt:
3870 # cleanup
3871 logging.config.stopListening()
3872 t.join()
3873
3874And here is a script that takes a filename and sends that file to the server,
3875properly preceded with the binary-encoded length, as the new logging
3876configuration::
3877
3878 #!/usr/bin/env python
3879 import socket, sys, struct
3880
3881 data_to_send = open(sys.argv[1], "r").read()
3882
3883 HOST = 'localhost'
3884 PORT = 9999
3885 s = socket.socket(socket.AF_INET, socket.SOCK_STREAM)
Georg Brandlf6945182008-02-01 11:56:49 +00003886 print("connecting...")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003887 s.connect((HOST, PORT))
Georg Brandlf6945182008-02-01 11:56:49 +00003888 print("sending config...")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003889 s.send(struct.pack(">L", len(data_to_send)))
3890 s.send(data_to_send)
3891 s.close()
Georg Brandlf6945182008-02-01 11:56:49 +00003892 print("complete")
Christian Heimes8b0facf2007-12-04 19:30:01 +00003893
3894
3895More examples
3896-------------
3897
3898Multiple handlers and formatters
3899^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3900
3901Loggers are plain Python objects. The :func:`addHandler` method has no minimum
3902or maximum quota for the number of handlers you may add. Sometimes it will be
3903beneficial for an application to log all messages of all severities to a text
3904file while simultaneously logging errors or above to the console. To set this
3905up, simply configure the appropriate handlers. The logging calls in the
3906application code will remain unchanged. Here is a slight modification to the
3907previous simple module-based configuration example::
3908
3909 import logging
3910
3911 logger = logging.getLogger("simple_example")
3912 logger.setLevel(logging.DEBUG)
3913 # create file handler which logs even debug messages
3914 fh = logging.FileHandler("spam.log")
3915 fh.setLevel(logging.DEBUG)
3916 # create console handler with a higher log level
3917 ch = logging.StreamHandler()
3918 ch.setLevel(logging.ERROR)
3919 # create formatter and add it to the handlers
3920 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3921 ch.setFormatter(formatter)
3922 fh.setFormatter(formatter)
3923 # add the handlers to logger
3924 logger.addHandler(ch)
3925 logger.addHandler(fh)
3926
3927 # "application" code
3928 logger.debug("debug message")
3929 logger.info("info message")
3930 logger.warn("warn message")
3931 logger.error("error message")
3932 logger.critical("critical message")
3933
3934Notice that the "application" code does not care about multiple handlers. All
3935that changed was the addition and configuration of a new handler named *fh*.
3936
3937The ability to create new handlers with higher- or lower-severity filters can be
3938very helpful when writing and testing an application. Instead of using many
3939``print`` statements for debugging, use ``logger.debug``: Unlike the print
3940statements, which you will have to delete or comment out later, the logger.debug
3941statements can remain intact in the source code and remain dormant until you
3942need them again. At that time, the only change that needs to happen is to
3943modify the severity level of the logger and/or handler to debug.
3944
3945
3946Using logging in multiple modules
3947^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
3948
3949It was mentioned above that multiple calls to
3950``logging.getLogger('someLogger')`` return a reference to the same logger
3951object. This is true not only within the same module, but also across modules
3952as long as it is in the same Python interpreter process. It is true for
3953references to the same object; additionally, application code can define and
3954configure a parent logger in one module and create (but not configure) a child
3955logger in a separate module, and all logger calls to the child will pass up to
3956the parent. Here is a main module::
3957
3958 import logging
3959 import auxiliary_module
3960
3961 # create logger with "spam_application"
3962 logger = logging.getLogger("spam_application")
3963 logger.setLevel(logging.DEBUG)
3964 # create file handler which logs even debug messages
3965 fh = logging.FileHandler("spam.log")
3966 fh.setLevel(logging.DEBUG)
3967 # create console handler with a higher log level
3968 ch = logging.StreamHandler()
3969 ch.setLevel(logging.ERROR)
3970 # create formatter and add it to the handlers
3971 formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
3972 fh.setFormatter(formatter)
3973 ch.setFormatter(formatter)
3974 # add the handlers to the logger
3975 logger.addHandler(fh)
3976 logger.addHandler(ch)
3977
3978 logger.info("creating an instance of auxiliary_module.Auxiliary")
3979 a = auxiliary_module.Auxiliary()
3980 logger.info("created an instance of auxiliary_module.Auxiliary")
3981 logger.info("calling auxiliary_module.Auxiliary.do_something")
3982 a.do_something()
3983 logger.info("finished auxiliary_module.Auxiliary.do_something")
3984 logger.info("calling auxiliary_module.some_function()")
3985 auxiliary_module.some_function()
3986 logger.info("done with auxiliary_module.some_function()")
3987
3988Here is the auxiliary module::
3989
3990 import logging
3991
3992 # create logger
3993 module_logger = logging.getLogger("spam_application.auxiliary")
3994
3995 class Auxiliary:
3996 def __init__(self):
3997 self.logger = logging.getLogger("spam_application.auxiliary.Auxiliary")
3998 self.logger.info("creating an instance of Auxiliary")
3999 def do_something(self):
4000 self.logger.info("doing something")
4001 a = 1 + 1
4002 self.logger.info("done doing something")
4003
4004 def some_function():
4005 module_logger.info("received a call to \"some_function\"")
4006
4007The output looks like this::
4008
Christian Heimes043d6f62008-01-07 17:19:16 +00004009 2005-03-23 23:47:11,663 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004010 creating an instance of auxiliary_module.Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00004011 2005-03-23 23:47:11,665 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004012 creating an instance of Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00004013 2005-03-23 23:47:11,665 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004014 created an instance of auxiliary_module.Auxiliary
Christian Heimes043d6f62008-01-07 17:19:16 +00004015 2005-03-23 23:47:11,668 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004016 calling auxiliary_module.Auxiliary.do_something
Christian Heimes043d6f62008-01-07 17:19:16 +00004017 2005-03-23 23:47:11,668 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004018 doing something
Christian Heimes043d6f62008-01-07 17:19:16 +00004019 2005-03-23 23:47:11,669 - spam_application.auxiliary.Auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004020 done doing something
Christian Heimes043d6f62008-01-07 17:19:16 +00004021 2005-03-23 23:47:11,670 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004022 finished auxiliary_module.Auxiliary.do_something
Christian Heimes043d6f62008-01-07 17:19:16 +00004023 2005-03-23 23:47:11,671 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004024 calling auxiliary_module.some_function()
Christian Heimes043d6f62008-01-07 17:19:16 +00004025 2005-03-23 23:47:11,672 - spam_application.auxiliary - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004026 received a call to "some_function"
Christian Heimes043d6f62008-01-07 17:19:16 +00004027 2005-03-23 23:47:11,673 - spam_application - INFO -
Christian Heimes8b0facf2007-12-04 19:30:01 +00004028 done with auxiliary_module.some_function()
4029