HMCCU: Neue Version 4.2 mit neuem RPC Server verfügbar

[Xervek]

Hallo zap,

vielen lieben Dank für deine ganze Arbeit hier!

Ich konnte meine Fehlermeldungen mittlerweile scheinbar mit einem Timeout von 6 Sekunden in den Griff bekommen. Es wurde merklich weniger bei 5, mit 6 habe ich nun seit knapp zwei Wochen keinen Timeout mehr gesehen. Zusätzlich habe ich dann jetzt das "nonBlocking" gesetzt, mal schauen wie es damit dann jetzt weiter geht. So ist das alles schon mal sehr gut.

Vielen Dank noch einmal!

Liebe Grüße

[zap]

Morgen steht die Version 4.2.005 von HMCCU per Update zur Verfügung.

Änderungen:

• Befehl "get firmware" geändert
• Der externe RPC Server HMCCURPCPROC unterstützt nun mehrere FHEM Instanzen, die sich mit der gleichen CCU verbinden
• Anzahl Events bei Verwendung des Attributs ccuaggregate reduziert
• Auf Homematic Script basierende Befehle (z.B. Setzen von Datenpunkten) optimiert

[Chris8888]

Hallo Zap,

eben mal upgedatet und soweit läuft es wie gewohnt.
Beim Start kommt allerdings ein Eintrag im Log:

Code Auswählen Erweitern


...
2018.04.20 19:41:26 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/88_HMCCURPCPROC.pm line 1354.
2018.04.20 19:41:26 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process started for interface HmIP-RF with PID=969
...


Viele Grüße
Christian

[weldel60]

Hallo,

nach Umstellung habe ich im Log Seitenweise folgende Einträge:

2018.04.22 23:36:58 0: HMCCURPCPROC: [d_rpcHmIP_RF] Received SL event for unknown RPC server CB201006210
2018.04.22 23:36:58 0: HMCCURPCPROC: [d_rpcHmIP_RF] Received SL event for unknown RPC server CB201006210
2018.04.22 23:36:58 0: HMCCURPCPROC: [d_rpcHmIP_RF] Received SL event for unknown RPC server CB201006210

Kann jemand helfen?

Es laufen 3 Server:
HMCCURPCPROC
CCU RPC BidCos-RF running/OK
CCU RPC HmIP-RF running/OK
CCU RPC VirtualDevices running/OK

[zap]

sonst irgendwelche Fehlermeldungen speziell beim Start der RPC Server? Kannst du mal die Logmeldungen posten, die während dem Start des RPC Servers geschrieben werden? dazu bei den RPC Devices und beim IO Device verbose mindestens auf 2 setzen.

[weldel60]

Hallo ZAP,

hier der LOG-Auszug nach RPC-Serverstart:

2018.04.23 08:04:23 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server starting
2018.04.23 08:04:23 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process started for interface HmIP-RF with PID=5835
2018.04.23 08:04:23 2: CCURPC: [d_rpcHmIP_RF] Initializing RPC server CB201006203 for interface HmIP-RF
2018.04.23 08:04:23 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server starting
2018.04.23 08:04:23 1: HMCCURPCPROC: [d_rpcVirtualDevices] RPC server starting
2018.04.23 08:04:23 2: HMCCURPCPROC: [d_rpcHmIP_RF] Callback server CB201006203 created. Listening on port 7420
2018.04.23 08:04:23 2: CCURPC: [d_rpcHmIP_RF] CB201006203 accepting connections. PID=5835
2018.04.23 08:04:23 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB200106221 running
2018.04.23 08:04:23 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server CB201006203 enters server loop
2018.04.23 08:04:23 2: HMCCURPCPROC: [d_rpcHmIP_RF] Registering callback http://192.168.178.62:7420/fh2010 of type A with ID CB201006203 at http://192.168.178.78:2010
2018.04.23 08:04:23 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server CB201006203 running
2018.04.23 08:04:24 2: CCURPC: [d_rpcHmIP_RF] CB201006203 NewDevice received 74 device and channel specifications
2018.04.23 08:04:33 1: HMCCURPCPROC: [d_rpcVirtualDevices] RPC server CB929206233 running
2018.04.23 08:04:33 1: HMCCU: [d_ccu] All RPC servers running
2018.04.23 08:04:34 2: HMCCU: [d_ccu] Updated devices. Success=11 Failed=0
2018.04.23 08:04:34 2: HMCCU: Duplicate name for device/channel HM-Thermostat-Kueche address=000A97099CEA34:1 in CCU.
2018.04.23 08:04:34 2: HMCCU: Duplicate name for device/channel HM-Thermostat-Kueche address=000A97099CEA34 in CCU.

[weldel60]

Habe den Thermostat Küche in der CCU und Fhem entfernt und neu angelegt.

Diesen Fehler im Log bin ich los.

HMCCURPCPROC: [d_rpcHmIP_RF] Received SL event for unknown RPC server CB201006210
laufen weiter.

[zap]

Ich vermute, dass da noch ein RPC Prozess im Hintergrund lief. Die IDs unterscheiden sich:

Beim Starten = CB201006203

Bei der Meldung = CB201006210

Im Zweifel mal FHEM stoppen, dann prüfen, ob noch weitere Prozesse mit Owner = fhem laufen und die manuell killen.

@Chris8888: Das Problem mit der Meldung bei Dir habe ich gefunden. Wird behoben.

[Jamo]

Hallo Zap,
wie bei user weldel60, bekomme ich jetzt auch seit 2 Tagen folgende Fehlermeldungen zuhauf:

Code Auswählen Erweitern

HMCCURPCPROC: [d_rpcHmIP_RF] Received SL event for unknown RPC server CB201001571
Bei mir läuft FHEM unter root.

Ich habe dann ein grep auf einen rpc process gemacht

Code Auswählen Erweitern

pi@jessie:/opt/fhem $ ps -auxw | grep rpc
root        40  0.0  0.0      0     0 ?        I<   Apr23   0:00 [rpciod]
pi       31801  0.0  0.0   4372   548 pts/0    S+   19:32   0:00 grep --color=auto rpc
Ich kann den prozess aber gar nicht 'killen', bei 'sudo  kill -9 40' passiert gar nichts.

Die Fehlermeldung bleibt. Was kann ich tun?

[zap]

Bitte den Prozess nicht killen!! Das ist der Linux RPC Daemon. Der HMCCU RPC Server ist nichts anderes als ein fhem.pl Prozess.

Also

Code Auswählen Erweitern


ps -ef | grep fhem

Die PID des Prozesses wird beim Starten in das FHEM Log geschrieben

[weldel60]

Nochmals ein Auszug des Log.
Ist es nun der rpcBidCos 2001?


2018.04.24 10:55:47 1: HMCCU: Device d_ccu. Initialized version 4.2.005
2018.04.24 10:55:49 1: HMCCU: Read 12 devices with 123 channels from CCU 192.168.178.78
2018.04.24 10:55:49 1: HMCCU: Read 3 interfaces from CCU 192.168.178.78
2018.04.24 10:55:49 1: HMCCURPCPROC: [d_rpcBidCos_RF] Initialized version 1.0.003 for interface BidCos-RF
2018.04.24 10:55:49 1: HMCCURPCPROC: [d_rpcHmIP_RF] Initialized version 1.0.003 for interface HmIP-RF
2018.04.24 10:55:49 1: HMCCURPCPROC: [d_rpcVirtualDevices] Initialized version 1.0.003 for interface VirtualDevices
2018.04.24 10:55:49 1: Including ./log/fhem.save
2018.04.24 10:55:51 0: HMCCU: Start of RPC server after FHEM initialization in 12 seconds
2018.04.24 10:55:58 0: Featurelevel: 5.8
2018.04.24 10:55:58 0: Server started with 216 defined entities (fhem.pl:16609/2018-04-13 perl:5.024001 os:linux user:fhem pid:587)
2018.04.24 10:56:03 1: PERL WARNING: Use of uninitialized value in concatenation (.) or string at ./FHEM/88_HMCCURPCPROC.pm line 1354.
2018.04.24 10:56:03 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server starting
2018.04.24 10:56:03 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server process started for interface HmIP-RF with PID=976
2018.04.24 10:56:03 2: CCURPC: [d_rpcHmIP_RF] Initializing RPC server CB201006223 for interface HmIP-RF
2018.04.24 10:56:03 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server starting
2018.04.24 10:56:03 1: HMCCURPCPROC: [d_rpcVirtualDevices] RPC server starting
2018.04.24 10:56:03 2: HMCCURPCPROC: [d_rpcHmIP_RF] Callback server CB201006223 created. Listening on port 7420
2018.04.24 10:56:03 2: CCURPC: [d_rpcHmIP_RF] CB201006223 accepting connections. PID=976
2018.04.24 10:56:03 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB200106223 running
2018.04.24 10:56:03 2: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server CB201006223 enters server loop
2018.04.24 10:56:03 2: HMCCURPCPROC: [d_rpcHmIP_RF] Registering callback http://192.168.178.62:7420/fh2010 of type A with ID CB201006223 at http://192.168.178.78:2010
2018.04.24 10:56:03 1: HMCCURPCPROC: [d_rpcHmIP_RF] RPC server CB201006223 running
2018.04.24 10:56:04 2: CCURPC: [d_rpcHmIP_RF] CB201006223 NewDevice received 74 device and channel specifications
2018.04.24 10:57:40 1: HMCCURPCPROC: [d_rpcVirtualDevices] RPC server CB929206231 running
2018.04.24 10:57:40 4: HMCCU: [d_ccu] Set rpcstate to running
2018.04.24 10:57:40 1: HMCCU: [d_ccu] All RPC servers running
2018.04.24 10:57:47 2: HMCCU: [d_ccu] Updated devices. Success=11 Failed=0
2018.04.24 23:05:41 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:05:41 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:05:41 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:12:29 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:12:29 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:12:29 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:13:03 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:13:03 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264
2018.04.24 23:13:03 0: HMCCURPCPROC: [d_rpcBidCos_RF] Received SL event for unknown RPC server CB200106264

[zap]

Ja scheint so. Kannst du mal bitte folgendes aus dem Log extrahieren:

grep CB200106264 logfile | grep -v Received

[weldel60]

So.......

2018.04.24 10:50:47 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server CB200106264 running
2018.04.24 10:56:51 1: HMCCURPCPROC: [d_rpcBidCos_RF] Stopping RPC server CB200106264
2018.04.24 10:56:51 1: HMCCURPCPROC: [d_rpcBidCos_RF] Deregistering RPC server http://192.168.178.62:7411/fh2001 with ID CB200106264 at http://192.168.178.78:2001
2018.04.24 10:56:51 1: HMCCURPCPROC: [d_rpcBidCos_RF] Failed to deregister RPC server CB200106264
2018.04.24 10:56:51 1: CCURPC: [d_rpcBidCos_RF] RPC server CB200106264 stopped handling connections. PID=1107
2018.04.24 10:56:54 1: HMCCURPCPROC: [d_rpcBidCos_RF] RPC server process CB200106264 not runnning

[Brause]

Hallo zusammen

Ich hatte auch die " Received SL event for unknown RPC server" Meldungen.
auch direkt nach kompletten Rechner reboot.

Ein Neustart der CCU war dann die Lösung.
Danach hatte ich keine Meldungen mehr

Gruss Brause

[weldel60]

Hallo Brause,

das ist mir auch aufgefallen.

CCU - Neustart und dann RPI - Neustart, dann war erst mal Ruhe.

Einen halben Tag später, starteten die Fehlermeldungen jedoch wieder.