[GELÖST] - [HMUARTLGW]: HMUART failed to enter App!

Begonnen von Benni, 06 November 2021, 14:42:17

Vorheriges Thema - Nächstes Thema

Benni

Hallo,

ich habe bei mir seit ca. 2-3 Wochen 2 neue RPI-PCB als HMUART laufen, angebunden über LAN/TCP232.
Beide werden FHEM von einer VCCU verwaltet.
Grundsätzlich habe ich auch keine Probleme mit den beiden. Meine Homematic-Installation funktioniert, wie ich es erwarte.

Allerdings habe ich beim FHEM-Start jetzt folgende Meldungen zu den beiden HMUART im Log:


2021.11.06 08:35:44.118 3: Opening HMUART4 device 10.77.10.50:1000
2021.11.06 08:35:44.119 3: Opening HMUART5 device 10.77.10.51:1000
2021.11.06 08:35:47.016 3: HMUART4 device opened
2021.11.06 08:35:47.038 3: HMUART5 device opened
2021.11.06 08:35:47.294 1: HMUARTLGW HMUART5 failed to enter App!
2021.11.06 08:35:48.676 1: HMUARTLGW HMUART4 failed to enter App!


Ich habe hier im Forum leider nicht wirklich was dazu gefunden, lediglich, dass man die Firmware des PCB auf 1.41 aktualisieren soll. Das hatte ich aber bereits direkt nach Inbetriebnahme schon gemacht.

Hier noch jeweils ein bereinigtes List der beiden:


Internals:
   AssignedPeerCnt 65
   CNT        216
   Clients    :CUL_HM:
   DEF        uart://10.77.10.50:1000
   DEVCNT     35
   DevState   99
   DevType    UART
   DeviceName 10.77.10.50:1000
   FD         32
   FUUID      6172e481-f33f-b8e7-876c-2488bd445d88030b
   LastOpen   1636184147.01607
   NAME       HMUART4
   NOTIFYDEV  global
   NR         1166
   NTFY_ORDER 50-HMUART4
   PARTIAL   
   RAWMSG     0501004086A61043BCA823A81306010000
   RSSI       -64
   STATE      opened: ok (4%)
   TYPE       HMUARTLGW
   XmitOpen   1
   model      HM-MOD-UART
   msgLoadCurrent 4
   msgLoadHistory -1/-1/0/-1/-1/-2/-2/-1/0/-1/0/-1
   msgLoadHistoryAbs 5/6/7/7/8/9/11/13/14/14/15/15/16
   owner      23A813
   owner_CCU  ccu
   Helper:
     CreditTimer 1431
     FW         66561
     Initialized 1
     SendCnt    100
     AckPending:
        <... bereinigt ...>
     DBLOG:
       load:
         logdb:
           TIME       1636205595.25359
           VALUE      4
     LastSendLen:
       3
       3
     Log:
       IDs:
     PendingCMD:
     RoundTrip:
       Delay      0.00487303733825684
     loadLvl:
       lastHistory 1636205451.03646
   MatchList:
     1:CUL_HM   ^A......................
   Peers:
    < ... bereinigt ... >
   READINGS:
     2021-11-06 08:35:50   D-HMIdAssigned  23A813
     2021-11-06 08:35:50   D-HMIdOriginal  582E9D
     2021-11-06 08:35:50   D-firmware      1.4.1
     2021-11-06 08:35:51   D-serialNr      OEQ0303075
     2021-11-06 08:35:30   D-type          HM-MOD-UART
     2021-11-06 08:35:51   cond            ok
     2021-11-06 14:33:15   load            4
     2021-11-06 08:35:51   loadLvl         low
     2021-11-06 08:35:47   state           opened
   helper:
Attributes:
   DbLogExclude .*
   DbLogInclude load
   event-on-change-reading .*
   group      hmIO
   hmId       23A813
   icon       hm_lan
   loadEvents 1
   room       System->Homematic
   stateFormat state: cond (load%)
   webCmd     open:close:reopen



Internals:
   AssignedPeerCnt 78
   CNT        116
   Clients    :CUL_HM:
   DEF        uart://10.77.10.51:1000
   DEVCNT     147
   DevState   99
   DevType    UART
   DeviceName 10.77.10.51:1000
   FD         33
   FUUID      617d3499-f33f-b8e7-653d-27cd38a79ed2d626
   LastOpen   1636184147.03827
   NAME       HMUART5
   NOTIFYDEV  global
   NR         1175
   NTFY_ORDER 50-HMUART5
   PARTIAL   
   RAWMSG     0500012F15861022B40E0000000AB4DF0A0E40
   RSSI       -47
   STATE      opened: ok (5%)
   TYPE       HMUARTLGW
   XmitOpen   1
   model      HM-MOD-UART
   msgLoadCurrent 5
   msgLoadHistory 0/-1/-1/1/0/1/-1/-1/0/0/-1/0
   msgLoadHistoryAbs 5/5/6/7/6/6/5/6/7/7/7/8/8
   owner      23A813
   owner_CCU  ccu
   Helper:
     CreditTimer 1448
     FW         66561
     Initialized 1
     SendCnt    190
     AckPending:
        < ... bereinigt ... >
     DBLOG:
       load:
         logdb:
           TIME       1636205339.87523
           VALUE      5
     LastSendLen:
       6
       6
     Log:
       IDs:
     PendingCMD:
     RoundTrip:
       Delay      0.0114929676055908
     loadLvl:
       lastHistory 1636205750.99031
   MatchList:
     1:CUL_HM   ^A......................
   Peers:
      < ... bereinigt ...>
   READINGS:
     2021-11-06 08:35:50   D-HMIdAssigned  23A813
     2021-11-06 08:35:50   D-HMIdOriginal  71C9E0
     2021-11-06 08:35:50   D-firmware      1.4.1
     2021-11-06 08:35:50   D-serialNr      REQ0914252
     2021-11-06 08:35:30   D-type          HM-MOD-UART
     2021-11-06 08:35:51   cond            ok
     2021-11-06 14:28:59   load            5
     2021-11-06 08:35:51   loadLvl         low
     2021-11-06 08:35:47   state           opened
   helper:
Attributes:
   DbLogExclude .*
   DbLogInclude load
   event-on-change-reading .*
   group      hmIO
   hmId       23A813
   icon       hm_lan
   loadEvents 1
   room       System->Homematic
   stateFormat state: cond (load%)
   webCmd     open:close:reopen


Ich habe leider keine Ahnung, ob das jetzt ein Problem ist oder noch eins werden könnte.

gb#

Sany

Hi Benni,

Zitatich habe bei mir seit ca. 2-3 Wochen 2 neue RPI-PCB als HMUART laufen, angebunden über LAN/TCP232.

Schau Dir mal im Wiki zur VCCU  das hier an: https://wiki.fhem.de/wiki/Virtueller_Controller_VCCU#Was_passiert_beim_Austausch.2FWegfall_von_IOs

Ich meine ich hatte das auch heute, nachdem ich ein HM-MOD-RPI-PCB HomeMatic Funkmodul zusammen mit einem LAN-TTL-Wandler als HMUARTLGW eingebunden hatte (aus dem Wiki: https://wiki.fhem.de/wiki/HM-MOD-RPI-PCB_HomeMatic_Funkmodul_f%C3%BCr_Raspberry_Pi#Betrieb_mit_einem_LAN-TTL-Wandler.

Nach Umbau der fhem.cfg (wird ja dort so beschrieben) kam das nicht mehr. Allerdings war es so, dass wenn man die fhem.cfg editiert hat und speichert wird sie zwar neu gelesen, das hat aber noch nicht gereicht. Erst ein Shutdown/restart hat dann dazu geführt, dass die Meldung nicht mehr auftaucht und der Adapter richtig funktioniert.

Ist vielleicht einen Versuch Wert.


Gruß

Sany
fhem als LXC auf Proxmox auf einem minix Z100 , weitere LXC mit ZigBee2MQTT, MariaDB und Grafana. Homematic, FS20, mySensors, MQTT2, Tasmota, Shelly, Z-Wave  ....

Benni

Hi Sany,

Zitat von: Sany am 06 November 2021, 23:45:28
Schau Dir mal im Wiki zur VCCU  das hier an: https://wiki.fhem.de/wiki/Virtueller_Controller_VCCU#Was_passiert_beim_Austausch.2FWegfall_von_IOs

Da steht nur was zu einer Meldung "unknown IODev specified" die Meldung bei mir ist aber eine andere (s.o.!)
Btw.: Ich nutze eine vccu schon seit Ewigkeiten.
Außerdem bin ich mir ziemlich sicher, dass das Thema mit der IO-Zuweisung inzwischen FHEM-Intern geregelt ist und nicht mehr von der Reihenfolge in der Config abhängt.

Zitat
Nach Umbau der fhem.cfg (wird ja dort so beschrieben) kam das nicht mehr.

Es soll auch Leute geben, die gar keine fhem.cfg haben! ;)

Zitat
dass die Meldung nicht mehr auftaucht und der Adapter richtig funktioniert.

Wie ich in meinem Post beschrieben habe, funktionieren beide Adapter ansonsten tadellos. Die Kommunikation zw. FHEM und meinen HM-Komponenten ist bisher völlig unauffällig. (Ich habe ausschließlich diese beiden HMUARTs als IO)

Ich bin mir nur nicht im Klaren, ob das Zufall ist und auch so bleibt, oder ob ich irgendwelche Maßnahmen ergreifen muss und wenn ja welche?
Irgendeinen Sinn/Hintergrund muss die Meldung ja haben.

gb#

Beta-User

Der VCCU-Artikel ist (leider) in vielen Teilen outdated - CUL_HM ist zwischenzeitlich afaik Reihenfolge-unempfindlich und "TYPE=autocreate" wird nicht genutzt/beachtet (Martin will das aus mir nicht bekannten Gründen definitiv nicht, und auch beim internen "autocreate"-Mechanismus sind wir uns nicht ganz einig... Das ist einer der wesentlichen Unterschiede zwischen patches II (autocreate wird supportet), patches Nov (internes autocreate eingeschränkt auf pairnig-Fälle) und der svn-Version (immer bei Anlern-Messages internes autocreate)).

Die Meldung kommt aus #1279 (HMUARTLGW) - also entweder stimmt was mit der firmware nicht, oder es kommt eine "kaputte" Meldung (oder einfach in der Form unerwartete). Aufgrund der anderen "uninitialized"-Meldungen würde ich eher auf "kaputt" tippen, aber ansonsten tappe ich im Dunkeln und hoffe auf Aufklärung durch jemand Wissenden.
Server: HP-elitedesk@Debian 12, aktuelles FHEM@ConfigDB | CUL_HM (VCCU) | MQTT2: ZigBee2mqtt, MiLight@ESP-GW, BT@OpenMQTTGw | ZWave | SIGNALduino | MapleCUN | RHASSPY
svn: u.a Weekday-&RandomTimer, Twilight,  div. attrTemplate-files, MySensors

frank

ich würde mal verbose=5 bei einem "defekten" und einem "heilen" setzen. danach restart und vergleichen.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Benni

Hallo Frank,

einen "heilen" habe ich aktuell nicht!

Ich habe aber mal für den HMUART4 verbose 5 gesetzt und bekomme damit folgende Log-Einträge beim fhem-start:


2021.11.07 10:07:49.356 5: HMUARTLGW HMUART4 Attr set webCmd open:close:reopen

2021.11.07 10:07:49.917 1: CUL_HM start inital cleanup
2021.11.07 10:07:55.332 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:19DF7B
2021.11.07 10:07:56.205 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:23A813
2021.11.07 10:07:56.297 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:209CAB
2021.11.07 10:07:56.320 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:22B40E
2021.11.07 10:07:56.326 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:23D28F
2021.11.07 10:07:56.333 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FF3C
2021.11.07 10:07:56.363 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2EB18E
2021.11.07 10:07:56.373 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:368CA5
2021.11.07 10:07:56.375 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:34FE93
2021.11.07 10:07:56.379 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:238AFF
2021.11.07 10:07:56.399 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5FDCC1
2021.11.07 10:07:56.404 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:1969AC
2021.11.07 10:07:56.417 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:250BC9
2021.11.07 10:07:56.422 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:26237F
2021.11.07 10:07:56.428 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2388CA
2021.11.07 10:07:56.436 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:33EA70
2021.11.07 10:07:56.438 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:247EE5
2021.11.07 10:07:56.454 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:43BBAA
2021.11.07 10:07:56.466 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5C6D25
2021.11.07 10:07:56.468 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2EB8C4
2021.11.07 10:07:56.469 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FF5C
2021.11.07 10:07:56.472 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258B4C
2021.11.07 10:07:56.544 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:1FCD78
2021.11.07 10:07:56.568 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:688648
2021.11.07 10:07:56.572 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2558B0
2021.11.07 10:07:56.578 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258C6A
2021.11.07 10:07:56.580 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258B51
2021.11.07 10:07:56.583 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258107
2021.11.07 10:07:56.592 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:345702
2021.11.07 10:07:56.601 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:250577
2021.11.07 10:07:56.627 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:33EA3E
2021.11.07 10:07:56.631 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:43BC5F
2021.11.07 10:07:56.635 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:688885
2021.11.07 10:07:56.637 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E8888
2021.11.07 10:07:56.643 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:67C53C
2021.11.07 10:07:56.646 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:206606
2021.11.07 10:07:56.654 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FE93
2021.11.07 10:07:56.658 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2AE818
2021.11.07 10:07:56.672 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E284C
2021.11.07 10:07:56.677 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:325EA1
2021.11.07 10:07:56.688 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:25056B
2021.11.07 10:07:56.689 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FE7F
2021.11.07 10:07:56.691 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5F1968
2021.11.07 10:07:56.700 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:250B32
2021.11.07 10:07:56.711 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2D3730
2021.11.07 10:07:56.717 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:19DF7B
2021.11.07 10:07:56.729 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:1A7A3A
2021.11.07 10:07:56.731 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:52CACF
2021.11.07 10:07:56.737 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:24A77A
2021.11.07 10:07:56.743 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5318EF
2021.11.07 10:07:56.748 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E2608
2021.11.07 10:07:56.750 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:373DA2
2021.11.07 10:07:56.796 5: HMUARTLGW HMUART4 HMUARTLGW_Write: writeAesKey:HMUART4
2021.11.07 10:07:56.906 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:FFFF47
2021.11.07 10:07:56.907 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:662080
2021.11.07 10:07:57.015 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:333005
2021.11.07 10:07:57.017 1: CUL_HM finished initial cleanup
2021.11.07 10:07:58.288 1: PERL WARNING: Use of uninitialized value in string eq at ./FHEM/10_CUL_HM.pm line 9840.
2021.11.07 10:07:59.690 3: CUL_HM set EG.TL.SW.Toilettenlicht off noArg
2021.11.07 10:08:00.767 3: Opening HMUART4 device 10.77.10.50:1000
2021.11.07 10:08:00.767 5: HttpUtils url=http://10.77.10.50:1000/ NonBlocking via http
2021.11.07 10:08:00.767 4: IP: 10.77.10.50 -> 10.77.10.50
2021.11.07 10:08:00.767 3: Opening HMUART5 device 10.77.10.51:1000
2021.11.07 10:08:01.773 3: version revision : Latest Revision: 25185
2021.11.07 10:08:02.398 0: Featurelevel: 99.99
2021.11.07 10:08:02.398 0: Server started with 1181 defined entities (fhem.pl:25161/2021-10-30 perl:5.014002 os:linux user:fhem pid:3377)
2021.11.07 10:08:03.320 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:209CAB
2021.11.07 10:08:03.321 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:22B40E
2021.11.07 10:08:03.321 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:23D28F
2021.11.07 10:08:03.322 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FF3C
2021.11.07 10:08:03.323 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2EB18E
2021.11.07 10:08:03.323 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:368CA5
2021.11.07 10:08:03.324 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:34FE93
2021.11.07 10:08:03.324 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:238AFF
2021.11.07 10:08:03.324 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5FDCC1
2021.11.07 10:08:03.325 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:1969AC
2021.11.07 10:08:03.325 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:250BC9
2021.11.07 10:08:03.326 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:26237F
2021.11.07 10:08:03.326 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2388CA
2021.11.07 10:08:03.326 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:33EA70
2021.11.07 10:08:03.326 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:247EE5
2021.11.07 10:08:03.327 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:43BBAA
2021.11.07 10:08:03.327 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5C6D25
2021.11.07 10:08:03.328 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2EB8C4
2021.11.07 10:08:03.328 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FF5C
2021.11.07 10:08:03.328 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258B4C
2021.11.07 10:08:03.329 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:1FCD78
2021.11.07 10:08:03.329 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:688648
2021.11.07 10:08:03.330 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2558B0
2021.11.07 10:08:03.330 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258C6A
2021.11.07 10:08:03.330 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258B51
2021.11.07 10:08:03.330 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258107
2021.11.07 10:08:03.331 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:345702
2021.11.07 10:08:03.331 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:250577
2021.11.07 10:08:03.332 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:33EA3E
2021.11.07 10:08:03.332 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:43BC5F
2021.11.07 10:08:03.333 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:688885
2021.11.07 10:08:03.333 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E8888
2021.11.07 10:08:03.333 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:67C53C
2021.11.07 10:08:03.333 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:206606
2021.11.07 10:08:03.334 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FE93
2021.11.07 10:08:03.334 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2AE818
2021.11.07 10:08:03.335 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E284C
2021.11.07 10:08:03.335 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:325EA1
2021.11.07 10:08:03.336 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:25056B
2021.11.07 10:08:03.336 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FE7F
2021.11.07 10:08:03.336 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5F1968
2021.11.07 10:08:03.336 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:250B32
2021.11.07 10:08:03.337 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2D3730
2021.11.07 10:08:03.337 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:19DF7B
2021.11.07 10:08:03.338 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:1A7A3A
2021.11.07 10:08:03.338 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:52CACF
2021.11.07 10:08:03.338 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:24A77A
2021.11.07 10:08:03.339 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:5318EF
2021.11.07 10:08:03.339 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E2608
2021.11.07 10:08:03.339 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:373DA2
2021.11.07 10:08:03.339 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:FFFF47
2021.11.07 10:08:03.339 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:662080
2021.11.07 10:08:03.340 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:333005
2021.11.07 10:08:03.579 2: AttrTemplates: got 240 entries
2021.11.07 10:08:04.428 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:490FA8
2021.11.07 10:08:04.429 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:490FB0
2021.11.07 10:08:04.429 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:237901
2021.11.07 10:08:04.430 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:21B39D
2021.11.07 10:08:04.430 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:33C48B
2021.11.07 10:08:04.430 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:258B39
2021.11.07 10:08:04.431 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:20FE9B
2021.11.07 10:08:04.431 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:23D4F1
2021.11.07 10:08:04.432 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:6B65C7
2021.11.07 10:08:04.432 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:261135
2021.11.07 10:08:04.433 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2D3551
2021.11.07 10:08:04.434 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:6B652C
2021.11.07 10:08:04.434 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2E2874
2021.11.07 10:08:04.434 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:6C6696
2021.11.07 10:08:04.435 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:34FE8B
2021.11.07 10:08:04.435 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2510F8
2021.11.07 10:08:04.436 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:368CB4
2021.11.07 10:08:04.436 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:368C9C
2021.11.07 10:08:04.437 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:35B096
2021.11.07 10:08:04.437 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:2DB437
2021.11.07 10:08:04.437 5: HMUARTLGW HMUART4 HMUARTLGW_Write: init:53184C
2021.11.07 10:08:04.586 3: HMUART4 device opened
2021.11.07 10:08:04.594 3: HMUART5 device opened
2021.11.07 10:08:04.938 1: HMUARTLGW HMUART5 failed to enter App!
2021.11.07 10:08:05.007 5: HMUARTLGW HMUART4 read raw (74): fd0015010b05000040f586102e875a0000000aa0d30b01409257fd001a010c0500003bc7a270afbc1123a81300e32d000000000d2009c49e26fd000c000000436f5f4350555f424c7251
2021.11.07 10:08:05.008 5: HMUARTLGW HMUART4 read (25): fd0015010b05000040f586102e875a0000000aa0d30b01409257 crc OK
2021.11.07 10:08:05.008 5: HMUARTLGW HMUART4 recv: 01 05 00 00 40 msg: F5 86 10 2E875A 000000 0AA0D30B0140
2021.11.07 10:08:05.008 5: HMUARTLGW HMUART4 read (30): fd001a010c0500003bc7a270afbc1123a81300e32d000000000d2009c49e26 crc OK
2021.11.07 10:08:05.008 5: HMUARTLGW HMUART4 recv: 01 05 00 00 3B msg: C7 A2 70 AFBC11 23A813 00E32D000000000D2009C4
2021.11.07 10:08:05.009 5: HMUARTLGW HMUART4 read (16): fd000c000000436f5f4350555f424c7251 crc OK
2021.11.07 10:08:05.009 5: HMUARTLGW HMUART4 recv: 00 00436F5F4350555F424C, state 0
2021.11.07 10:08:05.009 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:08:05.009 1: HMUARTLGW HMUART4 failed to enter App!
2021.11.07 10:08:06.016 3: CUL_HM set UG.TR.SW.FP.Haustuer on noArg
2021.11.07 10:08:06.153 3: CUL_HM set UG.TR.SW.FP.Kellertuer on noArg
2021.11.07 10:08:06.202 4: HMUARTLGW HMUART4 StartInit
2021.11.07 10:08:06.203 5: HMUARTLGW HMUART4 send: 00 00
2021.11.07 10:08:06.203 5: HMUARTLGW HMUART4 send: (8): fd00030001009e03
2021.11.07 10:08:06.203 5: DevIo_SimpleWrite HMUART4: fd00030001009e03
2021.11.07 10:08:06.405 5: HMUARTLGW HMUART4 read raw (18): fd000d00010402436f5f4350555f424c7f7b
2021.11.07 10:08:06.406 5: HMUARTLGW HMUART4 read (17): fd000d00010402436f5f4350555f424c7f7b crc OK
2021.11.07 10:08:06.406 5: HMUARTLGW HMUART4 recv: 00 0402436F5F4350555F424C, state 1
2021.11.07 10:08:06.406 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:08:06.406 5: HMUARTLGW HMUART4 send: 00 03
2021.11.07 10:08:06.406 5: HMUARTLGW HMUART4 send: (8): fd00030002039409
2021.11.07 10:08:06.406 5: DevIo_SimpleWrite HMUART4: fd00030002039409
2021.11.07 10:08:06.897 5: HMUARTLGW HMUART4 read raw (27): fd0004000204011916fd000d000000436f5f4350555f417070d831
2021.11.07 10:08:06.897 5: HMUARTLGW HMUART4 read (8): fd0004000204011916 crc OK
2021.11.07 10:08:06.897 5: HMUARTLGW HMUART4 recv: 00 0401, state 2
2021.11.07 10:08:06.897 5: HMUARTLGW HMUART4 read (17): fd000d000000436f5f4350555f417070d831 crc OK
2021.11.07 10:08:06.897 5: HMUARTLGW HMUART4 recv: 00 00436F5F4350555F417070, state 2
2021.11.07 10:08:06.897 4: HMUARTLGW HMUART4 currently running Co_CPU_App
2021.11.07 10:08:07.014 3: CUL_HM set OG.BU.SW.TabletBueroPower on noArg
2021.11.07 10:08:07.899 5: HMUARTLGW HMUART4 send: 01 0023A813
2021.11.07 10:08:07.899 5: HMUARTLGW HMUART4 send: (11): fd000601030023a8138ee4
2021.11.07 10:08:07.899 5: DevIo_SimpleWrite HMUART4: fd000601030023a8138ee4
2021.11.07 10:08:07.904 5: HMUARTLGW HMUART4 read raw (9): fd0004010304010d02
2021.11.07 10:08:07.905 5: HMUARTLGW HMUART4 read (8): fd0004010304010d02 crc OK
2021.11.07 10:08:07.905 5: HMUARTLGW HMUART4 recv: 01 0401, state 4
2021.11.07 10:08:07.906 5: HMUARTLGW HMUART4 GetSet Ack: 01, state 4
2021.11.07 10:08:07.906 5: HMUARTLGW HMUART4 send: 01 01
2021.11.07 10:08:07.906 5: HMUARTLGW HMUART4 send: (8): fd00030104018011
2021.11.07 10:08:07.906 5: DevIo_SimpleWrite HMUART4: fd00030104018011
2021.11.07 10:08:07.911 5: HMUARTLGW HMUART4 read raw (14): fd000901040407010123a8134c00
2021.11.07 10:08:07.912 5: HMUARTLGW HMUART4 read (13): fd000901040407010123a8134c00 crc OK
2021.11.07 10:08:07.912 5: HMUARTLGW HMUART4 recv: 01 0407010123A813, state 5
2021.11.07 10:08:07.912 5: HMUARTLGW HMUART4 GetSet Ack: 07, state 5
2021.11.07 10:08:07.913 5: HMUARTLGW HMUART4 send: 01 10
2021.11.07 10:08:07.913 5: HMUARTLGW HMUART4 send: (8): fd00030105100674
2021.11.07 10:08:07.914 5: DevIo_SimpleWrite HMUART4: fd00030105100674
2021.11.07 10:08:07.919 5: HMUARTLGW HMUART4 read raw (14): fd0009010504070101582e9dcd77
2021.11.07 10:08:07.919 5: HMUARTLGW HMUART4 read (13): fd0009010504070101582e9dcd77 crc OK
2021.11.07 10:08:07.919 5: HMUARTLGW HMUART4 recv: 01 04070101582E9D, state 6
2021.11.07 10:08:07.920 5: HMUARTLGW HMUART4 GetSet Ack: 07, state 6
2021.11.07 10:08:07.920 5: HMUARTLGW HMUART4 send: 00 0E6187977702
2021.11.07 10:08:07.921 5: HMUARTLGW HMUART4 send: (13): fd000800060e61879777022fed
2021.11.07 10:08:07.921 5: DevIo_SimpleWrite HMUART4: fd000800060e61879777022fed
2021.11.07 10:08:07.925 5: HMUARTLGW HMUART4 read raw (9): fd0004000604019945
2021.11.07 10:08:07.926 5: HMUARTLGW HMUART4 read (8): fd0004000604019945 crc OK
2021.11.07 10:08:07.926 5: HMUARTLGW HMUART4 recv: 00 0401, state 7
2021.11.07 10:08:07.926 5: HMUARTLGW HMUART4 GetSet Ack: 01, state 7
2021.11.07 10:08:07.927 5: HMUARTLGW HMUART4 send: 00 02
2021.11.07 10:08:07.927 5: HMUARTLGW HMUART4 send: (8): fd00030007020a0c
2021.11.07 10:08:07.927 5: DevIo_SimpleWrite HMUART4: fd00030007020a0c
2021.11.07 10:08:07.932 5: HMUARTLGW HMUART4 read raw (15): fd000a00070402010003010401abca
2021.11.07 10:08:07.933 5: HMUARTLGW HMUART4 read (14): fd000a00070402010003010401abca crc OK
2021.11.07 10:08:07.933 5: HMUARTLGW HMUART4 recv: 00 0402010003010401, state 8
2021.11.07 10:08:07.933 5: HMUARTLGW HMUART4 GetSet Ack: 02, state 8
2021.11.07 10:08:07.933 5: HMUARTLGW HMUART4 send: 00 06
2021.11.07 10:08:07.934 5: HMUARTLGW HMUART4 send: (8): fd0003000806a817
2021.11.07 10:08:07.934 5: DevIo_SimpleWrite HMUART4: fd0003000806a817
2021.11.07 10:08:07.938 5: HMUARTLGW HMUART4 read raw (9): fd000400080401199e
....


Meldungen aus anderen Modulen habe ich rausgefiltert (bspw. Sonos, Harmony, OBIS, ...)

gb#

Benni

Also ich habe jetzt beim HMUART4 das Firmware-Update nochmal durchgeführt:


2021.11.07 10:34:33.867 4: HMUARTLGW HMUART4 Reopen
2021.11.07 10:34:33.868 3: HMUART4 device closed
2021.11.07 10:34:33.975 4: IP: 10.77.10.50 -> 10.77.10.50
2021.11.07 10:34:33.981 1: 10.77.10.50:1000 reappeared (HMUART4)
2021.11.07 10:34:34.982 4: HMUARTLGW HMUART4 StartInit
2021.11.07 10:34:34.992 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:34:34.992 1: HMUARTLGW HMUART4 starting firmware upgrade
2021.11.07 10:34:58.916 1: HMUARTLGW HMUART4 firmware update successfull
2021.11.07 10:34:58.922 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:34:59.363 4: HMUARTLGW HMUART4 currently running Co_CPU_App
2021.11.07 10:35:00.432 4: HMUARTLGW HMUART4 key: <... bereinigt ...>, idx: 01


Das hat aber anscheinend auch keine Besserung gebracht:


2021.11.07 10:38:02.429 3: HMUART4 device opened
2021.11.07 10:38:02.433 3: HMUART5 device opened
2021.11.07 10:38:02.750 1: HMUARTLGW HMUART5 failed to enter App!
2021.11.07 10:38:04.010 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:38:04.010 1: HMUARTLGW HMUART4 failed to enter App!
2021.11.07 10:38:04.417 4: HMUARTLGW HMUART4 StartInit
2021.11.07 10:38:04.685 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:38:05.160 4: HMUARTLGW HMUART4 currently running Co_CPU_App


Verbose hatte ich jetzt nur auf 4 gesetzt!

gb#

frank

ich behaupte mal: der hmuart soll bereits senden, ist aber noch im bootloader, also vor dem initialisieren.
2021.11.07 10:08:05.009 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 10:08:05.009 1: HMUARTLGW HMUART4 failed to enter App!
2021.11.07 10:08:06.016 3: CUL_HM set UG.TR.SW.FP.Haustuer on noArg
2021.11.07 10:08:06.153 3: CUL_HM set UG.TR.SW.FP.Kellertuer on noArg
2021.11.07 10:08:06.202 4: HMUARTLGW HMUART4 StartInit
2021.11.07 10:08:06.203 5: HMUARTLGW HMUART4 send: 00 00


solange kein definierter ablauf beim start von cul_hm existiert, hilft es vielleicht doch, die definitionen der io frühest möglich durchzuführen.
bei mir ist hmuart der letze, der "fertig" ist. die ersten sendungen werden immer vom cul getätigt, da dieser sofort "fertig" ist. wenn du noch einen cul anstecken würdest, könnte die meldung dadurch auch verschwinden.

wie an anderer stelle bereits beschrieben, müsste cul_hm mit sendungen warten, bis wenigstens ein io "fertig" ist.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Benni

#8
Zitat von: frank am 07 November 2021, 11:26:46
ich behaupte mal: der hmuart soll bereits senden, ist aber noch im bootloader, also vor dem initialisieren.

Klingt plausibel!

Zitat
solange kein definierter ablauf beim start von cul_hm existiert, hilft es vielleicht doch, die definitionen der io frühest möglich durchzuführen.
bei mir ist hmuart der letze, der "fertig" ist. die ersten sendungen werden immer vom cul getätigt, da dieser sofort "fertig" ist. wenn du noch einen cul anstecken würdest, könnte die meldung dadurch auch verschwinden.

CUL habe ich leider gerade keinen griffbereit :D!

Ich könnte jetzt aber mal schauen, ob ich doch die DEF der HMUARTs mal in der Config nach vorne verlegen kann.

Grundsätzlich lese ich aber zwischen den Zeilen, dass in meinem Fall jetzt nicht mit ernsthaften Problemen zu rechnen ist.

gb#

Edit: (eher Off-Topic!)
Ich wusste, dass ich ein ähnlich gelagertes Problem (config-Reihenfolge) vor langer Zeit schon mal hatte und bin tatsächlich über den alten Thread gestolpert: https://forum.fhem.de/index.php/topic,62354.msg538174.html#msg538174
Lang, lang ist's her (... und manchmal kommen sie wieder) :D



frank

ZitatCUL habe ich leider gerade keinen griffbereit :D!
hmlan sollte auch früher fertig sein.

ob das auf dauer probleme bereitet, keine ahnung.
optimal wäre anders.
FHEM: 6.0(SVN) => Pi3(buster)
IO: CUL433|CUL868|HMLAN|HMUSB2|HMUART
CUL_HM: CC-TC|CC-VD|SEC-SD|SEC-SC|SEC-RHS|Sw1PBU-FM|Sw1-FM|Dim1TPBU-FM|Dim1T-FM|ES-PMSw1-Pl
IT: ITZ500|ITT1500|ITR1500|GRR3500
WebUI [HMdeviceTools.js (hm.js)]: https://forum.fhem.de/index.php/topic,106959.0.html

Benni

Ich habe die beiden HMUARTs in der config nun direkt vor die Definition der vccu gesetzt, die ist im Übrigen auch das allererste CUL_HM device in der config.

Änderung hat es keine gebracht:


2021.11.07 14:09:39.878 3: HMUART4 device opened
2021.11.07 14:09:40.547 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 14:09:40.553 1: HMUARTLGW HMUART4 failed to enter App!
2021.11.07 14:09:41.377 4: HMUARTLGW HMUART4 StartInit
2021.11.07 14:09:41.500 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 14:09:41.950 4: HMUARTLGW HMUART4 currently running Co_CPU_App


Für mich sieht es aber so aus, als ob in einer 2. Iteration (?) alles glatt läuft, denn nach der 2. Meldung "currently running Co_CPU_App" kommt keine weitere "failed to enter App!" für den HMUART4

Der 2., erfolgreiche Durchlauf kommt wohl nach der "StartInit"-Meldung. Vielleicht müsste die hier schon früher kommen?

Die Wahrheit ist aber, ich kenne mich mit den internen Abläufen in HMUARTLGW überhaupt nicht aus somit sind das auch nur Vermutungen, bzw. einfache Interpretationsversuche.

gb#

Beta-User

Mal die notify-Prio erhöhen (Präfix in initialize)? Siehe HMLAN-patch.
Server: HP-elitedesk@Debian 12, aktuelles FHEM@ConfigDB | CUL_HM (VCCU) | MQTT2: ZigBee2mqtt, MiLight@ESP-GW, BT@OpenMQTTGw | ZWave | SIGNALduino | MapleCUN | RHASSPY
svn: u.a Weekday-&RandomTimer, Twilight,  div. attrTemplate-files, MySensors

Benni

Zitat von: Beta-User am 07 November 2021, 14:34:09
Mal die notify-Prio erhöhen (Präfix in initialize)? Siehe HMLAN-patch.

Ich hab's bei mir mal in 00_HMUARTLGW.pm in die Initialize, ebenfalls mit 47- eingetragen, hat aber auch keine Änderung gebracht:


2021.11.07 15:22:41.639 3: HMUART4 device opened
2021.11.07 15:22:43.100 4: HMUARTLGW HMUART4 currently running Co_CPU_BL
2021.11.07 15:22:43.100 1: HMUARTLGW HMUART4 failed to enter App!
2021.11.07 15:22:43.946 4: HMUARTLGW HMUART4 StartInit
2021.11.07 15:22:44.159 4: HMUARTLGW HMUART4 currently running Co_CPU_BL

mgernoth

Hallo Benni,

Zitat von: Benni am 07 November 2021, 10:21:48

2021.11.07 10:08:05.009 5: HMUARTLGW HMUART4 recv: 00 00436F5F4350555F424C, state 0
2021.11.07 10:08:05.009 4: HMUARTLGW HMUART4 currently running Co_CPU_BL


Hier kommt im State 0 (noch bevor HMUARTLGW aktiv mit dem HMUART redet) die unaufgeforderte Meldung vom HMUART, dass dieser im Bootloader läuft. Es wurde (in dieser Verbidnung) noch nichts an den HMUART gesendet, deswegen sollte so eine Nachricht auch nicht kommen. Daher kommt die "failed" Meldung im Log, da in dem Zustand diese Meldung nicht kommen kann ;-)

Aber:
Das letzte was HMUARTLGW macht, bevor es ein Gerät schliesst (entweder explizit beim close oder beim shutdown) ist, das Gerät in den Bootloader zu schicken, damit dieses nicht mehr ACKs und Zeitnachrichten sendet. Die Antwort des Geräts ist dann genau die Nachricht von oben, landet aber normalerweise im leeren. Anscheinend puffert der LAN-UART-Wandler die Nachricht und schickt sie in der nächsten Verbindung an den Client. Schau mal, ob Du da irgendwo das Buffering ausschalten kannst.

Aber die Meldung ist in dem Fall harmlos, da es nur ein Relikt der letzten Sitzung ist.

Viele Grüße
  Michael

Benni

Hallo Michael,

das einzige was ich beim Wandler an Einstellung gefunden habe, was irgendwie auf deine Beschreibung passt ("Buffer Data before connected") ist bereits deaktiviert.

Keine Ahnung, ob man mit den anderen Einstellungen noch irgendwas erreichen kann. Ich werde mich da mal noch durchtesten.

Grundsätzlich kann ich aber mit der Aussage "tut nix!" :) gut leben!.
Für mich ist das Thema damit gelöst!

Sollte ich noch was mit den Einstellungen erreichen, tu' ich das natürlich hier kund!

Danke an alle für die Unterstützung!
gb#