Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot

AndyMu · 17 Dezember 2017, 10:35:33

Hallo *,

seit dem Umzug von FHEM auf einen Rasp3 habe ich, vornehmlich mit meinen beiden Türkontakten, ein Problem... die LED leuchtet nach kurz orange für eine kurze Zeit rot, wenn ich öffne/schließe. Früher war das grün.
Ich habe den verbose von global und dem Gerät auf 5 gesetzt, bekomme folgende Meldungen im Log:
CUL_HM eg_bar_tk dupe: dont process
Das aber auch für meine LED Panels, also irgendwie ist da was nicht sauber...

Kann sich das bitte mal einer anschauen, der sich auskennt? Ein Reset des Türkontakts über set xxx reset und Neuanlernen hat leider nix geholfen.

Danke!

Das Listing vom Gerät:

Code Auswählen

Internals: 
   DEF        343BD9 
   HMLAN1_MSGCNT 159 
   HMLAN1_RAWMSG E343BD9,0000,059E9080,FF,FFAF,87A641343BD9A24077018000 
   HMLAN1_RSSI -81 
   HMLAN1_TIME 2017-12-17 10:24:37 
   HMLAN2_MSGCNT 161 
   HMLAN2_RAWMSG E343BD9,0000,470295DC,FF,FFB6,87A641343BD9A24077018000 
   HMLAN2_RSSI -74 
   HMLAN2_TIME 2017-12-17 10:24:37 
   IODev      HMLAN1 
   LASTInputDev HMLAN2 
   MSGCNT     477 
   NAME       eg_bar_tk 
   NOTIFYDEV  global 
   NR         166 
   NTFY_ORDER 50-eg_bar_tk 
   STATE      closed 
   TYPE       CUL_HM 
   hmusb_MSGCNT 157 
   hmusb_RAWMSG E343BD9,0000,77112D23,FF,FFAE,87A641343BD9A24077018000 
   hmusb_RSSI -82 
   hmusb_TIME 2017-12-17 10:24:37 
   lastMsg    No:87 - t:41 s:343BD9 d:A24077 018000 
   protCondBurst off 
   protLastRcv 2017-12-17 10:24:37 
   protSnd    157 last_at:2017-12-17 10:24:37 
   protState  CMDs_done 
   rssi_at_HMLAN1 cnt:159 lst:-81 min:-102 max:-78 avg:-87.91 
   rssi_at_HMLAN2 min:-95 avg:-75.24 max:-63 cnt:161 lst:-74 
   rssi_at_hmusb lst:-82 cnt:157 avg:-81.21 max:-72 min:-100 
   READINGS: 
     2017-12-17 10:11:07   Activity        alive 
     2017-12-17 10:11:08   CommandAccepted yes 
     2017-12-17 10:11:07   D-firmware      2.4 
     2017-12-17 10:11:07   D-serialNr      LEQ1254374 
     2017-12-17 10:11:08   PairedTo        0xA24077 
     2017-09-17 20:36:48   R-cyclicInfoMsg off 
     2017-09-17 20:36:49   R-eventDlyTime  0 s 
     2017-09-17 20:36:49   R-ledOnTime     0.5 s 
     2017-09-17 20:36:49   R-msgRhsPosA    closed 
     2017-09-17 20:36:49   R-msgRhsPosB    open 
     2017-09-17 20:36:49   R-msgRhsPosC    tilted 
     2017-12-17 10:11:08   R-pairCentral   0xA24077 
     2017-09-17 20:36:49   R-sign          off 
     2017-09-17 20:36:48   R-transmDevTryMax 6 
     2017-09-17 20:36:49   R-transmitTryMax 6 
     2017-12-17 10:11:50   alive           yes 
     2017-12-17 10:24:37   battery         ok 
     2017-12-17 10:24:37   contact         closed (to vccu) 
     2017-12-17 10:11:50   recentStateType info 
     2017-12-17 10:11:50   sabotageError   off 
     2017-12-17 10:24:37   state           closed 
     2017-12-17 10:24:37   trigger_cnt     128 
   helper: 
     HM_CMDNR   135 
     cSnd       01A24077343BD901040000000001,01A24077343BD90103 
     mId        0030 
     peerIDsRaw ,00000000 
     rxType     4 
     supp_Pair_Rep 0 
     ack: 
     expert: 
       def        1 
       det        1 
       raw        0 
       tpl        0 
     io: 
       newChn     +343BD9,00,00,00 
       nextSend   1513502677.87891 
       rxt        0 
       vccu       vccu 
       p: 
         343BD9 
         00 
         00 
         00 
       prefIO: 
         HMLAN1 
     mRssi: 
       mNo        87 
       io: 
         HMLAN1     -79 
         HMLAN2     -74 
         hmusb      -82 
     prt: 
       awake      0 
       bErr       0 
       brstWu     0 
       sProc      0 
       rspWait: 
     q: 
       qReqConf 
       qReqStat 
     role: 
       chn        1 
       dev        1 
     rpt: 
       IO         HMLAN1 
       flg        A 
       ts         1513502677.59452 
       ack: 
         HASH(0x1d34c30) 
         878002A24077343BD900 
     rssi: 
       at_HMLAN1: 
         avg        -87.9119496855345 
         cnt        159 
         lst        -81 
         max        -78 
         min        -102 
       at_HMLAN2: 
         avg        -75.248447204969 
         cnt        161 
         lst        -74 
         max        -63 
         min        -95 
       at_hmusb: 
         avg        -81.2165605095541 
         cnt        157 
         lst        -82 
         max        -72 
         min        -100 
     shadowReg: 
     tmpl: 
Attributes: 
   Fensterkontakte SichereFenster 
   IODev      HMLAN2 
   IOgrp      vccu:HMLAN2 
   actCycle   028:00 
   actStatus  alive 
   alias      EG Bar Terrassentür 
   autoReadReg 4_reqStatus 
   burstAccess 1_auto 
   devStateIcon closed:fts_door open:fts_door_open zu:fts_door offen:fts_door_open tilted:fts_door_tilt: gekippt:fts_door_tilt tilted:fts_door_tilt tilted:fts_door_tilt 
   event-on-change-reading state 
   expert     1_on 
   firmware   2.4 
   fp_Erdgeschoss 460,345,0, 
   group      Fensterkontakte 
   model      HM-SEC-RHS 
   peerIDs    00000000, 
   room       EG Bar 
   serialNr   LEQ1254374 
   subType    threeStateSensor 
   userattr   Fensterkontakte Fensterkontakte_map structexclude winOpenMaxTrigger winOpenName winOpenTemp winOpenTimer winOpenTimer2 winOpenType:Fenster,Türe wz_fenster wz_fenster_map 
   verbose    5 
   winOpenMaxTrigger 6 
   winOpenTemp 10

Code Auswählen

2017.12.17 10:24:37.775 5: HMLAN/RAW: /EA24077,0000,059E9114,FF,FFBF,19A011A2407720F723801002

2017.12.17 10:24:37.775 5: HMLAN_Parse: HMLAN1 R:EA24077   stat:0000 t:059E9114 d:FF r:FFBF     m:19 A011 A24077 20F723 801002
2017.12.17 10:24:37.776 5: HMLAN1: dispatch A0C19A011A2407720F723801002::-65:HMLAN1
2017.12.17 10:24:37.778 5: HMLAN/RAW: /E343BD9,0000,77112D23,FF,FFAE,87A641343BD9A24077018000
EA24077,0000,77112D9D,FF,FFC3,878002A24077343BD900
EA24077,0000,77112DB6,FF,FFCA,19A011A2407720F723801002

2017.12.17 10:24:37.778 5: HMLAN_Parse: hmusb R:E343BD9   stat:0000 t:77112D23 d:FF r:FFAE     m:87 A641 343BD9 A24077 018000
2017.12.17 10:24:37.779 5: hmusb: dispatch A0C87A641343BD9A24077018000::-82:hmusb
2017.12.17 10:24:37.780 4: CUL_HM eg_bar_tk dupe: dont process
2017.12.17 10:24:37.780 5: HMLAN_Parse: hmusb R:EA24077   stat:0000 t:77112D9D d:FF r:FFC3     m:87 8002 A24077 343BD9 00
2017.12.17 10:24:37.781 5: hmusb: dispatch A0A878002A24077343BD900::-61:hmusb
2017.12.17 10:24:37.783 5: HMLAN_Parse: hmusb R:EA24077   stat:0000 t:77112DB6 d:FF r:FFCA     m:19 A011 A24077 20F723 801002
2017.12.17 10:24:37.784 5: hmusb: dispatch A0C19A011A2407720F723801002::-54:hmusb
2017.12.17 10:24:37.786 5: HMLAN/RAW: /E343BD9,0000,470295DC,FF,FFB6,87A641343BD9A24077018000
EA24077,0000,47029647,FF,FFCF,E0A011A240771EB52B800802

2017.12.17 10:24:37.786 5: HMLAN_Parse: HMLAN2 R:E343BD9   stat:0000 t:470295DC d:FF r:FFB6     m:87 A641 343BD9 A24077 018000
2017.12.17 10:24:37.787 5: HMLAN2: dispatch A0C87A641343BD9A24077018000::-74:HMLAN2
2017.12.17 10:24:37.788 4: CUL_HM eg_bar_tk dupe: dont process
2017.12.17 10:24:37.789 5: HMLAN_Parse: HMLAN2 R:EA24077   stat:0000 t:47029647 d:FF r:FFCF     m:E0 A011 A24077 1EB52B 800802
2017.12.17 10:24:37.789 5: HMLAN2: dispatch A0CE0A011A240771EB52B800802::-49:HMLAN2
2017.12.17 10:24:37.822 5: HMLAN/RAW: /E1EB52B,0000,470296C7,FF,FFB8,E080021EB52BA240770108020033000CA96A

Pfriemler · 17 Dezember 2017, 13:08:51

"dupe dont process" hat nach meinem Verständnis etwas mit mehrfach (ggf. über mehrere IO, Du hast ja drei) empfangenen Messages zu tun, die erklärtermaßen nicht doppelt prozessiert werden. Das ist also eher normal.

Die RHS haben gern mal Empfangsschwierigkeiten, d.h. das ACK auf ihre Sendung kommt nicht an. Das habe ich bei mir sogar auf 2 Metern Distanz, besonders wenn beim zügigen Terrassentürschließen der Türkontakt am Rahmen und der Griffsensor gleichzeitig Statusänderung melden. Bei Dir dürften die HMLAN dafür verantwortlich sein, "sauberer" wäre das peering mit einem virtuellen Button (vccu). Generell ist die Funkgüte bezüglich Deiner beiden HMLAN nicht so dolle. Hat sich da eine Position geändert?

frank · 17 Dezember 2017, 14:18:22

das automatische ack hat der hmusb gehört. nach seinen internen timestamps kam es 120 ms nach der statusinfo. eventuell schon zu spät?

das log ist leider unvollständig, sonst könnte man erkennen, welcher hmlan gesendet hat.

die zuordnung der io ist unklar. nach attributen sollte es hmlan2 sein. internals/iodev sagt allerdings hmlan1.
deswegen und weil die logeinträge im ms rythmus geschrieben wurden, vermute ich auch ein fhem freeze durch hmlan disconnect.

haben alle io aktuelle fw (0.965/0.967)?

@pfriemler
das gleichzeitige senden der fk, kannst du doch mit dem register evtdlytime entzerren.

frank · 17 Dezember 2017, 15:46:09

mir ist gerade noch dies eingefallen https://forum.fhem.de/index.php/topic,20776.msg430486.html#msg430486

beim rhs hatte ich ohne eventdlytime auch probleme beim schnellen durchschalten von zb pos1 nach pos3. und beim prellen in die mittelstellung.

Pfriemler · 17 Dezember 2017, 16:05:22

Zitat von: frank am 17 Dezember 2017, 14:18:22
das gleichzeitige senden der fk, kannst du doch mit dem register evtdlytime entzerren.

Ich dachte, ich hätte das längst getan. Aber da auch mein RHS zwischenzeitlich an Vergesslichtkeit litt (da gab es schon mal eine Diskussion), war das wieder auf 0. Das hatte ich übersehen. Korrigiert.

AndyMu · 17 Dezember 2017, 16:54:43

Vielen Dank für die vielen Antworten, ich will mal mein Bestes geben...

Das ganze Log aus der Zeitspanne ist das hier:

Code Auswählen

2017.12.17 10:24:37.589 5: HMLAN/RAW: /E343BD9,0000,059E9080,FF,FFAF,87A641343BD9A24077018000

2017.12.17 10:24:37.590 5: HMLAN_Parse: HMLAN1 R:E343BD9   stat:0000 t:059E9080 d:FF r:FFAF     m:87 A641 343BD9 A24077 018000
2017.12.17 10:24:37.591 5: HMLAN1: dispatch A0C87A641343BD9A24077018000::-81:HMLAN1
2017.12.17 10:24:37.594 5: CUL_HM eg_bar_tk prep ACK for 01
2017.12.17 10:24:37.594 5: HMLAN: Skip ACK
2017.12.17 10:24:37.595 5: CUL_HM eg_bar_tk protEvent:CMDs_done
2017.12.17 10:24:37.595 5: CUL_HM eg_bar_tk sent ACK:2
2017.12.17 10:24:37.598 5: Starting notify loop for eg_bar_tk, 1 event(s), first is closed
2017.12.17 10:24:37.599 5: Update structure 'AlarmanlageFenster' to closed because device eg_bar_tk has changed
2017.12.17 10:24:37.600 5: Starting notify loop for AlarmanlageFenster, 1 event(s), first is closed
2017.12.17 10:24:37.612 5: Triggering winOpen.CloseNotify
2017.12.17 10:24:37.612 4: winOpen.CloseNotify exec {winOpenStop($NAME)}
2017.12.17 10:24:37.613 5: Cmd: >{winOpenStop($NAME)}<
2017.12.17 10:24:37.616 5: End notify loop for AlarmanlageFenster
2017.12.17 10:24:37.618 5: Triggering AlarmanlageFensterZu
2017.12.17 10:24:37.619 4: AlarmanlageFensterZu exec set ug_eb_led16_Led_08,og_gang_led16_Led_16 led green;
 	IF (Value("Alarmanlage") eq "on") (
		{fhem('set AnMuLoBot message @@AnMuLo @@KaMuLo97 Entwarnung: '.AttrVal("$NAME","alias","$NAME").' wurde wieder geschlossen');;
 		Log 1, "Entwarnung an alle: ".AttrVal("$NAME","alias","$NAME")." wurden wieder geschlossen"})

2017.12.17 10:24:37.619 5: Cmd: >set ug_eb_led16_Led_08,og_gang_led16_Led_16 led green<
2017.12.17 10:24:37.621 5: Starting notify loop for ug_eb_led16, 1 event(s), first is CMDs_pending
2017.12.17 10:24:37.637 5: End notify loop for ug_eb_led16
2017.12.17 10:24:37.637 5: CUL_HM ug_eb_led16 protEvent:CMDs_pending pending:1
2017.12.17 10:24:37.639 5: Starting notify loop for ug_eb_led16_Led_08, 1 event(s), first is set_led green
2017.12.17 10:24:37.654 5: End notify loop for ug_eb_led16_Led_08
2017.12.17 10:24:37.655 3: CUL_HM set ug_eb_led16_Led_08 led green
2017.12.17 10:24:37.656 5: HMLAN_Send:  hmusb S:S63CA1BF7 stat:  00 t:00000000 d:01 r:63CA1BF7 m:E0 A011 A24077 1EB52B 800802
2017.12.17 10:24:37.658 5: CUL_HM ug_eb_led16 protEvent:CMDs_processing... pending:0
2017.12.17 10:24:37.660 5: Starting notify loop for og_gang_led16, 1 event(s), first is CMDs_pending
2017.12.17 10:24:37.676 5: End notify loop for og_gang_led16
2017.12.17 10:24:37.677 5: CUL_HM og_gang_led16 protEvent:CMDs_pending pending:1
2017.12.17 10:24:37.678 5: Starting notify loop for og_gang_led16_Led_16, 1 event(s), first is set_led green
2017.12.17 10:24:37.694 5: End notify loop for og_gang_led16_Led_16
2017.12.17 10:24:37.694 3: CUL_HM set og_gang_led16_Led_16 led green
2017.12.17 10:24:37.695 5: HMLAN_Send:  HMLAN2 S:S63CA1C1E stat:  00 t:00000000 d:01 r:63CA1C1E m:19 A011 A24077 20F723 801002
2017.12.17 10:24:37.696 5: CUL_HM og_gang_led16 protEvent:CMDs_processing... pending:0
2017.12.17 10:24:37.696 5: Cmd: >IF (Value("Alarmanlage") eq "on") (
		{fhem('set AnMuLoBot message @@AnMuLo @@KaMuLo97 Entwarnung: '.AttrVal("$NAME","alias","$NAME").' wurde wieder geschlossen');
 		Log 1, "Entwarnung an alle: ".AttrVal("$NAME","alias","$NAME")." wurden wieder geschlossen"})
<
2017.12.17 10:24:37.697 5: Cmd: >{if(Value("Alarmanlage") eq "on"){fhem('set AnMuLoBot message @@AnMuLo @@KaMuLo97 Entwarnung: '.AttrVal("eg_bar_tk","alias","eg_bar_tk").' wurde wieder geschlossen'); 		Log 1, "Entwarnung an alle: ".AttrVal("eg_bar_tk","alias","eg_bar_tk")." wurden wieder geschlossen"}}<
2017.12.17 10:24:37.702 5: Triggering TerrassentuerLichtAus
2017.12.17 10:24:37.702 4: TerrassentuerLichtAus exec set terrasse_ls:FILTER=STATE!=off off
2017.12.17 10:24:37.703 5: Cmd: >set terrasse_ls:FILTER=STATE!=off off<
2017.12.17 10:24:37.710 5: Triggering TerrassentuerZu
2017.12.17 10:24:37.710 4: TerrassentuerZu exec set ug_eb_led16_Led_04,og_gang_led16_Led_04 led green
2017.12.17 10:24:37.710 5: Cmd: >set ug_eb_led16_Led_04,og_gang_led16_Led_04 led green<
2017.12.17 10:24:37.712 5: Starting notify loop for ug_eb_led16_Led_04, 1 event(s), first is set_led green
2017.12.17 10:24:37.720 5: End notify loop for ug_eb_led16_Led_04
2017.12.17 10:24:37.721 3: CUL_HM set ug_eb_led16_Led_04 led green
2017.12.17 10:24:37.722 5: Starting notify loop for og_gang_led16_Led_04, 1 event(s), first is set_led green
2017.12.17 10:24:37.730 5: End notify loop for og_gang_led16_Led_04
2017.12.17 10:24:37.730 3: CUL_HM set og_gang_led16_Led_04 led green
2017.12.17 10:24:37.731 5: Update structure 'WzFenster' to open because device eg_bar_tk has changed
2017.12.17 10:24:37.734 5: Triggering winOpen.CloseNotify
2017.12.17 10:24:37.734 4: winOpen.CloseNotify exec {winOpenStop($NAME)}
2017.12.17 10:24:37.734 5: Cmd: >{winOpenStop($NAME)}<
2017.12.17 10:24:37.735 5: Cmd: >delete eg_bar_tk_OpenTimer<
2017.12.17 10:24:37.736 5: Starting notify loop for global, 1 event(s), first is DELETED eg_bar_tk_OpenTimer
2017.12.17 10:24:37.749 4: Ignoring nachbar1
2017.12.17 10:24:37.749 4: Ignoring nachbar2
2017.12.17 10:24:37.749 4: Ignoring nachbar3
2017.12.17 10:24:37.749 4: Ignoring nachbar4
2017.12.17 10:24:37.749 4: Ignoring nachbar5
2017.12.17 10:24:37.749 4: Ignoring nachbar6
2017.12.17 10:24:37.750 4: Ignoring nachbar7
2017.12.17 10:24:37.761 4: Ignoring nachbar1
2017.12.17 10:24:37.761 4: Ignoring nachbar2
2017.12.17 10:24:37.761 4: Ignoring nachbar3
2017.12.17 10:24:37.761 4: Ignoring nachbar4
2017.12.17 10:24:37.762 4: Ignoring nachbar5
2017.12.17 10:24:37.762 4: Ignoring nachbar6
2017.12.17 10:24:37.762 4: Ignoring nachbar7
2017.12.17 10:24:37.767 4: Ignoring nachbar1
2017.12.17 10:24:37.767 4: Ignoring nachbar2
2017.12.17 10:24:37.767 4: Ignoring nachbar3
2017.12.17 10:24:37.767 4: Ignoring nachbar4
2017.12.17 10:24:37.767 4: Ignoring nachbar5
2017.12.17 10:24:37.768 4: Ignoring nachbar6
2017.12.17 10:24:37.768 4: Ignoring nachbar7
2017.12.17 10:24:37.771 5: End notify loop for global
2017.12.17 10:24:37.774 5: End notify loop for eg_bar_tk
2017.12.17 10:24:37.775 5: HMLAN/RAW: /EA24077,0000,059E9114,FF,FFBF,19A011A2407720F723801002

2017.12.17 10:24:37.775 5: HMLAN_Parse: HMLAN1 R:EA24077   stat:0000 t:059E9114 d:FF r:FFBF     m:19 A011 A24077 20F723 801002
2017.12.17 10:24:37.776 5: HMLAN1: dispatch A0C19A011A2407720F723801002::-65:HMLAN1
2017.12.17 10:24:37.778 5: HMLAN/RAW: /E343BD9,0000,77112D23,FF,FFAE,87A641343BD9A24077018000
EA24077,0000,77112D9D,FF,FFC3,878002A24077343BD900
EA24077,0000,77112DB6,FF,FFCA,19A011A2407720F723801002

2017.12.17 10:24:37.778 5: HMLAN_Parse: hmusb R:E343BD9   stat:0000 t:77112D23 d:FF r:FFAE     m:87 A641 343BD9 A24077 018000
2017.12.17 10:24:37.779 5: hmusb: dispatch A0C87A641343BD9A24077018000::-82:hmusb
2017.12.17 10:24:37.780 4: CUL_HM eg_bar_tk dupe: dont process
2017.12.17 10:24:37.780 5: HMLAN_Parse: hmusb R:EA24077   stat:0000 t:77112D9D d:FF r:FFC3     m:87 8002 A24077 343BD9 00
2017.12.17 10:24:37.781 5: hmusb: dispatch A0A878002A24077343BD900::-61:hmusb
2017.12.17 10:24:37.783 5: HMLAN_Parse: hmusb R:EA24077   stat:0000 t:77112DB6 d:FF r:FFCA     m:19 A011 A24077 20F723 801002
2017.12.17 10:24:37.784 5: hmusb: dispatch A0C19A011A2407720F723801002::-54:hmusb
2017.12.17 10:24:37.786 5: HMLAN/RAW: /E343BD9,0000,470295DC,FF,FFB6,87A641343BD9A24077018000
EA24077,0000,47029647,FF,FFCF,E0A011A240771EB52B800802

2017.12.17 10:24:37.786 5: HMLAN_Parse: HMLAN2 R:E343BD9   stat:0000 t:470295DC d:FF r:FFB6     m:87 A641 343BD9 A24077 018000
2017.12.17 10:24:37.787 5: HMLAN2: dispatch A0C87A641343BD9A24077018000::-74:HMLAN2
2017.12.17 10:24:37.788 4: CUL_HM eg_bar_tk dupe: dont process
2017.12.17 10:24:37.789 5: HMLAN_Parse: HMLAN2 R:EA24077   stat:0000 t:47029647 d:FF r:FFCF     m:E0 A011 A24077 1EB52B 800802
2017.12.17 10:24:37.789 5: HMLAN2: dispatch A0CE0A011A240771EB52B800802::-49:HMLAN2
2017.12.17 10:24:37.822 5: HMLAN/RAW: /E1EB52B,0000,470296C7,FF,FFB8,E080021EB52BA240770108020033000CA96A

2017.12.17 10:24:37.822 5: HMLAN_Parse: HMLAN2 R:E1EB52B   stat:0000 t:470296C7 d:FF r:FFB8     m:E0 8002 1EB52B A24077 0108020033000CA96A
2017.12.17 10:24:37.823 5: HMLAN2: dispatch A12E080021EB52BA240770108020033000CA96A::-72:HMLAN2
2017.12.17 10:24:37.826 5: CUL_HM ug_eb_led16 protEvent:CMDs_pending pending:1
2017.12.17 10:24:37.828 5: Starting notify loop for ug_eb_led16, 3 event(s), first is color: 000CA96A
2017.12.17 10:24:37.839 5: End notify loop for ug_eb_led16
2017.12.17 10:24:37.840 5: Starting notify loop for ug_eb_led16_Led_04, 2 event(s), first is color: red
2017.12.17 10:24:37.850 5: End notify loop for ug_eb_led16_Led_04
2017.12.17 10:24:37.850 5: Starting notify loop for ug_eb_led16_Led_08, 2 event(s), first is color: green
2017.12.17 10:24:37.861 5: End notify loop for ug_eb_led16_Led_08
2017.12.17 10:24:37.862 5: HMLAN/RAW: /E1EB52B,0000,059E916C,FF,FFB6,E080021EB52BA240770108020033000CA96A

2017.12.17 10:24:37.862 5: HMLAN_Parse: HMLAN1 R:E1EB52B   stat:0000 t:059E916C d:FF r:FFB6     m:E0 8002 1EB52B A24077 0108020033000CA96A
2017.12.17 10:24:37.862 5: HMLAN1: dispatch A12E080021EB52BA240770108020033000CA96A::-74:HMLAN1
2017.12.17 10:24:37.863 4: CUL_HM ug_eb_led16 dupe: dont process
2017.12.17 10:24:37.864 5: HMLAN/RAW: /R63CA1BF7,0001,77112E13,FF,FFC7,E080021EB52BA240770108020033000CA96A

2017.12.17 10:24:37.864 5: HMLAN_Parse: hmusb R:R63CA1BF7 stat:0001 t:77112E13 d:FF r:FFC7     m:E0 8002 1EB52B A24077 0108020033000CA96A
2017.12.17 10:24:37.865 5: hmusb: dispatch A12E080021EB52BA240770108020033000CA96A::-57:hmusb
2017.12.17 10:24:37.866 4: CUL_HM ug_eb_led16 dupe: dont process
2017.12.17 10:24:37.965 5: HMLAN_Send:  hmusb S:S63CA1CCB stat:  00 t:00000000 d:01 r:63CA1CCB m:E1 A011 A24077 1EB52B 800402
2017.12.17 10:24:37.967 5: CUL_HM ug_eb_led16 protEvent:CMDs_processing... pending:0
2017.12.17 10:24:37.968 5: HMLAN/RAW: /E20F723,0000,059E9197,FF,FFBA,19800220F723A240770110020015800CA96A

2017.12.17 10:24:37.968 5: HMLAN_Parse: HMLAN1 R:E20F723   stat:0000 t:059E9197 d:FF r:FFBA     m:19 8002 20F723 A24077 0110020015800CA96A
2017.12.17 10:24:37.969 5: HMLAN1: dispatch A1219800220F723A240770110020015800CA96A::-70:HMLAN1
2017.12.17 10:24:37.972 5: CUL_HM og_gang_led16 protEvent:CMDs_pending pending:1
2017.12.17 10:24:37.974 5: Starting notify loop for og_gang_led16, 3 event(s), first is color: 800CA96A
2017.12.17 10:24:37.985 5: End notify loop for og_gang_led16
2017.12.17 10:24:37.985 5: Starting notify loop for og_gang_led16_Led_04, 2 event(s), first is color: red
2017.12.17 10:24:37.996 5: End notify loop for og_gang_led16_Led_04
2017.12.17 10:24:37.996 5: Starting notify loop for og_gang_led16_Led_16, 2 event(s), first is color: green
2017.12.17 10:24:38.007 5: End notify loop for og_gang_led16_Led_16
2017.12.17 10:24:38.007 5: HMLAN/RAW: /E20F723,0000,77112E3A,FF,FFCC,19800220F723A240770110020015800CA96A

2017.12.17 10:24:38.007 5: HMLAN_Parse: hmusb R:E20F723   stat:0000 t:77112E3A d:FF r:FFCC     m:19 8002 20F723 A24077 0110020015800CA96A
2017.12.17 10:24:38.008 5: hmusb: dispatch A1219800220F723A240770110020015800CA96A::-52:hmusb
2017.12.17 10:24:38.009 4: CUL_HM og_gang_led16 dupe: dont process
2017.12.17 10:24:38.010 5: HMLAN/RAW: /R63CA1C1E,0001,470296F7,FF,FFEA,19800220F723A240770110020015800CA96A
EA24077,0000,4702977C,FF,FFCF,E1A011A240771EB52B800402

2017.12.17 10:24:38.010 5: HMLAN_Parse: HMLAN2 R:R63CA1C1E stat:0001 t:470296F7 d:FF r:FFEA     m:19 8002 20F723 A24077 0110020015800CA96A
2017.12.17 10:24:38.010 5: HMLAN2: dispatch A1219800220F723A240770110020015800CA96A::-22:HMLAN2
2017.12.17 10:24:38.011 4: CUL_HM og_gang_led16 dupe: dont process
2017.12.17 10:24:38.108 5: HMLAN_Send:  HMLAN2 S:S63CA1D5C stat:  00 t:00000000 d:01 r:63CA1D5C m:1A A011 A24077 20F723 800402
2017.12.17 10:24:38.109 5: CUL_HM og_gang_led16 protEvent:CMDs_processing... pending:0
2017.12.17 10:24:38.110 5: HMLAN_Parse: HMLAN2 R:EA24077   stat:0000 t:4702977C d:FF r:FFCF     m:E1 A011 A24077 1EB52B 800402
2017.12.17 10:24:38.110 5: HMLAN2: dispatch A0CE1A011A240771EB52B800402::-49:HMLAN2
2017.12.17 10:24:38.114 5: HMLAN/RAW: /EA24077,0000,059E9220,FF,FFC3,E1A011A240771EB52B800402

2017.12.17 10:24:38.114 5: HMLAN_Parse: HMLAN1 R:EA24077   stat:0000 t:059E9220 d:FF r:FFC3     m:E1 A011 A24077 1EB52B 800402
2017.12.17 10:24:38.114 5: HMLAN1: dispatch A0CE1A011A240771EB52B800402::-61:HMLAN1
2017.12.17 10:24:38.115 4: CUL_HM vccu dupe: dont process
2017.12.17 10:24:38.159 5: HMLAN/RAW: /EA24077,0000,77112F55,FF,FFCA,1AA011A2407720F723800402

2017.12.17 10:24:38.159 5: HMLAN_Parse: hmusb R:EA24077   stat:0000 t:77112F55 d:FF r:FFCA     m:1A A011 A24077 20F723 800402
2017.12.17 10:24:38.160 5: hmusb: dispatch A0C1AA011A2407720F723800402::-54:hmusb
2017.12.17 10:24:38.205 5: HMLAN/RAW: /EA24077,0000,059E92E9,FF,FFC3,E1A011A240771EB52B800402

2017.12.17 10:24:38.206 5: HMLAN_Parse: HMLAN1 R:EA24077   stat:0000 t:059E92E9 d:FF r:FFC3     m:E1 A011 A24077 1EB52B 800402
2017.12.17 10:24:38.206 5: HMLAN1: dispatch A0CE1A011A240771EB52B800402::-61:HMLAN1
2017.12.17 10:24:38.209 5: HMLAN/RAW: /EA24077,0000,47029844,FF,FFCF,E1A011A240771EB52B800402

2017.12.17 10:24:38.209 5: HMLAN_Parse: HMLAN2 R:EA24077   stat:0000 t:47029844 d:FF r:FFCF     m:E1 A011 A24077 1EB52B 800402
2017.12.17 10:24:38.209 5: HMLAN2: dispatch A0CE1A011A240771EB52B800402::-49:HMLAN2
2017.12.17 10:24:38.210 4: CUL_HM vccu dupe: dont process
2017.12.17 10:24:38.282 5: HMLAN/RAW: /R63CA1D5C,0001,47029897,FF,FFEA,1A800220F723A240770104020015800CA9AA

2017.12.17 10:24:38.283 5: HMLAN_Parse: HMLAN2 R:R63CA1D5C stat:0001 t:47029897 d:FF r:FFEA     m:1A 8002 20F723 A24077 0104020015800CA9AA
2017.12.17 10:24:38.283 5: HMLAN2: dispatch A121A800220F723A240770104020015800CA9AA::-22:HMLAN2
2017.12.17 10:24:38.286 5: CUL_HM og_gang_led16 protEvent:CMDs_done
2017.12.17 10:24:38.288 5: Starting notify loop for og_gang_led16, 3 event(s), first is color: 800CA9AA
2017.12.17 10:24:38.299 5: End notify loop for og_gang_led16
2017.12.17 10:24:38.299 5: Starting notify loop for og_gang_led16_Led_04, 2 event(s), first is color: green
2017.12.17 10:24:38.310 5: End notify loop for og_gang_led16_Led_04
2017.12.17 10:24:38.310 5: HMLAN/RAW: /E20F723,0000,059E9337,FF,FFBA,1A800220F723A240770104020015800CA9AA

2017.12.17 10:24:38.311 5: HMLAN_Parse: HMLAN1 R:E20F723   stat:0000 t:059E9337 d:FF r:FFBA     m:1A 8002 20F723 A24077 0104020015800CA9AA
2017.12.17 10:24:38.311 5: HMLAN1: dispatch A121A800220F723A240770104020015800CA9AA::-70:HMLAN1
2017.12.17 10:24:38.312 4: CUL_HM og_gang_led16 dupe: dont process
2017.12.17 10:24:38.313 5: HMLAN/RAW: /E20F723,0000,77112FD9,FF,FFCC,1A800220F723A240770104020015800CA9AA

2017.12.17 10:24:38.313 5: HMLAN_Parse: hmusb R:E20F723   stat:0000 t:77112FD9 d:FF r:FFCC     m:1A 8002 20F723 A24077 0104020015800CA9AA
2017.12.17 10:24:38.314 5: hmusb: dispatch A121A800220F723A240770104020015800CA9AA::-52:hmusb
2017.12.17 10:24:38.315 4: CUL_HM og_gang_led16 dupe: dont process
2017.12.17 10:24:38.334 5: HMLAN/RAW: /E1EB52B,0000,470298C4,FF,FFB8,E180021EB52BA240770104020033000CA9AA

2017.12.17 10:24:38.334 5: HMLAN_Parse: HMLAN2 R:E1EB52B   stat:0000 t:470298C4 d:FF r:FFB8     m:E1 8002 1EB52B A24077 0104020033000CA9AA
2017.12.17 10:24:38.335 5: HMLAN2: dispatch A12E180021EB52BA240770104020033000CA9AA::-72:HMLAN2
2017.12.17 10:24:38.338 5: CUL_HM ug_eb_led16 protEvent:CMDs_done
2017.12.17 10:24:38.339 5: Starting notify loop for ug_eb_led16, 3 event(s), first is color: 000CA9AA
2017.12.17 10:24:38.350 5: End notify loop for ug_eb_led16
2017.12.17 10:24:38.351 5: Starting notify loop for ug_eb_led16_Led_04, 2 event(s), first is color: green
2017.12.17 10:24:38.361 5: End notify loop for ug_eb_led16_Led_04
2017.12.17 10:24:38.362 5: HMLAN/RAW: /E1EB52B,0000,059E936A,FF,FFB5,E180021EB52BA240770104020033000CA9AA

2017.12.17 10:24:38.362 5: HMLAN_Parse: HMLAN1 R:E1EB52B   stat:0000 t:059E936A d:FF r:FFB5     m:E1 8002 1EB52B A24077 0104020033000CA9AA
2017.12.17 10:24:38.363 5: HMLAN1: dispatch A12E180021EB52BA240770104020033000CA9AA::-75:HMLAN1
2017.12.17 10:24:38.364 4: CUL_HM ug_eb_led16 dupe: dont process
2017.12.17 10:24:38.365 5: HMLAN/RAW: /R63CA1CCB,0001,77113010,FF,FFC7,E180021EB52BA240770104020033000CA9AA

2017.12.17 10:24:38.365 5: HMLAN_Parse: hmusb R:R63CA1CCB stat:0001 t:77113010 d:FF r:FFC7     m:E1 8002 1EB52B A24077 0104020033000CA9AA
2017.12.17 10:24:38.365 5: hmusb: dispatch A12E180021EB52BA240770104020033000CA9AA::-57:hmusb
2017.12.17 10:24:38.366 4: CUL_HM ug_eb_led16 dupe: dont process

eine vccu setze ich ein, da hatte ich dann in den IODev/IOgrp auch hmusb bzw. vccu:hmusb eingetragen, so wie es in den Internals unter LASTInputDev steht.
Hat leider auch nicht viel gebracht, ausser dass zumindest beim Öffnen wieder das grüne LED kommt, beim Schließen kurz drauf aber wieder rot.

Firmware: der hmusb hat 0.967, die beiden HMLAN aber 0.964... werde mal zusehen, dass ich die update.

Position des HMLAN1 bzw. hmusb ist nicht ideal, der HMLAN2 lag hinter einer Couch in der Ecke. Werde ich mal verändern.

AndyMu · 17 Dezember 2017, 19:22:37

Zitat von: AndyMu am 17 Dezember 2017, 16:54:43
Firmware: der hmusb hat 0.967, die beiden HMLAN aber 0.964... werde mal zusehen, dass ich die update.

Der eine HMLAN hat sich leider dem Update verweigert... in der Software werden beide gelistet, wenn ich dann bei dem im Wohnzimmer auf "firmware update" drücke, kommt was in der Richtung "Gerät geht in den bootloader", danach dann ein "update failure" und dann muss ich den Konfigurator erstmal ein paar Minuten vom Strom nehmen, damit er wieder funktioniert.

Pfriemler · 18 Dezember 2017, 13:09:26

Code Auswählen

   rssi_at_HMLAN1 cnt:159 lst:-81 min:-102 max:-78 avg:-87.91
   rssi_at_HMLAN2 min:-95 avg:-75.24 max:-63 cnt:161 lst:-74
   rssi_at_hmusb lst:-82 cnt:157 avg:-81.21 max:-72 min:-100

Das müssten aber mehr als ein paar Meter sein. Eine Couch ist da kaum hinderlich.
Stöpsle mal den HMLAN aus den Wohnzimmer mehr in PC-Nähe ein (etwa ein Switch allda) und versuch's nochmal ...

AndyMu · 19 Dezember 2017, 20:50:10

Hmmm... Du meinst jetzt den HMLAN netzwerktechnisch näher an den Rasp bringen?
Der HMLAN ist an nem managed switch, der dann an die Fritzbox und von dort aus über WLAN zum Rasp.

Pfriemler · 19 Dezember 2017, 22:30:57

Nein. Ich meinte netzwerktechnisch näher an das Gerät, von dem aus das Firmware Update für den HMLAN aus laufen soll.

AndyMu · 09 März 2018, 21:09:04

Leider bin ich mein Problem immer noch nicht los, inzwischen ist ein weiteres hinzugekommen: mein HM-TC-IT-WM-W-EU (Wandregler) verarbeitet Befehle zur Temperaturänderung tlw. erst viele Stunden später, komischerweise nie wenn ich ihn manuell aus FHEM verändere.
Dann ist ewig "CMDs_pending", tlw. >4h. Irgendwann setzt er dann...

Aber erstmal zurück zum Problem hier:
Wenn ich zwei meiner drei IODevs (HM USB, HMLAN1, HMLAN2) deaktiviere, dann gibt es (logischerweise) keine "dupe" Meldung.
Soweit ich es verstanden habe, ist es ein Laufzeitproblem für das ACK. Kann ich das irgendwie beeinflussen/synchronisieren?