Freezes durch readingsGroup_Notify

Begonnen von CQuadrat, 12 Juni 2017, 13:20:08

Vorheriges Thema - Nächstes Thema

CQuadrat

Hallo Zusammen,

ich habe das Phänomen, dass ich gelegentlich bei offener readingsGroup regelmäßige (ca. alle zwei Minuten) Freezes von zwei bis drei Sekunden Dauer habe. Der Übeltäter scheint readingsGroup_Notify zu sein:

Log-Auszug:

2017.06.12 05:07:02.147 1: Perfmon: possible freeze starting at 05:07:00, delay is 2.147
2017.06.12 05:11:41.417 1: Perfmon: possible freeze starting at 05:11:38, delay is 3.417
2017.06.12 05:13:52.429 1: Perfmon: possible freeze starting at 05:13:50, delay is 2.428
2017.06.12 05:16:53.692 1: Perfmon: possible freeze starting at 05:16:51, delay is 2.692
2017.06.12 05:18:40.217 1: Perfmon: possible freeze starting at 05:18:39, delay is 1.217
2017.06.12 05:19:40.435 1: Perfmon: possible freeze starting at 05:19:38, delay is 2.435
2017.06.12 05:21:16.679 1: Perfmon: possible freeze starting at 05:21:15, delay is 1.679
2017.06.12 05:22:12.938 1: Perfmon: possible freeze starting at 05:22:11, delay is 1.937
2017.06.12 05:24:31.217 1: Perfmon: possible freeze starting at 05:24:29, delay is 2.217
2017.06.12 05:26:34.438 1: Perfmon: possible freeze starting at 05:26:32, delay is 2.438
2017.06.12 05:29:27.680 1: Perfmon: possible freeze starting at 05:29:25, delay is 2.68
2017.06.12 05:31:27.229 1: Perfmon: possible freeze starting at 05:31:26, delay is 1.229
2017.06.12 05:32:06.441 1: Perfmon: possible freeze starting at 05:32:04, delay is 2.441
2017.06.12 05:34:30.694 1: Perfmon: possible freeze starting at 05:34:28, delay is 2.694
2017.06.12 05:36:40.483 1: Perfmon: possible freeze starting at 05:36:38, delay is 2.482
2017.06.12 05:39:39.969 1: Perfmon: possible freeze starting at 05:39:38, delay is 1.969
2017.06.12 05:42:24.960 1: Perfmon: possible freeze starting at 05:42:23, delay is 1.96
2017.06.12 05:44:55.462 1: Perfmon: possible freeze starting at 05:44:53, delay is 2.462
2017.06.12 05:47:11.688 1: Perfmon: possible freeze starting at 05:47:09, delay is 2.688
2017.06.12 05:49:13.426 1: Perfmon: possible freeze starting at 05:49:11, delay is 2.426
2017.06.12 05:52:04.712 1: Perfmon: possible freeze starting at 05:52:02, delay is 2.712
2017.06.12 05:54:41.718 1: Perfmon: possible freeze starting at 05:54:39, delay is 2.718
2017.06.12 05:57:04.196 1: Perfmon: possible freeze starting at 05:57:02, delay is 2.196


apptime max:

name                                     function                               max  count    total  average maxDly TS Max call     param Max call
HMUART1                                  HMUARTLGW_Read                        2738   2992    58587    19.58      0 12.06. 10:55:46 HASH(HMUART1)
heatingInfo_Bad                          readingsGroup_Notify                  2679   6152     3303     0.54      0 12.06. 10:55:46 HASH(heatingInfo_Bad); HASH(Hzkp_Bad_ClimRT_tr)
myDbLog                                  DbLog_Log                             2630   6152     7540     1.23      0 12.06. 10:55:46 HASH(myDbLog); HASH(heatingInfo_Bad)
tmr-SMARTMON_Update                      HASH(0x586f5f0)                        492      4     1873   468.25      5 12.06. 09:23:53 HASH(SM_sda)
Sonos                                    SONOS_Read                             450      9     1364   151.56      0 12.06. 09:36:17 HASH(Sonos)
tmr-at_Exec                              HASH(0x70fedc0)                        437      4     1668   417.00      6 12.06. 10:57:45 HASH(at_Valves_avg)
tmr-at_Exec                              HASH(0x586e818)                        400      1      400   400.00      4 12.06. 10:11:03 HASH(at_restore)
FHEM_Worf                                FHEM2FHEM_Read                         351     13     2950   226.92      0 12.06. 09:45:47 HASH(FHEM_Worf)
tmr-SMARTMON_Update                      HASH(0x58cc150)                        341      3      999   333.00      4 12.06. 09:47:54 HASH(SM_sdb)
tmr-SMARTMON_Update                      HASH(0x58cfe80)                        341      3      922   307.33      4 12.06. 10:17:54 HASH(SM_sdd)
tmr-rssFeed_GetUpdate                    HASH(0x6491058)                        337      3      887   295.67      4 12.06. 10:28:09 HASH(rss_Tagesschau100Sek)
tmr-SMARTMON_Update                      HASH(0x58d0858)                        331      3      962   320.67     11 12.06. 09:47:54 HASH(SM_sdc)
HMUART2                                  HMUARTLGW_Read                         287   1761     8061     4.58      0 12.06. 09:40:36 HASH(HMUART2)
HMLAN1                                   HMLAN_Read                             278   1261    14620    11.59      0 12.06. 09:36:24 HASH(HMLAN1)
notify_Thrm_Wohnen_change                notify_Exec                            229      2      456   228.00      0 12.06. 09:40:36 HASH(notify_Thrm_Wohnen_change); HASH(Thrm_Wohnen_ClimRT_tr)
HMUSB1                                   HMLAN_Read                             210   1232     9285     7.54      0 12.06. 09:40:40 HASH(HMUSB1)
tmr-at_Exec                              HASH(0x2e4b740)                        194     65     5798    89.20    115 12.06. 09:58:30 HASH(Uhrzeit_at)
tmr-at_Exec                              HASH(0x44d4e78)                        185      5      894   178.80      6 12.06. 09:55:05 HASH(checkGDS)
tmr-at_Exec                              HASH(0x44d4ac0)                        168      5      798   159.60      8 12.06. 09:30:05 HASH(readGDS)
RFXTRXUSB                                TRX_Read                               158   2108    18297     8.68      0 12.06. 10:28:10 HASH(RFXTRXUSB)
tmr-FRITZBOX_Readout_Start               FritzBox_Wlan.Readout                  156    587    13696    23.33   2219 12.06. 09:28:50 FritzBox_Wlan.Readout


(Sind zwar Auszüge zu unterschiedlichen Zeitstempeln, aber inhaltlich ändert das nichts)

Schon ein disable=1 der entsprechenden readingsGroup "behebt" das Problem.

Manchmal tritt das Problem auch auf, wenn kein Frontend geöffnet ist. Dann allerdings bei der readingsGroup, die als letztes offen war. Vermutlich ist da dann noch etwas im "Hintergrund" aktiv.

Hat jemand eine Ahnung woher das kommen kann? Oder kommt das aus DbLog?

Hier gab es wohl schon mal etwas Ähnliches: https://forum.fhem.de/index.php/topic,40635.msg331262.html#msg331262


Danke und Gruß

Christoph
FHEM auf Mini-ITX-Server mit Intel Quad-Core J1900:
+ HM: HM-LAN, HM-USB, HM-MOD-UART mit div. HM-Komponenten
+ RFXtrx: Funkwetterstation Bresser mit ext. Thermometer, Regenmesser und Windmesser
+ TUL (KNX-Anbindung), KM271 (per ser2net), SONOS (div. Gimmicks), OneWire, Hue