在使用 uliweb 開發 soap webservice 後,啟動 uliweb 時,werkzeug 的日誌莫名其妙丟失了。
正常的日誌:
複製代碼 代碼如下:
[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 設定,這樣 會對別人造成困擾。