在Python中使用全局日志时需要注意的问题

正常的日志：

代码如下:

[INFO] * Loading DebuggedApplication...
[INFO] * Running on http://localhost:8000/
[INFO] * Restarting with reloader
[INFO] * Loading DebuggedApplication...

异常的日志：

代码如下:

[INFO] * Loading DebuggedApplication...
[INFO] * Loading DebuggedApplication...

事实上， werkzeug 相关 INFO 及其以下级别的日志全部都看不到了，这太诡异了，于是果断提交了issue

而且我创建一个新的项目和 app 后，并没有日志丢失的问题，于是我怀疑是因为我从 uliweb 0.1.3 升级到 0.1.4 导致的，我的项目是使用 0.1.3 创建的，之前也没有注意过日志的问题，但是得到 limodou 的答复后，我否定了这一点

• uliweb在创建项目时不会生成象 Django 一样的 manage.py 之类的东西，就是 settings.py 等一些配置文件和启动文件， 都是和版本无关的。

仔细想想，我的项目和新创建的项目的区别在于 settings.ini 丰富了一些，然后用了 pysimplelib，好吧，从源码追踪吧。

我看了 uliweb 内置的 zerkzeug 代码，创建日志这里很明确

uliweb/lib/werkzeug/serving.py

代码如下:

112 if not logging.root.handlers and _logger.level == logging.NOTSET:
113 _logger.setLevel(logging.INFO)
114 handler = logging.StreamHandler()
115 _logger.addHandler(handler)[code]

当全局 logging.root 中已经定义了 Handler 或者 werkzeug 没有定义日志级别，werkzeug 会将其 logger 的日志级别 设置为 info，而打印 werkzeug 日志的地方，恰巧用了 info 级别

[code][INFO] * Running on http://localhost:8000/
[INFO] * Restarting with reloader

也就是说，因为已经定义其它 logging handler 的原因，导致无法输出 werkzeug 日志

grep pysimplelib 下的结果

代码如下:

./client.py:33: logging.basicConfig(format='%(levelname)s:%(message)s', level=logging.WARNING)
./simplexml.py:27:logging.basicConfig(format='%(levelname)s:%(message)s', level=logging.WARNING)
./transport.py:30:logging.basicConfig(format='%(levelname)s:%(message)s', level=logging.WARNING)

好吧，果然是这家伙在作祟，注释掉这三行代码后，werkzeug 日志正常输出

说起来，这不应该算是 uliweb 的问题，pysimplesoap 的库修改的全局设计，似乎应该给它提个 issue 才是， 这个 issue 应该可以关闭了。

这里有个 workaround，在 apps/settings.ini 中添加

代码如下:

[LOG.Loggers]
werkzeug = {'propagate':0, 'level': 'info', 'format':'format_simple'}

当然，建议 limodou 将 default_settings.ini 中的 zerkzeug 的日志级别默认设置成 info，因为这个问题实 在太诡异了，毕竟 werkzeug 中的默认日志级别就是 info。

好了，回顾一下，pysimplesoap 中因为覆盖了全局日志级别，导致 werkzeug 无法输入低于 warning 的日志，所以， 如果我们在开发开放 python 库的时候，还是将日志打在自己的命名空间下吧，不要去碰全局的 logging 设置，这样 会对别人造成困扰。