SVG mit dblog extrem langsam

Begonnen von RockThisParty, 06 Januar 2023, 10:07:54

Vorheriges Thema - Nächstes Thema

RockThisParty

Moin in die Runde!

Ich hoffe, ich bin in diesem Unterforum richtig, sonst gern einen Hinweis.
Gefühlt seit der Umstellung von dblog auf Subprozess (das war Anfang Dezember?) brauchen meine SVGs rund 20 Sekunden zum Anzeigen. Sie kommen dann korrekt und zeigen richtige Werte an.
Dabei ist es egal, ob 1 SVG mit 2 Diagrammen oder 3 mit 12 Werte-Linien aufgerufen werden. :-\

attr WEB plotembed steht auf 2. Änderungen bringen keine Verbesserungen.

FHEM läuft im Docker Container auf einem Synology NAS.
dblog sichert in eine Maria DB auf demselben NAS.
Das NAS sollte mit 20 MB und Intel CPU reichlich Performance haben und zeigt an keiner anderen Stelle Auffälligkeiten.
FHEM ist aktuell ... Container ist aktuell (fhem/fhem latest)

Im Log sehe ich keine Einträge rund um das Aufrufen der SVGs. Rund um dbLog sehr sporadisch:
2023.01.06 01:00:00 3: DbLog logdb - addLog created - TS: 2023-01-06 01:00:00, Device: rr_Emilia, Type: ROOMMATE, Event: addLog, Reading: state, Value: home, Unit:
2023.01.06 01:07:47 2: DbLog logdb - Error: Can't call method "prepare" on unblessed reference at ./FHEM/93_DbLog.pm line 4240.

2023.01.06 01:07:47 2: SubProcess: onRun returned error: Can't call method "disconnect" on unblessed reference at ./FHEM/93_DbLog.pm line 2937.

2023.01.06 01:07:47 1: DbLog logdb - SubProcess EXITED!
2023.01.06 01:07:57 2: DbLog logdb - Subprocess >25978< initialized ... ready for non-blocking operation
2023.01.06 01:07:57 3: DbLog logdb - requested DB connection parameters are transmitted
2023.01.06 01:07:57 3: DbLog logdb - DB connection parameters are stored in SubProcess
2023.01.06 01:09:17 3: DbLog logdb - SubProcess connected to fhem
2023.01.06 02:00:00 3: DbLog logdb - addLog created - TS: 2023-01-06 02:00:00, Device: rr_Stefan, Type: ROOMMATE, Event: addLog, Reading: state, Value: home, Unit:


Irgendwelche Ideen?

Danke  :)

rudolfkoenig

ZitatGefühlt seit der Umstellung von dblog auf Subprozess (das war Anfang Dezember?) brauchen meine SVGs rund 20 Sekunden zum Anzeigen.
Das spricht dafuer, dass dieses Unterforum der Falsche ist.
Wie lange dauert es, wenn man in der Detailansicht "Show Preprocessed Input" anklickt?

RockThisParty

Hallo Rudolf,
das Verhalten mit ,,Show preprocessed ..." ist identisch.

DS_Starter

Ich denke nicht dass es an den SVG oder DbLog Modulen liegt, sondern tiefer. Die Fehler bzgl. Unblessed referenz habe ich bisher in dem Kontext noch nicht erlebt.

Setzt mal verbose 4 im dblog und rufe ein svg auf.
Dann poste den logausschnitt.
Und auch das attr showproctime wäre hilfreich um die performance deiner db einschätzen zu können.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

Ralli

... und poste doch mal den Output von


set logdb configCheck
Gruß,
Ralli

Proxmox 8.1 Cluster mit HP ED800G2i7, Intel NUC11TNHi7+NUC7i5BNH, virtualisiertes fhem 6.3 dev, virtualisierte RaspberryMatic (3.75.6.20240316) mit HB-RF-ETH 1.3.0 / RPI-RF-MOD, HM-LAN-GW (1.1.5) und HMW-GW, FRITZBOX 7490 (07.57), FBDECT, Siri und Alexa

RockThisParty

#5
set logdb configCheck rödelt ewig und bricht ab.


Log vom Aufruf eines einzelnen SVGs (Editor-Seite).
Auffällig ist, dass es nach dem Klick 20 Sekunden dauert, bis die Seite angezeigt wird und dann noch mal 20 Sekunden, bis das Diagramm angezeigt wird.
Alle anderen Seiten ohne SVG verhalten sich normal / sind sehr schnell.

2023.01.06 13:38:20 4: DbLog logdb - ################################################################
2023.01.06 13:38:20 4: DbLog logdb - ###                  new get data for SVG                    ###
2023.01.06 13:38:20 4: DbLog logdb - ################################################################
2023.01.06 13:38:20 4: DbLog logdb - main PID: 7424, secondary PID: 3335
2023.01.06 13:38:40 4: logdb - PID: 3335, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'gTagKerstin' AND READING = 'state' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 13:38:40 4: logdb - PID: 3335, rows count: 3
2023.01.06 13:38:40 4: logdb - PID: 3335, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'rr_Kerstin' AND READING = 'state' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 13:38:40 4: logdb - PID: 3335, rows count: 14
2023.01.06 13:38:40 4: logdb - PID: 3335, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'gTagEmilia' AND READING = 'state' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 13:38:40 4: logdb - PID: 3335, rows count: 4
2023.01.06 13:38:40 4: logdb - PID: 3335, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'rr_Emilia' AND READING = 'state' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 13:38:40 4: logdb - PID: 3335, rows count: 14

DS_Starter

Ist das alles was nach dem select kommt ?

Wenn schon configcheck ewig braucht, stimmt mit deiner db grundsätzlich etwas nicht.
Was sagen denn die readings sql_processing_time und background_processing_time ?

Ich gehe blind davon aus dass deine db grottenlangsam ist.

Das muss erstmal gerichtet werden und hat nichts mit SVG oder DbLog an sich zu tun.
Wahrscheinlich muss man hier ein break machen und das Thema in einem Serverthread behandeln.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

DS_Starter

Evtl. Verbose 5 noch einstellen, aber ich ahne das Ergebnis.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

RockThisParty

#8
Dass die DB grundsätzlich "grottenlahm" ist, kann ich ausschließen. Da liegen noch andere, viel größere Datenbanken (u.a. digikam mit >100.000 Fotos) drauf und arbeitet mehr als angemessen schnell. Liegt auf dem NAS auf einer SSD.

Für mich sieht das nach irgendeinem Timeout aus. Ist ja auffällig, dass es sehr konstant 20 Sekunden sind, egal wieviele Daten abgerufen werden.

dblog hat bei mir nicht die genannten readings:
Internals:
   COLUMNS    field length used for Device: 64, Type: 64, Event: 512, Reading: 64, Value: 128, Unit: 32
   CONFIGURATION ./db.conf
   DEF        ./db.conf .*:(1.ACTUAL_TEMPERATURE|1.HUMIDITY).*
   FD         5
   FUUID      5ff78651-f33f-d7f8-4c3f-689a9e08ff48d5d8
   FVERSION   93_DbLog.pm:v5.5.9-s26923/2022-12-29
   MODE       asynchronous
   MODEL      MYSQL
   NAME       logdb
   NR         2
   NTFY_ORDER 50-logdb
   PID        7424
   REGEXP     .*:(1.ACTUAL_TEMPERATURE|1.HUMIDITY).*
   SBP_PID    7425
   SBP_STATE  running
   STATE      connected
   TYPE       DbLog
   UTF8       1
   dbconn     mysql:database=fhem;host=192.168.178.2;port=3306
   dbuser     fhem
   eventCount 147
   HELPER:
     COLSET     1
     DEVICECOL  64
     EVENTCOL   512
     OLDSTATE   connected
     PACKAGE    main
     READINGCOL 64
     TC         current
     TH         history
     TYPECOL    64
     UNITCOL    32
     VALUECOL   128
     VERSION    5.5.9
   OLDREADINGS:
   READINGS:
     2023-01-06 17:43:11   CacheOverflowLastNum 0
     2022-01-28 09:41:23   CacheOverflowLastState normal
     2023-01-06 17:43:56   CacheUsage      3
     2023-01-06 17:43:41   NextSync        2023-01-06 17:44:11 or when CacheUsage 500 is reached
     2023-01-06 17:43:41   state           connected
   hmccu:
Attributes:
   DbLogSelectionMode Include
   asyncMode  1
   room       Zentral->Zentral
   verbose    4

Wo finde ich sie?

Verbose 5 liefert (Klick auf SVG-Seite um 17:48:00 / Editor um 17:48:20 da / Diagramm um 17:48:41:
2023.01.06 17:47:56 4: DbLog logdb - check Device: d_Msg_HeizungStatusOn , Event: state: on
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: d_Msg_HeizungStatusOn , Event: state: on
2023.01.06 17:47:56 4: DbLog logdb - ################################################################
2023.01.06 17:47:56 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:47:56 4: DbLog logdb - ################################################################
2023.01.06 17:47:56 4: DbLog logdb - number of events received: 20 of device: arbeitszimmer_Heizung_HMIP
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE_STATUS: NORMAL
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE_STATUS: NORMAL
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: valve_position: 15
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: valve_position: 15
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.BOOST_MODE: false
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.BOOST_MODE: false
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.WINDOW_STATE: Closed
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.WINDOW_STATE: Closed
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.ACTIVE_PROFILE: 1
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.ACTIVE_PROFILE: 1
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: control: 20
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: control: 20
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: temperature_soll: 20
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: temperature_soll: 20
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.SET_POINT_TEMPERATURE: 20
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.SET_POINT_TEMPERATURE: 20
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: desired-temp: 20
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: desired-temp: 20
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.SET_POINT_MODE: auto
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.SET_POINT_MODE: auto
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.LEVEL_STATUS: NORMAL
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.LEVEL_STATUS: NORMAL
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: state: 21
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: state: 21
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: temperature_ist: 21
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: temperature_ist: 21
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE: 21
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE: 21
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: measured-temp: 21
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: measured-temp: 21
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: devstate: ok
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: devstate: ok
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: hmstate: 21
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: hmstate: 21
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: Ist: 20
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: Ist: 20
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: Soll: 20.0
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: Soll: 20.0
2023.01.06 17:47:56 4: DbLog logdb - check Device: arbeitszimmer_Heizung_HMIP , Event: DisplayTemp: 20.0
2023.01.06 17:47:56 5: DbLog logdb - parsed Event: arbeitszimmer_Heizung_HMIP , Event: DisplayTemp: 20.0
2023.01.06 17:48:20 5: DbLog logdb: Table current present : 0 (0 = not present or no content)
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 4 of device: HUESensor11
2023.01.06 17:48:20 4: DbLog logdb - check Device: HUESensor11 , Event: reachable: 1
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: HUESensor11 , Event: reachable: 1
2023.01.06 17:48:20 4: DbLog logdb - check Device: HUESensor11 , Event: temperature: 19.87
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: HUESensor11 , Event: temperature: 19.87
2023.01.06 17:48:20 4: DbLog logdb - check Device: HUESensor11 , Event: batteryPercent: 26
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: HUESensor11 , Event: batteryPercent: 26
2023.01.06 17:48:20 4: DbLog logdb - check Device: HUESensor11 , Event: battery: 26
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: HUESensor11 , Event: battery: 26
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 3 of device: hueBewegungKueche2
2023.01.06 17:48:20 4: DbLog logdb - check Device: hueBewegungKueche2 , Event: reachable: 1
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: hueBewegungKueche2 , Event: reachable: 1
2023.01.06 17:48:20 5: DbLog logdb - DbLogInclude of "hueBewegungKueche2": state
2023.01.06 17:48:20 4: DbLog logdb - check Device: hueBewegungKueche2 , Event: battery: 26
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: hueBewegungKueche2 , Event: battery: 26
2023.01.06 17:48:20 5: DbLog logdb - DbLogInclude of "hueBewegungKueche2": state
2023.01.06 17:48:20 4: DbLog logdb - check Device: hueBewegungKueche2 , Event: batteryPercent: 26
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: hueBewegungKueche2 , Event: batteryPercent: 26
2023.01.06 17:48:20 5: DbLog logdb - DbLogInclude of "hueBewegungKueche2": state
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 1 of device: aussenfuehler_hum_dummy
2023.01.06 17:48:20 4: DbLog logdb - check Device: aussenfuehler_hum_dummy , Event: state: 1
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: aussenfuehler_hum_dummy , Event: state: 1
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 1 of device: aktualisiere_dummy_hum
2023.01.06 17:48:20 4: DbLog logdb - check Device: aktualisiere_dummy_hum , Event: state: Next: 17:49:15
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: aktualisiere_dummy_hum , Event: state: Next: 17:49:15
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 1 of device: Sonos
2023.01.06 17:48:20 4: DbLog logdb - check Device: Sonos , Event: LastProcessAnswer: 1673023700.36291
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: Sonos , Event: LastProcessAnswer: 1673023700.36291
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 4 of device: d_Heizung_Kueche_Status
2023.01.06 17:48:20 4: DbLog logdb - check Device: d_Heizung_Kueche_Status , Event: cmd_nr: 1
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: d_Heizung_Kueche_Status , Event: cmd_nr: 1
2023.01.06 17:48:20 4: DbLog logdb - check Device: d_Heizung_Kueche_Status , Event: cmd: 1
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: d_Heizung_Kueche_Status , Event: cmd: 1
2023.01.06 17:48:20 4: DbLog logdb - check Device: d_Heizung_Kueche_Status , Event: cmd_event: kueche_Heizung_HMIP
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: d_Heizung_Kueche_Status , Event: cmd_event: kueche_Heizung_HMIP
2023.01.06 17:48:20 4: DbLog logdb - check Device: d_Heizung_Kueche_Status , Event: state: cmd_1
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: d_Heizung_Kueche_Status , Event: state: cmd_1
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 23 of device: kueche_Heizung_HMIP
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: rssidevice: -79
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: rssidevice: -79
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: voltage: 2.5
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: voltage: 2.5
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: battery: ok
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: battery: ok
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: activity: alive
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: activity: alive
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: state: 22.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: state: 22.3
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: temperature_ist: 22.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: temperature_ist: 22.3
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE: 22.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE: 22.3
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: measured-temp: 22.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: measured-temp: 22.3
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.LEVEL_STATUS: NORMAL
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.LEVEL_STATUS: NORMAL
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.SET_POINT_MODE: auto
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.SET_POINT_MODE: auto
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: control: 20
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: control: 20
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: temperature_soll: 20
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: temperature_soll: 20
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.SET_POINT_TEMPERATURE: 20
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.SET_POINT_TEMPERATURE: 20
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: desired-temp: 20
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: desired-temp: 20
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.WINDOW_STATE: Closed
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.WINDOW_STATE: Closed
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.BOOST_MODE: false
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.BOOST_MODE: false
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE_STATUS: NORMAL
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: 1.ACTUAL_TEMPERATURE_STATUS: NORMAL
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: valve_position: 0
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: valve_position: 0
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: devstate: ok
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: devstate: ok
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: hmstate: 22.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: hmstate: 22.3
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: Ist: 21.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: Ist: 21.3
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: Soll: 20.0
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: Soll: 20.0
2023.01.06 17:48:20 4: DbLog logdb - check Device: kueche_Heizung_HMIP , Event: DisplayTemp: 21.3
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: kueche_Heizung_HMIP , Event: DisplayTemp: 21.3
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - number of events received: 1 of device: deC_Rollo_Arbeitszimmer
2023.01.06 17:48:20 4: DbLog logdb - check Device: deC_Rollo_Arbeitszimmer , Event: state: off
2023.01.06 17:48:20 5: DbLog logdb - parsed Event: deC_Rollo_Arbeitszimmer , Event: state: off
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - ###                  new get data for SVG                    ###
2023.01.06 17:48:20 4: DbLog logdb - ################################################################
2023.01.06 17:48:20 4: DbLog logdb - main PID: 7424, secondary PID: 5720
2023.01.06 17:48:22 4: DbLog logdb - ################################################################
2023.01.06 17:48:22 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:22 4: DbLog logdb - ################################################################
2023.01.06 17:48:22 4: DbLog logdb - number of events received: 1 of device: hueBewegungKueche2
2023.01.06 17:48:22 4: DbLog logdb - check Device: hueBewegungKueche2 , Event: state: motion
2023.01.06 17:48:22 5: DbLog logdb - parsed Event: hueBewegungKueche2 , Event: state: motion
2023.01.06 17:48:22 5: DbLog logdb - DbLogInclude of "hueBewegungKueche2": state
2023.01.06 17:48:22 4: DbLog logdb - added event - Timestamp: 2023-01-06 17:48:22, Device: hueBewegungKueche2, Type: HUEDEVICE, Event: state: motion, Reading: state, Value: motion, Unit:
2023.01.06 17:48:29 4: DbLog logdb - ################################################################
2023.01.06 17:48:29 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:29 4: DbLog logdb - ################################################################
2023.01.06 17:48:29 4: DbLog logdb - number of events received: 4 of device: bad_Sensor
2023.01.06 17:48:29 4: DbLog logdb - check Device: bad_Sensor , Event: activity: alive
2023.01.06 17:48:29 5: DbLog logdb - parsed Event: bad_Sensor , Event: activity: alive
2023.01.06 17:48:29 4: DbLog logdb - check Device: bad_Sensor , Event: rssidevice: -66
2023.01.06 17:48:29 5: DbLog logdb - parsed Event: bad_Sensor , Event: rssidevice: -66
2023.01.06 17:48:29 4: DbLog logdb - check Device: bad_Sensor , Event: devstate: updPending
2023.01.06 17:48:29 5: DbLog logdb - parsed Event: bad_Sensor , Event: devstate: updPending
2023.01.06 17:48:29 4: DbLog logdb - check Device: bad_Sensor , Event: hmstate: 20.9
2023.01.06 17:48:29 5: DbLog logdb - parsed Event: bad_Sensor , Event: hmstate: 20.9
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 1 of device: deC_Gruppe_SchreibtischStefan
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Gruppe_SchreibtischStefan , Event: state: on
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Gruppe_SchreibtischStefan , Event: state: on
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 1 of device: deconz_HUEDevice6
2023.01.06 17:48:30 4: DbLog logdb - check Device: deconz_HUEDevice6 , Event: lastseen: 2023-01-06T16:47Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deconz_HUEDevice6 , Event: lastseen: 2023-01-06T16:47Z
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 2 of device: deC_Rollo_Schlafzimmer
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Rollo_Schlafzimmer , Event: lastseen: 2023-01-06T16:45Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Rollo_Schlafzimmer , Event: lastseen: 2023-01-06T16:45Z
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Rollo_Schlafzimmer , Event: state: off
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Rollo_Schlafzimmer , Event: state: off
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 1 of device: deconz_HUEDevice9
2023.01.06 17:48:30 4: DbLog logdb - check Device: deconz_HUEDevice9 , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deconz_HUEDevice9 , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 1 of device: deC_ScannerStefan
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_ScannerStefan , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_ScannerStefan , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 2 of device: deC_Rollo_Kueche
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Rollo_Kueche , Event: lastseen: 2023-01-06T16:45Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Rollo_Kueche , Event: lastseen: 2023-01-06T16:45Z
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Rollo_Kueche , Event: state: dim06%
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Rollo_Kueche , Event: state: dim06%
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 1 of device: deconz_HUEDevice4
2023.01.06 17:48:30 4: DbLog logdb - check Device: deconz_HUEDevice4 , Event: lastseen: 2023-01-06T16:47Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deconz_HUEDevice4 , Event: lastseen: 2023-01-06T16:47Z
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 2 of device: deC_Rollo_Arbeitszimmer
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Rollo_Arbeitszimmer , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Rollo_Arbeitszimmer , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_Rollo_Arbeitszimmer , Event: state: off
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_Rollo_Arbeitszimmer , Event: state: off
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:30 4: DbLog logdb - ################################################################
2023.01.06 17:48:30 4: DbLog logdb - number of events received: 1 of device: deC_LautsprecherStefan
2023.01.06 17:48:30 4: DbLog logdb - check Device: deC_LautsprecherStefan , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:30 5: DbLog logdb - parsed Event: deC_LautsprecherStefan , Event: lastseen: 2023-01-06T16:48Z
2023.01.06 17:48:36 4: DbLog logdb - ################################################################
2023.01.06 17:48:36 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:36 4: DbLog logdb - ################################################################
2023.01.06 17:48:36 4: DbLog logdb - number of events received: 1 of device: hueBewegungKueche2
2023.01.06 17:48:36 4: DbLog logdb - check Device: hueBewegungKueche2 , Event: state: nomotion
2023.01.06 17:48:36 5: DbLog logdb - parsed Event: hueBewegungKueche2 , Event: state: nomotion
2023.01.06 17:48:36 5: DbLog logdb - DbLogInclude of "hueBewegungKueche2": state
2023.01.06 17:48:36 4: DbLog logdb - added event - Timestamp: 2023-01-06 17:48:36, Device: hueBewegungKueche2, Type: HUEDEVICE, Event: state: nomotion, Reading: state, Value: nomotion, Unit:
2023.01.06 17:48:40 4: logdb - PID: 5720, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'aussenfuehler_HMIP' AND READING = '1.ACTUAL_TEMPERATURE' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:01:43, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:04:25, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:06:52, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:09:05, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:12:07, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:14:55, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:17:28, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:19:47, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:21:52, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:27:26, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:29:52, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:32:03, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:35:03, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 00:37:49, DEV: aussenfuehler_HMIP, RD: 1.ACTUAL_TEMPERATURE, VAL: 8.7
2
/////gekürzt////
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:32:01, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 96
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:34:34, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 96
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:36:52, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 96
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:38:56, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 96
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:41:49, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 96
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:44:28, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 96
2023.01.06 17:48:40 5: logdb - SQL-result -> PID: 5720, TS: 2023-01-06 17:46:53, DEV: aussenfuehler_HMIP, RD: 1.HUMIDITY, VAL: 95
2023.01.06 17:48:40 4: logdb - PID: 5720, rows count: 407
2023.01.06 17:48:41 4: DbLog logdb - ################################################################
2023.01.06 17:48:41 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:41 4: DbLog logdb - ################################################################
2023.01.06 17:48:41 4: DbLog logdb - number of events received: 1 of device: hueBewegungKueche2
2023.01.06 17:48:41 4: DbLog logdb - check Device: hueBewegungKueche2 , Event: state: motion
2023.01.06 17:48:41 5: DbLog logdb - parsed Event: hueBewegungKueche2 , Event: state: motion
2023.01.06 17:48:41 5: DbLog logdb - DbLogInclude of "hueBewegungKueche2": state
2023.01.06 17:48:41 4: DbLog logdb - added event - Timestamp: 2023-01-06 17:48:41, Device: hueBewegungKueche2, Type: HUEDEVICE, Event: state: motion, Reading: state, Value: motion, Unit:
2023.01.06 17:48:45 4: DbLog logdb - ################################################################
2023.01.06 17:48:45 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:45 4: DbLog logdb - ################################################################
2023.01.06 17:48:45 4: DbLog logdb - number of events received: 1 of device: gTagKerstin
2023.01.06 17:48:45 4: DbLog logdb - check Device: gTagKerstin , Event: rssi: -51
2023.01.06 17:48:45 5: DbLog logdb - parsed Event: gTagKerstin , Event: rssi: -51
2023.01.06 17:48:45 5: DbLog logdb - DbLogInclude of "gTagKerstin": state
2023.01.06 17:48:50 4: DbLog logdb - ################################################################
2023.01.06 17:48:50 4: DbLog logdb - ###      New database processing cycle - SBP asynchronous    ###
2023.01.06 17:48:50 4: DbLog logdb - ################################################################
2023.01.06 17:48:50 4: DbLog logdb - MemCache contains 3 entries to process
2023.01.06 17:48:50 4: DbLog logdb - DbLogType is: History
2023.01.06 17:48:50 4: DbLog logdb - ################################################################
2023.01.06 17:48:50 4: DbLog logdb - ###              start of new Logcycle                       ###
2023.01.06 17:48:50 4: DbLog logdb - ################################################################
2023.01.06 17:48:50 4: DbLog logdb - number of events received: 1 of device: logdb
2023.01.06 17:48:50 4: DbLog logdb - check Device: logdb , Event: CacheOverflowLastNum: 0
2023.01.06 17:48:50 5: DbLog logdb - parsed Event: logdb , Event: CacheOverflowLastNum: 0
2023.01.06 17:48:50 5: DbLog logdb - MemCache contains:  437 -> 2023-01-06 17:48:22|hueBewegungKueche2|HUEDEVICE|state: motion|state|motion|
2023.01.06 17:48:50 5: DbLog logdb - MemCache contains:  438 -> 2023-01-06 17:48:36|hueBewegungKueche2|HUEDEVICE|state: nomotion|state|nomotion|
2023.01.06 17:48:50 5: DbLog logdb - MemCache contains:  439 -> 2023-01-06 17:48:41|hueBewegungKueche2|HUEDEVICE|state: motion|state|motion|
2023.01.06 17:48:50 4: DbLog logdb - Operation: log_asynch
2023.01.06 17:48:50 5: DbLog logdb - DbLogType: History
2023.01.06 17:48:50 4: DbLog logdb - AutoCommit: ON, Transaction: ON
2023.01.06 17:48:50 4: DbLog logdb - Insert mode: Array
2023.01.06 17:48:50 4: DbLog logdb - Primary Key used in history: TIMESTAMP,DEVICE,READING
2023.01.06 17:48:50 4: DbLog logdb - Primary Key used in current: DEVICE,READING
2023.01.06 17:48:50 5: DbLog logdb - processing 437 -> TS: 2023-01-06 17:48:22, Dev: hueBewegungKueche2, Type: HUEDEVICE, Event: state: motion, Reading: state, Val: motion, Unit:
2023.01.06 17:48:50 5: DbLog logdb - processing 438 -> TS: 2023-01-06 17:48:36, Dev: hueBewegungKueche2, Type: HUEDEVICE, Event: state: nomotion, Reading: state, Val: nomotion, Unit:
2023.01.06 17:48:50 5: DbLog logdb - processing 439 -> TS: 2023-01-06 17:48:41, Dev: hueBewegungKueche2, Type: HUEDEVICE, Event: state: motion, Reading: state, Val: motion, Unit:
2023.01.06 17:48:50 4: DbLog logdb - begin Transaction
2023.01.06 17:48:50 4: DbLog logdb - commit inserted data table history
2023.01.06 17:48:50 4: DbLog logdb - 3 of 3 events inserted into table history using PK on columns TIMESTAMP,DEVICE,READING



Ich habe den Log-Auszug in der Mitte gekürzt, da der Post sonst zu lang wurde.
Für mich sieht das aus, als wenn die Daten in derselben Sekunde kommen, in der sie angefragt werden.
Kann die Verzögerung irgendwo im Zugriff auf die SVG-Dateien liegen? Wo kann ich nach dem Problem suchen? Sorry, bin absolut ratlos.

Danke für Eure Geduld.
Viele Grüße Stefan

DS_Starter

Du hast die Attribute nicht gesetzt die dir oben genannt hatte.  ;)

Vielleicht hast du den Search Index nicht angelegt ?

Leider kann ich auf dem Handy deinen Logauszug nicht lesen.
Der configcheck rennt normal in 0,0x durch, irgenwas ist nicht i.o.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

DS_Starter

#10
Ah, jetzt konnte ich es lesen. Ja, die Daten kommen zügig. Zumindest zu diesem Zeitpunkt was man sieht.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

DS_Starter

Setze dir

  verbose4Devs = ddddd
Dann wird es übersichtlicher zeigt nur den lesefluss vom svg.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

RockThisParty

Habe die beiden Attribute gesetzt. Sorry hatte ich übersehen.

2023.01.06 21:49:56 4: DbLog logdb - ################################################################
2023.01.06 21:49:56 4: DbLog logdb - ###      New database processing cycle - SBP asynchronous    ###
2023.01.06 21:49:56 4: DbLog logdb - ################################################################
2023.01.06 21:49:56 4: DbLog logdb - MemCache contains 3 entries to process
2023.01.06 21:49:56 4: DbLog logdb - DbLogType is: History
2023.01.06 21:49:56 4: DbLog logdb - Operation: log_asynch
2023.01.06 21:49:56 4: DbLog logdb - AutoCommit: ON, Transaction: ON
2023.01.06 21:49:56 4: DbLog logdb - Insert mode: Array
2023.01.06 21:49:56 4: DbLog logdb - Primary Key used in history: TIMESTAMP,DEVICE,READING
2023.01.06 21:49:56 4: DbLog logdb - Primary Key used in current: DEVICE,READING
2023.01.06 21:49:56 4: DbLog logdb - begin Transaction
2023.01.06 21:49:56 4: DbLog logdb - commit inserted data table history
2023.01.06 21:49:56 4: DbLog logdb - 3 of 3 events inserted into table history using PK on columns TIMESTAMP,DEVICE,READING
2023.01.06 21:52:30 4: DbLog logdb - ################################################################
2023.01.06 21:52:30 4: DbLog logdb - ###                  new get data for SVG                    ###
2023.01.06 21:52:30 4: DbLog logdb - ################################################################
2023.01.06 21:52:30 4: DbLog logdb - main PID: 7424, secondary PID: 2180
2023.01.06 21:52:50 4: logdb - PID: 2180, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'aussenfuehler_HMIP' AND READING = '1.ACTUAL_TEMPERATURE' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 21:52:50 4: logdb - PID: 2180, rows count: 498
2023.01.06 21:52:50 4: logdb - PID: 2180, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'aussenfuehler_HMIP' AND READING = '1.HUMIDITY' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 21:52:50 4: logdb - PID: 2180, rows count: 498
2023.01.06 21:53:00 4: DbLog logdb - ################################################################
2023.01.06 21:53:00 4: DbLog logdb - ###      New database processing cycle - SBP asynchronous    ###
2023.01.06 21:53:00 4: DbLog logdb - ################################################################
2023.01.06 21:53:00 4: DbLog logdb - MemCache contains 3 entries to process
2023.01.06 21:53:00 4: DbLog logdb - DbLogType is: History
2023.01.06 21:53:00 4: DbLog logdb - Operation: log_asynch
2023.01.06 21:53:00 4: DbLog logdb - AutoCommit: ON, Transaction: ON
2023.01.06 21:53:00 4: DbLog logdb - Insert mode: Array
2023.01.06 21:53:00 4: DbLog logdb - Primary Key used in history: TIMESTAMP,DEVICE,READING
2023.01.06 21:53:00 4: DbLog logdb - Primary Key used in current: DEVICE,READING
2023.01.06 21:53:00 4: DbLog logdb - begin Transaction
2023.01.06 21:53:00 4: DbLog logdb - commit inserted data table history
2023.01.06 21:53:00 4: DbLog logdb - 3 of 3 events inserted into table history using PK on columns TIMESTAMP,DEVICE,READING


2023.01.06 22:00:00 4: DbLog logdb - ################################################################
2023.01.06 22:00:00 4: DbLog logdb - ###      New database processing cycle - SBP asynchronous    ###
2023.01.06 22:00:00 4: DbLog logdb - ################################################################
2023.01.06 22:00:00 4: DbLog logdb - MemCache contains 4 entries to process
2023.01.06 22:00:00 4: DbLog logdb - DbLogType is: History
2023.01.06 22:00:00 4: DbLog logdb - Operation: log_asynch
2023.01.06 22:00:00 4: DbLog logdb - AutoCommit: ON, Transaction: ON
2023.01.06 22:00:00 4: DbLog logdb - Insert mode: Array
2023.01.06 22:00:00 4: DbLog logdb - Primary Key used in history: TIMESTAMP,DEVICE,READING
2023.01.06 22:00:00 4: DbLog logdb - Primary Key used in current: DEVICE,READING
2023.01.06 22:00:00 4: DbLog logdb - begin Transaction
2023.01.06 22:00:00 4: DbLog logdb - commit inserted data table history
2023.01.06 22:00:00 4: DbLog logdb - 4 of 4 events inserted into table history using PK on columns TIMESTAMP,DEVICE,READING
2023.01.06 22:00:50 4: DbLog logdb - ################################################################
2023.01.06 22:00:50 4: DbLog logdb - ###                  new get data for SVG                    ###
2023.01.06 22:00:50 4: DbLog logdb - ################################################################
2023.01.06 22:00:50 4: DbLog logdb - main PID: 7424, secondary PID: 11942
2023.01.06 22:01:10 4: logdb - PID: 11942, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'aussenfuehler_HMIP' AND READING = '1.ACTUAL_TEMPERATURE' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 22:01:10 4: logdb - PID: 11942, rows count: 503
2023.01.06 22:01:10 4: logdb - PID: 11942, Processing Statement:
SELECT
                      DATE_FORMAT(TIMESTAMP, '%Y-%m-%d %H:%i:%s'),
                      DEVICE,
                      READING,
                      VALUE
                       FROM history WHERE 1=1 AND DEVICE = 'aussenfuehler_HMIP' AND READING = '1.HUMIDITY' AND TIMESTAMP >= STR_TO_DATE('2023-01-06 00:00:00', '%Y-%m-%d %H:%i:%s') AND TIMESTAMP <= STR_TO_DATE('2023-01-07 00:00:00', '%Y-%m-%d %H:%i:%s') ORDER BY TIMESTAMP
2023.01.06 22:01:10 4: logdb - PID: 11942, rows count: 503


Ich habe verbose4devs im ersten Anlauf auf "ddddd" gesetzt (hat das irgendeine Spezialbedeutung oder war ich nur zu doof?) im zweiten Anlauf "SVG_aussenfuehler", das ist das SVG mit dem ich außer im allerersten Log immer getestet habe.

background_processing_time 0.0122 2023-01-06 21:57:00
sql_processing_time 0.0100 2023-01-06 21:57:00


DANKE!!!

DS_Starter

#13
Zitat
Ich habe verbose4devs im ersten Anlauf auf "ddddd" gesetzt (hat das irgendeine Spezialbedeutung oder war ich nur zu doof?)
Nein, ist einfach nur ein Trick die Logcycle-Ausgaben auf ein nicht vorhandes Device (kann auch "blabla" sein) zu filtern. Daduch bleiben nur ein paar Daten und vor allem die "new get data for SVG" -Ausgaben sichtbar.

Deine Readings zeigen Schreibzeiten von ca. 10 ms was ok ist. Das ist die Zeit welche die DB zum Loggen von Daten braucht, nicht für das Lesen (SVG).

Jetzt müsstest du wieder verbose 5 setzen und ein SVG aufrufen, dann sehen wir genauer wieviel Zeit nach dem Aufruf des SVG und der Datenlieferung bzw. dessen Abschluß vergeht weil die Daten im Log jetzt besser gefiltert sind.
ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter

DS_Starter

#14
Was mir noch durch den Kopf gegangen ist .... das checkConfig läuft nicht bei dir und bricht irgendwann ab.
Gibt es eine Fehlermitteilung ?

Beim Aufruf des Kommandos geht das Modul online und ruft vom FHEM Updateserver evtl. neu vorhandene Versionen ab.
Hast du Probleme mit dem Internetzugriff?
Hast du das globale Attribut dnsServer gesetzt?

ESXi@NUC+Debian+MariaDB, PV: SMA, Victron MPII+Pylontech+CerboGX
Maintainer: SSCam, SSChatBot, SSCal, SSFile, DbLog/DbRep, Log2Syslog, SolarForecast,Watches, Dashboard, PylonLowVoltage
Kaffeekasse: https://www.paypal.me/HMaaz
Contrib: https://svn.fhem.de/trac/browser/trunk/fhem/contrib/DS_Starter