Pythonのloggingパッケージの仕様調査
-AzureFunctionsでの注意点を添えて-

import logging
val1 = "hello"
logging.warning("varName: {}".format(val1))

import logging
logger = logging.getLogger(__name__) 
val1 = "hello"
logger.warining("varName: {}".format(val1))

> python patternA-logging.py                                                                                                                       
WARNING:root:varName: hello

> python patternB-logger.py                                                                                                                        
varName: hello

import logging 
import argparse
from modules import submodule

# コマンドライン引数でログレベルを指定する
psr  = argparse.ArgumentParser()
psr.add_argument('-m', '--mode', help='実行時のログ出力モード', default='INFO')
args = psr.parse_args()

# Logger, Handler, Formatter をそれぞれインスタンス化
logger = logging.getLogger(__name__)
handler = logging.StreamHandler()
formatter = logging.Formatter("%(asctime)s %(name)s:%(lineno)s %(funcName)s [%(levelname)s]: %(message)s")

# HandlerにFormatterを紐付ける
handler.setFormatter(formatter)

# Loggerにログレベルの閾値を設定
logger.setLevel(args.mode)
handler.setLevel(args.mode)

# LoggerにHandlerを紐付ける
logger.addHandler(handler)

# ログ出力
logger.debug("main: デバッグログです")
logger.info("main: 想定通りです")
logger.warning("main: 想定外です")
logger.error("main: 重大です")
logger.critical("main: 極めて重大です")

# Submoduleを実行
submodule.log(args.mode)

import logging

def log(mode):
    ## Logger, Handler, Formatterをそれぞれインスタンス化
    logger = logging.getLogger(__name__)
    handler = logging.StreamHandler()
    formatter = logging.Formatter("%(asctime)s %(name)s:%(lineno)s %(funcName)s [%(levelname)s]: %(message)s")

    # handlerにformatterを紐付ける
    handler.setFormatter(formatter)

    # loggerにログレベルの閾値を設定
    logger.setLevel(mode)

    # loggerにhandlerを紐付ける
    logger.addHandler(handler)

    # ログ出力
    logger.debug("submodule: デバッグログです")
    logger.info("submodule: 想定通りです")
    logger.warning("submodule: 想定外です")
    logger.error("submodule: 重大です")
    logger.critical("submodule: 極めて重大です")

python main.py -m DEBUG

2023-08-07 17:54:15,261 __main__:30 <module> [DEBUG]: main: デバッグログです
2023-08-07 17:54:15,261 __main__:31 <module> [INFO]: main: 想定通りです
2023-08-07 17:54:15,261 __main__:32 <module> [WARNING]: main: 想定外です
2023-08-07 17:54:15,261 __main__:33 <module> [ERROR]: main: 重大です
2023-08-07 17:54:15,261 __main__:34 <module> [CRITICAL]: main: 極めて重大です
2023-08-07 17:54:15,261 modules.submodule:24 log [DEBUG]: submodule: デバッグログです
2023-08-07 17:54:15,261 modules.submodule:25 log [INFO]: submodule: 想定通りです
2023-08-07 17:54:15,261 modules.submodule:26 log [WARNING]: submodule: 想定外です
2023-08-07 17:54:15,261 modules.submodule:27 log [ERROR]: submodule: 重大です
2023-08-07 17:54:15,261 modules.submodule:28 log [CRITICAL]: submodule: 極めて重大です

python main.py -m CRITICAL

2023-08-07 17:53:30,361 __main__:34 <module> [CRITICAL]: main: 極めて重大です
2023-08-07 17:53:30,361 modules.submodule:28 log [CRITICAL]: submodule: 極めて重大です

import azure.functions as func
import logging
from modules import submodule

app = func.FunctionApp(http_auth_level=func.AuthLevel.ANONYMOUS)

@app.route(route="logging_python")
def logging_python(req: func.HttpRequest) -> func.HttpResponse:
    # Loggerを生成する
    logger = logging.getLogger(__name__)

    # StreamHandlerを定義する
    handler = logging.StreamHandler()

    # Formatterを定義する（第一引数はメッセージのフォーマット文字列、第二引数は日付時刻のフォーマット文字列）
    formatter = logging.Formatter("%(asctime)s %(name)s:%(lineno)s %(funcName)s [%(levelname)s]: %(message)s")

    # HTTPリクエストボディからログ出力モードを指定
    mode = req.params.get('mode')
    if not mode:
        try:
            req_body = req.get_json()
        except ValueError:
            pass
        else:
            mode = req_body.get('mode')

    # HandlerにFormatterを紐付ける
    handler.setFormatter(formatter)
    # Log Levelを変更
    logger.setLevel(mode)
    # LoggerにHandlerを紐付ける
    if not logger.handlers:
        logger.addHandler(handler)
    # ログ出力
    logger.debug("main: デバッグログです")
    logger.info("main: 想定通りです")
    logger.warning("main: 想定外です")
    logger.error("main: 重大です")
    logger.critical("main: 極めて重大です")

    submodule.log(mode)

    return func.HttpResponse(f"This HTTP triggered function on {mode} level log emission.")

2023-12-02T07:32:25Z   [Critical]   main: 極めて重大です
2023-12-02T07:32:25Z   [Critical]   submodule: 極めて重大です

2023-12-02T09:01:50Z   [Information]   2023-12-02 09:01:49,562 function_app:36 logging_python [DEBUG]: main: デバッグログです
2023-12-02T09:01:50Z   [Information]   2023-12-02 09:01:49,568 function_app:37 logging_python [INFO]: main: 想定通りです
2023-12-02T09:01:50Z   [Warning]   2023-12-02 09:01:49,568 function_app:38 logging_python [WARNING]: main: 想定外です
2023-12-02T09:01:50Z   [Verbose]   main: デバッグログです
2023-12-02T09:01:50Z   [Information]   main: 想定通りです
2023-12-02T09:01:50Z   [Warning]   main: 想定外です
2023-12-02T09:01:50Z   [Error]   2023-12-02 09:01:49,569 function_app:39 logging_python [ERROR]: main: 重大です
2023-12-02T09:01:50Z   [Error]   main: 重大です
2023-12-02T09:01:50Z   [Information]   2023-12-02 09:01:49,573 function_app:40 logging_python [CRITICAL]: main: 極めて重大です
2023-12-02T09:01:50Z   [Critical]   main: 極めて重大です
2023-12-02T09:01:50Z   [Information]   2023-12-02 09:01:49,574 modules.submodule:23 log [DEBUG]: submodule: デバッグログです
2023-12-02T09:01:50Z   [Information]   2023-12-02 09:01:49,575 modules.submodule:24 log [INFO]: submodule: 想定通りです
2023-12-02T09:01:50Z   [Verbose]   submodule: デバッグログです
2023-12-02T09:01:50Z   [Warning]   2023-12-02 09:01:49,576 modules.submodule:25 log [WARNING]: submodule: 想定外です
2023-12-02T09:01:50Z   [Information]   submodule: 想定通りです
2023-12-02T09:01:50Z   [Warning]   submodule: 想定外です
2023-12-02T09:01:50Z   [Error]   submodule: 重大です
2023-12-02T09:01:50Z   [Critical]   submodule: 極めて重大です
2023-12-02T09:01:50Z   [Error]   2023-12-02 09:01:49,579 modules.submodule:26 log [ERROR]: submodule: 重大です
2023-12-02T09:01:50Z   [Information]   2023-12-02 09:01:49,579 modules.submodule:27 log [CRITICAL]: submodule: 極めて重大です

{
  "version": "2.0",
  "logging": {
    "logLevel": {
      "default": "Warning"
    },
  〜中略〜
  }
}