Adapter hm-rpc friert CCU2 ein

? Offline

Hallo, habe seit mehreren Wochen ein nerviges Problem, dass ich leider nicht in den Griff bekomme:

Meine CCU2 läuft mit mehreren Funksteckdosen und Fernbedienungen.

Wenn ich morgens in die Räumlichkeiten komme, kann ich alles über die Fernbedienung schalten.

Sobald ich aber den PC mit ioBroker hochfahre (und der ioBroker-Dienst startet, nehme ich an), reagiert die CCU2 nicht mehr.

(allerdings nur manchmal, an manchen Tagen klappt es auch reibungslos.)

Ich kann dann weder per Fernbedienung noch per CCU2-WebUI noch per ioBroker irgendetwas schalten.

Im Log von ioBroker steht dann nur reconnectDB. Sonst gar nichts. Das taucht aber auch so immer wieder mal auf.

Ich kann in diesem Zustand per Browser noch auf die CCU2 WebUI zugreifen, allerdings nur auf die Hauptmenüs.

Will ich mir bei Einstellungen zum Beispiel die Log-Datei der CCU2 runterladen, bleibt auch die Webseite der CCU2 hängen.

Ich habe an Adaptern nur admin, hm-rpc und socket.io laufen.

Was ich bisher rausgefunden/probiert habe:

Wenn ich den Stecker von Router und CCU2 ziehe und alles wieder da ist, reagiert die gesamte Anlage wieder und lässt sich auch fehlerfrei über ioBroker und Fernsteuerungen steuern. Das dauert nur leider bis zu 5 Minuten.
Ich habe sämtliche node.js (0.35.0, 4.2.2, 4.2.3) Versionen installiert, weil ich dachte, es hängt damit zusammen. Früher hatte ich diesen Fehler nämlich nie und die Anlage lief über Monate reibungslos ohne jeden Neustart.
Ich habe sämtliche IPs geprüft. Die Firewall in der CCU2 geprüft. Mit xml-rpc und bin-rpc experimentiert. Alles scheint zu stimmen, denn es klappt ja normalerweise auch. Nur egal welche Einstellung ich mache: Der Fehler beim Neustart taucht alle paar Tage neu auf.
Adapter und ioBroker und js-controller (0.82) sind alle auf Standardeinstellungen und in der neuesten Version installiert. Das macht aber keinen Unterschied. In den älteren Versionen gab es den Fehler auch. Ich weiß leider nicht mehr, welche Versionen vor ca. einem Jahr installiert waren, dann da gab es den Fehler noch nicht.
Inzwischen nutze ich sogar einen neuen Rechner mit einer Neuinstallation von Windwos 10. Immer noch dasselbe Problem.
NEUESTE UND WICHTIGSTE ENTECKUNG: Wenn die CCU2 blockiert ist und ich den hm-rpc Adapter im Reiter Instanzen von ioBroker neu starte, dauert es nur Sekunden, und alles läuft wieder!

Ich denke also, dass es dort irgendwo einen Bug gibt?

Oder könnte es was damit zu tun haben, dass es einen zweiten Rechner gibt, der als Backupsystem mit ioBroker läuft? Der hat aber komplett eigene Einstellungen - und leidet am selben Problem wie der Haupt-PC.

FRAGE: Kann ich noch irgendwas tun, um den Fehler zu finden/einzugrenzen/auszumerzen?

In den Log-Dateien von ioBroker und der CCU2 kann ich leider keinen Fehlerhinweis finden. Da steht einfach gar nichts.

ODER NOTLÖSUNG:

Könnte ich eine Windows 10-Batch-Datei schreiben, die ich per Doppelklick aufrufe, für den Fall, dass es hängen bleibt und die hm-rpc einfach neu startet?

Wie sähe die dann aus?

Danke fürs Lesen :)

? Offline

Hier ein paar Logdaten aus einem aktuellen Absturz:

1. CCU2 funktioniert

2. Win10 PC wird um 12:27:30 eingeschalten

3. Bei ca. 12:27:36 (Desktop ist noch NICHT da) reagiert CCU2 nicht mehr

4. Manueller Neustart des hm-rpc-Adapters um 12:31:00

5. Alles funktioniert wieder.

Kann vielleicht mal jemand gucken? Bittebitte?

ioBroker-Log:

admin.0		2015-12-30 12:29:33	info	admin.0 Repository received successfully.
admin.0		2015-12-30 12:29:33	info	admin.0 Repository received successfully.
admin.0		2015-12-30 12:29:27	info	admin.0 Request actual repository...
admin.0		2015-12-30 12:29:27	info	admin.0 Request actual repository...
socketio-0	2015-12-30 12:28:45	warn	Reconnection to DB.
socketio-0	2015-12-30 12:28:45	warn	Reconnection to DB.
hm-rpc-0	2015-12-30 12:28:37	warn	Reconnection to DB.
hm-rpc-0	2015-12-30 12:28:37	warn	Reconnection to DB.
admin-0		2015-12-30 12:28:37	warn	Reconnection to DB.
admin-0		2015-12-30 12:28:37	warn	Reconnection to DB.
hm-rpc-0	2015-12-30 12:27:36	info	binrpc <- listDevices ["hm-rpc.0"]

CCU2-Log:

Dec 30 12:27:34 homematic-ccu2 user.debug rfd: (LEQ1005454) Response status: Telegram sent, received ACK.
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: Response accepted:  @4070875359 RSSI=-46dB 0x2A9E9F -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=65,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 0   STATE = 0   CLOCK = 0   LOWBAT = 0
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: Event: LEQ0478161:1.STATE=false
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:27:34 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0478161:1"."STATE"=false [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 2094 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 2069 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 0 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: Event: LEQ0478161:1.WORKING=false
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:27:34 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0478161:1"."WORKING"=false [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 2094 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 2069 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 0 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:34 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::CallXmlrpcMethod: method =setValue params = {"LEQ0478161:1","STATE",false} result = "" [../Platform/DOM/iseXmlRpc.cpp (2632)]
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: RX for LEQ0478161: @4070875359 RSSI=-46dB 0x2A9E9F -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=65,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 0   STATE = 0   CLOCK = 0   LOWBAT = 0
Dec 30 12:27:34 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: set state - DP id = 2098 [../Platform/RT/iseRTScheduler.cpp (634)]
Dec 30 12:27:34 homematic-ccu2 local0.debug ReGaHss: Verbose: SchedulerRT::Execute -> ExecuteWriteJob: oid = 2098; ticks = 289 [../Platform/RT/iseRTScheduler.cpp (591)]
Dec 30 12:27:34 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ThreadFunction(): wait for event ms= 4294967295 [../Platform/RT/iseRTScheduler.cpp (481)]
Dec 30 12:27:34 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ThreadFunction(): wait for event ms= 4294967295 [../Platform/RT/iseRTScheduler.cpp (481)]
Dec 30 12:27:34 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:36 homematic-ccu2 user.debug rfd: PlatformInit()
Dec 30 12:27:36 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:36 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 4 events
Dec 30 12:27:36 homematic-ccu2 user.info rfd: hm-rpc.0 support event
Dec 30 12:27:36 homematic-ccu2 user.info rfd: hm-rpc.0 support deleteDevices
Dec 30 12:27:36 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:27:36 homematic-ccu2 user.info rfd: hm-rpc.0 support listDevices
Dec 30 12:27:36 homematic-ccu2 user.info rfd: hm-rpc.0 support newDevices
Dec 30 12:27:36 homematic-ccu2 user.info rfd: hm-rpc.0 support system.listMethods
Dec 30 12:27:36 homematic-ccu2 user.info rfd: hm-rpc.0 support system.multicall
Dec 30 12:28:06 homematic-ccu2 local0.info ReGaHss: Info: CheckModifiedThread::ThreadFunction(): check modified flag [../Platform/RT/iseRTDOM.cpp (62)]
Dec 30 12:28:06 homematic-ccu2 local0.info ReGaHss: Info: CheckModifiedThread::ThreadFunction(): start save [../Platform/RT/iseRTDOM.cpp (72)]
Dec 30 12:28:06 homematic-ccu2 local0.info ReGaHss: Info: IsePersist::SaveToFile-> open file succeeded, start save [../Platform/DOM/iseDOMpersist.cpp (219)]
Dec 30 12:28:06 homematic-ccu2 local0.info ReGaHss: Info: IseObject::RemoveUnreferencedObjects() start, count=893 [../Platform/DOM/iseDOMobj.cpp (586)]
Dec 30 12:28:06 homematic-ccu2 local0.info ReGaHss: Info: IseObject::RemoveUnreferencedObjects() removed 0 unreferenced objects, count=893 [../Platform/DOM/iseDOMobj.cpp (692)]
Dec 30 12:28:09 homematic-ccu2 local0.info ReGaHss: Info: IsePersist::SaveToFile-> end save [../Platform/DOM/iseDOMpersist.cpp (225)]
Dec 30 12:28:09 homematic-ccu2 local0.info ReGaHss: Info: IsePersist::SaveToFile-> end save to file= /etc/config/homematic.regadom.new [../Platform/DOM/iseDOMpersist.cpp (233)]

...
...
...

Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Event: LEQ0807702:5.PRESS_SHORT=true
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: RX for LEQ0807702: @4070915853 AES(2) RSSI=-67dB 0x2F53EE -> 0x31FCB5 SWITCH [LEQ1005454]:   CNT=88,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=1,BCAST=1,TYPE=0x40   COUNTER = 185   CHANNEL = 5   LOWBAT = 0   DURATION = 0
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Event: LEQ0807702:5.PRESS_SHORT=true
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: RX for LEQ0807702: @4070958216 AES(2) RSSI=-58dB 0x2F53EE -> 0x31FCB5 SWITCH [LEQ1005454]:   CNT=89,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=1,BCAST=1,TYPE=0x40   COUNTER = 186   CHANNEL = 5   LOWBAT = 0   DURATION = 0
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Event: LEQ0807702:5.PRESS_SHORT=true
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: RX for LEQ0807702: @4071005681 AES(2) RSSI=-56dB 0x2F53EE -> 0x31FCB5 SWITCH [LEQ1005454]:   CNT=90,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=1,BCAST=1,TYPE=0x40   COUNTER = 187   CHANNEL = 5   LOWBAT = 0   DURATION = 0
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Event: LEQ0807702:5.PRESS_SHORT=true
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 8 events
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."INSTALL_TEST"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: try to call set state - DP id = 1343 value = 1 [../Platform/RT/iseRTScheduler.cpp (629)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::GetInterfaceByID: interface name= BidCos-RF for ID=1007 [../Platform/DOM/iseXmlRpc.cpp (2571)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: RX for LEQ0807702: @4071008283 AES(2) RSSI=-57dB 0x2F53EE -> 0x31FCB5 SWITCH [LEQ1005454]:   CNT=91,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=1,BCAST=1,TYPE=0x40   COUNTER = 188   CHANNEL = 5   LOWBAT = 0   DURATION = 0
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Event: LEQ0807702:5.PRESS_SHORT=true
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Event: CENTRAL.PONG="hm-rpc.0"
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 10 events
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: TX:  @4071081671 0x31FCB5 -> 0x2A9E38 CENTRAL_RAMP_START [LEQ1005454]:   CNT=5,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   CHANNEL = 1   LEVEL = 200   RAMPTIME = 0   ONTIME = 0
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 1; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 9 events
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."PRESS_SHORT"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: called Program ID = 1969 with valNew = 1 ; oidTrigger = 1295 [../Platform/DOM/iseDOMPrg.cpp (469)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."PRESS_SHORT"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."PRESS_SHORT"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."PRESS_SHORT"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 1; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: called Program ID = 1969 with valNew = 1 ; oidTrigger = 1295 [../Platform/DOM/iseDOMPrg.cpp (469)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 1; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: called Program ID = 1969 with valNew = 1 ; oidTrigger = 1295 [../Platform/DOM/iseDOMPrg.cpp (469)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."PRESS_SHORT"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 1 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0807702:5"."PRESS_SHORT"=true [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 1971 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 1; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1237 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1290 [../Platform/DOM/iseDOMchn.h (288)]

...
... muss Zeichen kürzen da Beitrag sonst zu lang
...

Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 0 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 2923 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0478264:1"."WORKING"=false [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1339 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1314 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 0 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="CENTRAL"."PONG"="hm-rpc.0" [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:00 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::GetObjectByHSSAddress: no exists device object with address= CENTRAL [../Platform/DOM/iseXmlRpc.cpp (2166)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 2923 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 0; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 2098; oid= 2098; val= 0; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: called Program ID = 1969 with valNew = 1 ; oidTrigger = 1295 [../Platform/DOM/iseDOMPrg.cpp (469)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 0 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 0 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 2923 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 2923 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 0; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 2098; oid= 2098; val= 0; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: called Program ID = 1969 with valNew = 1 ; oidTrigger = 1295 [../Platform/DOM/iseDOMPrg.cpp (469)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 0 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 0 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 0 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1063)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 1, condition result = 0 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 1, condition result = 0 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: DP_VALUE_CHECK_ONLY is set, condition = 1 [../Platform/DOM/iseCondition.cpp (477)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseSingleCondition::Evaluate: type = 1, condition valL = 1 valR1 = 1 [../Platform/DOM/iseCondition.cpp (381)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseCondition::Evaluate: sc = 0, condition result = 1 [../Platform/DOM/iseCondition.cpp (1108)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRule::Evaluate: c = 0, condition result = 1 [../Platform/DOM/iseRule.cpp (229)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::SetState: Rule evaluated state is TRUE by Program ID = 1969; Destination ID = 2923 [../Platform/DOM/iseDOMPrg.cpp (492)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseDOMPrg::SetState: Program ID = 1969, rule's trigger flags = 2 [../Platform/DOM/iseDOMPrg.cpp (506)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: execute Program ID = 1969; Destination ID = 2923 [../Platform/DOM/iseDOMPrg.cpp (602)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: start execute Destination within the trigger delay 0 [../Platform/DOM/iseDOMPrg.cpp (606)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 1343; oid= 1343; val= 0; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::Add: added element - id= 2098; oid= 2098; val= 0; time= 0 [../Platform/RT/iseRTScheduler.cpp (369)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: IseDOMPrg::Execute: pDest->ExecuteDestination succeeded from Program ID = 1969 with valNew = 1 [../Platform/DOM/iseDOMPrg.cpp (616)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: (LEQ1005454) Response status: Telegram sent, received ACK.
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: Response accepted:  @4071082128 RSSI=-47dB 0x2A9E38 -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=14,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 200   STATE = 0   CLOCK = 0   LOWBAT =
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: RX for LEQ0478264: @4071082128 RSSI=-47dB 0x2A9E38 -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=14,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 200   STATE = 0   CLOCK = 0   LOWBAT = 
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::CallXmlrpcMethod: method =setValue params = {"LEQ0478264:1","STATE",true} result = "" [../Platform/DOM/iseXmlRpc.cpp (2632)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: set state - DP id = 1343 [../Platform/RT/iseRTScheduler.cpp (634)]
Dec 30 12:31:00 homematic-ccu2 local0.debug ReGaHss: Verbose: SchedulerRT::Execute -> ExecuteWriteJob: oid = 1343; ticks = 249 [../Platform/RT/iseRTScheduler.cpp (591)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: try to call set state - DP id = 1343 value = 0 [../Platform/RT/iseRTScheduler.cpp (629)]
Dec 30 12:31:00 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::GetInterfaceByID: interface name= BidCos-RF for ID=1007 [../Platform/DOM/iseXmlRpc.cpp (2571)]
Dec 30 12:31:00 homematic-ccu2 user.debug rfd: TX:  @4071082137 0x31FCB5 -> 0x2A9E38 CENTRAL_RAMP_START [LEQ1005454]:   CNT=23,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   CHANNEL = 1   LEVEL = 0   RAMPTIME = 0   ONTIME = 0
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: (LEQ1005454) Response status: Telegram sent, received ACK.
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: Response accepted:  @4071082395 RSSI=-48dB 0x2A9E38 -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=23,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 0   STATE = 0   CLOCK = 0   LOWBAT = 0
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: Event: LEQ0478264:1.STATE=false
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: RX for LEQ0478264: @4071082395 RSSI=-48dB 0x2A9E38 -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=23,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 0   STATE = 0   CLOCK = 0   LOWBAT = 0
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::CallXmlrpcMethod: method =setValue params = {"LEQ0478264:1","STATE",false} result = "" [../Platform/DOM/iseXmlRpc.cpp (2632)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: set state - DP id = 1343 [../Platform/RT/iseRTScheduler.cpp (634)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: SchedulerRT::Execute -> ExecuteWriteJob: oid = 1343; ticks = 271 [../Platform/RT/iseRTScheduler.cpp (591)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: try to call set state - DP id = 2098 value = 0 [../Platform/RT/iseRTScheduler.cpp (629)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::GetInterfaceByID: interface name= BidCos-RF for ID=1007 [../Platform/DOM/iseXmlRpc.cpp (2571)]
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: TX:  @4071082410 0x31FCB5 -> 0x2A9E9F CENTRAL_RAMP_START [LEQ1005454]:   CNT=74,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   CHANNEL = 1   LEVEL = 0   RAMPTIME = 0   ONTIME = 0
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0478264:1"."STATE"=false [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 1339 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 1314 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 0 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: (LEQ1005454) Response status: Telegram sent, received ACK.
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: Response accepted:  @4071082663 RSSI=-46dB 0x2A9E9F -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=74,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 0   STATE = 0   CLOCK = 0   LOWBAT = 0
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: Event: LEQ0478161:1.STATE=false
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: Event: LEQ0478161:1.WORKING=false
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: RX for LEQ0478161: @4071082663 RSSI=-46dB 0x2A9E9F -> 0x31FCB5 ACK_STATUS [LEQ1005454]:   CNT=74,RPTEN=1,RPTED=0,BIDI=0,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x02   CHANNEL = 1   STATUS = 0   STATE = 0   CLOCK = 0   LOWBAT = 0
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 2 events
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 2 events
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 2 events
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: IseXmlRpc::CallXmlrpcMethod: method =setValue params = {"LEQ0478161:1","STATE",false} result = "" [../Platform/DOM/iseXmlRpc.cpp (2632)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ExecuteWriteJob: set state - DP id = 2098 [../Platform/RT/iseRTScheduler.cpp (634)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: SchedulerRT::Execute -> ExecuteWriteJob: oid = 2098; ticks = 272 [../Platform/RT/iseRTScheduler.cpp (591)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ThreadFunction(): wait for event ms= 4294967295 [../Platform/RT/iseRTScheduler.cpp (481)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: SchedulerRT::ThreadFunction(): wait for event ms= 4294967295 [../Platform/RT/iseRTScheduler.cpp (481)]
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0478161:1"."STATE"=false [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 2094 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 2069 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 0 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:01 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="LEQ0478161:1"."WORKING"=false [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: CHANNEL::SetLastDPActionTime: call on change; id = 2094 [../Platform/DOM/iseDOMchn.h (288)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: DEVICE::SetLastActionTime: call on change; id = 2069 [../Platform/DOM/iseDOMdev.h (225)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: EventThread::ThreadFunction: wait max timeout = 240000 [../Platform/RT/iseRTEvent.cpp (69)]
Dec 30 12:31:01 homematic-ccu2 local0.debug ReGaHss: Verbose: XmlRpcMethodEvent::execute: UpdateValue succeeded; val = 0 [../Platform/DOM/iseXmlRpc.cpp (379)]
Dec 30 12:31:01 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: PlatformInit()
Dec 30 12:31:05 homematic-ccu2 user.info rfd: hm-rpc.0 support event
Dec 30 12:31:05 homematic-ccu2 user.info rfd: hm-rpc.0 support deleteDevices
Dec 30 12:31:05 homematic-ccu2 user.info rfd: hm-rpc.0 support listDevices
Dec 30 12:31:05 homematic-ccu2 user.info rfd: hm-rpc.0 support newDevices
Dec 30 12:31:05 homematic-ccu2 user.info rfd: hm-rpc.0 support system.listMethods
Dec 30 12:31:05 homematic-ccu2 user.info rfd: hm-rpc.0 support system.multicall
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-RCV-50 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Dim1T-Pl 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Dim1T-Pl 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-RC-P1 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for ZEL STG RM HS 4 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for ZEL STG RM WT 2 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for ZEL STG RM FFK 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Sw1-Pl-2 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Sw1-Pl-2 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Sw1-Pl-2 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Sw1-Pl-2 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-PB-2-WM55 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-RC-8 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: GetAvailableFirmware for HM-LC-Sw1PBU-FM 
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: ServiceEvent {"hm-rpc.0","JEQ0648287:0","LOWBAT",true}
Dec 30 12:31:05 homematic-ccu2 user.debug rfd: ServiceEvent {"hm-rpc.0","LEQ0478010:0","UNREACH",true}
Dec 30 12:31:07 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:07 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 16 events
Dec 30 12:31:07 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:19 homematic-ccu2 local0.debug ReGaHss: Verbose: TimerThread::ThreadFunction: ISETIMEZONE = -3600 [../Platform/RT/iseRTTimer.cpp (81)]
Dec 30 12:31:19 homematic-ccu2 local0.debug ReGaHss: Verbose: FillTimerEventList: DST offset = 0 [../Platform/RT/iseRTTimer.cpp (422)]
Dec 30 12:31:19 homematic-ccu2 local0.debug ReGaHss: Verbose: IseRT::SetFinishedDPInitEvent: TimerInitDPsComplete event setting [../Platform/RT/iseRT.cpp (373)]
Dec 30 12:31:19 homematic-ccu2 local0.info ReGaHss: Info: TimerThread::ThreadFunction() - no timer exists, so Wait (60 s) ! [../Platform/RT/iseRTTimer.cpp (209)]
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: Event: CENTRAL.PONG="hm-rpc.0"
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send 1 events
Dec 30 12:31:22 homematic-ccu2 local0.info ReGaHss: Info: XmlRpcMethodEvent::execute: Event="CENTRAL"."PONG"="hm-rpc.0" [../Platform/DOM/iseXmlRpc.cpp (344)]
Dec 30 12:31:22 homematic-ccu2 local0.err ReGaHss: Error: IseXmlRpc::GetObjectByHSSAddress: no exists device object with address= CENTRAL [../Platform/DOM/iseXmlRpc.cpp (2166)]
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed
Dec 30 12:31:22 homematic-ccu2 user.debug rfd: HSSXmlRpcEventDispatcher::Handle send completed

Jey Cee

Stell mal die Log Stufe vom hm-rpc Adapter auf Debug. Kannst du in der Instanz übersicht machen in dem du Doppelt auf "Info" in der Spalte Log Stufe klickst.

Viel geben die Logs nicht her. Scheint aber so das bei der Abfrage der Geräte die an der CCU angemeldet sind etwas schief läuft.

Im CCU log sieht es bei 12:31:00 so aus das nachdem Neustart kein listDevices mehr ausgeführt wird. Oder findet das statt und du hast es nicht mit rein kopiert?

? Offline

Danke für die Antwort. Das mit "debug" kannte ich noch nicht, habs jetzt eingestellt.

Das Dumme:

Bin die letzten zwei Wochen nicht ans Gerät rangekommen (deshalb auch die späte Antwort, sorry)

Meine provisorische Lösung war, den hm-rpc-Adapter einfach nach Start des PCs über Autostart mit einer Batchdatei neu zu starten.

Einfach: c:\iobroker\iobroker restart hm-rpc

Das hat geklpappt.

Zwei Wochen lief nun alles reibungslos.

Heute Abend hatte ich mal Zeit, diese Notlösung zu deaktivieren und wieder nach dem Bug zu suchen.

Und jetzt kann ich ihn nicht mehr reproduzieren!

Ahh! ich werde noch wahnsinnig. Vor zwei Wochen konnte ich die CCU mit einem simplen Neustart des PCs noch gezielt einfrieren lassen - und mit einem refresh des Adapters wieder alles ans Laufen bringen.

Jetzt läuft es auch so - und in den DEBUG-Logs krieg ich also folglich keine Fehlermeldung.

Ich kann über die beiden Wochen auch keine Updates erkennen. Weder in den Adaptern

admin 0.7.2

hm-rpc 0.5.2

sockets.io 1.2.3 (oder war das 1.2.2? hm…)

noch in iobroker.js-controller 0.8.2

noch in node.js 0.10.35

oder npm 1.4.28

Hm...

Mir bleibt nur eins: Ich melde mich wieder, sobald ich was rausgefunden habe.

Die autostart.bat lasse ich solange trotzdem sicherheitshalber drin.

Achso: Zwischendurch hatte ich mal das hier gefunden:

                2016-01-07 19:57:34	info	instance system.adapter.hm-rpc.0 terminated with code 6 (uncaught exception)
Error:	2016-01-07 19:57:34	error	at TCP.onread (net.js:559:19)
Error:	2016-01-07 19:57:34	error	at errnoException (net.js:905:11)
Error:	2016-01-07 19:57:34	error	read ECONNRESET
uncaught	2016-01-07 19:57:34	error	exception: read ECONNRESET

Da ist aus irgendwelchen Gründen beim einloggen der Adapter nicht gestartet.

Aber ich glaube, das hat nichts damit zu tun, oder?

? Offline

Habs DOCH NOCH reproduzieren können!

Ich musste nur ein, zwei Minuten den Rechner auslassen.

Jetzt friert der PC die CCU2 wieder ein.

ALso nochmal Ablauf:

PC aus.

CCU2 funktioniert.

PC an.

Bis zum Login und PIN-Eingabe.

CCU2 reagiert nicht mehr.

Einloggen.

iobroker-WebUI aufrufen: Alle Adapter laufen, aber NICHTS im Log.

Neustart hm-rpc-Adapters: Log füllt sich und CCU2 reagiert wieder.

Interessant ist:

Wenn ich den PC bei eingefrorener CCU2 ausmache, bleibt sie eingefroren = Lässt sich nicht mehr bedienen.

Erst wenn ich den PC wieder anmache und den Adapter neu starte (oder der CCU den Stecker ziehe), reagiert die CCU wieder.

Das hier stand im Log, nachdem ich den hm-rpc-Adapter neu gestartet habe (vorher war wie gesagt nichts drin):

TARDIS	2016-01-08 00:04:52	warn	host.TARDIS instance system.adapter.hm-rpc.0 already running with pid 8088
TARDIS	2016-01-08 00:04:52	warn	host.TARDIS instance system.adapter.hm-rpc.0 already running with pid 8088
hm-rpc.0	2016-01-08 00:04:30	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0478264.1.STATE
hm-rpc.0	2016-01-08 00:04:30	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0478264.1.WORKING
hm-rpc.0	2016-01-08 00:04:30	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0478264:1","STATE",true]
hm-rpc.0	2016-01-08 00:04:30	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0478264:1","WORKING",false]
hm-rpc.0	2016-01-08 00:04:30	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0478010.0.UNREACH
hm-rpc.0	2016-01-08 00:04:30	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0478010:0","UNREACH",true]
hm-rpc.0	2016-01-08 00:04:30	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0478161.1.WORKING
hm-rpc.0	2016-01-08 00:04:30	info	hm-rpc.0 binrpc -> 103 devices
hm-rpc.0	2016-01-08 00:04:30	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0478161.1.STATE
hm-rpc.0	2016-01-08 00:04:30	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0478161:1","WORKING",false]
hm-rpc.0	2016-01-08 00:04:30	info	hm-rpc.0 binrpc <- listDevices ["hm-rpc.0"]
hm-rpc.0	2016-01-08 00:04:30	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0478161:1","STATE",false]
hm-rpc.0	2016-01-08 00:04:30	info	hm-rpc.0 binrpc <- system.listMethods ["hm-rpc.0"]
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0478010.0.UNREACH
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.INSTALL_TEST
hm-rpc.0	2016-01-08 00:04:29	debug	inMem message hm-rpc.0.* hm-rpc.0.LEQ0807702.5.PRESS_SHORT
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0478010:0","UNREACH",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","INSTALL_TEST",true]
hm-rpc.0	2016-01-08 00:04:29	debug	hm-rpc.0 binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc.0	2016-01-08 00:04:29	info	hm-rpc.0 binrpc -> 103 devices
hm-rpc.0	2016-01-08 00:04:29	info	hm-rpc.0 binrpc <- listDevices ["hm-rpc.0"]
hm-rpc-0	2016-01-08 00:04:26	debug	Send INIT...
hm-rpc-0	2016-01-08 00:04:26	info	binrpc -> 192.168.178.30:2001 init ["xmlrpc_bin://192.168.178.52:2001","hm-rpc.0"]
hm-rpc-0	2016-01-08 00:04:26	info	binrpc server is trying to listen on 192.168.178.52:2001
hm-rpc-0	2016-01-08 00:04:26	debug	statesDB connected
hm-rpc-0	2016-01-08 00:04:26	debug	objectDB connected
host-TARDIS	2016-01-08 00:04:24	info	instance system.adapter.hm-rpc.0 started with pid 8088
host-TARDIS	2016-01-08 00:04:22	info	Restart adapter system.adapter.hm-rpc.0 because enabled
host-TARDIS	2016-01-08 00:04:22	warn	instance system.adapter.hm-rpc.0 terminated due to SIGTERM
host-TARDIS	2016-01-08 00:04:22	info	stopInstance system.adapter.hm-rpc.0 killing pid 6952
host-TARDIS	2016-01-08 00:04:22	info	stopInstance system.adapter.hm-rpc.0
host-TARDIS	2016-01-08 00:04:22	info	object change system.adapter.hm-rpc.0
inMem	2016-01-08 00:03:58	debug	message *.logging system.adapter.admin.0.logging val=true, ack=true, ts=1452207839, q=0, from=system.adapter.admin.0, lc=1452207839
inMem	2016-01-08 00:03:58	debug	message *.logging system.adapter.admin.0.logging val=false, ack=true, ts=1452207838, q=0, from=system.adapter.admin.0, lc=1452207838
inMem	2016-01-08 00:02:55	debug	message *.logging system.adapter.admin.0.logging val=true, ack=true, ts=1452207776, q=0, from=system.adapter.admin.0, lc=1452207776
hm-rpc-0	2016-01-08 00:02:54	debug	Send PING...
socketio-0	2016-01-08 00:02:29	warn	Reconnection to DB.
socketio-0	2016-01-08 00:02:29	warn	Reconnection to DB.
hm-rpc-0	2016-01-08 00:02:25	warn	Reconnection to DB.
hm-rpc-0	2016-01-08 00:02:25	warn	Reconnection to DB.
ping	2016-01-08 00:02:24	debug	timeout
admin-0	2016-01-08 00:02:23	warn	Reconnection to DB.
admin-0	2016-01-08 00:02:23	warn	Reconnection to DB.
hm-rpc-0	2016-01-08 00:01:25	debug	binrpc <- event ["hm-rpc.0","LEQ0478264:1","STATE",false]
hm-rpc-0	2016-01-08 00:01:25	debug	binrpc <- event ["hm-rpc.0","LEQ0478264:1","WORKING",false]
hm-rpc-0	2016-01-08 00:01:25	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","INSTALL_TEST",true]
hm-rpc-0	2016-01-08 00:01:25	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc-0	2016-01-08 00:01:25	info	binrpc <- listDevices ["hm-rpc.0"]
hm-rpc-0	2016-01-08 00:01:25	info	binrpc <- system.listMethods ["hm-rpc.0"]
hm-rpc-0	2016-01-08 00:01:24	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","INSTALL_TEST",true]
hm-rpc-0	2016-01-08 00:01:24	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]

Kann man da irgendwas erkennen?

? Offline

Ich kann auch nochmal bestätigen:

Der PC muss zwischen zwei Neustarts ein, zwei Minuten aus sein, damit der Fehler auftritt.

Wenn ich direkt runter und wieder hochfahre, blockiert er die CCU nicht.

Nochmal ein Log:

host-TARDIS	2016-01-08 00:33:30	info	Restart adapter system.adapter.hm-rpc.0 because enabled
host-TARDIS	2016-01-08 00:33:30	warn	instance system.adapter.hm-rpc.0 terminated due to SIGTERM
host-TARDIS	2016-01-08 00:33:30	info	stopInstance system.adapter.hm-rpc.0 killing pid 1008
host-TARDIS	2016-01-08 00:33:30	info	stopInstance system.adapter.hm-rpc.0
host-TARDIS	2016-01-08 00:33:30	info	object change system.adapter.hm-rpc.0
hm-rpc-0	2016-01-08 00:32:52	debug	Send PING...
socketio-0	2016-01-08 00:32:29	warn	Reconnection to DB.
socketio-0	2016-01-08 00:32:29	warn	Reconnection to DB.
hm-rpc-0	2016-01-08 00:32:23	warn	Reconnection to DB.
hm-rpc-0	2016-01-08 00:32:23	warn	Reconnection to DB.
ping	2016-01-08 00:32:22	debug	timeout
admin-0	2016-01-08 00:32:17	warn	Reconnection to DB.
admin-0	2016-01-08 00:32:17	warn	Reconnection to DB.
hm-rpc-0	2016-01-08 00:31:22	info	binrpc <- listDevices ["hm-rpc.0"]
hm-rpc-0	2016-01-08 00:31:22	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","INSTALL_TEST",true]
hm-rpc-0	2016-01-08 00:31:22	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc-0	2016-01-08 00:31:22	info	binrpc <- system.listMethods ["hm-rpc.0"]
hm-rpc-0	2016-01-08 00:31:22	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","INSTALL_TEST",true]
hm-rpc-0	2016-01-08 00:31:22	debug	binrpc <- event ["hm-rpc.0","LEQ0807702:5","PRESS_SHORT",true]
hm-rpc-0	2016-01-08 00:31:22	debug	Send INIT...

AAA Das hier sind die Meldungen, nachdem ich den PC neu gestartet habe AAA

VVV Letzte Meldungen, bevor ich den PC ausgeschaltet hab VVV

inMem	2016-01-08 00:27:49	debug	message *.logging system.adapter.admin.0.logging val=false, ack=true, ts=1452209270, q=0, from=system.adapter.admin.0, lc=1452209270
inMem	2016-01-08 00:27:25	debug	message *.logging system.adapter.admin.0.logging val=true, ack=true, ts=1452209246, q=0, from=system.adapter.admin.0, lc=1452209246

? Offline

Ich habe noch etwas rausgefunden.

Zumindest eine Vermutung:

Ich glaube, es hat was mit der Passworteingabe/der Nutzerauswahl von Windows 10 zu tun.

Ich habe 2 Nutzer auf dem System:

Admin und Gast

Admin MIT Passwort.

Gast OHNE Passwort.

Sobald der User-Auswahlbildschirm oder die Passworteingabe kommt, friert die CCU2 ein - und erholt sich erst nach Neustart.

Wenn der PC automatisch Gast startet (ohne Auswahl, ohne Passworteingabe … Das tut er, wenn ich vorher als Gast drin war und den PC auch vom Gast-Account runtergefahren wurde), passiert auch nichts mit der CCU. Glaube ich zumindest, hab's nur 1-2 Mal getestet.

Das würde auch erklären, warum es viele Monate reibungslos geklappt hat. Da hatte ich nämlich immer nur einen Admin-Account ohne Passwort.

Muss ich irgendwelche Sicherheitseinstellungen ändern? Irgendwas freigeben?

Oder liegt es daran, dass ich ioBroker im Hauptverzeichnis installiert habe, statt unter einem User?

Aber wieso funktioniert es dann beim Gastaccount (nur wenn der Auswahlbildschirm nicht kommt)?

Jemand eine Idee? Oder ist das die falsche Spur?

Jey Cee

Ich tippe mal darauf das es was damit zu tun hat das iobroker gestartet wird bevor du dich anmeldest und das verursacht das Problem. Möglicherweise werden dienste von den iobroker abhängig ist erst nach der Anmeldung geladen. Die Lösung wäre den Dienst erst nach der Anmeldung des Benutzers zu laden.

Gesendet von meinem Jolla mit Tapatalk

Beatz

Hallo,

ich hatte die Tage ein ähnliches Problem, auch wenn die Fehlermeldungen im Log nicht ganz identisch waren. Ich habe iobroker zwar auf einem raspi laufen, aber der rpc-Adapter hat reproduzierbar immer meine CCU abgeschossen. Auch im Log waren danach keine Einträge mehr zu sehen.

Geholfen hat, die beiden Adapter rpc und rega zu löschen und neu zu installieren. Seitdem läuft mein System wieder stabil.

Viele Grüße

Andreas

? Offline

@Beatz:

Vielen Dank. Das klingt schon sehr ähnlich. Aber Neuinstallation habe ich schon Tausend Mal probiert. In verschiedensten Programm-Versionen und von automatisch bis manuell bis wasweißich. Jedes mal mit Löschung aller Ordner und Caches und hastdunichtgesehen. Inzwischen st es ja sogar ein neuer Computer mit neuem Betriebssystem. Der Fehler bleibt leider.

@Jey Cee:

Das könnte schon irgendwie sein.

Irgendetwas startet.

Etwas anderes ist aber noch nicht gestartet, weil das erst auf die Authentifizierung wartet.

Und schon ist die richtige Reihenfolge dahin - und der erste Dienst .. tja.. bombardiert die CCU mit Abfragen? Oder lässt sie in einem Wartungszustand zurück? Keine Ahnung.

Ich hatte den ioBroker-DIenst mal auf "verzögerten Start" gesetzt. Das hat leider nicht wirklich geholfen. Ich weiß nicht, ob der Fehler so weg ging. Aber das Problem war dann, dass der Dienst sehr spät gestartet wurde, was die (Gast-)Nutzer extrem verwirrt hat und weitere Eingriffe nötig macht.

Aber ist das überhaupt der richtige Dienst? Oder müsste es was mit node.js sein?

Gibt es einen Weg, Windows 10 oder node.js zu sagen, was wann wie mit welchen Rechten starten soll?

Jey Cee

Du könntest mal "Dienste" suchen und schauen was du dort für möglichkeiten hast.

Gesendet von meinem Jolla mit Tapatalk

? Offline

Habe ich.

Da war eine Option:

"Datenaustausch zwischen Dienst und Desktop zulassen."

Die habe ich mal angekreuzt.

Und jetzt… GEHT ES ANSCHEINEND :D :D :D

Ich habe keine Ahnung, aus dem Internet werd ich nicht ganz schlau, wozu er gut ist.

Die meisten sagen, man soll ihn nicht setzen.

Im Moment gelingt es mir aber nicht, den Fehler zu reproduzieren.

Ob das nur vorübergehend ist, weiß ich nicht. Ganz koscher kommt mir die Sache immer noch nicht vor.

Melde mich, wenn's was Neues gibt.

Wenn jemand weiß, was hier vor sich geht, würde ich es wirklich gern erfahren.

? Offline

Zu früh gefreut… :( :(

Hatte den PC wohl nicht lang genug aus.

:cry:

greyhound

Hallo,

bei der Recherche zu meinem Problem bin ich auf diese Diskussion gestoßen und da nicht als gelöst markiert, könnte es ja noch interessierte geben.

Meine momentane Installation:

Livesystem auf Raspberry Pi3

Test/Dev auf Windows10

Homematic CCU2, u.a. mit einigen Fenster- und Türsensoren

Beide Installationen sind aktuell und in beiden habe ich hm-rpc installiert, konfiguriert und am Laufen.

Ich hatte mir nun das Nuki-Türschloss zugelegt und habe dieses Zwecks besserer Integration mit dem Sensor der Haustür gekoppelt, etwas geskriptet und mir beim Öffnen und Schließen per Telegram eine Info zukommen lassen.

Mich hat es dann gewundert, dass ich am Tage keine Nachrichten bekommen. Zu Hause musste ich dann feststellen, dass die CCU2 (oder wer auch immer für die Sensordaten verantwortlich ist) eingefroren war. Es wurde der Status von keinem Sensor mehr erkannt. Ein weiteres Zeichen dafür ist, dass über die GUI das Systemmenü nicht aktivierbar/ aufgebaut wird. Nach einem Neustart (reboot) der CCU2 war wieder alles schön - bis zum nächsten Morgen.

Nach der Einschränkung des Zeitraumes, in welchem die CCU2 bei mir einfriert und dem Lesen dieser Diskussion, bin ich zu der Überzeugung gekommen, dass es am Schlafengehen meines Windowsrechners liegen muss. Wahrscheinlich wird die hm-rpv-Verbindung auf der CCU2 nicht terminiert, wenn ich meinen PC in den Ruhemodus sende, sondern mutiert zu einem Zombie und blockiert dann alles.

Ich werde die Woche mal ausprobieren, ob es anders ist, wenn ich den ioBroker-Dienst vor dem Ruhemodus beende.

Gruß

GH

greyhound

lasse nur noch einen ioBroker (24/7) auf CCu2 zugreifen und seit dem keine Probleme mehr.

Community Forum

NEWS

Adapter hm-rpc friert CCU2 ein

Support us

715

32.6k

82.1k

1.3m