keymatic Meldungen seltsam

juppzupp · 29 April 2021, 12:21:11

hi,

seit langem 2 keymatic im einsatz.
Seit eben wird mein Log mit folgenden Meldungen von einem der beiden geflutet:


2021.04.29 12:04:19 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:06:55 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:06:57 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:06:57 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:06:57 5: CUL_HM get HM_549DD0 ?
2021.04.29 12:11:52 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:03 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:11 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:11 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:11 5: CUL_HM get HM_549DD0 ?
2021.04.29 12:13:26 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:30 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:30 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:13:30 5: CUL_HM get HM_549DD0 ?
2021.04.29 12:13:40 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:14:16 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:14:19 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:14:19 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:14:19 5: CUL_HM get HM_549DD0 ?
2021.04.29 12:15:59 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:16:02 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:16:02 5: CUL_HM set HM_549DD0 ?
2021.04.29 12:16:02 5: CUL_HM get HM_549DD0 ?

ich steh auf'm Schlauch.
Keine config Änderungen, kein update o.Ä.

Was kann das sein?

DeeSPe · 29 April 2021, 12:35:47

Zitat von: juppzupp am 29 April 2021, 12:21:11
Seit eben wird mein Log mit folgenden Meldungen von einem der beiden geflutet:

Die eigentliche Frage ist: Wieso flutest Du überhaupt Dein Log mit "verbose 5"?

Gruß
Dan

juppzupp · 29 April 2021, 12:56:53

die Frage ist, warum das nur für dieses device erst seit heute Mittag so ist.

frank · 29 April 2021, 13:37:46

poste ein list vom aktor und "get hminfo configCheck".
im log gibt es keine weiteren hinweise?

juppzupp · 29 April 2021, 16:02:35

meine bessere hälfte hatte mir gesagt das sie 2mal abschliessen (was bedeutet RFID.....FHEM......HM) musste, um 10:30, ich konnte aber erst mittags gucken.
und das war auch überhaupt der grund zu schauen.

Code Auswählen

2021.04.29 10:31:57 5: CUL_HM HM_549DD0 protEvent:CMDs_done
2021.04.29 10:31:57 5: CUL_HM HM_549DD0 sent ACK:2
2021.04.29 10:32:10 3: CUL_HM set HM_549DD0 lock noArg
2021.04.29 10:32:10 5: CUL_HM HM_549DD0 protEvent:CMDs_pending pending:1
2021.04.29 10:32:10 5: CUL_HM HM_549DD0 protEvent:CMDs_processing... pending:0
2021.04.29 10:32:11 5: CUL_HM HM_549DD0 sent ACK:2
2021.04.29 10:32:15 4: CUL_HM_Resend: HM_549DD0 nr 2
2021.04.29 10:32:16 5: CUL_HM HM_549DD0 sent ACK:2
2021.04.29 10:32:21 4: CUL_HM_Resend: HM_549DD0 nr 3
2021.04.29 10:32:22 5: CUL_HM HM_549DD0 sent ACK:2
2021.04.29 10:32:26 4: CUL_HM_Resend: HM_549DD0 nr 4
2021.04.29 10:32:27 5: CUL_HM HM_549DD0 sent ACK:2
2021.04.29 10:32:30 5: CUL_HM HM_549DD0 protEvent:CMDs_done_Errors:1
2021.04.29 10:33:21 3: CUL_HM set HM_549DD0 lock noArg
2021.04.29 10:33:21 5: CUL_HM HM_549DD0 protEvent:CMDs_pending pending:1
2021.04.29 10:33:21 5: CUL_HM HM_549DD0 protEvent:CMDs_processing... pending:0
2021.04.29 10:33:21 5: CUL_HM HM_549DD0 protEvent:CMDs_done
2021.04.29 10:33:26 5: CUL_HM HM_549DD0 protEvent:CMDs_done
2021.04.29 10:33:26 5: CUL_HM HM_549DD0 sent ACK:2

dann bis 12.04 nix, und dann der "sturm" von meldungen die ich oben gepostet hatte.

list :

Code Auswählen

Internals:
   DEF        549DD0
   FUUID      5c7ff2ee-f33f-c0e9-9ba8-41e69f110cef8eac
   IODev      hmusb
   LASTInputDev hmusb
   MSGCNT     746
   NAME       HM_549DD0
   NOTIFYDEV  global
   NR         272
   NTFY_ORDER 50-HM_549DD0
   STATE      locked
   TYPE       CUL_HM
   chanNo     01
   hmusb_MSGCNT 746
   hmusb_RAWMSG R1D20F9C1,0021,6330C215,01,FFCF,028002549DD0CAFEFE0101000032AAF2B19F
   hmusb_RSSI -49
   hmusb_TIME 2021-04-29 12:16:11
   lastMsg    No:02 - t:02 s:549DD0 d:CAFEFE 0101000032AAF2B19F
   peerList   HM_54E874_lock,HM_54E874_unlock
   protCmdDel 1
   protEvt_AESCom-ok 125 last_at:2021-04-29 12:16:11
   protEvt_AESerrReject 5 last_at:2021-04-29 10:32:27
   protLastRcv 2021-04-29 12:16:11
   protRcv    471 last_at:2021-04-29 12:16:11
   protResnd  5 last_at:2021-04-29 10:32:26
   protResndFail 1 last_at:2021-04-29 10:32:30
   protSnd    482 last_at:2021-04-29 12:16:10
   protSndB   133 last_at:2021-04-29 12:16:10
   protState  CMDs_done
   rssi_at_hmusb cnt:617 min:-79 max:-41 avg:-47.97 lst:-49 
   rssi_hmusb cnt:126 min:-57 max:-44 avg:-49.04 lst:-50 
   Helper:
     DBLOG:
       aesCommToDev:
         logdb:
           TIME       1619691371.40851
           VALUE      ok
       battery:
         logdb:
           TIME       1617985431.95142
           VALUE      ok
       cfgState:
         logdb:
           TIME       1617985436.41906
           VALUE      ok
       commState:
         logdb:
           TIME       1619691371.40851
           VALUE      CMDs_done
       lock:
         logdb:
           TIME       1619685206.40061
           VALUE      locked
       powerOn:
         logdb:
           TIME       1617985431.95142
           VALUE      2021-04-09 18:23:51
       state:
         logdb:
           TIME       1619691371.40851
           VALUE      locked
       uncertain:
         logdb:
           TIME       1619685206.40061
           VALUE      no
   READINGS:
     2021-04-29 12:16:11   CommandAccepted yes
     2018-07-07 13:08:57   D-firmware      2.5
     2018-07-07 13:08:57   D-serialNr      OEQ0119491
     2021-04-09 18:23:53   PairedTo        0xCAFEFE
     2018-07-07 13:09:40   R-angelLocked   810.08100810081 deg
     2018-07-07 13:09:40   R-angelMax      885.088508850885 deg
     2018-07-07 13:09:40   R-angelOpen     210.02100210021 deg
     2018-07-07 13:09:39   R-pairCentral   0xCAFEFE
     2018-07-07 13:09:40   R-setupPosition 450.04500450045 deg
     2021-04-09 18:23:53   RegL_00.         00:00 02:01 03:19 0A:FE 0B:CA 0C:FE
     2021-04-09 18:23:54   RegL_01.         00:00 14:64 15:64 16:01 17:1E 18:0E 19:3B 1A:36 1F:00
     2021-04-09 18:23:56   RegL_03.HM_54E874_lock  00:00 03:00 04:32 05:64 07:FF 0B:66 83:00 84:32 85:64 87:FF 8B:66
     2021-04-09 18:23:55   RegL_03.HM_54E874_unlock  00:00 03:00 04:32 05:64 07:FF 0B:33 83:00 84:32 85:64 87:FF 8B:36
     2021-04-29 12:16:11   aesCommToDev    ok
     2021-04-29 12:16:11   aesKeyNbr       02
     2021-04-29 12:16:11   battery         ok
     2021-04-29 14:08:18   cfgState        ok
     2021-04-29 12:16:11   commState       CMDs_done
     2021-04-29 12:16:11   direction       none
     2021-04-29 12:16:11   error           none
     2021-04-29 12:16:11   lock            locked
     2021-04-09 18:23:54   peerList        HM_54E874_lock,HM_54E874_unlock
     2021-04-09 18:23:51   powerOn         2021-04-09 18:23:51
     2021-04-29 12:16:11   recentStateType ack
     2021-04-29 12:16:11   state           locked
     2021-04-29 12:16:11   uncertain       no
     2021-04-26 11:11:41   unknown         40
   helper:
     HM_CMDNR   2
     PONtest    0
     cSnd       11CAFEFE549DD0800100FF,11CAFEFE549DD0800100FF
     mId        0026
     peerFriend peerSens,peerVirt
     peerIDsRaw ,54E87401,54E87402,00000000
     peerIDsState complete
     peerOpt    3:keyMatic
     regLst     0,1,3p
     rxType     2
     supp_Pair_Rep 0
     cmds:
       TmplKey    HM_54E874_lock,HM_54E874_unlock:no:1616260450.28407
       TmplTs     1616260450.28407
       cmdKey     1:1:0::HM_549DD0:0026:01:HM_54E874_lock,HM_54E874_unlock
       cmdLst:
         assignHmKey noArg
         clear      [(readings|trigger|register|oldRegs|rssi|msgEvents|{msgErrors}|attack|all)]
         deviceRename -newName-
         eventL     -peer- -cond-
         eventS     -peer- -cond-
         fwUpdate   -filename- [-bootTime-]
         getConfig  noArg
         getDevInfo noArg
         getRegRaw  (List0|List1|List2|List3|List4|List5|List6) [-peerChn-]
         inhibit    [(on|{off})]
         lock       noArg
         open       [(-sec-|{0})]
         peerBulk   -peer1,peer2,...- [({set}|unset)]
         peerSmart  -peerOpt-
         press      [(long|{short})] [(-peer-|{self01})] [(-repCount-|{0})] [(-repDelay-|{0.25})]
         pressL     [(-peer-|{self01})]
         pressS     [(-peer-|{self01})]
         raw        -data- [...]
         regBulk    -list-.-peerChn- -addr1:data1- -addr2:data2-...
         regSet     [(prep|{exec})] -regName- -value- [-peerChn-]
         reset      noArg
         sign       [(on|{off})]
         statusRequest noArg
         tplDel     -tplDel-
         tplSet_0   -tplChan-
         tplSet_HM_54E874_lock -tplPeer-
         tplSet_HM_54E874_unlock -tplPeer-
         unlock     [(-sec-|{0})]
         unpair     noArg
       lst:
         condition  slider,0,1,255
         peer       Names,HM_549DD0
         peerOpt    CUL_HM_HM_ES_PMSw1_Pl_2E1A95_SenF,CUL_HM_HM_ES_PMSw1_Pl_2E1A95_SenI,CUL_HM_HM_ES_PMSw1_Pl_2E1A95_SenPwr,CUL_HM_HM_ES_PMSw1_Pl_2E1A95_SenU,CUL_HM_HM_ES_PMSw1_Pl_2E1AFF_SenF,CUL_HM_HM_ES_PMSw1_Pl_2E1AFF_SenI,CUL_HM_HM_ES_PMSw1_Pl_2E1AFF_SenPwr,CUL_HM_HM_ES_PMSw1_Pl_2E1AFF_SenU,CUL_HM_HM_MOD_Em_8_313178_Btn_01,CUL_HM_HM_MOD_Em_8_313178_Btn_02,CUL_HM_HM_MOD_Em_8_313178_Btn_03,CUL_HM_HM_MOD_Em_8_313178_Btn_04,CUL_HM_HM_MOD_Em_8_313178_Btn_05,CUL_HM_HM_MOD_Em_8_313178_Btn_06,CUL_HM_HM_MOD_Em_8_313178_Btn_07,CUL_HM_HM_MOD_Em_8_313178_Btn_08,CUL_HM_HM_SEC_SC_200651,CUL_HM_HM_SEC_SC_2253BC,CUL_HM_HM_SEC_SC_2_2AE3D6,CUL_HM_HM_SEC_SC_2_2AE625,CUL_HM_HM_SEC_SC_2_36C4B5,CUL_HM_HM_SEC_SC_2_37182B,HM_283B50_SenF,HM_283B50_SenI,HM_283B50_SenPwr,HM_283B50_SenU,HM_54E56B_light,HM_54E56B_lock,HM_54E56B_open,HM_54E56B_unlock,HM_54E874_light,HM_54E874_lock,HM_54E874_open,HM_54E874_unlock,HM_57C1F2_Btn_01,HM_57C1F2_Btn_02,HM_57C1F2_Motion,HM_57C1F7_Btn_01,HM_57C1F7_Btn_02,HM_57C1F7_Motion,HM_57D128_Btn_01,HM_57D128_Btn_02,HM_57D128_Motion,HM_57D135_Btn_01,HM_57D135_Btn_02,HM_57D135_Motion,HM_581310,HM_58131A,HM_581810,HM_5822DD,vccu_Btn1,vccu_Btn2
         tplChan    
         tplDel     
         tplPeer    
       rtrvLst:
         cmdList    [({short}|long)]
         deviceInfo [({short}|long)]
         list       [({normal}|full)]
         param      -param-
         reg        -addr- -list- [-peerChn-]
         regList    noArg
         regTable   noArg
         regVal     -addr- -list- [-peerChn-]
         saveConfig [-filename-]
         tplInfo    noArg
     expert:
       def        1
       det        0
       raw        1
       tpl        0
     io:
       newChn     +549DD0,00,01,00
       nextSend   1619691371.50426
       rxt        0
       vccu       vccu
       p:
         549DD0
         00
         01
         00
       prefIO:
         hmusb
     mRssi:
       mNo        02
       io:
         hmusb:
           -41
           -41
     peerIDsH:
       00000000   broadcast
       54E87401   HM_54E874_unlock
       54E87402   HM_54E874_lock
     prt:
       bErr       0
       sProc      0
     q:
       qReqConf   
       qReqStat   
     regCollect:
     role:
       chn        1
       dev        1
       prs        1
     rssi:
       at_hmusb:
         avg        -47.9789303079416
         cnt        617
         lst        -49
         max        -41
         min        -79
       hmusb:
         avg        -49.0476190476191
         cnt        126
         lst        -50
         max        -44
         min        -57
     shadowReg:
     tmpl:
Attributes:
   DbLogExclude aesKeyNbr,direction,recentStateType,unknown
   IODev      hmusb
   IOgrp      vccu:hmusb
   alias      Garagentürschloss
   autoReadReg 4_reqStatus
   comment    batteriewechsel 01feb2019,01nov2019, 
31 Aug 2020 gp gold/grün
09 Apr 2021 Varta industrial
   devStateIcon locked:secur_locked@00FE00 unlocked:secur_open@FF0000
   event-on-change-reading .*
   expert     defReg,rawReg
   firmware   2.5
   model      HM-SEC-KEY-S
   msgRepeat  3
   peerIDs    00000000,54E87401,54E87402
   room       CUL_HM,Garage,zSchließanlage
   serialNr   OEQ0119491
   subType    keyMatic
   verbose    5
   webCmd     lock:unlock:inhibit on:inhibit off

configcheck :

Code Auswählen

configCheck done:

 missing register list
    CUL_HM_HB_UW_Sen_THPL_I_E453C2:	RegL_00.
    CUL_HM_HM_MOD_Em_8_313178_Btn_01:	RegL_04.3E46B202
    CUL_HM_HM_MOD_Em_8_313178_Btn_02:	RegL_04.3E46B202
    CUL_HM_HM_SEC_SC_200651:	RegL_00.,RegL_01.
    HM_698044:	RegL_00.
    HM_698044_IEC_01:	RegL_01.
    HM_698044_IEC_02:	RegL_01.

 peer not defined
    CUL_HM_HM_MOD_Em_8_313178_Btn_01:	 id:3E46B202
    CUL_HM_HM_MOD_Em_8_313178_Btn_02:	 id:3E46B202

 PairedTo missing/unknown
    CUL_HM_HB_UW_Sen_THPL_I_E453C2:	
    CUL_HM_HM_SEC_SC_200651:	

 templist mismatch
    CUL_HM_HM_TC_IT_WM_W_EU_3226D3_Climate: file: ./tempList.cfg error:Can't open ./tempList.cfg: No such file or directory
    CUL_HM_HM_TC_IT_WM_W_EU_34B40A_Climate: file: ./tempList.cfg error:Can't open ./tempList.cfg: No such file or directory
    CUL_HM_HM_TC_IT_WM_W_EU_3A7C02_Climate: file: ./tempList.cfg error:Can't open ./tempList.cfg: No such file or directory

frank · 29 April 2021, 17:29:34

hier mal ein "sinnloser" ausschnitt aus meiner fhem.log, wo ich zum testen auch verbose 5 bei einem device gesetzt hatte. die "set ?" einträge sind scheinbar grundsätzlich normal im ablauf der cmd verarbeitung.
damit werden ja die verfügbaren befehle eines device abgefragt.

Code Auswählen

2021.04.15 15:10:29.797 5: CUL_HM DimPBU01 protEvent:CMDs_done, 1 -> 0, +266A86,00,00,00, 0
2021.04.15 15:10:29.798 5: CUL_HM DimPBU01 sent ACK:2
2021.04.15 15:10:29.824 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:29.827 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:29.896 0: HMLAN_Parse: hmlan1 R:E266A86   stat:0000 t:AE86B996 d:FF r:FFD1     m:6D A410 266A86 1ACE1F 060200004500
2021.04.15 15:10:29.898 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 15:10:29.901 0: HMUARTLGW hmuart1 recv: 01 0402, state 100
2021.04.15 15:10:29.902 0: HMUARTLGW hmuart1 Ack: 02 
2021.04.15 15:10:29.903 0: HMUARTLGW hmuart1 send: 00 08
2021.04.15 15:10:29.906 0: HMUARTLGW hmuart1 recv: 01 05 01 00 3E msg: 6D A4 10 266A86 1ACE1F 060200004500
2021.04.15 15:10:29.908 4: CUL_HM DimPBU01 dupe: dont process
2021.04.15 15:10:29.911 0: HMUARTLGW hmuart1 recv: 00 040248, state 98
2021.04.15 15:10:29.912 0: HMUARTLGW hmuart1 GetSet Ack: 02, state 98
2021.04.15 15:10:29.913 4: CUL_Parse: cul868 A 0A 6D 8002 1ACE1F 266A86 0067 -22.5
2021.04.15 15:10:29.921 0: HMLAN_Parse: hmlan1 R:E1ACE1F   stat:0000 t:AE86BA0F d:FF r:FFCD     m:6D 8002 1ACE1F 266A86 00
2021.04.15 15:10:30.667 3: CUL_HM set DimPBU01_Dim_V_02 statusRequest noArg
2021.04.15 15:10:30.687 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:30.690 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:30.710 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:30.713 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:30.715 5: CUL_HM DimPBU01 protEvent:CMDs_pending pending:1, 0 -> 2, +266A86,00,00,00, 0
2021.04.15 15:10:30.718 0: HMUARTLGW hmuart1 send: 01 02 00 00 00 msg: 6E A0 01 1ACE1F 266A86 030E
2021.04.15 15:10:30.740 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:30.743 5: CUL_HM set DimPBU01 ?
2021.04.15 15:10:30.745 5: CUL_HM DimPBU01 protEvent:CMDs_processing... pending:0, 2 -> 1, +266A86,00,00,00, 0

bei dir sieht mir das danach aus, dass die "set/get ?" einträge einfach 2 stunden zu spät eingetrudelt sind.

denn im log von 10:30 gibt es keine und später fehlt irgendwie die ursache dafür.

in deinem list gibt es einige timestamps mit 12:16:10/11, also etwas später als der letzte "set ?".
war da noch was, was du bisher verschwiegen hast? oder sind das die nachwirkungen auf das verspätete eintrudeln der "set ?"?

ist dein log aus der datei fhem.log? oder eine datenbank, oder was auch immer?

auf das erste abschliessen hat die keymatic nicht reagiert, obwohl es 3 wiederholungen gab.
eventuell ein zusammenhang? wurden die funkmessages dabei eventuell gar nicht gesendet?

ich würde erst mal die configcheck probleme beseitigen.
dann mal erneut einen befehl probieren, ob die "set ?" einträge nun im verbund mit den anderen einträgen kommen.

juppzupp · 29 April 2021, 19:23:37

frank, erstmal danke das du dir zeit nimmst.

ich wüsste nicht, was du mit "verschweigen" meinst. das system läuft seit jahren, seit wochen kein update, auch keine baulichen änderungen.
um 12:16:10 habe ich manuell einen lock geschickt, um zu schauen ob es überhaupt reagiert. mehr nicht.

oben alles aus fhem.log.

wenn der log (z.b.) sagt "CUL_HM_Resend: HM_549DD0 nr 2"...wie kann ich raus finden ob gesendet wurde ? das einzige was ich ja sagen kann, ist das nicht empfangen wurde.

die configcheck "probleme" schieb ich auch seit jahren vor mir her, weil die mich eigentlich nicht wirklich stören.

danke !

juppzupp · 30 April 2021, 09:38:54

so sieht es heute aus, viel ruhiger.
ich hatte gestern "sicherheitshalber" noch einen "set hmusb restart" gemacht.

Code Auswählen

2021.04.30 00:00:44 3: TelegramBot_Callback teleBot: Digest: Number of poll failures on 2021-04-29 is :7:
2021.04.30 00:30:00 3: CUL_HM set HM_549EF3 lock noArg
2021.04.30 00:30:00 5: CUL_HM HM_549EF3 protEvent:CMDs_pending pending:1
2021.04.30 00:30:00 5: CUL_HM HM_549EF3 protEvent:CMDs_processing... pending:0
2021.04.30 00:30:00 5: CUL_HM HM_549EF3 protEvent:CMDs_done
2021.04.30 00:30:07 5: CUL_HM HM_549EF3 protEvent:CMDs_done
2021.04.30 00:30:07 5: CUL_HM HM_549EF3 sent ACK:2
2021.04.30 00:46:34 1: PERL WARNING: Smartmatch is experimental at (eval 3838079) line 1.
2021.04.30 00:46:34 3: eval: my $EVENT=   $evalSpecials->{'%EVENT'};my $EVTPART0=   $evalSpecials->{'%EVTPART0'};my $NAME=   $evalSpecials->{'%NAME'};my $SELF=   $evalSpecials->{'%SELF'};my $TYPE=   $evalSpecials->{'%TYPE'};{ if (($hour ~~ [22..23]) || ($hour ~~ [0..4])) { fhem("set HUEDevice25 pct 5 ; set HUEDevice25 on-for-timer 240 ; set HUEBridge scene w8qRTdjWrJ7Qiws ; set HUEDevice13 on-for-timer 120 ;  set esp8266_2 sleep") } }
2021.04.30 00:46:34 3: MQTT2_DEVICE set MQTT2_DVES_821DB1 p2 off
2021.04.30 03:37:47 2: HUEBridge: http request failed: 192.168.0.47: Connection refused (111)
2021.04.30 04:19:39 3: ptv_ambicontrol: Read callback: Error: connect to http://192.168.0.113:1925 timed out
2021.04.30 06:50:00 3: CUL_HM set HM_549EF3 unlock 0
2021.04.30 06:50:00 5: CUL_HM HM_549EF3 protEvent:CMDs_pending pending:1
2021.04.30 06:50:00 5: CUL_HM HM_549EF3 protEvent:CMDs_processing... pending:0
2021.04.30 06:50:00 5: CUL_HM HM_549EF3 protEvent:CMDs_done
2021.04.30 06:50:06 5: CUL_HM HM_549EF3 protEvent:CMDs_done
2021.04.30 06:50:06 5: CUL_HM HM_549EF3 sent ACK:2
2021.04.30 07:30:00 1: PERL WARNING: Argument "" isn't numeric in numeric gt (>) at (eval 3863116) line 1.
2021.04.30 07:30:00 3: eval: di_dwd_warnung: warning in condition c01
2021.04.30 09:17:47 3: CUL_HM set CUL_HM_HM_ES_PMSw1_Pl_2E1A95_Sw on noArg
2021.04.30 09:33:47 5: CUL_HM HM_549DD0 protEvent:CMDs_done
2021.04.30 09:33:47 5: CUL_HM HM_549DD0 sent ACK:2
2021.04.30 09:34:00 3: CUL_HM set HM_549DD0 lock noArg
2021.04.30 09:34:00 5: CUL_HM HM_549DD0 protEvent:CMDs_pending pending:1
2021.04.30 09:34:00 5: CUL_HM HM_549DD0 protEvent:CMDs_processing... pending:0
2021.04.30 09:34:01 5: CUL_HM HM_549DD0 protEvent:CMDs_done
2021.04.30 09:34:05 5: CUL_HM HM_549DD0 protEvent:CMDs_done
2021.04.30 09:34:05 5: CUL_HM HM_549DD0 sent ACK:2

Otto123 · 30 April 2021, 09:58:24

Hi,

nach

Code Auswählen

deleteattr HM_549DD0 verboseund damit Standard Level 3 wird es noch ruhiger

Gruß Otto

juppzupp · 30 April 2021, 10:03:02

ja, das ist klar.
ich suche aber nach dem grund/einer erklärung warum das schloss nicht geschaltet hat, und warum der "burst" an "?" im log war

frank · 30 April 2021, 11:10:40

die "set ?" sind ja nun nichts "böses", sondern werden bei bestimmten funktionen ggfs erzeugt.
eventuell durch automatische checks von cul_hm/hminfo? hat mich noch nicht wirklich interessiert.

so richtig verstehe ich aber nun nicht deine herangehensweise an "probleme".
ein paar auffällige "set ?" im log, die mit verbose 5 eigentlich nie zu sehen sind, veranlassen dich zum handeln.

andererseits kannst du jahrelang ruhig schlafen, wenn hminfo configcheck echte probleme erkennt und diese sogar explizit anzeigt. vermutlich sollte hminfo die fehler besser nach fhem.log schreiben, um sich gehöhr zu verschaffen.

meine erfahrung:
je mehr fehler in hminfo existieren, desto grösser die wahrscheinlichkeit, dass es irgendwo und irgendwann im system "harkt".

für einen schnellen überblick empfehle ich HMinfoTools.js.

ob ein io wirklich funkt, sieht man eigentlich nur durch ein 2. io, dass die msgs empfängt.
besser als verbose 5 ist das sniffen der raw msgs. siehe wiki. lässt sich auch auf einzelne devices begrenzen.

juppzupp · 30 April 2021, 11:29:50

zum handeln veranlasst haben mich nicht die "?", sondern das das schloss nicht reagiert hat. das erste mal seit jahren.

configcheck berichtet von "problemen" die keine sind, und auch mit dem keymatic nix zu tun haben. oder übersehe ich was ?