python多线程无法写入日志

whyliyi

一个简单的测试，两个线程，在线程中打印日志，日志打印到文件中，发现多线程无法打印日志。
thread1.py代码如下：

1. import logger.log
   
2. import threading
   
3. import t
   
4. 
   
5. log = logger.log.Logger.getLogger(__name__)
   
6. 
   
7. def thread1():
   
8.     t.test('1')
   
9. 
   
10. def thread2():
    
11.     t.test('2')
    
12. 
    
13. 
    
14. if __name__ == '__main__':
    
15.     log.debug('start main')
    
16.     t1 = threading.Timer(10.0, thread1)
    
17.     t1.start()
    
18.     t2 = threading.Timer(10.0, thread2)
    
19.     t2.start()
    
20. 
    
21.     t1.join()
    
22.     t2.join()

复制代码

t.py代码如下：

1. import logging
   
2. import logging.config
   
3. 
   
4. logging.config.fileConfig('../conf/log.conf')
   
5. log = logging.getLogger(__name__)
   
6. 
   
7. def test(name):
   
8.     log.debug( 'thread' + name)

复制代码

日志配置文件如下：

1. # logger configure file
   
2. 
   
3. [loggers]
   
4. keys=root,example
   
5. 
   
6. [handlers]
   
7. keys=consoleHandler,rotateFileHandler
   
8. 
   
9. [formatters]
   
10. keys=simpleFormatter
    
11. 
    
12. [formatter_simpleFormatter]
    
13. format=[%(asctime)s][%(levelname)s][%(name)s][%(filename)s:%(lineno)s]:%(message)s
    
14. 
    
15. [logger_root]
    
16. level=DEBUG
    
17. handlers=consoleHandler,rotateFileHandler
    
18. 
    
19. [logger_example]
    
20. level=DEBUG
    
21. handlers=consoleHandler,rotateFileHandler
    
22. qualname=example
    
23. propagate=0
    
24. 
    
25. [handler_consoleHandler]
    
26. class=StreamHandler
    
27. level=DEBUG
    
28. formatter=simpleFormatter
    
29. args=(sys.stdout,)
    
30. 
    
31. [handler_rotateFileHandler]
    
32. class=handlers.RotatingFileHandler
    
33. level=DEBUG
    
34. formatter=simpleFormatter
    
35. args=('hcagent.log', 'a', 200000, 10)

复制代码

结果打印的日志如下：

1. [2014-08-29 16:48:16,132][DEBUG][__main__][thread1.py:17]:start main
   
2. [2014-08-29 17:14:59,849][DEBUG][__main__][thread1.py:17]:start main
   
3. [2014-08-29 17:15:59,180][DEBUG][__main__][thread1.py:17]:start main
   
4. [2014-08-29 17:16:40,759][DEBUG][__main__][thread1.py:17]:start main
   
5. [2014-08-29 17:20:26,417][DEBUG][__main__][thread1.py:17]:start main
   

复制代码

问题：按理说在t.py里边打印的日志，应该能够打到hcagent.log里边？可是为什么没有呢？多线程需要做什么特殊处理？
恳请各位大神赐招

淡定与洒脱

据说python多线程无法使用多core，与单线程性能一样？楼主正好测试下，看网上传言是否属实。

whitelotus19

..........

whyliyi

没太看懂什么意思？能给点提示吗？
我t.py也有自己的日志设置的呀

回复 3# whitelotus19


   

whitelotus19

我在3楼的回复没注意看你的代码，只是大概看了下你的描述，一下理解错了。

我没有用过日志配置文件的方式，我这样试了下好像可以的吧：

thread1.py内容：

1. #!/usr/bin/env python
   
2. # -*- coding: utf-8 -*-
   
3. 
   
4. import logging
   
5. import logging.handlers
   
6. #import logger.log
   
7. import threading
   
8. import t
   
9. 
   
10. #log = logger.log.Logger.getLogger(__name__)
    
11. log = logging.getLogger(__name__)
    
12. log.setLevel(logging.DEBUG)
    
13. logfn='e:/temp/python/test/main.log'
    
14. hd = logging.handlers.RotatingFileHandler(logfn,maxBytes=10000000,backupCount=5)
    
15. formatter = logging.Formatter('%(asctime)s - %(name)s - %(levelname)s :: %(message)s')
    
16. hd.setFormatter(formatter)
    
17. log.addHandler(hd)
    
18. 
    
19. 
    
20. def thread1():
    
21.     t.test('1')
    
22. 
    
23. def thread2():
    
24.     t.test('2')
    
25. 
    
26. 
    
27. if __name__ == '__main__':
    
28.     log.debug('start main')
    
29.     t1 = threading.Timer(10.0, thread1)
    
30.     t1.start()
    
31.     t2 = threading.Timer(10.0, thread2)
    
32.     t2.start()
    
33. 
    
34.     t1.join()
    
35.     t2.join()
    

复制代码

t.py内容：

1. #!/usr/bin/env python
   
2. # -*- coding: utf-8 -*-
   
3. 
   
4. import logging
   
5. import logging.config
   
6. 
   
7. #logging.config.fileConfig('./log.conf')
   
8. log = logging.getLogger(__name__)
   
9. log.setLevel(logging.DEBUG)
   
10. logfn='e:/temp/python/test/thread.log'
    
11. hd = logging.handlers.RotatingFileHandler(logfn,maxBytes=10000000,backupCount=5)
    
12. formatter = logging.Formatter('%(asctime)s - %(name)s - %(levelname)s :: %(message)s')
    
13. hd.setFormatter(formatter)
    
14. log.addHandler(hd)
    
15. 
    
16. def test(name):
    
17.     log.debug( 'thread' + name)
    

复制代码

运行thread1.py后，看到的日志文件内容如下：
main.log里的：
2014-08-30 10:12:20,157 - __main__ - DEBUG :: start main

thread.log里的：
2014-08-30 10:12:30,157 - t - DEBUG :: thread2
2014-08-30 10:12:30,157 - t - DEBUG :: thread1

不知道是不是这个意思？

whyliyi

这样可以了，但是有一个问题，我把程序封装成一个Windows服务程序后，又不能打印了。不知道为什么。
回复 5# whitelotus19


   

whitelotus19

我看了下以前写的程序，应该可以的阿，逻辑结构大体跟你的程序相同，只是我是用的我前面贴的代码的那种写日志的方式，没有用配置文件的方式。

whitelotus19

我写了个demo
http://lotus.pixub.com/?p=438
如果有不对的地方欢迎指正

whyliyi

whitelotus19 发表于 2014-08-31 15:25
我写了个demo
如果有不对的地方欢迎指正

非常感谢，这样就可以了。

还有个问题，请问你知道linux 的服务程序怎么写吗？？我不太会

whyliyi

whitelotus19 发表于 2014-08-31 15:25
我写了个demo
如果有不对的地方欢迎指正

一下是从你demo里边摘下来的日志，有个问题，想问一下，为什么这些日志都是打印的两行呢？？？
2014-08-31 15:08:07,545 – thread – INFO :: thread1: 0 info
2014-08-31 15:08:07,545 – thread – INFO :: thread2: 0 info
2014-08-31 15:08:07,561 – thread – INFO :: thread2: 1 info
2014-08-31 15:08:07,561 – thread – INFO :: thread1: 1 info
2014-08-31 15:08:07,576 – thread – INFO :: thread2: 2 info
2014-08-31 15:08:07,576 – thread – INFO :: thread1: 2 info
2014-08-31 15:08:07,592 – thread – INFO :: thread1: 3 info
2014-08-31 15:08:07,592 – thread – INFO :: thread2: 3 info