logging library doesn't seem to work with spyder

[pidefrem]

Hello,

I'm trying to run a script using python standard library 'logging'. This script works fine from the command line but when run from Spyder, no log is printed.

My configuration is:
Windows 7
Python 2.7.10 :: Anaconda 2.3.0 (64-bit)
spyder-app 2.3.5.2

The script is:

# -*- coding: utf-8 -*-
"""
Simple test using logging
"""
import logging

# Logger configuration:
log_level = logging.DEBUG 
log_format = '%(levelname)s:%(message)s'

# Testing logger
logger = logging.root
logger.basicConfig = logging.basicConfig(format=log_format, filename='test.log', level=log_level)
logger.info('Testing logger (info)')
logger.debug('Testing logger (debug)')
logger.warning('Testing logger (warn)')
logger.info("")

[Nodd]

What do you expect to be printed exactly ? Your logger only writes to test.log, nothing should be shown in the console.

[pidefrem]

Hello,
thank you for the reply. Have you tried running it when you say "Your logger writes to leg.log"? This was my problem actually (not printing to the file test.log). I didn't mention the console in my question but printing to console doesn't seem to work on my version as well.

[Nodd]

Yes, I ran it and the file was created with the correct content. It also works in the console if I remove filename='test.log', (I mentionned the console because you said "no log is printed", it's just a misunderstanding, sorry).
Did you check the working directory ? Since test.log is a relative filename, it will be created in whatever directory you run it from.

[pidefrem]

Ok, thank you for taking the time to run it. It might then be specific to the IPython used by Spyder I have installed on my computer (installed with Anaconda). Thank you for the feedback. I'll just avoid using the log as the problem is not reproducible.

I simplified the script above, but my actual script uses the absolute path.

# -*- coding: utf-8 -*-
import os
import logging

# Global definitions:
workspace_path = 'My/absolute/path'
log_path = os.path.join(workspace_path, 'test.log')

# Logger configuration:
log_level = logging.DEBUG 
log_format = '%(message)s'

# Testing logger
logger = logging.root
logger.basicConfig = logging.basicConfig(format=log_format, filename=log_path, level=log_level)
#logger.basicConfig = logging.basicConfig(format=log_format, level=log_level)
logger.info('Testing logger (info)')
logger.debug('Testing logger (debug)')
logger.warning('Testing logger (warn)')
logger.info("")

[Nodd]

Sorry for all the stupid question, it's only to make sure that we missed nothing obvious. Did you check if there was anything printed in the internal console ? If it's not opened, you can activate it in the Panes submenu.

My setup is quite different (I run the master branch under Linux with python3 from the system), maybe it's reproducible on windows or with python2.

[SunPowerMike]

I'm having a similar issue. The script below runs, and creates the log file in the correct location with the correct information when i run it from 1) DOS window, or 2) double clicking on a *.py file with this inside of it. But when I run it from within Spyder, either 1) using "run file" or, 2) typing into the ipython window, it prints the Warning to the console, but does not create the file.

import logging
logging.basicConfig(filename='c:\temp\logfile13.log',level=logging.DEBUG)
logging.debug('This message should go to the log file')
logging.info('So should this')
logging.warning('And this, too')

Running Python 2.7.9 (default, Dec 10 2014, 12:28:03) [MSC v.1500 64 bit (AMD64)] on win32
Spyder 2.3.7

[TimGJ]

I have the same.

If I run the following code

if __name__ == '__main__':

    logging.basicConfig(format="%(asctime)s: [%(levelname)s], %(message)s", 
                        filename="trafficlight.log", level=logging.DEBUG)
    logging.info("Started")
    for i in range(10):
        logging.debug("Hello #{}".format(i))
    logging.info("Finished!")

from the console I get the expected output

2015-10-26 14:19:30,024: [INFO], Started
2015-10-26 14:19:30,024: [DEBUG], Hello #0
2015-10-26 14:19:30,024: [DEBUG], Hello #1
2015-10-26 14:19:30,025: [DEBUG], Hello #2
2015-10-26 14:19:30,025: [DEBUG], Hello #3
2015-10-26 14:19:30,025: [DEBUG], Hello #4
2015-10-26 14:19:30,025: [DEBUG], Hello #5
2015-10-26 14:19:30,025: [DEBUG], Hello #6
2015-10-26 14:19:30,025: [DEBUG], Hello #7
2015-10-26 14:19:30,025: [DEBUG], Hello #8
2015-10-26 14:19:30,025: [DEBUG], Hello #9
2015-10-26 14:19:30,025: [INFO], Finished!

but nothing if I run it from the Spyder. If I do a logging.warning or logging.error in Spyder I get a message written to the console, but not in the format I have specified. So it's as if there is a logger running where level = logging.INFO but my own newly created logger is being ignored.

I'm running Spyder 2.3.0 Python 3.4.3 64bits, Qt 4.8.6, PyQt4 (API v2) 4.10.4 on Linux

[ccordoba12]

We'll see what to do about this problem for Spyder 3.0 (to be released at the end of the year :-)

[anduls]

I have probably the same: Ubuntu 14.04, spyder 2.3.6 , ipython 4.0.0.

logging.basicConfig(level=logging.DEBUG,format='%(name)s : %(levelname)s : %(module)s.%(funcName)s : line %(lineno)-6d %(message)s')

logger does not show the format tags as line number, module name etc. only the logger level and message. It works with ipython 4.0.0 in a shell.