Moved FDK logging to debug, and turned debug off by default.

This patch quietens down logging by default, but all FDK logging can
still be seen by setting FDK_LOGGING=1.

Author: Matt Curtis

fdk/async_http/app.py

@@ -74,7 +74,7 @@ async def handle_request(self, request, write_callback, stream_callback):
 
             request.uri_template = uri
             response = handler(request)
-            logger.info("got response from function")
+            logger.debug("got response from function")
             res = await response
             body = res.body
             headers = res.headers

fdk/async_http/protocol.py

@@ -251,7 +251,7 @@ def keep_alive_timeout_callback(self):
                 time_left, self.keep_alive_timeout_callback
             )
         else:
-            logger.info("KeepAlive Timeout. Closing connection.")
+            logger.debug("KeepAlive Timeout. Closing connection.")
             self.transport.close()
             self.transport = None
 

fdk/async_http/server.py

@@ -203,7 +203,7 @@ def start_serving():
     def start():
         pid = os.getpid()
         try:
-            logger.info("Starting worker [%s]", pid)
+            logger.debug("Starting worker [%s]", pid)
             if constants.is_py37():
                 loop.run_until_complete(http_server.serve_forever())
             else:

fdk/event_handler.py

@@ -18,6 +18,7 @@
 import sys
 
 from fdk import constants
+from fdk import log
 
 from fdk.async_http import response
 
@@ -36,13 +37,13 @@ def event_handle(handle_code):
     """
     async def pure_handler(request):
         from fdk import runner
-        logger.info("in pure_handler")
+        log.log("in pure_handler")
         headers = dict(request.headers)
         log_frame_header(headers)
         func_response = await runner.handle_request(
             handle_code, constants.HTTPSTREAM,
             headers=headers, data=io.BytesIO(request.body))
-        logger.info("request execution completed")
+        log.log("request execution completed")
 
         headers = func_response.context().GetResponseHeaders()
         status = func_response.status()

fdk/log.py

@@ -18,9 +18,14 @@
 
 
 def __setup_logger():
-    logging.getLogger("asyncio").setLevel(logging.DEBUG)
+    fdk_debug = os.environ.get("FDK_DEBUG") in [
+        'true', '1', 't', 'y', 'yes', 'yeah', 'yup', 'certainly', 'uh-huh']
+
+    logging.getLogger("asyncio").setLevel(logging.WARNING)
     root = logging.getLogger()
-    root.setLevel(logging.DEBUG)
+    if fdk_debug:
+        root.setLevel(logging.DEBUG)
+
     ch = logging.StreamHandler(sys.stderr)
     formatter = logging.Formatter(
         '%(asctime)s - '
@@ -30,7 +35,8 @@ def __setup_logger():
     )
     ch.setFormatter(formatter)
     root.addHandler(ch)
-    return root
+    logger = logging.getLogger("fdk")
+    return logger
 
 
 __log__ = __setup_logger()
@@ -41,7 +47,4 @@ def get_logger():
 
 
 def log(message):
-    fdk_debug = os.environ.get("FDK_DEBUG")
-    if fdk_debug in ['true', '1', 't', 'y', 'yes',
-                     'yeah', 'yup', 'certainly', 'uh-huh']:
-        __log__.info(message)
+    __log__.debug(message)