MySQl DB probleme -Fehlermeldung - 93_DbLog.pm line 946

[Tedious]

Morgen zusammen,

ich habe im Eventlog Fehlermeldungen zum DBLog:

Code Auswählen Erweitern

2017-01-10 10:03:47 DbLog myDbLog connected
2017-01-10 10:03:47 DbLog myDbLog connected
2017-01-10 10:03:47 DbLog myDbLog connected
2017-01-10 10:03:47 DbLog myDbLog DBD::mysql::st execute_array failed: MySQL server has gone away [err was 2006 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:47 DbLog myDbLog DBD::mysql::st execute_array failed: Data too long for column 'VALUE' at row 1 [err was 1406 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:47 Revolt Revolt_55f1 P:  52.7 E: 210.29 V: 234 C:   0.26 F: 50 Pf: 0.86
2017-01-10 10:03:50 DbLog myDbLog connected
2017-01-10 10:03:50 DbLog myDbLog connected
2017-01-10 10:03:50 DbLog myDbLog connected
2017-01-10 10:03:50 DbLog myDbLog DBD::mysql::st execute_array failed: MySQL server has gone away [err was 2006 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:50 DbLog myDbLog DBD::mysql::st execute_array failed: Data too long for column 'VALUE' at row 1 [err was 1406 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:50 Revolt Revolt_38c3 P:   9.0 E: 394.62 V: 233 C:   0.08 F: 50 Pf: 0.51
2017-01-10 10:03:53 DbLog myDbLog connected
2017-01-10 10:03:53 DbLog myDbLog connected
2017-01-10 10:03:53 DbLog myDbLog connected
2017-01-10 10:03:53 DbLog myDbLog DBD::mysql::st execute_array failed: MySQL server has gone away [err was 2006 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:53 DbLog myDbLog DBD::mysql::st execute_array failed: Data too long for column 'VALUE' at row 1 [err was 1406 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:53 Revolt Revolt_38c3 P:   9.1 E: 394.62 V: 233 C:   0.08 F: 50 Pf: 0.51
2017-01-10 10:03:54 DbLog myDbLog connected
2017-01-10 10:03:54 DbLog myDbLog connected
2017-01-10 10:03:54 DbLog myDbLog connected
2017-01-10 10:03:54 DbLog myDbLog DBD::mysql::st execute_array failed: MySQL server has gone away [err was 2006 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:54 DbLog myDbLog DBD::mysql::st execute_array failed: executing 2 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:54 DbLog myDbLog connected
2017-01-10 10:03:54 DbLog myDbLog DBD::mysql::st execute_array failed: Data too long for column 'READING' at row 1 [err was 1406 now 2000000000] executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.
2017-01-10 10:03:54 Revolt Revolt_55f1 P:  52.5 E: 210.29 V: 234 C:   0.26 F: 50 Pf: 0.86
2017-01-10 10:03:54 Revolt Revolt_55f1 power: 52.5

Die kamen quasi "über Nacht", ich habs nur gesehen weil mache Plots verzerrt ausschauen. Im Log tsand nichts, weil ich Verbose 1 gesetzt habe. Auf Verbose 5 gehts denn los:

Code Auswählen Erweitern

2017.01.10 10:06:56 4: DbLog myDbLog -> ################################################################
2017.01.10 10:06:56 4: DbLog myDbLog -> ###              start of new Logcycle                       ###
2017.01.10 10:06:56 4: DbLog myDbLog -> ################################################################
2017.01.10 10:06:56 4: DbLog myDbLog -> amount of events received: 1 for device: myDbLog
2017.01.10 10:06:56 4: DbLog myDbLog -> check Device: myDbLog , Event: DBD::mysql::st execute_array failed: Data too long for column 'VALUE' at row 1 [err was 1406 now 2000000000]
executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.

2017.01.10 10:06:56 4: DbLog myDbLog -> ################################################################
2017.01.10 10:06:56 4: DbLog myDbLog -> ###              start of new Logcycle                       ###
2017.01.10 10:06:56 4: DbLog myDbLog -> ################################################################
2017.01.10 10:06:56 4: DbLog myDbLog -> amount of events received: 1 for device: Revolt_38c3
2017.01.10 10:06:56 4: DbLog myDbLog -> check Device: Revolt_38c3 , Event: P:   8.3 E: 394.62 V: 233 C:   0.07 F: 50 Pf: 0.50
2017.01.10 10:06:56 4: DbLog myDbLog -> added event to memcache - Timestamp: 2017-01-10 10:06:56, Device: Revolt_38c3, Type: REVOLT, Event: P:   8.3 E: 394.62 V: 233 C:   0.07 F: 50 Pf: 0.50, Reading: P, Value:   8.3 E: 394.62 V: 233 C:   0.07 F: 50 Pf: 0.50, Unit:
2017.01.10 10:06:56 4: DbLog myDbLog -> processing event Timestamp: 2017-01-10 10:06:56, Device: Revolt_38c3, Type: REVOLT, Event: P:   8.3 E: 394.62 V: 233 C:   0.07 F: 50 Pf: 0.50, Reading: P, Value:   8.3 E: 394.62 V: 233 C:   0.07 F: 50 Pf: 0.50, Unit:
2017.01.10 10:06:56 2: DbLog myDbLog -> Error: DBD::mysql::st execute_array failed: Data too long for column 'VALUE' at row 1 [err was 1406 now 2000000000]
executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.

2017.01.10 10:06:56 3: Connecting to database mysql:database=fhem;host=127.0.0.1;port=3306 with user fhemuser
2017.01.10 10:06:56 3: Connection to db mysql:database=fhem;host=127.0.0.1;port=3306 established for pid 632
2017.01.10 10:06:56 4: DbLog myDbLog -> ################################################################
2017.01.10 10:06:56 4: DbLog myDbLog -> ###              start of new Logcycle                       ###
2017.01.10 10:06:56 4: DbLog myDbLog -> ################################################################
2017.01.10 10:06:56 4: DbLog myDbLog -> amount of events received: 1 for device: myDbLog
2017.01.10 10:06:56 4: DbLog myDbLog -> check Device: myDbLog , Event: connected
2017.01.10 10:06:56 4: DbLog myDbLog -> added event to memcache - Timestamp: 2017-01-10 10:06:56, Device: myDbLog, Type: DBLOG, Event: connected, Reading: state, Value: connected, Unit:
2017.01.10 10:06:56 3: Connecting to database mysql:database=fhem;host=127.0.0.1;port=3306 with user fhemuser
2017.01.10 10:06:56 3: Connection to db mysql:database=fhem;host=127.0.0.1;port=3306 established for pid 632
2017.01.10 10:06:56 3: Connection to db mysql:database=fhem;host=127.0.0.1;port=3306 established
2017.01.10 10:06:56 4: DbLog myDbLog -> processing event Timestamp: 2017-01-10 10:06:56, Device: myDbLog, Type: DBLOG, Event: connected, Reading: state, Value: connected, Unit:
2017.01.10 10:06:56 2: DbLog myDbLog -> Error: DBD::mysql::st execute_array failed: MySQL server has gone away [err was 2006 now 2000000000]
executing 1 generated 1 errors at ./FHEM/93_DbLog.pm line 946.

Hatte das schon jemand? Die DB hat einen Check, Analyze und Optimze bekommen und liefert keine Fehler!

EDIT: Problem ist gelöst - hab die Feldlänge erhöht. Kam mir zuerst nicht in den Sinn, da das Ganze ewig sauber und problemlos lief.

Grüße Sascha

[Tedious]

Heute ist der Fehler wieder da... lag also offensichtlich nicht daran. Jemand eine Idee?

[scr3tchy]

Ich habe das gleiche Problem. Genau die gleiche Fehlermeldung. Aber kein genauer Anhaltspunkt, woran das liegen könnte.
Hast du in der Zwischenzeit schon etwas herausgefunden?

[Tedious]

Nein, nicht wirklich. Ich kanns auch nicht reproduzieren - mal ein paar Tage keine Probleme, denn läufts wieder gut. Im Moment ist mal Ruhe...

[Jon-bo]

habt ihr eine Lösung für das Problem gefunden?

[budy]

...Spaltenbreite zu klein... da passen einige Values nicht rein. Steht doch im Log drin...

Gruß,
Stephan

[blofield]

Moin,

ich habe gestern auf MySQL 5.7.19 upgegraded und nun auch das hier beschriebene Problem.
Vorher lief es jahrelang mit 5.1 und nun werden in mein Field "Value" (das noch varchar(32) ist, war vermutlich irgendwann mal das default) auch nix mehr reingeloggt was länger ist. In der History sehe ich, dass vor dem Upgrade bei 32 einfach abgeschnitten wurde, was keine Probleme verursacht hat.
Ein ALTER TABLE auf history möchte ich ungern machen, da meine history sehr groß ist.

Hat jemand noch einen anderen Lösungsvorschlag?
Danke

blofield

[DS_Starter]

Hallo zusammen,

um die Spaltenbreite im Modul der verwendeten Breite in der DB anzupassen gibt es drei Attribute die mit "col ..." beginnen. Das ist für genau diesen beschriebenen Fall gedacht.

Grüße,
Heiko

[blofield]

Hallo Heiko,

wer lesen kann ... habe ich wieder was gelernt.
Vielen Dank.
Da es jahrelang lief habe ich mich mit den ganzen neueren Features nicht auseinandergesetzt.

Grüße
blofield

[DS_Starter]

Es gibt z.B. einen neuen Befehl configCheck der solche Dinge prüft und dir Empfehlungen für Verbesserungen gibt.

VG

[Tedious]

Jau - demit hab ich es denn auch hinbekommen

[Xcoder]

Ist bei mir gerade aktuell geworden weil bei bei Debian von MariaDB 10.1.38 auf 10.3.15 gewechselt wurde. Da MariaDB >=10.2.4 nun den STRICT_TRANS_TABLES sql_mode per default aktiviert hat, gibt es Fehler statt nur Warnungen...