Python日志记录模块在“加载项”属性上引发KeyError异常

Question

我有一个问题，我试图在Python CGI脚本上记录一些额外的属性（用户ID和连接主机IP）。这是在RHEL5系统上的python 2.6.8下运行的。我遵循了扩展基本日志字典中属性的文档，如下所示：

from __future__ import print_function
import logging
import os
import sys

LOG_DIR = '/apps/log'
LOG_PAGE = re.sub(r'\/(.*)\/.*$', r'\1', os.environ['REQUEST_URI'])
#
# The log format should be 
# <date stamp>  <level>   <remote user> <remote IP>: <message>
#
LOG_FORMAT = '%(asctime)-19s %(levelname)-9s %(user)-7s %(clientip)-15s - %(message)s'
LOG_DATE = '%Y-%m-%d %H:%M:%S'
orig_user = os.environ['REMOTE_USER']
orig_ip = os.environ['REMOTE_ADDR']
xtras = { 'user': orig_user, 'clientip': orig_ip }
#
# Set up logging
#
LOG_FILE = LOG_DIR + '/' + LOG_PAGE
logging.basicConfig(format=LOG_FORMAT, datefmt=LOG_DATE, level=logging.DEBUG, filename=LOG_FILE)
logit = logging.getLogger('groups')

我用：

logit.debug('user visited page',extra=xtras)
logit.warn('user has no account under domain %s', myDOM, extra=xtras)

每次记录消息时，都会在web服务器错误日志中记录3个KeyError异常：

s = self._fmt % record.__dict__, referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
KeyError: 'user', referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
Traceback (most recent call last):, referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
File "/usr/lib64/python2.6/logging/__init__.py", line 776, in emit, referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
msg = self.format(record), referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
File "/usr/lib64/python2.6/logging/__init__.py", line 654, in format, referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
return fmt.format(record), referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
File "/usr/lib64/python2.6/logging/__init__.py", line 439, in format, referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
s = self._fmt % record.__dict__, referer: https://its-serv-dev.case.edu/googlegroups/index.cgi
KeyError: 'user', referer: https://its-serv-dev.case.edu/googlegroups/index.cgi

奇怪的是，KeyError异常只为其中一个“额外”字典项生成，并且信息被正确地登录到文件中。我尝试了删除两个额外组件的各种组合（错误只是转移到剩下的任何部分），除非完全删除额外信息，否则似乎没有什么能阻止异常被抛出。

我想我可以将信息包含在格式字符串中作为消息的一部分，但这似乎是重新发明轮子。

Answer 1

我知道这里发生了什么：

我还导入了Google的oauth2client.client模块，它也使用了日志记录模块。由于oauth2Cleint.Client模块被认为是我的页面的“子”，日志记录被传递到我的日志对象，并且由于Google模块在其调用中不包括额外的日志字典，Google模块是生成KeyError异常的项，而不是我自己的代码。目前我已经通过将额外的条目作为消息的一部分来解决这个问题，并且需要进一步深入日志模块，看看是否有更好的方法来防止Google oauth2clent.client模块日志记录与页面日志记录发生冲突。