Absturz CCU1
23.10.2014, 13:23
Hallo
Nachdem ich die CCU nach Einspielen eines Backups wieder glücklich am Laufen hatte (vorher befand sich die CCU nach einem Neustart plötzlich bis auf die Direktverkn. im Auslieferungszustand!) lief diese auch eine Weile. Nachdem ich ein neues Programm getestet und implementiert hatte, stellte ich am nächsten Tag fest, dass die CCU eingefroren war. Nach einem Neustart brauchte man nur versuchen sie zu bedienen (Servicemeldungen anschauen, Gerät bedienen, Programm öffnen) und schon war die CCU wieder eingefroeren. Das Deaktivieren des besagten Programms hat nichts geholfen. Das Log ist voll von Meldungen wie diese
Und auch diese hier klingt verdächtig:
Nachdem ich die CCU nach Einspielen eines Backups wieder glücklich am Laufen hatte (vorher befand sich die CCU nach einem Neustart plötzlich bis auf die Direktverkn. im Auslieferungszustand!) lief diese auch eine Weile. Nachdem ich ein neues Programm getestet und implementiert hatte, stellte ich am nächsten Tag fest, dass die CCU eingefroren war. Nach einem Neustart brauchte man nur versuchen sie zu bedienen (Servicemeldungen anschauen, Gerät bedienen, Programm öffnen) und schon war die CCU wieder eingefroeren. Das Deaktivieren des besagten Programms hat nichts geholfen. Das Log ist voll von Meldungen wie diese
ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Und auch diese hier klingt verdächtig:
local0.emerg ReGaHss: Crash: Could not spawn a new request handler! {"HTTP-Listener"}
Das aktuelle Log sieht wie folgt aus
Aug 6 14:56:03 (none) syslog.info syslogd started: BusyBox v1.00 (2014.07.21-09:08+0000)
Aug 6 14:56:04 (none) user.debug setclock: Try to get time from ntp.homematic.com
Oct 23 12:23:08 (none) user.debug setclock: Thu Oct 23 12:23:08 CEST 2014
Oct 23 12:23:09 (none) user.info firewall: configuration set
Oct 23 12:23:17 (none) local0.info udhcpd: udhcpd (v0.9.9-pre) started
Oct 23 12:23:18 (none) cron.notice crond: crond 2.3.2 dillon, started, log level 8
Oct 23 12:23:19 (none) user.info : hss_lcd: Programm initialisiert. Starte Hauptschleife.
Oct 23 12:23:29 (none) daemon.info cuxd: write_pid /var/run/cuxd.pid
Oct 23 12:23:29 (none) user.info homematic: started cux-daemon
Oct 23 12:23:30 (none) daemon.info cuxd: CUx-Daemon(0.66) on CCU(1.515) start PID:1223
Oct 23 12:23:34 (none) daemon.info cuxd: AUTOMOUNT system(mount -t vfat /dev/sda1 /home)
Oct 23 12:23:34 (none) daemon.info cuxd: write_proxy /tmp/cuxd_proxy.ini
Oct 23 12:23:34 (none) daemon.info cuxd: add interface 'CUxD'
Oct 23 12:23:34 (none) daemon.info cuxd: write 4 interfaces to /usr/local/etc/config/InterfacesList.xml
Oct 23 12:23:35 (none) daemon.info cuxd: USB(1-2) 'insmod /usr/local/addons/cuxd/cdc-acm.ko'
Oct 23 12:23:36 (none) user.info homematic: started ftp server
Oct 23 12:23:40 (none) user.info homematic: started inetd
Oct 23 12:23:45 (none) user.info homematic: Homematic 1.515 has been started
Oct 23 12:23:45 (none) daemon.info init: ^MStarting pid 1395, console /dev/ttyS0: '/sbin/getty'
Oct 23 12:23:50 (none) daemon.info cuxd: USB(1-2) mknod '/dev/ttyACM0'
Oct 23 12:23:50 (none) daemon.info cuxd: USB(1-2/ttyACM0) CUL868 connect(9600:8N1)
Oct 23 12:28:59 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: XmlrpcTypeToIseVal failed
Oct 23 12:28:59 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =
Oct 23 12:28:59 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: XmlrpcTypeToIseVal failed
Oct 23 12:28:59 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =
Oct 23 12:28:59 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: XmlrpcTypeToIseVal failed
Oct 23 12:28:59 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =
Oct 23 12:29:00 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: XmlrpcTypeToIseVal failed
Oct 23 12:29:00 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =
Oct 23 12:29:00 (none) local0.err ReGaHss: Error: IseSingleCondition::GetValData - invalid object ID
Oct 23 12:29:05 (none) daemon.info cuxd: INIT 'xmlrpc_bin://127.0.0.1:1999' '7330'
Oct 23 12:29:06 (none) local0.err ReGaHss: Error: IseESP::ExecError= Execution failed: 0 0x00 99 0x63 0 0x00 100 0x64 0 0x00 92 0x5c
Oct 23 12:29:06 (none) daemon.info cuxd: setup CUX9000006:1 read(JEQ0686199:1) => BidCos-RF.JEQ0686199:1 (WEATHER) DP(|TEMPERATURE|)
Oct 23 12:29:07 (none) daemon.info cuxd: setup CUX9002007:1 read(JEQ0724802:1) => BidCos-RF.JEQ0724802:1 (WEATHER) DP(|TEMPERATURE|HUMIDITY|)
Oct 23 12:29:07 (none) daemon.info cuxd: setup CUX9002008:1 read(JEQ0725751:1) => BidCos-RF.JEQ0725751:1 (WEATHER) DP(|TEMPERATURE|HUMIDITY|)
Oct 23 12:29:07 (none) daemon.info cuxd: setup CUX9002009:1 read(LEQ0177292:1) => BidCos-RF.LEQ0177292:1 (WEATHER) DP(|TEMPERATURE|HUMIDITY|)
Oct 23 12:29:18 (none) user.err rfd: RFPhysicalDataInterfaceCommand::GetData SendFrame failed for LEVEL_GET
Oct 23 12:29:18 (none) user.err rfd: HSSParameter::GetValue() id=STATE failed getting physical value.
Oct 23 12:29:18 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"JEQ0656217:1","STATE"} result=
Oct 23 12:29:18 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:18 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:19 (none) user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 12:29:19 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"JEQ0459101:1","BRIGHTNESS"} result=
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:20 (none) user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"JEQ0459209:1","BRIGHTNESS"} result=
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:20 (none) user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"JEQ0459120:1","BRIGHTNESS"} result=
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:20 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:22 (none) user.err rfd: RFPhysicalDataInterfaceCommand::GetData SendFrame failed for LEVEL_GET
Oct 23 12:29:22 (none) user.err rfd: HSSParameter::GetValue() id=STATE failed getting physical value.
Oct 23 12:29:22 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"JEQ0584426:1","STATE"} result=
Oct 23 12:29:22 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:22 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:26 (none) user.err rfd: RFPhysicalDataInterfaceCommand::GetData SendFrame failed for LEVEL_GET
Oct 23 12:29:26 (none) user.err rfd: HSSParameter::GetValue() id=STATE failed getting physical value.
Oct 23 12:29:26 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"KEQ0023274:1","STATE"} result=
Oct 23 12:29:26 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:26 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:27 (none) user.err rfd: RFPhysicalDataInterfaceCommand::GetData SendFrame failed for LEVEL_GET
Oct 23 12:29:27 (none) user.err rfd: HSSParameter::GetValue() id=STATE failed getting physical value.
Oct 23 12:29:27 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"KEQ0023274:2","STATE"} result=
Oct 23 12:29:27 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:27 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:29:29 (none) user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 12:29:29 (none) local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"JEQ0459140:1","BRIGHTNESS"} result=
Oct 23 12:29:29 (none) local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed
Oct 23 12:29:29 (none) local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0
Oct 23 12:30:01 (none) cron.notice crond: USER root pid 1702 cmd /bin/arm7setclock
Oct 23 12:43:01 (none) syslog.info -- MARK --
Oct 23 12:45:01 (none) cron.notice crond: USER root pid 2030 cmd /bin/arm7setclock
Oct 23 12:53:59 (none) auth.info login: root login on `pts/0'
Oct 23 12:57:45 (none) local0.emerg ReGaHss: Crash: Could not spawn a new request handler! {"HTTP-Listener"}
Ein vergangenes Log enthielt eine weitere verdächtige Meldung. Diese sieht allerdings nach C++ Code aus, muss also Teil der Firmware sein. Kann das sein?:
Oct 20 22:17:57 (none) local0.err ReGaHss: Error: IseESP::SyntaxError= Error 1 at row 288 col 18 near ^::SetBreakOnRestart()^M {^M if( system.IsVar("id") )^M {^M object oTmp = dom^M
Oct 20 22:17:57 (none) local0.err ReGaHss: Error: ParseProgram: SyntaxError= ::SetBreakOnRestart()^M {^M if( system.IsVar("id") )^M {^M object oTmp = dom.GetObject( system.GetVar("id") );^M if( oTmp )^M {^M if( system.GetVar("value") == "true" )^M {^M Write( oTmp.BreakOnRestart( true ) );^M }^M else^M {^M Write( oTmp.BreakOnRestart( false ) );^M }^M }^M }^M return;^M }^M ^M function ::SetActive()^M {^M if( system.IsVar("id") )^M {^M object oTmp = dom.GetObject( system.GetVar("id") );^M if( oTmp )^M {^M if( system.GetVar("value") == "true" )^M {^M Write( oTmp.Active( true ) );^M }^M else^M {^M Write( oTmp.Active( false ) );^M }^M }^M }^M return;^M }^M ^M function ::SetVisible()^M {^M if( system.IsVar("id") )^M {^M object oTmp = dom.GetObject( system.GetVar("id") );^M if( oTmp )^M {^M if( system.GetVar("value") == "true" )^M {^M Wri
Oct 20 22:17:57 (none) user.notice e( oTmp.Visible( true ) );^M }^M
Ich hoffe Sie können mir helfen. Ich habe immer wieder Probleme mit der CCU bezüglich Stabilität und solchen Abstürzen. Leider hab ich schon viel Geld in das Homematic System investiert, sonst haette ich mich schön längst nach einer Alternative umgesehen
Gruss Ralf Zaißer