so2018.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:
vccuInternals:
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 hmlanInternals:
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)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!
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.
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
@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 gelaged2018.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 keepaliveInternals:
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: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?
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
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?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?