pesan log muncul dua kali dengan Python Logging

Saya menggunakan Python logging, dan untuk beberapa alasan, semua pesan saya muncul dua kali.

Saya memiliki modul untuk mengonfigurasi logging:

# BUG: It's outputting logging messages twice - not sure why - it's not the propagate setting.
def configure_logging(self, logging_file):
    self.logger = logging.getLogger("my_logger")
    self.logger.setLevel(logging.DEBUG)
    self.logger.propagate = 0
    # Format for our loglines
    formatter = logging.Formatter("%(asctime)s - %(name)s - %(levelname)s - %(message)s")
    # Setup console logging
    ch = logging.StreamHandler()
    ch.setLevel(logging.DEBUG)
    ch.setFormatter(formatter)
    self.logger.addHandler(ch)
    # Setup file logging as well
    fh = logging.FileHandler(LOG_FILENAME)
    fh.setLevel(logging.DEBUG)
    fh.setFormatter(formatter)
    self.logger.addHandler(fh)

Nanti, saya memanggil metode ini untuk mengkonfigurasi logging:

if __name__ == '__main__':
    tom = Boy()
    tom.configure_logging(LOG_FILENAME)
    tom.buy_ham()

Dan kemudian katakanlah, modul buy_ham, saya akan menelepon:

self.logger.info('Successfully able to write to %s' % path)

Dan untuk beberapa alasan, semua pesan muncul dua kali. Saya mengomentari salah satu penangan arus, masih hal yang sama. Agak aneh, tidak yakin mengapa ini terjadi ... lol. Dengan asumsi saya melewatkan sesuatu yang jelas.

Cheers, Victor

Anda memanggil configure_loggingdua kali (mungkin dalam __init__metode Boy): getLoggerakan mengembalikan objek yang sama, tetapi addHandlertidak memeriksa apakah penangan serupa telah ditambahkan ke logger.

Coba lacak panggilan ke metode itu dan hilangkan salah satunya. Atau atur tanda yang logging_initializeddiinisialisasi ke Falsedalam __init__metode Boydan ubah configure_loggingmenjadi tidak melakukan apa pun jika logging_initializedada True, dan setel ke Truesetelah Anda menginisialisasi logger.

Jika program Anda membuat beberapa Boycontoh, Anda harus mengubah cara Anda melakukan sesuatu dengan configure_loggingfungsi global menambahkan penangan, dan Boy.configure_loggingmetode hanya menginisialisasi self.loggeratribut.

Cara lain untuk menyelesaikan ini adalah dengan memeriksa atribut penangan dari logger Anda:

logger = logging.getLogger('my_logger')
if not logger.handlers:
    # create the handlers and call logger.addHandler(logging_handler)

Jika Anda melihat masalah ini dan Anda tidak menambahkan handler dua kali, lihat jawaban abarnert di sini

Dari dokumen :

Catatan: Jika Anda memasang handler ke sebuah logger dan satu atau lebih dari leluhurnya, itu mungkin mengeluarkan catatan yang sama beberapa kali. Secara umum, Anda tidak perlu memasang penangan ke lebih dari satu pencatat - jika Anda hanya melampirkannya ke pencatat yang sesuai yang tertinggi dalam hierarki pencatat, maka itu akan melihat semua peristiwa yang dicatat oleh semua penebang turunan, asalkan mereka menyebarkan pengaturan dibiarkan disetel ke True. Skenario umum adalah memasang penangan hanya ke root logger, dan membiarkan propagasi mengurus sisanya.

Jadi, jika Anda menginginkan penangan khusus pada "test", dan Anda tidak ingin pesannya juga masuk ke penangan root, jawabannya sederhana: matikan tanda propagasi:

logger.propagate = Salah

Pawang ditambahkan setiap kali Anda menelepon dari luar. Coba Lepaskan Handler setelah Anda menyelesaikan pekerjaan Anda:

self.logger.removeHandler(ch)

Saya pemula python, tetapi ini sepertinya berhasil untuk saya (Python 2.7)

while logger.handlers:
     logger.handlers.pop()

Dalam kasus saya, saya akan mengatur logger.propagate = Falseuntuk mencegah pencetakan ganda.

Dalam kode di bawah ini jika Anda menghapus logger.propagate = Falsemaka Anda akan melihat pencetakan ganda.

import logging
from typing import Optional

_logger: Optional[logging.Logger] = None

def get_logger() -> logging.Logger:
    global _logger
    if _logger is None:
        raise RuntimeError('get_logger call made before logger was setup!')
    return _logger

def set_logger(name:str, level=logging.DEBUG) -> None:
    global _logger
    if _logger is not None:
        raise RuntimeError('_logger is already setup!')
    _logger = logging.getLogger(name)
    _logger.handlers.clear()
    _logger.setLevel(level)
    ch = logging.StreamHandler()
    ch.setLevel(level)
    # warnings.filterwarnings("ignore", "(Possibly )?corrupt EXIF data", UserWarning)
    ch.setFormatter(_get_formatter())
    _logger.addHandler(ch)
    _logger.propagate = False # otherwise root logger prints things again


def _get_formatter() -> logging.Formatter:
    return logging.Formatter(
        '[%(asctime)s] [%(name)s] [%(levelname)s] %(message)s')

Panggilan untuk logging.debug()memanggil logging.basicConfig()jika tidak ada penangan root yang diinstal. Itu terjadi pada saya dalam kerangka pengujian di mana saya tidak dapat mengontrol urutan kasus uji yang dijalankan. Kode inisialisasi saya sedang menginstal yang kedua. Defaultnya menggunakan logging.BASIC_FORMAT yang tidak saya inginkan.

Tampaknya jika Anda mengeluarkan sesuatu ke logger (secara tidak sengaja) kemudian mengkonfigurasinya, itu sudah terlambat. Misalnya, dalam kode saya, saya punya

logging.warning("look out)"

...
ch = logging.StreamHandler(sys.stdout)
root = logging.getLogger()
root.addHandler(ch)

root.info("hello")

Saya akan mendapatkan sesuatu seperti (mengabaikan opsi format)

look out
hello
hello

dan semuanya ditulis untuk stdout dua kali. Saya percaya ini karena panggilan pertama untuk logging.warningmembuat penangan baru secara otomatis, dan kemudian saya secara eksplisit menambahkan penangan lain. Masalahnya hilang ketika saya menghapus logging.warningpanggilan pertama yang tidak disengaja .

Saya mendapatkan situasi yang aneh di mana log konsol digandakan tetapi log file saya tidak. Setelah banyak menggali, saya menemukan jawabannya.

Perlu diketahui bahwa paket pihak ketiga dapat mendaftarkan penebang. Ini adalah sesuatu yang harus diperhatikan (dan dalam beberapa kasus tidak dapat dicegah). Dalam banyak kasus, kode pihak ketiga memeriksa untuk melihat apakah ada penangan root logger yang ada; dan jika tidak ada - mereka mendaftarkan penangan konsol baru.

Solusi saya untuk ini adalah mendaftarkan logger konsol saya di tingkat root:

rootLogger = logging.getLogger()  # note no text passed in--that's how we grab the root logger
if not rootLogger.handlers:
        ch = logging.StreamHandler()
        ch.setLevel(logging.INFO)
        ch.setFormatter(logging.Formatter('%(process)s|%(levelname)s] %(message)s'))
        rootLogger.addHandler(ch)