nachdem ich jahrelang sehr zuverlässig Raspberrymatic auf Raspi mit dem alten sowie neuen Funkmodul betreibe, habe ich nun seit dem Umstieg auf eine Proxmox VM hin und wieder Probleme - insg. ca 6-10x seit Umstellung, wo ich das System stoppen und die VM rebooten musste. Heute jedoch war die Situation besonders unschön, sodass ich das so nicht mehr belassen kann.
Ich nutze eine 4fach Fernbedienung zum öffnen der Garage, Haustür sowie Wohnungstür. Nachdem ich noch erfolgreich die Wohnung verschließen konnte (Druck auf FB steuert 4fach HM-Aktor an, wobei dann Alarm aktiviert wird und zeitgleich das Funkschloss zum schließen freigegeben wird)
Die Garage lies sich dann leider nicht mehr öffnen, ggf. hat die weitere Abfolge nach dem Alarm aktivieren das System bereits lahm gelegt. (Geräte, Lichter etc werden ausgeschaltet, Kamera aktivert etc.) Jedenfalls wollte ich dann zurück in die Wohnung, um die originale Fernbedienung für das Garagentor zu holen, notfalls den Notschlüssel - ledier kam ich auch nicht mehr ins Haus, da Raspberrymatic nicht mehr reagiert hat. Ich bin mit Handy und VPN ins Proxmox (APROX App) und habe gesehen, dass die VM eine Auslastung von ca 80% hatte, normal ca 15%, bei Backup auch mal 25%. Der Speicher war zu 100% ausgelastet, sonst auch so 15%. Also momentan habe ich 288MB von 1,6GB benutzt und das läuft durchgängig so. Cpus habe ich 1 Socket, 2 Cores zugewiesen, sowie 12GB Festplattenspeicher. Läuft so alles super flott.. Da ich das Szenario kenne und ich keine Anstalten machen brauche, das System aufzufangen, habe ich die VM kurzerhand gestoppt und neu gestartet. kurze gewartet, dann hatte ich auch wieder meine Telegram-Benachrichtigungen über Handy bekommen, wenn die Fernbedienung zum Öffnen der Haustür gedrückt wurde - jedoch keine Bestätigung, dass der Aktor betätigt wurde. Die Haustür wurde in der Tat auch nicht geöffnet. Weiter 2 Minuten gewartet und ich musste bei den Nachbarn, welche sich mit mir das Haus teilen, klingeln. Meine Wohnung konnte ich dann mit Handy (BLE) alternativ öffnen.
Ich weiß mir soweit zu helfen, meine Frau oder die 7jährige Tochter , da sieht es anders aus.
Ich bin dann zum Rechner, konnte nichts finden, wieso diese Fernbedieung (und andere auch) nicht reagierten. Also habe ich ein Snapshot der VM von gestern (vor dem aufspielen der aktuellen 3.53) eingesetzt. Ich bin nun also wieder bei der 3.51. Aber wie gesagt, ich möchte das nicht auf die aktuelle Version schieben, Probleme hatte ich schon in den letzten Monaten, wenn auch an anderen Sitationen. Jedesmal bekam ich in den Syslogs Meldungen, dass der Coprozessor nicht mehr reagiert ?!
Ich habe mal einen Syslog-Auszug vom meinem Server, den originalen Fehlerlog der RM habe ich leider erst ab 9Uhr, der bessagte Zeitraum war ca 7:38-07:50 ca. wobei ich die letzten Minuten weggelassen habe, da da rebootet wurde etc und hunderte Meldungen die Übersicht killen.
Worüber ich mir aber nun als nächstes Gedanken mache, die beiden Aktoren mit Direktverknüpfungen auf die Fernbedieunung zu setzen, auch wenn es einiges an Variabilität einbüßt. Zumindest kommt man dann in die Wohnung auch wenn die dann ziemlich "tot" ist.
Code: Alles auswählen
Date Facility Severity Host Syslogtag Message
Today 07:47:57 1 6 192.168.1.63 kernel: [ 37.084986] eq3loop: eq3loop_open_slave() mmd_bidcos
Today 07:47:54 1 6 192.168.1.63 kernel: [ 35.041840] eq3loop: created slave mmd_bidcos
Today 07:47:54 1 6 192.168.1.63 kernel: [ 35.041641] eq3loop: created slave mmd_hmip
Today 07:47:54 1 6 192.168.1.63 update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Today 07:47:54 1 6 192.168.1.63 root: Updating RF Lan Gateway Firmware
Today 07:47:54 1 7 192.168.1.63 update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Today 07:47:54 1 6 192.168.1.63 root: Updating RF Lan Gateway Coprocessor Firmware
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: add interface 'CUxD'
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: write_proxy /var/cache/cuxd_proxy.ini (678 /usr/local/addons/cuxd/ 2.3.3 3.53.30.20200919 0)
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: 4 device-paramset(s) loaded ok!
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:2175 update(-35446s):Sun Sep 20 21:57:08 2020
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: CUx-Daemon(2.3.3) on CCU(3.53.30.20200919) start PID:678
Today 07:47:54 1 6 192.168.1.63 homematic: started cux-daemon
Today 07:47:54 3 6 192.168.1.63 cuxd[678]: write_pid /var/run/cuxd.pid [678]
Today 07:47:54 4 6 192.168.1.63 sshd[654]: Server listening on :: port 22.
Today 07:47:54 4 6 192.168.1.63 sshd[654]: Server listening on 0.0.0.0 port 22.
Today 07:47:54 3 2 192.168.1.63 xinetd[626]: 626 {init_services} no services. Exiting...
Today 07:47:54 3 3 192.168.1.63 xinetd[626]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Today 07:47:54 3 6 192.168.1.63 chronyd[607]: chronyd version 3.5.1 starting (+CMDMON +NTP +REFCLOCK +RTC -PRIVDROP -SCFILTER -SIGND +ASYNCDNS -SECHASH +IPV6 -DEBUG)
Today 07:47:54 3 5 192.168.1.63 ntpdate[603]: step time server 194.25.134.196 offset +1.557453 sec
Today 07:47:47 3 6 192.168.1.63 ifplugd(eth0)[598]: Program executed successfully.
Today 07:47:47 3 4 192.168.1.63 ifplugd(eth0)[598]: client: ifup: interface eth0 already configured
Today 07:47:47 3 6 192.168.1.63 ifplugd(eth0)[598]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Today 07:47:47 3 6 192.168.1.63 ifplugd(eth0)[598]: Initialization complete, link beat detected.
Today 07:47:47 3 6 192.168.1.63 ifplugd(eth0)[598]: Using detection mode: SIOCETHTOOL
Today 07:47:47 3 6 192.168.1.63 ifplugd(eth0)[598]: Using interface eth0/7E:44:49:99:13:58 with driver <virtio_net> (version: 1.0.0)
Today 07:47:47 3 6 192.168.1.63 ifplugd(eth0)[598]: ifplugd 0.28 initializing.
Today 07:47:47 1 6 192.168.1.63 firewall: configuration set
Today 07:47:46 1 6 192.168.1.63 kernel: [ 28.200559] Segment Routing with IPv6
Today 07:47:46 1 6 192.168.1.63 kernel: [ 28.195212] NET: Registered protocol family 10
Today 07:46:03 1 7 192.168.1.63 script: [ALCHY ALLINScript ENDE]
Today 07:46:03 1 7 192.168.1.63 script: [ALCHY ALLINScript START]
Today 07:46:01 1 7 192.168.1.63 script: [ALCHY ALLINScript ENDE]
Today 07:46:01 1 7 192.168.1.63 script: [ALCHY ALLINScript Mails raus]
Today 07:46:01 1 3 192.168.1.63 rfd: (PEQ0628399) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
Today 07:46:01 1 7 192.168.1.63 script: [ALCHY ALLINScript START]
Today 07:45:59 1 3 192.168.1.63 rfd: HSSParameter::SetValue() false Put failed
Today 07:45:01 1 3 192.168.1.63 rfd: (PEQ0628399) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
Today 07:44:01 1 3 192.168.1.63 rfd: (PEQ0628399) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
Today 07:43:50 16 3 192.168.1.63 ReGaHss: ERROR: rpc.CallSetValue failed; address = LEQ1194152:1 [WriteValue():iseDOMdpHSS.cpp:76]
Today 07:43:50 16 3 192.168.1.63 ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {\LEQ1194152:1\" \"LED_SLEEP_MODE\" 1}) [CallSetValue():iseXmlRpc.cpp:1505]"
Today 07:43:50 16 4 192.168.1.63 ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {\LEQ1194152:1\" \"LED_SLEEP_MODE\" 1} result: [faultCode:-1 faultString:\"Failure\"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]"
Today 07:43:50 1 3 192.168.1.63 rfd: HSSParameter::SetValue() 1 Put failed
Today 07:43:01 1 3 192.168.1.63 rfd: (PEQ0628399) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
Today 07:42:28 1 7 192.168.1.63 script: [ALCHY ALLINScript ENDE]
Today 07:42:28 1 7 192.168.1.63 script: [ALCHY ALLINScript START]
Today 07:42:26 1 7 192.168.1.63 script: [ALCHY ALLINScript ENDE]
Today 07:42:26 1 7 192.168.1.63 script: [ALCHY ALLINScript Mails raus]
Today 07:42:26 1 7 192.168.1.63 script: [ALCHY ALLINScript START]
Today 07:42:26 1 3 192.168.1.63 multimac: Copro timeout on response send. _autoTxState = AutoTxState_Ack
Today 07:42:24 16 3 192.168.1.63 ReGaHss: ERROR: rpc.CallSetValue failed; address = BidCoS-RF:4 [WriteValue():iseDOMdpHSS.cpp:76]
Today 07:42:24 16 3 192.168.1.63 ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {\BidCoS-RF:4\" \"PRESS_SHORT\" false}) [CallSetValue():iseXmlRpc.cpp:1505]"
Today 07:42:24 16 4 192.168.1.63 ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {\BidCoS-RF:4\" \"PRESS_SHORT\" false} result: [faultCode:-1 faultString:\"Failure\"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]"
Today 07:42:24 1 3 192.168.1.63 rfd: HSSParameter::SetValue() false Put failed
Today 07:41:55 16 3 192.168.1.63 ReGaHss: ERROR: rpc.CallSetValue failed; address = BidCoS-RF:9 [WriteValue():iseDOMdpHSS.cpp:76]
Today 07:41:55 16 3 192.168.1.63 ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {\BidCoS-RF:9\" \"PRESS_SHORT\" false}) [CallSetValue():iseXmlRpc.cpp:1505]"
Today 07:41:55 16 4 192.168.1.63 ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {\BidCoS-RF:9\" \"PRESS_SHORT\" false} result: [faultCode:-1 faultString:\"Failure\"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]"
Today 07:41:55 1 3 192.168.1.63 rfd: HSSParameter::SetValue() false Put failed
Today 07:41:52 16 3 192.168.1.63 ReGaHss: ERROR: rpc.CallSetValue failed; address = BidCoS-RF:8 [WriteValue():iseDOMdpHSS.cpp:76]
Today 07:41:52 16 3 192.168.1.63 ReGaHss: ERROR: XMLRPC 'setValue' call failed (interface: 1007, params: {\BidCoS-RF:8\" \"PRESS_SHORT\" false}) [CallSetValue():iseXmlRpc.cpp:1505]"
Today 07:41:52 16 4 192.168.1.63 ReGaHss: WARNING: XMLRPC 'setValue': rpcClient.isFault() failed (url: xmlrpc_bin://127.0.0.1:32001, params: {\BidCoS-RF:8\" \"PRESS_SHORT\" false} result: [faultCode:-1 faultString:\"Failure\"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]"
Today 07:41:52 1 3 192.168.1.63 rfd: HSSParameter::SetValue() false Put failed
Zu erwähnen wäre noch, dass sicher der Duty-Cycle im normalen Bereich befand, also unter 50% !
Die Aktionen zu diesem Zeitpunkt laufen so auch schon seit Jahren - also ich habe da nichts geändert, was Einfluss haben könnte bzw das System ins Nirvana befördern könnte...
Hat jemand ähnliche Erfahrungen gemacht, wer die OVA Version benutzt ?
Danke schon mal und Grüße,
Holger