HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:

Begonnen von Rewe2000, 08 März 2019, 20:40:43

Vorheriges Thema - Nächstes Thema

Ban

Zitat von: Rewe2000 am 22 März 2019, 16:46:48
Hallo,

hat denn echt niemand sonst ähnliche Probleme?
Bei mir ist es derzeit so dramatisch, dass sich nun immer zu 100% die CCU2 aufhängt, wenn ich die RPC-Server starte. Diese reagiert dann nicht mehr auf HmIP-Geräte, nur ein Neustart der CCU2 behebt das Problem wieder.

Das hört sich sehr ähnlich zu meinem Verhalten an.

Ich habe auch starke Probleme, aber in Verbindung mit der CCU3.
Bei mir kommt auch keine Antwort von der CCU3 zurück.
Noch merkwürdiger ist, dass nach einem Neustart der CCU3 diese einwandfrei funktioniert.
Starte ich fhem neu, damit sich die rpcserver neu verbinden, wird die CCU3 so langsam, dass selbst die internen Programme der CCU3 nicht mehr ausgeführt werden.
Auch die Oberfläche der CCU3 reagiert kaum noch. Seitenaufbau bis zu einer Minute und schalten kann ich darüber dann auch nichts mehr.
Wie gesagt, alles erst, wenn sich fhem verbinden will.
Habe erst gedacht, dass irgendein Task die CPU auslastet. Aber da ist mit Top nicht zu sehen. Die Oberfläche verhält sich, als ob keine Rechenzeit zur Verfügung steht,
die CPU ist aber in dem Moment auf ca. 5%.
Habe noch kein Schema gefunden. Irgendwann, nach mehreren CCU3 und fhem Neustarts, geht alles wieder.
Habe sogar die CCU3 ausgetauscht, da ich dachte, dass diese evtl. defekt ist.
Aber das Problem besteht weiterhin.

Weiterhin ist interessant, dass dies nur die IP Geräte betrifft. Ich habe noch 2 Homematic HM-LC-Sw1PBU-FM über die CCU3 laufen.
Die kann ich über fhem weiterhin bedienen.


@Rewe2000: Ist bei dir nach einem Neustart der CCU2 die Oberfläche schnell und nach dem Reconnectversuch von fhem auch langsam?

Grüße,
Ban

PS: Heute habe ich nach dem Update der CCU auf Firmware 3.43.16 fast 2 Stunden probiert. Da nach dem Update die CCU3 neustartet, hatte ich wieder das oben beschrieben Verhalten. Nichts ging, nachdem fhem reconnecten wollte. Jedesmal war die CCU3 fast nicht mehr erreichbar. Jetzt nach einem Neustart der CCU3, ohne Neustart von fhem, läuft wieder alles einwandfrei. Eigentlich dürften die rpcserver nicht connected sein. Was mir aufgefallen ist, CCU RPC BidCos-RF und CCU RPC HmIP-RF haben sich neuinstalliert, ohne dass ich diese gelöscht hatte. Gemerkt habe ich das nur, da mein devStateIcon weg ist. Ist das evtl. die Lösung. Die rpcserver neu zu erstellen?


Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

Rewe2000

Hallo Ban,

es freut mich zwar nicht, dass auch noch jemand anderer Probleme hat, aber es beruhigt mich ein wenig.
Ich habe echt bisher alle Register gezogen und bekomme Fhem in Verbindung mit der CCU2 derzeit nicht mehr zum laufen.

Die CCU2 und auch Fhem läuft alleine absolut prima und ohne jegliche Hänger, bis ich die RPC-Server starte, dann bricht das Chaos los.
Fhem und die CCU2 hängt für ca. 3-5 Minuten, Fhem läuft dann wieder, allerdings ohne Verbindung zur CCU2. Meine CCU2 erholt sich überhaupt nicht mehr, diese erkennt wie bei dir keine HmIP Geräte mehr. Ich meine auch, HM-Geräte sollten sich noch bedienen lassen, meine HM-Wetterstation wurde noch aktualisiert, ich prüfe das morgen.

Ich habe schon alles was möglich ist gewechselt und bin mit der Firmware und mit Backups weit in die Vergangenheit zurückgegangen, ohne jeglichen Erfolg. Ich vermute schon irgendwelche Firmware von HmIP-Geräten, was ja eigentlich sehr unwahrscheinlich ist.
Angefangen hat dies bei mir schleichend, die Erstverbindung CCU2 und Fhem hat immer länger gedauert, dann musste ich von Hand die RPC Server stoppen und Neustarten, jetzt geht dies auch nicht mehr.
Der erste Verbindungsversuch wird immer mit "HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:" beantwortet. Was hast du für Meldungen im Log, wenn du die Probleme bemerkst?

Morgen kommt meine CCU3, bin mal gespannt ob der Fehler weg ist.
Wenn nicht, so bleibt mir nur ein Versuchsaufbau mit Minimalkonfiguration CCU3 und Fhem, an die Arbeit will ich jetzt noch überhaupt nicht denken.
Wie hast du von CCU2 auf CCU3 migriert? Mit Backup - Restore oder Neuanlernen.

Für heute mach ich Feierabend, nachdem ich ca. 10-15 Neustarts der CCU2 hinter mir habe.

Gruß Reinhard

P.s. Hört sich verdammt nach dem gleichen Problem an.
Hast du Gruppen in der CCU3 angelegt?
Ich meine fast seitdem ich meine 5 Heizgruppen angelegt hatte, ist mir das Verhalten erst aufgefallen.
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

Ban

Hallo Rewe2000,

mir ging es vorhin ähnlich wie dir. Ich habe mich "gefreut", dass ich jemanden gefunden habe, der dasselbe Problem hat.
Habe schon an mir gezweifelt.
Auch bei mir läuft die CCU3 und fhem allein einwandfrei. Auch bei mir war es nicht von Anfang an so.
Unterschied zu dir. Ich habe irgendwann einen Punkt, an dem läuft alles wieder einwandfrei. Dann machen auch keinen fhem Neustarts mehr etwas aus.
Nur ein CCU3 Neustart und alles geht wieder von vorne los.

Deine Meldungen im Log habe ich auch:  HMCCU: [HMIP_CCU] HMScript failed. http://192.168.10.14:8181/tclrega.exe: Select timeout/error:

Ich habe noch die CCU2 und wollte wieder zurück, um zu schauen, ob es dort weg ist:-)

Gruppen habe ich keine. Alles was Heizung angeht, habe ich mit  Homematic ohne IP direkt über fhem realisiert. Das läuft alles mit VCCU mit 2 HM-Lan direkt an fhem.
Die CCU3 übernimmt bei mir alles was Licht und Wassermeldung angeht. Mit folgenden Geräten: HmIP-SWD, HMIP-PS, HMIP-PSM, HmIP-BSL, HmIP-FSM, HMIP-WRC2, HmIP-BRC2, HmIP-RC8, HmIP-WRC6 usw.

Ich bin von der CCU2 auf die CCU3 mit Backup und Restore. Auf den ersten Blick hat das einwandfrei funktioniert. Habe die Probleme erst später bekommen.

Wie viel Geräte hast du in der CCU? Vielleicht sind wir an der Grenze?
Mein HMCCU sagt: Read 46 devices with 395 channels from CCU
Lese immer von einer möglichen Grenze bei 400 Channels.
Aber die CCU allein läuft ja einwandfrei.

Viele Grüße,
Ban


Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

Ban

Zitat von: Ban am 22 März 2019, 22:17:04
Unterschied zu dir. Ich habe irgendwann einen Punkt, an dem läuft alles wieder einwandfrei. Dann machen auch keinen fhem Neustarts mehr etwas aus.
Nur ein CCU3 Neustart und alles geht wieder von vorne los.

Hallo Rewe2000,

so, jetzt bin ich wohl auch an dem Punkt, das jeder fhem Neustart das Problem auslöst.
Gestern ging alles einwandfrei. Heute morgen hatte BidCos_RF keine Rückmeldung von der CCU3 mehr bekommen.
HMCCURPCPROC: [d_rpc010014BidCos_RF] Received no events from interface CB2001010007010014 for 600.918122768402 seconds

Deswegen habe ich fhem neu gestartet.
Ergebnis wieder:


2019.03.23 07:59:04 2: HMCCU: [HMIP_CCU] Get RPC device for interface BidCos-RF
2019.03.23 07:59:04 2: HMCCU: [HMIP_CCU] Get RPC device for interface HmIP-RF
2019.03.23 07:59:04 2: HMCCURPCPROC: [d_rpc010014BidCos_RF] RPC server process started for interface BidCos-RF with PID=843
2019.03.23 07:59:04 2: CCURPC: [d_rpc010014BidCos_RF] Initializing RPC server CB2001010007010014 for interface BidCos-RF
2019.03.23 07:59:04 1: HMCCURPCPROC: [d_rpc010014BidCos_RF] RPC server starting
2019.03.23 07:59:04 2: HMCCURPCPROC: [d_rpc010014HmIP_RF] RPC server process started for interface HmIP-RF with PID=844
2019.03.23 07:59:04 2: CCURPC: [d_rpc010014HmIP_RF] Initializing RPC server CB2010010007010014 for interface HmIP-RF
2019.03.23 07:59:04 1: HMCCURPCPROC: [d_rpc010014HmIP_RF] RPC server starting
2019.03.23 07:59:05 2: HMCCURPCPROC: [d_rpc010014BidCos_RF] Callback server CB2001010007010014 created. Listening on port 7411
2019.03.23 07:59:05 2: HMCCURPCPROC: [d_rpc010014HmIP_RF] Callback server CB2010010007010014 created. Listening on port 7420
2019.03.23 07:59:05 2: CCURPC: [d_rpc010014BidCos_RF] CB2001010007010014 accepting connections. PID=843
2019.03.23 07:59:05 2: CCURPC: [d_rpc010014HmIP_RF] CB2010010007010014 accepting connections. PID=844
2019.03.23 07:59:05 2: HMCCURPCPROC: [d_rpc010014BidCos_RF] RPC server CB2001010007010014 enters server loop
2019.03.23 07:59:05 2: HMCCURPCPROC: [d_rpc010014BidCos_RF] Registering callback [url=http://192.168.10.7:7411/fh2001]http://192.168.10.7:7411/fh2001[/url] of type A with ID CB2001010007010014 at [url=http://192.168.10.14:2001]http://192.168.10.14:2001[/url]
2019.03.23 07:59:05 1: HMCCURPCPROC: [d_rpc010014BidCos_RF] RPC server CB2001010007010014 running
2019.03.23 07:59:06 1: HMCCURPCPROC: [d_rpc010014BidCos_RF] Scheduled CCU ping every 300 seconds
2019.03.23 07:59:06 2: HMCCURPCPROC: [d_rpc010014HmIP_RF] RPC server CB2010010007010014 enters server loop
2019.03.23 07:59:06 2: HMCCURPCPROC: [d_rpc010014HmIP_RF] Registering callback [url=http://192.168.10.7:7420/fh2010]http://192.168.10.7:7420/fh2010[/url] of type A with ID CB2010010007010014 at [url=http://192.168.10.14:2010]http://192.168.10.14:2010[/url]
2019.03.23 07:59:06 1: HMCCURPCPROC: [d_rpc010014HmIP_RF] RPC server CB2010010007010014 running
2019.03.23 07:59:06 1: HMCCU: [HMIP_CCU] All RPC servers running
2019.03.23 07:59:06 2: CCURPC: [d_rpc010014BidCos_RF] CB2001010007010014 NewDevice received 78 device and channel specifications
2019.03.23 07:59:07 2: CCURPC: [d_rpc010014HmIP_RF] CB2010010007010014 NewDevice received 363 device and channel specifications
2019.03.23 07:59:11 2: HMCCU: [HMIP_CCU] HMScript failed. [url=http://192.168.10.14:8181/tclrega.exe]http://192.168.10.14:8181/tclrega.exe[/url]: Select timeout/error:
2019.03.23 07:59:11 2: HMCCU: Update of device 001898A9926292 failed
2019.03.23 07:59:15 2: HMCCU: [HMIP_CCU] HMScript failed. [url=http://192.168.10.14:8181/tclrega.exe]http://192.168.10.14:8181/tclrega.exe[/url]: Select timeout/error:
...
...


und nichts lässt sich mehr bedienen. Es laufen nur noch die Direktverknüpfungen.
Ausnahme, die HM Geräte in der CCU3. Die lassen sich über fhem bedienen.

Jetzt habe ich auch folgende Meldung auf der CCU3 Oberfläche bekommen:


HmIP-RF
Eine Komponente der Homematic Zentrale reagiert nicht mehr.
Hierfür kann es eine Reihe von Ursachen geben:

es besteht keine Netzwerk-Verbindung
die Stromversorung der HomeMatic Zentrale wurde unterbrochen
mindestens eine Komponente der HomeMatic Zentrale ist abgestürzt
Überprüfen Sie die Netzwerk-Verbindung und die Stromversorgung der HomeMatic Zentrale. Starten Sie ggf. die HomeMatic Zentrale neu.



Viele Grüße,
Ban
Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

zap

Du könntest beim Neustart der CCU oder FHEM vorher mal die RPC Server stoppen und mit dem Neustart warten, bis sie als inaktiv angezeigt werden. Würde mich interessieren,ob das Problem dann auch auftritt.

Übrigens: nach gerade mal 10 Min ohne Events würde ich nichts neu starten. Kann immer mal vorkommen, kann aber auch sein, dass auf der CCU etwas abgeraucht ist.
2xCCU3, Fenster, Rollläden, Themostate, Stromzähler, Steckdosen ...)
Entwicklung: FHEM auf AMD NUC (Ubuntu)
Produktiv inzwischen auf Home Assistant gewechselt.
Maintainer: FULLY, Meteohub, HMCCU, AndroidDB

Ban

Hallo zap,

bin im Moment am Testen. Werde das direkt ausprobieren.

Habe es getestet. Leider gleiches Ergebnis.

-CCU3 Neustart > Alle Programme der CCU3 funktionieren. Oberfläche der CCU3 ist schnell
-fhem RPC-Server gestoppt und shutdown restart > Keine Programme der CCU3 funktionieren. Oberfläche langsam

Jetzt werde ich folgendes testen.
-CCU3 Neustart
-fhem RPC-Server löschen und Neustart
Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

Ban

Zitat von: zap am 23 März 2019, 09:02:46
Übrigens: nach gerade mal 10 Min ohne Events würde ich nichts neu starten. Kann immer mal vorkommen, kann aber auch sein, dass auf der CCU etwas abgeraucht ist.

Ich habe es auch schon mit Warten versucht.
Abends die CCU3 und fhem neugestartet. Über Nacht laufen gelassen.
Morgens gleiches Bild. CCU3 hat nicht mehr funktioniert.

Test mit CCU3 Neustart und fhem RPC-Server löschen ist durch.
Leider war es vorher wohl Zufall.

-CCU3 Neustart > Alle Programme der CCU3 funktionieren. Oberfläche schnell
-fhem RPC-Server gelöscht und shutdown restart > Keine Programme der CCU3 funktionieren. Oberfläche langsam

- noch ein fhem Neustart, CCU3 wurde nicht neu gestartet. Erstmal keine Fehlermeldungen in fhem. Aber die Programme der CCU3 gehen weiterhin nicht.
Einmalig ist ein Schaltbefehl von fhem zur CCU3 durchgegangen. Das Licht wurde geschaltet. Rückmeldung über den Schaltzustand gab es keine.
Ein zweiter Schaltbefehl wird mit:
HMCCUCHN: HMIP_Licht_Flur_OG1 Execution of CCU script or command failed
HMCCU: [HMIP_CCU] Error during HTTP request: http://192.168.10.14:8181/tclrega.exe: Select timeout/error:
quitiert.
- Neustart CCU3 ohne Neustart von fhem > gleiches Bild. Aber hin und wieder geht ein Schaltbefehl durch.

Wieder die Fehlermeldung auf der CCU3 Oberfläche:


HmIP-RF
Eine Komponente der Homematic Zentrale reagiert nicht mehr.
Hierfür kann es eine Reihe von Ursachen geben:

es besteht keine Netzwerk-Verbindung
die Stromversorung der HomeMatic Zentrale wurde unterbrochen
mindestens eine Komponente der HomeMatic Zentrale ist abgestürzt
Überprüfen Sie die Netzwerk-Verbindung und die Stromversorgung der HomeMatic Zentrale. Starten Sie ggf. die HomeMatic Zentrale neu.


Hab gerade keine Idee mehr.

Überlege wie ich weitermache.
Entweder mit der CCU2 neu anfangen und einzelne Aktoren darauf umziehen und schauen, wie lange das gut geht oder mehr ins debugging der CCU3 und HMCCU einsteigen.
Das Problem für zap, ich kenne das selbst, ohne das Problem nachstellen zu können bekommt man da auch nix gefixt/angepasst :'(

Bringt es etwas, wenn ich mir noch einen Charly baue/kaufe? Bekomme ich da mehr Infos, bzw. habe ich da mehr Kontrolle?

Noch ein Nachtrag:
Ich habe die CCU3 neugestartet, dass die Grundfunktionen wieder funktionieren.
Fhem oder RPC-Server wurden nicht neu gestartet.
D.h. ich empfange aktuell keine Events von der CCU3. Das Steuern der Aktoren von fhem über die CCU3 geht einwandfrei und die CCU3 verhält sich auch normal.
D.h. für mich, es hängt wirklich "nur" an dem Abfragen der Events. Das bringt die CCU durcheinander.
Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

Rewe2000

Hallo Ban,

da hat es dich ja genauso wie mich erwischt.

An der Anzahl der Geräte, denke ich sollte es nicht liegen, bei mir sieht es so aus:
2019.03.01 18:04:21 3: COC device opened
2019.03.01 18:04:21 2: Switched COC rfmode to HomeMatic
2019.03.01 18:04:23 1: HMCCU: [CCU2] Initialized version 4.3.014
2019.03.01 18:04:23 1: HMCCU: [CCU2] HMCCU: Initializing device
2019.03.01 18:04:28 1: HMCCU: [CCU2] HMCCU: Read 64 devices with 329 channels from CCU 192.168.1.32
2019.03.01 18:04:28 1: HMCCU: [CCU2] HMCCU: Read 3 interfaces from CCU 192.168.1.32
2019.03.01 18:04:28 1: HMCCU: [CCU2] HMCCU: Read 12 programs from CCU 192.168.1.32
2019.03.01 18:04:28 1: HMCCU: [CCU2] HMCCU: Read 5 virtual groups from CCU 192.168.1.32


Ich hab nicht die leiseste Spur, woran dieses Verhalten bei mir liegen könnte. Ich habe die CCU3 und Fhem (nicht gleichzeitig, sondern nacheinander) auf den Stand von Dezember 2018 gebracht, absolut gleiches Verhalten. Selbst meinen Router hab ich außen vorgelassen, wegen Verdacht auf Firewall oder Filter, auch das hat nicht das Problem gelöst.

Gestern habe ich dann noch Linux auf den neuesten Stand gebracht, hat auch nicht mein Problem gelöst.

Ich starte derzeit die RPC-Server überhaupt nicht mehr, damit wenigstens Fhem und meine Modbuskommunikation zu meiner WAGO Steuerung läuft.
Ich versuche mal genau zu beschreiben, wie das Auftreten des Fehlers bei mir abläuft, eventuell gibt es ja noch Ideen was man noch machen könnte.

Versuch1:

  • Raspi läuft mit stehenden RPC-Servern
  • CCU2 wird neu gestartet und gewartet bis diese stabil läuft
  • Nur die RPC-Server werden nun unter Fhem gestartet, Fhem und CCU2 hängt für mindestens 3-5 Minuten und ist über WEB nicht mehr zu erreichen
  • CCU2 reagiert nicht mehr auf HmIP Geräte, HM-Geräte werden noch angezeigt, CCU2 WEB noch erreichbar, jedoch etwas langsamer

Versuch 2:

  • CCU2 läuft stabil, alle Geräte können bedient werden
  • Fhem "shutdown restart" oder unter Linux sudo reboot
  • Fhem startet etwas länger als normal, im Log steht zu 100% immer folgende timeout Meldung
2019.03.10 11:41:20 1: Including fhem.cfg
2019.03.10 11:41:20 3: telnetPort: port 7072 opened
2019.03.10 11:41:20 3: WEBphone: port 8084 opened
2019.03.10 11:41:20 3: WEBtablet: port 8085 opened
2019.03.10 11:41:21 2: eventTypes: loaded 5228 events from ./log/eventTypes.txt
2019.03.10 11:41:21 3: Opening COC device /dev/ttyAMA0
2019.03.10 11:41:21 3: Setting COC serial parameters to 38400,8,N,1
2019.03.10 11:41:21 3: COC: Possible commands: mBbCFiAZGMYRTVWXefltuxz
2019.03.10 11:41:21 3: COC device opened
2019.03.10 11:41:21 2: Switched COC rfmode to HomeMatic
2019.03.10 11:41:23 1: HMCCU: [CCU2] Initialized version 4.3.014
2019.03.10 11:41:23 1: HMCCU: [CCU2] HMCCU: Initializing device
2019.03.10 11:41:27 2: HMCCU: [CCU2] HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:41:27 1: HMCCU: [CCU2] HMCCU: Error while reading device list from CCU 192.168.1.32
2019.03.10 11:41:27 1: HMCCU: [CCU2] Scheduling delayed initialization in 180 seconds
2019.03.10 11:41:27 2: HMCCUDEV: [HM_OG_FKE1_BueroReinhard] Cannot detect IO device, maybe CCU not ready. Trying later ...

  • für die nächsten 3 Minuten ist die CCU2 noch für sich, normal zu bedienen
  • nach 3 Minuten starten nun die RPC-Server und das Chaos bricht los, im Log stehen folgende Meldungen, fast alle mit timeout
2019.03.10 11:41:32 1: define d_rpc001032BidCos_RF HMCCURPCPROC http://192.168.1.32 BidCos-RF: Invalid port or interface BidCos-RF
2019.03.10 11:41:32 1: define d_rpc001032HmIP_RF HMCCURPCPROC http://192.168.1.32 HmIP-RF: Invalid port or interface HmIP-RF
2019.03.10 11:41:32 1: define d_rpc001032VirtualDevices HMCCURPCPROC http://192.168.1.32 VirtualDevices: Invalid port or interface VirtualDevices
2019.03.10 11:41:32 1: Including ./log/fhem.save
2019.03.10 11:41:32 1: configfile: Invalid port or interface BidCos-RF
Please define d_rpc001032BidCos_RF 5c84d8d0-f33f-7df9-95d2-2b6256d07014e91b first
Invalid port or interface HmIP-RF
Please define d_rpc001032HmIP_RF 5c84d8d0-f33f-7df9-2445-d5db0c7475e66722 first
Invalid port or interface VirtualDevices
Please define d_rpc001032VirtualDevices 5c84d8d0-f33f-7df9-c9a6-b4fdaed3633cda2f first
./log/fhem.save: Please define d_rpc001032BidCos_RF first
Please define d_rpc001032BidCos_RF first
Please define d_rpc001032BidCos_RF first

2019.03.10 11:41:32 3: Device HM_4DC29E added to ActionDetector with 000:10 time
2019.03.10 11:41:34 0: HMCCU: Start of RPC server after FHEM initialization in 200 seconds
2019.03.10 11:41:34 3: Opening WAGO device 192.168.1.30:502
2019.03.10 11:41:34 3: WAGO device opened
2019.03.10 11:41:34 1: usb create starting
2019.03.10 11:41:34 3: Probing ZWDongle device /dev/serial1
2019.03.10 11:41:35 3: Probing CUL device /dev/ttyS0
2019.03.10 11:41:35 1: usb create end
2019.03.10 11:41:35 0: Featurelevel: 5.9
2019.03.10 11:41:35 0: Server started with 383 defined entities (fhem.pl:18623/2019-02-17 perl:5.024001 os:linux user:fhem pid:26785)
2019.03.10 11:41:36 1: Perfmon: possible freeze starting at 11:41:21, delay is 15.07
2019.03.10 11:41:36 3: telnetForBlockingFn_1552214496: port 46075 opened
2019.03.10 11:41:36 2: ModbusTCPServer_Parse: except (code 2)
2019.03.10 11:44:27 1: HMCCU: [CCU2] HMCCU: Initializing devices
2019.03.10 11:44:30 2: HMCCU: [CCU2] Initializing 65 client devices in state 'pending'
2019.03.10 11:44:30 1: PERL WARNING: Argument "Initialized" isn't numeric in multiplication (*) at (eval 1647) line 1.
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_EG_GR_Wohnzimmer
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_OG_GR_BueroReinhard
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_EG_GR_Dusche
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_OG_GR_Schlafzimmer
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_HO_Bm_Hof_oben
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_OG_GR_Bad
2019.03.10 11:44:30 1: PERL WARNING: Argument "Initialized" isn't numeric in multiplication (*) at (eval 1688) line 1.
2019.03.10 11:44:30 1: PERL WARNING: Argument "Initialized" isn't numeric in multiplication (*) at (eval 1694) line 1.
2019.03.10 11:44:30 3: HMCCU: [CCU2] Can't initialize client device HM_HA_SS_Haus_1
2019.03.10 11:44:30 1: PERL WARNING: Argument "Initialized" isn't numeric in multiplication (*) at (eval 1710) line 1.
2019.03.10 11:44:30 1: PERL WARNING: Argument "Initialized" isn't numeric in multiplication (*) at (eval 1721) line 1.
2019.03.10 11:44:32 1: HMCCU: [CCU2] HMCCU: Read 57 devices with 289 channels from CCU 192.168.1.32
2019.03.10 11:44:32 1: HMCCU: [CCU2] HMCCU: Read 3 interfaces from CCU 192.168.1.32
2019.03.10 11:44:32 1: HMCCU: [CCU2] HMCCU: Read 17 programs from CCU 192.168.1.32
2019.03.10 11:44:32 1: HMCCU: [CCU2] HMCCU: Read 0 virtual groups from CCU 192.168.1.32
2019.03.10 11:44:32 1: Perfmon: possible freeze starting at 11:44:28, delay is 4.272
2019.03.10 11:44:54 2: HMCCU: [CCU2] Get RPC device for interface BidCos-RF
2019.03.10 11:44:54 1: HMCCU: [CCU2] No RPC device defined for interface BidCos-RF
2019.03.10 11:44:54 1: HMCCU: [CCU2] Creating new RPC device d_rpc001032BidCos_RF
2019.03.10 11:44:54 1: HMCCURPCPROC: [d_rpc001032BidCos_RF] Initialized version 1.7.001 for interface BidCos-RF with I/O device CCU2
2019.03.10 11:44:54 1: HMCCU: [CCU2] Saving FHEM config
2019.03.10 11:44:54 2: HMCCURPCPROC: [d_rpc001032BidCos_RF] RPC server process started for interface BidCos-RF with PID=26894
2019.03.10 11:44:54 2: CCURPC: [d_rpc001032BidCos_RF] Initializing RPC server CB2001001033001032 for interface BidCos-RF
2019.03.10 11:44:54 1: HMCCURPCPROC: [d_rpc001032BidCos_RF] RPC server starting
2019.03.10 11:44:54 2: HMCCURPCPROC: [d_rpc001032BidCos_RF] Callback server CB2001001033001032 created. Listening on port 7411
2019.03.10 11:44:54 2: CCURPC: [d_rpc001032BidCos_RF] CB2001001033001032 accepting connections. PID=26894
2019.03.10 11:44:54 2: HMCCURPCPROC: [d_rpc001032BidCos_RF] RPC server CB2001001033001032 enters server loop
2019.03.10 11:44:54 2: HMCCURPCPROC: [d_rpc001032BidCos_RF] Registering callback http://192.168.1.33:7411/fh2001 of type A with ID CB2001001033001032 at http://192.168.1.32:2001
2019.03.10 11:44:55 1: HMCCURPCPROC: [d_rpc001032BidCos_RF] RPC server CB2001001033001032 running
2019.03.10 11:44:55 1: HMCCU: [CCU2] All RPC servers running
2019.03.10 11:44:55 2: CCURPC: [d_rpc001032BidCos_RF] CB2001001033001032 NewDevice received 79 device and channel specifications
2019.03.10 11:44:55 2: HMCCU: [CCU2] Updated devices. Success=6 Failed=0
2019.03.10 11:44:55 1: HMCCURPCPROC: [d_rpc001032BidCos_RF] Scheduled CCU ping every 300 seconds
2019.03.10 11:46:12 2: AttrTemplates: got 64 entries
2019.03.10 11:47:34 1: Perfmon: possible freeze starting at 11:47:33, delay is 1.048
2019.03.10 11:50:08 2: HMCCUDEV: [HM_Regensensor] Error during CCU request. read from http://192.168.1.32:8181 timed out
2019.03.10 11:50:38 2: HMCCU: [CCU2] HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:50:38 1: HMCCU: [CCU2] HMCCU: CCU2 Execution of CCU script or command failed. ERROR: HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:50:38 3: at_CCU2_Systemvariablen: HMCCU: CCU2 Execution of CCU script or command failed. ERROR: HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:50:38 1: Perfmon: possible freeze starting at 11:50:31, delay is 7.721
2019.03.10 11:51:38 2: HMCCU: [CCU2] HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:51:38 1: HMCCU: [CCU2] HMCCU: CCU2 Execution of CCU script or command failed. ERROR: HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:51:38 3: at_CCU2_Systemvariablen: HMCCU: CCU2 Execution of CCU script or command failed. ERROR: HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:
2019.03.10 11:51:38 1: Perfmon: possible freeze starting at 11:51:31, delay is 7.669
2019.03.10 11:52:38 2: HMCCU: [CCU2] HMScript failed. http://192.168.1.32:8181/tclrega.exe: Select timeout/error:

  • egal welche Fehlerebene ich in Fhem einstelle, außer timeout Fehler beim Zugriff auf tclrega.exe bekomme ich nichts zu sehen
  • Die CCU2 hängt nun permanent, Fhem erholt sich wieder, natürlich ohne CCU2 Kommunikation

Versuch mal sofort nach den Fhem schutdown restart die RPC-Server zu stoppen, die CCU sollte dann weiterlaufen, jedoch ohne Kommunikation zu Fhem. Du kannst nun nach einer Zeit > 180 Sekunden die RPC-Server von Hand starten, dann sollte der gleiche Fehler auftreten.

Ich hab schon mal überlegt, mit OpenHub oder IO-Broker zu testen, aber alleine die Installation kostet mich Tage.
Mir ist nicht verständlich, weshalb immer zu 100% der erste Verbindungsversuch zur CCU2 mit timeout beantwortet wird. Sieht fast für mich wie ein Problem mit einer Firewall aus. Die Firewall auf der CCU2 habe ich auch schon in alle Richtungen verstellt, jedoch ohne Erfolg. Auch die Kennwortabfrage ist komplett ausgeschaltet.

Ich verwende für den Raspi den IP Adressbereich 192.168.1.33 und für die CCU2 192.168.1.32, habe gesten beide auf statische IP-Adressen umgestellt.
Ich dachte mir schon es könnte auch an der .1. in der IP-Adresse liegen, die meisten User werden unter 192.168.0.x unterwegs sein. Bekanntlich die letzten Strohhalme an die man sich klammert.

Bin nun echt gespannt wie es mit der CCU3 aussieht, aber deine Erfahrungen machen mir da echt keine große Hoffnung.

Gruß Reinhard

P.s. Im Beitrag https://forum.fhem.de/index.php/topic,98287.msg922354.html#msg922354 habe ich meine bisher getätigten Maßnahmen beschrieben, diesen halte ich auch aktuell.
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

Ban

Hallo Rewe2000,

das sieht alles sehr identisch zu meinem Verhalten aus.

Was bei mir gerade wieder funktioniert hat.
CCU3 neugestartet. Lief dann ca. 2 Stunden ohne RPC Server. Jetzt habe ich in fhem die RPC-Server wieder hochgefahren und alles macht das, was es soll.
Muss sich die CCU3 nach einem Neustart evtl. erstmal um sich selbst kümmern und alle Daten der Geräte abfragen, bevor sie wieder bereit ist mit fhem zu reden?
Habe jetzt auch einen Syslog Server nebenbei laufen, der gibt mehr Infos aus, als das Log der CCU.

Geht die CCU evtl in den DutyCycle, wenn diese neu gestartet ist und gleichzeitig fhem neu connected wird?

Was mir noch einfällt, im ServiceMenü steht "HmIP-RF not running". Das steht da bei mir schon von anfang an. Auch wenn alles einwandfrei funktioniert.
Das stand schon bei der alten CCU3, jetzt bei der Neuen wieder.

Hattest du schon mal versucht die CCU 1-2 Stunden ohne RPC Server laufen zu lassen und dann die RPC Server wieder zu starten?

Nachtrag:
Sehe gerade im fhem Log, dass ich nach dem Start der RPC-Server auch wieder timeouts hatte. Aber aktuell läuft trotzdem alles.

Viele Grüße,
Ban
Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

Rewe2000

Hallo Ban,

alle deine Fragen kann ich mit ja beantworten, alles das habe ich auch schon versucht, ich dachte auch CCU2 mal über Nacht in Ruhe gelassen und dann den Raspi neu gestartet, half aber genau so wenig.

Ich hab schon mal nach dem Neustart der CCU2 alle HmIP Geräte betätigt, dass der Zustand der CCU2 bekannt ist und dann die RPC-Server gestartet in der Hoffnung alle "Zustände" der Geräte würden nun bekannt sein, gleicher Erfolg, nämlich keiner.

Auch die Idee mit dem Duty Cycle ist mir gekommen, habe im Februar diesen von allen Geräten und von der CCU2 mitgeschrieben, ich konnte niemals einen höheren als 70% sehen, nach einer Wartezeit von einigen Minuten war dieser irgendwann wieder auf unter 10%.

Irgendwie haben wir die gleichen Ideen, welche aber leider alle ins leere führen.

Kennst du dich mit einem Sniffer aus?
Würde es etwas bringen den Netzwerkverkehr bei der Entstehung des Problems zu analysieren?
Ich habe immer noch den Verdacht irgend eine Abfrage wird da bewusst abgewiesen (Firewall etc.)
Einen Sniffer habe ich mir vor einigen Monaten mal installiert, ich bin aber leider nicht in der Lage die Protokolle zu verstehen, mir fehlt hier einfach das Wissen.

Gruß Reinhard

P.s. Der Postbote müsste nun jeden Moment klingeln und mir meine neue CCU3 bringen. Leider hab ich aber heute nur wenig Zeit, da ich nicht nur Homematic und Fhem sondern auch noch eine Familie habe. CCU3 ist eben gekommen, ich sichere erstmal das Image der Speicherkarte im Fabrikneuen zustand.
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

Ban

Ok, wir haben wirklich die selben "Probleme", ich muss jetzt auch erstmal auf den Spielplatz:-)

Hab zwar vor Ewigkeiten mal WireShark verwendet, aber einen wirklichen Plan habe ich da nicht von.
Habe irgendwo noch einen kleinen managed Switch, den muss ich vorher suchen, sonst komme ich an den Netzwerktraffic zwischen fhem und der CCU nicht ran.
Ich werde es mal versuchen, im Moment traue ich mich aber nicht irgendetwas neu zu starten, sonst sind gleich wieder Stunden weg...

Meine Gedanken gehen aber auch dahin weiter zu analysieren, was da am Anfang abläuft.

Noch eine Frage fällt mir ein. Hattest du schon mal versucht zum Test ein neues fhem nur mit HMCCU zu betreiben?

Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)

zap

Irgendetwas muss an eurer Config anders sein als bei mir.

Habe Eure Beiträge mal überflogen. Hier einige Punkte, die mir aufgefallen sind:

- Wenn während dem Define vom IO Device die Abfrage der CCU fehl schlägt, macht es keinen Sinn, die RPC Server zu starten, da FHEM dann die Schnittstellen der CCU nicht kennt. Das führt zu massig Fehlermeldungen.

- FHEM und CCU müssen!!! feste IPs haben. Sonst funktioniert nichts mehr, wenn eines von beiden neu gestartet wird und dabei eine andere IP bekommt.

- wenn in der CCU eine Komponente wie HmIP nicht mehr funktioniert, liegt der Fehler bei der CCU und muss gefunden werden. Ggf. stehen Fehlermeldungen im Log der CCU. Bei solchen Ausfällen gibt es definitv dann auch Probleme auf der FHEM Seite.

- Ein Neustart von FHeM bei laufenden RPC Servern ist immer kritisch. hMCCU unterstützt zwar den delayed Shutdown von FHEM, es kann aber trotzdem sein, dass ein RPC Server beim Neustart noch läuft. Dann sind die Ports belegt und es gibt Probleme.

- Ich halte die RPC Server vor einem Neustart von FHEM oder der CCU an und starte sie später wieder. Ist zwar etwas Aufwand, aber ich starte sowieso nur bei Updates neu, also so alle 1-2 Monate mal.

Ich nehme mal an, dass eure Configs mittlerweile so verbogen sind, dass weitere Versuche durch Folgefehler vorgehender Tests ebenfalls fehlschlagen.

Ich würde mal die fhem.cfg sichern und dann mit einem nackten IO Device neu anfangen. Dann erst mal einen RPC definieren, den starten und schauen wie es läuft.

2xCCU3, Fenster, Rollläden, Themostate, Stromzähler, Steckdosen ...)
Entwicklung: FHEM auf AMD NUC (Ubuntu)
Produktiv inzwischen auf Home Assistant gewechselt.
Maintainer: FULLY, Meteohub, HMCCU, AndroidDB

Rewe2000

Hallo Ban,

nein das wäre auch noch eine Idee, die CCU und ein nacktes Fhem mit HMCCU.

@zap: Die Idee hatte Ban eben auch, ich denke das werden wir mal machen.

Ich habe in der Vergangenheit so viel ausprobiert, dass ich nahezu den Überblick verloren habe. Ich werde auch noch mal ein altes Image vom Raspi vom Oktober 2018 testen, ob da der Fehler auch auftaucht.

Ich teste nun erstmal meine CCU3, aber die Hoffnung ist nicht sehr groß.

Gruß Reinhard
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

Rewe2000

Hallo zap,

Zitat- Wenn während dem Define vom IO Device die Abfrage der CCU fehl schlägt, macht es keinen Sinn, die RPC Server zu starten, da FHEM dann die Schnittstellen der CCU nicht kennt. Das führt zu massig Fehlermeldungen.

Bei mir schlägt die erste Abfrage der CCU2 grundsätzlich fehl, danach startet aber HMCCU nach 180 Sekunden die RPC-Server automatisch neu. In der Vergangenheit hat dies auch noch funktioniert.
Oder verstehe ich da etwas falsch?

Zitat- FHEM und CCU müssen!!! feste IPs haben. Sonst funktioniert nichts mehr, wenn eines von beiden neu gestartet wird und dabei eine andere IP bekommt.
Ich denke wenn ich im Router einstelle, dass immer die gleiche IP verwendet werden soll, sollte das passen. Meine Geräte CCU und Raspi haben immer die gleiche IP. Ich habe allerdings nur zu Testzwecken (Switch-Betrieb) feste IP Adressen eingestellt, da bestand aber das gleiche Problem.

Ich werde zukünftig die RPC-Server auch von Hand anhalten, bevor ich ein shutdown restart mache. Das bedeutet aber, bei dir werden diese dann über attr nicht automatisch gestartet.

Gruß Reinhard
Fhem 6.3 auf Raspberry Pi4 SSD mit Raspbian Bookworm, Homematic, Homematic IP, CCU3 mit RapberryMatic, WAGO 750-880, E3DC S10E Hauskraftwerk, E3DC Wallbox, my-PV AC ELWA-E Heizstab, Fritz!Box 7590, KIA Bluelinky

Ban

@zap: Danke für deine Tipps.

- Feste IPs hat bei mir fhem und die CCU.
- Ich schließe nicht aus, dass der Fehler in der CCU liegt. Aber irgendwas von HMCCU triggert in der CCU etwas, dass diese ausflippt:-)
- Das mit dem Anhalten der RPC Server beim Neustart ist interessant, habe ich tatsächlich noch nicht darauf geachtet. Werde ich ab jetzt tun!

Kann es sein, dass noch etwas vom externen RPC-Server übrig geblieben ist?
Ich hatte vor HMCCU 4.2 diese Config.

Ich werde auch versuchen ein nacktes fhem nur mit HMCCU aufzusetzen.
Aktuell läuft bei mir wieder alles einwandfrei...




Homematic, Homematic IP, Sonos, Echos
fhem Raspberry Pi 4B, CCU Charly (RaspberryMatic)