[GELÖST] [HMCCU] Zustandsänderungen werden nicht erkannt

Begonnen von Laire, 28 März 2018, 12:24:35

Vorheriges Thema - Nächstes Thema
Drucken
Nach unten Seiten1
Benutzer-Aktionen

Laire

28 März 2018, 12:24:35 Letzte Bearbeitung: 03 April 2018, 16:39:00 von Laire
Moin,

ich richte gerade FHEM auf einem neuen Server ein. Jetzt habe ich ein Problem beim Einbinden meiner Homematic Geräte.

Vorhandene Gerätearten:

  • klassiche HM Funk
  • HM IP

Ich habe meine CCU laut Wiki Eintrag bei FHEM eingerichtet. Er legt das Hauptgerät an und auch die beiden DEVICES für die Schnittstelle.

Hier fällt mir das erste auf, was mir seltsam erscheint, nach einem Neustart steht nicht alles auf running/OK:

HM_CCU - inactive/OK
CCU RPC BidCos-RF - running/OK
CCU RPC HmIP-RF - inactive/OK

HMCCU:
Code Auswählen
defmod HM_CCU HMCCU 192.168.178.20
attr HM_CCU ccuflags procrpc
attr HM_CCU room Interfaces
attr HM_CCU rpcinterfaces BidCos-RF,HmIP-RF
attr HM_CCU rpcinterval 2
attr HM_CCU rpcport 2001,2010
attr HM_CCU rpcserver on
attr HM_CCU stateFormat rpcstate/state

Wenn ich jetzt einen Aktor per Hand schalte, bekommt das FHEM nicht automatisch mit (egal ob IP oder nicht). Nach get update stimmt es wieder.
Schalten kann ich ohne Probleme über FHEM wobei sich dabei auch das reading nicht ändert.

Zusammenfassend: Manuelles abrufen funktioniert und schalten funktioniert. Änderungen erkennen bzw. automatisches abrufen funktioniert nicht.

Hier die Defintionen von einem IP und einem klassischen Gerät aus meinem FHEM:

HmIP-RF
Code Auswählen
defmod HM_Gaestezimmer_Licht HMCCUDEV 000895699E72AE
attr HM_Gaestezimmer_Licht IODev HM_CCU
attr HM_Gaestezimmer_Licht ccureadingfilter STATE
attr HM_Gaestezimmer_Licht group Aktor
attr HM_Gaestezimmer_Licht room Homematic
attr HM_Gaestezimmer_Licht statedatapoint 2.STATE
attr HM_Gaestezimmer_Licht statevals on:1,off:0
attr HM_Gaestezimmer_Licht substitute STATE!(1|true):on,(0|false):off
attr HM_Gaestezimmer_Licht webCmd devstate
attr HM_Gaestezimmer_Licht widgetOverride devstate:uzsuToggle,off,on

HM-LC-Sw1PBU-FM
Code Auswählen
defmod HM_Dachboden_Licht HMCCUDEV OEQ0358189
attr HM_Dachboden_Licht IODev HM_CCU
attr HM_Dachboden_Licht ccureadingfilter STATE
attr HM_Dachboden_Licht controldatapoint 1.STATE
attr HM_Dachboden_Licht group Aktor,Schalter
attr HM_Dachboden_Licht room Homematic
attr HM_Dachboden_Licht statedatapoint 1.STATE
attr HM_Dachboden_Licht statevals on:true,off:false
attr HM_Dachboden_Licht substitute STATE!(true|1):on,(false|0):off
attr HM_Dachboden_Licht webCmd control
attr HM_Dachboden_Licht widgetOverride control:uzsuToggle,off,on

Die Logdatei vom Bootvorgang
Code Auswählen
2018.03.28 12:20:36 1: Including /opt/fhem/fhem.cfg
2018.03.28 12:20:36 3: telnetPort: port 7072 opened
2018.03.28 12:20:36 3: WEB: port 8083 opened
2018.03.28 12:20:36 2: eventTypes: loaded 102 events from /opt/fhem/log/eventTypes.txt
2018.03.28 12:20:36 3: TabletUi: new ext defined infix:ftui/: dir:/opt/fhem/www/tablet/:
2018.03.28 12:20:36 3: Registering HTTPSRV TabletUi for URL /ftui   and assigned link ftui/ ...
2018.03.28 12:20:36 1: HMCCU: Device HM_CCU. Initialized version 4.2.003
2018.03.28 12:20:37 1: HMCCU: Read 10 devices with 81 channels from CCU 192.168.178.20
2018.03.28 12:20:37 1: HMCCU: Read 3 interfaces from CCU 192.168.178.20
2018.03.28 12:20:37 1: HMCCURPCPROC: [d_rpcBidCos_RF] Initialized version 1.0.002 for interface BidCos-RF
2018.03.28 12:20:37 1: HMCCURPCPROC: [d_rpcHmIP_RF] Initialized version 1.0.002 for interface HmIP-RF
2018.03.28 12:20:37 1: Including /opt/fhem/log/fhem.save
2018.03.28 12:20:37 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2018.03.28 12:20:37 0: Featurelevel: 5.8
2018.03.28 12:20:37 0: Server started with 22 defined entities (fhem.pl:16453/2018-03-20 perl:5.024001 os:linux user:root pid:7156)
2018.03.28 12:20:49 2: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server process started for interface BidCos-RF with PID=7374
2018.03.28 12:20:49 2: CCURPC: [d_rpcBidCos_RF] Initializing RPC server CB2001 for interface BidCos-RF
2018.03.28 12:20:49 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server starting
2018.03.28 12:20:49 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process started for interface HmIP-RF with PID=7375
2018.03.28 12:20:49 2: CCURPC: [d_rpcHmIP_RF] Initializing RPC server CB2010 for interface HmIP-RF
2018.03.28 12:20:49 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server starting
2018.03.28 12:20:49 2: HMCCURPCPROC: [d_rpcBidCos_RF] Callback server CB2001 created. Listening on port 7411
2018.03.28 12:20:49 2: CCURPC: [d_rpcBidCos_RF] CB2001 accepting connections. PID=7374
2018.03.28 12:20:49 2: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB2001 enters server loop
2018.03.28 12:20:49 2: HMCCURPCPROC: [d_rpcBidCos_RF] Registering callback http://172.17.0.3:7411/fh2001 of type A with ID CB2001 at http://192.168.178.20:2001
2018.03.28 12:20:49 2: HMCCURPCPROC: [d_rpcHmIP_RF] Callback server CB2010 created. Listening on port 7420
2018.03.28 12:20:49 2: CCURPC: [d_rpcHmIP_RF] CB2010 accepting connections. PID=7375
2018.03.28 12:22:55 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB2001 running
2018.03.28 12:22:55 2: HMCCURPCPROC: [d_rpcHmIP_RF] Checking if RPC server process is running
2018.03.28 12:22:55 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process not running. Cleaning up
2018.03.28 12:22:55 1: HMCCURPCPROC: [d_rpcHmIP_RF] Housekeeping called. Cleaning up RPC environment
2018.03.28 12:22:55 2: HMCCURPCPROC: [d_rpcHmIP_RF] Sending signal INT to RPC server process CB2010 with PID=7375
2018.03.28 12:22:55 2: CCURPC: [d_rpcHmIP_RF] CB2010 received signal INT
2018.03.28 12:22:55 1: CCURPC: [d_rpcHmIP_RF] RPC server CB2010 stopped handling connections. PID=7375
2018.03.28 12:22:55 2: CCURPC: [d_rpcHmIP_RF] Number of I/O errors = 0
2018.03.28 12:22:57 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process CB2010 deleted
2018.03.28 12:22:57 2: HMCCURPCPROC: [d_rpcHmIP_RF] Stop I/O handling

Laire

#1
29 März 2018, 20:36:24
Ich habe jetzt mal den Server auf Verbose 4 laufen lassen, das sind die Einträge in der Logfile die HMCCU macht:

Code Auswählen
2018.03.29 20:24:52 1: HMCCU: Device HM_CCU. Initialized version 4.2.004
2018.03.29 20:24:53 1: HMCCU: Read 10 devices with 81 channels from CCU 192.168.178.20
2018.03.29 20:24:53 1: HMCCU: Read 3 interfaces from CCU 192.168.178.20
2018.03.29 20:24:53 1: HMCCURPCPROC: [d_rpcBidCos_RF] Initialized version 1.0.002 for interface BidCos-RF
2018.03.29 20:24:53 1: HMCCURPCPROC: [d_rpcHmIP_RF] Initialized version 1.0.002 for interface HmIP-RF
2018.03.29 20:24:55 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2018.03.29 20:25:07 2: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server process started for interface BidCos-RF with PID=15037
2018.03.29 20:25:07 2: CCURPC: [d_rpcBidCos_RF] Initializing RPC server CB2001 for interface BidCos-RF
2018.03.29 20:25:07 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server starting
2018.03.29 20:25:07 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process started for interface HmIP-RF with PID=15038
2018.03.29 20:25:07 2: CCURPC: [d_rpcHmIP_RF] Initializing RPC server CB2010 for interface HmIP-RF
2018.03.29 20:25:07 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server starting
2018.03.29 20:25:07 2: HMCCURPCPROC: [d_rpcBidCos_RF] Callback server CB2001 created. Listening on port 7411
2018.03.29 20:25:07 2: CCURPC: [d_rpcBidCos_RF] CB2001 accepting connections. PID=15037
2018.03.29 20:25:07 2: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB2001 enters server loop
2018.03.29 20:25:07 2: HMCCURPCPROC: [d_rpcBidCos_RF] Registering callback http://172.17.0.3:7411/fh2001 of type A with ID CB2001 at http://192.168.178.20:2001
2018.03.29 20:25:07 2: HMCCURPCPROC: [d_rpcHmIP_RF] Callback server CB2010 created. Listening on port 7420
2018.03.29 20:25:07 2: CCURPC: [d_rpcHmIP_RF] CB2010 accepting connections. PID=15038
2018.03.29 20:27:14 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB2001 running
2018.03.29 20:27:14 2: HMCCURPCPROC: [d_rpcHmIP_RF] Checking if RPC server process is running
2018.03.29 20:27:14 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process not running. Cleaning up
2018.03.29 20:27:14 1: HMCCURPCPROC: [d_rpcHmIP_RF] Housekeeping called. Cleaning up RPC environment
2018.03.29 20:27:14 2: HMCCURPCPROC: [d_rpcHmIP_RF] Sending signal INT to RPC server process CB2010 with PID=15038
2018.03.29 20:27:14 2: CCURPC: [d_rpcHmIP_RF] CB2010 received signal INT
2018.03.29 20:27:14 1: CCURPC: [d_rpcHmIP_RF] RPC server CB2010 stopped handling connections. PID=15038
2018.03.29 20:27:14 2: CCURPC: [d_rpcHmIP_RF] Number of I/O errors = 0
2018.03.29 20:27:16 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process CB2010 deleted
2018.03.29 20:27:16 2: HMCCURPCPROC: [d_rpcHmIP_RF] Stop I/O handling
2018.03.29 20:35:07 2: HMCCURPCPROC: [d_rpcBidCos_RF] Received no events from interface CB2001 for 600 seconds

zap

#2
30 März 2018, 18:54:52
hm, der RPC Server für BidCos startet, der für HMIP nicht. Aus den Meldungen erschließt sich leider nicht, warum.

Die Aktualisierung für Nicht-IP Geräte müsste eigentlich funktionieren.

Du kannst mal schauen, ob während dem Start der RPC-Server im Log der CCU (/var/log/messages) irgendwelche Fehlermeldungen auftauchen.
2xCCU3 mit ca. 100 Aktoren, Sensoren
Entwicklung: FHEM auf Proxmox Debian VM
Produktiv inzwischen auf Home Assistant gewechselt.
Maintainer: HMCCU, (Fully, AndroidDB)

Laire

#3
03 April 2018, 13:10:56 Letzte Bearbeitung: 03 April 2018, 13:13:04 von Laire
Geht aber beides nicht, also normal Funk und IP.

Also auf der CCU erschienen zeitgleich zum Start von FHEM und Initialisierung der RPC Server folgende Meldung im Bereich messages:

ZitatApr  3 13:00:39 homematic-ccu2 local0.err ReGaHss: Error: IseDOMPrg::Execute: pDest->ExecuteDestination failed from Program ID = 1916 'prgEnergyCounter_1896_000895699E72AE:5' with valNew = '' [iseDOMPrg.cpp:621]
Apr  3 13:04:32 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"CB2001"}) on http://172.17.0.3:7411/fh2001:
Apr  3 13:05:36 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"CB2001"}) on http://172.17.0.3:7411/fh2001:

Laire

#4
03 April 2018, 13:40:40
Ok, hier nochmal die Logfiles von meinem FHEM Sever und meiner CCU nach einem Neustart beider Geräte

FHEM gestoppt -> CCU neugestartet -> CCU neustart komplett -> FHEM gestartet

FHEM Log (Verbose Lvl 2):

Code Auswählen
018.04.03 13:32:05 1: Including /opt/fhem/fhem.cfg
2018.04.03 13:32:05 2: eventTypes: loaded 353 events from /opt/fhem/log/eventTypes.txt
2018.04.03 13:32:06 1: HMCCU: Device HM_CCU. Initialized version 4.2.004
2018.04.03 13:32:06 1: HMCCU: Read 10 devices with 82 channels from CCU 192.168.178.20
2018.04.03 13:32:06 1: HMCCU: Read 3 interfaces from CCU 192.168.178.20
2018.04.03 13:32:06 1: HMCCURPCPROC: [d_rpcBidCos_RF] Initialized version 1.0.002 for interface BidCos-RF
2018.04.03 13:32:06 1: HMCCURPCPROC: [d_rpcHmIP_RF] Initialized version 1.0.002 for interface HmIP-RF
2018.04.03 13:32:08 1: Including /opt/fhem/log/fhem.save
2018.04.03 13:32:08 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2018.04.03 13:32:08 0: Featurelevel: 5.8
2018.04.03 13:32:08 0: Server started with 28 defined entities (fhem.pl:16453/2018-03-20 perl:5.024001 os:linux user:root pid:5721)
2018.04.03 13:32:20 2: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server process started for interface BidCos-RF with PID=5938
2018.04.03 13:32:20 2: CCURPC: [d_rpcBidCos_RF] Initializing RPC server CB2001 for interface BidCos-RF
2018.04.03 13:32:20 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server starting
2018.04.03 13:32:20 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process started for interface HmIP-RF with PID=5939
2018.04.03 13:32:20 2: CCURPC: [d_rpcHmIP_RF] Initializing RPC server CB2010 for interface HmIP-RF
2018.04.03 13:32:20 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server starting
2018.04.03 13:32:20 2: HMCCURPCPROC: [d_rpcBidCos_RF] Callback server CB2001 created. Listening on port 7411
2018.04.03 13:32:20 2: CCURPC: [d_rpcBidCos_RF] CB2001 accepting connections. PID=5938
2018.04.03 13:32:20 2: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB2001 enters server loop
2018.04.03 13:32:20 2: HMCCURPCPROC: [d_rpcBidCos_RF] Registering callback http://172.17.0.3:7411/fh2001 of type A with ID CB2001 at http://192.168.178.20:2001
2018.04.03 13:32:20 2: HMCCURPCPROC: [d_rpcHmIP_RF] Callback server CB2010 created. Listening on port 7420
2018.04.03 13:32:20 2: CCURPC: [d_rpcHmIP_RF] CB2010 accepting connections. PID=5939
2018.04.03 13:34:27 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB2001 running
2018.04.03 13:34:27 2: HMCCURPCPROC: [d_rpcHmIP_RF] Checking if RPC server process is running
2018.04.03 13:34:27 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process not running. Cleaning up
2018.04.03 13:34:27 1: HMCCURPCPROC: [d_rpcHmIP_RF] Housekeeping called. Cleaning up RPC environment
2018.04.03 13:34:27 2: HMCCURPCPROC: [d_rpcHmIP_RF] Sending signal INT to RPC server process CB2010 with PID=5939
2018.04.03 13:34:27 2: CCURPC: [d_rpcHmIP_RF] CB2010 received signal INT
2018.04.03 13:34:27 1: CCURPC: [d_rpcHmIP_RF] RPC server CB2010 stopped handling connections. PID=5939
2018.04.03 13:34:27 2: CCURPC: [d_rpcHmIP_RF] Number of I/O errors = 0
2018.04.03 13:34:29 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process CB2010 deleted
2018.04.03 13:34:29 2: HMCCURPCPROC: [d_rpcHmIP_RF] Stop I/O handling

CUU Messages:

Code Auswählen
Apr  3 13:33:24 homematic-ccu2 user.err rfd: XmlRpc transport error calling system.listMethods({"CB2001"}) on http://172.17.0.3:7411/fh2001:
Apr  3 13:34:27 homematic-ccu2 user.err rfd: XmlRpc transport error calling listDevices({"CB2001"}) on http://172.17.0.3:7411/fh2001:

Laire

#5
03 April 2018, 16:38:20
Problem behoben mein FHEM läuft im Docker.

Lösung für das Problem: https://forum.fhem.de/index.php/topic,79767.msg718054.html#msg718054

zap

#6
03 April 2018, 17:23:42
Ah ja. Sowas in der Art hatte ich vermutet. Immer der neumodische Kram ;-)
2xCCU3 mit ca. 100 Aktoren, Sensoren
Entwicklung: FHEM auf Proxmox Debian VM
Produktiv inzwischen auf Home Assistant gewechselt.
Maintainer: HMCCU, (Fully, AndroidDB)
Drucken
Nach oben Seiten1
Benutzer-Aktionen