Python logging模块日志存储位置踩坑

枫铃3年前 (2021-07-23)Python296

问题描述

项目过程中写了一个小模块，设计到了日志存储的问题，结果发现了个小问题。

代码结构如下：

db.py
run.py

其中db.py是操作数据库抽象出来的一个类，run.py是业务逻辑代码。两个文件中都有使用Python自带的logging模块，来记录日志。其中前者将日志存入到db_xxx.log下，后者存入run_xxx.log下。

两者logging相关代码为：

# db.py
import logging
import time

dt = time.time()
logging.basicConfig(filename='db_' + str(dt) + '.log', level=logging.INFO)


# run.py
import logging
import time

dt = time.time()
logging.basicConfig(filename='run_' + str(dt) + '.log', level=logging.INFO)

同时，在run.py中会调用db.py的函数，例如：

'''
遇到问题没人解答？小编创建了一个Python学习交流QQ群：778463939
寻找有志同道合的小伙伴，互帮互助,群里还有不错的视频学习教程和PDF电子书！
'''
# db.py

class DB():
		def __init__(self):
				xxxx
		
		def select(self):
				logging.info('log from db.py')

# run.py

from db import DB

if __name__ == '__main__':
		db = DB()
		db.select()
		
		logging.info('log from run.py')

实际运行起来，发现所有的日志都只会存在 db_xxx.log 中，同时并不会产生 run_xxx.log 文件。

排错

猜测

依照上面的结果，猜测run.py文件中，引入的db.py中也有logging的设置，但只会有一个生效。

验证 1

写两个py文件first.py和second.py，在second中引用first的函数，看最终日志的输出文件名及顺序。

内容分别为：

# first.py

import logging

class TEST():
    def __init__(self, log_type, dt):
        dt = str(dt)
        logging.basicConfig(filename='./log/' + log_type + '_' + dt + '.txt', level=logging.INFO)

    def test_log(self):
        logging.info('log from first.py')


# second.py

from first import TEST
import time

dt = time.time()

import logging
logging.basicConfig(filename='./log/' + 'second.txt', level=logging.INFO)
logging.info('log from second')


test = TEST('db', dt)
test.test_log()


# 结果

➜  log_dir_test python second.py
➜  log_dir_test ls log
second.txt
➜  log_dir_test cat log/second.txt
INFO:root:log from second
INFO:root:log from first.py

可以看到，文件名为 second.txt，即采用了 second.py 的logging设置。同时，日志输出的顺序也是先输出 second 再是 first。

验证 2

此时尝试将second.py的语句顺序做一个调整，调整为下面：

'''
遇到问题没人解答？小编创建了一个Python学习交流QQ群：778463939
寻找有志同道合的小伙伴，互帮互助,群里还有不错的视频学习教程和PDF电子书！
'''
# second.py

from first import TEST
import time

dt = time.time()
test = TEST('db', dt)
test.test_log()

import logging
logging.basicConfig(filename='./log/' + 'second.txt', level=logging.INFO)
logging.info('log from second')

# result

➜  log_dir_test cat log/db_1561088221.83.txt
INFO:root:log from first.py
INFO:root:log from second

可以看到结果存储到了 db_xxx.txt 中，即采用了 first.py 的logging设置。

验证 3

再对second.py做调整：

# second.py

from first import TEST
import time

dt = time.time()
test = TEST('db', dt)

import logging
logging.basicConfig(filename='./log/' + 'second.txt', level=logging.INFO)
logging.info('log from second')

test.test_log()

# result

➜  log_dir_test cat log/db_1561088393.36.txt
INFO:root:log from second
INFO:root:log from first.py

采用了first.py的设置

验证 4

继续调整second.py：

'''
遇到问题没人解答？小编创建了一个Python学习交流QQ群：778463939
寻找有志同道合的小伙伴，互帮互助,群里还有不错的视频学习教程和PDF电子书！
'''
# second.py

from first import TEST
import time
import logging
logging.basicConfig(filename='./log/' + 'second.txt', level=logging.INFO)

dt = time.time()
test = TEST('db', dt)

logging.info('log from second')

test.test_log()


# result

➜  log_dir_test cat log/second.txt
INFO:root:log from second
INFO:root:log from first.py