blakeblackshear.frigate/frigate/log.py

107 lines
3.1 KiB
Python
Raw Normal View History

2020-11-04 04:26:39 +01:00
# adapted from https://medium.com/@jonathonbao/python3-logging-with-multiprocessing-f51f460b8778
import logging
import multiprocessing as mp
2020-12-04 13:59:03 +01:00
import os
2020-11-30 02:29:32 +01:00
import queue
import signal
import threading
from collections import deque
2020-11-04 04:26:39 +01:00
from logging import handlers
from multiprocessing.queues import Queue
from types import FrameType
from typing import Deque, Optional
from setproctitle import setproctitle
2020-11-04 04:26:39 +01:00
from frigate.util import clean_camera_user_pass
2020-11-04 13:31:25 +01:00
def listener_configurer() -> None:
2020-11-04 04:26:39 +01:00
root = logging.getLogger()
if root.hasHandlers():
root.handlers.clear()
2020-11-04 04:26:39 +01:00
console_handler = logging.StreamHandler()
formatter = logging.Formatter(
"[%(asctime)s] %(name)-30s %(levelname)-8s: %(message)s", "%Y-%m-%d %H:%M:%S"
)
2020-11-04 04:26:39 +01:00
console_handler.setFormatter(formatter)
root.addHandler(console_handler)
root.setLevel(logging.INFO)
2021-02-17 14:23:32 +01:00
def root_configurer(queue: Queue) -> None:
2020-11-04 04:26:39 +01:00
h = handlers.QueueHandler(queue)
root = logging.getLogger()
if root.hasHandlers():
root.handlers.clear()
2020-11-04 04:26:39 +01:00
root.addHandler(h)
root.setLevel(logging.INFO)
2021-02-17 14:23:32 +01:00
def log_process(log_queue: Queue) -> None:
threading.current_thread().name = "logger"
2021-01-03 20:41:02 +01:00
setproctitle("frigate.logger")
2020-11-04 04:26:39 +01:00
listener_configurer()
stop_event = mp.Event()
def receiveSignal(signalNumber: int, frame: Optional[FrameType]) -> None:
stop_event.set()
signal.signal(signal.SIGTERM, receiveSignal)
signal.signal(signal.SIGINT, receiveSignal)
2020-11-04 04:26:39 +01:00
while True:
2020-11-29 23:19:59 +01:00
try:
record = log_queue.get(timeout=1)
2021-05-18 07:52:08 +02:00
except (queue.Empty, KeyboardInterrupt):
if stop_event.is_set():
break
2020-11-29 23:19:59 +01:00
continue
2023-05-31 16:12:43 +02:00
if record.msg.startswith("You are using a scalar distance function"):
continue
2020-11-04 04:26:39 +01:00
logger = logging.getLogger(record.name)
logger.handle(record)
2020-12-04 13:59:03 +01:00
2021-02-17 14:23:32 +01:00
2020-12-04 13:59:03 +01:00
# based on https://codereview.stackexchange.com/a/17959
class LogPipe(threading.Thread):
def __init__(self, log_name: str):
"""Setup the object with a logger and start the thread"""
2020-12-04 13:59:03 +01:00
threading.Thread.__init__(self)
self.daemon = False
self.logger = logging.getLogger(log_name)
self.level = logging.ERROR
self.deque: Deque[str] = deque(maxlen=100)
2020-12-04 13:59:03 +01:00
self.fdRead, self.fdWrite = os.pipe()
self.pipeReader = os.fdopen(self.fdRead)
self.start()
def cleanup_log(self, log: str) -> str:
"""Cleanup the log line to remove sensitive info and string tokens."""
log = clean_camera_user_pass(log).strip("\n")
return log
def fileno(self) -> int:
2021-02-17 14:23:32 +01:00
"""Return the write file descriptor of the pipe"""
2020-12-04 13:59:03 +01:00
return self.fdWrite
def run(self) -> None:
2021-02-17 14:23:32 +01:00
"""Run the thread, logging everything."""
for line in iter(self.pipeReader.readline, ""):
self.deque.append(self.cleanup_log(line))
2020-12-04 13:59:03 +01:00
self.pipeReader.close()
2021-02-17 14:23:32 +01:00
def dump(self) -> None:
while len(self.deque) > 0:
self.logger.log(self.level, self.deque.popleft())
2020-12-04 13:59:03 +01:00
def close(self) -> None:
2021-02-17 14:23:32 +01:00
"""Close the write end of the pipe."""
2021-01-03 20:41:02 +01:00
os.close(self.fdWrite)