HMLAN Adapter wechselt permanent zwischen disconnected / connected

Begonnen von bdombrowsky, 26 Februar 2014, 19:41:00

Vorheriges Thema - Nächstes Thema

bdombrowsky

Moin!

Ich bin neu im FHEM und Haustechnik bereich, allerdings einigermaßen fit unter Linux / PC Technik.

Ich habe mir eine kleine "Testumgebung" zusammengestellt, bestehend aus:

- 1 x EQ3 85128 HomeMatic Konfigurations-Adapter LAN
-- 1 x HomeMatic 105155 Funk-Stellantrieb
-- 1 x HomeMatic Funk-Tür-/ Fensterkontakt für Zentrale
-- 1 x HomeMatic 105788 Funk-Zwischenstecker-Schaltaktor 1-fach, HM-LC-Sw1-PL

Ich habe, nach Hinweise aus dem Netz, den LAN Adapter hinzugefügt (Inkl. geändertem Zugangsschlüssel, über die Windows Software AES deaktiviert etc. also nach Anleitung vorgegangen) und die Komponenten angelernt. Die Funktion ist auch gegeben, allerdings switcht der Lan Adapter andauernt zwischen verbunden und nicht verbunden, wo ich derzeit absolut keine Begründung für sehe.

Installiert ist FHEM (Update auf neuste Version wurde via update Befehl ausgefüht) auf einem aktuell gepachtem Ubuntu 64 Bit System. Intakte und sonst einwandfreie verkabelte Ethernet Verkabelung.

Im Logfile sieht das ganze wiefolgt aus:


...
2014.02.26 18:53:53 1: 192.168.10.50:1000 disconnected, waiting to reappear
2014.02.26 18:53:53 1: HMLAN_Parse: wz_HMLAN new condition disconnected
2014.02.26 18:55:58 1: 192.168.10.50:1000 reappeared (wz_HMLAN)
2014.02.26 18:55:58 1: HMLAN_Parse: wz_HMLAN new condition init
2014.02.26 18:55:58 1: HMLAN_Parse: wz_HMLAN new condition ok
2014.02.26 18:58:48 1: 192.168.10.50:1000 disconnected, waiting to reappear
2014.02.26 18:58:48 1: HMLAN_Parse: wz_HMLAN new condition disconnected
2014.02.26 19:00:53 1: 192.168.10.50:1000 reappeared (wz_HMLAN)
2014.02.26 19:00:53 1: HMLAN_Parse: wz_HMLAN new condition init
2014.02.26 19:00:53 1: HMLAN_Parse: wz_HMLAN new condition ok
2014.02.26 19:03:58 1: 192.168.10.50:1000 disconnected, waiting to reappear
2014.02.26 19:03:58 1: HMLAN_Parse: wz_HMLAN new condition disconnected
2014.02.26 19:06:03 1: 192.168.10.50:1000 reappeared (wz_HMLAN)
2014.02.26 19:06:03 1: HMLAN_Parse: wz_HMLAN new condition init
2014.02.26 19:06:03 1: HMLAN_Parse: wz_HMLAN new condition ok
2014.02.26 19:09:08 1: 192.168.10.50:1000 disconnected, waiting to reappear
2014.02.26 19:09:08 1: HMLAN_Parse: wz_HMLAN new condition disconnected
2014.02.26 19:11:13 1: 192.168.10.50:1000 reappeared (wz_HMLAN)
2014.02.26 19:11:13 1: HMLAN_Parse: wz_HMLAN new condition init
2014.02.26 19:11:13 1: HMLAN_Parse: wz_HMLAN new condition ok
2014.02.26 19:14:08 1: 192.168.10.50:1000 disconnected, waiting to reappear
2014.02.26 19:14:08 1: HMLAN_Parse: wz_HMLAN new condition disconnected
2014.02.26 19:17:17 1: 192.168.10.50:1000 reappeared (wz_HMLAN)
2014.02.26 19:17:17 1: HMLAN_Parse: wz_HMLAN new condition init
2014.02.26 19:17:17 1: HMLAN_Parse: wz_HMLAN new condition ok
... usw.


Sprich irgendwann disconnected sich der Adapter und und connected sich einige Minuten später wieder. Das kann doch so nicht normal sein? Ich habe den Adapter div. male neugestartet und an andere Knoten / Netzwerkpunkte gehängt.

Kann jemand dieses Verhalten auch bestätigen? Ich habe ein paar Einträge im Netz bezüglich Netzwerkproblemen gesehen, was ich bei mir ausschließen kann... Mit dem wdTimer Wert habe ich es zwischen 5 und 25 auch probiert.

Über Hinweise wäre ich sehr dankbar. Ansonsten muss ich wohl von einem Defekt ausgehen?

Gruß
Benni

martinp876

das sollte er sicher nicht tun. Der Adapter muss mit keepalive alle 30sec am leben gehalten werden. FHEM sendet daher alle 25sec (default) einen trigger.
Was sagt ein list den HMLAN?
Gründe könnten sein
- schlechte LAN verbindung, messages gehen verloren
- überschreiten der 30sec keepalive
- HW defekt
- ethernet schlecht eingerichtet

bdombrowsky

List Ausgabe bei disconnect:

   DEF        192.168.10.50:1000
   DeviceName 192.168.10.50:1000
   HM_CMDNR   49
   NAME       wz_HMLAN
   NEXT_OPEN  1393442450.75722
   NR         23
   NTFY_ORDER 50-wz_HMLAN
   PARTIAL   
   RAWMSG     E2402EF,0000,02C5FF3A,FF,FFCF,1586102402EF0000000A78EB10002D
   RSSI       -49
   STATE      disconnected
   TYPE       HMLAN
   XmitOpen   0
   assignedIDs 250227,2402EF,238585
   assignedIDsCnt 3
   assignedIDsReport 0
   firmware   0.961
   msgKeepAlive dlyMax:11.511 bufferMin:13
   msgLoadEst 1hour:0% 10min steps: 0/0/0/0/0/0
   msgParseDly min:-14 max:121 last:7 cnt:142
   owner      2574EB
   serialNr   KEQ1023114
   uptime     000 12:55:42.167
   wz_HMLAN_MSGCNT 362
   wz_HMLAN_TIME 2014-02-26 20:19:33
   Readings:
     2014-02-26 20:19:50   Xmit-Events     disconnected:1
     2014-02-26 20:19:50   cond            disconnected
     2014-02-26 20:19:50   prot_disconnected last
     2014-02-26 20:14:55   prot_init       last
     2014-02-26 20:14:55   prot_ok         last
     2014-02-25 21:46:16   prot_timeout    last
   Assids:
     238585     1
     2402EF     1
     250227     1
   Helper:
     assId      0
     000001:
       flg        0
       msg       
       to         1393442097.55511
     238585:
       chn        02
       flg        0
       msg       
       name       wz_Stehlampe
       to         1393441448.71087
     2402ef:
       chn        86
       flg        0
       msg       
       name       bz_Heizung
       to         1393442221.41694
     250227:
       chn        01
       flg        0
       msg       
       name       efl_Tuer
       to         1393441481.28502
     2574eb:
       flg        0
     Assids:
     Dly:
       cnt        142
       lst        7
       max        121
       min        -14
     K:
       BufMin     13
       DlyMax     11.511
       Next       1393442395.7541
       Start      1393442390.7541
     Log:
       all        0
       sys        0
       ids:
         ARRAY(0x1990570)
     Q:
       HMcndN     253
       answerPend 0
       hmLanQlen  1
       keepAliveRec 0
       keepAliveRpt 1
       apIDs:
       Cap:
         0          0
         1          12
         2          0
         3          0
         4          0
         5          0
         last       1
         sum        12
     Ref:
       drft       0
       hmtL       46542167
       kTs        1393442390754
       offL       1393395843589
       sysL       1393442385756
Attributes:
   hmId       2574EB
   hmKey      01:******************************
   hmLanQlen  1_min
   room       Technik
   wdTimer    5


List Ausgabe bei Connect:


Internals:
   DEF        192.168.10.50:1000
   DeviceName 192.168.10.50:1000
   FD         11
   HM_CMDNR   49
   NAME       wz_HMLAN
   NR         23
   NTFY_ORDER 50-wz_HMLAN
   PARTIAL   
   RAWMSG     E2402EF,0000,02C8280B,FF,FFCF,1686102402EF0000000A78EA10002D
   RSSI       -49
   STATE      opened
   TYPE       HMLAN
   XmitOpen   1
   assignedIDs 250227,2402EF,238585
   assignedIDsCnt 3
   assignedIDsReport 0
   firmware   0.961
   msgKeepAlive dlyMax:11.511 bufferMin:13
   msgLoadEst 1hour:0% 10min steps: 0/0/0/0/0/0
   msgParseDly min:-14 max:121 last:7 cnt:142
   owner      2574EB
   serialNr   KEQ1023114
   uptime     000 12:59:38.533
   wz_HMLAN_MSGCNT 363
   wz_HMLAN_TIME 2014-02-26 20:21:57
   Readings:
     2014-02-26 20:21:57   Xmit-Events     ok:1
     2014-02-26 20:21:57   cond            ok
     2014-02-26 20:19:50   prot_disconnected last
     2014-02-26 20:21:57   prot_init       last
     2014-02-26 20:21:57   prot_ok         last
     2014-02-25 21:46:16   prot_timeout    last
   Assids:
     238585     1
     2402EF     1
     250227     1
   Helper:
     assId      0
     000001:
       flg        0
       msg       
       to         1393442519.01913
     238585:
       chn        02
       flg        0
       msg       
       name       wz_Stehlampe
       to         1393441448.71087
     2402ef:
       chn        86
       flg        0
       msg       
       name       bz_Heizung
       to         1393442221.41694
     250227:
       chn        01
       flg        0
       msg       
       name       efl_Tuer
       to         1393441481.28502
     2574eb:
       flg        0
     Assids:
     Dly:
       cnt        142
       lst        7
       max        121
       min        -14
     K:
       BufMin     13
       DlyMax     11.511
       Next       1393442627.09252
       Start      1393442622.09252
     Log:
       all        0
       sys        0
       ids:
         ARRAY(0x1990570)
     Q:
       HMcndN     0
       answerPend 0
       hmLanQlen  1
       keepAliveRec 1
       keepAliveRpt 0
       apIDs:
       Cap:
         0          0
         1          0
         2          1
         3          0
         4          0
         5          0
         last       2
         sum        1
     Ref:
       drft       -0.000199920031987205
       hmtL       46778533
       kTs        0
       offL       1393395843562
       sysL       1393442622095
Attributes:
   hmId       2574EB
   hmKey      01:******************
   hmLanQlen  1_min
   room       Technik
   wdTimer    5


Eine schlechte LAN Verbindung kann ich ausschließen, da alle anderen Dienste sehr performant funktionieren (Auch von diesem Server aus).

Den KeepAlive habe ich jetzt von 5-25 Sek mit div. Werten ausprobiert, ohne Veränderung...

Ethernet schlecht eingerichtet schließe ich auch mal aus, ich habe eine feste IP für den LAN Adapter vergeben und dieser ist dauerthaft via PING erreichbar mit einem Wert Zeit<1ms und das auch wenn der Adapter laut FHEM nicht erreichbar sein soll.

Also gehe ich jetzt von einem Defekt aus... Auch wenn ich es nicht wirklich verstehen kann, da ich den Adapter wie gesagt auch permanent pingen kann.

martinp876

 msgKeepAlive dlyMax:11.511 bufferMin:13

die 11 sec klingen nicht gut, aber du hast ja den wdTimer auf 5 gesetzt. Das sollte reichen.

evtl einmal die rohmessages loggen. Interessant ist auch, wer hier 11 sec bummelt. apptime probieren

Raven

IP-Adresse evtl. (doch) doppelt vergeben? (Hatten wir hier schon im Forum als Ursache mal gehabt - war das Tablett vom Sohn...).

Cubietruck-Prod: HM-LAN, Heizung, Rolläden, Schalter, Viessmann (optolink)
Cubietruck-DEV:
Fritzbox 7490

bdombrowsky

Doppelt vergebene IP kann ich ausschließen, die habe ich schon gewechselt...

Was genau bedeutet der Wert "msgKeepAlive dlyMax" von 11 Sekunden? Müsste der eigentlich auf 5 stehen, oder was wäre hier der Zielwert?

Wie würde ich die "Rohmessages" loggen und was bedeutet apptime probieren?



Michi240281

#7
Ich habe genau das gleiche Problem:

2014.02.28 22:47:54 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:47:54 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 22:48:55 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 22:48:55 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 22:48:55 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 22:49:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:49:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 22:50:55 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 22:50:55 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 22:50:55 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 22:51:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:51:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 22:52:56 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 22:52:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 22:52:56 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 22:53:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:53:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 22:54:56 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 22:54:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 22:54:59 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 22:55:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:55:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 22:56:56 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 22:56:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 22:56:56 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 22:57:56 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:57:56 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 22:58:56 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 22:58:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 22:58:56 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 22:59:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 22:59:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:00:00 2: CUL_HM set Klingelbeleuchtung off
2014.02.28 23:00:58 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:00:58 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:00:58 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:01:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:01:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:02:58 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:02:58 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:02:58 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:03:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:03:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:04:58 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:04:58 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:04:58 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:05:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:05:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:06:58 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:06:58 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:06:58 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:07:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:07:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:08:58 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:08:58 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:08:58 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:09:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:09:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:10:58 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:10:58 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:10:58 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:11:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:11:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:12:56 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:12:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:12:56 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:13:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:13:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:13:56 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:13:56 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:13:56 1: HMLAN_Parse: HMLAN1 new condition ok
2014.02.28 23:14:55 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.02.28 23:14:55 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.02.28 23:14:57 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.02.28 23:14:57 1: HMLAN_Parse: HMLAN1 new condition init
2014.02.28 23:15:01 1: HMLAN_Parse: HMLAN1 new condition ok


Würde ebenfalls sagen, dass ich Netzwerkprobleme ausschließen kann! Schließlich läuft der HMLAN schon 2 Monate ohne Probleme! Nun fängt ständig die linke LED an zu blinken, dann irgendwann leuchtet kurz die mittlere LED rot und der HMLAN ist wieder online. Dann geht das Spiel von vorne los!

List bei disconnect:



Internals:
   DEF        192.168.188.222:1000
   DeviceName 192.168.188.222:1000
   HMLAN1_MSGCNT 121
   HMLAN1_TIME 2014-02-28 23:26:19
   HM_CMDNR   65
   NAME       HMLAN1
   NEXT_OPEN  1393626475.49495
   NR         102
   NTFY_ORDER 50-HMLAN1
   PARTIAL   
   RAWMSG     R7A9AF718,0001,00013BA8,FF,FFDC,41800223D52523A6D70101C80025
   RSSI       -36
   STATE      disconnected
   TYPE       HMLAN
   XmitOpen   0
   assignedIDs 23D2DD,23D2DE,254DE7,24DBBB,23D525,206036,1D66E8
   assignedIDsCnt 7
   assignedIDsReport 3
   firmware   0.961
   msgKeepAlive dlyMax:10.542 bufferMin:-5
   msgLoadEst 1hour:0% 10min steps: 0/0/0/0/0/0
   owner      23A6D7
   serialNr   KEQ0852411
   uptime     000 00:01:24.461
   Readings:
     2014-02-28 23:26:55   Xmit-Events     disconnected:1
     2014-02-28 23:26:55   cond            disconnected
     2014-02-28 23:26:55   prot_disconnected last
     2014-02-28 23:25:58   prot_init       last
     2014-02-28 00:07:31   prot_keepAlive  last
     2014-02-28 23:25:58   prot_ok         last
   Assids:
     1D66E8     1
     206036     1
     23D2DD     1
     23D2DE     1
     23D525     1
     24DBBB     1
     254DE7     1
   Helper:
     assId      0
     000001:
       flg        0
       msg       
       to         1393626360.54062
     1d66e8:
       chn        01
       flg        0
       msg       
       name       Dimmer_Terasse
       to         1393626378.93429
     206036:
       chn        02
       flg        0
       msg       
       name       Hutschienenaktor
       to         1393624860.46341
     23a6d7:
       flg        0
     23d2dd:
       chn        02
       flg        0
       msg       
       name       Vase
       to         1393626381.45552
     23d2de:
       chn        02
       flg        0
       msg       
       name       Sideboard
       to         1393626381.07937
     23d525:
       chn        02
       flg        0
       msg       
       name       Vitrine
       to         1393626381.7335
     24dbbb:
       chn        02
       flg        0
       msg       
       name       Funk_Gong
       to         1393621009.2885
     254de7:
       chn        01
       flg        0
       msg       
       name       Garage_Aktor
       to         1393621009.9087
     Assids:
     K:
       BufMin     -5
       DlyMax     10.542
       Next       1393626440.45279
       Start      1393626415.45279
     Log:
       all        0
       sys        0
       ids:
         ARRAY(0xdb7bf0)
     Q:
       HMcndN     253
       answerPend 0
       hmLanQlen  1
       keepAliveRec 0
       keepAliveRpt 1
       apIDs:
       Cap:
         0          0
         1          0
         2          7
         3          0
         4          0
         5          0
         last       2
         sum        7
     Ref:
       hmtL       84461
       kTs        1393626415452
Attributes:
   group      System
   hmId       23A6D7
   hmKey      01:CE619E3197059B7751208CFFEED9E285
   hmLanQlen  1_min
   respTime   5
   room       System
   wdTimer    25


List bei opened:



Internals:
   DEF        192.168.188.222:1000
   DeviceName 192.168.188.222:1000
   FD         24
   HMLAN1_MSGCNT 121
   HMLAN1_TIME 2014-02-28 23:26:19
   HM_CMDNR   65
   NAME       HMLAN1
   NR         102
   NTFY_ORDER 50-HMLAN1
   PARTIAL   
   RAWMSG     R7A9AF718,0001,00013BA8,FF,FFDC,41800223D52523A6D70101C80025
   RSSI       -36
   STATE      opened
   TYPE       HMLAN
   XmitOpen   1
   assignedIDs 23D2DD,23D2DE,254DE7,24DBBB,23D525,206036,1D66E8
   assignedIDsCnt 7
   assignedIDsReport 0
   firmware   0.961
   msgKeepAlive dlyMax:10.542 bufferMin:-5
   msgLoadEst 1hour:0% 10min steps: 0/0/0/0/0/0
   owner      23A6D7
   serialNr   KEQ0852411
   uptime     000 00:03:22.202
   Readings:
     2014-02-28 23:27:56   Xmit-Events     ok:1
     2014-02-28 23:27:56   cond            ok
     2014-02-28 23:26:55   prot_disconnected last
     2014-02-28 23:27:56   prot_init       last
     2014-02-28 00:07:31   prot_keepAlive  last
     2014-02-28 23:27:56   prot_ok         last
   Assids:
     1D66E8     1
     206036     1
     23D2DD     1
     23D2DE     1
     23D525     1
     24DBBB     1
     254DE7     1
   Helper:
     assId      0
     000001:
       flg        0
       msg       
       to         1393626478.25174
     1d66e8:
       chn        01
       flg        0
       msg       
       name       Dimmer_Terasse
       to         1393626378.93429
     206036:
       chn        02
       flg        0
       msg       
       name       Hutschienenaktor
       to         1393624860.46341
     23a6d7:
       flg        0
     23d2dd:
       chn        02
       flg        0
       msg       
       name       Vase
       to         1393626381.45552
     23d2de:
       chn        02
       flg        0
       msg       
       name       Sideboard
       to         1393626381.07937
     23d525:
       chn        02
       flg        0
       msg       
       name       Vitrine
       to         1393626381.7335
     24dbbb:
       chn        02
       flg        0
       msg       
       name       Funk_Gong
       to         1393621009.2885
     254de7:
       chn        01
       flg        0
       msg       
       name       Garage_Aktor
       to         1393621009.9087
     Assids:
     K:
       BufMin     -5
       DlyMax     10.542
       Next       1393626526.26816
       Start      1393626501.26816
     Log:
       all        0
       sys        0
       ids:
         ARRAY(0xdb7bf0)
     Q:
       HMcndN     0
       answerPend 0
       hmLanQlen  1
       keepAliveRec 1
       keepAliveRpt 0
       apIDs:
       Cap:
         0          0
         1          0
         2          1
         3          0
         4          0
         5          0
         last       2
         sum        1
     Ref:
       hmtL       202202
       kTs        0
Attributes:
   group      System
   hmId       23A6D7
   hmKey      01:CE619E3197059B7751208CFFEED9E285
   hmLanQlen  1_min
   respTime   5
   room       System
   wdTimer    25
FHEM 5.6 auf RPi2 / HM LAN Adapter / diverse HM-Devices
FHEM-Remote-App
QNAP 419P / Onkyo TX-SR 608
DM500HD / GM Spark One
Sony 52HX905

martinp876

ZitatmsgKeepAlive dlyMax:10.542 bufferMin:-5
das keepalive wurde max um 10.542sec verzoegert, was einen Puffer von -5sec ergibt. Nun, negativ heist, du warst zu spät.
Suche also, wer sich erlaubt, dein System für über 10 sec zu blockieren. Solche Delays sind voraussichtlich FHEM-intern.
Apptime koennte eine hinweis geben (siehe commandref).

Michi240281

#9
Zitat von: martinp876 am 01 März 2014, 08:23:07
das keepalive wurde max um 10.542sec verzoegert, was einen Puffer von -5sec ergibt. Nun, negativ heist, du warst zu spät.
Suche also, wer sich erlaubt, dein System für über 10 sec zu blockieren. Solche Delays sind voraussichtlich FHEM-intern.
Apptime koennte eine hinweis geben (siehe commandref).

Ok!

Gerade mal Apptime gestartet! Mal heute Abend schauen, was es so aufgezeichnet hat.

Was ich komisch finde: Die ganze Nacht hat sich der HMLAN nicht neu connected, hat aber dann heut morgen wieder angefangen. Kann man damit darauf schließen, dass es sich um irgendein Programm handeln muss, das nachts nicht aktiv ist? Was meinst du genau mit FHEM-intern? Sollte ich mal die fhem.cfg nach Funktionen absuchen, die evtl. nicht richtig funktionieren? Oder können das auch Module sein, die den HMLAN ausbremsen?

2014.03.01 00:17:27 2: CUL_HM set Sideboard off
2014.03.01 00:17:27 2: CUL_HM set Vase off
2014.03.01 00:17:27 2: CUL_HM set Vitrine off
2014.03.01 01:00:00 2: CUL_HM set Sideboard off
2014.03.01 01:00:00 2: CUL_HM set Vase off
2014.03.01 01:00:00 2: CUL_HM set Vitrine off
2014.03.01 03:00:00 2: ENIGMA2 set Spark_One off
2014.03.01 06:12:00 2: CUL_HM set Lampe_Terasse on
2014.03.01 06:12:06 2: CUL_HM set Lampe_Terasse statusRequest
2014.03.01 06:13:30 2: CUL_HM set Lampe_Terasse off
2014.03.01 06:13:36 2: CUL_HM set Lampe_Terasse statusRequest
2014.03.01 06:18:33 2: CUL_HM set Sideboard on
2014.03.01 06:18:33 2: CUL_HM set Vase on
2014.03.01 06:18:33 2: CUL_HM set Vitrine on
2014.03.01 08:31:11 2: CUL_HM set Sideboard off
2014.03.01 08:31:11 2: CUL_HM set Vase off
2014.03.01 08:31:11 2: CUL_HM set Vitrine off
2014.03.01 09:42:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:42:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:43:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:43:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:43:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:44:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:44:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:45:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:45:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:45:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:46:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:46:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:47:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:47:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:47:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:48:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:48:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:49:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:49:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:49:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:50:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:50:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:51:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:51:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:51:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:52:13 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:52:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:53:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:53:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:53:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:54:15 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:54:15 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:55:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:55:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:55:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:56:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:56:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:57:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:57:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:57:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 09:58:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 09:58:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 09:59:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 09:59:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 09:59:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:00:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:00:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:01:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:01:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:01:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:02:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:02:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:03:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:03:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:03:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:03:43 1: HMLAN_Parse: HMLAN1 new condition timeout
2014.03.01 10:03:43 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:03:43 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:03:48 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:03:48 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:03:48 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:05:13 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:05:13 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:06:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:06:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:06:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:07:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:07:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:08:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:08:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:08:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:09:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:09:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:10:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:10:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:10:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:11:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:11:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:12:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:12:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:12:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:13:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:13:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:14:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:14:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:14:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:15:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:15:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:16:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:16:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:16:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:17:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:17:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:18:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:18:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:18:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:19:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:19:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:20:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:20:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:20:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:21:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:21:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:22:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:22:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:22:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:23:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:23:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:24:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:24:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:24:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:25:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:25:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:26:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:26:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:26:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:27:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:27:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:28:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:28:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:28:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:29:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:29:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:30:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:30:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:30:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:31:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:31:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:32:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:32:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:32:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:33:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:33:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:34:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:34:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:34:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:35:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:35:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:36:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:36:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:36:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:37:13 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:37:13 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:38:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:38:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:38:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:39:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:39:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:40:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:40:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:40:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:41:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:41:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:42:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:42:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:42:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:43:13 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:43:13 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:44:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:44:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:44:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:45:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:45:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:46:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:46:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:46:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:47:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:47:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:48:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:48:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:48:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:49:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:49:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:50:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:50:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:50:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:51:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:51:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:52:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:52:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:52:15 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:53:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:53:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:54:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:54:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:54:16 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:55:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:55:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:56:14 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:56:14 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:56:14 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:57:15 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:57:15 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 10:58:16 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 10:58:16 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 10:58:16 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 10:59:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 10:59:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 11:00:16 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 11:00:16 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 11:00:16 1: HMLAN_Parse: HMLAN1 new condition ok
Subroutine HandleTimeout redefined at ./FHEM/98_apptime.pm line 24.
Subroutine CallFn redefined at ./FHEM/98_apptime.pm line 58.
2014.03.01 11:01:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 11:01:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 11:01:15 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 11:01:15 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 11:01:16 1: HMLAN_Parse: HMLAN1 new condition ok
2014.03.01 11:02:14 1: 192.168.188.222:1000 disconnected, waiting to reappear
2014.03.01 11:02:14 1: HMLAN_Parse: HMLAN1 new condition disconnected
2014.03.01 11:03:16 1: 192.168.188.222:1000 reappeared (HMLAN1)
2014.03.01 11:03:16 1: HMLAN_Parse: HMLAN1 new condition init
2014.03.01 11:03:16 1: HMLAN_Parse: HMLAN1 new condition ok


@Bdombrowsky: Hast du die Ursache bei dir gefunden?

Hier ein erster Auszug von Apptime:

                        name             function    max  count    total  average maxDly
                              HMLAN1          HMLAN_Ready     52    171      263     1.54      0 HASH(0xd82ab8)
                  FileLog_Garagentor          FileLog_Get     37      3      106    35.33      0 HASH(0xb5b3e8); FileLog_Garagentor; CURRENT; INT; 2014-02-28_00:00:00; 2014-03-02_00:00:01; 4:Garagentor.Torstatus\x3a::
               FileLog_Klingeltaster          FileLog_Get     28      3       83    27.67      0 HASH(0x141d880); FileLog_Klingeltaster; CURRENT; INT; 2014-02-28_00:00:00; 2014-03-02_00:00:01; 4:Klingeltaster.Klingelstatus\x3a::
                              HMLAN1           HMLAN_Read     23     18      202    11.22      0 HASH(0xd82ab8)
                  Alle_Steckdosen_EG        structure_Set     22      5      104    20.80      0 HASH(0x1363a58); Alle_Steckdosen_EG; ?
         FileLog_Raumtemperatur_Soll          FileLog_Get     21      3       61    20.33      0 HASH(0x14a0068); FileLog_Raumtemperatur_Soll; CURRENT; INT; 2014-02-28_00:00:00; 2014-03-02_00:00:01; 4:Raumtemperatur_Soll.Temperatur\x3a::
                 tmr-Twilight_sunpos      HASH(0x1d26120)     21      1       21    21.00      5 HASH(0x1d26120)
                 tmr-Twilight_sunpos      HASH(0x1b45158)     16      1       16    16.00    507 HASH(0x1b45158)
               tmr-ENIGMA2_GetStatus      HASH(0x129e170)     11      9       70     7.78    299 HASH(0x129e170)
                          n_batt_chk          notify_Exec     11     61      266     4.36      0 HASH(0x138bc30); HASH(0x129e170)
FileLog_Funkschnittstelle_Sicherungskasten          FileLog_Set     10      4       37     9.25      0 HASH(0x1431a68); FileLog_Funkschnittstelle_Sicherungskasten; ?
                  FileLog_Garagentor          FileLog_Set     10      3       28     9.33      0 HASH(0xb5b3e8); FileLog_Garagentor; ?
              FileLog_Dimmer_Terasse          FileLog_Set      9      4       36     9.00      0 HASH(0x14a16d0); FileLog_Dimmer_Terasse; ?
                   FileLog_Funk_Gong          FileLog_Set      9      4       36     9.00      0 HASH(0x1431f48); FileLog_Funk_Gong; ?
                FileLog_Garage_Aktor          FileLog_Set      9      3       27     9.00      0 HASH(0xc82c40); FileLog_Garage_Aktor; ?
            FileLog_Hutschienenaktor          FileLog_Set      9      4       35     8.75      0 HASH(0xe072d8); FileLog_Hutschienenaktor; ?
               FileLog_Klingeltaster          FileLog_Set      9      3       27     9.00      0 HASH(0x141d880); FileLog_Klingeltaster; ?
         FileLog_Raumtemperatur_Soll          FileLog_Set      9      3       26     8.67      0 HASH(0x14a0068); FileLog_Raumtemperatur_Soll; ?
                     FileLog_Vitrine          FileLog_Set      9      4       36     9.00      0 HASH(0x1363958); FileLog_Vitrine; ?
                             Logfile          FileLog_Set      9      3       27     9.00      0 HASH(0xe074c0); Logfile; ?
              tmr-FW_closeOldClients                           9      9       36     4.00    168
FHEM 5.6 auf RPi2 / HM LAN Adapter / diverse HM-Devices
FHEM-Remote-App
QNAP 419P / Onkyo TX-SR 608
DM500HD / GM Spark One
Sony 52HX905

Michi240281

Jetzt sieht Apptime so aus:

    name             function    max  count    total  average maxDly
                          eventTypes    eventTypes_Define   4222      1     4222  4222.00      0 HASH(0x1363728); eventTypes eventTypes ./log/eventTypes.txt
                             DM500HD       ENIGMA2_Define   2010      1     2010  2010.00      0 HASH(0x139d6e0); DM500HD ENIGMA2 192.168.188.33 80 60 root blendax3
                           Spark_One       ENIGMA2_Define   2010      1     2010  2010.00      0 HASH(0x1363ad8); Spark_One ENIGMA2 192.168.188.11 80 60 root pkteam
             tmr-CUL_HM_updateConfig         updateConfig    601      1      601   601.00      5 updateConfig
               tmr-Weather_GetUpdate       HASH(0x70f960)    400      2      785   392.50      5 HASH(0x70f960)
                              HMLAN1           HMLAN_Read    374   1616    13312     8.24      0 HASH(0x14314e8)
               tmr-Weather_GetUpdate      HASH(0x208b2d0)    369      2      733   366.50     75 HASH(0x208b2d0)
                           MyWeather       Weather_Define    358      1      358   358.00      0 HASH(0x70f960); MyWeather Weather 651465 3600 de
               tmr-Twilight_Midnight      HASH(0x1dfad10)    340      1      340   340.00  12430 HASH(0x1dfad10)
                            Forecast       Weather_Define    320      1      320   320.00      0 HASH(0x208b2d0); Forecast Weather 651465 3600 de
                   BM_Terasse_Motion          notify_Exec    224     29      446    15.38      0 HASH(0x1ab5a80); HASH(0x1d62130)
                       Klingelsignal          notify_Exec    134      6      331    55.17      0 HASH(0x1b3cc70); HASH(0x1c68ac8)
                     WatchGaragentor          notify_Exec    133     11     1388   126.18      0 HASH(0x141d550); HASH(0x2062eb0)
                  FileLog_Garagentor          FileLog_Get     98      3      197    65.67      0 HASH(0x2063740); FileLog_Garagentor; CURRENT; INT; 2014-02-28_00:00:00; 2014-03-02_00:00:01; 4:Garagentor.Torstatus\x3a::
             tmr-WeekdayTimer_Update      HASH(0x12f8130)     86      1       86    86.00   1921 HASH(0x12f8130)
                       Lampe_Terasse           CUL_HM_Set     69     40      461    11.53      0 HASH(0x196e860); Lampe_Terasse; 100; 30
                 WatchRaumtemperatur          notify_Exec     66      1       66    66.00      0 HASH(0xf560a0); HASH(0x12f8130)
                              HMLAN1         HMLAN_Define     63      1       63    63.00      0 HASH(0x14314e8); HMLAN1 HMLAN 192.168.188.222:1000
                          n_batt_chk          notify_Exec     61    249      946     3.80      0 HASH(0x2073050); HASH(0x1363ad8)
                              HMLAN1          HMLAN_Ready     54      1       54    54.00      0 HASH(0x14314e8)
               FileLog_Klingeltaster          FileLog_Get     53      3      129    43.00      0 HASH(0x1a70a08); FileLog_Klingeltaster; CURRENT; INT; 2014-02-28_00:00:00; 2014-03-02_00:00:01; 4:Klingeltaster.Klingelstatus\x3a::


Ich habe inzwischen wdtimer auf 5 gesetzt (vorher 25) und jetzt habe ich keine disconnects mehr. Kann ich das denn so lassen oder ist das nicht empfehlenswert?

Was kann/soll ich sonst machen?
FHEM 5.6 auf RPi2 / HM LAN Adapter / diverse HM-Devices
FHEM-Remote-App
QNAP 419P / Onkyo TX-SR 608
DM500HD / GM Spark One
Sony 52HX905

frank

ZitatIch habe inzwischen wdtimer auf 5 gesetzt (vorher 25) und jetzt habe ich keine disconnects mehr. Kann ich das denn so lassen oder ist das nicht empfehlenswert?

Was kann/soll ich sonst machen?

empfehlenswert ist, die verzögrungen in deinem system zu identifizieren, und dann, wenn möglich, zu eleminieren.

mit der einstellung wdtimer 5, erreichst du nur, dass trotz deiner bisherigen systemverzögerungen der keep-alive noch rechtzeitig gesendet wird. deine verzögerungen behälst du bei. fhem ist während der verzögerungen lahmgelegt. die 5-fach häufigeren keep-alives belasten dein system nun zusätzlich.

wenn dir das reicht, ok! gut und normal ist das nicht.

ich habe meine verzögerungen sehr gut hiermit identifizieren können: http://forum.fhem.de/index.php/topic,16347.msg106295.html#msg106295

gruss frank
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Michi240281

Kann denn jmd von Euch in meinem Apptime-Post einen möglichen Verursacher entdecken?

Was könnte alles zu Verzögerungen führen?

Soll ich mal meine komplette cfg posten?
FHEM 5.6 auf RPi2 / HM LAN Adapter / diverse HM-Devices
FHEM-Remote-App
QNAP 419P / Onkyo TX-SR 608
DM500HD / GM Spark One
Sony 52HX905

frank

an deinen apptime-aufzeichnungen sind die ersten drei zeilen erwähnenswert. die funktion eventTypes wurde 1 mal ausgeführt und verursachte 4222 milisec verzögerung. ob die zeit normal ist weiss ich nicht. aber 1 mal ausgeführt, kann das keine ursache für dauernde verzögerungen sein.

ebenso die nächsten zwei zeilen.

auffällig wären regelmässige verzögerungen, grösseren aussmasses.

hast du mal meinen vorschlag probiert?

gruss frank.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

martinp876

Die grossen Verzögerungen sind im Aufnahmezeitraum nur einmal aufgetreten. Wenn das nicht häufiger kommt kann es nicht z regelmäßigen Ausfälle führen

Interessant sind auch die 12 sec:
tmr-Twilight_Midnight      HASH(0x1dfad10)    340      1      340   340.00  12430 HASH(0x1dfad10)

du kannst apptime maxDly eingeben, dann sortiert es entsprechend. Vielleicht gibt es noch mehr kritische im MaxDly bereich.
=> wenn es keine langläufer in FHEM gibt und die Verzögerungen anhalten muss der Übertäter außerhalb sitzen. Dann wird es komplexer.