エラーが出ているのにログファイルに反映されない。

Question

初歩的な質問ですみません。

cmdのコンソール画面にて対象のファイル「remove.py」を指定して実行すると、コンソール画面にはエラー内容が表示されるのですが、ログファイル出力された「test.log」にはその内容が記載されません。

それぞれの意味は解説ページでなんとなく把握できたのですが、実際の使い方がよくわからない状態です。
検索も下手なので自分のレベルで理解できるところが見つからず、行き詰ってしまったので質問した次第です。
ご指摘いただけると幸いです。
windows10です。
よろしくお願いいたします。

import os
import glob
import time
import shutil
import logging

logger = logging.getLogger('LoggingTest')

logger.setLevel(10)
 
sh = logging.StreamHandler()
logger.addHandler(sh)

fh = logging.FileHandler('C:/Users/user/Desktop/test.log')
logger.addHandler(fh)

formatter = logging.Formatter('%(asctime)s:%(lineno)d:%(levelname)s:%(message)s')
fh.setFormatter(formatter)
sh.setFormatter(formatter)

logger.log(10, 'debag')
logger.log(20, 'info')
logger.log(30, 'warning')
logger.log(100, 'test')


os.chdir('E:/')
shutil.rmtree("DCIM")
shutil.rmtree("MISC")

file_list = glob.glob("*.url")

for file in file_list:
    print("remove：{0}".format(file))
    os.remove(file)
    
file_list = glob.glob("*.db")
 
for file in file_list:
    print("remove：{0}".format(file))
    os.remove(file)

file_list = glob.glob("*.bk")
 
for file in file_list:
    print("remove：{0}".format(file))
    os.remove(file)

###出力されたログファイル（test.log）

2020-11-01 10:39:28,762:30:DEBUG:debag
2020-11-01 10:39:28,762:31:INFO:info
2020-11-01 10:39:28,762:32:WARNING:warning
2020-11-01 10:39:28,762:33:Level 100:test

###コンソール画面

C:\Users\user>C:/Users/user/desktop/remove.py
2020-11-01 10:39:28,762:30:DEBUG:debag
2020-11-01 10:39:28,762:31:INFO:info
2020-11-01 10:39:28,762:32:WARNING:warning
2020-11-01 10:39:28,762:33:Level 100:test
Traceback (most recent call last):
  File "C:\Users\user\Desktop\remove.py", line 37, in <module>
    shutil.rmtree("DCIM")
  File "C:\Users\user\AppData\Local\Programs\Python\Python38-32\lib\shutil.py", line 737, in rmtree
    return _rmtree_unsafe(path, onerror)
  File "C:\Users\user\AppData\Local\Programs\Python\Python38-32\lib\shutil.py", line 596, in _rmtree_unsafe
    onerror(os.scandir, path, sys.exc_info())
  File "C:\Users\user\AppData\Local\Programs\Python\Python38-32\lib\shutil.py", line 593, in _rmtree_unsafe
    with os.scandir(path) as scandir_it:
FileNotFoundError: [WinError 3] 指定されたパスが見つかりません。: 'DCIM'

上記のエラーが「test.log」に反映されるようにしたいです。

Answer 1

例外をフックしてログを吐くようにすることはできます。
ロガーを準備している段階に、このようなコードを挿入してみて下さい。

import sys
import traceback

def _except_hook(type_, value, traceback_):
    s = '例外が発生しました。\n' + ''.join(
        traceback.format_exception(type_, value, traceback_)
    )
    logger.error(s)

sys.excepthook = _except_hook

もちろん毎回try-exceptを書いても良いですが、めんどくさいのが最大の難点です。

import sys

try:
    ...
except:
    logger.exception('エラーが発生しました。\n')
    sys.exit(1)

ところで

ロガーを使うという目的とは異なりますが、リダイレクトも選択肢の一つにはなりそうです。
何にせよ手軽ですし。

C:...> python プログラム.py >> test.log 2>&1

Comments

[TomF]

回答ありがとうございます！
色々なパターンを提示していただき、全て試してログ出力できました！
loggerだけで動かないのには驚きました。

ちなみに処理の内容を全てログに残したい場合はどの様にすればよいのでしょうか？
エラーの取り方がわかればそちらもわかると思っていたのですが、難しく感じています。
図々しいお願いですが、お手すきの際に教えていただけると幸いです。

[LouiS0616]

処理の内容というと、ところどころのprint(...)の出力のことですかね？
標準出力・標準エラー出力を過不足なく拾いたいならば、リダイレクトを使うのが素直だと思います。

[TomF]

回答ありがとうございます。
そうなんですか、リダイレクトが簡単で確かなんですね！
リダイレクトについて色々調べて使っていきたいと思います。
この度は助かりました。
ありがとうございました。