Fragen zu Logging Modul

[Camu]

Hallo zusammen,

ich versuche gerade mich ins logging einzuarbeiten. So grob habe ich den Aufbau und die Funktionsweise verstanden, dachte ich. Habe versucht als Beispiel Fehler von sqlAlchemy zu loggen. Der Code sieht wie folgt aus:

Code: Alles auswählen

import sqlalchemy
import logging

active_db_url = 'mysql+mysqlconnector://user:password@localhost/testdb'

logging.basicConfig(filename='log.log', level=logging.INFO)

logger = logging.getLogger('sqlalchemy.engine')
logger.setLevel(logging.INFO)
fileh = logging.FileHandler('logme.txt')
form = logging.Formatter('%(name)s - %(levelname)s : %(asctime)s - %(message)s')

fileh.setFormatter(form)
logger.addHandler(fileh)

logger.info('info')

engine = sqlalchemy.create_engine(active_db_url, echo=True)
engine.connect()

Korrekterweise bekomme ich folgenden Fehler: InterfaceError: (mysql.connector.errors.InterfaceError) 2003: Can't connect to MySQL server on 'localhost:3306' (10061 Es konnte keine Verbindung hergestellt werden, da der Zielcomputer die Verbindung verweigerte)

In meiner logme.txt steht aber nur folgendes: sqlalchemy.engine - INFO : 2020-03-31 16:31:29,573 - info

Wo ist da mein Fehler?

Danke und viele Grüße

[__blackjack__]

@Camu: Was hättest Du denn da jetzt erwartet? Es werden bei Info die SQL-Abfragen protokolliert. Wenn nicht mal einen Verbindung zur Datenbank zustande kommt, können da auch keine SQL-Anfragen gestellt und damit protokolliert werden.

``echo=True`` ist keine gute Idee wenn Du selbst Logging aufsetzt, weil das intern noch mal `basicConfig()` aufruft.

[Camu]

Ah in Ordnung, ich ging davon aus das alles protokolliert wird was über dem Level Info steht. Was müsste ich denn aber machen damit zum Beispiel auch protokolliert wird das keine Verbindung hergestellt werden konnte?

[DasIch]

Deine Annahme ist richtig. Wenn man eine Exception wirft, loggt man den korrespondierenden Fehler allerdings nicht noch zusätzlich. Dementsprechend wirst du dies selbst loggen müssen.

[__deets__]

Es wird auch alles groesser gleich INFO geloggt. Du hast aber falscherweise angenommen, eine Exception wuerde *auch* geloggt. Das mag sein (schlussendlich ist das jedem Modul/Autor selbst ueberlassen), aber es passiert NICHT automatisch, und augenscheinlich hier auch nicht.

[Camu]

Oh, Exception und Error muss ich mir wohl nochmal anschauen.

Code: Alles auswählen

import sqlalchemy
import logging

active_db_url = 'mysql+mysqlconnector://user:password@localhost/testdb'

logging.basicConfig(filename='log.log', level=logging.INFO)

logger = logging.getLogger('sqlalchemy.engine')
logger.setLevel(logging.INFO)
fileh = logging.FileHandler('logme.txt')
form = logging.Formatter('%(name)s - %(levelname)s : %(asctime)s - %(message)s')

fileh.setFormatter(form)
logger.addHandler(fileh)

logger.info('info')

try:
    engine = sqlalchemy.create_engine(active_db_url, echo=False)
    engine.connect()
except Exception as e:
    #logger.exception('this is an exception')
    logger.error(e, exc_info=True)

Welche Variante bevorzugt man beim logging von exceptions? Die über exc_info=True oder logger.exception?

In meinem Logfile taucht nun die entsprechende Exception auf, aber leider auch noch (tippe Traceback):

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
File "C:\Apps\Anaconda\lib\site-packages\sqlalchemy\engine\base.py", line 2285, in _wrap_pool_connect
return fn()
und viele weitere Zeilen

Kann man dies noch deaktivieren?

[__blackjack__]

@Camu: Du hast das explizit angeschaltet und fragst jetzt wie man es deaktiviert‽

Ich bevorzuge `exception()` für Ausnahmen, weil das genau dafür gedacht ist und man das `exc_info`-Argument nicht extra übergeben muss, denn den Traceback will ich dann natürlich auch haben, damit ich auch eine Chance habe die Ausnahme nachzuvollziehen.

Eventuell möchtest Du die Ausnahme selbst aber auch gar nicht loggen sondern irgendwie anders darauf reagieren.

Warum erstellst Du eigentlich *zwei* Logdateien mit den gleichen Nachrichten die bloss anders formatiert sind?

@__deets__: Die SQLAlchemy Dokumentation ist da recht eindeutig was "sqlalchemy.engine" protokolliert bei INFO und DEBUG. Und alles was da nicht steht, wird halt auch nicht protokolliert.

[Camu]

Danke für die Hilfe. Ich bekomme halt in der Spyder GUI nur den Fehler angezeigt, daher dachte ich das man dies reduzieren kann. Aber Du hast schon recht, es zu deaktivieren macht keinen Sinn.

Eigentlich sollte die sqlalchemy Logging Info nur in die logme.txt - sonstige spätere Fehlermeldungen in die log.log - wie ich das aber richtig programmiere hab ich noch nicht herausgefunden.