DBlog blockiert FHEM völlig wenn keine Verbindung zur DB auf NAS

Peter aus Calw · 10 März 2018, 16:45:52

Hallo Heiko,
nun bin ich konfus, im Logfile kommt nur noch :

Warning: Unable to locate configuration directory, default config not loaded.
Error: Connection refused

was ist das denn ?

Peter aus Calw · 10 März 2018, 16:50:01

und jetzt das :
2018.03.10 16:46:58 1: FHEMWEB SSL/HTTPS error: SSL accept attempt failed because of handshake problems
2018.03.10 16:46:58 3: DbLog dblog - Creating Push-Handle to database mysql:database=fhem;host=localhost;port=3306 with user fhemuser

Code Auswählen

2018.03.10 16:46:58 3: DbLog dblog - Push-Handle to db mysql:database=fhem;host=localhost;port=3306 created
2018.03.10 16:46:58 3: DbLog dblog - UTF8 support enabled
2018.03.10 16:46:58 1: FHEMWEB SSL/HTTPS error:  SSL accept attempt failed because of handshake problems
2018.03.10 16:46:59 4: Dhttps://forum.fhem.de/index.php/topic,83673.0.htmlbLog dblog: Records count requested.

Peter aus Calw · 10 März 2018, 16:57:43

scheint wieder am alten Stand :

Code Auswählen

2018.03.10 16:54:54 4: DbLog dblog -> ################################################################
2018.03.10 16:54:54 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 16:54:54 4: DbLog dblog -> ################################################################
2018.03.10 16:54:54 4: DbLog dblog -> number of events received: 1 for device: EHT_Zaehlerstand
2018.03.10 16:54:54 4: DbLog dblog -> check Device: EHT_Zaehlerstand , Event: average: 13968.048
2018.03.10 16:55:36 4: DbLog dblog: Records count requested.

DS_Starter · 10 März 2018, 16:58:07

Hallo Peter,

das sind DbLog-Meldungen:

Zitat
2018.03.10 16:46:58 3: DbLog dblog - Push-Handle to db mysql:database=fhem;host=localhost;port=3306 created
2018.03.10 16:46:58 3: DbLog dblog - UTF8 support enabled

Das nicht, sondern FHEMWEB bzgl. SSL

Zitat
FHEMWEB SSL/HTTPS error: SSL accept attempt failed because of handshake problems

Hier hast du bloß was falsches reinkopiert und ist nur eine Ausgabe:

Code Auswählen


2018.03.10 16:46:59 4: DbLog dblog: Records count requested.

DS_Starter · 10 März 2018, 16:59:39

Um mal weiter zu kommen, setz mal bitte verbose level auf 5 im DbLog und poste die Ausgaben aus dem Logfile.

Peter aus Calw · 10 März 2018, 17:06:24

das war jetzt mit verbose 5 :

Code Auswählen

2018.03.10 16:54:54 4: DbLog dblog -> ################################################################
2018.03.10 16:54:54 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 16:54:54 4: DbLog dblog -> ################################################################
2018.03.10 16:54:54 4: DbLog dblog -> number of events received: 1 for device: EHT_Zaehlerstand
2018.03.10 16:54:54 4: DbLog dblog -> check Device: EHT_Zaehlerstand , Event: average: 13968.048
2018.03.10 16:55:36 4: DbLog dblog: Records count requested.

hast noch gefragt wegen Eintrag DbExclude und DbInclude in EHT_Zä.. - kein Eintrag vorhanden

DS_Starter · 10 März 2018, 17:25:35

Hmm, um mal etwas reinzubekommen ändere mal bitte das DEF so ab:

Code Auswählen


DEF        /opt/fhem/db.conf  .*:.*

Jetzt wird alles erdenkliche geloggt. Können wir später wieder alles rauslöschen. Aber ich will erstmal was laufen sehen.

Peter aus Calw · 10 März 2018, 17:34:16

Ergebnisse :

Code Auswählen

2018.03.10 17:31:21 4: DbLog dblog -> ################################################################
2018.03.10 17:31:21 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 17:31:21 4: DbLog dblog -> ################################################################
2018.03.10 17:31:21 4: DbLog dblog -> number of events received: 4 for device: 13_Wz
2018.03.10 17:31:21 4: DbLog dblog -> check Device: 13_Wz , Event: cmd_nr: 2
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> number of events received: 1 for device: at_Ladeformat
2018.03.10 17:31:22 4: DbLog dblog -> check Device: at_Ladeformat , Event: state: 36.5
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> number of events received: 4 for device: AT_Ladeformat
2018.03.10 17:31:22 4: DbLog dblog -> check Device: AT_Ladeformat , Event: cmd_nr: 2
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> number of events received: 1 for device: IT_Temp_Vorhersage
2018.03.10 17:31:22 4: DbLog dblog -> check Device: IT_Temp_Vorhersage , Event: state: 8
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> ###              start of new Logcycle                       ###
2018.03.10 17:31:22 4: DbLog dblog -> ################################################################
2018.03.10 17:31:22 4: DbLog dblog -> number of events received: 4 for device: di_wetter
2018.03.10 17:31:22 4: DbLog dblog -> check Device: di_wetter , Event: cmd_nr: 1

aber in countcurrent steht immer noch 0

DS_Starter · 10 März 2018, 17:43:58

Ja, es läuft auch nichts durch den Filter durch, wenn du tatsächlich auf verbose 5 umgestellt hast und trotzdem die Einträge "parsed" und "added" bei dir nicht kommen. Zum Vergleich wie es bei mir aussieht. Der Eintrag "parsed Event" kommt erst dann, wenn ein Event den DEF-Filter erfolgreich passiert hat und "added event" kommt wenn das Loggen nicht durch DbExclude bzw. DbInclude verhindert wird.

Code Auswählen


2018.03.10 17:37:24.742 4: DbLog LogDB -> ################################################################
2018.03.10 17:37:24.743 4: DbLog LogDB -> ###              start of new Logcycle                       ###
2018.03.10 17:37:24.744 4: DbLog LogDB -> ################################################################
2018.03.10 17:37:24.744 4: DbLog LogDB -> number of events received: 5 for device: MyWetter
2018.03.10 17:37:24.749 4: DbLog LogDB -> check Device: MyWetter , Event: wind: 7
2018.03.10 17:37:24.750 5: DbLog LogDB -> parsed Event: MyWetter , Event: wind: 7
2018.03.10 17:37:24.751 4: DbLog LogDB -> added event - Timestamp: 2018-03-10 17:37:24, Device: MyWetter, Type: WEATHER, Event: wind: 7, Reading: wind, Value: 7, Unit: km/h
2018.03.10 17:37:24.752 4: DbLog LogDB -> check Device: MyWetter , Event: humidity: 65
2018.03.10 17:37:24.752 5: DbLog LogDB -> parsed Event: MyWetter , Event: humidity: 65
2018.03.10 17:37:24.753 4: DbLog LogDB -> added event - Timestamp: 2018-03-10 17:37:24, Device: MyWetter, Type: WEATHER, Event: humidity: 65, Reading: humidity, Value: 65, Unit: %
2018.03.10 17:37:24.754 4: DbLog LogDB -> check Device: MyWetter , Event: pressure: 990
2018.03.10 17:37:24.755 5: DbLog LogDB -> parsed Event: MyWetter , Event: pressure: 990
2018.03.10 17:37:24.756 4: DbLog LogDB -> added event - Timestamp: 2018-03-10 17:37:24, Device: MyWetter, Type: WEATHER, Event: pressure: 990, Reading: pressure, Value: 990, Unit: hPa
2018.03.10 17:37:24.757 4: DbLog LogDB -> check Device: MyWetter , Event: temperature: 15
2018.03.10 17:37:24.758 5: DbLog LogDB -> parsed Event: MyWetter , Event: temperature: 15
2018.03.10 17:37:24.759 4: DbLog LogDB -> added event - Timestamp: 2018-03-10 17:37:24, Device: MyWetter, Type: WEATHER, Event: temperature: 15, Reading: temperature, Value: 15, Unit: °C
2018.03.10 17:37:24.760 4: DbLog LogDB -> check Device: MyWetter , Event: wettertest: 07
2018.03.10 17:37:24.761 5: DbLog LogDB -> parsed Event: MyWetter , Event: wettertest: 07
2018.03.10 17:37:24.761 4: DbLog LogDB -> added event - Timestamp: 2018-03-10 17:37:24, Device: MyWetter, Type: WEATHER, Event: wettertest: 07, Reading: wettertest, Value: 07, Unit:

Da aber nichtmal "parsed" kommt bei einem Regex ".*:.*" gibt mir das schwer zu denken.
Mach nochmal bitte lein List von deinem jetzigen DbLog, mir fällt grad nichts sinnvolles ein.

Peter aus Calw · 10 März 2018, 17:53:13

hier das list von dblog :

Code Auswählen

Internals: 
   COLUMNS    field length used for Device: 64, Type: 64, Event: 512, Reading: 64, Value: 128, Unit: 32 
   CONFIGURATION /opt/fhem/db.conf 
   DEF        /opt/fhem/db.conf  .*:.* 
   MODE       asynchronous 
   MODEL      MYSQL 
   NAME       dblog 
   NR         736 
   NTFY_ORDER 50-dblog 
   PID        8712 
   REGEXP     .*:.* 
   STATE      initialized 
   TYPE       DbLog 
   UTF8       1 
   VERSION    3.8.8 
   dbconn     mysql:database=fhem;host=localhost;port=3306 
   dbuser     fhemuser 
   Helper: 
     COLSET     1 
     DEVICECOL  64 
     EVENTCOL   512 
     OLDSTATE   initialized 
     READINGCOL 64 
     TYPECOL    64 
     UNITCOL    32 
     VALUECOL   128 
   Readings: 
     2018-03-10 17:46:51   CacheUsage      0 
     2018-03-10 17:46:51   NextSync        2018-03-10 17:47:21 or if CacheUsage 500 reached 
     2018-03-10 17:31:11   countCurrent    0 
     2018-03-10 17:31:11   countHistory    0 
     2018-03-10 17:46:51   state           initialized 
   Cache: 
     index      0 
Attributes: 
   DbLogType  Current/History 
   asyncMode  1 
   room       DBlog 
   verbose    5

tut mir echt leid, dich so zu beanspruchen, kann es an mysql oder evt. an fhem liegen, hätte kein Problem das neu zu installieren da dieser PC Aspire revo als Ersatz-PC für meine Haussteuerung geplant ist, also im Moment als Testgerät.

Peter aus Calw · 10 März 2018, 18:05:59

Der eigentliche Grund meiner Anfrage von gestern :
Bei "update 93_DbLog.pm" war fhem per Browser erst nach mindestens 2-maligen start/stop von fhem wieder erreichbar.
Könnte das ein Indiz sein ?

DS_Starter · 10 März 2018, 18:08:46

An MySQL liegt es ganz bestimmt nicht und an FHEM an sich bezweifle ich auch. Das was ich sehe passt irgendwie nicht mit dem Ergebnis zusammen da alles richtig aussieht.
Sag mal, ist dein FHEM insgesamt aktuell. d.h. aktuell upgedated ?

Auch interressant wäre ein Auszug der Einträge die der Eventmonitor bringt.
Deine DEF-Änderungen machst du aber im FHEMWEB über den normalen DEF-Editor und nicht durch direktes editieren der fhem.cfg ?

Zitat
Bei "update 93_DbLog.pm" war fhem per Browser erst nach mindestens 2-maligen start/stop von fhem wieder erreichbar.
Könnte das ein Indiz sein ?

Eher nicht wenn ein Restart jetzt ganz normal funktioniert. So etwas ist schwierig zu beurteilen wenn es keine Fehlermitteilungen gibt die etwas aussagen.

Peter aus Calw · 10 März 2018, 18:16:20

Heiko nun mache ich mal ein update, habe das zwar schon mal gemacht, aber es schadet ja nicht.
werde Dir dann berichten, bis dahin ein herzliches Danke.

Peter aus Calw · 10 März 2018, 21:35:09

Hallo Heiko,
habe nun FHEM (version 5.

neu installiert und dann auch DbLog wieder eingerichtet.
Mit dieser Ausgabe von 93_DbLog.pm (kam aus dem Netz) :
$Id: 93_DbLog.pm 11825 2016-07-21 05:40:59Z tobiasfaust $

DEF :
./db.conf EHT_Zaehlerstand:(average).*|ENT_Zaehlerstand:(average).*||EPE_Zaehlerstand:(average).*|EPV_Zaehlerstand:(average).*

wird mysql/phpmyadmin sofort befüllt :

Code Auswählen

Server: localhost »Datenbank: fhem »Tabelle: current
    
[code]SELECT * FROM `current`

+ Optionen
TIMESTAMP 	DEVICE 	TYPE 	EVENT 	READING 	VALUE 	UNIT 	
2018-03-10 21:16:11 	EHT_Zaehlerstand 	VOLKSZAEHLER 	average: 13970.755 	average 	13970.755 	
2018-03-10 21:04:12 	ENT_Zaehlerstand 	VOLKSZAEHLER 	average: 	state 	average: 	
2018-03-10 21:16:12 	ENT_Zaehlerstand 	VOLKSZAEHLER 	average: 98691.537 	average 	98691.537

[/code]

hier noch list logdb :

Code Auswählen

Internals: 
   CFGFN 
   CONFIGURATION ./db.conf 
   DBMODEL    MYSQL 
   DEF        ./db.conf EHT_Zaehlerstand:(average).*|ENT_Zaehlerstand:(average).*||EPE_Zaehlerstand:(average).*|EPV_Zaehlerstand:(average).* 
   
NAME       logdb 
   NR         746 
   NTFY_ORDER 50-logdb 
   PID        7399 
   REGEXP     EHT_Zaehlerstand:(average).*|ENT_Zaehlerstand:(average).*||EPE_Zaehlerstand:(average).*|EPV_Zaehlerstand:(average).* 
   STATE      connected 
   TYPE       DbLog 
   dbconn     mysql:database=fhem;host=127.0.0.1;port=3306 
   dbuser     fhemuser 
   Helper: 
     Dblog: 
       Countcurrent: 
         Logdb: 
           TIME       1520712994.98442 
           VALUE      6 
       Counthistory: 
         Logdb: 
           TIME       1520712994.93851 
           VALUE      38 
       State: 
         Logdb: 
           TIME       1520712986.12875 
           VALUE      connected 
   Readings: 
     2018-03-10 21:16:34   countCurrent    6 
     2018-03-10 21:16:34   countHistory    38 
     2018-03-10 21:16:26   state           connected 
Attributes: 
   DbLogType  Current/History 
   room       DbLog

Habe in FHEM nach der Neuinstallation (aus dem Netz) zwar noch kein globales update gemacht aber so läuft es erstmal.
Würde dazu gerne Deine Meinung hören.
Gruß Peter

Peter aus Calw · 10 März 2018, 21:48:44

neues Ergebnis in phpmyadmin :
+ Optionen
TIMESTAMP    DEVICE    TYPE    EVENT    READING    VALUE    UNIT
2018-03-10 21:46:16    ENT_Zaehlerstand    VOLKSZAEHLER    average: 98691.537    average    98691.537
2018-03-10 21:46:16    EHT_Zaehlerstand    VOLKSZAEHLER    average: 13971.108    average    13971.108
2018-03-10 21:46:16    EPV_Zaehlerstand    VOLKSZAEHLER    average: 20826.009    average    20826.009
2018-03-10 21:46:05    PV_WR1    SMAUTILS    etotal: 29583.926    etotal    29583.926