wip: uvicorn logging conf to json

app/api/stuff.py

@@ -21,6 +21,7 @@ async def create_multi_stuff(
         db_session.add_all(stuff_instances)
         await db_session.commit()
     except SQLAlchemyError as ex:
+        logger.error(f"Error inserting instances of Stuff: {repr(ex)}")
         raise HTTPException(
             status_code=status.HTTP_422_UNPROCESSABLE_ENTITY, detail=repr(ex)
         ) from ex

app/models/base.py

@@ -5,6 +5,9 @@ from fastapi import HTTPException, status
 from sqlalchemy.exc import SQLAlchemyError, IntegrityError
 from sqlalchemy.ext.asyncio import AsyncSession
 from sqlalchemy.orm import declared_attr, DeclarativeBase
+from app.utils.logging import AppLogger
+
+logger = AppLogger().get_logger()
 
 
 class Base(DeclarativeBase):
@@ -26,6 +29,7 @@ class Base(DeclarativeBase):
             db_session.add(self)
             return await db_session.commit()
         except SQLAlchemyError as ex:
+            logger.error(f"Error inserting instance of {self}: {repr(ex)}")
             raise HTTPException(
                 status_code=status.HTTP_422_UNPROCESSABLE_ENTITY, detail=repr(ex)
             ) from ex

app/utils/logging.py

@@ -20,5 +20,5 @@ class AppLogger(metaclass=SingletonMeta):
 class RichConsoleHandler(RichHandler):
     def __init__(self, width=200, style=None, **kwargs):
         super().__init__(
-            console=Console(color_system="256", width=width, style=style), **kwargs
+            console=Console(color_system="256", width=width, style=style, stderr=True), **kwargs
         )

compose.yml

@@ -7,7 +7,7 @@ services:
       - .secrets
     command: bash -c "
       uvicorn app.main:app
-      --log-config ./config.ini
+      --log-config ./uvicorn-logging.json
       --host 0.0.0.0 --port 8080
       --lifespan=on --use-colors --loop uvloop --http httptools
       --reload --log-level debug

config.ini

@@ -27,20 +27,20 @@ qualname = uvicorn.access
 [handler_stream]
 class = app.utils.logging.RichConsoleHandler
 kwargs = {"omit_repeated_times":True, "show_time": False, "enable_link_path": False, "tracebacks_show_locals": True, "rich_tracebacks": True}
-args = (100, "white")
+args = (140, "white")
 formatter = default
 stream = ext://sys.stdout
 
 [handler_sqlalchemy]
 class = app.utils.logging.RichConsoleHandler
 kwargs = {"omit_repeated_times":True, "show_time": False, "enable_link_path": False, "tracebacks_show_locals": True, "rich_tracebacks": True}
-args = (100, "magenta")
+args = (140, "magenta")
 formatter = default
 
 [handler_uvicorn]
 class = app.utils.logging.RichConsoleHandler
 kwargs = {"omit_repeated_times":True, "show_time": False, "enable_link_path": False, "tracebacks_show_locals": True, "rich_tracebacks": True}
-args = (100, "yellow")
+args = (140, "yellow")
 formatter = default
 
 [formatter_default]

granian-compose.yml

@@ -5,7 +5,7 @@ services:
     env_file:
       - .env
       - .secrets
-    command: granian --interface asgi --host 0.0.0.0 --port 8080 --loop uvloop app.main:app --log-level debug
+    command: granian --interface asgi --host 0.0.0.0 --port 8080 --loop uvloop app.main:app --log-level debug --log-config ./logging-config.json
     volumes:
       - .:/home/code
     ports:

logging-config.json

@@ -1,55 +1,56 @@
 {
-    "version": 1,
+  "version": 1,
-    "disable_existing_loggers": false,
+  "disable_existing_loggers": false,
-    "formatters": {
+  "formatters": {
-        "default": {
+    "generic": {
-            "format": "[%(process)d|%(name)-12s] %(message)s",
+      "()": "logging.Formatter",
-            "class": "logging.Formatter"
+      "fmt": "[%(process)d|%(name)-12s] %(message)s",
-        }
+      "datefmt": "[%Y-%m-%d %H:%M:%S %z]"
     },
-    "handlers": {
+    "access": {
-        "console": {
+      "()": "logging.Formatter",
-            "class": "app.utils.logging.RichConsoleHandler",
+      "fmt": "[%(process)d|%(name)-12s] %(message)s",
-            "level": "DEBUG",
+      "datefmt": "[%Y-%m-%d %H:%M:%S %z]"
-            "formatter": "default",
-            "stream": "ext://sys.stdout",
-            "kwargs": {"omit_repeated_times": "True", "show_time": "False", "enable_link_path": "False", "tracebacks_show_locals": "True", "rich_tracebacks": "True"},
-            "args": "(100, 'white')"
-
-        },
-        "sqlalchemy": {
-//            "class": "logging.handlers.TimedRotatingFileHandler",
-//            "level": "DEBUG",
-//            "formatter": "verbose",
-//            "when": "D",
-//            "backupCount": 0,
-//            "filename": "./logs/training-stats.log"
-        },
-        "granian": {
-//            "class": "logging.handlers.TimedRotatingFileHandler",
-//            "level": "DEBUG",
-//            "formatter": "verbose",
-//            "when": "D",
-//            "backupCount": 0,
-//            "filename": "./logs/uvicorn.log"
-        }
-    },
-    "loggers": {
-        "root": {
-//            "level": "DEBUG",
-//            "handlers": ["console"]
-        },
-        "sqlalchemy.engine.Engine": {
-//            "level": "DEBUG",
-//            "handlers": ["file"],
-//            "propagate": true,
-//            "qualname": "app"
-        },
-        "granian.access": {
-//            "level": "DEBUG",
-//            "handlers": ["uvicorn"],
-//            "propagate": true,
-//            "qualname": "uvicorn"
-        }
     }
+  },
+  "handlers": {
+    "console": {
+      "formatter": "generic",
+      "class": "logging.StreamHandler",
+      "stream": "ext://sys.stdout"
+    },
+    "access": {
+      "formatter": "access",
+      "class": "logging.StreamHandler",
+      "stream": "ext://sys.stdout"
+    },
+    "sqlalchemy": {
+      "class": "app.utils.logging.RichConsoleHandler",
+      "formatter": "generic"
+    }
+  },
+  "loggers": {
+    "_granian": {
+      "handlers": [
+        "console"
+      ],
+      "level": "INFO",
+      "propagate": false
+    },
+    "granian.access": {
+      "handlers": [
+        "access"
+      ],
+      "level": "INFO",
+      "propagate": false
+    },
+    "sqlalchemy.engine.Engine": {
+      "handlers": [
+        "sqlalchemy"
+      ],
+      "level": "ERROR",
+      "propagate": true,
+      "qualname": "sqlalchemy.engine.Engine"
+    }
+  }
 }

uvicorn-logging-old.json

@@ -0,0 +1,52 @@
+
+
+{
+  "version": 1,
+  "disable_existing_loggers": false,
+  "formatters": {
+    "default": {
+      "()": "logging.Formatter",
+      "fmt": "[%(process)d|%(name)-12s] %(message)s"
+    }
+  },
+  "handlers": {
+    "stream": {
+      "formatter": "default",
+      "class": "app.utils.logging.RichConsoleHandler",
+      "stream": "ext://sys.stdout"
+    },
+    "uvicorn": {
+      "formatter": "default",
+      "class": "app.utils.logging.RichConsoleHandler",
+      "stream": "ext://sys.stdout"
+    },
+    "sqlalchemy": {
+      "formatter": "default",
+      "class": "app.utils.logging.RichConsoleHandler"
+    }
+  },
+  "loggers": {
+    "root": {
+      "handlers": [
+        "stream"
+      ],
+      "level": "INFO",
+      "propagate": false
+    },
+    "uvicorn.access": {
+      "handlers": [
+        "uvicorn"
+      ],
+      "level": "INFO",
+      "propagate": false
+    },
+    "sqlalchemy.engine.Engine": {
+      "handlers": [
+        "sqlalchemy"
+      ],
+      "level": "ERROR",
+      "propagate": true,
+      "qualname": "sqlalchemy.engine.Engine"
+    }
+  }
+}

uvicorn-logging.json

@@ -0,0 +1,51 @@
+{
+  "version": 1,
+  "disable_existing_loggers": false,
+  "formatters": {
+    "default": {
+      "()": "logging.Formatter",
+      "fmt": "[%(process)d|%(name)-12s] %(message)s"
+    }
+  },
+  "handlers": {
+    "uvicorn": {
+      "class": "app.utils.logging.RichConsoleHandler",
+      "omit_repeated_times": true,
+      "show_time": false,
+      "enable_link_path": false,
+      "tracebacks_show_locals": true,
+      "rich_tracebacks": true,
+      "formatter": "default",
+      "width": 140,
+      "style": "yellow"
+    },
+    "sqlalchemy": {
+      "class": "app.utils.logging.RichConsoleHandler",
+      "omit_repeated_times": true,
+      "show_time": false,
+      "enable_link_path": false,
+      "tracebacks_show_locals": true,
+      "rich_tracebacks": true,
+      "formatter": "default",
+      "width": 140,
+      "style": "magenta"
+    }
+  },
+  "loggers": {
+    "root": {
+      "handlers": [
+        "uvicorn"
+      ],
+      "propagate": false,
+      "level": "TRACE"
+    },
+    "sqlalchemy.engine.Engine": {
+      "handlers": [
+        "sqlalchemy"
+      ],
+      "level": "ERROR",
+      "propagate": false,
+      "qualname": "sqlalchemy.engine.Engine"
+    }
+  }
+}