Seite 1 von 8

RegaHss hängt sich auf?

Verfasst: 21.11.2018, 06:38
von klana
Guten Morgen,

in letzter Zeit habe ich alle paar Tage/Wochen, dass die RaspberryMatic nicht erreichbar ist.
Ich habe sehr lange nach irgend welchen Fehlern (auch im Hardwarebereich) gesucht.
Heute Nacht ist es wieder soweit gewesen...WebUI nicht erreichbar, Zugriff über PocketControl nicht möglich.

Per SSH bin ich dann noch auf die RaspberryMatic gekommen und konnte das Log ziehen.
Hier sieht man, das gegen 03:00 Uhr die FritzBox die tägliche Zwangstrennung gemacht hat und dann 1 ½ Stunden später
werden XmlRpcClient Error angezeigt bei verschiedenen Geräten (Bewegungsmelder, Wandthermostat, Alarmsirene)

Vielleicht weiß ja jemand von Euch, wo ich jetzt weiter ansetzen könnte. :?:

Code: Alles auswählen

Nov 21 02:37:51 homematic-raspi user.debug script: [Feiertage feststellen]
Nov 21 02:37:52 homematic-raspi user.debug script: [Urlaubstage feststellen]
Nov 21 02:37:54 homematic-raspi user.debug script: [Batteriespannungen auslesen]
Nov 21 02:38:14 homematic-raspi user.debug script: [Mondphase berechnen]
Nov 21 02:38:29 homematic-raspi user.debug script: [Wochentag und Wochenende setzen]
Nov 21 02:38:49 homematic-raspi user.debug script: [Senden des Systemlogs per EMail]
Nov 21 03:07:10 homematic-raspi daemon.warn openvpn[862]: WARNING: --ns-cert-type is DEPRECATED.  Use --remote-cert-tls instead.
Nov 21 04:32:05 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0412633:1","BRIGHTNESS",33}],[methodName:"event",params:{"1007","LEQ0412633:1","ERROR",0}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:05 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:06 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0995080:1","TEMPERATURE",19.200000}],[methodName:"event",params:{"1007","LEQ0995080:1","HUMIDITY",51}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:06 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:07 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0995069:2","ACTUAL_TEMPERATURE",16.900000}],[methodName:"event",params:{"1007","LEQ0995069:2","ACTUAL_HUMIDITY",56.000000}],[methodName:"event",params:{"1007","LEQ0995069:2","SET_TEMPERATURE",17.000000}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:07 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:11 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ1081038:1","BRIGHTNESS",34}],[methodName:"event",params:{"1007","LEQ1081038:1","MOTION",true}],[methodName:"event",params:{"1007","LEQ1081038:1","INSTALL_TEST",true}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:11 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:11 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","NEQ0926664:2","STATE",false}],[methodName:"event",params:{"1007","NEQ0926664:2","WORKING",false}],[methodName:"event",params:{"1007","NEQ0926664:0","ERROR_SABOTAGE",0}],[methodName:"event",params:{"1007","NEQ0926664:0","STICKY_SABOTAGE",0}],[methodName:"event",params:{"1007","NEQ0926664:1","ERROR_SABOTAGE",0}],[methodName:"event",params:{"1007","NEQ0926664:1","LOWBAT",false}],[methodName:"ev
nt",params:{"1007","NEQ0926664:2","ERROR_SABOTAGE",0}],[methodName:"event",params:{"1007","NEQ0926664:2","LOWBAT",false}],[methodName:"event",params:{"1007","NEQ0926664:3","ERROR_SABOTAGE",0}],[methodName:"event",params:{"1007","NEQ0926664:3","LOWBAT",false}],[methodName:"event",params:{"1007","NEQ0926664:4","ERROR_SABOTAGE",0}],[methodName:"event",params:{"1007","NEQ0926664:4","LOWBAT",false}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:11 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:15 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0581425:4","CONTROL_MODE",0}],[methodName:"event",params:{"1007","LEQ0581425:4","FAULT_REPORTING",0}],[methodName:"event",params:{"1007","LEQ0581425:4","BATTERY_STATE",2.900000}],[methodName:"event",params:{"1007","LEQ0581425:4","VALVE_STATE",0}],[methodName:"event",params:{"1007","LEQ0581425:4","BOOST_STATE",0}],[methodName:"event",params:{"1007","LEQ0581425:4","ACTUAL_TEMPERATURE",18.40
000}],[methodName:"event",params:{"1007","LEQ0581425:4","SET_TEMPERATURE",18.000000}],[methodName:"event",params:{"1007","LEQ0581425:4","PARTY_TEMPERATURE",5.000000}],[methodName:"event",params:{"1007","LEQ0581425:4","PARTY_START_TIME",0}],[methodName:"event",params:{"1007","LEQ0581425:4","PARTY_START_DAY",1}],[methodName:"event",params:{"1007","LEQ0581425:4","PARTY_START_MONTH",1}],[methodName:"event",params:{"1007","LEQ0581425:4","PARTY_START_YEAR",0}],[methodName:"event",params:{"1007","LEQ0581425:4","
ARTY_STOP_TIME",0}],[m
Nov 21 04:32:15 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:23 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0581479:4","CONTROL_MODE",0}],[methodName:"event",params:{"1007","LEQ0581479:4","FAULT_REPORTING",0}],[methodName:"event",params:{"1007","LEQ0581479:4","BATTERY_STATE",2.900000}],[methodName:"event",params:{"1007","LEQ0581479:4","VALVE_STATE",0}],[methodName:"event",params:{"1007","LEQ0581479:4","BOOST_STATE",0}],[methodName:"event",params:{"1007","LEQ0581479:4","ACTUAL_TEMPERATURE",22.00
000}],[methodName:"event",params:{"1007","LEQ0581479:4","SET_TEMPERATURE",5.000000}],[methodName:"event",params:{"1007","LEQ0581479:4","PARTY_TEMPERATURE",5.000000}],[methodName:"event",params:{"1007","LEQ0581479:4","PARTY_START_TIME",0}],[methodName:"event",params:{"1007","LEQ0581479:4","PARTY_START_DAY",1}],[methodName:"event",params:{"1007","LEQ0581479:4","PARTY_START_MONTH",1}],[methodName:"event",params:{"1007","LEQ0581479:4","PARTY_START_YEAR",0}],[methodName:"event",params:{"1007","LEQ0581479:4","P
RTY_STOP_TIME",0}],[me
Nov 21 04:32:23 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:25 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0581493:4","CONTROL_MODE",0}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:25 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:25 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0581493:4","FAULT_REPORTING",0}],[methodName:"event",params:{"1007","LEQ0581493:4","BATTERY_STATE",2.900000}],[methodName:"event",params:{"1007","LEQ0581493:4","VALVE_STATE",0}],[methodName:"event",params:{"1007","LEQ0581493:4","BOOST_STATE",0}],[methodName:"event",params:{"1007","LEQ0581493:4","ACTUAL_TEMPERATURE",19.200000}],[methodName:"event",params:{"1007","LEQ0581493:4","SET_TEMPERA
URE",18.000000}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_TEMPERATURE",5.000000}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_START_TIME",0}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_START_DAY",1}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_START_MONTH",1}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_START_YEAR",0}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_STOP_TIME",0}],[methodName:"event",params:{"1007","LEQ0581493:4
,"PARTY_STOP_DAY",1}]}
Nov 21 04:32:25 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:32:25 homematic-raspi user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_STOP_MONTH",1}],[methodName:"event",params:{"1007","LEQ0581493:4","PARTY_STOP_YEAR",0}]}) on binary://127.0.0.1:1999/RPC2:
Nov 21 04:32:25 homematic-raspi user.err rfd: XmlRpc transport error
Nov 21 04:33:56 homematic-raspi user.info kernel: [60540.480877] smsc95xx 1-1.1:1.0 eth0: link down
Nov 21 04:33:56 homematic-raspi daemon.info ifplugd(eth0)[487]: Link beat lost.
Nov 21 04:33:57 homematic-raspi daemon.info ntpd[500]: Deleting interface #3 eth0, 192.168.178.26#123, interface stats: received=127, sent=127, dropped=0, active_time=60517 secs
Nov 21 04:33:57 homematic-raspi daemon.info ntpd[500]: 81.14.202.21 local addr 192.168.178.26 -> <null>
Nov 21 04:33:57 homematic-raspi daemon.info ntpd[500]: Deleting interface #5 eth0, fd00::ba27:ebff:fe09:4e2f#123, interface stats: received=0, sent=0, dropped=0, active_time=60517 secs
Nov 21 04:33:57 homematic-raspi daemon.info ntpd[500]: Deleting interface #6 eth0, fe80::ba27:ebff:fe09:4e2f%2#123, interface stats: received=0, sent=0, dropped=0, active_time=60517 secs
Nov 21 04:33:59 homematic-raspi user.info kernel: [60543.752603] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC7E1
Nov 21 04:33:59 homematic-raspi daemon.info ifplugd(eth0)[487]: Link beat detected.
Nov 21 04:34:00 homematic-raspi daemon.info ntpd[500]: Listen normally on 9 eth0 192.168.178.26:123
Nov 21 04:34:00 homematic-raspi daemon.info ntpd[500]: Listen normally on 10 eth0 [fd00::ba27:ebff:fe09:4e2f]:123
Nov 21 04:34:00 homematic-raspi daemon.info ntpd[500]: Listen normally on 11 eth0 [fe80::ba27:ebff:fe09:4e2f%2]:123
Nov 21 04:34:00 homematic-raspi daemon.info ntpd[500]: 81.14.202.21 local addr 10.220.59.92 -> 192.168.178.26
Nov 21 04:38:45 homematic-raspi daemon.warn openvpn[862]: WARNING: --ns-cert-type is DEPRECATED.  Use --remote-cert-tls instead.
Nov 21 04:38:49 homematic-raspi daemon.notice openvpn[862]: do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Nov 21 04:38:49 homematic-raspi daemon.warn openvpn[862]: ERROR: Linux route add command failed: external program exited with error status: 2
Nov 21 04:38:50 homematic-raspi daemon.info ntpd[500]: Listen normally on 12 tun0 10.215.59.92:123
Nov 21 04:38:50 homematic-raspi daemon.info ntpd[500]: Listen normally on 13 tun0 [fe80::ab7f:7d40:8f08:13aa%5]:123
Nov 21 04:38:50 homematic-raspi daemon.info ntpd[500]: Deleting interface #7 tun0, 10.220.59.92#123, interface stats: received=0, sent=0, dropped=0, active_time=60776 secs
Nov 21 04:38:50 homematic-raspi daemon.info ntpd[500]: Deleting interface #8 tun0, fe80::a29c:c1c2:2e87:356%4#123, interface stats: received=0, sent=0, dropped=0, active_time=60776 secs
Nov 21 04:38:50 homematic-raspi daemon.err snmpd[629]: IfIndex of an interface changed. Such interfaces will appear multiple times in IF-MIB.
Nov 21 05:03:42 homematic-raspi user.info kernel: [62327.040860] smsc95xx 1-1.1:1.0 eth0: link down
Nov 21 05:03:43 homematic-raspi daemon.info ifplugd(eth0)[487]: Link beat lost.
Nov 21 05:03:44 homematic-raspi daemon.info ntpd[500]: Deleting interface #9 eth0, 192.168.178.26#123, interface stats: received=9, sent=11, dropped=0, active_time=1784 secs
Nov 21 05:03:44 homematic-raspi daemon.info ntpd[500]: 81.14.202.21 local addr 192.168.178.26 -> <null>
Nov 21 05:03:44 homematic-raspi daemon.info ntpd[500]: Deleting interface #10 eth0, fd00::ba27:ebff:fe09:4e2f#123, interface stats: received=0, sent=0, dropped=0, active_time=1784 secs
Nov 21 05:03:44 homematic-raspi daemon.info ntpd[500]: Deleting interface #11 eth0, fe80::ba27:ebff:fe09:4e2f%2#123, interface stats: received=0, sent=0, dropped=0, active_time=1784 secs
Nov 21 05:03:50 homematic-raspi user.info kernel: [62334.448654] smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC7E1
Nov 21 05:03:50 homematic-raspi daemon.info ifplugd(eth0)[487]: Link beat detected.
Nov 21 05:03:51 homematic-raspi daemon.info ntpd[500]: Listen normally on 14 eth0 192.168.178.26:123
Nov 21 05:03:51 homematic-raspi daemon.info ntpd[500]: Listen normally on 15 eth0 [fd00::ba27:ebff:fe09:4e2f]:123
Nov 21 05:03:51 homematic-raspi daemon.info ntpd[500]: Listen normally on 16 eth0 [fe80::ba27:ebff:fe09:4e2f%2]:123
Nov 21 05:03:51 homematic-raspi daemon.info ntpd[500]: 81.14.202.21 local addr 10.215.59.92 -> 192.168.178.26
Nov 21 05:30:00 homematic-raspi daemon.err cuxd[592]: sendbinrpc(127.0.0.1:1999) - write() Connection refused
Nov 21 05:30:01 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:02 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:03 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:04 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:05 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:06 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:07 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:08 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:09 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:10 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:11 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:12 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:13 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:14 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:15 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:16 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:17 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:18 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL
Nov 21 05:30:19 homematic-raspi daemon.err cuxd[592]: query_CCU_device_params() is NULL

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 06:54
von jmaus
Das liest sich in der Tat nach einem Absturz von ReGaHss. Nun ist allerdings die Frage inwieweit das reproduzierbar ist und regelmäßig auftritt. Denn das wäre Vorrausetzung dafür, das man Maßnahmen ergreifen könnte um den Bug zu finden und zu eliminieren.

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 07:03
von klana
Hallo Jens,
genau das ist das Problem...mal sind es nur 1-2 Tage und mal sind es 1-3 Wochen bis es wieder auftritt.
Was macht bzw. wofür ist dieser XlmRpc denn eigentlich gut, evtl. kann ich das dann ein bisschen besser einkreisen, wenn ich das verstehe.
Gruß
Klana

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 07:43
von jmaus
Tut mir leid. So einfach ist das leider nicht. Du müsstest schauen ob du das irgendwie reproduzierbar bekommst. D.h. Mit einer definierten Abfolge von Aktionen das dann definitiv passiert. Und wenn du soweit bist dann können wir uns das gemeinsam anschauen.

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 07:56
von klana
Tja, es passiert meistens Nachts oder am späten Vormittag.
Es laufen in dieser Zeit keine Aktionen oder Programme.
Tagsüber ist auch niemand Zuhause der z.B. die Bewegungsmelder o.ä. auslösen kann und Nachts liegen wir normalerweise im Bett.
Da kann ich natürlich auch keine Abfolge von Aktionen nachvollziehen.
Ist ein echt blödes Problem.
Ich werde heute erstmal eine WLAN Steckdose von AVM einsetzen, damit ich dann im Notfall über VPN die HM aus und wieder einschalten kann.

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 07:59
von jmaus
klana hat geschrieben:
22.11.2018, 07:56
Ich werde heute erstmal eine WLAN Steckdose von AVM einsetzen, damit ich dann im Notfall über VPN die HM aus und wieder einschalten kann.
Das solltest du lieber lassen, denn so sind Dateisystem- bzw SD-Karten Defekte vorprogrammiert wenn man einfach hart an/ausschaltet. Besser so etwas wie mein hm-watchdog Addon installieren damit ReGaHss automatisch neugestartet wird. Das kannst du dann sigar so einstellen das dann automatisch eine Meldung verschickt wird wenn er einen Dienst neustarten musste.

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 08:02
von klana
Ich dachte der HM WatchDog funktioniert nicht mehr seit einer bestimmten HM Version

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 08:04
von jmaus
Meines Wissens sollte er wieder gehen in der neuesten Version - bis zum Beweis des Gegenteils.

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 08:45
von deimos
Hi,

ich sehe da im Log auch einen Disconnect von eth0. Beim Raspberry muss ich da immer sofort auch an eine instabile Spannungsversorgung denken, da der Pi bei Undervoltage die USB Geräte abschaltet und die Netzwerkkarte ist intern über USB angebunden.

Viele Grüße
Alex

Re: RegaHss hängt sich auf?

Verfasst: 22.11.2018, 10:12
von klana
Hi deimos,

es ist das originale Netzteil vom Raspi angeschlossen
Hardware: Raspi 3B+, altes Funkmodul, USBStick für Logging und Diagramme
Sollte doch funktionieren.

Gruß
Klana