[391] | 1 | # Copyright 2001-2013 by Vinay Sajip. All Rights Reserved.
|
---|
[2] | 2 | #
|
---|
| 3 | # Permission to use, copy, modify, and distribute this software and its
|
---|
| 4 | # documentation for any purpose and without fee is hereby granted,
|
---|
| 5 | # provided that the above copyright notice appear in all copies and that
|
---|
| 6 | # both that copyright notice and this permission notice appear in
|
---|
| 7 | # supporting documentation, and that the name of Vinay Sajip
|
---|
| 8 | # not be used in advertising or publicity pertaining to distribution
|
---|
| 9 | # of the software without specific, written prior permission.
|
---|
| 10 | # VINAY SAJIP DISCLAIMS ALL WARRANTIES WITH REGARD TO THIS SOFTWARE, INCLUDING
|
---|
| 11 | # ALL IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS. IN NO EVENT SHALL
|
---|
| 12 | # VINAY SAJIP BE LIABLE FOR ANY SPECIAL, INDIRECT OR CONSEQUENTIAL DAMAGES OR
|
---|
| 13 | # ANY DAMAGES WHATSOEVER RESULTING FROM LOSS OF USE, DATA OR PROFITS, WHETHER
|
---|
| 14 | # IN AN ACTION OF CONTRACT, NEGLIGENCE OR OTHER TORTIOUS ACTION, ARISING OUT
|
---|
| 15 | # OF OR IN CONNECTION WITH THE USE OR PERFORMANCE OF THIS SOFTWARE.
|
---|
| 16 |
|
---|
| 17 | """
|
---|
| 18 | Additional handlers for the logging package for Python. The core package is
|
---|
[391] | 19 | based on PEP 282 and comments thereto in comp.lang.python.
|
---|
[2] | 20 |
|
---|
[391] | 21 | Copyright (C) 2001-2013 Vinay Sajip. All Rights Reserved.
|
---|
[2] | 22 |
|
---|
| 23 | To use, simply 'import logging.handlers' and log away!
|
---|
| 24 | """
|
---|
| 25 |
|
---|
[391] | 26 | import errno, logging, socket, os, cPickle, struct, time, re
|
---|
| 27 | from stat import ST_DEV, ST_INO, ST_MTIME
|
---|
[2] | 28 |
|
---|
| 29 | try:
|
---|
| 30 | import codecs
|
---|
| 31 | except ImportError:
|
---|
| 32 | codecs = None
|
---|
[391] | 33 | try:
|
---|
| 34 | unicode
|
---|
| 35 | _unicode = True
|
---|
| 36 | except NameError:
|
---|
| 37 | _unicode = False
|
---|
[2] | 38 |
|
---|
| 39 | #
|
---|
| 40 | # Some constants...
|
---|
| 41 | #
|
---|
| 42 |
|
---|
| 43 | DEFAULT_TCP_LOGGING_PORT = 9020
|
---|
| 44 | DEFAULT_UDP_LOGGING_PORT = 9021
|
---|
| 45 | DEFAULT_HTTP_LOGGING_PORT = 9022
|
---|
| 46 | DEFAULT_SOAP_LOGGING_PORT = 9023
|
---|
| 47 | SYSLOG_UDP_PORT = 514
|
---|
[391] | 48 | SYSLOG_TCP_PORT = 514
|
---|
[2] | 49 |
|
---|
| 50 | _MIDNIGHT = 24 * 60 * 60 # number of seconds in a day
|
---|
| 51 |
|
---|
| 52 | class BaseRotatingHandler(logging.FileHandler):
|
---|
| 53 | """
|
---|
| 54 | Base class for handlers that rotate log files at a certain point.
|
---|
| 55 | Not meant to be instantiated directly. Instead, use RotatingFileHandler
|
---|
| 56 | or TimedRotatingFileHandler.
|
---|
| 57 | """
|
---|
| 58 | def __init__(self, filename, mode, encoding=None, delay=0):
|
---|
| 59 | """
|
---|
| 60 | Use the specified filename for streamed logging
|
---|
| 61 | """
|
---|
| 62 | if codecs is None:
|
---|
| 63 | encoding = None
|
---|
| 64 | logging.FileHandler.__init__(self, filename, mode, encoding, delay)
|
---|
| 65 | self.mode = mode
|
---|
| 66 | self.encoding = encoding
|
---|
| 67 |
|
---|
| 68 | def emit(self, record):
|
---|
| 69 | """
|
---|
| 70 | Emit a record.
|
---|
| 71 |
|
---|
| 72 | Output the record to the file, catering for rollover as described
|
---|
| 73 | in doRollover().
|
---|
| 74 | """
|
---|
| 75 | try:
|
---|
| 76 | if self.shouldRollover(record):
|
---|
| 77 | self.doRollover()
|
---|
| 78 | logging.FileHandler.emit(self, record)
|
---|
| 79 | except (KeyboardInterrupt, SystemExit):
|
---|
| 80 | raise
|
---|
| 81 | except:
|
---|
| 82 | self.handleError(record)
|
---|
| 83 |
|
---|
| 84 | class RotatingFileHandler(BaseRotatingHandler):
|
---|
| 85 | """
|
---|
| 86 | Handler for logging to a set of files, which switches from one file
|
---|
| 87 | to the next when the current file reaches a certain size.
|
---|
| 88 | """
|
---|
| 89 | def __init__(self, filename, mode='a', maxBytes=0, backupCount=0, encoding=None, delay=0):
|
---|
| 90 | """
|
---|
| 91 | Open the specified file and use it as the stream for logging.
|
---|
| 92 |
|
---|
| 93 | By default, the file grows indefinitely. You can specify particular
|
---|
| 94 | values of maxBytes and backupCount to allow the file to rollover at
|
---|
| 95 | a predetermined size.
|
---|
| 96 |
|
---|
| 97 | Rollover occurs whenever the current log file is nearly maxBytes in
|
---|
| 98 | length. If backupCount is >= 1, the system will successively create
|
---|
| 99 | new files with the same pathname as the base file, but with extensions
|
---|
| 100 | ".1", ".2" etc. appended to it. For example, with a backupCount of 5
|
---|
| 101 | and a base file name of "app.log", you would get "app.log",
|
---|
| 102 | "app.log.1", "app.log.2", ... through to "app.log.5". The file being
|
---|
| 103 | written to is always "app.log" - when it gets filled up, it is closed
|
---|
| 104 | and renamed to "app.log.1", and if files "app.log.1", "app.log.2" etc.
|
---|
| 105 | exist, then they are renamed to "app.log.2", "app.log.3" etc.
|
---|
| 106 | respectively.
|
---|
| 107 |
|
---|
| 108 | If maxBytes is zero, rollover never occurs.
|
---|
| 109 | """
|
---|
[391] | 110 | # If rotation/rollover is wanted, it doesn't make sense to use another
|
---|
| 111 | # mode. If for example 'w' were specified, then if there were multiple
|
---|
| 112 | # runs of the calling application, the logs from previous runs would be
|
---|
| 113 | # lost if the 'w' is respected, because the log file would be truncated
|
---|
| 114 | # on each run.
|
---|
[2] | 115 | if maxBytes > 0:
|
---|
[391] | 116 | mode = 'a'
|
---|
[2] | 117 | BaseRotatingHandler.__init__(self, filename, mode, encoding, delay)
|
---|
| 118 | self.maxBytes = maxBytes
|
---|
| 119 | self.backupCount = backupCount
|
---|
| 120 |
|
---|
| 121 | def doRollover(self):
|
---|
| 122 | """
|
---|
| 123 | Do a rollover, as described in __init__().
|
---|
| 124 | """
|
---|
[391] | 125 | if self.stream:
|
---|
| 126 | self.stream.close()
|
---|
| 127 | self.stream = None
|
---|
[2] | 128 | if self.backupCount > 0:
|
---|
| 129 | for i in range(self.backupCount - 1, 0, -1):
|
---|
| 130 | sfn = "%s.%d" % (self.baseFilename, i)
|
---|
| 131 | dfn = "%s.%d" % (self.baseFilename, i + 1)
|
---|
| 132 | if os.path.exists(sfn):
|
---|
| 133 | #print "%s -> %s" % (sfn, dfn)
|
---|
| 134 | if os.path.exists(dfn):
|
---|
| 135 | os.remove(dfn)
|
---|
| 136 | os.rename(sfn, dfn)
|
---|
| 137 | dfn = self.baseFilename + ".1"
|
---|
| 138 | if os.path.exists(dfn):
|
---|
| 139 | os.remove(dfn)
|
---|
[391] | 140 | # Issue 18940: A file may not have been created if delay is True.
|
---|
| 141 | if os.path.exists(self.baseFilename):
|
---|
| 142 | os.rename(self.baseFilename, dfn)
|
---|
| 143 | if not self.delay:
|
---|
| 144 | self.stream = self._open()
|
---|
[2] | 145 |
|
---|
| 146 | def shouldRollover(self, record):
|
---|
| 147 | """
|
---|
| 148 | Determine if rollover should occur.
|
---|
| 149 |
|
---|
| 150 | Basically, see if the supplied record would cause the file to exceed
|
---|
| 151 | the size limit we have.
|
---|
| 152 | """
|
---|
| 153 | if self.stream is None: # delay was set...
|
---|
| 154 | self.stream = self._open()
|
---|
| 155 | if self.maxBytes > 0: # are we rolling over?
|
---|
| 156 | 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 | return 1
|
---|
| 160 | return 0
|
---|
| 161 |
|
---|
| 162 | class TimedRotatingFileHandler(BaseRotatingHandler):
|
---|
| 163 | """
|
---|
| 164 | Handler for logging to a file, rotating the log file at certain timed
|
---|
| 165 | intervals.
|
---|
| 166 |
|
---|
| 167 | If backupCount is > 0, when rollover is done, no more than backupCount
|
---|
| 168 | files are kept - the oldest ones are deleted.
|
---|
| 169 | """
|
---|
[391] | 170 | def __init__(self, filename, when='h', interval=1, backupCount=0, encoding=None, delay=False, utc=False):
|
---|
[2] | 171 | BaseRotatingHandler.__init__(self, filename, 'a', encoding, delay)
|
---|
[391] | 172 | self.when = when.upper()
|
---|
[2] | 173 | self.backupCount = backupCount
|
---|
| 174 | self.utc = utc
|
---|
| 175 | # Calculate the real rollover interval, which is just the number of
|
---|
| 176 | # seconds between rollovers. Also set the filename suffix used when
|
---|
| 177 | # a rollover occurs. Current 'when' events supported:
|
---|
| 178 | # S - Seconds
|
---|
| 179 | # M - Minutes
|
---|
| 180 | # H - Hours
|
---|
| 181 | # D - Days
|
---|
| 182 | # midnight - roll over at midnight
|
---|
| 183 | # W{0-6} - roll over on a certain day; 0 - Monday
|
---|
| 184 | #
|
---|
| 185 | # Case of the 'when' specifier is not important; lower or upper case
|
---|
| 186 | # will work.
|
---|
| 187 | if self.when == 'S':
|
---|
| 188 | self.interval = 1 # one second
|
---|
| 189 | 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 | elif self.when == 'M':
|
---|
| 192 | self.interval = 60 # one minute
|
---|
| 193 | self.suffix = "%Y-%m-%d_%H-%M"
|
---|
| 194 | self.extMatch = r"^\d{4}-\d{2}-\d{2}_\d{2}-\d{2}$"
|
---|
| 195 | elif self.when == 'H':
|
---|
| 196 | self.interval = 60 * 60 # one hour
|
---|
| 197 | self.suffix = "%Y-%m-%d_%H"
|
---|
| 198 | self.extMatch = r"^\d{4}-\d{2}-\d{2}_\d{2}$"
|
---|
| 199 | elif self.when == 'D' or self.when == 'MIDNIGHT':
|
---|
| 200 | self.interval = 60 * 60 * 24 # one day
|
---|
| 201 | self.suffix = "%Y-%m-%d"
|
---|
| 202 | self.extMatch = r"^\d{4}-\d{2}-\d{2}$"
|
---|
| 203 | elif self.when.startswith('W'):
|
---|
| 204 | self.interval = 60 * 60 * 24 * 7 # one week
|
---|
| 205 | if len(self.when) != 2:
|
---|
| 206 | raise ValueError("You must specify a day for weekly rollover from 0 to 6 (0 is Monday): %s" % self.when)
|
---|
| 207 | if self.when[1] < '0' or self.when[1] > '6':
|
---|
| 208 | raise ValueError("Invalid day specified for weekly rollover: %s" % self.when)
|
---|
| 209 | self.dayOfWeek = int(self.when[1])
|
---|
| 210 | self.suffix = "%Y-%m-%d"
|
---|
| 211 | self.extMatch = r"^\d{4}-\d{2}-\d{2}$"
|
---|
| 212 | else:
|
---|
| 213 | raise ValueError("Invalid rollover interval specified: %s" % self.when)
|
---|
| 214 |
|
---|
| 215 | self.extMatch = re.compile(self.extMatch)
|
---|
| 216 | self.interval = self.interval * interval # multiply by units requested
|
---|
[391] | 217 | if os.path.exists(filename):
|
---|
| 218 | t = os.stat(filename)[ST_MTIME]
|
---|
| 219 | else:
|
---|
| 220 | t = int(time.time())
|
---|
| 221 | self.rolloverAt = self.computeRollover(t)
|
---|
[2] | 222 |
|
---|
| 223 | def computeRollover(self, currentTime):
|
---|
| 224 | """
|
---|
| 225 | Work out the rollover time based on the specified time.
|
---|
| 226 | """
|
---|
| 227 | result = currentTime + self.interval
|
---|
| 228 | # If we are rolling over at midnight or weekly, then the interval is already known.
|
---|
| 229 | # What we need to figure out is WHEN the next interval is. In other words,
|
---|
| 230 | # if you are rolling over at midnight, then your base interval is 1 day,
|
---|
| 231 | # but you want to start that one day clock at midnight, not now. So, we
|
---|
| 232 | # have to fudge the rolloverAt value in order to trigger the first rollover
|
---|
| 233 | # at the right time. After that, the regular interval will take care of
|
---|
| 234 | # the rest. Note that this code doesn't care about leap seconds. :)
|
---|
| 235 | if self.when == 'MIDNIGHT' or self.when.startswith('W'):
|
---|
| 236 | # This could be done with less code, but I wanted it to be clear
|
---|
| 237 | if self.utc:
|
---|
| 238 | t = time.gmtime(currentTime)
|
---|
| 239 | else:
|
---|
| 240 | t = time.localtime(currentTime)
|
---|
| 241 | currentHour = t[3]
|
---|
| 242 | currentMinute = t[4]
|
---|
| 243 | currentSecond = t[5]
|
---|
| 244 | # r is the number of seconds left between now and midnight
|
---|
| 245 | r = _MIDNIGHT - ((currentHour * 60 + currentMinute) * 60 +
|
---|
| 246 | currentSecond)
|
---|
| 247 | result = currentTime + r
|
---|
| 248 | # If we are rolling over on a certain day, add in the number of days until
|
---|
| 249 | # the next rollover, but offset by 1 since we just calculated the time
|
---|
| 250 | # until the next day starts. There are three cases:
|
---|
| 251 | # Case 1) The day to rollover is today; in this case, do nothing
|
---|
| 252 | # Case 2) The day to rollover is further in the interval (i.e., today is
|
---|
| 253 | # day 2 (Wednesday) and rollover is on day 6 (Sunday). Days to
|
---|
| 254 | # next rollover is simply 6 - 2 - 1, or 3.
|
---|
| 255 | # Case 3) The day to rollover is behind us in the interval (i.e., today
|
---|
| 256 | # is day 5 (Saturday) and rollover is on day 3 (Thursday).
|
---|
| 257 | # Days to rollover is 6 - 5 + 3, or 4. In this case, it's the
|
---|
| 258 | # number of days left in the current week (1) plus the number
|
---|
| 259 | # of days in the next week until the rollover day (3).
|
---|
| 260 | # The calculations described in 2) and 3) above need to have a day added.
|
---|
| 261 | # This is because the above time calculation takes us to midnight on this
|
---|
| 262 | # day, i.e. the start of the next day.
|
---|
| 263 | if self.when.startswith('W'):
|
---|
| 264 | day = t[6] # 0 is Monday
|
---|
| 265 | if day != self.dayOfWeek:
|
---|
| 266 | if day < self.dayOfWeek:
|
---|
| 267 | daysToWait = self.dayOfWeek - day
|
---|
| 268 | else:
|
---|
| 269 | daysToWait = 6 - day + self.dayOfWeek + 1
|
---|
| 270 | newRolloverAt = result + (daysToWait * (60 * 60 * 24))
|
---|
| 271 | if not self.utc:
|
---|
| 272 | dstNow = t[-1]
|
---|
| 273 | dstAtRollover = time.localtime(newRolloverAt)[-1]
|
---|
| 274 | if dstNow != dstAtRollover:
|
---|
| 275 | if not dstNow: # DST kicks in before next rollover, so we need to deduct an hour
|
---|
[391] | 276 | addend = -3600
|
---|
[2] | 277 | else: # DST bows out before next rollover, so we need to add an hour
|
---|
[391] | 278 | addend = 3600
|
---|
| 279 | newRolloverAt += addend
|
---|
[2] | 280 | result = newRolloverAt
|
---|
| 281 | return result
|
---|
| 282 |
|
---|
| 283 | def shouldRollover(self, record):
|
---|
| 284 | """
|
---|
| 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 | t = int(time.time())
|
---|
| 291 | if t >= self.rolloverAt:
|
---|
| 292 | return 1
|
---|
| 293 | #print "No need to rollover: %d, %d" % (t, self.rolloverAt)
|
---|
| 294 | return 0
|
---|
| 295 |
|
---|
| 296 | def getFilesToDelete(self):
|
---|
| 297 | """
|
---|
| 298 | Determine the files to delete when rolling over.
|
---|
| 299 |
|
---|
| 300 | More specific than the earlier method, which just used glob.glob().
|
---|
| 301 | """
|
---|
| 302 | dirName, baseName = os.path.split(self.baseFilename)
|
---|
| 303 | fileNames = os.listdir(dirName)
|
---|
| 304 | result = []
|
---|
| 305 | prefix = baseName + "."
|
---|
| 306 | plen = len(prefix)
|
---|
| 307 | for fileName in fileNames:
|
---|
| 308 | if fileName[:plen] == prefix:
|
---|
| 309 | suffix = fileName[plen:]
|
---|
| 310 | if self.extMatch.match(suffix):
|
---|
| 311 | result.append(os.path.join(dirName, fileName))
|
---|
| 312 | result.sort()
|
---|
| 313 | if len(result) < self.backupCount:
|
---|
| 314 | result = []
|
---|
| 315 | else:
|
---|
| 316 | result = result[:len(result) - self.backupCount]
|
---|
| 317 | return result
|
---|
| 318 |
|
---|
| 319 | def doRollover(self):
|
---|
| 320 | """
|
---|
| 321 | do a rollover; in this case, a date/time stamp is appended to the filename
|
---|
| 322 | when the rollover happens. However, you want the file to be named for the
|
---|
| 323 | start of the interval, not the current time. If there is a backup count,
|
---|
| 324 | then we have to get a list of matching filenames, sort them and remove
|
---|
| 325 | the one with the oldest suffix.
|
---|
| 326 | """
|
---|
| 327 | if self.stream:
|
---|
| 328 | self.stream.close()
|
---|
[391] | 329 | self.stream = None
|
---|
[2] | 330 | # get the time that this sequence started at and make it a TimeTuple
|
---|
[391] | 331 | currentTime = int(time.time())
|
---|
| 332 | dstNow = time.localtime(currentTime)[-1]
|
---|
[2] | 333 | t = self.rolloverAt - self.interval
|
---|
| 334 | if self.utc:
|
---|
| 335 | timeTuple = time.gmtime(t)
|
---|
| 336 | else:
|
---|
| 337 | timeTuple = time.localtime(t)
|
---|
[391] | 338 | dstThen = timeTuple[-1]
|
---|
| 339 | if dstNow != dstThen:
|
---|
| 340 | if dstNow:
|
---|
| 341 | addend = 3600
|
---|
| 342 | else:
|
---|
| 343 | addend = -3600
|
---|
| 344 | timeTuple = time.localtime(t + addend)
|
---|
[2] | 345 | dfn = self.baseFilename + "." + time.strftime(self.suffix, timeTuple)
|
---|
| 346 | if os.path.exists(dfn):
|
---|
| 347 | os.remove(dfn)
|
---|
[391] | 348 | # Issue 18940: A file may not have been created if delay is True.
|
---|
| 349 | if os.path.exists(self.baseFilename):
|
---|
| 350 | os.rename(self.baseFilename, dfn)
|
---|
[2] | 351 | if self.backupCount > 0:
|
---|
| 352 | for s in self.getFilesToDelete():
|
---|
| 353 | os.remove(s)
|
---|
[391] | 354 | if not self.delay:
|
---|
| 355 | self.stream = self._open()
|
---|
[2] | 356 | newRolloverAt = self.computeRollover(currentTime)
|
---|
| 357 | while newRolloverAt <= currentTime:
|
---|
| 358 | newRolloverAt = newRolloverAt + self.interval
|
---|
| 359 | #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 | dstAtRollover = time.localtime(newRolloverAt)[-1]
|
---|
| 362 | if dstNow != dstAtRollover:
|
---|
| 363 | if not dstNow: # DST kicks in before next rollover, so we need to deduct an hour
|
---|
[391] | 364 | addend = -3600
|
---|
[2] | 365 | else: # DST bows out before next rollover, so we need to add an hour
|
---|
[391] | 366 | addend = 3600
|
---|
| 367 | newRolloverAt += addend
|
---|
[2] | 368 | self.rolloverAt = newRolloverAt
|
---|
| 369 |
|
---|
| 370 | class WatchedFileHandler(logging.FileHandler):
|
---|
| 371 | """
|
---|
| 372 | A handler for logging to a file, which watches the file
|
---|
| 373 | to see if it has changed while in use. This can happen because of
|
---|
| 374 | usage of programs such as newsyslog and logrotate which perform
|
---|
| 375 | log file rotation. This handler, intended for use under Unix,
|
---|
| 376 | watches the file to see if it has changed since the last emit.
|
---|
| 377 | (A file has changed if its device or inode have changed.)
|
---|
| 378 | If it has changed, the old file stream is closed, and the file
|
---|
| 379 | opened to get a new stream.
|
---|
| 380 |
|
---|
| 381 | This handler is not appropriate for use under Windows, because
|
---|
| 382 | under Windows open files cannot be moved or renamed - logging
|
---|
| 383 | opens the files with exclusive locks - and so there is no need
|
---|
| 384 | for such a handler. Furthermore, ST_INO is not supported under
|
---|
| 385 | Windows; stat always returns zero for this value.
|
---|
| 386 |
|
---|
| 387 | This handler is based on a suggestion and patch by Chad J.
|
---|
| 388 | Schroeder.
|
---|
| 389 | """
|
---|
| 390 | def __init__(self, filename, mode='a', encoding=None, delay=0):
|
---|
| 391 | logging.FileHandler.__init__(self, filename, mode, encoding, delay)
|
---|
[391] | 392 | self.dev, self.ino = -1, -1
|
---|
| 393 | self._statstream()
|
---|
[2] | 394 |
|
---|
[391] | 395 | def _statstream(self):
|
---|
| 396 | if self.stream:
|
---|
| 397 | sres = os.fstat(self.stream.fileno())
|
---|
| 398 | self.dev, self.ino = sres[ST_DEV], sres[ST_INO]
|
---|
| 399 |
|
---|
[2] | 400 | def emit(self, record):
|
---|
| 401 | """
|
---|
| 402 | Emit a record.
|
---|
| 403 |
|
---|
| 404 | First check if the underlying file has changed, and if it
|
---|
| 405 | has, close the old stream and reopen the file to get the
|
---|
| 406 | current stream.
|
---|
| 407 | """
|
---|
[391] | 408 | # Reduce the chance of race conditions by stat'ing by path only
|
---|
| 409 | # once and then fstat'ing our new fd if we opened a new log stream.
|
---|
| 410 | # See issue #14632: Thanks to John Mulligan for the problem report
|
---|
| 411 | # and patch.
|
---|
| 412 | try:
|
---|
| 413 | # stat the file by path, checking for existence
|
---|
| 414 | sres = os.stat(self.baseFilename)
|
---|
| 415 | except OSError as err:
|
---|
| 416 | if err.errno == errno.ENOENT:
|
---|
| 417 | sres = None
|
---|
| 418 | else:
|
---|
| 419 | raise
|
---|
| 420 | # compare file system stat with that of our stream file handle
|
---|
| 421 | if not sres or sres[ST_DEV] != self.dev or sres[ST_INO] != self.ino:
|
---|
| 422 | if self.stream is not None:
|
---|
| 423 | # we have an open file handle, clean it up
|
---|
| 424 | self.stream.flush()
|
---|
| 425 | self.stream.close()
|
---|
| 426 | # open a new file handle and get new stat info from that fd
|
---|
| 427 | self.stream = self._open()
|
---|
| 428 | self._statstream()
|
---|
[2] | 429 | logging.FileHandler.emit(self, record)
|
---|
| 430 |
|
---|
| 431 | class SocketHandler(logging.Handler):
|
---|
| 432 | """
|
---|
| 433 | A handler class which writes logging records, in pickle format, to
|
---|
| 434 | a streaming socket. The socket is kept open across logging calls.
|
---|
| 435 | If the peer resets it, an attempt is made to reconnect on the next call.
|
---|
| 436 | The pickle which is sent is that of the LogRecord's attribute dictionary
|
---|
| 437 | (__dict__), so that the receiver does not need to have the logging module
|
---|
| 438 | installed in order to process the logging event.
|
---|
| 439 |
|
---|
| 440 | To unpickle the record at the receiving end into a LogRecord, use the
|
---|
| 441 | makeLogRecord function.
|
---|
| 442 | """
|
---|
| 443 |
|
---|
| 444 | def __init__(self, host, port):
|
---|
| 445 | """
|
---|
| 446 | Initializes the handler with a specific host address and port.
|
---|
| 447 |
|
---|
| 448 | The attribute 'closeOnError' is set to 1 - which means that if
|
---|
| 449 | a socket error occurs, the socket is silently closed and then
|
---|
| 450 | reopened on the next logging call.
|
---|
| 451 | """
|
---|
| 452 | logging.Handler.__init__(self)
|
---|
| 453 | self.host = host
|
---|
| 454 | self.port = port
|
---|
| 455 | self.sock = None
|
---|
| 456 | self.closeOnError = 0
|
---|
| 457 | self.retryTime = None
|
---|
| 458 | #
|
---|
| 459 | # Exponential backoff parameters.
|
---|
| 460 | #
|
---|
| 461 | self.retryStart = 1.0
|
---|
| 462 | self.retryMax = 30.0
|
---|
| 463 | self.retryFactor = 2.0
|
---|
| 464 |
|
---|
| 465 | def makeSocket(self, timeout=1):
|
---|
| 466 | """
|
---|
| 467 | A factory method which allows subclasses to define the precise
|
---|
| 468 | type of socket they want.
|
---|
| 469 | """
|
---|
| 470 | s = socket.socket(socket.AF_INET, socket.SOCK_STREAM)
|
---|
| 471 | if hasattr(s, 'settimeout'):
|
---|
| 472 | s.settimeout(timeout)
|
---|
| 473 | s.connect((self.host, self.port))
|
---|
| 474 | return s
|
---|
| 475 |
|
---|
| 476 | def createSocket(self):
|
---|
| 477 | """
|
---|
| 478 | Try to create a socket, using an exponential backoff with
|
---|
| 479 | a max retry time. Thanks to Robert Olson for the original patch
|
---|
| 480 | (SF #815911) which has been slightly refactored.
|
---|
| 481 | """
|
---|
| 482 | now = time.time()
|
---|
| 483 | # Either retryTime is None, in which case this
|
---|
| 484 | # is the first time back after a disconnect, or
|
---|
| 485 | # we've waited long enough.
|
---|
| 486 | if self.retryTime is None:
|
---|
| 487 | attempt = 1
|
---|
| 488 | else:
|
---|
| 489 | attempt = (now >= self.retryTime)
|
---|
| 490 | if attempt:
|
---|
| 491 | try:
|
---|
| 492 | self.sock = self.makeSocket()
|
---|
| 493 | self.retryTime = None # next time, no delay before trying
|
---|
| 494 | except socket.error:
|
---|
| 495 | #Creation failed, so set the retry time and return.
|
---|
| 496 | if self.retryTime is None:
|
---|
| 497 | self.retryPeriod = self.retryStart
|
---|
| 498 | else:
|
---|
| 499 | self.retryPeriod = self.retryPeriod * self.retryFactor
|
---|
| 500 | if self.retryPeriod > self.retryMax:
|
---|
| 501 | self.retryPeriod = self.retryMax
|
---|
| 502 | self.retryTime = now + self.retryPeriod
|
---|
| 503 |
|
---|
| 504 | def send(self, s):
|
---|
| 505 | """
|
---|
| 506 | Send a pickled string to the socket.
|
---|
| 507 |
|
---|
| 508 | This function allows for partial sends which can happen when the
|
---|
| 509 | network is busy.
|
---|
| 510 | """
|
---|
| 511 | if self.sock is None:
|
---|
| 512 | self.createSocket()
|
---|
| 513 | #self.sock can be None either because we haven't reached the retry
|
---|
| 514 | #time yet, or because we have reached the retry time and retried,
|
---|
| 515 | #but are still unable to connect.
|
---|
| 516 | if self.sock:
|
---|
| 517 | try:
|
---|
| 518 | if hasattr(self.sock, "sendall"):
|
---|
| 519 | self.sock.sendall(s)
|
---|
| 520 | else:
|
---|
| 521 | sentsofar = 0
|
---|
| 522 | left = len(s)
|
---|
| 523 | while left > 0:
|
---|
| 524 | sent = self.sock.send(s[sentsofar:])
|
---|
| 525 | sentsofar = sentsofar + sent
|
---|
| 526 | left = left - sent
|
---|
| 527 | except socket.error:
|
---|
| 528 | self.sock.close()
|
---|
| 529 | self.sock = None # so we can call createSocket next time
|
---|
| 530 |
|
---|
| 531 | def makePickle(self, record):
|
---|
| 532 | """
|
---|
| 533 | Pickles the record in binary format with a length prefix, and
|
---|
| 534 | returns it ready for transmission across the socket.
|
---|
| 535 | """
|
---|
| 536 | ei = record.exc_info
|
---|
| 537 | if ei:
|
---|
[391] | 538 | # just to get traceback text into record.exc_text ...
|
---|
| 539 | dummy = self.format(record)
|
---|
[2] | 540 | record.exc_info = None # to avoid Unpickleable error
|
---|
[391] | 541 | # See issue #14436: If msg or args are objects, they may not be
|
---|
| 542 | # available on the receiving end. So we convert the msg % args
|
---|
| 543 | # to a string, save it as msg and zap the args.
|
---|
| 544 | d = dict(record.__dict__)
|
---|
| 545 | d['msg'] = record.getMessage()
|
---|
| 546 | d['args'] = None
|
---|
| 547 | s = cPickle.dumps(d, 1)
|
---|
[2] | 548 | if ei:
|
---|
| 549 | record.exc_info = ei # for next handler
|
---|
| 550 | slen = struct.pack(">L", len(s))
|
---|
| 551 | return slen + s
|
---|
| 552 |
|
---|
| 553 | def handleError(self, record):
|
---|
| 554 | """
|
---|
| 555 | Handle an error during logging.
|
---|
| 556 |
|
---|
| 557 | An error has occurred during logging. Most likely cause -
|
---|
| 558 | connection lost. Close the socket so that we can retry on the
|
---|
| 559 | next event.
|
---|
| 560 | """
|
---|
| 561 | if self.closeOnError and self.sock:
|
---|
| 562 | self.sock.close()
|
---|
| 563 | self.sock = None #try to reconnect next time
|
---|
| 564 | else:
|
---|
| 565 | logging.Handler.handleError(self, record)
|
---|
| 566 |
|
---|
| 567 | def emit(self, record):
|
---|
| 568 | """
|
---|
| 569 | Emit a record.
|
---|
| 570 |
|
---|
| 571 | Pickles the record and writes it to the socket in binary format.
|
---|
| 572 | If there is an error with the socket, silently drop the packet.
|
---|
| 573 | If there was a problem with the socket, re-establishes the
|
---|
| 574 | socket.
|
---|
| 575 | """
|
---|
| 576 | try:
|
---|
| 577 | s = self.makePickle(record)
|
---|
| 578 | self.send(s)
|
---|
| 579 | except (KeyboardInterrupt, SystemExit):
|
---|
| 580 | raise
|
---|
| 581 | except:
|
---|
| 582 | self.handleError(record)
|
---|
| 583 |
|
---|
| 584 | def close(self):
|
---|
| 585 | """
|
---|
| 586 | Closes the socket.
|
---|
| 587 | """
|
---|
[391] | 588 | self.acquire()
|
---|
| 589 | try:
|
---|
| 590 | if self.sock:
|
---|
| 591 | self.sock.close()
|
---|
| 592 | self.sock = None
|
---|
| 593 | finally:
|
---|
| 594 | self.release()
|
---|
[2] | 595 | logging.Handler.close(self)
|
---|
| 596 |
|
---|
| 597 | class DatagramHandler(SocketHandler):
|
---|
| 598 | """
|
---|
| 599 | A handler class which writes logging records, in pickle format, to
|
---|
| 600 | a datagram socket. The pickle which is sent is that of the LogRecord's
|
---|
| 601 | attribute dictionary (__dict__), so that the receiver does not need to
|
---|
| 602 | have the logging module installed in order to process the logging event.
|
---|
| 603 |
|
---|
| 604 | To unpickle the record at the receiving end into a LogRecord, use the
|
---|
| 605 | makeLogRecord function.
|
---|
| 606 |
|
---|
| 607 | """
|
---|
| 608 | def __init__(self, host, port):
|
---|
| 609 | """
|
---|
| 610 | Initializes the handler with a specific host address and port.
|
---|
| 611 | """
|
---|
| 612 | SocketHandler.__init__(self, host, port)
|
---|
| 613 | self.closeOnError = 0
|
---|
| 614 |
|
---|
| 615 | def makeSocket(self):
|
---|
| 616 | """
|
---|
| 617 | The factory method of SocketHandler is here overridden to create
|
---|
| 618 | a UDP socket (SOCK_DGRAM).
|
---|
| 619 | """
|
---|
| 620 | s = socket.socket(socket.AF_INET, socket.SOCK_DGRAM)
|
---|
| 621 | return s
|
---|
| 622 |
|
---|
| 623 | def send(self, s):
|
---|
| 624 | """
|
---|
| 625 | Send a pickled string to a socket.
|
---|
| 626 |
|
---|
| 627 | This function no longer allows for partial sends which can happen
|
---|
| 628 | when the network is busy - UDP does not guarantee delivery and
|
---|
| 629 | can deliver packets out of sequence.
|
---|
| 630 | """
|
---|
| 631 | if self.sock is None:
|
---|
| 632 | self.createSocket()
|
---|
| 633 | self.sock.sendto(s, (self.host, self.port))
|
---|
| 634 |
|
---|
| 635 | class SysLogHandler(logging.Handler):
|
---|
| 636 | """
|
---|
| 637 | A handler class which sends formatted logging records to a syslog
|
---|
| 638 | server. Based on Sam Rushing's syslog module:
|
---|
| 639 | http://www.nightmare.com/squirl/python-ext/misc/syslog.py
|
---|
| 640 | Contributed by Nicolas Untz (after which minor refactoring changes
|
---|
| 641 | have been made).
|
---|
| 642 | """
|
---|
| 643 |
|
---|
| 644 | # from <linux/sys/syslog.h>:
|
---|
| 645 | # ======================================================================
|
---|
| 646 | # priorities/facilities are encoded into a single 32-bit quantity, where
|
---|
| 647 | # the bottom 3 bits are the priority (0-7) and the top 28 bits are the
|
---|
| 648 | # facility (0-big number). Both the priorities and the facilities map
|
---|
| 649 | # roughly one-to-one to strings in the syslogd(8) source code. This
|
---|
| 650 | # mapping is included in this file.
|
---|
| 651 | #
|
---|
| 652 | # priorities (these are ordered)
|
---|
| 653 |
|
---|
| 654 | LOG_EMERG = 0 # system is unusable
|
---|
| 655 | LOG_ALERT = 1 # action must be taken immediately
|
---|
| 656 | LOG_CRIT = 2 # critical conditions
|
---|
| 657 | LOG_ERR = 3 # error conditions
|
---|
| 658 | LOG_WARNING = 4 # warning conditions
|
---|
| 659 | LOG_NOTICE = 5 # normal but significant condition
|
---|
| 660 | LOG_INFO = 6 # informational
|
---|
| 661 | LOG_DEBUG = 7 # debug-level messages
|
---|
| 662 |
|
---|
| 663 | # facility codes
|
---|
| 664 | LOG_KERN = 0 # kernel messages
|
---|
| 665 | LOG_USER = 1 # random user-level messages
|
---|
| 666 | LOG_MAIL = 2 # mail system
|
---|
| 667 | LOG_DAEMON = 3 # system daemons
|
---|
| 668 | LOG_AUTH = 4 # security/authorization messages
|
---|
| 669 | LOG_SYSLOG = 5 # messages generated internally by syslogd
|
---|
| 670 | LOG_LPR = 6 # line printer subsystem
|
---|
| 671 | LOG_NEWS = 7 # network news subsystem
|
---|
| 672 | LOG_UUCP = 8 # UUCP subsystem
|
---|
| 673 | LOG_CRON = 9 # clock daemon
|
---|
[391] | 674 | LOG_AUTHPRIV = 10 # security/authorization messages (private)
|
---|
| 675 | LOG_FTP = 11 # FTP daemon
|
---|
[2] | 676 |
|
---|
| 677 | # other codes through 15 reserved for system use
|
---|
| 678 | LOG_LOCAL0 = 16 # reserved for local use
|
---|
| 679 | LOG_LOCAL1 = 17 # reserved for local use
|
---|
| 680 | LOG_LOCAL2 = 18 # reserved for local use
|
---|
| 681 | LOG_LOCAL3 = 19 # reserved for local use
|
---|
| 682 | LOG_LOCAL4 = 20 # reserved for local use
|
---|
| 683 | LOG_LOCAL5 = 21 # reserved for local use
|
---|
| 684 | LOG_LOCAL6 = 22 # reserved for local use
|
---|
| 685 | LOG_LOCAL7 = 23 # reserved for local use
|
---|
| 686 |
|
---|
| 687 | priority_names = {
|
---|
| 688 | "alert": LOG_ALERT,
|
---|
| 689 | "crit": LOG_CRIT,
|
---|
| 690 | "critical": LOG_CRIT,
|
---|
| 691 | "debug": LOG_DEBUG,
|
---|
| 692 | "emerg": LOG_EMERG,
|
---|
| 693 | "err": LOG_ERR,
|
---|
| 694 | "error": LOG_ERR, # DEPRECATED
|
---|
| 695 | "info": LOG_INFO,
|
---|
| 696 | "notice": LOG_NOTICE,
|
---|
| 697 | "panic": LOG_EMERG, # DEPRECATED
|
---|
| 698 | "warn": LOG_WARNING, # DEPRECATED
|
---|
| 699 | "warning": LOG_WARNING,
|
---|
| 700 | }
|
---|
| 701 |
|
---|
| 702 | facility_names = {
|
---|
| 703 | "auth": LOG_AUTH,
|
---|
| 704 | "authpriv": LOG_AUTHPRIV,
|
---|
| 705 | "cron": LOG_CRON,
|
---|
| 706 | "daemon": LOG_DAEMON,
|
---|
[391] | 707 | "ftp": LOG_FTP,
|
---|
[2] | 708 | "kern": LOG_KERN,
|
---|
| 709 | "lpr": LOG_LPR,
|
---|
| 710 | "mail": LOG_MAIL,
|
---|
| 711 | "news": LOG_NEWS,
|
---|
| 712 | "security": LOG_AUTH, # DEPRECATED
|
---|
| 713 | "syslog": LOG_SYSLOG,
|
---|
| 714 | "user": LOG_USER,
|
---|
| 715 | "uucp": LOG_UUCP,
|
---|
| 716 | "local0": LOG_LOCAL0,
|
---|
| 717 | "local1": LOG_LOCAL1,
|
---|
| 718 | "local2": LOG_LOCAL2,
|
---|
| 719 | "local3": LOG_LOCAL3,
|
---|
| 720 | "local4": LOG_LOCAL4,
|
---|
| 721 | "local5": LOG_LOCAL5,
|
---|
| 722 | "local6": LOG_LOCAL6,
|
---|
| 723 | "local7": LOG_LOCAL7,
|
---|
| 724 | }
|
---|
| 725 |
|
---|
| 726 | #The map below appears to be trivially lowercasing the key. However,
|
---|
| 727 | #there's more to it than meets the eye - in some locales, lowercasing
|
---|
| 728 | #gives unexpected results. See SF #1524081: in the Turkish locale,
|
---|
| 729 | #"INFO".lower() != "info"
|
---|
| 730 | priority_map = {
|
---|
| 731 | "DEBUG" : "debug",
|
---|
| 732 | "INFO" : "info",
|
---|
| 733 | "WARNING" : "warning",
|
---|
| 734 | "ERROR" : "error",
|
---|
| 735 | "CRITICAL" : "critical"
|
---|
| 736 | }
|
---|
| 737 |
|
---|
[391] | 738 | def __init__(self, address=('localhost', SYSLOG_UDP_PORT),
|
---|
| 739 | facility=LOG_USER, socktype=None):
|
---|
[2] | 740 | """
|
---|
| 741 | Initialize a handler.
|
---|
| 742 |
|
---|
| 743 | If address is specified as a string, a UNIX socket is used. To log to a
|
---|
| 744 | local syslogd, "SysLogHandler(address="/dev/log")" can be used.
|
---|
[391] | 745 | If facility is not specified, LOG_USER is used. If socktype is
|
---|
| 746 | specified as socket.SOCK_DGRAM or socket.SOCK_STREAM, that specific
|
---|
| 747 | socket type will be used. For Unix sockets, you can also specify a
|
---|
| 748 | socktype of None, in which case socket.SOCK_DGRAM will be used, falling
|
---|
| 749 | back to socket.SOCK_STREAM.
|
---|
[2] | 750 | """
|
---|
| 751 | logging.Handler.__init__(self)
|
---|
| 752 |
|
---|
| 753 | self.address = address
|
---|
| 754 | self.facility = facility
|
---|
[391] | 755 | self.socktype = socktype
|
---|
| 756 |
|
---|
| 757 | if isinstance(address, basestring):
|
---|
[2] | 758 | self.unixsocket = 1
|
---|
| 759 | self._connect_unixsocket(address)
|
---|
| 760 | else:
|
---|
| 761 | self.unixsocket = 0
|
---|
[391] | 762 | if socktype is None:
|
---|
| 763 | socktype = socket.SOCK_DGRAM
|
---|
| 764 | self.socket = socket.socket(socket.AF_INET, socktype)
|
---|
| 765 | if socktype == socket.SOCK_STREAM:
|
---|
| 766 | self.socket.connect(address)
|
---|
| 767 | self.socktype = socktype
|
---|
[2] | 768 | self.formatter = None
|
---|
| 769 |
|
---|
| 770 | def _connect_unixsocket(self, address):
|
---|
[391] | 771 | use_socktype = self.socktype
|
---|
| 772 | if use_socktype is None:
|
---|
| 773 | use_socktype = socket.SOCK_DGRAM
|
---|
| 774 | self.socket = socket.socket(socket.AF_UNIX, use_socktype)
|
---|
[2] | 775 | try:
|
---|
| 776 | self.socket.connect(address)
|
---|
[391] | 777 | # it worked, so set self.socktype to the used type
|
---|
| 778 | self.socktype = use_socktype
|
---|
[2] | 779 | except socket.error:
|
---|
| 780 | self.socket.close()
|
---|
[391] | 781 | if self.socktype is not None:
|
---|
| 782 | # user didn't specify falling back, so fail
|
---|
| 783 | raise
|
---|
| 784 | use_socktype = socket.SOCK_STREAM
|
---|
| 785 | self.socket = socket.socket(socket.AF_UNIX, use_socktype)
|
---|
| 786 | try:
|
---|
| 787 | self.socket.connect(address)
|
---|
| 788 | # it worked, so set self.socktype to the used type
|
---|
| 789 | self.socktype = use_socktype
|
---|
| 790 | except socket.error:
|
---|
| 791 | self.socket.close()
|
---|
| 792 | raise
|
---|
[2] | 793 |
|
---|
| 794 | # curious: when talking to the unix-domain '/dev/log' socket, a
|
---|
| 795 | # zero-terminator seems to be required. this string is placed
|
---|
| 796 | # into a class variable so that it can be overridden if
|
---|
| 797 | # necessary.
|
---|
| 798 | log_format_string = '<%d>%s\000'
|
---|
| 799 |
|
---|
| 800 | def encodePriority(self, facility, priority):
|
---|
| 801 | """
|
---|
| 802 | Encode the facility and priority. You can pass in strings or
|
---|
| 803 | integers - if strings are passed, the facility_names and
|
---|
| 804 | priority_names mapping dictionaries are used to convert them to
|
---|
| 805 | integers.
|
---|
| 806 | """
|
---|
[391] | 807 | if isinstance(facility, basestring):
|
---|
[2] | 808 | facility = self.facility_names[facility]
|
---|
[391] | 809 | if isinstance(priority, basestring):
|
---|
[2] | 810 | priority = self.priority_names[priority]
|
---|
| 811 | return (facility << 3) | priority
|
---|
| 812 |
|
---|
| 813 | def close (self):
|
---|
| 814 | """
|
---|
| 815 | Closes the socket.
|
---|
| 816 | """
|
---|
[391] | 817 | self.acquire()
|
---|
| 818 | try:
|
---|
| 819 | if self.unixsocket:
|
---|
| 820 | self.socket.close()
|
---|
| 821 | finally:
|
---|
| 822 | self.release()
|
---|
[2] | 823 | logging.Handler.close(self)
|
---|
| 824 |
|
---|
| 825 | def mapPriority(self, levelName):
|
---|
| 826 | """
|
---|
| 827 | Map a logging level name to a key in the priority_names map.
|
---|
| 828 | This is useful in two scenarios: when custom levels are being
|
---|
| 829 | used, and in the case where you can't do a straightforward
|
---|
| 830 | mapping by lowercasing the logging level name because of locale-
|
---|
| 831 | specific issues (see SF #1524081).
|
---|
| 832 | """
|
---|
| 833 | return self.priority_map.get(levelName, "warning")
|
---|
| 834 |
|
---|
| 835 | def emit(self, record):
|
---|
| 836 | """
|
---|
| 837 | Emit a record.
|
---|
| 838 |
|
---|
| 839 | The record is formatted, and then sent to the syslog server. If
|
---|
| 840 | exception information is present, it is NOT sent to the server.
|
---|
| 841 | """
|
---|
[391] | 842 | msg = self.format(record) + '\000'
|
---|
[2] | 843 | """
|
---|
| 844 | We need to convert record level to lowercase, maybe this will
|
---|
| 845 | change in the future.
|
---|
| 846 | """
|
---|
[391] | 847 | prio = '<%d>' % self.encodePriority(self.facility,
|
---|
| 848 | self.mapPriority(record.levelname))
|
---|
| 849 | # Message is a string. Convert to bytes as required by RFC 5424
|
---|
| 850 | if type(msg) is unicode:
|
---|
| 851 | msg = msg.encode('utf-8')
|
---|
| 852 | msg = prio + msg
|
---|
[2] | 853 | try:
|
---|
| 854 | if self.unixsocket:
|
---|
| 855 | try:
|
---|
| 856 | self.socket.send(msg)
|
---|
| 857 | except socket.error:
|
---|
[391] | 858 | self.socket.close() # See issue 17981
|
---|
[2] | 859 | self._connect_unixsocket(self.address)
|
---|
| 860 | self.socket.send(msg)
|
---|
[391] | 861 | elif self.socktype == socket.SOCK_DGRAM:
|
---|
| 862 | self.socket.sendto(msg, self.address)
|
---|
[2] | 863 | else:
|
---|
[391] | 864 | self.socket.sendall(msg)
|
---|
[2] | 865 | except (KeyboardInterrupt, SystemExit):
|
---|
| 866 | raise
|
---|
| 867 | except:
|
---|
| 868 | self.handleError(record)
|
---|
| 869 |
|
---|
| 870 | class SMTPHandler(logging.Handler):
|
---|
| 871 | """
|
---|
| 872 | A handler class which sends an SMTP email for each logging event.
|
---|
| 873 | """
|
---|
[391] | 874 | def __init__(self, mailhost, fromaddr, toaddrs, subject,
|
---|
| 875 | credentials=None, secure=None):
|
---|
[2] | 876 | """
|
---|
| 877 | Initialize the handler.
|
---|
| 878 |
|
---|
| 879 | Initialize the instance with the from and to addresses and subject
|
---|
| 880 | line of the email. To specify a non-standard SMTP port, use the
|
---|
| 881 | (host, port) tuple format for the mailhost argument. To specify
|
---|
| 882 | authentication credentials, supply a (username, password) tuple
|
---|
[391] | 883 | for the credentials argument. To specify the use of a secure
|
---|
| 884 | protocol (TLS), pass in a tuple for the secure argument. This will
|
---|
| 885 | only be used when authentication credentials are supplied. The tuple
|
---|
| 886 | will be either an empty tuple, or a single-value tuple with the name
|
---|
| 887 | of a keyfile, or a 2-value tuple with the names of the keyfile and
|
---|
| 888 | certificate file. (This tuple is passed to the `starttls` method).
|
---|
[2] | 889 | """
|
---|
| 890 | logging.Handler.__init__(self)
|
---|
[391] | 891 | if isinstance(mailhost, tuple):
|
---|
[2] | 892 | self.mailhost, self.mailport = mailhost
|
---|
| 893 | else:
|
---|
| 894 | self.mailhost, self.mailport = mailhost, None
|
---|
[391] | 895 | if isinstance(credentials, tuple):
|
---|
[2] | 896 | self.username, self.password = credentials
|
---|
| 897 | else:
|
---|
| 898 | self.username = None
|
---|
| 899 | self.fromaddr = fromaddr
|
---|
[391] | 900 | if isinstance(toaddrs, basestring):
|
---|
[2] | 901 | toaddrs = [toaddrs]
|
---|
| 902 | self.toaddrs = toaddrs
|
---|
| 903 | self.subject = subject
|
---|
[391] | 904 | self.secure = secure
|
---|
| 905 | self._timeout = 5.0
|
---|
[2] | 906 |
|
---|
| 907 | def getSubject(self, record):
|
---|
| 908 | """
|
---|
| 909 | Determine the subject for the email.
|
---|
| 910 |
|
---|
| 911 | If you want to specify a subject line which is record-dependent,
|
---|
| 912 | override this method.
|
---|
| 913 | """
|
---|
| 914 | return self.subject
|
---|
| 915 |
|
---|
| 916 | def emit(self, record):
|
---|
| 917 | """
|
---|
| 918 | Emit a record.
|
---|
| 919 |
|
---|
| 920 | Format the record and send it to the specified addressees.
|
---|
| 921 | """
|
---|
| 922 | try:
|
---|
| 923 | import smtplib
|
---|
[391] | 924 | from email.utils import formatdate
|
---|
[2] | 925 | port = self.mailport
|
---|
| 926 | if not port:
|
---|
| 927 | port = smtplib.SMTP_PORT
|
---|
[391] | 928 | smtp = smtplib.SMTP(self.mailhost, port, timeout=self._timeout)
|
---|
[2] | 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,
|
---|
[391] | 932 | ",".join(self.toaddrs),
|
---|
[2] | 933 | self.getSubject(record),
|
---|
| 934 | formatdate(), msg)
|
---|
| 935 | if self.username:
|
---|
[391] | 936 | if self.secure is not None:
|
---|
| 937 | smtp.ehlo()
|
---|
| 938 | smtp.starttls(*self.secure)
|
---|
| 939 | smtp.ehlo()
|
---|
[2] | 940 | smtp.login(self.username, self.password)
|
---|
| 941 | smtp.sendmail(self.fromaddr, self.toaddrs, msg)
|
---|
| 942 | smtp.quit()
|
---|
| 943 | except (KeyboardInterrupt, SystemExit):
|
---|
| 944 | raise
|
---|
| 945 | except:
|
---|
| 946 | self.handleError(record)
|
---|
| 947 |
|
---|
| 948 | class NTEventLogHandler(logging.Handler):
|
---|
| 949 | """
|
---|
| 950 | A handler class which sends events to the NT Event Log. Adds a
|
---|
| 951 | registry entry for the specified application name. If no dllname is
|
---|
| 952 | provided, win32service.pyd (which contains some basic message
|
---|
| 953 | placeholders) is used. Note that use of these placeholders will make
|
---|
| 954 | your event logs big, as the entire message source is held in the log.
|
---|
| 955 | If you want slimmer logs, you have to pass in the name of your own DLL
|
---|
| 956 | which contains the message definitions you want to use in the event log.
|
---|
| 957 | """
|
---|
| 958 | def __init__(self, appname, dllname=None, logtype="Application"):
|
---|
| 959 | logging.Handler.__init__(self)
|
---|
| 960 | try:
|
---|
| 961 | import win32evtlogutil, win32evtlog
|
---|
| 962 | self.appname = appname
|
---|
| 963 | self._welu = win32evtlogutil
|
---|
| 964 | if not dllname:
|
---|
| 965 | dllname = os.path.split(self._welu.__file__)
|
---|
| 966 | dllname = os.path.split(dllname[0])
|
---|
| 967 | dllname = os.path.join(dllname[0], r'win32service.pyd')
|
---|
| 968 | self.dllname = dllname
|
---|
| 969 | self.logtype = logtype
|
---|
| 970 | self._welu.AddSourceToRegistry(appname, dllname, logtype)
|
---|
| 971 | self.deftype = win32evtlog.EVENTLOG_ERROR_TYPE
|
---|
| 972 | self.typemap = {
|
---|
| 973 | logging.DEBUG : win32evtlog.EVENTLOG_INFORMATION_TYPE,
|
---|
| 974 | logging.INFO : win32evtlog.EVENTLOG_INFORMATION_TYPE,
|
---|
| 975 | logging.WARNING : win32evtlog.EVENTLOG_WARNING_TYPE,
|
---|
| 976 | logging.ERROR : win32evtlog.EVENTLOG_ERROR_TYPE,
|
---|
| 977 | logging.CRITICAL: win32evtlog.EVENTLOG_ERROR_TYPE,
|
---|
| 978 | }
|
---|
| 979 | except ImportError:
|
---|
[391] | 980 | print("The Python Win32 extensions for NT (service, event "\
|
---|
| 981 | "logging) appear not to be available.")
|
---|
[2] | 982 | self._welu = None
|
---|
| 983 |
|
---|
| 984 | def getMessageID(self, record):
|
---|
| 985 | """
|
---|
| 986 | Return the message ID for the event record. If you are using your
|
---|
| 987 | own messages, you could do this by having the msg passed to the
|
---|
| 988 | logger being an ID rather than a formatting string. Then, in here,
|
---|
| 989 | you could use a dictionary lookup to get the message ID. This
|
---|
| 990 | version returns 1, which is the base message ID in win32service.pyd.
|
---|
| 991 | """
|
---|
| 992 | return 1
|
---|
| 993 |
|
---|
| 994 | def getEventCategory(self, record):
|
---|
| 995 | """
|
---|
| 996 | Return the event category for the record.
|
---|
| 997 |
|
---|
| 998 | Override this if you want to specify your own categories. This version
|
---|
| 999 | returns 0.
|
---|
| 1000 | """
|
---|
| 1001 | return 0
|
---|
| 1002 |
|
---|
| 1003 | def getEventType(self, record):
|
---|
| 1004 | """
|
---|
| 1005 | Return the event type for the record.
|
---|
| 1006 |
|
---|
| 1007 | Override this if you want to specify your own types. This version does
|
---|
| 1008 | a mapping using the handler's typemap attribute, which is set up in
|
---|
| 1009 | __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 | either need to override this method or place a suitable dictionary in
|
---|
| 1012 | the handler's typemap attribute.
|
---|
| 1013 | """
|
---|
| 1014 | return self.typemap.get(record.levelno, self.deftype)
|
---|
| 1015 |
|
---|
| 1016 | def emit(self, record):
|
---|
| 1017 | """
|
---|
| 1018 | Emit a record.
|
---|
| 1019 |
|
---|
| 1020 | Determine the message ID, event category and event type. Then
|
---|
| 1021 | log the message in the NT event log.
|
---|
| 1022 | """
|
---|
| 1023 | if self._welu:
|
---|
| 1024 | try:
|
---|
| 1025 | id = self.getMessageID(record)
|
---|
| 1026 | cat = self.getEventCategory(record)
|
---|
| 1027 | type = self.getEventType(record)
|
---|
| 1028 | msg = self.format(record)
|
---|
| 1029 | self._welu.ReportEvent(self.appname, id, cat, type, [msg])
|
---|
| 1030 | except (KeyboardInterrupt, SystemExit):
|
---|
| 1031 | raise
|
---|
| 1032 | except:
|
---|
| 1033 | self.handleError(record)
|
---|
| 1034 |
|
---|
| 1035 | def close(self):
|
---|
| 1036 | """
|
---|
| 1037 | Clean up this handler.
|
---|
| 1038 |
|
---|
| 1039 | You can remove the application name from the registry as a
|
---|
| 1040 | source of event log entries. However, if you do this, you will
|
---|
| 1041 | not be able to see the events as you intended in the Event Log
|
---|
| 1042 | Viewer - it needs to be able to access the registry to get the
|
---|
| 1043 | DLL name.
|
---|
| 1044 | """
|
---|
| 1045 | #self._welu.RemoveSourceFromRegistry(self.appname, self.logtype)
|
---|
| 1046 | logging.Handler.close(self)
|
---|
| 1047 |
|
---|
| 1048 | class HTTPHandler(logging.Handler):
|
---|
| 1049 | """
|
---|
| 1050 | A class which sends records to a Web server, using either GET or
|
---|
| 1051 | POST semantics.
|
---|
| 1052 | """
|
---|
| 1053 | def __init__(self, host, url, method="GET"):
|
---|
| 1054 | """
|
---|
| 1055 | Initialize the instance with the host, the request URL, and the method
|
---|
| 1056 | ("GET" or "POST")
|
---|
| 1057 | """
|
---|
| 1058 | logging.Handler.__init__(self)
|
---|
[391] | 1059 | method = method.upper()
|
---|
[2] | 1060 | if method not in ["GET", "POST"]:
|
---|
[391] | 1061 | raise ValueError("method must be GET or POST")
|
---|
[2] | 1062 | self.host = host
|
---|
| 1063 | self.url = url
|
---|
| 1064 | self.method = method
|
---|
| 1065 |
|
---|
| 1066 | def mapLogRecord(self, record):
|
---|
| 1067 | """
|
---|
| 1068 | Default implementation of mapping the log record into a dict
|
---|
| 1069 | that is sent as the CGI data. Overwrite in your class.
|
---|
| 1070 | Contributed by Franz Glasner.
|
---|
| 1071 | """
|
---|
| 1072 | return record.__dict__
|
---|
| 1073 |
|
---|
| 1074 | def emit(self, record):
|
---|
| 1075 | """
|
---|
| 1076 | Emit a record.
|
---|
| 1077 |
|
---|
[391] | 1078 | Send the record to the Web server as a percent-encoded dictionary
|
---|
[2] | 1079 | """
|
---|
| 1080 | try:
|
---|
| 1081 | import httplib, urllib
|
---|
| 1082 | host = self.host
|
---|
| 1083 | h = httplib.HTTP(host)
|
---|
| 1084 | url = self.url
|
---|
| 1085 | data = urllib.urlencode(self.mapLogRecord(record))
|
---|
| 1086 | if self.method == "GET":
|
---|
[391] | 1087 | if (url.find('?') >= 0):
|
---|
[2] | 1088 | sep = '&'
|
---|
| 1089 | else:
|
---|
| 1090 | sep = '?'
|
---|
| 1091 | url = url + "%c%s" % (sep, data)
|
---|
| 1092 | h.putrequest(self.method, url)
|
---|
| 1093 | # support multiple hosts on one IP address...
|
---|
| 1094 | # need to strip optional :port from host, if present
|
---|
[391] | 1095 | i = host.find(":")
|
---|
[2] | 1096 | if i >= 0:
|
---|
| 1097 | host = host[:i]
|
---|
| 1098 | h.putheader("Host", host)
|
---|
| 1099 | if self.method == "POST":
|
---|
| 1100 | h.putheader("Content-type",
|
---|
| 1101 | "application/x-www-form-urlencoded")
|
---|
| 1102 | h.putheader("Content-length", str(len(data)))
|
---|
[391] | 1103 | h.endheaders(data if self.method == "POST" else None)
|
---|
[2] | 1104 | h.getreply() #can't do anything with the result
|
---|
| 1105 | except (KeyboardInterrupt, SystemExit):
|
---|
| 1106 | raise
|
---|
| 1107 | except:
|
---|
| 1108 | self.handleError(record)
|
---|
| 1109 |
|
---|
| 1110 | class BufferingHandler(logging.Handler):
|
---|
| 1111 | """
|
---|
| 1112 | A handler class which buffers logging records in memory. Whenever each
|
---|
| 1113 | record is added to the buffer, a check is made to see if the buffer should
|
---|
| 1114 | be flushed. If it should, then flush() is expected to do what's needed.
|
---|
| 1115 | """
|
---|
| 1116 | def __init__(self, capacity):
|
---|
| 1117 | """
|
---|
| 1118 | Initialize the handler with the buffer size.
|
---|
| 1119 | """
|
---|
| 1120 | logging.Handler.__init__(self)
|
---|
| 1121 | self.capacity = capacity
|
---|
| 1122 | self.buffer = []
|
---|
| 1123 |
|
---|
| 1124 | def shouldFlush(self, record):
|
---|
| 1125 | """
|
---|
| 1126 | Should the handler flush its buffer?
|
---|
| 1127 |
|
---|
| 1128 | Returns true if the buffer is up to capacity. This method can be
|
---|
| 1129 | overridden to implement custom flushing strategies.
|
---|
| 1130 | """
|
---|
| 1131 | return (len(self.buffer) >= self.capacity)
|
---|
| 1132 |
|
---|
| 1133 | def emit(self, record):
|
---|
| 1134 | """
|
---|
| 1135 | Emit a record.
|
---|
| 1136 |
|
---|
| 1137 | Append the record. If shouldFlush() tells us to, call flush() to process
|
---|
| 1138 | the buffer.
|
---|
| 1139 | """
|
---|
| 1140 | self.buffer.append(record)
|
---|
| 1141 | if self.shouldFlush(record):
|
---|
| 1142 | self.flush()
|
---|
| 1143 |
|
---|
| 1144 | def flush(self):
|
---|
| 1145 | """
|
---|
| 1146 | Override to implement custom flushing behaviour.
|
---|
| 1147 |
|
---|
| 1148 | This version just zaps the buffer to empty.
|
---|
| 1149 | """
|
---|
[391] | 1150 | self.acquire()
|
---|
| 1151 | try:
|
---|
| 1152 | self.buffer = []
|
---|
| 1153 | finally:
|
---|
| 1154 | self.release()
|
---|
[2] | 1155 |
|
---|
| 1156 | def close(self):
|
---|
| 1157 | """
|
---|
| 1158 | Close the handler.
|
---|
| 1159 |
|
---|
| 1160 | This version just flushes and chains to the parent class' close().
|
---|
| 1161 | """
|
---|
| 1162 | self.flush()
|
---|
| 1163 | logging.Handler.close(self)
|
---|
| 1164 |
|
---|
| 1165 | class MemoryHandler(BufferingHandler):
|
---|
| 1166 | """
|
---|
| 1167 | A handler class which buffers logging records in memory, periodically
|
---|
| 1168 | flushing them to a target handler. Flushing occurs whenever the buffer
|
---|
| 1169 | is full, or when an event of a certain severity or greater is seen.
|
---|
| 1170 | """
|
---|
| 1171 | def __init__(self, capacity, flushLevel=logging.ERROR, target=None):
|
---|
| 1172 | """
|
---|
| 1173 | Initialize the handler with the buffer size, the level at which
|
---|
| 1174 | flushing should occur and an optional target.
|
---|
| 1175 |
|
---|
| 1176 | Note that without a target being set either here or via setTarget(),
|
---|
| 1177 | a MemoryHandler is no use to anyone!
|
---|
| 1178 | """
|
---|
| 1179 | BufferingHandler.__init__(self, capacity)
|
---|
| 1180 | self.flushLevel = flushLevel
|
---|
| 1181 | self.target = target
|
---|
| 1182 |
|
---|
| 1183 | def shouldFlush(self, record):
|
---|
| 1184 | """
|
---|
| 1185 | Check for buffer full or a record at the flushLevel or higher.
|
---|
| 1186 | """
|
---|
| 1187 | return (len(self.buffer) >= self.capacity) or \
|
---|
| 1188 | (record.levelno >= self.flushLevel)
|
---|
| 1189 |
|
---|
| 1190 | def setTarget(self, target):
|
---|
| 1191 | """
|
---|
| 1192 | Set the target handler for this handler.
|
---|
| 1193 | """
|
---|
| 1194 | self.target = target
|
---|
| 1195 |
|
---|
| 1196 | def flush(self):
|
---|
| 1197 | """
|
---|
| 1198 | For a MemoryHandler, flushing means just sending the buffered
|
---|
| 1199 | records to the target, if there is one. Override if you want
|
---|
| 1200 | different behaviour.
|
---|
| 1201 | """
|
---|
[391] | 1202 | self.acquire()
|
---|
| 1203 | try:
|
---|
| 1204 | if self.target:
|
---|
| 1205 | for record in self.buffer:
|
---|
| 1206 | self.target.handle(record)
|
---|
| 1207 | self.buffer = []
|
---|
| 1208 | finally:
|
---|
| 1209 | self.release()
|
---|
[2] | 1210 |
|
---|
| 1211 | def close(self):
|
---|
| 1212 | """
|
---|
| 1213 | Flush, set the target to None and lose the buffer.
|
---|
| 1214 | """
|
---|
| 1215 | self.flush()
|
---|
[391] | 1216 | self.acquire()
|
---|
| 1217 | try:
|
---|
| 1218 | self.target = None
|
---|
| 1219 | BufferingHandler.close(self)
|
---|
| 1220 | finally:
|
---|
| 1221 | self.release()
|
---|