handlers.py 46.4 KB
Newer Older
1
# Copyright 2001-2013 by Vinay Sajip. All Rights Reserved.
2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17
#
# Permission to use, copy, modify, and distribute this software and its
# documentation for any purpose and without fee is hereby granted,
# provided that the above copyright notice appear in all copies and that
# both that copyright notice and this permission notice appear in
# supporting documentation, and that the name of Vinay Sajip
# not be used in advertising or publicity pertaining to distribution
# of the software without specific, written prior permission.
# VINAY SAJIP DISCLAIMS ALL WARRANTIES WITH REGARD TO THIS SOFTWARE, INCLUDING
# ALL IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS. IN NO EVENT SHALL
# VINAY SAJIP BE LIABLE FOR ANY SPECIAL, INDIRECT OR CONSEQUENTIAL DAMAGES OR
# ANY DAMAGES WHATSOEVER RESULTING FROM LOSS OF USE, DATA OR PROFITS, WHETHER
# IN AN ACTION OF CONTRACT, NEGLIGENCE OR OTHER TORTIOUS ACTION, ARISING OUT
# OF OR IN CONNECTION WITH THE USE OR PERFORMANCE OF THIS SOFTWARE.

"""
Vinay Sajip's avatar
Vinay Sajip committed
18
Additional handlers for the logging package for Python. The core package is
Vinay Sajip's avatar
Vinay Sajip committed
19
based on PEP 282 and comments thereto in comp.lang.python.
20

21
Copyright (C) 2001-2013 Vinay Sajip. All Rights Reserved.
22

23
To use, simply 'import logging.handlers' and log away!
24 25
"""

26
import errno, logging, socket, os, cPickle, struct, time, re
27
from stat import ST_DEV, ST_INO, ST_MTIME
28

29 30 31 32
try:
    import codecs
except ImportError:
    codecs = None
33 34 35 36 37
try:
    unicode
    _unicode = True
except NameError:
    _unicode = False
38

39 40 41 42 43 44 45 46 47
#
# Some constants...
#

DEFAULT_TCP_LOGGING_PORT    = 9020
DEFAULT_UDP_LOGGING_PORT    = 9021
DEFAULT_HTTP_LOGGING_PORT   = 9022
DEFAULT_SOAP_LOGGING_PORT   = 9023
SYSLOG_UDP_PORT             = 514
48
SYSLOG_TCP_PORT             = 514
49

50 51
_MIDNIGHT = 24 * 60 * 60  # number of seconds in a day

52 53 54 55 56 57
class BaseRotatingHandler(logging.FileHandler):
    """
    Base class for handlers that rotate log files at a certain point.
    Not meant to be instantiated directly.  Instead, use RotatingFileHandler
    or TimedRotatingFileHandler.
    """
58
    def __init__(self, filename, mode, encoding=None, delay=0):
59 60 61
        """
        Use the specified filename for streamed logging
        """
62 63
        if codecs is None:
            encoding = None
64
        logging.FileHandler.__init__(self, filename, mode, encoding, delay)
65 66
        self.mode = mode
        self.encoding = encoding
67

68 69 70 71 72 73 74
    def emit(self, record):
        """
        Emit a record.

        Output the record to the file, catering for rollover as described
        in doRollover().
        """
75 76 77 78
        try:
            if self.shouldRollover(record):
                self.doRollover()
            logging.FileHandler.emit(self, record)
79 80
        except (KeyboardInterrupt, SystemExit):
            raise
81 82
        except:
            self.handleError(record)
83 84 85 86 87 88

class RotatingFileHandler(BaseRotatingHandler):
    """
    Handler for logging to a set of files, which switches from one file
    to the next when the current file reaches a certain size.
    """
89
    def __init__(self, filename, mode='a', maxBytes=0, backupCount=0, encoding=None, delay=0):
90 91 92 93 94 95 96 97 98 99 100 101 102 103 104 105 106 107 108 109
        """
        Open the specified file and use it as the stream for logging.

        By default, the file grows indefinitely. You can specify particular
        values of maxBytes and backupCount to allow the file to rollover at
        a predetermined size.

        Rollover occurs whenever the current log file is nearly maxBytes in
        length. If backupCount is >= 1, the system will successively create
        new files with the same pathname as the base file, but with extensions
        ".1", ".2" etc. appended to it. For example, with a backupCount of 5
        and a base file name of "app.log", you would get "app.log",
        "app.log.1", "app.log.2", ... through to "app.log.5". The file being
        written to is always "app.log" - when it gets filled up, it is closed
        and renamed to "app.log.1", and if files "app.log.1", "app.log.2" etc.
        exist, then they are renamed to "app.log.2", "app.log.3" etc.
        respectively.

        If maxBytes is zero, rollover never occurs.
        """
110 111 112 113 114
        # If rotation/rollover is wanted, it doesn't make sense to use another
        # mode. If for example 'w' were specified, then if there were multiple
        # runs of the calling application, the logs from previous runs would be
        # lost if the 'w' is respected, because the log file would be truncated
        # on each run.
115
        if maxBytes > 0:
116
            mode = 'a'
117
        BaseRotatingHandler.__init__(self, filename, mode, encoding, delay)
118 119 120 121 122 123 124
        self.maxBytes = maxBytes
        self.backupCount = backupCount

    def doRollover(self):
        """
        Do a rollover, as described in __init__().
        """
125 126
        if self.stream:
            self.stream.close()
127
            self.stream = None
128 129 130 131 132 133 134 135 136 137 138 139
        if self.backupCount > 0:
            for i in range(self.backupCount - 1, 0, -1):
                sfn = "%s.%d" % (self.baseFilename, i)
                dfn = "%s.%d" % (self.baseFilename, i + 1)
                if os.path.exists(sfn):
                    #print "%s -> %s" % (sfn, dfn)
                    if os.path.exists(dfn):
                        os.remove(dfn)
                    os.rename(sfn, dfn)
            dfn = self.baseFilename + ".1"
            if os.path.exists(dfn):
                os.remove(dfn)
140 141 142
            # Issue 18940: A file may not have been created if delay is True.
            if os.path.exists(self.baseFilename):
                os.rename(self.baseFilename, dfn)
143 144
        if not self.delay:
            self.stream = self._open()
145

146
    def shouldRollover(self, record):
147
        """
148
        Determine if rollover should occur.
149

150 151
        Basically, see if the supplied record would cause the file to exceed
        the size limit we have.
152
        """
153 154
        if self.stream is None:                 # delay was set...
            self.stream = self._open()
155 156
        if self.maxBytes > 0:                   # are we rolling over?
            msg = "%s\n" % self.format(record)
157
            self.stream.seek(0, 2)  #due to non-posix-compliant Windows feature
158
            if self.stream.tell() + len(msg) >= self.maxBytes:
159 160 161 162 163 164 165 166 167 168 169
                return 1
        return 0

class TimedRotatingFileHandler(BaseRotatingHandler):
    """
    Handler for logging to a file, rotating the log file at certain timed
    intervals.

    If backupCount is > 0, when rollover is done, no more than backupCount
    files are kept - the oldest ones are deleted.
    """
170
    def __init__(self, filename, when='h', interval=1, backupCount=0, encoding=None, delay=False, utc=False):
171
        BaseRotatingHandler.__init__(self, filename, 'a', encoding, delay)
172
        self.when = when.upper()
173
        self.backupCount = backupCount
174
        self.utc = utc
175 176 177 178 179 180 181 182 183 184 185 186 187 188 189
        # Calculate the real rollover interval, which is just the number of
        # seconds between rollovers.  Also set the filename suffix used when
        # a rollover occurs.  Current 'when' events supported:
        # S - Seconds
        # M - Minutes
        # H - Hours
        # D - Days
        # midnight - roll over at midnight
        # W{0-6} - roll over on a certain day; 0 - Monday
        #
        # Case of the 'when' specifier is not important; lower or upper case
        # will work.
        if self.when == 'S':
            self.interval = 1 # one second
            self.suffix = "%Y-%m-%d_%H-%M-%S"
190
            self.extMatch = r"^\d{4}-\d{2}-\d{2}_\d{2}-\d{2}-\d{2}$"
191 192 193
        elif self.when == 'M':
            self.interval = 60 # one minute
            self.suffix = "%Y-%m-%d_%H-%M"
194
            self.extMatch = r"^\d{4}-\d{2}-\d{2}_\d{2}-\d{2}$"
195 196 197
        elif self.when == 'H':
            self.interval = 60 * 60 # one hour
            self.suffix = "%Y-%m-%d_%H"
198
            self.extMatch = r"^\d{4}-\d{2}-\d{2}_\d{2}$"
199 200 201
        elif self.when == 'D' or self.when == 'MIDNIGHT':
            self.interval = 60 * 60 * 24 # one day
            self.suffix = "%Y-%m-%d"
202
            self.extMatch = r"^\d{4}-\d{2}-\d{2}$"
203 204 205 206 207 208 209 210
        elif self.when.startswith('W'):
            self.interval = 60 * 60 * 24 * 7 # one week
            if len(self.when) != 2:
                raise ValueError("You must specify a day for weekly rollover from 0 to 6 (0 is Monday): %s" % self.when)
            if self.when[1] < '0' or self.when[1] > '6':
                raise ValueError("Invalid day specified for weekly rollover: %s" % self.when)
            self.dayOfWeek = int(self.when[1])
            self.suffix = "%Y-%m-%d"
211
            self.extMatch = r"^\d{4}-\d{2}-\d{2}$"
212 213 214
        else:
            raise ValueError("Invalid rollover interval specified: %s" % self.when)

215
        self.extMatch = re.compile(self.extMatch)
216
        self.interval = self.interval * interval # multiply by units requested
217 218 219 220 221
        if os.path.exists(filename):
            t = os.stat(filename)[ST_MTIME]
        else:
            t = int(time.time())
        self.rolloverAt = self.computeRollover(t)
222

Vinay Sajip's avatar
Vinay Sajip committed
223 224 225 226 227
    def computeRollover(self, currentTime):
        """
        Work out the rollover time based on the specified time.
        """
        result = currentTime + self.interval
228 229 230 231 232 233 234 235 236
        # If we are rolling over at midnight or weekly, then the interval is already known.
        # What we need to figure out is WHEN the next interval is.  In other words,
        # if you are rolling over at midnight, then your base interval is 1 day,
        # but you want to start that one day clock at midnight, not now.  So, we
        # have to fudge the rolloverAt value in order to trigger the first rollover
        # at the right time.  After that, the regular interval will take care of
        # the rest.  Note that this code doesn't care about leap seconds. :)
        if self.when == 'MIDNIGHT' or self.when.startswith('W'):
            # This could be done with less code, but I wanted it to be clear
237
            if self.utc:
238 239 240
                t = time.gmtime(currentTime)
            else:
                t = time.localtime(currentTime)
241 242 243 244
            currentHour = t[3]
            currentMinute = t[4]
            currentSecond = t[5]
            # r is the number of seconds left between now and midnight
245 246
            r = _MIDNIGHT - ((currentHour * 60 + currentMinute) * 60 +
                    currentSecond)
Vinay Sajip's avatar
Vinay Sajip committed
247
            result = currentTime + r
248 249 250 251 252 253 254 255 256 257 258 259
            # If we are rolling over on a certain day, add in the number of days until
            # the next rollover, but offset by 1 since we just calculated the time
            # until the next day starts.  There are three cases:
            # Case 1) The day to rollover is today; in this case, do nothing
            # Case 2) The day to rollover is further in the interval (i.e., today is
            #         day 2 (Wednesday) and rollover is on day 6 (Sunday).  Days to
            #         next rollover is simply 6 - 2 - 1, or 3.
            # Case 3) The day to rollover is behind us in the interval (i.e., today
            #         is day 5 (Saturday) and rollover is on day 3 (Thursday).
            #         Days to rollover is 6 - 5 + 3, or 4.  In this case, it's the
            #         number of days left in the current week (1) plus the number
            #         of days in the next week until the rollover day (3).
260 261 262
            # The calculations described in 2) and 3) above need to have a day added.
            # This is because the above time calculation takes us to midnight on this
            # day, i.e. the start of the next day.
263
            if self.when.startswith('W'):
264
                day = t[6] # 0 is Monday
265 266
                if day != self.dayOfWeek:
                    if day < self.dayOfWeek:
267
                        daysToWait = self.dayOfWeek - day
268
                    else:
269
                        daysToWait = 6 - day + self.dayOfWeek + 1
Vinay Sajip's avatar
Vinay Sajip committed
270
                    newRolloverAt = result + (daysToWait * (60 * 60 * 24))
271
                    if not self.utc:
272 273 274 275
                        dstNow = t[-1]
                        dstAtRollover = time.localtime(newRolloverAt)[-1]
                        if dstNow != dstAtRollover:
                            if not dstNow:  # DST kicks in before next rollover, so we need to deduct an hour
276
                                addend = -3600
277
                            else:           # DST bows out before next rollover, so we need to add an hour
278 279
                                addend = 3600
                            newRolloverAt += addend
Vinay Sajip's avatar
Vinay Sajip committed
280 281
                    result = newRolloverAt
        return result
282 283 284

    def shouldRollover(self, record):
        """
285
        Determine if rollover should occur.
286 287

        record is not used, as we are just comparing times, but it is needed so
288
        the method signatures are the same
289 290 291 292
        """
        t = int(time.time())
        if t >= self.rolloverAt:
            return 1
293
        #print "No need to rollover: %d, %d" % (t, self.rolloverAt)
294 295
        return 0

296 297 298 299 300 301 302 303 304 305 306 307 308 309 310
    def getFilesToDelete(self):
        """
        Determine the files to delete when rolling over.

        More specific than the earlier method, which just used glob.glob().
        """
        dirName, baseName = os.path.split(self.baseFilename)
        fileNames = os.listdir(dirName)
        result = []
        prefix = baseName + "."
        plen = len(prefix)
        for fileName in fileNames:
            if fileName[:plen] == prefix:
                suffix = fileName[plen:]
                if self.extMatch.match(suffix):
311
                    result.append(os.path.join(dirName, fileName))
312 313 314 315 316 317 318
        result.sort()
        if len(result) < self.backupCount:
            result = []
        else:
            result = result[:len(result) - self.backupCount]
        return result

319 320 321 322 323 324 325 326
    def doRollover(self):
        """
        do a rollover; in this case, a date/time stamp is appended to the filename
        when the rollover happens.  However, you want the file to be named for the
        start of the interval, not the current time.  If there is a backup count,
        then we have to get a list of matching filenames, sort them and remove
        the one with the oldest suffix.
        """
327 328
        if self.stream:
            self.stream.close()
329
            self.stream = None
330
        # get the time that this sequence started at and make it a TimeTuple
331 332
        currentTime = int(time.time())
        dstNow = time.localtime(currentTime)[-1]
333
        t = self.rolloverAt - self.interval
334 335 336 337
        if self.utc:
            timeTuple = time.gmtime(t)
        else:
            timeTuple = time.localtime(t)
338 339 340 341 342 343 344
            dstThen = timeTuple[-1]
            if dstNow != dstThen:
                if dstNow:
                    addend = 3600
                else:
                    addend = -3600
                timeTuple = time.localtime(t + addend)
345 346 347
        dfn = self.baseFilename + "." + time.strftime(self.suffix, timeTuple)
        if os.path.exists(dfn):
            os.remove(dfn)
348 349 350
        # Issue 18940: A file may not have been created if delay is True.
        if os.path.exists(self.baseFilename):
            os.rename(self.baseFilename, dfn)
351
        if self.backupCount > 0:
352 353
            for s in self.getFilesToDelete():
                os.remove(s)
354 355
        if not self.delay:
            self.stream = self._open()
Vinay Sajip's avatar
Vinay Sajip committed
356
        newRolloverAt = self.computeRollover(currentTime)
357 358 359
        while newRolloverAt <= currentTime:
            newRolloverAt = newRolloverAt + self.interval
        #If DST changes and midnight or weekly rollover, adjust for this.
360
        if (self.when == 'MIDNIGHT' or self.when.startswith('W')) and not self.utc:
361 362 363
            dstAtRollover = time.localtime(newRolloverAt)[-1]
            if dstNow != dstAtRollover:
                if not dstNow:  # DST kicks in before next rollover, so we need to deduct an hour
364
                    addend = -3600
365
                else:           # DST bows out before next rollover, so we need to add an hour
366 367
                    addend = 3600
                newRolloverAt += addend
368
        self.rolloverAt = newRolloverAt
369

370 371 372 373 374 375 376 377 378 379 380 381 382 383 384 385 386 387 388 389
class WatchedFileHandler(logging.FileHandler):
    """
    A handler for logging to a file, which watches the file
    to see if it has changed while in use. This can happen because of
    usage of programs such as newsyslog and logrotate which perform
    log file rotation. This handler, intended for use under Unix,
    watches the file to see if it has changed since the last emit.
    (A file has changed if its device or inode have changed.)
    If it has changed, the old file stream is closed, and the file
    opened to get a new stream.

    This handler is not appropriate for use under Windows, because
    under Windows open files cannot be moved or renamed - logging
    opens the files with exclusive locks - and so there is no need
    for such a handler. Furthermore, ST_INO is not supported under
    Windows; stat always returns zero for this value.

    This handler is based on a suggestion and patch by Chad J.
    Schroeder.
    """
390 391
    def __init__(self, filename, mode='a', encoding=None, delay=0):
        logging.FileHandler.__init__(self, filename, mode, encoding, delay)
392 393 394 395 396 397 398
        self.dev, self.ino = -1, -1
        self._statstream()

    def _statstream(self):
        if self.stream:
            sres = os.fstat(self.stream.fileno())
            self.dev, self.ino = sres[ST_DEV], sres[ST_INO]
399 400 401 402 403 404 405 406 407

    def emit(self, record):
        """
        Emit a record.

        First check if the underlying file has changed, and if it
        has, close the old stream and reopen the file to get the
        current stream.
        """
408 409 410 411 412 413 414 415 416 417 418 419 420 421 422 423 424 425 426 427 428
        # Reduce the chance of race conditions by stat'ing by path only
        # once and then fstat'ing our new fd if we opened a new log stream.
        # See issue #14632: Thanks to John Mulligan for the problem report
        # and patch.
        try:
            # stat the file by path, checking for existence
            sres = os.stat(self.baseFilename)
        except OSError as err:
            if err.errno == errno.ENOENT:
                sres = None
            else:
                raise
        # compare file system stat with that of our stream file handle
        if not sres or sres[ST_DEV] != self.dev or sres[ST_INO] != self.ino:
            if self.stream is not None:
                # we have an open file handle, clean it up
                self.stream.flush()
                self.stream.close()
                # open a new file handle and get new stat info from that fd
                self.stream = self._open()
                self._statstream()
429 430
        logging.FileHandler.emit(self, record)

431 432 433 434 435
class SocketHandler(logging.Handler):
    """
    A handler class which writes logging records, in pickle format, to
    a streaming socket. The socket is kept open across logging calls.
    If the peer resets it, an attempt is made to reconnect on the next call.
436 437 438 439 440 441
    The pickle which is sent is that of the LogRecord's attribute dictionary
    (__dict__), so that the receiver does not need to have the logging module
    installed in order to process the logging event.

    To unpickle the record at the receiving end into a LogRecord, use the
    makeLogRecord function.
442 443 444 445 446 447 448 449 450 451 452 453 454 455 456
    """

    def __init__(self, host, port):
        """
        Initializes the handler with a specific host address and port.

        The attribute 'closeOnError' is set to 1 - which means that if
        a socket error occurs, the socket is silently closed and then
        reopened on the next logging call.
        """
        logging.Handler.__init__(self)
        self.host = host
        self.port = port
        self.sock = None
        self.closeOnError = 0
Vinay Sajip's avatar
Vinay Sajip committed
457 458 459 460 461 462 463
        self.retryTime = None
        #
        # Exponential backoff parameters.
        #
        self.retryStart = 1.0
        self.retryMax = 30.0
        self.retryFactor = 2.0
464

465
    def makeSocket(self, timeout=1):
466 467 468 469 470
        """
        A factory method which allows subclasses to define the precise
        type of socket they want.
        """
        s = socket.socket(socket.AF_INET, socket.SOCK_STREAM)
471 472
        if hasattr(s, 'settimeout'):
            s.settimeout(timeout)
473 474 475
        s.connect((self.host, self.port))
        return s

Vinay Sajip's avatar
Vinay Sajip committed
476 477 478 479 480 481 482 483 484 485 486
    def createSocket(self):
        """
        Try to create a socket, using an exponential backoff with
        a max retry time. Thanks to Robert Olson for the original patch
        (SF #815911) which has been slightly refactored.
        """
        now = time.time()
        # Either retryTime is None, in which case this
        # is the first time back after a disconnect, or
        # we've waited long enough.
        if self.retryTime is None:
Tim Peters's avatar
Tim Peters committed
487
            attempt = 1
Vinay Sajip's avatar
Vinay Sajip committed
488
        else:
Tim Peters's avatar
Tim Peters committed
489
            attempt = (now >= self.retryTime)
Vinay Sajip's avatar
Vinay Sajip committed
490 491 492 493
        if attempt:
            try:
                self.sock = self.makeSocket()
                self.retryTime = None # next time, no delay before trying
494
            except socket.error:
Vinay Sajip's avatar
Vinay Sajip committed
495 496 497 498 499 500 501 502 503
                #Creation failed, so set the retry time and return.
                if self.retryTime is None:
                    self.retryPeriod = self.retryStart
                else:
                    self.retryPeriod = self.retryPeriod * self.retryFactor
                    if self.retryPeriod > self.retryMax:
                        self.retryPeriod = self.retryMax
                self.retryTime = now + self.retryPeriod

504 505 506 507 508 509 510
    def send(self, s):
        """
        Send a pickled string to the socket.

        This function allows for partial sends which can happen when the
        network is busy.
        """
Vinay Sajip's avatar
Vinay Sajip committed
511 512 513 514 515 516 517 518 519 520 521 522 523 524 525 526 527 528 529
        if self.sock is None:
            self.createSocket()
        #self.sock can be None either because we haven't reached the retry
        #time yet, or because we have reached the retry time and retried,
        #but are still unable to connect.
        if self.sock:
            try:
                if hasattr(self.sock, "sendall"):
                    self.sock.sendall(s)
                else:
                    sentsofar = 0
                    left = len(s)
                    while left > 0:
                        sent = self.sock.send(s[sentsofar:])
                        sentsofar = sentsofar + sent
                        left = left - sent
            except socket.error:
                self.sock.close()
                self.sock = None  # so we can call createSocket next time
530 531 532 533 534 535

    def makePickle(self, record):
        """
        Pickles the record in binary format with a length prefix, and
        returns it ready for transmission across the socket.
        """
Vinay Sajip's avatar
Vinay Sajip committed
536 537
        ei = record.exc_info
        if ei:
538 539
            # just to get traceback text into record.exc_text ...
            dummy = self.format(record)
Tim Peters's avatar
Tim Peters committed
540
            record.exc_info = None  # to avoid Unpickleable error
541 542 543 544 545 546 547
        # See issue #14436: If msg or args are objects, they may not be
        # available on the receiving end. So we convert the msg % args
        # to a string, save it as msg and zap the args.
        d = dict(record.__dict__)
        d['msg'] = record.getMessage()
        d['args'] = None
        s = cPickle.dumps(d, 1)
Vinay Sajip's avatar
Vinay Sajip committed
548
        if ei:
Tim Peters's avatar
Tim Peters committed
549
            record.exc_info = ei  # for next handler
550 551 552
        slen = struct.pack(">L", len(s))
        return slen + s

553
    def handleError(self, record):
554 555 556 557 558 559 560 561 562 563 564
        """
        Handle an error during logging.

        An error has occurred during logging. Most likely cause -
        connection lost. Close the socket so that we can retry on the
        next event.
        """
        if self.closeOnError and self.sock:
            self.sock.close()
            self.sock = None        #try to reconnect next time
        else:
565
            logging.Handler.handleError(self, record)
566 567 568 569 570 571 572 573 574 575 576 577 578

    def emit(self, record):
        """
        Emit a record.

        Pickles the record and writes it to the socket in binary format.
        If there is an error with the socket, silently drop the packet.
        If there was a problem with the socket, re-establishes the
        socket.
        """
        try:
            s = self.makePickle(record)
            self.send(s)
579 580
        except (KeyboardInterrupt, SystemExit):
            raise
581
        except:
582
            self.handleError(record)
583 584 585 586 587

    def close(self):
        """
        Closes the socket.
        """
588 589
        self.acquire()
        try:
590 591 592
            if self.sock:
                self.sock.close()
                self.sock = None
593 594
        finally:
            self.release()
Vinay Sajip's avatar
Vinay Sajip committed
595
        logging.Handler.close(self)
596 597 598 599

class DatagramHandler(SocketHandler):
    """
    A handler class which writes logging records, in pickle format, to
600 601 602 603 604 605
    a datagram socket.  The pickle which is sent is that of the LogRecord's
    attribute dictionary (__dict__), so that the receiver does not need to
    have the logging module installed in order to process the logging event.

    To unpickle the record at the receiving end into a LogRecord, use the
    makeLogRecord function.
606 607 608 609 610 611 612 613 614 615 616 617 618 619 620 621 622 623 624 625 626 627 628 629 630

    """
    def __init__(self, host, port):
        """
        Initializes the handler with a specific host address and port.
        """
        SocketHandler.__init__(self, host, port)
        self.closeOnError = 0

    def makeSocket(self):
        """
        The factory method of SocketHandler is here overridden to create
        a UDP socket (SOCK_DGRAM).
        """
        s = socket.socket(socket.AF_INET, socket.SOCK_DGRAM)
        return s

    def send(self, s):
        """
        Send a pickled string to a socket.

        This function no longer allows for partial sends which can happen
        when the network is busy - UDP does not guarantee delivery and
        can deliver packets out of sequence.
        """
631 632
        if self.sock is None:
            self.createSocket()
633 634 635 636 637 638 639 640 641 642 643 644 645 646 647 648 649 650 651 652 653 654 655 656 657 658 659 660 661 662 663 664 665 666 667 668 669 670 671 672 673
        self.sock.sendto(s, (self.host, self.port))

class SysLogHandler(logging.Handler):
    """
    A handler class which sends formatted logging records to a syslog
    server. Based on Sam Rushing's syslog module:
    http://www.nightmare.com/squirl/python-ext/misc/syslog.py
    Contributed by Nicolas Untz (after which minor refactoring changes
    have been made).
    """

    # from <linux/sys/syslog.h>:
    # ======================================================================
    # priorities/facilities are encoded into a single 32-bit quantity, where
    # the bottom 3 bits are the priority (0-7) and the top 28 bits are the
    # facility (0-big number). Both the priorities and the facilities map
    # roughly one-to-one to strings in the syslogd(8) source code.  This
    # mapping is included in this file.
    #
    # priorities (these are ordered)

    LOG_EMERG     = 0       #  system is unusable
    LOG_ALERT     = 1       #  action must be taken immediately
    LOG_CRIT      = 2       #  critical conditions
    LOG_ERR       = 3       #  error conditions
    LOG_WARNING   = 4       #  warning conditions
    LOG_NOTICE    = 5       #  normal but significant condition
    LOG_INFO      = 6       #  informational
    LOG_DEBUG     = 7       #  debug-level messages

    #  facility codes
    LOG_KERN      = 0       #  kernel messages
    LOG_USER      = 1       #  random user-level messages
    LOG_MAIL      = 2       #  mail system
    LOG_DAEMON    = 3       #  system daemons
    LOG_AUTH      = 4       #  security/authorization messages
    LOG_SYSLOG    = 5       #  messages generated internally by syslogd
    LOG_LPR       = 6       #  line printer subsystem
    LOG_NEWS      = 7       #  network news subsystem
    LOG_UUCP      = 8       #  UUCP subsystem
    LOG_CRON      = 9       #  clock daemon
674 675
    LOG_AUTHPRIV  = 10      #  security/authorization messages (private)
    LOG_FTP       = 11      #  FTP daemon
676 677 678 679 680 681 682 683 684 685 686 687 688 689 690 691 692 693 694 695 696 697 698 699 700 701 702 703 704 705 706

    #  other codes through 15 reserved for system use
    LOG_LOCAL0    = 16      #  reserved for local use
    LOG_LOCAL1    = 17      #  reserved for local use
    LOG_LOCAL2    = 18      #  reserved for local use
    LOG_LOCAL3    = 19      #  reserved for local use
    LOG_LOCAL4    = 20      #  reserved for local use
    LOG_LOCAL5    = 21      #  reserved for local use
    LOG_LOCAL6    = 22      #  reserved for local use
    LOG_LOCAL7    = 23      #  reserved for local use

    priority_names = {
        "alert":    LOG_ALERT,
        "crit":     LOG_CRIT,
        "critical": LOG_CRIT,
        "debug":    LOG_DEBUG,
        "emerg":    LOG_EMERG,
        "err":      LOG_ERR,
        "error":    LOG_ERR,        #  DEPRECATED
        "info":     LOG_INFO,
        "notice":   LOG_NOTICE,
        "panic":    LOG_EMERG,      #  DEPRECATED
        "warn":     LOG_WARNING,    #  DEPRECATED
        "warning":  LOG_WARNING,
        }

    facility_names = {
        "auth":     LOG_AUTH,
        "authpriv": LOG_AUTHPRIV,
        "cron":     LOG_CRON,
        "daemon":   LOG_DAEMON,
707
        "ftp":      LOG_FTP,
708 709 710 711 712 713 714 715 716 717 718 719 720 721 722 723 724 725
        "kern":     LOG_KERN,
        "lpr":      LOG_LPR,
        "mail":     LOG_MAIL,
        "news":     LOG_NEWS,
        "security": LOG_AUTH,       #  DEPRECATED
        "syslog":   LOG_SYSLOG,
        "user":     LOG_USER,
        "uucp":     LOG_UUCP,
        "local0":   LOG_LOCAL0,
        "local1":   LOG_LOCAL1,
        "local2":   LOG_LOCAL2,
        "local3":   LOG_LOCAL3,
        "local4":   LOG_LOCAL4,
        "local5":   LOG_LOCAL5,
        "local6":   LOG_LOCAL6,
        "local7":   LOG_LOCAL7,
        }

726 727 728 729 730 731 732 733 734 735 736 737
    #The map below appears to be trivially lowercasing the key. However,
    #there's more to it than meets the eye - in some locales, lowercasing
    #gives unexpected results. See SF #1524081: in the Turkish locale,
    #"INFO".lower() != "info"
    priority_map = {
        "DEBUG" : "debug",
        "INFO" : "info",
        "WARNING" : "warning",
        "ERROR" : "error",
        "CRITICAL" : "critical"
    }

738
    def __init__(self, address=('localhost', SYSLOG_UDP_PORT),
739
                 facility=LOG_USER, socktype=None):
740 741 742
        """
        Initialize a handler.

743 744
        If address is specified as a string, a UNIX socket is used. To log to a
        local syslogd, "SysLogHandler(address="/dev/log")" can be used.
745 746 747 748 749
        If facility is not specified, LOG_USER is used. If socktype is
        specified as socket.SOCK_DGRAM or socket.SOCK_STREAM, that specific
        socket type will be used. For Unix sockets, you can also specify a
        socktype of None, in which case socket.SOCK_DGRAM will be used, falling
        back to socket.SOCK_STREAM.
750 751 752 753 754
        """
        logging.Handler.__init__(self)

        self.address = address
        self.facility = facility
755 756 757
        self.socktype = socktype

        if isinstance(address, basestring):
758
            self.unixsocket = 1
759
            self._connect_unixsocket(address)
760 761
        else:
            self.unixsocket = 0
762 763
            if socktype is None:
                socktype = socket.SOCK_DGRAM
764 765 766
            self.socket = socket.socket(socket.AF_INET, socktype)
            if socktype == socket.SOCK_STREAM:
                self.socket.connect(address)
767
            self.socktype = socktype
768 769
        self.formatter = None

770
    def _connect_unixsocket(self, address):
771 772 773 774
        use_socktype = self.socktype
        if use_socktype is None:
            use_socktype = socket.SOCK_DGRAM
        self.socket = socket.socket(socket.AF_UNIX, use_socktype)
775 776
        try:
            self.socket.connect(address)
777 778
            # it worked, so set self.socktype to the used type
            self.socktype = use_socktype
779 780
        except socket.error:
            self.socket.close()
781 782 783 784 785 786 787 788 789 790 791 792
            if self.socktype is not None:
                # user didn't specify falling back, so fail
                raise
            use_socktype = socket.SOCK_STREAM
            self.socket = socket.socket(socket.AF_UNIX, use_socktype)
            try:
                self.socket.connect(address)
                # it worked, so set self.socktype to the used type
                self.socktype = use_socktype
            except socket.error:
                self.socket.close()
                raise
793

794 795 796 797 798 799
    # curious: when talking to the unix-domain '/dev/log' socket, a
    #   zero-terminator seems to be required.  this string is placed
    #   into a class variable so that it can be overridden if
    #   necessary.
    log_format_string = '<%d>%s\000'

800
    def encodePriority(self, facility, priority):
801 802 803 804 805 806
        """
        Encode the facility and priority. You can pass in strings or
        integers - if strings are passed, the facility_names and
        priority_names mapping dictionaries are used to convert them to
        integers.
        """
807
        if isinstance(facility, basestring):
808
            facility = self.facility_names[facility]
809
        if isinstance(priority, basestring):
810 811 812 813 814 815 816
            priority = self.priority_names[priority]
        return (facility << 3) | priority

    def close (self):
        """
        Closes the socket.
        """
817 818
        self.acquire()
        try:
819 820
            if self.unixsocket:
                self.socket.close()
821 822
        finally:
            self.release()
Vinay Sajip's avatar
Vinay Sajip committed
823
        logging.Handler.close(self)
824

825 826 827 828 829 830 831 832 833 834
    def mapPriority(self, levelName):
        """
        Map a logging level name to a key in the priority_names map.
        This is useful in two scenarios: when custom levels are being
        used, and in the case where you can't do a straightforward
        mapping by lowercasing the logging level name because of locale-
        specific issues (see SF #1524081).
        """
        return self.priority_map.get(levelName, "warning")

835 836 837 838 839 840 841
    def emit(self, record):
        """
        Emit a record.

        The record is formatted, and then sent to the syslog server. If
        exception information is present, it is NOT sent to the server.
        """
842
        msg = self.format(record) + '\000'
843 844 845 846
        """
        We need to convert record level to lowercase, maybe this will
        change in the future.
        """
847 848 849 850
        prio = '<%d>' % self.encodePriority(self.facility,
                                            self.mapPriority(record.levelname))
        # Message is a string. Convert to bytes as required by RFC 5424
        if type(msg) is unicode:
851
            msg = msg.encode('utf-8')
852
        msg = prio + msg
853 854
        try:
            if self.unixsocket:
855 856 857
                try:
                    self.socket.send(msg)
                except socket.error:
858
                    self.socket.close() # See issue 17981
859 860
                    self._connect_unixsocket(self.address)
                    self.socket.send(msg)
861
            elif self.socktype == socket.SOCK_DGRAM:
862
                self.socket.sendto(msg, self.address)
863 864
            else:
                self.socket.sendall(msg)
865 866
        except (KeyboardInterrupt, SystemExit):
            raise
867
        except:
868
            self.handleError(record)
869 870 871 872 873

class SMTPHandler(logging.Handler):
    """
    A handler class which sends an SMTP email for each logging event.
    """
874
    def __init__(self, mailhost, fromaddr, toaddrs, subject,
875
                 credentials=None, secure=None):
876 877 878 879 880
        """
        Initialize the handler.

        Initialize the instance with the from and to addresses and subject
        line of the email. To specify a non-standard SMTP port, use the
881 882
        (host, port) tuple format for the mailhost argument. To specify
        authentication credentials, supply a (username, password) tuple
883
        for the credentials argument. To specify the use of a secure
884 885 886 887 888
        protocol (TLS), pass in a tuple for the secure argument. This will
        only be used when authentication credentials are supplied. The tuple
        will be either an empty tuple, or a single-value tuple with the name
        of a keyfile, or a 2-value tuple with the names of the keyfile and
        certificate file. (This tuple is passed to the `starttls` method).
889 890
        """
        logging.Handler.__init__(self)
891
        if isinstance(mailhost, tuple):
892
            self.mailhost, self.mailport = mailhost
893
        else:
894
            self.mailhost, self.mailport = mailhost, None
895
        if isinstance(credentials, tuple):
896 897 898
            self.username, self.password = credentials
        else:
            self.username = None
899
        self.fromaddr = fromaddr
900
        if isinstance(toaddrs, basestring):
901
            toaddrs = [toaddrs]
902 903
        self.toaddrs = toaddrs
        self.subject = subject
904
        self.secure = secure
Vinay Sajip's avatar
Vinay Sajip committed
905
        self._timeout = 5.0
906 907 908 909 910 911 912 913 914 915 916 917 918 919 920 921 922 923

    def getSubject(self, record):
        """
        Determine the subject for the email.

        If you want to specify a subject line which is record-dependent,
        override this method.
        """
        return self.subject

    def emit(self, record):
        """
        Emit a record.

        Format the record and send it to the specified addressees.
        """
        try:
            import smtplib
924
            from email.utils import formatdate
925 926 927
            port = self.mailport
            if not port:
                port = smtplib.SMTP_PORT
Vinay Sajip's avatar
Vinay Sajip committed
928
            smtp = smtplib.SMTP(self.mailhost, port, timeout=self._timeout)
929
            msg = self.format(record)
930
            msg = "From: %s\r\nTo: %s\r\nSubject: %s\r\nDate: %s\r\n\r\n%s" % (
931
                            self.fromaddr,
932
                            ",".join(self.toaddrs),
933
                            self.getSubject(record),
934
                            formatdate(), msg)
935
            if self.username:
936
                if self.secure is not None:
937
                    smtp.ehlo()
938
                    smtp.starttls(*self.secure)
939
                    smtp.ehlo()
940
                smtp.login(self.username, self.password)
941 942
            smtp.sendmail(self.fromaddr, self.toaddrs, msg)
            smtp.quit()
943 944
        except (KeyboardInterrupt, SystemExit):
            raise
945
        except:
946
            self.handleError(record)
947 948 949 950 951 952 953 954 955 956 957 958 959 960 961 962 963 964 965 966 967 968 969 970 971 972 973 974

class NTEventLogHandler(logging.Handler):
    """
    A handler class which sends events to the NT Event Log. Adds a
    registry entry for the specified application name. If no dllname is
    provided, win32service.pyd (which contains some basic message
    placeholders) is used. Note that use of these placeholders will make
    your event logs big, as the entire message source is held in the log.
    If you want slimmer logs, you have to pass in the name of your own DLL
    which contains the message definitions you want to use in the event log.
    """
    def __init__(self, appname, dllname=None, logtype="Application"):
        logging.Handler.__init__(self)
        try:
            import win32evtlogutil, win32evtlog
            self.appname = appname
            self._welu = win32evtlogutil
            if not dllname:
                dllname = os.path.split(self._welu.__file__)
                dllname = os.path.split(dllname[0])
                dllname = os.path.join(dllname[0], r'win32service.pyd')
            self.dllname = dllname
            self.logtype = logtype
            self._welu.AddSourceToRegistry(appname, dllname, logtype)
            self.deftype = win32evtlog.EVENTLOG_ERROR_TYPE
            self.typemap = {
                logging.DEBUG   : win32evtlog.EVENTLOG_INFORMATION_TYPE,
                logging.INFO    : win32evtlog.EVENTLOG_INFORMATION_TYPE,
975
                logging.WARNING : win32evtlog.EVENTLOG_WARNING_TYPE,
976 977 978 979
                logging.ERROR   : win32evtlog.EVENTLOG_ERROR_TYPE,
                logging.CRITICAL: win32evtlog.EVENTLOG_ERROR_TYPE,
         }
        except ImportError:
980 981
            print("The Python Win32 extensions for NT (service, event "\
                        "logging) appear not to be available.")
982 983 984 985 986 987 988 989 990 991 992 993 994 995 996 997 998 999 1000 1001 1002 1003 1004 1005 1006 1007 1008 1009
            self._welu = None

    def getMessageID(self, record):
        """
        Return the message ID for the event record. If you are using your
        own messages, you could do this by having the msg passed to the
        logger being an ID rather than a formatting string. Then, in here,
        you could use a dictionary lookup to get the message ID. This
        version returns 1, which is the base message ID in win32service.pyd.
        """
        return 1

    def getEventCategory(self, record):
        """
        Return the event category for the record.

        Override this if you want to specify your own categories. This version
        returns 0.
        """
        return 0

    def getEventType(self, record):
        """
        Return the event type for the record.

        Override this if you want to specify your own types. This version does
        a mapping using the handler's typemap attribute, which is set up in
        __init__() to a dictionary which contains mappings for DEBUG, INFO,
1010
        WARNING, ERROR and CRITICAL. If you are using your own levels you will
1011 1012 1013 1014 1015 1016 1017 1018 1019 1020 1021 1022 1023 1024 1025 1026 1027 1028 1029
        either need to override this method or place a suitable dictionary in
        the handler's typemap attribute.
        """
        return self.typemap.get(record.levelno, self.deftype)

    def emit(self, record):
        """
        Emit a record.

        Determine the message ID, event category and event type. Then
        log the message in the NT event log.
        """
        if self._welu:
            try:
                id = self.getMessageID(record)
                cat = self.getEventCategory(record)
                type = self.getEventType(record)
                msg = self.format(record)
                self._welu.ReportEvent(self.appname, id, cat, type, [msg])
1030 1031
            except (KeyboardInterrupt, SystemExit):
                raise
1032
            except:
1033
                self.handleError(record)
1034 1035 1036 1037 1038 1039 1040 1041 1042 1043 1044 1045

    def close(self):
        """
        Clean up this handler.

        You can remove the application name from the registry as a
        source of event log entries. However, if you do this, you will
        not be able to see the events as you intended in the Event Log
        Viewer - it needs to be able to access the registry to get the
        DLL name.
        """
        #self._welu.RemoveSourceFromRegistry(self.appname, self.logtype)
Vinay Sajip's avatar
Vinay Sajip committed
1046
        logging.Handler.close(self)
1047 1048 1049 1050 1051 1052 1053 1054 1055 1056 1057 1058

class HTTPHandler(logging.Handler):
    """
    A class which sends records to a Web server, using either GET or
    POST semantics.
    """
    def __init__(self, host, url, method="GET"):
        """
        Initialize the instance with the host, the request URL, and the method
        ("GET" or "POST")
        """
        logging.Handler.__init__(self)
1059
        method = method.upper()
1060
        if method not in ["GET", "POST"]:
1061
            raise ValueError("method must be GET or POST")
1062 1063 1064 1065
        self.host = host
        self.url = url
        self.method = method

1066 1067 1068
    def mapLogRecord(self, record):
        """
        Default implementation of mapping the log record into a dict
Vinay Sajip's avatar
Vinay Sajip committed
1069
        that is sent as the CGI data. Overwrite in your class.
1070 1071 1072 1073
        Contributed by Franz  Glasner.
        """
        return record.__dict__

1074 1075 1076 1077
    def emit(self, record):
        """
        Emit a record.

1078
        Send the record to the Web server as a percent-encoded dictionary
1079 1080 1081
        """
        try:
            import httplib, urllib
1082 1083
            host = self.host
            h = httplib.HTTP(host)
1084
            url = self.url
1085
            data = urllib.urlencode(self.mapLogRecord(record))
1086
            if self.method == "GET":
1087
                if (url.find('?') >= 0):
1088 1089 1090 1091 1092
                    sep = '&'
                else:
                    sep = '?'
                url = url + "%c%s" % (sep, data)
            h.putrequest(self.method, url)
1093 1094
            # support multiple hosts on one IP address...
            # need to strip optional :port from host, if present
1095
            i = host.find(":")
1096 1097 1098
            if i >= 0:
                host = host[:i]
            h.putheader("Host", host)
1099
            if self.method == "POST":
1100 1101
                h.putheader("Content-type",
                            "application/x-www-form-urlencoded")
1102
                h.putheader("Content-length", str(len(data)))
1103
            h.endheaders(data if self.method == "POST" else None)
1104
            h.getreply()    #can't do anything with the result
1105 1106
        except (KeyboardInterrupt, SystemExit):
            raise
1107
        except:
1108
            self.handleError(record)
1109 1110 1111 1112 1113 1114 1115 1116 1117 1118 1119 1120 1121 1122 1123 1124 1125 1126 1127 1128 1129 1130 1131 1132 1133 1134 1135 1136 1137 1138 1139 1140 1141 1142 1143 1144 1145 1146 1147 1148 1149

class BufferingHandler(logging.Handler):
    """
  A handler class which buffers logging records in memory. Whenever each
  record is added to the buffer, a check is made to see if the buffer should
  be flushed. If it should, then flush() is expected to do what's needed.
    """
    def __init__(self, capacity):
        """
        Initialize the handler with the buffer size.
        """
        logging.Handler.__init__(self)
        self.capacity = capacity
        self.buffer = []

    def shouldFlush(self, record):
        """
        Should the handler flush its buffer?

        Returns true if the buffer is up to capacity. This method can be
        overridden to implement custom flushing strategies.
        """
        return (len(self.buffer) >= self.capacity)

    def emit(self, record):
        """
        Emit a record.

        Append the record. If shouldFlush() tells us to, call flush() to process
        the buffer.
        """
        self.buffer.append(record)
        if self.shouldFlush(record):
            self.flush()

    def flush(self):
        """
        Override to implement custom flushing behaviour.

        This version just zaps the buffer to empty.
        """
1150 1151
        self.acquire()
        try:
1152
            self.buffer = []
1153 1154
        finally:
            self.release()
1155

1156 1157 1158 1159 1160 1161 1162 1163 1164
    def close(self):
        """
        Close the handler.

        This version just flushes and chains to the parent class' close().
        """
        self.flush()
        logging.Handler.close(self)

1165 1166 1167 1168 1169 1170 1171 1172 1173 1174 1175 1176 1177 1178 1179 1180 1181 1182 1183 1184 1185 1186 1187 1188 1189 1190 1191 1192 1193 1194 1195 1196 1197 1198 1199 1200 1201
class MemoryHandler(BufferingHandler):
    """
    A handler class which buffers logging records in memory, periodically
    flushing them to a target handler. Flushing occurs whenever the buffer
    is full, or when an event of a certain severity or greater is seen.
    """
    def __init__(self, capacity, flushLevel=logging.ERROR, target=None):
        """
        Initialize the handler with the buffer size, the level at which
        flushing should occur and an optional target.

        Note that without a target being set either here or via setTarget(),
        a MemoryHandler is no use to anyone!
        """
        BufferingHandler.__init__(self, capacity)
        self.flushLevel = flushLevel
        self.target = target

    def shouldFlush(self, record):
        """
        Check for buffer full or a record at the flushLevel or higher.
        """
        return (len(self.buffer) >= self.capacity) or \
                (record.levelno >= self.flushLevel)

    def setTarget(self, target):
        """
        Set the target handler for this handler.
        """
        self.target = target

    def flush(self):
        """
        For a MemoryHandler, flushing means just sending the buffered
        records to the target, if there is one. Override if you want
        different behaviour.
        """
1202 1203
        self.acquire()
        try:
1204 1205 1206 1207
            if self.target:
                for record in self.buffer:
                    self.target.handle(record)
                self.buffer = []
1208 1209
        finally:
            self.release()
1210 1211 1212 1213 1214 1215

    def close(self):
        """
        Flush, set the target to None and lose the buffer.
        """
        self.flush()
1216 1217
        self.acquire()
        try:
1218 1219
            self.target = None
            BufferingHandler.close(self)
1220 1221
        finally:
            self.release()