Nur Text | Text mit Bildern

FHEM Forum

FHEM - Hausautomations-Systeme => Homematic => Thema gestartet von: AndyMu am 17 Dezember 2017, 10:35:33

Titel: Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: AndyMu am 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
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: Pfriemler am 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?
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: frank am 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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: frank am 17 Dezember 2017, 15:46:09
mir ist gerade noch dies eingefallen https://forum.fhem.de/index.php/topic,20776.msg430486.html#msg430486 (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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: Pfriemler am 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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: AndyMu am 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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: AndyMu am 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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: Pfriemler am 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 ...
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: AndyMu am 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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: Pfriemler am 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.
Titel: Antw:Duplikate im Log bei Türkontakt HM-SEC-RHS / LED leuchtet rot
Beitrag von: AndyMu am 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?
Nur Text | Text mit Bildern