HMCCU unter Windows

Nobby1805 · 03 Januar 2024, 22:37:35

Die Konfiguration der HM-Geräte funktioniert. Es können auch die Readings per Poll von der CCU gelesen werden. Was bei mir aber nicht funktioniert ist die Übertragung der geänderten Werte per RPC

Im Log erscheint alle 10 Minuten

Code Auswählen

2024.01.03 16:29:16.789 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Received no events from interface CB2001001030001011 for 600.937408924103 seconds
2024.01.03 16:29:16.789 2: HMCCU [ccu2] Reconnecting to CCU
2024.01.03 16:29:16.795 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Registering callback http://192.168.1.30:6023/fh2001 of type A with ID CB2001001030001011 at http://192.168.1.11:2001

zap · 04 Januar 2024, 17:34:59

Wir versuchen das mal systematisch anzugehen. Zunächst die CCU Einstellungen prüfen:

1. Firewall

Einstellungen > Systemsteuerung > Firewall

Firewall Richtlinie = Ports offen
Homematic XML RPC API = Vollzugriff
Remote Homematic-Script API = Vollzugriff
IP Adressen für den eingeschränkten Zugriff = zumindest 192.168.0.0/16

2. Benutzerrechte

Einstellungen > Systemsteuerung > Sicherheit

Authentifizierung aktiv: Nein (kein Haken)

3. Windows

Bei laufendem FHEM ein Kommandozeilenfenster öffnen und folgendes eingeben:

Code Auswählen

netstat -an | find "LISTEN"
Möglicherweise kommt dabei nichts raus, da Microsoft der Meinung ist, alles eindeutschen zu müssen. In dem Fall mal einfach mal nur "netstat -an" eingeben und schauen, ob in einigen Zeilen sowas wie "ABHÖREN" steht (total schräg). Bitte auch auf Groß-/Kleinschreibung achten. Dann nochmal versuchen, z.B.

Code Auswählen

netstat -an | find "ABHÖREN"
Da sollte dann in einer Zeile mit "LISTENING" oder "ABHÖREN" die Zahl 6023 auftauchen.

Was mich gleich zu meiner nächsten Frage kommen läßt. Wieso steht bei Dir im Log "Registering callback http://192.168.1.30:6023/fh2001"? Statt 6023 müsste da eine Zahl > 7000 stehen, es sei denn, Du hast im Device "ccu2" das Attribut "rpcserverport" gesetzt. Wenn ja, warum?

Nobby1805 · 04 Januar 2024, 23:34:31

OK.

zu 1) Firewall Richtlinie gibt es bei mir nicht ---> siehe Screenshot

zu 2) OK ---> siehe Screenshot

zu 3) netstat -anb stat -an

---> siehe Screenshot

rpcserverport war gesetzt (auf 4012) ... hatte ich beim lesen diverser Artikel irgendwo gefunden, habe das Attribut jetzt gelöscht

Oops ... da hat sich etwas geändert, diese Messages im Log hatte ich bisher noch nicht ... aber Daten werden wohl immer noch nicht empfangen

Code Auswählen

2024.01.04 23:17:36.071 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Sending signal INT to RPC server process CB2001001030001011 with PID=-5768
2024.01.04 23:17:36.073 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Scheduling cleanup in 30 seconds
2024.01.04 23:17:58.314 2: HMCCURPCPROC [d_rpc001011BidCos_RF] CB2001001030001011 received signal INT
2024.01.04 23:17:59.378 1: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server CB2001001030001011 stopped handling connections. PID=-5768
2024.01.04 23:17:59.384 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Number of I/O errors = 0
2024.01.04 23:17:59.385 1: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server process CB2001001030001011 terminated.
2024.01.04 23:17:59.391 1: HMCCU [ccu2] All RPC servers inactive
2024.01.04 23:17:59.398 1: HMCCURPCPROC [d_rpc001011BidCos_RF] Process CB2001001030001011 with PID=-5768 still running. Killing it.
2024.01.04 23:18:00.396 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Stop I/O handling
2024.01.04 23:18:00.412 2: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server stopped. Cancel delayed shutdown.
2024.01.04 23:18:07.026 2: HMCCU [ccu2] RPC device for interface BidCos-RF: d_rpc001011BidCos_RF
2024.01.04 23:18:09.550 2: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server process started for interface BidCos-RF with PID=-6732
2024.01.04 23:18:09.560 1: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server starting
2024.01.04 23:18:09.570 2: HMCCU [ccu2] RPC server start: 1 started, 0 already running, 0 failed to start
2024.01.04 23:18:09.654 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Initializing RPC server CB2001001030001011 for interface BidCos-RF
2024.01.04 23:18:09.979 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Callback server CB2001001030001011 created. Listening on port 7411
2024.01.04 23:18:09.981 2: HMCCURPCPROC [d_rpc001011BidCos_RF] CB2001001030001011 accepting connections. PID=-6732
2024.01.04 23:18:09.972 2: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server CB2001001030001011 enters server loop
2024.01.04 23:18:09.982 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Registering callback http://192.168.1.30:7411/fh2001 of type A with ID CB2001001030001011 at http://192.168.1.11:2001
2024.01.04 23:20:16.253 1: HMCCURPCPROC [d_rpc001011BidCos_RF] RPC server CB2001001030001011 running
2024.01.04 23:20:16.259 1: HMCCU [ccu2] All RPC servers running
2024.01.04 23:20:16.271 2: HMCCU [ccu2] Updating 22 of 22 client devices matching devexp=.* filter=ccudevstate=active,ccuif=BidCos-RF
2024.01.04 23:20:16.288 1: HMCCURPCPROC [d_rpc001011BidCos_RF] Scheduled CCU ping every 300 seconds
2024.01.04 23:20:16.301 1: [Freezemon] myFreezemon: possible freeze starting at 23:18:10, delay is 126.298 possibly caused by: tmr-HMCCURPCPROC_IsRPCServerRunning(d_rpc001011BidCos_RF) 
2024.01.04 23:20:18.705 2: HMCCU [ccu2] Update success=22 failed=0
2024.01.04 23:20:55.481 2: HMCCU [ccu2] Updating 22 of 22 client devices matching devexp=.* filter=ccudevstate=active
2024.01.04 23:20:57.196 2: HMCCU [ccu2] Update success=22 failed=0
2024.01.04 23:24:55.491 2: HMCCU [ccu2] Updating 22 of 22 client devices matching devexp=.* filter=ccudevstate=active
2024.01.04 23:24:56.973 2: HMCCU [ccu2] Update success=22 failed=0
2024.01.04 23:28:10.369 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Received no events from interface CB2001001030001011 for 600.387305021286 seconds
2024.01.04 23:28:10.369 2: HMCCU [ccu2] Reconnecting to CCU
2024.01.04 23:28:10.380 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Registering callback http://192.168.1.30:7411/fh2001 of type A with ID CB2001001030001011 at http://192.168.1.11:2001
2024.01.04 23:30:16.703 1: [Freezemon] myFreezemon: possible freeze starting at 23:28:11, delay is 125.702 possibly caused by: no bad guy found :-(
2024.01.04 23:30:16.806 2: HMCCU [ccu2] Updating 22 of 22 client devices matching devexp=.* filter=ccudevstate=active
2024.01.04 23:30:19.047 2: HMCCU [ccu2] Update success=22 failed=0

Danke schon mal für Deine Hilfe

zap · 05 Januar 2024, 20:59:20

Dein Windows nutzt ausschließlich IPv6 ?

Nobby1805 · 06 Januar 2024, 08:19:19

Zitat von: zap am 05 Januar 2024, 20:59:20Dein Windows nutzt ausschließlich IPv6 ?

Nein, wie kommst du darauf?

zap · 06 Januar 2024, 12:16:41

Der netstat Output enthält nur v6 Adressen

Wernieman · 06 Januar 2024, 12:32:05

Wobei (unter Linux) netstat bei Port, die Protokollunabhängig geöffnet sind (also IPv4 und IPv6), nur die IPv6 anzeigt. Wie es unter Windows genau ist, kann ich nicht sagen.

Btw: Registriert sich das Modul überhaupt mit der Richtigen Serveradresse? Sonst müsste man rpcserveraddr setzen? Im geposteten Logs stehen die IPs drin, weiß nur nicht, ob die richtig sind ...

Nobby1805 · 06 Januar 2024, 12:47:27

ich habe dann mal ein netstat -anbo | find "8528" gemacht ... 8528 ist die PID des perl.exe

Code Auswählen

  TCP    0.0.0.0:7072          0.0.0.0:0              ABHÖREN        8528
  TCP    0.0.0.0:8083          0.0.0.0:0              ABHÖREN        8528
  TCP    0.0.0.0:8084          0.0.0.0:0              ABHÖREN        8528
  TCP    0.0.0.0:8085          0.0.0.0:0              ABHÖREN        8528
  TCP    127.0.0.1:52504        127.0.0.1:52505        HERGESTELLT    8528
  TCP    127.0.0.1:52505        127.0.0.1:52504        HERGESTELLT    8528
  TCP    127.0.0.1:55840        127.0.0.1:55841        HERGESTELLT    8528
  TCP    127.0.0.1:55841        127.0.0.1:55840        HERGESTELLT    8528
  TCP    127.0.0.1:56128        127.0.0.1:56129        HERGESTELLT    8528
  TCP    127.0.0.1:56129        127.0.0.1:56128        HERGESTELLT    8528
  TCP    127.0.0.1:57909        127.0.0.1:57910        HERGESTELLT    8528
  TCP    127.0.0.1:57910        127.0.0.1:57909        HERGESTELLT    8528
  TCP    127.0.0.1:57969        127.0.0.1:57970        HERGESTELLT    8528
  TCP    127.0.0.1:57970        127.0.0.1:57969        HERGESTELLT    8528
  TCP    127.0.0.1:62638        127.0.0.1:62639        HERGESTELLT    8528
  TCP    127.0.0.1:62639        127.0.0.1:62638        HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.30:51909    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.30:52496    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:52498    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:52499    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:52500    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:52501    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:52502    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:55838    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:56115    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57888    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57889    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57891    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57892    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57893    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57894    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57929    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57930    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57931    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57932    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57933    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:57940    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:62621    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:62622    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:62627    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:62628    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:62629    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.30:62634    SCHLIESSEN_WARTEN    8528
  TCP    192.168.1.30:8083      192.168.1.40:50289    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:52041    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55248    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55249    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55250    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55251    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55252    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55253    HERGESTELLT    8528
  TCP    192.168.1.30:8083      192.168.1.40:55256    HERGESTELLT    8528
  TCP    192.168.1.30:56028    192.168.1.11:2001      HERGESTELLT    8528
  TCP    [::]:4012              [::]:0                ABHÖREN        8528
  TCP    [::]:4012              [::]:0                ABHÖREN        8528
  TCP    [::]:6023              [::]:0                ABHÖREN        8528
  TCP    [::]:6023              [::]:0                ABHÖREN        8528
  TCP    [::]:6023              [::]:0                ABHÖREN        8528
  TCP    [::]:7411              [::]:0                ABHÖREN        8528

192.168.1.30 ist der Server, .40 mein Arbeits-PC und .11 die CCU

Nobby1805 · 06 Januar 2024, 13:05:57

rpcserveraddr ist gesetzt, soll ich das mal löschen?

Code Auswählen

Attributes:
  ccuGetVars 120
  ccuflags  procrpc,reconnect
  cmdIcon    on:general_an off:general_aus
  event-on-change-reading .*
  room      Homematic
  rpcinterfaces BidCos-RF
  rpcserver  on
  rpcserveraddr 192.168.1.30
  stateFormat rpcstate/state
  verbose    2

was mich weiterfhin wundert sind die Freeze nach dem reconnect des RPC

Code Auswählen

2024.01.06 13:00:30.934 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Received no events from interface CB2001001030001011 for 600.391055107117 seconds
2024.01.06 13:00:30.935 2: HMCCU [ccu2] Reconnecting to CCU
2024.01.06 13:00:30.943 2: HMCCURPCPROC [d_rpc001011BidCos_RF] Registering callback http://192.168.1.30:7411/fh2001 of type A with ID CB2001001030001011 at http://192.168.1.11:2001
2024.01.06 13:02:37.362 1: [Freezemon] myFreezemon: possible freeze starting at 13:00:31, delay is 126.361 possibly caused by: no bad guy found :-(

zap · 08 Januar 2024, 18:28:15

Für mich ist das leider alles Blindflug, da ich schon vor 15 Jahren auf Mac OS und Linux umgestiegen bin. Ich kann also nicht mal versuchsweise FHEM unter Windows installieren.