在gunicorn内运行时，烧瓶应用程序记录器不工作

Question

我试图将来自一个非常简单的flask应用程序的应用程序日志消息保存在日志文件中。当我使用嵌入式Flask服务器运行应用程序时，这项功能完美无瑕，但在gUnicorn中运行时，它根本不起作用，基本上，运行gUnicorn时，不会将任何应用程序输出重定向到日志文件（我的Flask应用程序中指定的日志文件）或标准输出。

也就是说，这是我的烧瓶应用程序：

@app.route('/')
def index():
    app.logger.debug('Into /!!!!')
    print 'Will this print?'
    return 'Flask is running!'


if __name__ == '__main__':
    #Setup the logger
    file_handler = FileHandler('test.log')
    handler = logging.StreamHandler()
    file_handler.setLevel(logging.DEBUG)
    handler.setLevel(logging.DEBUG)
    file_handler.setFormatter(Formatter(
    '%(asctime)s %(levelname)s: %(message)s '
    '[in %(pathname)s:%(lineno)d]'))
    handler.setFormatter(Formatter(
    '%(asctime)s %(levelname)s: %(message)s '
    '[in %(pathname)s:%(lineno)d]'))
    app.logger.addHandler(handler)
    app.logger.addHandler(file_handler)
    app.run(debug=True)

现在，如果我以以下方式启动应用程序：

python app.py

我得到预期的输出：

 * Running on http://127.0.0.1:5000/ (Press CTRL+C to quit)
 * Restarting with stat

--------------------------------------------------------------------------------
DEBUG in app [app.py:23]:
Into /!!!!
--------------------------------------------------------------------------------
2015-03-11 09:36:18,375 DEBUG: Into /!!!! [in app.py:23]
Will this print?
127.0.0.1 - - [11/Mar/2015 09:36:18] "GET / HTTP/1.1" 200 -

Tailing test.log，我明白了：

2015-03-11 09:36:18,375 DEBUG: Into /!!!! [in app.py:23]

到目前为止，一切看起来都很棒，然后当我尝试用nginx Gunicorn运行应用程序时，首先我尝试这样运行Gunicorn：

gunicorn app:app -b localhost:8000 --debug --log-level debug

应用程序正在工作，如果我去http://localhost：

curl http://localhost
Flask is running!

但是查看日志文件，它是空的，没有写入任何内容。我已经添加了777个权限，只是为了检查这是否是一个权限问题，但没有结果。然后看看gunicorn stdout，除了打印语句外，没有其他内容：

2015-03-11 09:42:06 [25641] [DEBUG] GET /
Will this print?

环顾四周，我尝试将所有输出重定向到gunicorn日志，然后像这样启动gunicorn：

gunicorn app:app -b localhost:8000 --debug --log-file /tmp/test.log --log-level debug --error-logfile /tmp/error.log

但是现在我甚至没有在gunicorn文件中得到print语句，这是test.log和error.log的输出（它们是相同的）：

2015-03-11 09:46:17 [26257] [DEBUG]   tmp_upload_dir: None
2015-03-11 09:46:17 [26257] [DEBUG]   keyfile: None
2015-03-11 09:46:17 [26257] [DEBUG]   backlog: 2048
2015-03-11 09:46:17 [26257] [DEBUG]   logger_class: simple
2015-03-11 09:46:17 [26257] [INFO] Starting gunicorn 17.5
2015-03-11 09:46:17 [26257] [DEBUG] Arbiter booted
2015-03-11 09:46:17 [26257] [INFO] Listening at: http://127.0.0.1:8000 (26257)
2015-03-11 09:46:17 [26257] [INFO] Using worker: sync
2015-03-11 09:46:17 [26262] [INFO] Booting worker with pid: 26262
2015-03-11 09:48:15 [26262] [DEBUG] GET /

这里有一个非常类似的问题，其中一个答案似乎表明在gunicorn中运行时没有可用的应用程序记录器？？？这听起来，至少，很奇怪……那我该怎么记录呢？

另一个提议的解决方案似乎建议不要使用烧瓶记录器，但与Gunicorn无关（我认为）...

我错过了什么？我应该放弃gunicorn，转而使用Apache mod wsgi吗？Nginx-uWSGI？快速CGI？有什么想法吗？

谢谢亚历杭德罗

编辑：

我用uWGSI而不是gunicorn尝试了同样的设置，同样的行为，没有获得任何应用程序日志记录。

现在根据这个和另一个反应，我提出了这个（关于gUnicorn和uWSGI，在这两个版本中）

from flask import Flask
import logging
from logging import Formatter, FileHandler

app = Flask(__name__)

LOGGER = logging.getLogger('whatever')
file_handler = FileHandler('test.log')
handler = logging.StreamHandler()
file_handler.setFormatter(Formatter(
    '%(asctime)s %(levelname)s: %(message)s '
    '[in %(pathname)s:%(lineno)d]'
))
handler.setFormatter(Formatter(
    '%(asctime)s %(levelname)s: %(message)s '
    '[in %(pathname)s:%(lineno)d]'
))
LOGGER.addHandler(file_handler)
LOGGER.addHandler(handler)
LOGGER.setLevel(logging.INFO)

@app.route('/')
def hello():
    LOGGER.info('info log')
    LOGGER.debug('debug log')
    return 'Hello!'

if __name__ == '__main__':
    app.run()

gunicorn的输出：

2015-03-11 12:25:01 [11540] [INFO] Starting gunicorn 17.5
2015-03-11 12:25:01 [11540] [INFO] Listening at: http://127.0.0.1:8000 (11540)
2015-03-11 12:25:01 [11540] [INFO] Using worker: sync
2015-03-11 12:25:01 [11545] [INFO] Booting worker with pid: 11545
2015-03-11 12:26:20,765 INFO: info log [in /home/mosquito/www/flask-project/flask-project/app.py:24]

看着我的test.log档案：

2015-03-11 12:26:20,765 INFO: info log [in /home/mosquito/www/flask-project/flask-project/app.py:24]

所以，是的，它有点工作，但最初的问题仍然存在…为什么该死的烧瓶记录器在wsgi容器中运行时似乎不工作-gunicorn，uWSGI？

Answer 1

在Gunicorn 19.6中，-捕获-输出-启用-标准输入输出-继承似乎可以工作。

Answer 2

烧瓶使用Werkzeug的WSGI。您看到的“烧瓶日志”实际上来自Werkzeug的内置开发服务器，而不是烧瓶本身。

当您用Gunicorn或uWSGI之类的东西替换开发服务器时，您不会看到它的日志。

调试器也是如此。即使只使用Werkzeug的调试器，也可以看到熟悉的“Flask调试页面”。

现在你知道了。：）

Answer 3

你在这里自己回答了你的问题。虽然我会添加我的答案，希望它能帮助其他有类似问题的人。

由于你的问题有两个部分，其中第一部分已经解决，我将对每个部分的回答进行标记：

第1部分：如果不是通过python直接运行应用程序，而是在gunicorn下运行，则不会发生日志记录。这是因为，当直接运行时，name=='main'为True，并且您的代码初始化了FileHandler和StreamHandler，日志记录工作正常。但是当运行gunicorn时，name=='main'将失败，因为name将包含模块的名称。这意味着不会初始化任何有效的处理程序。因此看不到日志记录。

第2部分：为什么烧瓶记录器在Gunicorn/uWSGI下默认不工作最新的烧瓶版本从头开始初始化app.logger，并默认附加一些处理程序，如DebugHandler，StreamHandler，具体取决于app.debug==True。记录器仍然不够，只能登录到STDERR。在过去的几个版本中，Gunicorn发生了多次变化。版本19.4.1没有将STDOUT和STDERR捕获到Gunicornerror.log.但是它确实提供了名为Gunicorn、gunicorn.access和gunicorn.error的记录器。最后一个有一个FileHandler写入到配置的error.log.如果您希望从烧瓶应用程序的日志转到error.log使用以下方法之一：方法1：

#only use gunicorn.error logger for all logging
LOGGER = logging.getLogger('gunicorn.error')
LOGGER.info('my info')
LOGGER.debug('debug message')
# this would write the log messages to error.log

方法二：

# Only use the FileHandler from gunicorn.error logger
gunicorn_error_handlers = logging.getLogger('gunicorn.error').handlers
app.logger.handlers.extend(gunicorn_error_handlers )
app.logger.addHandler(myhandler1)
app.logger.addHandler(myhandler2)
app.logger.info('my info')
app.logger.debug('debug message')

将推荐方法2，因为除了gunicorn.error.之外，您可以保留任何您想要的处理程序。此外，您可以根据条件选择不添加gunicorn.error处理程序。

谢啦