성태의 닷넷 이야기
홈 주인
모아 놓은 자료
프로그래밍
질문/답변
사용자 관리
사용자
메뉴
아티클
외부 아티클
유용한 코드
온라인 기능
MathJax 입력기
최근 덧글
[정성태] Java - How to use the Foreign Funct...
[정성태] 제가 큰 실수를 했군요. ^^; Delegate를 통한 Bein...
[정성태] Working with Rust Libraries from C#...
[정성태] Detecting blocking calls using asyn...
[정성태] 아쉽게도, 커뮤니티는 아니고 개인 블로그입니다. ^^
[정성태] 질문이 잘 이해가 안 됩니다. 우선, 해당 소스코드에서 ILis...
[양승조
] var대신 dinamic으로 선언해서 해결은 했습니다. 맞는 해...
[양승조
] 또 막혔습니다. ㅠㅠ var list = props[i].Ge...
[양승조
] 아. 감사합니다. 어제는 안됐던것 같은데....정신을 차려야겠네...
[정성태] "props[i].GetValue(props[i])" 코드에서 ...
글쓰기
제목
이름
암호
전자우편
HTML
홈페이지
유형
제니퍼 .NET
닷넷
COM 개체 관련
스크립트
VC++
VS.NET IDE
Windows
Team Foundation Server
디버깅 기술
오류 유형
개발 환경 구성
웹
기타
Linux
Java
DDK
Math
Phone
Graphics
사물인터넷
부모글 보이기/감추기
내용
<div style='display: inline'> <h1 style='font-family: Malgun Gothic, Consolas; font-size: 20pt; color: #006699; text-align: center; font-weight: bold'>파이썬 - uvicorn 사용자 정의 Logger 작성</h1> <p> uvicorn으로,<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > 파이썬 - FastAPI / uvicorn 호스팅 환경에서 asyncio 사용하는 방법 ; <a target='tab' href='https://www.sysnet.pe.kr/2/0/13087'>https://www.sysnet.pe.kr/2/0/13087</a> </pre> <br /> 파이썬 FastAPI 앱을 호스팅하면,<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > from fastapi import FastAPI import nest_asyncio import os nest_asyncio.apply() app = FastAPI() @app.get("/") def read_root(): return "Hello world" </pre> <br /> 보통 이런 식의 출력을 볼 수 있습니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > $ <span style='color: blue; font-weight: bold'>uvicorn main:app --reload --host 0.0.0.0 --port 18003 --loop asyncio</span> INFO: Will watch for changes in these directories: ['/mnt/d/testprj/test_fastapi'] INFO: Uvicorn running on http://0.0.0.0:18003 (Press CTRL+C to quit) INFO: Started reloader process [7601] using watchgod INFO: Started server process [7603] INFO: Waiting for application startup. INFO: Application startup complete. </pre> <br /> 그리고 만약 루트(/) 경로를 웹 브라우저에서 방문하면 이런 식의 출력이 추가됩니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > INFO: 127.0.0.1:45578 - "GET / HTTP/1.1" 200 OK </pre> <br /> uvicorn의 출력 로그는 크게 2가지로 나뉘는데요, 하나는 "uvicorn.error" 범주가 있고 또 하나는 "uvicorn.access"가 있습니다. 위의 출력에서 처음 uvicorn을 호스팅할 때 출력한 "INFO"는 (이름과 어울리지 않게) "uvicorn.error"에 속하고, 이후 개별 경로를 방문할 때 발생하는 로그들이 "uvicorn.access"에 속합니다.<br /> <br /> <hr style='width: 50%' /><br /> <br /> 예제 코드를 통해 보는 것이 ^^ 더 이해가 빠르겠죠? 예를 들기 위해, 로그를 UDP로 전송하는 경우를 가정해 보겠습니다. 우선, 다음과 같이 logging.Handler를 상속받는 클래스를 하나 만들면 됩니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > class UdpLogger(logging.Handler): def __init__(self, udp_server, udp_port): self.endpoint = (udp_server, udp_port) self.sock = socket.socket(socket.AF_INET, socket.SOCK_DGRAM) super().__init__() def emit(self, record): self.sock.sendto(record.getMessage().encode(), self.endpoint) </pre> <br /> 위에서 중요한 것은 "emit" 함수인데요, 이것은 <a target='tab' href='https://docs.python.org/3/library/logging.html#handler-objects'>logger.Handler</a>에 정의된 함수로, Logger.log/debug/info/warning/error/critical의 모든 호출마다 실행되는 함수입니다.<br /> <br /> 전달된 record 인자는 <a target='tab' href='https://docs.python.org/3/library/logging.html#logrecord-objects'>LogRecord Objects</a> 타입이어서, 그 타입이 제공하는 필드를 통해 Logger.log/debug/info/warning/error/critical에서 전달한 정보를 구할 수 있습니다. 위의 코드에서는 단순히 record.getMessage()를 UDP 서버로 전송하는 것이기 때문에 사용자가 Logger.info("test")라고 하는 경우 "test"에 해당하는 문자열이 record.getMessage()로 반환됩니다.<br /> <br /> 이후, 사용은 다음과 같이 할 수 있습니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > <span style='color: blue; font-weight: bold'>handler = UdpLogger('192.168.100.50', 60900) logger = logging.getLogger("uvicorn.error") logger.handlers = [] logger.setLevel(logging.DEBUG) logger.addHandler(handler) </span> print('logger installed') nest_asyncio.apply() app = FastAPI() </pre> <br /> 위의 경우 setLevel을 DEBUG로 설정했는데요, Logger가 제공하는 로깅 함수의 수준이 DEBUG/INFO/WARNING/ERROR/CRITICAL 순이기 때문에 모든 로그를 handler에 전달하겠다는 것을 의미합니다.<br /> <br /> 그래서 다시 실행해 보면,<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > // 실제 실행된 프로세스의 Log $ <span style='color: blue; font-weight: bold'>uvicorn main:app --reload --host 0.0.0.0 --port 18003 --loop asyncio</span> INFO: Will watch for changes in these directories: ['/mnt/d/workshop2/python-agent/testprj/latest_fastapi'] INFO: Uvicorn running on http://0.0.0.0:18003 (Press CTRL+C to quit) INFO: Started reloader process [7667] using watchgod logger installed <span style='color: blue; font-weight: bold'>INFO: Started server process [7669] INFO: Waiting for application startup. INFO: Application startup complete.</span> INFO: 127.0.0.1:56486 - "GET / HTTP/1.1" 200 OK </pre> <br /> 이번엔 UDP 서버에 다음과 같은 출력을 얻을 수 있습니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > // UDP 서버에서 받은 LogRecord.getMessage() Started server process [7683] Waiting for application startup. Application startup complete. </pre> <br /> 보시면 print 코드를 사용한 "logger installed" 메시지가 출력된 후 "GET ..."을 제외한 로그가 들어왔습니다. 이전에 설정했듯이 "GET ..." 로그는 "access log"에 해당하기 때문에 그것도 받고 싶다면 다음과 같이 한 번 더 handler를 추가해야 합니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > logger2 = logging.getLogger(<span style='color: blue; font-weight: bold'>"uvicorn.access"</span>) logger2.handlers = [] logger2.setLevel(logging.DEBUG) logger2.addHandler(handler) </pre> <br /> 이렇게 변경하면 이제 UDP 서버 측에서도 다음의 메시지를 받게 됩니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > 127.0.0.1:56748 - "GET / HTTP/1.1" 200 </pre> <br /> 여기서 한 가지 차이점이 있는데요, "uvicorn.error" 범주로 받았던 호스팅 관련 메시지들은 Logger를 추가해도 uvicorn의 콘솔 화면에 여전히 출력되는 반면 "uvicorn.access" 로그의 경우에는 uvicorn 콘솔 화면에 출력되지 않습니다.<br /> <br /> <hr style='width: 50%' /><br /> <br /> Logger를 설정한 경우 가장 중요한 것이 예외 로그일 것입니다. 그런데 이게 좀 애매합니다. ^^; 일부러 다음과 같이 오류가 발생하도록 만들고,<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > @app.get("/") def read_root(): <span style='color: blue; font-weight: bold'>text = None text.split(' ')</span> return "Hello world" </pre> <br /> 루트를 방문하면 uvicorn 콘솔 화면에는 이렇게 예외의 모든 정보가 나오지만,<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > ERROR: Exception in ASGI application Traceback (most recent call last): File "/home/kevin/.local/lib/python3.8/site-packages/uvicorn/protocols/http/httptools_impl.py", line 372, in run_asgi result = await app(self.scope, self.receive, self.send) ...[생략]... File "/usr/lib/python3.8/asyncio/futures.py", line 178, in result raise self._exception File "/home/kevin/.local/lib/python3.8/site-packages/anyio/_backends/_asyncio.py", line 867, in run result = context.run(func, *args) File "/mnt/d/workshop2/python-agent/testprj/latest_fastapi/./main.py", line 48, in read_root text.split(' ') AttributeError: 'NoneType' object has no attribute 'split' </pre> <br /> UDP 서버에 출력된 텍스트는, 즉 UdpLogger.emit에 의해 전달된 메시지는 단순히 다음과 같습니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > Exception in ASGI application </pre> <br /> 중요한 Traceback 정보가 없군요. ^^; 왜냐하면, 실제로 LogRecord의 getMessage() 함수가 반환하는 값이 그것뿐이기 때문입니다. 이를 위해선 record의 다른 속성을 통해 예외 메시지를 뽑아내서 전송하는 부가 처리를 이렇게 추가해야 합니다.<br /> <br /> <pre style='margin: 10px 0px 10px 10px; padding: 10px 0px 10px 10px; background-color: #fbedbb; overflow: auto; font-family: Consolas, Verdana;' > def emit(self, record): import traceback self.sock.sendto(record.getMessage().encode(), self.endpoint) if record.exc_info is not None: ex = record.exc_info[1] <span style='color: blue; font-weight: bold'>text = traceback.format_exception(etype=type(ex), value=ex, tb=ex.__traceback__)</span> text = ''.join(text) <span style='color: blue; font-weight: bold'>self.sock.sendto(text.encode(), self.endpoint)</span> </pre> <br /> <hr style='width: 50%' /><br /> <br /> 한 가지 유의해야 할 점이 있다면, logging.Handler로 전달하는 값은 오직 Logger.log/debug/info/warning/error/critical 함수에 의한 호출만을 처리할 수 있다는 점입니다. 즉, uvicorn의 콘솔 화면에 출력되는 모든 메시지가 아니라는 건데요, 일례로 print 함수를 이용한 출력은 콘솔에는 나오지만 logging.Handler로는 잡을 수 없습니다.<br /> </p><br /> <br /><hr /><span style='color: Maroon'>[이 글에 대해서 여러분들과 의견을 공유하고 싶습니다. 틀리거나 미흡한 부분 또는 의문 사항이 있으시면 언제든 댓글 남겨주십시오.]</span> </div>
첨부파일
스팸 방지용 인증 번호
3748
(왼쪽의 숫자를 입력해야 합니다.)