[Gelöst]:DBlog gegen MySQL stellt logging ein, wenn ein SVG plot aufgerufen wird

[budy]

Moin,

ich beobachte folgende zwei Probleme mit meinem FHEM, welcher per DBlog gegen eine MySQL loggt:

a) das Logging funktioniert solange einwandfrei, bis ich einen beliebigen der 6 Heizungsplots aufrufe. Manchmal läuft FHEM weiter, während kein Logging mehr stattfindet, manchmal reißt es den FHEM auch mit.

b) Wenn ich den FHEM von der Konsole starte, dann sehe ich immer folgende Ausgaben:

Code Auswählen Erweitern

Can't ignore signal CHLD, forcing to default.


Diese Meldung kommt meiner Meinung nach vom DBlog, aber das scheint irgendwie schwer zu tracen zu sein. Sie ist auch unabhängig davon, ob ich als Bacjend Sqlite oder MySQL verwende. Ich habe auch den Loglevel maximiert sowie die stacktraces aktiviert, aber irgendwie steht da nix auffälliges drin:

Code Auswählen Erweitern

2015.06.04 21:44:11 4: HTTP FHEMWEB:10.10.0.101:59013 GET /fhem?detail=HeizungWhzFlur_plot
2015.06.04 21:44:12 4: 3205:FHEMWEB:10.10.0.101:59013: /fhem?detail=HeizungWhzFlur_plot / RL:6721 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59015
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59014
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59015 GET /fhem/pgm2/jquery-ui.min.css
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59014 GET /fhem/pgm2/style.css
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59017
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59017 GET /fhem/pgm2/jquery-ui.min.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59018
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59018 GET /fhem/pgm2/fhemweb.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59016
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59016 GET /fhem/pgm2/jquery.min.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59019
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59020
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59019 GET /fhem/pgm2/fhemweb_colorpicker.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59021
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59020 GET /fhem/pgm2/fhemweb_knob.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59023
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59021 GET /fhem/pgm2/fhemweb_readingsGroup.js
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59023 GET /fhem/pgm2/fhemweb_readingsHistory.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59024
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59024 GET /fhem/pgm2/fhemweb_sortable.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59025
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59026
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59025 GET /fhem/pgm2/fhemweb_uzsu.js
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59026 GET /fhem/pgm2/svg.js
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59027
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59027 GET /fhem/pgm2/darkCommon.css
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59028
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59028 GET /fhem/pgm2/dashboard_darkstyle.css
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59029
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59029 GET /fhem/SVG_showLog?dev=HeizungWhzFlur_plot&logdev=myDblog&gplotfile=HeizungWhzFlur_plot&logfile=HISTORY&pos=
2015.06.04 21:44:12 5: plotcommand: get myDblog HISTORY INT 2015-06-04_00:00:00 2015-06-05_00:00:01  Heizung_Whz_Flur_Clima:desired-temp Heizung_Whz_Flur_Clima:measured-temp Heizung_Whz_Flur_Clima:ValvePosition
2015.06.04 21:44:12 5: Cmd: >get myDblog HISTORY INT 2015-06-04_00:00:00 2015-06-05_00:00:01 Heizung_Whz_Flur_Clima:desired-temp Heizung_Whz_Flur_Clima:measured-temp Heizung_Whz_Flur_Clima:ValvePosition<
2015.06.04 21:44:12 3: Connecting to database mysql:database=fhemThermostate;host=127.0.0.1;port=3306 with user fhemuser
2015.06.04 21:44:12 3: Connection to db mysql:database=fhemThermostate;host=127.0.0.1;port=3306 established for pid 3423
2015.06.04 21:44:12 5: Triggering myDblog (1 changes)
2015.06.04 21:44:12 5: Notify loop for myDblog connected
2015.06.04 21:44:12 4: Processing Statement: SELECT
                  DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                  DEVICE,
                  READING,
                  VALUE
                   FROM history WHERE 1=1 AND DEVICE  = 'Heizung_Whz_Flur_Clima' AND READING = 'desired-temp' AND TIMESTAMP >= STR_TO_DATE('2015-06-04 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP < STR_TO_DATE('2015-06-05 00:00:01', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2015.06.04 21:44:12 4: Processing Statement: SELECT
                  DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                  DEVICE,
                  READING,
                  VALUE
                   FROM history WHERE 1=1 AND DEVICE  = 'Heizung_Whz_Flur_Clima' AND READING = 'measured-temp' AND TIMESTAMP >= STR_TO_DATE('2015-06-04 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP < STR_TO_DATE('2015-06-05 00:00:01', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59030
2015.06.04 21:44:12 4: Processing Statement: SELECT
                  DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                  DEVICE,
                  READING,
                  VALUE
                   FROM history WHERE 1=1 AND DEVICE  = 'Heizung_Whz_Flur_Clima' AND READING = 'ValvePosition' AND TIMESTAMP >= STR_TO_DATE('2015-06-04 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP < STR_TO_DATE('2015-06-05 00:00:01', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59030 GET /fhem?cmd={ReadingsVal(%22HeizungWhzFlur_plot%22,%22copyGplotFile%22,%22%22)}&XHR=1
2015.06.04 21:44:12 5: Cmd: >{ReadingsVal("HeizungWhzFlur_plot","copyGplotFile","")}<
2015.06.04 21:44:12 4: 3205:FHEMWEB:10.10.0.101:59030: /fhem?cmd={ReadingsVal(%22HeizungWhzFlur_plot%22,%22copyGplotFile%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.06.04 21:44:12 5: Cmd: >{ "Heizung Wohnzimmer/Flur" }<
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59031
2015.06.04 21:44:12 5: Cmd: >{ "Heizung Whz/Flur Temp.: $data{currval2}" }<
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59031 GET /fhem?cmd={AttrVal(%22HeizungWhzFlur_plot%22,%22room%22,%22%22)}&XHR=1
2015.06.04 21:44:12 5: Cmd: >{AttrVal("HeizungWhzFlur_plot","room","")}<
2015.06.04 21:44:12 4: 3205:FHEMWEB:10.10.0.101:59031: /fhem?cmd={AttrVal(%22HeizungWhzFlur_plot%22,%22room%22,%22%22)}&XHR=1 / RL:28 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.06.04 21:44:12 4: 3423:FHEMWEB:10.10.0.101:59029: /fhem/SVG_showLog?dev=HeizungWhzFlur_plot&logdev=myDblog&gplotfile=HeizungWhzFlur_plot&logfile=HISTORY&pos= / RL:2341 / image/svg+xml / Content-Encoding: gzip
/
2015.06.04 21:44:12 4: Connection accepted from FHEMWEB:10.10.0.101:59032
2015.06.04 21:44:12 4: HTTP FHEMWEB:10.10.0.101:59032 GET /fhem?XHR=1&inform=type=status;filter=HeizungWhzFlur_plot;since=1433447050;fmt=JSON&timestamp=1433447052847
2015.06.04 21:44:20 5: PRESENCE (presenceEltern) - stopping timer


Da erscheint noch der Plot, aber anschließend ist das Logging bis zum Neustart von FHEM tot. Hat da jemand eine Idee, wie ich dem Problem auf die Spur kommen könnte?

Gruß,
budy

[rudolfkoenig]

Es gab mal ein aehnliches Problem, das durch das plotfork FHEMWEB Attribut verursacht wurde, ich dachte aber, dass dieses Problem gefixt ist. Ist FHEM aktuell? Auf welchem OS laeuft FHEM?

[budy]

FHEM sollte aktuell sein - zumindest hatte ich den gestern aktualisiert:

Code Auswählen Erweitern

fhem> version
# $Id: fhem.pl 8574 2015-05-14 07:59:32Z rudolfkoenig $


Als OS kommt raspbian auf Basis von jessie zum Einsatz. Das ist zwar eine ganz nue Installation - mein alter FHEM lief auf einem RasPiB unter Pidora, aber zumindest mal die Probleme mit dem Can't ignore signal CHLD, forcing to default, gab es dort auch. Allerdings hatte ich da DBlog ausschließlich gegen zwei SQLite DBs laufen lassen.

Was den plotfork angeht, der steht tatsächlich auf 1 - den kann ich ja mal audf 0 stellen und mir das nochmals ansehen.

Upps... tatsächlich. Das setzen von plotfork auf 0 hat das gefixt. Allerdings hatte ich dich so verstanden, dass es hätte gar nicht auftreten sollen, richtig?