Why doesn't logging work when running a Flask application with werkzeug?

Question

So, here is a copy example that reproduces the problem.

import logging

from flask import Flask
from werkzeug.serving import run_simple
from werkzeug.wsgi import DispatcherMiddleware

def app_builder(app_name, log_file):

    app = Flask(app_name)
    app.debug = True

    handler = logging.FileHandler(log_file)
    handler.setLevel(logging.DEBUG)
    app.logger.addHandler(handler)

    return app

def _simple(env, resp):
    resp(b'200 OK', [(b'Content-Type', b'text/plain')])
    return [b'root']

if __name__ == "__main__":

    app = app_builder(app_name='app', log_file='app.log')

    @app.route('/')
    def index():
        return '<a href="/app/error">click for error</a>'

    @app.route('/error')
    def error():
        1/0
        return 'error page'

    app2 = app_builder(app_name='app2', log_file='app2.log')

    @app2.route('/')
    def index():
        return 'you are getting responses from app2'

    app.debug = True
    app2.debug = True

    application = DispatcherMiddleware(_simple, {
        '/app':     app,
        '/app2':    app2
        })

    run_simple(hostname='localhost',
               port=5000,
               application=application,
               use_reloader=True,
               use_debugger=True)

To show the error message, go to http://localhost:5000/app/error, I want to know why the stack trace is not displayed in the file app.log. I suppose that DispatcherMiddlewareor run_simplesomehow catch the exception before it can be registered. If I run only the instance appusing app.run(), the error log works fine.

Answer 1

A regular exception handler is not called when app.debug = True. I am looking in the code app.pyin the flask:

def log_exception(self, exc_info):
    """Logs an exception.  This is called by :meth:`handle_exception`
    if debugging is disabled and right before the handler is called.
    ^^^^^^^^^^^^^^^^^^^^^^^^
    The default implementation logs the exception as error on the
    :attr:`logger`.

, app.debug = True True, log_exception. ( ):

PROPAGATE_EXCEPTIONS: . None, , , DEBUG .

, werkzeug, :

import logging

from flask import Flask
from werkzeug.serving import run_simple
from werkzeug.wsgi import DispatcherMiddleware
## NEW CODE HERE
import functools
from flask._compat import reraise

def my_log_exception(exc_info, original_log_exception=None):
    original_log_exception(exc_info)
    exc_type, exc, tb = exc_info
    # re-raise for werkzeug
    reraise(exc_type, exc, tb)
## 

def app_builder(app_name, log_file):
    app = Flask(app_name)
    app.debug = True
    app.config.update(PROPAGATE_EXCEPTIONS=False)

    handler = logging.FileHandler(log_file)
    handler.setLevel(logging.DEBUG)
    app.logger.addHandler(handler)

    ## NEW CODE
    app.log_exception = functools.partial(my_log_exception,  original_log_exception=app.log_exception)
    ##

    return app

# rest of your code is unchanged

Answer 2

gist, . andyxning ( 18 2015 ) - if app.debug is True then all log level above DEBUG will be logged to stderr(StreamHandler).

flask/logging.py. create_logger DebugHandler, StreamHandler.

app.logger.handlers, , flask.logging.DebugHandler.

print app.logger.handlers
[<flask.logging.DebugHandler object at 0x110315090>]

DebugHandler, , , app.debug is set to true , , .

, , .