Flask-Python控制台日志保存不完整问题排查求助

这个问题我之前碰到过好几次，核心原因其实是Python文件缓冲机制在Flask Web环境下的表现和原生脚本不一样：

• 在原生Python脚本中，当程序运行结束时，Python会自动关闭所有打开的文件对象，关闭过程中会强制把缓冲区里的内容写入磁盘，所以日志文件总是完整的。
• 但在Flask的Web服务中，请求是在一个长期运行的进程（比如Werkzeug开发服务器）里处理的。当你用sys.stdout = open(log_file, 'w')重定向输出后，程序执行完请求并不会终止进程，这个打开的文件对象会一直存在，而默认的文件缓冲是「块缓冲」——只有缓冲区满、手动调用flush()或者文件被关闭时，内容才会写入磁盘。这就导致首次运行的日志内容留在内存缓冲区里，没被写入磁盘，看起来就是日志不完整。
• 当你刷新页面时，第二次执行sys.stdout = open(log_file, 'w')会覆盖之前的文件对象，旧的文件对象会被Python垃圾回收机制关闭，此时缓冲区里的内容才会被写入磁盘，所以你会看到刷新后日志突然完整了。

另外还有一个潜在问题：直接修改sys.stdout在Flask的多请求环境下是线程不安全的，如果多个请求同时处理，会互相覆盖日志输出的目标文件，导致日志混乱。

方案一：改用Python标准logging模块（推荐） #

logging模块是专门为日志场景设计的，支持线程安全、灵活的输出配置，还能直接控制缓冲行为，完全替代sys.stdout重定向的方式：

import logging
from flask import Flask

app = Flask(__name__)

@app.route('/execute')
def execute_program():
    log_file = 'app_run.log'
    # 配置日志：每次运行覆盖文件，输出INFO级别及以上的日志
    logging.basicConfig(
        filename=log_file,
        level=logging.INFO,
        format='%(asctime)s - %(message)s',
        filemode='w'
    )
    # 替换原来的print语句为logging.info
    logging.info("程序启动，开始执行任务")
    # --- 你的业务代码逻辑 ---
    logging.info("任务执行完成，程序结束")
    
    # 手动触发flush，确保所有日志立即写入磁盘
    for handler in logging.getLogger().handlers:
        handler.flush()
    
    return "程序执行完成，日志已保存"

方案二：如果坚持使用sys.stdout重定向 #

可以通过修改文件打开参数或手动flush来解决缓冲问题，同时注意恢复默认stdout避免影响其他请求：

import sys
from flask import Flask

app = Flask(__name__)

@app.route('/execute')
def execute_program():
    log_file = 'app_run.log'
    # 打开文件时启用行缓冲：每次print换行时自动flush
    original_stdout = sys.stdout
    sys.stdout = open(log_file, 'w', line_buffering=True)
    
    print("程序启动，开始执行任务")
    # --- 你的业务代码逻辑 ---
    print("任务执行完成，程序结束")
    
    # 手动flush确保所有内容写入磁盘
    sys.stdout.flush()
    # 关闭文件并恢复默认的stdout，避免影响后续请求
    sys.stdout.close()
    sys.stdout = original_stdout
    
    return "程序执行完成，日志已保存"

内容的提问来源于stack exchange，提问作者Petar