Hallo Zusammen,
ich habe mehrmals am Tag das Problem dass das Gateway bei geöffnet wird weil ein Delay bei keepAlive über 5 Sekunden auftritt.
Output apptime:
active-timers: 40; max-active timers: 41; max-timer-load: 5 min-tmrHandlingTm: 0.0ms; max-tmrHandlingTm: 1516.0ms; totAvgDly: 86.7ms
name function max count total average maxDly avgDly TS Max call param Max call
HMLANGW HMUARTLGW_Read 4102 61 5054.38 82.86 0.00 0.00 10.03. 10:00:43 HASH(HMLANGW)
DBLogging DbLog_Log 3899 236 12084.69 51.21 0.00 0.00 10.03. 10:00:43 HASH(DBLogging); HASH(EZ_HeizungsThermostat)
HMLANGW2 HMUARTLGW_Read 2450 62 4662.15 75.20 0.00 0.00 10.03. 10:02:58 HASH(HMLANGW2)
tmr-SYSMON_Update HASH(0x3ae40c0) 1514 4 2619.29 654.82 1.33 1.23 10.03. 10:00:49 HASH(BU.sysmon)
DBLogging DbLog_Get 401 21 2690.82 128.13 0.00 0.00 10.03. 10:00:12 HASH(DBLogging); DBLogging; HISTORY; INT; 2018-03-10_00:00:00; 2018-03-10_23:59:59; BU.sysmon:ALO_cpu_SumUsed; BU.sysmon:ALO_cpu_user; BU.sysmon:ALO_cpu_sys; BU.sysmon:ALO_cpu_nice; BU.sysmon:ALO_cpu_irq; BU.sysmon:ALO_cpu_sirq; BU.sysmon:ALO_cpu_io
WEB FW_Read 296 20 2707.52 135.38 0.00 0.00 10.03. 10:03:25 HASH(WEB)
n_publish_mqtt notify_Exec 90 236 1437.29 6.09 0.00 0.00 10.03. 09:59:49 HASH(n_publish_mqtt); HASH(BU.sysmon)
Mosquitto MQTT::Read 50 45 1627.72 36.17 0.00 0.00 10.03. 10:00:43 HASH(Mosquitto)
mqttSonoffList readingsGroup_Notify 49 236 726.99 3.08 0.00 0.00 10.03. 10:01:49 HASH(mqttSonoffList); HASH(BU.sysmon)
mqttSonoffUpdate readingsGroup_Notify 43 236 616.62 2.61 0.00 0.00 10.03. 10:02:49 HASH(mqttSonoffUpdate); HASH(BU.sysmon)
tmr-freezemon_ProcessTimer HASH(0x4f866d0) 42 211 556.79 2.64 3595.96 48.18 10.03. 10:01:08 HASH(fm_Raspi)
mqttPublishList readingsGroup_Notify 37 236 623.37 2.64 0.00 0.00 10.03. 10:02:49 HASH(mqttPublishList); HASH(BU.sysmon)
WEB_192.168.50.249_50639 FW_Read 33 3 34.06 11.35 0.00 0.00 10.03. 10:03:35 HASH(WEB_192.168.50.249_50639)
rg_battery readingsGroup_Notify 32 236 304.36 1.29 0.00 0.00 10.03. 10:00:28 HASH(rg_battery); HASH(MA_HeizungsThermostat)
rg_Heizung readingsGroup_Notify 25 236 276.24 1.17 0.00 0.00 10.03. 10:01:28 HASH(rg_Heizung); HASH(FZ_HeizungsThermostat_Clima)
tmr-PRESENCE_StartLocalScan HASH(0x35db768) 15 6 80.11 13.35 391.99 66.42 10.03. 10:01:59 HASH(BluetoothAnwesend)
rg_HM_Components readingsGroup_Notify 13 236 269.30 1.14 0.00 0.00 10.03. 10:01:55 HASH(rg_HM_Components); HASH(WZ_WandThermostat_Climate)
tmr-Weather_GetUpdate HASH(0x3139b00) 11 1 11.28 11.28 241.63 241.63 10.03. 10:03:25 HASH(OUT.Wetter)
WEB_192.168.50.249_50641 FW_Read 7 1 7.74 7.74 0.00 0.00 10.03. 10:03:35 HASH(WEB_192.168.50.249_50641)
tmr-CUL_HM_ActCheck ActionDetector 6 1 6.79 6.79 1.44 1.44 10.03. 10:02:39 ActionDetector
apiWEB_127.0.0.1_53002 FW_Notify 5 236 340.24 1.44 0.00 0.00 10.03. 10:01:55 HASH(apiWEB_127.0.0.1_53002); HASH(WZ_WandThermostat_Climate)
WZ_LichtSzene LightScene_Notify 4 236 20.37 0.09 0.00 0.00 10.03. 10:00:08 HASH(WZ_LichtSzene); HASH(global)
WEB_192.168.50.249_50636 FW_Read 3 2 7.74 3.87 0.00 0.00 10.03. 10:03:26 HASH(WEB_192.168.50.249_50636)
tmr-HUEBridge_GetUpdate HASH(0x3c300a8) 3 3 8.75 2.92 3931.83 1311.45 10.03. 10:02:43 HASH(Hue)
eventTypes eventTypes_Notify 3 236 69.34 0.29 0.00 0.00 10.03. 10:02:49 HASH(eventTypes); HASH(BU.sysmon)
WZ_WandThermostat_Climate CUL_HM_Set 3 1 3.77 3.77 0.00 0.00 10.03. 10:01:55 HASH(WZ_WandThermostat_Climate); WZ_WandThermostat_Climate; ?
WEB_192.168.50.249_50640 FW_Read 3 1 3.75 3.75 0.00 0.00 10.03. 10:03:35 HASH(WEB_192.168.50.249_50640)
tmr-HMUARTLGW_CheckCredits HMUARTLGW_CheckCredits 3 32 88.61 2.77 185.96 13.13 10.03. 10:01:19 HMUARTLGW_CheckCredits:HMLANGW
telnetForBlockingFn_1520670767 telnet_Read 3 10 20.33 2.03 0.00 0.00 10.03. 10:02:40 HASH(telnetForBlockingFn_1520670767)
tmr-MQTT::Timer HASH(0x4210370) 3 3 7.90 2.63 3929.46 1311.90 10.03. 10:02:43 HASH(Mosquitto)
tmr-HMUARTLGW_SendKeepAlive HASH(0x1d21de0) 2 23 48.19 2.10 3882.43 170.38 10.03. 10:02:03 HASH(HMLANGW2:keepAlive)
Mosquitto MQTT::Set 2 7 4.45 0.64 0.00 0.00 10.03. 10:03:26 HASH(Mosquitto); Mosquitto; publish; /SmartHome/Draussen/Wetter/humidity; 70
tmr-HMUARTLGW_SendKeepAlive HASH(0x3911d28) 2 23 45.06 1.96 3884.26 170.71 10.03. 10:02:53 HASH(HMLANGW:keepAlive)
fm_Raspi freezemon_Notify 2 236 37.50 0.16 0.00 0.00 10.03. 10:00:28 HASH(fm_Raspi); HASH(rg_battery)
BA_HeizungsThermostat_Clima CUL_HM_Set 2 2 4.21 2.10 0.00 0.00 10.03. 10:03:28 HASH(BA_HeizungsThermostat_Clima); BA_HeizungsThermostat_Clima; ?
EZ_HeizungsThermostat_Clima CUL_HM_Set 2 2 4.12 2.06 0.00 0.00 10.03. 10:02:44 HASH(EZ_HeizungsThermostat_Clima); EZ_HeizungsThermostat_Clima; ?
MA_HeizungsThermostat_Clima CUL_HM_Set 2 2 3.95 1.98 0.00 0.00 10.03. 10:00:28 HASH(MA_HeizungsThermostat_Clima); MA_HeizungsThermostat_Clima; ?
FZ_HeizungsThermostat_Clima CUL_HM_Set 1 1 1.99 1.99 0.00 0.00 10.03. 10:01:28 HASH(FZ_HeizungsThermostat_Clima); FZ_HeizungsThermostat_Clima; ?
WZ_HeizungsThermostat_Clima CUL_HM_Set 1 2 3.91 1.95 0.00 0.00 10.03. 10:02:58 HASH(WZ_HeizungsThermostat_Clima); WZ_HeizungsThermostat_Clima; ?
FL_HeizungsThermostat_Clima CUL_HM_Set 1 1 1.98 1.98 0.00 0.00 10.03. 10:02:15 HASH(FL_HeizungsThermostat_Clima); FL_HeizungsThermostat_Clima; ?
SZ_HeizungsThermostat_Clima CUL_HM_Set 1 1 1.92 1.92 0.00 0.00 10.03. 10:01:34 HASH(SZ_HeizungsThermostat_Clima); SZ_HeizungsThermostat_Clima; ?
Output freezemon:
2018.03.10 10:00:05 2: [Freezemon] fm_Raspi ready to watch out for delays greater than 1 second(s)
2018.03.10 10:00:43 1: [Freezemon] fm_Raspi: possible freeze starting at 10:00:40, delay is 3.595 possibly caused by HUEBridge_GetUpdate(Hue) MQTT((Timer(Mosquitto) HMUARTLGW_SendKeepAlive(HMLANGW) HMUARTLGW_SendKeepAlive(HMLANGW2)
Ein Ping auf die Gateways sind normal (0.4).
Kann jemand helfen?
Hier mal der Output meines Produktions-FHEM.
active-timers: 36; max-active timers: 37; max-timer-load: 3 min-tmrHandlingTm: 0.0ms; max-tmrHandlingTm: 5818.5ms; totAvgDly: 133.3ms
name function max count total average maxDly avgDly TS Max call param Max call
HMLANGW2 HMUARTLGW_Read 7917 184 29696.48 161.39 0.00 0.00 11.03. 09:49:14 HASH(HMLANGW2)
DBLogging DbLog_Log 7696 597 57254.76 95.90 0.00 0.00 11.03. 09:49:13 HASH(DBLogging); HASH(FL_HeizungsThermostat)
tmr-SYSMON_Update HASH(0x2944d78) 5817 10 9130.46 913.05 1.97 1.52 11.03. 09:52:05 HASH(BU.sysmon)
allowed_WEB allowed_Authenticate 5501 83 7218.04 86.96 0.00 0.00 11.03. 09:45:12 HASH(allowed_WEB); HASH(WEB_192.168.50.249_50238); HASH(0x18e21d8)
tmr-CUL_HM_ActCheck ActionDetector 2927 1 2927.41 2927.41 1.00 1.00 11.03. 09:43:15 ActionDetector
HMLANGW HMUARTLGW_Read 1867 216 5828.38 26.98 0.00 0.00 11.03. 09:46:59 HASH(HMLANGW)
WEB FW_Read 178 11 1562.58 142.05 0.00 0.00 11.03. 09:45:13 HASH(WEB)
n_publish_mqtt notify_Exec 145 597 3938.87 6.60 0.00 0.00 11.03. 09:43:28 HASH(n_publish_mqtt); HASH(OUT.Wetter)
tmr-Weather_GetUpdate HASH(0x1ebafc8) 142 2 158.11 79.06 3.60 2.37 11.03. 09:48:28 HASH(OUT.Wetter)
mqttSonoffList readingsGroup_Notify 67 597 1699.67 2.85 0.00 0.00 11.03. 09:47:01 HASH(mqttSonoffList); HASH(BU.sysmon)
mqttPublishList readingsGroup_Notify 62 597 1440.48 2.41 0.00 0.00 11.03. 09:52:06 HASH(mqttPublishList); HASH(BU.sysmon)
Mosquitto MQTT::Read 47 115 3990.32 34.70 0.00 0.00 11.03. 09:49:16 HASH(Mosquitto)
mqttSonoffUpdate readingsGroup_Notify 37 597 1424.82 2.39 0.00 0.00 11.03. 09:43:01 HASH(mqttSonoffUpdate); HASH(BU.sysmon)
rg_battery readingsGroup_Notify 35 597 822.72 1.38 0.00 0.00 11.03. 09:49:13 HASH(rg_battery); HASH(FL_HeizungsThermostat)
tmr-HMUARTLGW_StartInit HASH(0x3391d08) 31 1 31.87 31.87 1.76 1.76 11.03. 09:49:17 HASH(HMLANGW)
rg_Heizung readingsGroup_Notify 29 597 672.92 1.13 0.00 0.00 11.03. 09:50:21 HASH(rg_Heizung); HASH(MA_HeizungsThermostat_Clima)
di_HMGWState DOIF_Notify 24 597 92.48 0.15 0.00 0.00 11.03. 09:49:16 HASH(di_HMGWState); HASH(HMLANGW)
tmr-PRESENCE_StartLocalScan HASH(0x24396d8) 20 14 242.43 17.32 3.71 1.87 11.03. 09:50:09 HASH(BluetoothAnwesend)
HMLANGW HMUARTLGW_Ready 17 2 19.28 9.64 0.00 0.00 11.03. 09:49:16 HASH(HMLANGW)
rg_HM_Components readingsGroup_Notify 14 597 717.02 1.20 0.00 0.00 11.03. 09:49:33 HASH(rg_HM_Components); HASH(WZ_WandThermostat_Climate)
eventTypes eventTypes_Notify 7 597 187.14 0.31 0.00 0.00 11.03. 09:52:06 HASH(eventTypes); HASH(BU.sysmon)
rg_Heizung readingsGroup_Define 6 2 12.13 6.06 0.00 0.00 11.03. 09:48:08 HASH(rg_Heizung); rg_Heizung readingsGroup <name>,<Ventilstellung>,<Mode>,<desired Temp>,<measured Temp> .*_Clima:NAME,ValvePosition,controlMode,desired-temp,measured-temp
apiWEB_127.0.0.1_59816 FW_Notify 5 597 923.78 1.55 0.00 0.00 11.03. 09:46:09 HASH(apiWEB_127.0.0.1_59816); HASH(BU_RackTemp)
nf_batt_chk notify_Exec 5 597 260.07 0.44 0.00 0.00 11.03. 09:46:59 HASH(nf_batt_chk); HASH(SZ_HeizungsThermostat)
tmr-HMUARTLGW_CheckCredits HMUARTLGW_CheckCredits 3 79 221.15 2.80 3738.27 184.45 11.03. 09:42:41 HMUARTLGW_CheckCredits:HMLANGW
telnetForBlockingFn_1520752386 telnet_Read 3 24 57.51 2.40 0.00 0.00 11.03. 09:45:21 HASH(telnetForBlockingFn_1520752386)
WZ_WandThermostat_Climate CUL_HM_Set 3 5 14.58 2.92 0.00 0.00 11.03. 09:51:54 HASH(WZ_WandThermostat_Climate); WZ_WandThermostat_Climate; ?
tmr-HMUARTLGW_SendPendingTimer HASH(0x3391d08) 3 2 6.34 3.17 2388.14 1194.63 11.03. 09:51:33 HASH(HMLANGW)
tmr-MQTT::Timer HASH(0x2efd610) 3 9 21.91 2.43 2916.85 373.59 11.03. 09:50:15 HASH(Mosquitto)
tmr-HMUARTLGW_GetSetParameters HASH(0x3391d08) 3 1 3.01 3.01 1.86 1.86 11.03. 09:49:21 HASH(HMLANGW)
Mosquitto MQTT::Set 2 35 28.44 0.81 0.00 0.00 11.03. 09:43:28 HASH(Mosquitto); Mosquitto; publish; /SmartHome/Draussen/Wetter/pubDate; Sun,; 11; Mar; 2018; 09:00; AM; CET
tmr-HMUARTLGW_SendKeepAlive HASH(0x443fdd8) 2 40 78.23 1.96 7889.49 241.19 11.03. 09:46:05 HASH(HMLANGW:keepAlive)
tmr-HMUARTLGW_SendKeepAlive HASH(0x43c7670) 2 58 121.43 2.09 848.76 39.19 11.03. 09:45:25 HASH(HMLANGW2:keepAlive)
FL_WohnungstuerKontakt CUL_HM_Set 2 2 4.83 2.42 0.00 0.00 11.03. 09:51:32 HASH(FL_WohnungstuerKontakt); FL_WohnungstuerKontakt; ?
WZ_BalkontuerLKontakt CUL_HM_Set 2 1 2.28 2.28 0.00 0.00 11.03. 09:43:15 HASH(WZ_BalkontuerLKontakt); WZ_BalkontuerLKontakt; ?
SZ_HeizungsThermostat CUL_HM_Set 2 4 6.92 1.73 0.00 0.00 11.03. 09:44:00 HASH(SZ_HeizungsThermostat); SZ_HeizungsThermostat; ?
tmr-HMUARTLGW_SendKeepAlive HASH(0x45e2bd8) 2 17 34.74 2.04 2.27 1.36 11.03. 09:49:27 HASH(HMLANGW:keepAlive)
doif_Abfall DOIF_Notify 2 597 60.95 0.10 0.00 0.00 11.03. 09:46:50 HASH(doif_Abfall); HASH(BU_RackTemp)
EZ_HeizungsThermostat CUL_HM_Set 2 4 6.76 1.69 0.00 0.00 11.03. 09:46:53 HASH(EZ_HeizungsThermostat); EZ_HeizungsThermostat; ?
EZ_HeizungsThermostat_Clima CUL_HM_Set 2 4 7.95 1.99 0.00 0.00 11.03. 09:46:53 HASH(EZ_HeizungsThermostat_Clima); EZ_HeizungsThermostat_Clima; ?
tmr-HMUARTLGW_StartInit HASH(0x45e2bd8) 2 1 2.09 2.09 0.81 0.81 11.03. 09:49:17 HASH(HMLANGW:keepAlive)
Beim keepAlive gibt es da Probleme.
Wäre toll wenn mir jemand einen Tipp geben könnte wie ich das Problem eingrenzen kann.
Ich habe mal ein Ping auf die beiden Gateways über mehrere Minuten laufen lassen.
Antwortzeiten sind bei beiden Gateways zwischen 0,3 bis 0,4 ms.
Hast du die Gateways zufällig über Power-LAN ins Netz eingebunden? Bei mir hatte irgend ein Gerät im Stromnetz immer wieder so gestört was zu kurzen Verbindungsabbrüchen geführt hat
Nein, aber ich habe vermutlich das Problem gelöst (schaue mal ob die Meldungen in den kommenden Tagen noch kommen).
Ich verwende DBLogging und habe, im Gegensatz zu meinem Test fhem, vergessen den syncMode zu setzen.
Ergo, steht dieser auf syncron und nicht auf asyncron.
Damit wird beim log schreiben das fhrm kurzzeitig angehalten.
Gsnz schlimm ist es dann Nachts, wenn ich den Log aufräume (delete aller Zeilen älter 14 Tage und erzeugen eines dump der db).
Hier steht dann das fhem für mehrere Sekunden bis Minuten.
Aber wie gesagt, ich warte jetzt mal die Nacht ab ob die reopen weg sind.
Nachdem kein weiterer Post kommt, hat sich Dein Problem offensichtlich wirklich so gelöst, richtig?
Ich habe nämlich praktisch genau dieses Problem. Das kann sich schon öfters mal soweit steigern, dass die Subdevice keepAlive gar nicht mehr verbunden wird sondern disconnected bleigt - was auch immer das für Folgen haben kann. DBLogging verwende ich nicht, bin also noch auf der Suche. Es ist offensichtlich so, dass einige Aktionen in dem Falle, dass sie genau zu dem Zeitpunkt des Prüfens dieser Verbindung eine leichte Verzögerung auslosen, diesen Abbruch bewirken und damit selber wieder zu noch stärkeren Verzögerungen führen.
Ich habe da wirklich nicht den Durchblick, muss aber feststellen, dass sich diese Verzögerungen erst seit dem Einsatz des HMUARTLGW-Moduls so intensiv bemerkbar machen. Leider ist dieser Thread hier einer der wenigen, die ich auf meiner Recherche auftreiben konnte zu meinem Problem - abgesehen von Schwierigkeiten bei der LAN Anbindung, die ich bei mir ausgiebig geprüft habe und die ich sicher ausschließen kann.
und, was sagt freezemon bei dir?
Wie der Teufel so will: Im Moment wenig, ist aber auch niemand zu Hause.
1 - 2018-04-13: s:15:29:29 e:15:29:30 f:1.067 d:tmr-BlockingKill(N/A) tmr-HMUARTLGW_SendKeepAlive(HMLANGW)
1 - 2018-04-13: s:15:29:34 e:15:29:35 f:1.804 d:tmr-HMUARTLGW_CheckCredits(N/A)
1 - 2018-04-13: s:15:29:59 e:15:30:00 f:1.26 d:no bad guy found :-(
1 - 2018-04-13: s:15:30:01 e:15:30:02 f:1.279 d:tmr-HMUARTLGW_SendKeepAlive(HMLANGW)
1 - 2018-04-13: s:15:30:31 e:15:30:32 f:1.425 d:tmr-HMUARTLGW_SendKeepAlive(HMLANGW)
1 - 2018-04-13: s:15:31:03 e:15:31:04 f:1.428 d:tmr-HMLAN_KeepAliveCheck(N/A)
1 - 2018-04-13: s:15:31:18 e:15:31:19 f:1.24 d:tmr-SYSMON_Update(Cubie)
1 - 2018-04-13: s:15:31:20 e:15:31:22 f:2.066 d:tmr-SB_PLAYER_GetStatus(SB_PLAYER_Kueche) tmr-HMUARTLGW_CheckCredits(N/A)
1 - 2018-04-13: s:15:31:24 e:15:31:25 f:1.411 d:tmr-ESPEasy_statusRequest(ESPEasy_ESP_0732ae_INA219_1)
1 - 2018-04-13: s:15:31:35 e:15:31:36 f:1.13 d:tmr-HMUARTLGW_CheckCredits(N/A)
1 - 2018-04-13: s:15:31:37 e:15:31:38 f:1.125 d:tmr-HMUARTLGW_CheckCmdResp(HMLANGW)
wenig? das sehe ich anders.
so wirst du mit homematic nicht glücklich.
Zitat von: frank am 13 April 2018, 16:14:07
wenig? das sehe ich anders.
"wenig" meine ich im Bezug darauf, dass da schon durchaus deutlich mehr kommt, wenn weitere Ereignisse häufiger sind.
Zitat
so wirst du mit homematic nicht glücklich.
Gut erkannt! Habe aber keinen Plan, wo ich die Ursache finde. Homematic selber wird es ja wohl nicht sein?
Ich habe schon länger ein HM-CFG-USB USB Konfigurations-Adapter am Raspberry 3 und diesen über das Netz im FHEM am Cubie eingebunden. Dort hatte ich dieses Problem nie. Nun habe ich mir zur Absicherung dieses HM-LGW-O-TW-W-EU Funk-LAN Gateway gekauft und zusammen mit dem alten Gerät mittels VCC verknüpft. Einrichtung und Update der FW habe ich nach dem entsprechenden Wiki durchgeführt.
Als Devices habe ich 3 HM-Zwischensteckdosen mit Energie-Funktion und 3 Unterputz-Wandschalter, also eigentlich nicht die Welt.
nutze das logfile attr, da sieht man mehr
Zitatfm_logFile: takes a valid file name (like e.g. ./log/freeze-%Y%m%d-%H%M%S.log). If set, logs messages of loglevel 5 (even if global loglevel is < 5) before a freeze in separate file.
Danke, das mache ich. Hatte das nicht richtig verstanden, dass man da mehr sieht. Ich dachte, dann wird einfach nur geloggt.
Da fällt mir erst mal nur ein: Uiuiuiui - da hat sich über nach einiges angesammelt, was es zu begutachten gilt.