HMLAN Adapter wechselt permanent zwischen disconnected / connected

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

Vorheriges Thema - Nächstes Thema

Nobby1805

Der erste Blick sollte m.E. immer zu der uptime des HMLAN führen ... wie bereits mehrfach hier beschrieben rebootet der HMLAN bei mir manchmal im Minutentakt und dann sind die disconnects nicht von Fhem sondern durch die Reboots hervorgerufen.
FHEM-Featurelevel: 6.2   (fhem.pl:28227/2023-11-29) auf Windows 10 Pro mit Strawberry Perl 5.32.1.1-32bit
TabletUI: 2.7.15
IO: 2xHMLAN(0.965)|HMUSB2(0.967)

martinp876

Reboots können von fehlenden keepalive kommen, also von schlechten Verbindungen oder timing Problemen in fhem.
Es kann auch an unsauberen messages liegen....das sollte ader nicht der Fall sein, das Problem hätten auch andere

Amenophis86

Das Harmony Hub hatte seit ich Verbose 5 eingestellt hab, bisher kein Verbindungsverlust mehr. Und der HMLan vorhin das erste Mal. Das Log davor hat folgendes ausgegeben:

2015.09.17 18:28:05 5: HMLAN_Parse: HMLAN1 R:E309275   stat:0000 t:0843B532 d:FF r:FFDF     m:35 A03F 309275 26EEC9
2015.09.17 18:28:05 5: HMLAN1 dispatch A0935A03F30927526EEC9::-33:HMLAN1
2015.09.17 18:28:05 5: HMLAN_Send:  HMLAN1 S:+309275,00,00,00
2015.09.17 18:28:05 5: HMLAN_Send:  HMLAN1 S:SDC2264D8 stat:  00 t:00000000 d:01 r:DC2264D8 m:35 803F 26EEC9 309275 02041D8DA695
2015.09.17 18:28:05 5: CUL_HM WZ.Heizung.R protEvent:CMDs_done
2015.09.17 18:28:05 5: CUL_HM WZ.Heizung.R sent ACK:2
2015.09.17 18:28:05 5: Triggering WZ.Heizung.R (2 changes)
2015.09.17 18:28:05 5: Notify loop for WZ.Heizung.R CMDs_done
2015.09.17 18:28:11 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:11 4: HarmonyHub: send: <iq type='get' id='ping-439'><ping xmlns='urn:xmpp:ping'/></iq>
2015.09.17 18:28:11 5: HarmonyHub: tag: iq, attr:  id='ping-439' type='result'
2015.09.17 18:28:11 5: HarmonyHub: got ping response 439
2015.09.17 18:28:12 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:13 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:14 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:15 1: HMLAN_Parse: HMLAN1 new condition timeout
2015.09.17 18:28:15 5: Triggering HMLAN1 (3 changes)
2015.09.17 18:28:15 5: Notify loop for HMLAN1 cond: timeout
2015.09.17 18:28:15 5: Triggering myVCCU (1 changes)
2015.09.17 18:28:15 5: Notify loop for myVCCU HMLAN1:timeout,
2015.09.17 18:28:15 1: 192.168.2.104:1000 disconnected, waiting to reappear (HMLAN1)
2015.09.17 18:28:15 5: Triggering HMLAN1 (1 changes)
2015.09.17 18:28:15 5: Notify loop for HMLAN1 DISCONNECTED
2015.09.17 18:28:15 1: HMLAN_Parse: HMLAN1 new condition disconnected
2015.09.17 18:28:15 5: Triggering HMLAN1 (4 changes)
2015.09.17 18:28:15 5: Triggering myVCCU (1 changes)
2015.09.17 18:28:15 5: Notify loop for myVCCU HMLAN1:disconnected,
2015.09.17 18:28:19 1: Perfmon: possible freeze starting at 18:28:16, delay is 3.012
2015.09.17 18:28:24 1: Perfmon: possible freeze starting at 18:28:21, delay is 3.006
2015.09.17 18:29:01 4: HarmonyHub: send: <iq type='get' id='ping-440'><ping xmlns='urn:xmpp:ping'/></iq>
2015.09.17 18:29:01 5: HarmonyHub: tag: iq, attr:  id='ping-440' type='result'
2015.09.17 18:29:01 5: HarmonyHub: got ping response 440
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55439
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem?room=S_DOIF
2015.09.17 18:29:07 4: 7938:FHEMWEB:192.168.2.10:55439: /fhem?room=S_DOIF / RL:1756 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:07 4: Connection closed for FHEMWEB:192.168.2.10:55372: EOF
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/style.css
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_colorpicker.js
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55440
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_fbcalllist.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/jquery-ui.min.css
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55441
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55442
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/pgm2/jquery.min.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_knob.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/darkCommon.css
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/jquery-ui.min.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/pgm2/fhemweb_readingsGroup.js
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55443
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/fhemweb_sortable.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_readingsHistory.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/fhemweb.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/images/default/icoEverything.png
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/dashboard_darkstyle.css
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/pgm2/fhemweb_uzsu.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/images/default/fhemicon_dark.png
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem?XHR=1&inform=type=status;filter=room=S_DOIF;since=1442507346;fmt=JSON×tamp=1442507348116
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem?detail=Heizung.Wohnraum
2015.09.17 18:29:13 4: 7938:FHEMWEB:192.168.2.10:55442: /fhem?detail=Heizung.Wohnraum / RL:3304 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:13 4: Connection closed for FHEMWEB:192.168.2.10:55441: EOF
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/jquery-ui.min.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/style.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/jquery.min.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/fhemweb_colorpicker.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/jquery-ui.min.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/fhemweb_fbcalllist.js
2015.09.17 18:29:13 4: Connection accepted from FHEMWEB:192.168.2.10:55444
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/fhemweb_readingsHistory.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_readingsGroup.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/darkCommon.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/fhemweb_knob.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55444 GET /fhem/pgm2/fhemweb.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/fhemweb_sortable.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_uzsu.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/images/default/icoEverything.png
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/dashboard_darkstyle.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/images/default/fhemicon_dark.png
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem?cmd={ReadingsVal(%22Heizung.Wohnraum%22,%22disable%22,%22%22)}&XHR=1
2015.09.17 18:29:13 5: Cmd: >{ReadingsVal("Heizung.Wohnraum","disable","")}<
2015.09.17 18:29:13 4: 7938:FHEMWEB:192.168.2.10:55443: /fhem?cmd={ReadingsVal(%22Heizung.Wohnraum%22,%22disable%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem?cmd={AttrVal(%22Heizung.Wohnraum%22,%22room%22,%22%22)}&XHR=1
2015.09.17 18:29:13 5: Cmd: >{AttrVal("Heizung.Wohnraum","room","")}<
2015.09.17 18:29:13 4: 7938:FHEMWEB:192.168.2.10:55440: /fhem?cmd={AttrVal(%22Heizung.Wohnraum%22,%22room%22,%22%22)}&XHR=1 / RL:27 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem?XHR=1&inform=type=status;filter=Heizung.Wohnraum;since=1442507352;fmt=JSON×tamp=1442507354168
2015.09.17 18:29:20 1: 192.168.2.104:1000 reappeared (HMLAN1)
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:A26EEC9
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:C
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+26924E,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2AB1CD,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+269027,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCDB0,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+303868,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+290540,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+35367D,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BA5BD,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCF78,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2FE033,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+309275,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+28C16A,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+26982D,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2FE139,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+269591,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCCC8,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCD3B,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+353807,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:Y01,00,
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:Y02,00,
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:Y03,00,
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:T1D8DA6E0,04,00,00000000
2015.09.17 18:29:20 1: HMLAN_Parse: HMLAN1 new condition init
2015.09.17 18:29:20 5: Triggering HMLAN1 (3 changes)
2015.09.17 18:29:20 5: Notify loop for HMLAN1 cond: init
2015.09.17 18:29:20 5: ZE.Batterie: no longer visible, ignoring notify
2015.09.17 18:29:20 5: Triggering myVCCU (1 changes)
2015.09.17 18:29:20 5: Notify loop for myVCCU HMLAN1:init,
2015.09.17 18:29:20 5: ZE.Batterie: not on any display, ignoring notify
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 S:SDC238903 stat:  00 t:00000000 d:01 r:DC238903 m:99 8112 26EEC9 000000
2015.09.17 18:29:20 5: Triggering HMLAN1 (1 changes)
2015.09.17 18:29:20 5: Notify loop for HMLAN1 CONNECTED


Aktuell dabei unser neues Haus mit KNX am einrichten. Im nächsten Schritt dann KNX mit FHEM verbinden. Allein zwei Dinge sind dabei selten: Zeit und Geld...

justme1968

der harmony hub erwartet jede minute ein keepalive. das modul sendet es 50 sekunden. kleinere abweichungen sind aber nicht so kritisch wie bei hmlan.

gruss
  andre
hue, tradfri, alexa-fhem, homebridge-fhem, LightScene, readingsGroup, ...

https://github.com/sponsors/justme-1968

Amenophis86

wie gesagt, hat das Hub auch nicht mehr gemeckert seit heute Mittag. Nur der HMLan hat sich mal wieder verabschiedet. Vll kann ja einer in dem Log oben etwas erkennen, ich sehe keinen Fehler. Meine Kenntnisse sind aber auch beschränkt.
Aktuell dabei unser neues Haus mit KNX am einrichten. Im nächsten Schritt dann KNX mit FHEM verbinden. Allein zwei Dinge sind dabei selten: Zeit und Geld...

Nobby1805

Zitat von: martinp876 am 17 September 2015, 20:19:52
Reboots können von fehlenden keepalive kommen,
Bist du sicher ? Ich habe selbst einen Monitor programmiert der die Messages vom HMLAN liest aber NIE einen keepalive sendet ... reboots passieren da keine
FHEM-Featurelevel: 6.2   (fhem.pl:28227/2023-11-29) auf Windows 10 Pro mit Strawberry Perl 5.32.1.1-32bit
TabletUI: 2.7.15
IO: 2xHMLAN(0.965)|HMUSB2(0.967)

martinp876

Nein, nicht sicher. Es gibt sicher einen disconnect wenn nach 30s kein keepalive gekommen ist. Ob die uptime auf 0 gesetzt wird kann ich eigentlich nicht sagen.

Nobby1805

uptime, bzw. genauer: der x8 Zeitstempel-Wert in den Telegrammen, wird dabei NICHT auf Null gesetzt
FHEM-Featurelevel: 6.2   (fhem.pl:28227/2023-11-29) auf Windows 10 Pro mit Strawberry Perl 5.32.1.1-32bit
TabletUI: 2.7.15
IO: 2xHMLAN(0.965)|HMUSB2(0.967)

Amenophis86


2015.09.17 18:28:05 5: HMLAN_Parse: HMLAN1 R:E309275   stat:0000 t:0843B532 d:FF r:FFDF     m:35 A03F 309275 26EEC9
2015.09.17 18:28:05 5: HMLAN1 dispatch A0935A03F30927526EEC9::-33:HMLAN1
2015.09.17 18:28:05 5: HMLAN_Send:  HMLAN1 S:+309275,00,00,00
2015.09.17 18:28:05 5: HMLAN_Send:  HMLAN1 S:SDC2264D8 stat:  00 t:00000000 d:01 r:DC2264D8 m:35 803F 26EEC9 309275 02041D8DA695
2015.09.17 18:28:05 5: CUL_HM WZ.Heizung.R protEvent:CMDs_done
2015.09.17 18:28:05 5: CUL_HM WZ.Heizung.R sent ACK:2
2015.09.17 18:28:05 5: Triggering WZ.Heizung.R (2 changes)
2015.09.17 18:28:05 5: Notify loop for WZ.Heizung.R CMDs_done
2015.09.17 18:28:11 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:11 4: HarmonyHub: send: <iq type='get' id='ping-439'><ping xmlns='urn:xmpp:ping'/></iq>
2015.09.17 18:28:11 5: HarmonyHub: tag: iq, attr:  id='ping-439' type='result'
2015.09.17 18:28:11 5: HarmonyHub: got ping response 439
2015.09.17 18:28:12 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:13 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:14 5: HMLAN_Send:  HMLAN1 I:K
2015.09.17 18:28:15 1: HMLAN_Parse: HMLAN1 new condition timeout
2015.09.17 18:28:15 5: Triggering HMLAN1 (3 changes)
2015.09.17 18:28:15 5: Notify loop for HMLAN1 cond: timeout
2015.09.17 18:28:15 5: Triggering myVCCU (1 changes)
2015.09.17 18:28:15 5: Notify loop for myVCCU HMLAN1:timeout,
2015.09.17 18:28:15 1: 192.168.2.104:1000 disconnected, waiting to reappear (HMLAN1)
2015.09.17 18:28:15 5: Triggering HMLAN1 (1 changes)
2015.09.17 18:28:15 5: Notify loop for HMLAN1 DISCONNECTED
2015.09.17 18:28:15 1: HMLAN_Parse: HMLAN1 new condition disconnected
2015.09.17 18:28:15 5: Triggering HMLAN1 (4 changes)
2015.09.17 18:28:15 5: Triggering myVCCU (1 changes)
2015.09.17 18:28:15 5: Notify loop for myVCCU HMLAN1:disconnected,
2015.09.17 18:28:19 1: Perfmon: possible freeze starting at 18:28:16, delay is 3.012
2015.09.17 18:28:24 1: Perfmon: possible freeze starting at 18:28:21, delay is 3.006
2015.09.17 18:29:01 4: HarmonyHub: send: <iq type='get' id='ping-440'><ping xmlns='urn:xmpp:ping'/></iq>
2015.09.17 18:29:01 5: HarmonyHub: tag: iq, attr:  id='ping-440' type='result'
2015.09.17 18:29:01 5: HarmonyHub: got ping response 440
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55439
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem?room=S_DOIF
2015.09.17 18:29:07 4: 7938:FHEMWEB:192.168.2.10:55439: /fhem?room=S_DOIF / RL:1756 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:07 4: Connection closed for FHEMWEB:192.168.2.10:55372: EOF
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/style.css
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_colorpicker.js
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55440
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_fbcalllist.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/jquery-ui.min.css
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55441
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55442
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/pgm2/jquery.min.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_knob.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/darkCommon.css
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/jquery-ui.min.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/pgm2/fhemweb_readingsGroup.js
2015.09.17 18:29:07 4: Connection accepted from FHEMWEB:192.168.2.10:55443
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/fhemweb_sortable.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_readingsHistory.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/fhemweb.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/images/default/icoEverything.png
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/dashboard_darkstyle.css
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/pgm2/fhemweb_uzsu.js
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem/images/default/fhemicon_dark.png
2015.09.17 18:29:07 4: HTTP FHEMWEB:192.168.2.10:55441 GET /fhem?XHR=1&inform=type=status;filter=room=S_DOIF;since=1442507346;fmt=JSON×tamp=1442507348116
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem?detail=Heizung.Wohnraum
2015.09.17 18:29:13 4: 7938:FHEMWEB:192.168.2.10:55442: /fhem?detail=Heizung.Wohnraum / RL:3304 / text/html; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:13 4: Connection closed for FHEMWEB:192.168.2.10:55441: EOF
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/jquery-ui.min.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/style.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/jquery.min.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/fhemweb_colorpicker.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/jquery-ui.min.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/fhemweb_fbcalllist.js
2015.09.17 18:29:13 4: Connection accepted from FHEMWEB:192.168.2.10:55444
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/fhemweb_readingsHistory.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_readingsGroup.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/pgm2/darkCommon.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/fhemweb_knob.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55444 GET /fhem/pgm2/fhemweb.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55442 GET /fhem/pgm2/fhemweb_sortable.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55439 GET /fhem/pgm2/fhemweb_uzsu.js
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem/images/default/icoEverything.png
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/pgm2/dashboard_darkstyle.css
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem/images/default/fhemicon_dark.png
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55443 GET /fhem?cmd={ReadingsVal(%22Heizung.Wohnraum%22,%22disable%22,%22%22)}&XHR=1
2015.09.17 18:29:13 5: Cmd: >{ReadingsVal("Heizung.Wohnraum","disable","")}<
2015.09.17 18:29:13 4: 7938:FHEMWEB:192.168.2.10:55443: /fhem?cmd={ReadingsVal(%22Heizung.Wohnraum%22,%22disable%22,%22%22)}&XHR=1 / RL:21 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem?cmd={AttrVal(%22Heizung.Wohnraum%22,%22room%22,%22%22)}&XHR=1
2015.09.17 18:29:13 5: Cmd: >{AttrVal("Heizung.Wohnraum","room","")}<
2015.09.17 18:29:13 4: 7938:FHEMWEB:192.168.2.10:55440: /fhem?cmd={AttrVal(%22Heizung.Wohnraum%22,%22room%22,%22%22)}&XHR=1 / RL:27 / text/plain; charset=UTF-8 / Content-Encoding: gzip
/
2015.09.17 18:29:13 4: HTTP FHEMWEB:192.168.2.10:55440 GET /fhem?XHR=1&inform=type=status;filter=Heizung.Wohnraum;since=1442507352;fmt=JSON×tamp=1442507354168
2015.09.17 18:29:20 1: 192.168.2.104:1000 reappeared (HMLAN1)
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:A26EEC9
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:C
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+26924E,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2AB1CD,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+269027,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCDB0,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+303868,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+290540,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+35367D,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BA5BD,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCF78,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2FE033,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+309275,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+28C16A,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+26982D,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2FE139,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+269591,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCCC8,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+2BCD3B,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:+353807,00,00,00
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:Y01,00,
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:Y02,00,
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:Y03,00,
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 I:T1D8DA6E0,04,00,00000000
2015.09.17 18:29:20 1: HMLAN_Parse: HMLAN1 new condition init
2015.09.17 18:29:20 5: Triggering HMLAN1 (3 changes)
2015.09.17 18:29:20 5: Notify loop for HMLAN1 cond: init
2015.09.17 18:29:20 5: ZE.Batterie: no longer visible, ignoring notify
2015.09.17 18:29:20 5: Triggering myVCCU (1 changes)
2015.09.17 18:29:20 5: Notify loop for myVCCU HMLAN1:init,
2015.09.17 18:29:20 5: ZE.Batterie: not on any display, ignoring notify
2015.09.17 18:29:20 5: HMLAN_Send:  HMLAN1 S:SDC238903 stat:  00 t:00000000 d:01 r:DC238903 m:99 8112 26EEC9 000000
2015.09.17 18:29:20 5: Triggering HMLAN1 (1 changes)
2015.09.17 18:29:20 5: Notify loop for HMLAN1 CONNECTED



und



2015.09.18 01:27:17 5: HMLAN_Parse: HMLAN1 R:E2BCD3B   stat:0000 t:017F8E6A d:FF r:FFD3     m:30 A03F 2BCD3B 26EEC9
2015.09.18 01:27:17 5: HMLAN1 dispatch A0930A03F2BCD3B26EEC9::-45:HMLAN1
2015.09.18 01:27:17 5: HMLAN_Send:  HMLAN1 S:+2BCD3B,00,00,00
2015.09.18 01:27:17 5: HMLAN_Send:  HMLAN1 S:SDDA22DB7 stat:  00 t:00000000 d:01 r:DDA22DB7 m:30 803F 26EEC9 2BCD3B 02041D8E08D5
2015.09.18 01:27:17 5: CUL_HM WZ.Heizung.L protEvent:CMDs_done
2015.09.18 01:27:17 5: CUL_HM WZ.Heizung.L sent ACK:2
2015.09.18 01:27:17 5: Triggering WZ.Heizung.L (2 changes)
2015.09.18 01:27:17 5: Notify loop for WZ.Heizung.L CMDs_done
2015.09.18 01:27:17 5: ZE.Batterie: not on any display, ignoring notify
2015.09.18 01:27:38 5: HMLAN_Send:  HMLAN1 I:K
2015.09.18 01:27:39 5: HMLAN_Send:  HMLAN1 I:K
2015.09.18 01:27:40 5: HMLAN_Send:  HMLAN1 I:K
2015.09.18 01:27:41 5: HMLAN_Send:  HMLAN1 I:K
2015.09.18 01:27:42 1: HMLAN_Parse: HMLAN1 new condition timeout
2015.09.18 01:27:42 5: Triggering HMLAN1 (3 changes)
2015.09.18 01:27:42 5: Notify loop for HMLAN1 cond: timeout
2015.09.18 01:27:42 5: ZE.Batterie: not on any display, ignoring notify
2015.09.18 01:27:42 5: Triggering myVCCU (1 changes)
2015.09.18 01:27:42 5: Notify loop for myVCCU HMLAN1:timeout,
2015.09.18 01:27:42 5: ZE.Batterie: not on any display, ignoring notify
2015.09.18 01:27:42 1: 192.168.2.104:1000 disconnected, waiting to reappear (HMLAN1)
2015.09.18 01:27:42 5: Triggering HMLAN1 (1 changes)
2015.09.18 01:27:42 5: Notify loop for HMLAN1 DISCONNECTED
2015.09.18 01:27:42 1: HMLAN_Parse: HMLAN1 new condition disconnected
2015.09.18 01:27:42 5: Triggering HMLAN1 (4 changes)
2015.09.18 01:27:42 5: Triggering myVCCU (1 changes)
2015.09.18 01:27:42 5: Notify loop for myVCCU HMLAN1:disconnected,
2015.09.18 01:27:42 5: ZE.Batterie: not on any display, ignoring notify
2015.09.18 01:27:42 5: ZE.Batterie: not on any display, ignoring notify
2015.09.18 01:27:44 1: 192.168.2.104:1000 reappeared (HMLAN1)


kann jemand hieraus erkennen, wieso der HMLan sich zwei Mal disconnected hat und mir damit bei der Fehler Beseitigung helfen?
Aktuell dabei unser neues Haus mit KNX am einrichten. Im nächsten Schritt dann KNX mit FHEM verbinden. Allein zwei Dinge sind dabei selten: Zeit und Geld...

Nobby1805

FHEM-Featurelevel: 6.2   (fhem.pl:28227/2023-11-29) auf Windows 10 Pro mit Strawberry Perl 5.32.1.1-32bit
TabletUI: 2.7.15
IO: 2xHMLAN(0.965)|HMUSB2(0.967)

frank

Zitat von: martinp876 am 17 September 2015, 22:35:00
Nein, nicht sicher. Es gibt sicher einen disconnect wenn nach 30s kein keepalive gekommen ist. Ob die uptime auf 0 gesetzt wird kann ich eigentlich nicht sagen.
ich hatte auch schon mal tests gemacht mit einem blockierenden sleep, um einen reboot des hmlan zu erzwingen. das war mir aber nicht gelungen. ob es in dieser zeit einen "normalen" disconnect gab, kann ich nicht sagen. fhem hatte ich mindestens für 5 minuten blockiert und es gab nie einen reboot.

jedenfalls habe ich festgestellt, sobald fhem condition=timeout meldet, muss ein reboot des hmlan erfolgt sein, da die load immer zurückgesetzt wurde. bei einem "normalen" disconnect passiert dies nicht.

da die letzten beiden geposteten disconnects in verbindung mit einem timeout stattgefunden haben, behaupte ich, dass diese disconnects nicht durch eine blockade von fhem (fehlende keepalive) hervorgerufen wurden. auch perfmon bestätigt dies, da keine freezes zu den zeitpunkten geloggt wurden.

es bleibt also die frage, warum der hmlan rebootet.

wenn überhaupt, kann es wohl nur aus einem lan mitschnitt ermittelt werden. da nobby noch nie einen reboot mit seinem monitor hatte, müsste es ja dann eigentlich an unterschiedlichen connections zum hmlan liegen, so dass eine connction von fhem zum hmlan diesen "anfällig" für reboots macht. dann wäre es ja interessant zu erfahren, welche unterschiede es hier gibt.

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

Amenophis86

Zitat von: Nobby1805 am 18 September 2015, 10:44:57
schau doch mal nach der uptime des HMLAN

Die Uptime passt genau zum letzten Disconncet.

Zitat von: frank am 18 September 2015, 12:20:22
wenn überhaupt, kann es wohl nur aus einem lan mitschnitt ermittelt werden. da nobby noch nie einen reboot mit seinem monitor hatte, müsste es ja dann eigentlich an unterschiedlichen connections zum hmlan liegen, so dass eine connction von fhem zum hmlan diesen "anfällig" für reboots macht. dann wäre es ja interessant zu erfahren, welche unterschiede es hier gibt.

Aktuell ist das ganze wie folgt angeschlossen:

Router <-> Switch <-> HMLan alles per LANKabel.

Wenn du mir sagst, wie ich einen WLan Mitschnitt mache, dann kann ich das überprüfen. Die Steckdose, in der der HMLan sitzt ist eine Mehrfachsteckdose, welche sich nicht schalten lässt. Ein Stromverlust kann somit eigentlich ausgeschlossen werden, da sonst auch der PI (gleiche Mehrfachsteckdose) mit neustarten müsste und dies ist ja nicht der Fall.
Aktuell dabei unser neues Haus mit KNX am einrichten. Im nächsten Schritt dann KNX mit FHEM verbinden. Allein zwei Dinge sind dabei selten: Zeit und Geld...

Nobby1805

Zitat von: frank am 18 September 2015, 12:20:22
es bleibt also die frage, warum der hmlan rebootet.
diese Frage habe ich auch an ELV und ELV dann an eQ3 gestellt ... leider bis heute noch keine Antwort
Zitat

wenn überhaupt, kann es wohl nur aus einem lan mitschnitt ermittelt werden. da nobby noch nie einen reboot mit seinem monitor hatte, müsste es ja dann eigentlich an unterschiedlichen connections zum hmlan liegen, so dass eine connction von fhem zum hmlan diesen "anfällig" für reboots macht. dann wäre es ja interessant zu erfahren, welche unterschiede es hier gibt.
das muss ich etwas präzisieren ... ich habe mit dem Monitor schon reboots festgestellt ... am normalen LAN genau so häufig, aber nicht gleichzeitig, zum HMLAN der am Fhem hängt ... dann auf Bitte von ELV/eQ3 an einem LAN an dem nur eine Rechner und der HMLAN hängt sehr, sehr selten

Es liegt aus meiner Sicht also an bestimmten Paketen auf dem LAN ... ein normaler Netzwerk-Sniff auf dem Rechner auf dem auch Fhem bzw. der Monitor läuft zeigt zu den Zeiten wo die reboots stattfinden nichts Auffäliges. Eigentlich müsste man ja direkt den Port des HMLAN sniffen, aber leider habe ich keinen Zugriff auf einen Hardware-Sniffer (mehr).

@Amenophis86: hast du bei dir im Netz Geräte die mit Multicast arbeiten? Z.B. Telekom Entertain ?

PS ich habe für den HMLAN ein User-Reading eingerichtet das die Uptime als Reading "wandelt" und sie dadurch in den Log schreibt, da habe ich dann gefunden, das manchmal die Reboots im Abstand von wenigen Minuten stattfinden   ... ok, xyz ist vielleicht nicht der sinnvollste Name dafür  :o
userReadings

xyz {substr(InternalVal("HMLAN1","uptime",""),4,length(InternalVal("HMLAN1","uptime",""))-11)}
FHEM-Featurelevel: 6.2   (fhem.pl:28227/2023-11-29) auf Windows 10 Pro mit Strawberry Perl 5.32.1.1-32bit
TabletUI: 2.7.15
IO: 2xHMLAN(0.965)|HMUSB2(0.967)

frank

die wenigsten disconnects gibt es wohl, wenn fhem und der hmlan möglichst ohne weitere netzwerkkomponenten am selben router/switch hängen. und beachten, der hmlan kann nur 100mbit. hat nobby ja gerade bestätigt.

meine fritzbox hat wireshark zum mitschneiden direkt auf der box.

wie schonmal irgendwo gesagt, hatte ich regelmässige reboots des hmlan kurz nach mitternacht beobachtet, im zusammenhang mit dem setzen der uhrzeit an meinen hm-cc-tc. nicht jeden tag und auch nicht immer mit dem selben tc, aber auffällig häufig. seitdem diese vom hmusb bedient werden, ist mit diesen mitternächtlichen reboots schluss.
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

Amenophis86

Zitat von: Nobby1805 am 18 September 2015, 13:23:45
@Amenophis86: hast du bei dir im Netz Geräte die mit Multicast arbeiten? Z.B. Telekom Entertain ?

Ja, habe Telekom Entertain. Kann aber zu den Uhrzeiten wo der HMLan sich disconnceted keine Verbindung zu Telekom Entertain feststellen. Die Box war nur im Standby.

Zitat von: frank am 18 September 2015, 13:40:25
die wenigsten disconnects gibt es wohl, wenn fhem und der hmlan möglichst ohne weitere netzwerkkomponenten am selben router/switch hängen. und beachten, der hmlan kann nur 100mbit. hat nobby ja gerade bestätigt.

Beide hängen am gleichen Switch, welches bis zu 1GBit kann. Der PI jedoch an einem Medium Port und der HMLan am Standardport. Es handelt sich um ein Zyxel GS-108B v2 (http://www.zyxel.com/uk/en/products_services/gs_108b.shtml)  Natürliche hängen an dem Switch noch andere Komponenten, wie zB die Synology NAS. Der Entertain Reciver hingegen hängt direkt am Speedport.
Aktuell dabei unser neues Haus mit KNX am einrichten. Im nächsten Schritt dann KNX mit FHEM verbinden. Allein zwei Dinge sind dabei selten: Zeit und Geld...