hmlan keep alive 76mb logeinträge ... was mach ich falsch?

[the ratman]

so

Code Auswählen Erweitern

2018.03.24 08:05:19 1: HMUARTLGW hmLan2:keepAlive KeepAlive sent 105.075s too late, this might cause a disconnect!und ähnlich sehen meine einträge über nacht im log aus.
ich habe ein altes hmlan (derzeit inaktiv), das ich als defeckt angenommen hab. seit gestern hab ich ein neues hmlan und es geht wieder los.
stundenlang ist alles in bester ordnung, dann kommen permanent die logeinträge und meistens kackt fhem dann ab.

ich bring mak ein paar lists:
vccu

Code Auswählen Erweitern

Internals:
   DEF        322433
   IODev      hmLan
   NAME       vccu
   NOTIFYDEV  global
   NR         64
   NTFY_ORDER 50-vccu
   STATE      hmLan:disconnected,hmLan2:ok,
   TYPE       CUL_HM
   assignedIOs hmLan,hmLan2
   READINGS:
     2018-03-23 15:52:53   CommandAccepted yes
     2018-03-23 15:15:43   recentStateType ack
     2018-03-24 07:56:32   state           hmLan:disconnected,hmLan2:ok,
   helper:
     HM_CMDNR   189
     mId        FFF0
     regLst     ,0
     rxType     1
     expert:
       def        1
       det        0
       raw        1
       tpl        0
     io:
       prefIO     
       vccu       
       ioList:
         hmLan
         hmLan2
     mRssi:
       mNo       
     prt:
       bErr       0
       sProc      0
     q:
       qReqConf   
       qReqStat   
     role:
       chn        1
       dev        1
       vrt        1
Attributes:
   IOList     hmLan,hmLan2
   expert     2_raw
   group      hardware
   icon       hm_ccu
   model      CCU-FHEM
   room       homematic
   subType    virtual
   webCmd     virtual:updatedas neue hmlan

Code Auswählen Erweitern

Internals:
   AssignedPeerCnt 15
   CNT        49
   Clients    :CUL_HM:
   DEF        192.168.178.10
   DEVCNT     49
   DevState   99
   DevType    LGW
   DeviceName 192.168.178.10:2000
   FD         16
   LastOpen   1521874589.66514
   NAME       hmLan2
   NR         185
   PARTIAL   
   RAWMSG     040207
   RSSI       -58
   STATE      opened
   TYPE       HMUARTLGW
   XmitOpen   1
   model      eQ3-HM-LGW
   msgLoadCurrent 4
   msgLoadHistory 1/0/3/-/-/-/-/-/-/-/-/-
   msgLoadHistoryAbs 4/3/3/0/-/-/-/-/-/-/-/-/-
   owner      322433
   owner_CCU  vccu
   Helper:
     CreditTimer 53
     FW         66561
     Initialized 1
     SendCnt    17
     AckPending:
     LastSendLen:
       3
       3
     Log:
       IDs:
     PeerQueue:
     PendingCMD:
     RoundTrip:
       Delay      0.00466299057006836
     loadLvl:
       lastHistory 1521875492.58113
   MatchList:
     1:CUL_HM   ^A......................
   Peers:
     1A9622     +1A9622,00,00,00
     1AA473     +1AA473,00,00,00
     292986     +292986,00,00,00
     3A6F79     +3A6F79,00,00,00
     3E77A4     +3E77A4,00,00,00
     3E77BB     +3E77BB,00,00,00
     3F5912     +3F5912,00,00,00
     434C2B     +434C2B,00,00,00
     436E25     +436E25,00,00,00
     461363     +461363,00,00,00
     4BEA88     +4BEA88,00,00,00
     53C4CC     +53C4CC,00,00,00
     54C24B     +54C24B,00,00,00
     56C171     +56C171,00,00,00
     62E4AE     +62E4AE,00,00,00
   READINGS:
     2018-03-24 07:56:32   D-HMIdAssigned  322433
     2018-03-24 07:56:32   D-HMIdOriginal  FFFFFF
     2018-03-24 07:56:29   D-LANfirmware   1.1.5
     2018-03-24 07:56:32   D-firmware      1.4.1
     2018-03-24 07:56:29   D-serialNr      OEQ0797622
     2018-03-24 07:56:29   D-type          eQ3-HM-LGW
     2018-03-24 07:56:32   cond            ok
     2018-03-24 08:08:43   load            4
     2018-03-24 07:56:32   loadLvl         low
     2018-03-24 07:56:29   state           opened
   helper:
   keepAlive:
     CNT        220
     DEVCNT     219
     DevState   99
     DevType    LGW-KeepAlive
     DeviceName 192.168.178.10:2001
     FD         13
     LastOpen   1521874589.73438
     NAME       hmLan2:keepAlive
     NR         191
     PARTIAL   
     STATE      opened
     TEMPORARY  1
     TYPE       HMUARTLGW
     XmitOpen   0
     Helper:
       NextKeepAlive 1521875528.09445
       Log:
         Resolve    1
         IDs:
     READINGS:
       2018-03-24 07:56:29   state           opened
Attributes:
   group      hardware
   hmId       322433
   icon       hm_ccu
   lgwPw      xxxxxxx
   room       homematicund das alt hmLan (derzeit vom strom)

Code Auswählen Erweitern

Internals:
   DEF        192.168.178.12:1000
   DeviceName 192.168.178.12:1000
   NAME       hmLan
   NEXT_OPEN  1521875683.60192
   NR         19
   NTFY_ORDER 50-hmLan
   PARTIAL   
   STATE      disconnected
   TYPE       HMLAN
   XmitOpen   0
   assignedIDsCnt 0
   msgKeepAlive
   msgLoadCurrent 0
   owner     
   owner_CCU  vccu
   READINGS:
     2018-03-23 08:20:08   D-HMIdAssigned  322433
     2018-03-23 08:20:08   D-HMIdOriginal  322433
     2018-03-23 08:20:08   D-firmware      0.964
     2018-03-23 08:20:08   D-serialNr      LEQ0986035
     2018-03-24 07:56:12   Xmit-Events     disconnected:1
     2018-03-24 07:56:12   cond            disconnected
     2018-03-23 15:54:59   loadLvl         low
     2018-03-16 16:04:02   prot_ERROR-Overload last
     2018-03-18 11:12:54   prot_Warning-HighLoad last
     2018-03-24 07:56:12   prot_disconnected last
     2018-03-23 15:55:00   prot_dummy      last
     2018-03-23 15:53:44   prot_init       last
     2018-03-18 17:37:56   prot_keepAlive  last
     2018-03-23 15:53:44   prot_ok         last
     2018-03-24 08:13:43   state           disconnected
   helper:
     assIdCnt   0
     assIdRep   0
     cnd:
       253        1
     k:
       BufMin     30
       DlyMax     0
     loadLvl:
       bl         40
       a:
         99
         90
         40
         0
       h:
         0          low
         40         batchLevel
         90         high
         99         suspended
     log:
       all        0
       sys        0
       ids:
         ARRAY(0x55f1b5e22af0)
     q:
       HMcndN     253
       answerPend 0
       hmLanQlen  3
       loadLastMax 0
       loadNo     0
       scnt       0
       ald:
         0
         0
         0
         0
         0
         0
         0
         0
         0
         0
         0
         0
       apIDs:
Attributes:
   group      hardware
   hmId       322433
   hmLanQlen  3_normal
   icon       hm_lan
   loadLevel  0:low,40:batchLevel,90:high,99:suspended
   room       homematic
alle meine devices laufen über iogrp vccu:hmLan2

wo könnte der fehler liegen? wo könnten diese riesigen verzögerungen herkommen?
bitte dringendst um hilfe!

[frank]

ich glaube alle 15s wird ein keepalive zum lgw gesendet. dafür gibt es ein verstecktes device mit namen hmLan2:keepAlive. scheinbar gibt es da probleme, wahrscheinlich freezes in fhem.

setze mal für dein alten hmlan "attr dummy 1", vielleicht gibt es hier freezes, da du ihn wohl einfach nur ausgesteckt hast.

ansonnsten hilft vielleicht ein log mit attr logIDs sys,all beim neuen hmlan.

probiere das neue modul FREEZEMON.

[Chris8888]

Hallo Dirk,

meines Wissens nach kommt dies i.d.R. von Netzwerkproblemen...die Antwortzeit des HMLANs ist zu langsam.
Dazu gibt es auch zahlreiche Foreneinträge ...einfach dort mal suchen.

Behelfen könntest du dir für´s erste mit dem Attr respTime auf zB 3 oder 4 , dann ist das Problem zwar nicht weg, aber die LogEinträge.
Auch ist die Firmware deines alten HMLANs alt, ich meine in dem letzten Update auf die 0.965 gab es genau dazu eine Verbesserung.

VG
Christian

[the ratman]

@frank
dummy hab ich mal gesetzt
des weiteren hab ich ein userreading von meiner fritzbox entfern. scheinbar scheint das kommando nicht mehr zu funzen.
seit meinem hilferuf hats nur mehr 1 mal gelaged

Code Auswählen Erweitern

2018.03.24 10:39:14 1: HMUARTLGW hmLan2:keepAlive KeepAlive sent 5.124s too late, this might cause a disconnect!
2018.03.24 10:39:14 1: Timeout for LGTV_WebOS_PresenceRun reached, terminated process 1222
2018.03.24 10:39:14 1: 192.168.178.10:2001 disconnected, waiting to reappear (hmLan2:keepAlive)
2018.03.24 10:39:17 1: HMUARTLGW hmLan2:keepAlive did not respond after all, reopening
2018.03.24 10:39:17 1: 192.168.178.10:2000 reappeared (hmLan2)aber die pings des lgtv-moduls könnens doch nicht sein, oder?

das devicelisting war ich noch schuldig - der keepalive

Code Auswählen Erweitern

Internals:
   CNT        56
   DEVCNT     55
   DevState   99
   DevType    LGW-KeepAlive
   DeviceName 192.168.178.10:2001
   FD         9
   LastOpen   1521884357.76934
   NAME       hmLan2:keepAlive
   NR         6172
   PARTIAL   
   STATE      opened
   TEMPORARY  1
   TYPE       HMUARTLGW
   XmitOpen   0
   Helper:
     NextKeepAlive 1521886084.33469
     Log:
       Resolve    1
       IDs:
   READINGS:
     2018-03-24 10:39:17   state           opened
Attributes:
   room       hidden
   verbose  verbose ohne irgendwas? is das normal?

freezemon is wieder mal an - mal gucken ... und glei gehts los:

Code Auswählen Erweitern

state

s:11:09:59 e:11:10:38 f:39.822 d:no bad guy found :-(

2018-03-24 11:10:38

@dirk
wo muß ich den das attr setzen? find da nix beim hmlan
das alte hmlan hatte mal 95 und nu wieder 94. ein neues update war nicht möglich, drum hab ich auch angenommen, dass es defekt ist und gestern mal ganz raus genommen mit einem close und dann abstecken.

ich glaub langsam auch, dass es an meinem netzwerk liegt ... nur wo? das ganze rennt bei mir auf meiner windows nas mit ner vm mit debian.
was ich noch sagen kann - zwar unwarscheinlich, aber wer weiß - ich hab gröbste probleme mit meinem internetanbieter. dauern disconnects usw. - da waren auch schon 2 techniker da.
könnte es sein, dass die fritzbox dann immer kurz auch intern spinnt?

[Chris8888]

Hi,

das LGTV-Modul produziert ganz schön viel Netzwerktrafik.
Versuche mal das durch das Attr "channelGuide 0" im LG-Modul zu reduzieren.

Das Attr respTime gibt es für das HMLAN. Die Definition kannst du ja auch über die Device-Specific-Help nachlesen.

Ansonsten musst du dein Netz kontrollieren. Timing und Last ist nicht einfach zu analysieren, da gibt es viele Gründe für Probleme.
WLAN Mist, Multicast-Pakete durch Internet-TV, etc.....
   
VG
Christian

[the ratman]

ach du schande - hatte bei beiden lg-tv's channel-guide ein - dabei krieg ich auch so nicht mal das programm angezeigt *g*
is nun wieder aus.
was bleibt sind halt die pings auf die lg's. die brauch ich leider.

netzwerk rennt bei mir alles zeitintensive über n gbit-lan (auch mein virtuelles fehm)
vor allem tritt das alles vor allem nachts auf scheint mir. da ist hier gar nix los.

ich glaub, ich hab den übeltäter - logdb - rennt bei mir automatisch immer so um 3 rum:

ich krieg sofort [Freezemon] freezemon: possible freeze starting at 11:35:40, delay is 1.124 possibly caused by: no bad guy found
wenn ich set logdb deleteOldDays 700

und bei set logdb reduceLog 14
kann ich ned sagen - fhem scheint abgekackt zu sein - ich komm nicht mal mehr auf debian

das ist aber auch neu - ist jetzt 1 jahr oder so problemlos gelaufen
der mysql-server rennt übrigens auch auf dem virtuellen debian

hat da wer ahnung von?

Code Auswählen Erweitern

Internals:
   COLUMNS    field length used for Device: 64, Type: 64, Event: 512, Reading: 64, Value: 128, Unit: 32
   CONFIGURATION ./db.conf
   DEF        ./db.conf .*:(regen|dewpoint|brightness|userTemperature|temperature|valveposition|humidity|userLuminance|luminance|Temperature|co2|noise|pressure|box_cpuTemp|box_powerRate|cpu_temp_avg|userCpu).*
   MODE       asynchronous
   MODEL      MYSQL
   NAME       logdb
   NR         20
   NTFY_ORDER 50-logdb
   PID        562
   REGEXP     .*:(regen|dewpoint|brightness|userTemperature|temperature|valveposition|humidity|userLuminance|luminance|Temperature|co2|noise|pressure|box_cpuTemp|box_powerRate|cpu_temp_avg|userCpu).*
   STATE      connected
   TYPE       DbLog
   UTF8       0
   VERSION    3.9.0
   dbconn     mysql:database=fhem;host=localhost;port=3306
   dbuser     fhem
   HELPER:
     COLSET     1
     DEVICECOL  64
     EVENTCOL   512
     OLDSTATE   connected
     READINGCOL 64
     TYPECOL    64
     UNITCOL    32
     VALUECOL   128
   READINGS:
     2018-03-24 11:46:05   CacheUsage      4
     2018-03-24 11:45:57   NextSync        2018-03-24 11:46:27 or if CacheUsage 25 reached
     2018-03-15 01:00:00   lastRowsDeleted 0
     2018-03-15 05:26:50   reduceLogState  Rows processed: 65412, deleted: 32259, time: 15110.93sec
     2018-03-24 11:45:57   state           connected
   cache:
     index      240
Attributes:
   DbLogType  History
   asyncMode  1
   cacheLimit 25
   devStateIcon connected:time_graph@#00ff00 initialized:time_graph@orange .*:time_graph@red
   group      sys
   icon       edit_paste
   room       fhemach guck - jetzt gibts ja auch non blocking versionen der befehle ... is das neu, oder war ich nur wieder mal blind?