FHEM Forum

Moin!
Irgendwie bin ich gerade irritiert - in 2017 habe ich die AES Konfiguration an der VCCU vorgenommen; mein Changelog sagt:
035 2017-08-07 AES Konfiguration
AES Lib installieren
- 'sudo apt-get install libcrypt-rijndael-perl'
AES Key auf VCCU
- 'attr VCCU hmKey <siehe Password Safe>'


Gestern ist mir aufgefallen, ich sehe den AES Key nicht (mehr?) an der VCCU:
Internals:
   DEF        DBDBDB
   FUUID      5c433c7d-f33f-739f-d0d0-d6046a89eaa29167
   HMUSB_MSGCNT 46446
   HMUSB_RAWMSG E2C2178,0000,6078E7CE,FF,FFAE,65A6102C217842F65706013600
   HMUSB_RSSI -82
   HMUSB_TIME 2021-10-29 09:22:07
   HmLGWEG_MSGCNT 65671
   HmLGWEG_RAWMSG 0500005565A6102C217842F65706013600
   HmLGWEG_RSSI -85
   HmLGWEG_TIME 2021-10-29 09:22:07
   HmLGWKG_MSGCNT 37807
   HmLGWKG_RAWMSG 0500004C318002DBDBDB49774100
   HmLGWKG_RSSI -76
   HmLGWKG_TIME 2021-10-29 09:20:56
   HmLGWOG_MSGCNT 43440
   HmLGWOG_RAWMSG 0500005165A0032C217842F657491FB8097993B33F0DF858B99F64E37E
   HmLGWOG_RSSI -81
   HmLGWOG_TIME 2021-10-29 09:22:07
   IODev      HMUSB
   LASTInputDev HmLGWOG
   MSGCNT     193364
   NAME       VCCU
   NR         67
   NTFY_ORDER 48-VCCU
   STATE      HMUSB:UAS,HmLGWEG:UAS,HmLGWKG:UAS,HmLGWOG:UAS,
   TYPE       CUL_HM
   assignedIOs HMUSB,HmLGWEG,HmLGWKG,HmLGWOG
   channel_01 VCCU_Btn1
   channel_02 VCCU_Btn2
   channel_03 VCCU_Btn3
   channel_04 VCCU_Btn4
   channel_05 VCCU_Btn5
   channel_06 VCCU_Btn6
   channel_07 VCCU_Btn7
   channel_08 VCCU_Btn8
   channel_09 VCCU_Btn9
   channel_0A VCCU_Btn10
   disableNotifyFn 1
   lastMsg    No:31 - t:02 s:DBDBDB d:497741 00
   protLastRcv 2021-10-29 09:20:56
   protRcv    17832 last_at:2021-10-29 09:20:56
   protRcvB   6753 last_at:2021-10-29 09:09:05
   rssi_at_HMUSB cnt:28498 min:-98 max:-51 avg:-61.17 lst:-59
   rssi_at_HmLGWEG cnt:37842 min:-100 max:-45 avg:-67.29 lst:-78
   rssi_at_HmLGWKG cnt:35850 min:-90 max:-64 avg:-74.7 lst:-76
   rssi_at_HmLGWOG cnt:13137 min:-84 max:-47 avg:-57.05 lst:-51
   READINGS:
     2021-10-29 09:20:56   CommandAccepted yes
     2021-10-18 15:04:54   IODev           HMUSB
     2021-10-18 15:05:25   IOopen          0
     2021-10-29 08:00:43   aesKeyNbr       00
     2021-10-29 08:00:08   aesReqTo        Jalousie.EG.Windfang
     2021-01-11 12:37:15   cfgState        ok
     2021-08-29 13:38:28   commState       CMDs_done
     2021-10-16 18:55:42   hmPair          name:HM_96E9E9 SN:QCU9844438 model:HB-GEN-SENS
     2021-10-18 15:05:25   state           HMUSB:UAS,HmLGWEG:UAS,HmLGWKG:UAS,HmLGWOG:UAS,
     2020-09-10 20:00:39   unknown_0C0120  received
     2021-02-03 14:18:26   unknown_14B03E  received
     2019-02-10 13:23:54   unknown_1EA209  received
     2019-09-08 20:06:05   unknown_1EA212  received
     2019-09-08 18:17:56   unknown_1EA216  received
     2021-10-29 09:20:25   unknown_246977  received
     2018-10-07 12:49:36   unknown_274EE8  received
     2018-11-24 15:29:51   unknown_27D86F  received
     2019-06-11 17:06:38   unknown_2842B4  received
     2021-10-29 09:20:25   unknown_28CA3C  received
     2021-01-17 00:02:35   unknown_28CB3D  received
     2020-08-07 14:09:16   unknown_298053  received
     2020-08-07 14:15:52   unknown_298364  received
     2021-10-29 09:22:07   unknown_2C2178  received
     2018-03-27 04:15:12   unknown_2E2378  received
     2021-02-03 13:57:16   unknown_30C30B  received
     2020-08-07 13:51:41   unknown_33228E  received
     2020-03-23 22:20:56   unknown_39D518  received
     2021-10-29 08:09:28   unknown_3B4181  received
     2021-10-26 03:56:11   unknown_3BC63A  received
     2020-02-25 12:00:00   unknown_3F1475  received
     2021-10-29 09:16:39   unknown_3F14F5  received
     2021-10-29 09:08:22   unknown_3F1511  received
     2018-09-21 07:40:36   unknown_3F1517  received
     2021-10-29 09:03:31   unknown_3F1527  received
     2021-10-29 08:44:57   unknown_3F1552  received
     2019-06-13 19:34:54   unknown_400CEB  received
     2021-10-29 09:10:17   unknown_400EE9  received
     2021-10-28 19:33:06   unknown_406833  received
     2018-11-24 15:17:55   unknown_41BC3E  received
     2018-11-24 15:18:41   unknown_41BD3E  received
     2018-11-24 15:18:16   unknown_41BE3E  received
     2019-01-05 13:05:31   unknown_42DB2D  received
     2021-09-19 15:55:42   unknown_42F657  received
     2018-12-13 06:33:28   unknown_474F92  received
     2018-11-24 14:56:46   unknown_47F80F  received
     2021-09-06 01:43:54   unknown_4B4BDB  received
     2018-04-04 13:00:59   unknown_4D2065  received
     2021-08-14 12:49:54   unknown_4F2265  received
     2021-09-21 20:20:09   unknown_50D15C  received
     2018-11-24 14:56:46   unknown_51D5BE  received
     2021-10-29 09:15:54   unknown_529025  received
     2021-05-23 20:32:47   unknown_5291A4  received
     2020-10-18 10:37:40   unknown_563825  received
     2021-10-12 11:37:03   unknown_568C35  received
     2018-09-22 14:35:15   unknown_5C3120  received
     2021-04-18 18:13:19   unknown_5C312A  received
     2018-06-22 17:39:33   unknown_5F1368  received
     2019-01-19 10:36:11   unknown_602CB1  received
     2018-11-24 15:01:36   unknown_6170BE  received
     2018-11-24 15:01:40   unknown_6171BE  received
     2019-03-23 12:15:59   unknown_61903E  received
     2018-11-24 14:58:03   unknown_61A0DE  received
     2018-11-24 15:31:08   unknown_61B03E  received
     2018-11-24 14:56:36   unknown_61B23E  received
     2018-11-24 14:59:29   unknown_61B33E  received
     2018-09-22 15:02:31   unknown_68A35E  received
     2021-01-11 11:16:17   unknown_68A4AF  received
     2018-11-24 14:58:03   unknown_6A9DA2  received
     2020-07-09 10:49:11   unknown_6B7411  received
     2018-12-10 14:48:23   unknown_6FB5F1  received
     2021-06-24 07:18:04   unknown_6FCC0E  received
     2020-12-14 13:06:49   unknown_6FFA89  received
     2020-12-14 13:28:16   unknown_6FFB2F  received
     2019-11-24 10:40:43   unknown_71276F  received
     2018-11-24 15:02:07   unknown_71279E  received
     2018-11-24 14:56:35   unknown_788FB0  received
     2018-11-24 14:59:29   unknown_798EB1  received
     2018-11-24 15:31:08   unknown_7A8DB2  received
     2018-11-24 15:01:10   unknown_812D9E  received
     2018-11-24 15:01:17   unknown_812E9E  received
     2018-11-24 15:01:23   unknown_812F9E  received
     2018-11-24 14:58:22   unknown_81EF9E  received
     2018-11-24 14:58:22   unknown_8DB255  received
     2018-11-24 14:59:57   unknown_90B748  received
     2018-11-24 15:29:51   unknown_91B5FE  received
     2018-11-24 15:17:50   unknown_91B649  received
     2018-11-24 14:59:34   unknown_91D8FE  received
     2018-11-24 14:59:44   unknown_91D9FE  received
     2018-11-24 14:59:57   unknown_91DAFE  received
     2018-11-24 15:17:50   unknown_91DBFE  received
     2018-11-24 14:59:34   unknown_92B54A  received
     2021-02-03 14:10:18   unknown_936016  received
     2021-03-03 06:02:46   unknown_939125  received
     2018-11-24 14:59:44   unknown_93B44B  received
     2021-10-16 18:54:29   unknown_96E9E9  received
     2019-03-23 12:15:59   unknown_9AAD52  received
     2018-11-24 15:18:16   unknown_9CA3A4  received
     2018-11-24 15:17:55   unknown_9EA1A6  received
     2018-11-24 15:18:41   unknown_9FA0A7  received
     2019-01-05 13:29:38   unknown_A24B19  received
     2021-08-28 12:43:15   unknown_A26B21  received
     2019-01-19 10:43:08   unknown_AEFC1E  received
     2021-10-16 18:31:08   unknown_B10A59  received
     2021-09-26 02:38:00   unknown_B1D9E7  received
     2018-11-24 15:01:36   unknown_BA4DF2  received
     2018-11-24 15:01:40   unknown_BB4CF3  received
     2021-06-06 04:26:10   unknown_CA88FC  received
     2018-11-24 15:01:17   unknown_CC7394  received
     2018-11-24 15:01:23   unknown_CD7295  received
     2018-11-24 15:01:09   unknown_CF7097  received
     2021-03-23 14:40:22   unknown_CFC4C6  received
     2018-11-24 15:02:07   unknown_D56A9D  received
     2019-04-21 09:01:09   unknown_F11234  received
     2018-09-20 19:26:46   unknown_F3DC09  received
     2021-06-05 17:11:02   unknown_FBBBDB  received
     2018-11-30 18:19:12   unknown_FFFFFF  received
   helper:
     HM_CMDNR   49
     PONtest    1
     lastMsgTm  1635492056.96813
     mId        FFF0
     peerFriend -
     peerOpt    -:virtual
     regLst     
     rxType     1
     supp_Pair_Rep 0
     ack:
     cmds:
       TmplKey    :no:1634562307.63494
       TmplTs     1634562307.63494
       cmdKey     0:1:1::VCCU:FFF0:00:
       cmdLst:
         assignIO   -IO- [({set}|unset)]
         clear      [(readings|rssi|msgEvents|attack|{msgErrors}|unknownDev)]
         defIgnUnknown noArg
         hmPairForSec [-sec-]
         hmPairSerial -serial-
         update     noArg
         virtual    [(1..50;1|{1})]
       lst:
         condition  slider,0,1,255
         peer       
         peerOpt   
         tplChan   
         tplDel     
         tplPeer   
       rtrvLst:
         cmdList    [({short}|long)]
         deviceInfo [({short}|long)]
         list       [({normal}|full)]
         listDevice noArg
         param      -param-
     expert:
       def        1
       det        0
       raw        0
       tpl        0
     io:
       nextSend   1635492057.05453
       vccu       VCCU
       prefIO:
     mRssi:
       mNo        31
       io:
         HMUSB:
           -53
           -53
         HmLGWEG:
           -78
           -78
         HmLGWKG:
           -76
           -76
         HmLGWOG:
           -51
     peerIDsH:
     prt:
       bErr       0
       sProc      0
       rspWait:
     q:
       qReqConf   
       qReqStat   
     role:
       dev        1
       vrt        1
     rssi:
       at_HMUSB:
         avg        -61.1768545161065
         cnt        28498
         lst        -59
         max        -51
         min        -98
       at_HmLGWEG:
         avg        -67.2958088895939
         cnt        37842
         lst        -78
         max        -45
         min        -100
       at_HmLGWKG:
         avg        -74.7087029288702
         cnt        35850
         lst        -76
         max        -64
         min        -90
       at_HmLGWOG:
         avg        -57.0507726269314
         cnt        13137
         lst        -51
         max        -47
         min        -84
     tmpl:
Attributes:
   icon       cul_usb
   webCmd     virtual:update


... und ein Attribut 'hmKey' an der VCCU ist nicht mehr verfügbar/möglich...
VCCU: unknown attribute hmKey. Type 'attr VCCU ?' for a detailed list.

... sondern nur noch an den einzelnen zugeordneten IOs (3*HMUARTLGW & 1*HMLAN).
Habe ich hier eine Änderung verpasst?

Mein letztes Update war am 12.10.:
Latest Revision: 25065

File                       Rev   Last Change

fhem.pl                    25039 2021-10-01 16:21:46Z rudolfkoenig
10_CUL_HM.pm               25059 2021-10-10 07:50:22Z martinp876
00_HMLAN.pm                18152 2019-01-05 23:18:38Z martinp876
00_HMUARTLGW.pm            18838 2019-03-09 20:40:14Z mgernoth


Kann das jemand nachvollziehen/bestätigen?
Gruß Peter

Hallo Peter,

Deine VCCU ist quasi Keine (mehr?)! Da fehlen alle Attribute.
Was daran Schuld ist kann ich nicht sagen. Wenn Du vor kurzem ein Update gemacht hast, kann es daran liegen. Dann empfehle ich ein restore ;)

Ansonsten: hier gibt es eine Zusammenfassung der Probleme mit Lösung https://forum.fhem.de/index.php/topic,123436.0.html

Gruß Otto

Hallo Otto,

ja klar - nicht nur fehlen die Attribute - ich kann sie ja noch nicht einmal mehr setzen!
Was muss ich an Modulen zurück spielen, damit das nicht wieder passiert?

Diese beiden außer der FHEM cfg hier?
10_CUL_HM.pm               25059 2021-10-10 07:50:22Z martinp876
98_HMinfo.pm               25060 2021-10-10 07:51:26Z martinp876

Gruß Peter

Es gibt drei Module die irgendwie zusammengehören:
10_CUL_HM.pm     24449 2021-05-16 09:03:48Z martinp876
98_HMinfo.pm     24321 2021-04-24 08:07:16Z martinp876
HMConfig.pm      24773 2021-07-18 18:18:13Z martinp876

Die funktionieren bei mir. (Ich bin mir nicht ganz sicher, ob ich im Sommer nicht schon mal Patches von Beta-User eingespielt habe) Aber spiel deine alten funktionierenden zurück.
Mit dem FHEM restore Befehl kannst Du umgehen? Tipps https://wiki.fhem.de/wiki/Update#R.C3.BCcksichern_beim_Update_.C3.BCberschriebener_Dateien

Alternativ hilfst Du Beta-user und probierst seine korrigierten Versionen aus dem verlinktem Thread.

Damit dann erstmal nichts wieder passiert beim Update solltest Du sowas setzen:
attr global exclude_from_update 10_CUL_HM.pm 98_HMinfo.pm 00_HMUARTLGW.pm 00_HMLAN.pm HMConfig.pm


Als Hilfestellung für andere noch: Ich würde auch gerne verstehen, wie dieses Problem entsteht (das hier ist #2). Kannst du mal die (bzgl. AES-Keys anonymisierte) lezte funktionierende Konfiguration der VCCU zeigen (ausnahmsweise ein cfg-Auszug).

Ergänzend noch: Eigentlich hätte ich vermutet, dass sowas nur auftritt, wenn man eine HMLAN-only Installation hat. Das scheint hier aber nicht der Fall gewesen zu sein. Falls du nicht "rückwärts" gehen willst: Bitte unbedingt auch HMLAN auf den "patches"-Stand bringen, sonst erkennen aktuelle Versionen von CUL_HM den nicht als zulässiges IO an...

@Otto123
Danke, Restore auf Stand 11.10. ist erfolgt; folgende Versionen laufen zufriedenstellend:
10_CUL_HM.pm               24449 2021-05-16 09:03:48Z martinp876
98_HMinfo.pm               24824 2021-08-03 18:29:34Z martinp876
HMConfig.pm                24773 2021-07-18 18:18:13Z martinp876


@Beta-User
Hier natürlich gern die Info's:
define VCCU CUL_HM DBDBDB
setuuid VCCU 5c433c7d-f33f-739f-d0d0-d6046a89eaa29167
attr VCCU .mId FFF0
attr VCCU IODev HMUSB
attr VCCU IOList HMUSB,HmLGWKG,HmLGWOG,HmLGWEG
attr VCCU IOgrp VCCU
attr VCCU hmKey 01:#-#-#-#-#-#
attr VCCU icon cul_usb
attr VCCU model CCU-FHEM
attr VCCU subType virtual
attr VCCU webCmd virtual:update

Gruß Peter

Danke.
Da "nur" TYPE=HMLAN hier wohl ausscheiden dürfte, ist es vermutlich "heiteres Würfeln mit Perl-Hashes" gewesen, das zu diesem Problem geführt hat...
(@Otto123 und andere Multiplikatoren: der Punkt dürfte mit der Folgeversion schon abgeräumt gewesen sein).

@PSI69: Vermutlich fährst du mit einem Gesamtupdate auf den "Patches II"-Stand (incl. meiner neuesten CUL_HM-Fassung von heute morgen und dem verlinkten Patch für HMUARTLGW) besser wie mit den alten Fassungen. Sobald jemand "Entwarnung" zu der betr. CUL_HM gibt, würde ich eine Wochendenaktion anraten, den Rest (HMLAN, HMUARTGW, HMinfo) auch schon ohne Entwarnung...

Zitat von: Beta-User am 29 Oktober 2021, 12:13:50
@PSI69: Vermutlich fährst du mit einem Gesamtupdate auf den "Patches II"-Stand (incl. meiner neuesten CUL_HM-Fassung von heute morgen und dem verlinkten Patch für HMUARTLGW) besser wie mit den alten Fassungen. Sobald jemand "Entwarnung" zu der betr. CUL_HM gibt, würde ich eine Wochendenaktion anraten, den Rest (HMLAN, HMUARTGW, HMinfo) auch schon ohne Entwarnung...

Hm, da ich die ganze Zeit mit diesem (jetzt wiederhergestelltem) Stand keine Probleme hatte /erkennen konnte, belasse ich das lieber so, bis alles bezüglich HM passt und wieder per Update verteilt wird. Für große (=zeitfressende) Versuche habe ich gerade keine selbige.

Danke noch einmal!
Peter

Zitat von: PSI69 am 29 Oktober 2021, 13:00:06
Hm, da ich die ganze Zeit mit diesem (jetzt wiederhergestelltem) Stand keine Probleme hatte /erkennen konnte, belasse ich das lieber so, bis alles bezüglich HM passt und wieder per Update verteilt wird. Für große (=zeitfressende) Versuche habe ich gerade keine selbige.

Danke noch einmal!
Peter

..verständlich...

Kleine Anmerkung: Leider hat das letzte Update in dem Bereich (rev. 25091) nur einen Teil der Probleme gelöst, du solltest also auf alle Fälle absichern, dass alle Problemkreise wirklich gelöst sind, bevor du dann nochmal unnötig Zeit verlierst, und es kann durchaus sein, dass martinp876 nicht unbedingt gleich alles mit in das update reinnimmt, v.a. dann, wenn er nicht die mehrfach bestätigte Rückmeldung hat, dass alles soweit paßt.

Falls also jemand etwas mehr Zeit als "keine" hat, ist es in unser aller Sinn, in dem nunmehr m.E. relativ "gefahrlosen" weiteren Vorabtesten mitzumachen. (Danke nochmal v.a. an frank für das wirklich vorbildliche Engagement!)

Zitat von: Beta-User am 29 Oktober 2021, 13:44:12
Falls also jemand etwas mehr Zeit als "keine" hat, ...

Ich habe mir am am WE sowieso Zeit geplant, um meinen letzten noch verbliebenen ESP HMUART durch eine entsprechende LAN-TCP232-Variante zu ersetzen.

Da kann ich dann auch gleich deine/eure Patches aus dem anderen Thread (https://forum.fhem.de/index.php/topic,123436.0.html) bei mir installieren.
Geht dann direkt in meine (HMLAN-freie) Produktivumgebung (Leben am Limit!  8) )

Aktuell bin ich auf update-Stand 25088 (Ist wenn ich es richtig verstanden habe noch mehr am Limit!  ;D)

gb#

Zitat von: Benni am 29 Oktober 2021, 16:43:30
Ich habe mir am am WE sowieso Zeit geplant, um meinen letzten noch verbliebenen ESP HMUART durch eine entsprechende LAN-TCP232-Variante zu ersetzen.

Da kann ich dann auch gleich deine/eure Patches aus dem anderen Thread (http://"https://forum.fhem.de/index.php/topic,123436.0.html") bei mir installieren.
Geht dann direkt in meine (HMLAN-freie) Produktivumgebung (Leben am Limit!  8) )

frank war schneller :P und hat HMLAN ;D .

Trotzdem natürlich: Viel Erfolg, gutes Gelingen!

Zitat
Aktuell bin ich auf update-Stand 25088 (Ist wenn ich es richtig verstanden habe noch mehr am Limit!  ;D )

gb#

Jein. Das Vertrackte ist, dass es immer auf die Umstände des Einzelfalls ankommt. Zufälligkeiten sollten eigentlich seit 25059 raus sein, aber ehrlich gesagt habe ich etwas den Überblick verloren...

Zitat von: Beta-User am 29 Oktober 2021, 17:08:48
Trotzdem natürlich: Viel Erfolg, gutes Gelingen!

Das hat soweit fast schmerzfrei funktioniert! Das einspielen der gepatchten Dateien hat völlig transparent funktioniert.
Keine Auffälligkeiten in Log und Funktion

Einzig bei nach Aufnahme des neuen HMUART-Devices (List s.u.!) gab es zunächst Probleme mit AES-Devices. Der HMUART scheint wohl bei der initialen Zuweisung zur vccu keinen aesKey bekommen zu haben, zumindest wäre das eine Erklärung für folgende Meldungen im Log:


2021.10.30 14:30:59.317 1: HMUARTLGW HMUART5 Adding peer 24FDD3 failed! You have probably forced an unknown aesKey for this device.
2021.10.30 14:32:06.387 1: HMUARTLGW HMUART5 Adding peer 35B096 failed! You have probably forced an unknown aesKey for this device.
2021.10.30 14:32:19.500 1: HMUARTLGW HMUART5 Adding peer 247D88 failed! You have probably forced an unknown aesKey for this device.
2021.10.30 14:32:48.579 1: HMUARTLGW HMUART5 Adding peer 247D88 failed! You have probably forced an unknown aesKey for this device.
2021.10.30 14:32:53.307 1: HMUARTLGW HMUART5 Adding peer 43BCA8 failed! You have probably forced an unknown aesKey for this device.


Das waren alles HM-SEC Devices (HM-SEC-RHS, HM-SEC-SC-2 und HM-SEC-KEY-S)

Nach einem shutdown restart war das Problem dann aber direkt weg.

Da es sich augenscheinlich um ein vccu-Problem (oder doch HMUARTLGW?) handelt, habe ich das jetzt mal an diesen Thread angehängt.

Zum Autocreates testen hatte ich noch keine Zeit, da mache ich ggf. morgen mal noch was.

Wie auch immer, vielen Dank für euer Engagement!

Hier noch das List (Peers und PendingAcks habe ich rausgenommen und meine HMID auch):


Internals:
   AssignedPeerCnt 22
   CNT        138
   Clients    :CUL_HM:
   DEF        uart://10.77.10.51:1000
   DEVCNT     153
   DevState   99
   DevType    UART
   DeviceName 10.77.10.51:1000
   FD         34
   FUUID      617d3499-f33f-b8e7-653d-27cd38a79ed2d626
   LastOpen   1635597345.30714
   NAME       HMUART5
   NOTIFYDEV  global
   NR         1192
   NTFY_ORDER 50-HMUART5
   PARTIAL   
   RAWMSG     0500012F33847026249800000000D834
   RSSI       -47
   STATE      opened: ok (9%)
   TYPE       HMUARTLGW
   XmitOpen   1
   model      HM-MOD-UART
   msgLoadCurrent 9
   msgLoadHistory 0/0/1/1/0/2/0/0/0/5/-/-
   msgLoadHistoryAbs 9/9/9/8/7/7/5/5/5/5/0/-/-
   owner      <HMID>
   owner_CCU  ccu
   Helper:
     CreditTimer 214
     FW         66561
     Initialized 1
     SendCnt    26
     LastSendLen:
       3
       3
     Log:
       IDs:
     PendingCMD:
     RoundTrip:
       Delay      0.00459885597229004
     loadLvl:
       lastHistory 1635600349.3475
   MatchList:
     1:CUL_HM   ^A......................
   READINGS:
     2021-10-30 14:35:49   D-HMIdAssigned  <HMID>
     2021-10-30 14:35:49   D-HMIdOriginal  71C9E0
     2021-10-30 14:35:49   D-firmware      1.4.1
     2021-10-30 14:35:49   D-serialNr      REQ0914252
     2021-10-30 14:35:30   D-type          HM-MOD-UART
     2021-10-30 14:35:49   cond            ok
     2021-10-30 15:13:37   load            9
     2021-10-30 14:35:49   loadLvl         low
     2021-10-30 14:35:45   state           opened
   helper:
Attributes:
   DbLogExclude .*
   group      hmIO
   hmId       <HMID>
   icon       hm_lan
   room       System->Homematic
   stateFormat state: cond (load%)
   webCmd     open:close:reopen

gb#

Mist!

Zu früh gefreut!

Seit einem weiteren FHEM-Neustart wird mir das Log geflutet, mit Verweis auf meinen anderen IO, der vorher schon vorhanden war:


2021.10.30 17:27:48.545 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:27:48.552 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:27:48.559 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:27:59.570 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:27:59.580 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:27:59.589 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:10.601 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:10.609 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:10.617 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:21.628 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:21.636 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:21.643 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:32.657 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:32.667 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:32.676 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:43.689 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:43.699 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:43.709 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:54.716 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:54.722 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:28:54.726 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:05.742 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:05.754 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:05.764 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:16.771 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:16.777 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:16.783 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:27.802 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:27.814 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:27.824 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:38.834 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:38.841 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:38.848 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:49.863 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:49.874 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:29:49.885 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:00.894 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:00.901 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:00.907 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:11.923 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:11.935 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:11.946 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:22.953 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:22.958 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:22.963 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:33.977 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:33.988 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:33.998 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:45.008 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:45.016 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:45.024 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:56.033 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:56.039 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:30:56.044 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:07.051 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:07.056 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:07.061 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:18.073 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:18.082 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:18.089 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:29.097 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:29.102 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:29.107 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:40.122 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:40.134 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:40.144 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:51.150 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:51.155 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:31:51.160 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:02.169 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:02.176 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:02.182 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:13.189 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:13.194 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:13.199 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:24.218 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:24.230 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:24.240 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:35.247 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:35.252 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:35.257 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:46.272 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:46.284 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:46.295 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:57.305 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:57.316 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:32:57.325 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:08.341 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:08.353 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:08.364 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:19.379 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:19.391 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:19.402 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:30.418 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:30.430 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:30.440 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:41.447 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:41.452 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:41.457 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:52.476 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:52.487 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:33:52.498 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:03.509 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:03.518 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:03.525 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:14.539 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:14.549 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:14.559 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:25.566 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:25.572 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:25.576 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:36.592 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:36.604 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:36.615 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:47.621 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:47.626 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:47.630 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:58.645 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:58.653 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:34:58.660 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:09.669 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:09.676 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:09.681 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:20.689 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:20.695 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:20.700 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:31.711 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:31.721 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:31.730 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:42.745 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:42.757 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:42.767 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:53.780 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:53.793 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:35:53.804 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:36:04.820 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:36:04.832 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:36:04.843 2: fhem.pl does not assign desired IODev HMUART4!
2021.10.30 17:36:15.854 2: fhem.pl does not assign desired IODev HMUART4!


HMUART4 wird aber als open angezeigt und funktioniert auch!

gb#

Edit: Die hier kamen übrigens direkt beim FHEM-Neustart:


2021.10.30 16:49:09.163 1: CUL_HM start inital cleanup
2021.10.30 16:49:15.829 1: CUL_HM finished initial cleanup
2021.10.30 16:49:16.281 1: PERL WARNING: Use of uninitialized value $ps in substitution (s///) at ./FHEM/10_CUL_HM.pm line 9647.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $name in hash element at ./FHEM/10_CUL_HM.pm line 9146.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $name in pattern match (m//) at ./FHEM/10_CUL_HM.pm line 9148.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $name in pattern match (m//) at ./FHEM/10_CUL_HM.pm line 9149.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $name in string eq at ./FHEM/10_CUL_HM.pm line 9150.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $name in pattern match (m//) at ./FHEM/10_CUL_HM.pm line 9151.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $name in pattern match (m//) at ./FHEM/10_CUL_HM.pm line 9153.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $d in hash element at fhem.pl line 4681.
2021.10.30 16:49:16.282 1: PERL WARNING: Use of uninitialized value $list in addition (+) at ./FHEM/10_CUL_HM.pm line 9526.
2021.10.30 16:49:16.283 1: PERL WARNING: Use of uninitialized value $peerRq in string eq at ./FHEM/10_CUL_HM.pm line 9303.
2021.10.30 16:49:17.616 1: PERL WARNING: Use of uninitialized value in string eq at ./FHEM/10_CUL_HM.pm line 9845.
20


Hmm, kannst du die log-Zeile erst mal deaktivieren und die danach aktivieren? (Sollte eine DEVIo-Hash-Zuweisung sein).
Sorry, hier ist alles ruhig in der Beziehung...

Muss ich morgen machen, bin heute nicht mehr vor Ort.
Habe erst mal global verbose auf 1 gesetzt, damit mir das log nicht sinnlos geflutet wird.

gb#

Guten Morgen allerseits!

Also ich habe die Logzeile nicht deaktiviert, sondern um den Namen des Device erweitert, dem ein neuer IO zugewiesen werden soll.

Nachdem ich die Geräte identifizieren konnte, war das Problem und damit die  Lösung auch schnell gefunden:
Ich hatte noch 3 HM-Devices, denen im Attibut IODev ein HMUART3 zugewiesen war.
Diesen gibt es aber seit gestern nicht mehr in meiner Installation.
Sprich er wurde durch die TCP232-Variante ersetzt und das HMUART3-Device in FHEM aus aus der IOList der vccu entfernt und auf dummy=1 gesetzt.

Ich habe also für diese 3 Devices in IOGrp meine vccu eingetragen (so wie es sich gehört!) und seither ist Ruhe!
Warum dort im IODev noch ein HMUART direkt eingetragen war kann ich nicht sagen, ich "fahre" eigentlich schon seit Ewigkeiten mit einer vccu.

Tipp.: Wenn die Log-Meldung von Haus aus etwas ausführlicher gewesen wäre, wäre ich mit dem Ding gar nicht erst hier aufgeschlagen ;)

Vielen Dank für den Schubs in den Code :)
und einen entspannten Sonntag wünsche ich!

gb#

Edit:
Inzwischen habe ich auch verstanden, warum die (preferred) IO-Zuweisung nicht funktioniert hat:
fhem_setIoDev (aufgerufen in AssignIoPort) macht keine IODev-Zuweisung, wenn ein IO über das Attribut IODev bereits festgelegt ist.
CUL_HM wollte aber unbedingt ein neues IO mittels AssignIOPort zuweisen, weil das bisher im Reading IODev eingetragene nicht mehr als IO verfügbar war und hat im Anschluss festgestellt, die Zuweisung schlug, warum auch immer fehl, weil im Reading IODev immer noch der alte IO drin steht.

Edit2:
Möglicherweise habe ich im ersten Edit noch irgendwo Reading und Internal durcheinander geworfen.
Es ist aber auch echt verwirrend, dass es IODev in allen 3 Bereichen (Attribute, Reading und Internal) gibt. :D

moin benni,

ZitatIch hatte noch 3 HM-Devices, denen im Attibut IODev ein HMUART3 zugewiesen war.

gab es dort kein attr IOgrp?
gab es attr IOgrp dort noch nie? oder ist es durch ein update abhanden gekommen?
immer nur attr IODev alleine?

beide attribute sind verboten, dieser fall sollte gar nicht mehr vorkommen können.
ansonsten frage ich mich gerade, ob ein mischbetrieb, mit und ohne vccu, überhaupt vorgesehen ist?


edit:

ZitatDiesen gibt es aber seit gestern nicht mehr in meiner Installation.
Sprich er wurde durch die TCP232-Variante ersetzt und das HMUART3-Device in FHEM aus aus der IOList der vccu entfernt und auf dummy=1 gesetzt.

für AssignIoPort ist dummy=1 kein kriterium zum ausschluss, für cul_hm aber schon.

ZitatIch habe also für diese 3 Devices in IOGrp meine vccu eingetragen (so wie es sich gehört!) und seither ist Ruhe!

ich meine: stationäre devices sollten besser immer mindestens ein prefered io bekommen.

Moin frank,

Zitat von: frank am 31 Oktober 2021, 09:24:02
moin benni,
gab es dort kein attr IOgrp?

Nö, nur IODev!

Zitat
gab es attr IOgrp dort noch nie? oder ist es durch ein update abhanden gekommen?
immer nur attr IODev alleine?

Das kann ich dir beim besten Willen nicht zuverlässig beantworten.
Aber wie gesagt, arbeite ich schon seit Ewigkeiten mit einer vccu und lerne meine Devices generell auch nur über diese an. Die 3 betroffenen Devices sind eher jüngeren Datums also definitiv schon unter der vccu angelernt worden.

Zitat
beide attribute sind verboten, dieser fall sollte gar nicht mehr vorkommen können.
ansonsten frage ich mich gerade, ob ein mischbetrieb, mit und ohne vccu, überhaupt vorgesehen ist?

Du meinst beide Attribute gleichzeitig sind verboten. IOgrp als einzelnes Attribut brauche ich ja für die vccu-Zuweisung. Bei den 3 o.g. Devices wurde das IODev-Attribut beim setzen von IOgrp auch korrekt gelöscht!

Zitat
edit: für AssignIoPort ist dummy=1 kein kriterium zum ausschluss, für cul_hm aber schon.
ich meine: stationäre devices sollten besser immer mindestens ein prefered io bekommen.

Die prefered-Zuweisung für die stationären Devices über IOgrp nehme ich erst in ein paar Tagen vor, wenn ich für die beiden neuen IOs aussagefähige rssi-Werte habe.

gb#