python多線程無(wú)法寫入日志

whyliyi

一個(gè)簡(jiǎn)單的測(cè)試，兩個(gè)線程，在線程中打印日志，日志打印到文件中，發(fā)現(xiàn)多線程無(wú)法打印日志。
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()

復(fù)制代碼

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)

復(fù)制代碼

日志配置文件如下：

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)

復(fù)制代碼

結(jié)果打印的日志如下：

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
   

復(fù)制代碼

問(wèn)題：按理說(shuō)在t.py里邊打印的日志，應(yīng)該能夠打到hcagent.log里邊？可是為什么沒(méi)有呢？多線程需要做什么特殊處理？
懇請(qǐng)各位大神賜招

淡定與灑脫

據(jù)說(shuō)python多線程無(wú)法使用多core，與單線程性能一樣？樓主正好測(cè)試下，看網(wǎng)上傳言是否屬實(shí)。

whitelotus19

..........

whyliyi

沒(méi)太看懂什么意思？能給點(diǎn)提示嗎？
我t.py也有自己的日志設(shè)置的呀

回復(fù) 3# whitelotus19


   

whitelotus19

我在3樓的回復(fù)沒(méi)注意看你的代碼，只是大概看了下你的描述，一下理解錯(cuò)了。

我沒(méi)有用過(guò)日志配置文件的方式，我這樣試了下好像可以的吧：

thread1.py內(nèi)容：

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()
    

復(fù)制代碼

t.py內(nèi)容：

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)
    

復(fù)制代碼

運(yùn)行thread1.py后，看到的日志文件內(nèi)容如下：
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

不知道是不是這個(gè)意思？

whyliyi

這樣可以了，但是有一個(gè)問(wèn)題，我把程序封裝成一個(gè)Windows服務(wù)程序后，又不能打印了。不知道為什么。
回復(fù) 5# whitelotus19


   

whitelotus19

我看了下以前寫的程序，應(yīng)該可以的阿，邏輯結(jié)構(gòu)大體跟你的程序相同，只是我是用的我前面貼的代碼的那種寫日志的方式，沒(méi)有用配置文件的方式。

whitelotus19

我寫了個(gè)demo
http://lotus.pixub.com/?p=438
如果有不對(duì)的地方歡迎指正

whyliyi

whitelotus19 發(fā)表于 2014-08-31 15:25
我寫了個(gè)demo
如果有不對(duì)的地方歡迎指正

非常感謝，這樣就可以了。

還有個(gè)問(wèn)題，請(qǐng)問(wèn)你知道linux 的服務(wù)程序怎么寫嗎？？我不太會(huì)

whyliyi

whitelotus19 發(fā)表于 2014-08-31 15:25
我寫了個(gè)demo
如果有不對(duì)的地方歡迎指正

一下是從你demo里邊摘下來(lái)的日志，有個(gè)問(wèn)題，想問(wèn)一下，為什么這些日志都是打印的兩行呢？？？
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