Python Forum

I am new to python, and am trying to work out a logging issue. I am trying to stream stdoout, as well as stderr, to the log file that I also write entries to when using log...

Also, I am getting duplicate entries on the console, but only one entry in the actual log file when I log entries.

import os 
import datetime
from subprocess import call
import sys
import logging


currDir = os.path.dirname(os.path.abspath(__file__))
today_key = datetime.datetime.now().strftime("%Y%m%d%H%M")
log_file = os.path.join(currDir, "logs", 'stream_'+today_key)+".log"
log_formatter = logging.Formatter('%(asctime)s — %(name)s — %(levelname)s — %(funcName)s:%(lineno)d — %(message)s')
pStart = datetime.datetime.now()

def get_stdout_handler():
    stdout_handler = logging.StreamHandler(sys.stdout)
    stdout_handler.setFormatter(log_formatter)
    return stdout_handler

def get_stderr_handler():
    stderr_handler = logging.StreamHandler(sys.stderr)
    stderr_handler.setFormatter(log_formatter)
    return stderr_handler

def get_file_handler():
    file_handler = logging.FileHandler(log_file, 'a')
    file_handler.setFormatter(log_formatter)
    return file_handler

def get_logger(logger_name):
    log = logging.getLogger(logger_name)
    log.setLevel(logging.DEBUG) 
    log.addHandler(get_stdout_handler())
    log.addHandler(get_stderr_handler())
    log.addHandler(get_file_handler())
    log.propagate = False
    log.hasHandlers = True

    return log

def restore_db():

    call(["ping", "google.com"])


logger = get_logger(__name__)

if __name__ == '__main__':
    logger.info('-----------------------------------')
    logger.info('process start')
    restore_db()
    logger.info('process end')
    logger.info('total processing time (%s)',(datetime.datetime.now() - pStart))
    logger.info('-----------------------------------')

The stream from the ping doesn't appear at all in the log file. Any help would be greatly appreciated.

You most use a subprocess method that capture stdout or stderr call() dos not that.
Also have logging boilerplate code separate,then it look much cleaner.

import subprocess
import my_log

out = subprocess.run(['ping', 'google.com'], capture_output=True)
my_log.logger.info('Start')
output = out.stdout.decode()
my_log.logger.debug(output)
my_log.logger.info('Finish')

Now in file logg.log

Output:2019-04-23 16:13:50,242 - my_log - INFO - Start
2019-04-23 16:13:50,243 - my_log - DEBUG - 
Pinging google.com [216.58.211.142] with 32 bytes of data:
Reply from 216.58.211.142: bytes=32 time=30ms TTL=54
Reply from 216.58.211.142: bytes=32 time=39ms TTL=54
Reply from 216.58.211.142: bytes=32 time=37ms TTL=54
Reply from 216.58.211.142: bytes=32 time=46ms TTL=54

Ping statistics for 216.58.211.142:
    Packets: Sent = 4, Received = 4, Lost = 0 (0% loss),
Approximate round trip times in milli-seconds:
    Minimum = 30ms, Maximum = 46ms, Average = 38ms
2019-04-23 16:13:50,244 - my_log - INFO - Finish

import my_log code:

Hide/Show

import logging

logger = logging.getLogger(__name__)
logger.setLevel(logging.DEBUG)

# create a file handler
handler = logging.FileHandler('logg.log')
handler.setLevel(logging.DEBUG)

# create a logging format
formatter = logging.Formatter('%(asctime)s - %(name)s - %(levelname)s - %(message)s')
handler.setFormatter(formatter)

# add the handlers to the logger
logger.addHandler(handler)

Thank you snippsat

cbj0517

snippsat

cbj0517