Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.500000 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.980000 [execute():iseXmlRpc.cpp:321] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.980000 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304698.799805' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304698.799805 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304698.799805' [Execute():iseDOMPrg.cpp:625] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 4 [Execute():iseRTScheduler.cpp:604] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:08:48 homematic1 user.debug ^E: C> @1064291143: #74 LLMAC Response ACK @7830: 1E 96 Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: recvd 434 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /index.htm [GetSessionId():iseSession.cpp:185] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /index.htm [GetSessionId():iseSession.cpp:185] Nov 22 20:08:48 homematic1 local0.debug ReGaHss: Verbose: flag is set to = 1 from Line = 3215 [SetDOMModifiedFlag():iseESPexec.cpp:13465] Nov 22 20:08:48 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: recvd 458 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /pages/index.htm?client=3 [GetSessionId():iseSession.cpp:185] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: IseSession Created: id: KPQLRib8Va Request URL: /pages/index.htm?client=3 [GetFreeSession():iseSession.cpp:127] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: recvd 466 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /pages/index.htm?sid=@KPQLRib8Va@ [GetSessionId():iseSession.cpp:185] Nov 22 20:08:49 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 83 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 385 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 257 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 248 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 173 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: recvd 237 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:50 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: interface name= HmIP-RF for ID=2338 [GetInterfaceByID():iseXmlRpc.cpp:2552] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 718 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /pages/tabs/startpage.htm?sid=@KPQLRib8Va@ [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 62 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 323 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 user.debug ^E: A>: #93 HmSystem DutyCycleRequest Nov 22 20:08:51 homematic1 user.debug ^E: A<: #93 HmSystem Response 02 05 Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:51 homematic1 user.debug ^E: A>: #94 HmSystem DutyCycleRequest Nov 22 20:08:51 homematic1 user.debug ^E: A<: #94 HmSystem Response 02 05 Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:52 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:52 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:52 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:52 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:52 homematic1 user.debug ^E: C<: #75 TRX GetDutyCycle Nov 22 20:08:52 homematic1 user.debug ^E: C< @1064295586: bin:FD 00 03 01 4B 03 A2 18 Nov 22 20:08:52 homematic1 user.debug ^E: C> @1064295590: #75 TRX Response Ack 05 Nov 22 20:08:52 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:08:54 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:54 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:54 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:08:54 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:55 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:55 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:55 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:08:55 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:56 homematic1 user.debug ^E: C> @1064299027: #87 LLMAC RX @15729ms -81dBm AD A4 5F 2E 0D AC 16 32 1B AE 7E 78 03 26 D7 23 26 08 FD FE Nov 22 20:08:56 homematic1 user.debug ^E: Bidcos RX: #AD[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7E 78 03 26 D7 23 26 08 FD FE Nov 22 20:08:56 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:08:56 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @15830ms [10k,NoCCA] AD 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:08:56 homematic1 user.debug ^E: AutoTx: #AD[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:08:56 homematic1 user.debug ^E: C<: #76 LLMAC TX @15830ms [10k,NoCCA] AD 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:08:56 homematic1 user.debug ^E: A<: #163 HmBidcos RxTelegram AuthNotNecessary #AD[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7E 78 03 26 D7 23 26 08 FD FE Nov 22 20:08:56 homematic1 user.debug ^E: C< @1064299028: bin:FD 00 10 03 4C 06 3D D6 80 AD 80 02 16 32 1B 2E 0D AC 00 76 06 Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304703.199707 [execute():iseXmlRpc.cpp:321] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304703.199707 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=2065.509998 [execute():iseXmlRpc.cpp:321] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 2065.509998 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8998.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8998.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=230.100000 [execute():iseXmlRpc.cpp:321] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.100000 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.980000 [execute():iseXmlRpc.cpp:321] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.980000 [execute():iseXmlRpc.cpp:364] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304703.199707' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304703.199707 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304703.199707' [Execute():iseDOMPrg.cpp:625] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:08:56 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:08:56 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:08:56 homematic1 user.debug ^E: C> @1064299144: #76 LLMAC Response ACK @15830: 3D D6 Nov 22 20:08:57 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:57 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:57 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:08:57 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:59 homematic1 user.err Sniffing Mode: XmlRpc transport error calling system.listMethods({"RF-ccu"}) on http://192.168.2.4:9125/RPC2: Nov 22 20:08:59 homematic1 user.err Sniffing Mode: XmlRpc transport error calling listDevices({"RF-ccu"}) on http://192.168.2.4:9125/RPC2: Nov 22 20:08:59 homematic1 user.err Sniffing Mode: XmlRpcClient error calling system.multicall({{[methodName:"event",params:{"RF-ccu","HEQ0139353:0","STICKY_UNREACH",true}],[methodName:"event",params:{"RF-ccu","HEQ0139353:0","UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0058576:0","UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0069563:0","STICKY_UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0069563:0","UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0079479:0","STICKY_UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0079479:0","UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0378453:0","STICKY_UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0378453:0","UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0379556:0","STICKY_UNREACH",true}],[methodName:"event",params:{"RF-ccu","IEQ0379556:0","UNREACH",true}],[methodName:"event",params:{"RF-ccu","OEQ2018348:0","STICKY_UNREACH",true}],[methodName:"event",params:{"RF-ccu","OEQ2018348:0","UNREACH",true}],[me Nov 22 20:08:59 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: recvd 731 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /pages/tabs/control/systemProtocol.htm?sid=@KPQLRib8Va@ [GetSessionId():iseSession.cpp:185] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: recvd 745 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /pages/tabs/control/systemProtocolLoader.htm?sid=@KPQLRib8Va@ [GetSessionId():iseSession.cpp:185] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: recvd 787 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@ [GetSessionId():iseSession.cpp:185] Nov 22 20:08:59 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:00 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:02 homematic1 user.debug ^E: C<: #77 TRX GetDutyCycle Nov 22 20:09:02 homematic1 user.debug ^E: C< @1064305586: bin:FD 00 03 01 4D 03 B6 18 Nov 22 20:09:02 homematic1 user.debug ^E: C> @1064305690: #77 TRX Response Ack 05 Nov 22 20:09:02 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:09:03 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:03 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:03 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:03 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:04 homematic1 user.debug ^E: C> @1064307027: #88 LLMAC RX @23729ms -82dBm AE A4 5F 2E 0D AC 16 32 1B AE 7E A6 03 19 8D 22 7D 08 FF FE Nov 22 20:09:04 homematic1 user.debug ^E: Bidcos RX: #AE[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7E A6 03 19 8D 22 7D 08 FF FE Nov 22 20:09:04 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:09:04 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @23830ms [10k,NoCCA] AE 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:09:04 homematic1 user.debug ^E: AutoTx: #AE[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:09:04 homematic1 user.debug ^E: C<: #78 LLMAC TX @23830ms [10k,NoCCA] AE 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:09:04 homematic1 user.debug ^E: A<: #166 HmBidcos RxTelegram AuthNotNecessary #AE[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7E A6 03 19 8D 22 7D 08 FF FE Nov 22 20:09:04 homematic1 user.debug ^E: C< @1064307028: bin:FD 00 10 03 4E 06 5D 16 80 AE 80 02 16 32 1B 2E 0D AC 00 64 75 Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:04 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","LEQ0532838:2","BOOT",true}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","ENERGY_COUNTER",304707.800000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","POWER",2031.490000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","CURRENT",8829.000000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","VOLTAGE",230.300000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","FREQUENCY",49.980000}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:04 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304707.799805 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304707.799805 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=2031.490000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304707.799805' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304707.799805 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304707.799805' [Execute():iseDOMPrg.cpp:625] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 2031.490000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8829.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8829.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=230.300000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.300000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.980000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 4 [Execute():iseRTScheduler.cpp:604] Nov 22 20:09:04 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.980000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:04 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:04 homematic1 user.debug ^E: C> @1064307144: #78 LLMAC Response ACK @23830: 5D 16 Nov 22 20:09:05 homematic1 user.debug ^E: C> @1064308253: #89 LLMAC RX @24956ms -65dBm 97 86 70 16 A3 77 00 00 00 00 BD 30 Nov 22 20:09:05 homematic1 user.debug ^E: Bidcos RX: #97[BC|Ren|WMup] 16A377->000000 WeatherData: 00 BD 30 Nov 22 20:09:05 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_NotForUs, wakeup=None Nov 22 20:09:05 homematic1 user.debug ^E: A<: #168 HmBidcos RxTelegram AuthNone #97[BC|Ren|WMup] 16A377->000000 WeatherData: 00 BD 30 Nov 22 20:09:05 homematic1 local0.info ReGaHss: Info: Event="IEQ0077925:1"."TEMPERATURE"=18.900000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1829 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1811 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 18.900000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:05 homematic1 local0.info ReGaHss: Info: Event="IEQ0077925:1"."HUMIDITY"=48 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1829 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1811 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:05 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","IEQ0077925:1","TEMPERATURE",18.900000}],[methodName:"event",params:{"RF-ccu","IEQ0077925:1","HUMIDITY",48}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 48 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:05 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:05 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:05 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:05 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:05 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:05 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:06 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:06 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:06 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:06 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:09 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:09 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:09 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:09 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:10 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:10 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:10 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:10 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:12 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:12 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:12 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:12 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:12 homematic1 user.debug ^E: C<: #79 TRX GetDutyCycle Nov 22 20:09:12 homematic1 user.debug ^E: C< @1064315587: bin:FD 00 03 01 4F 03 3A 1B Nov 22 20:09:12 homematic1 user.debug ^E: C> @1064315690: #79 TRX Response Ack 05 Nov 22 20:09:12 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:09:14 homematic1 user.debug ^E: C> @1064317027: #90 LLMAC RX @ 960ms -83dBm AF A4 5F 2E 0D AC 16 32 1B AE 7E DE 03 09 23 21 B6 09 01 FF Nov 22 20:09:14 homematic1 user.debug ^E: Bidcos RX: #AF[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7E DE 03 09 23 21 B6 09 01 FF Nov 22 20:09:14 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:09:14 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @ 1061ms [10k,NoCCA] AF 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:09:14 homematic1 user.debug ^E: AutoTx: #AF[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:09:14 homematic1 user.debug ^E: C<: #80 LLMAC TX @ 1061ms [10k,NoCCA] AF 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:09:14 homematic1 user.debug ^E: A<: #171 HmBidcos RxTelegram AuthNotNecessary #AF[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7E DE 03 09 23 21 B6 09 01 FF Nov 22 20:09:14 homematic1 user.debug ^E: C< @1064317028: bin:FD 00 10 03 50 06 04 25 80 AF 80 02 16 32 1B 2E 0D AC 00 C9 76 Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304713.399902 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:14 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","LEQ0532838:2","BOOT",true}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","ENERGY_COUNTER",304713.400000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","POWER",1989.470000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","CURRENT",8630.000000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","VOLTAGE",230.500000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","FREQUENCY",49.990000}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:14 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304713.399902 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=1989.469999 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1989.469999 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8630.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8630.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=230.500000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.500000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.990000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.990000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304713.399902' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304713.399902 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304713.399902' [Execute():iseDOMPrg.cpp:625] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:14 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:09:14 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:09:14 homematic1 user.debug ^E: C> @1064317143: #80 LLMAC Response ACK @1061: 04 25 Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:15 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:18 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:18 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:18 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:18 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:19 homematic1 user.debug ^E: C> @1064322294: #91 LLMAC RX @ 6228ms -78dBm C4 86 70 16 6B B9 00 00 00 00 E0 24 Nov 22 20:09:19 homematic1 user.debug ^E: Bidcos RX: #C4[BC|Ren|WMup] 166BB9->000000 WeatherData: 00 E0 24 Nov 22 20:09:19 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_NotForUs, wakeup=None Nov 22 20:09:19 homematic1 user.debug ^E: A<: #173 HmBidcos RxTelegram AuthNone #C4[BC|Ren|WMup] 166BB9->000000 WeatherData: 00 E0 24 Nov 22 20:09:19 homematic1 local0.info ReGaHss: Info: Event="IEQ0067902:1"."TEMPERATURE"=22.400000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1548 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1530 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 22.400000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:19 homematic1 local0.info ReGaHss: Info: Event="IEQ0067902:1"."HUMIDITY"=36 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1548 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:19 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","IEQ0067902:1","TEMPERATURE",22.400000}],[methodName:"event",params:{"RF-ccu","IEQ0067902:1","HUMIDITY",36}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:19 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1530 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 36 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:19 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:20 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:20 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:20 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:20 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: recvd 62 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: dst change: 0 [ThreadFunction():iseRTTimer.cpp:233] Nov 22 20:09:21 homematic1 local0.debug ReGaHss: Verbose: ISETIMEZONE = -3600 [ThreadFunction():iseRTTimer.cpp:81] Nov 22 20:09:21 homematic1 local0.debug ReGaHss: Verbose: DST offset = 0 [FillTimerEventList():iseRTTimer.cpp:427] Nov 22 20:09:21 homematic1 local0.debug ReGaHss: Verbose: TimerInitDPsComplete event setting [SetFinishedDPInitEvent():iseRT.cpp:375] Nov 22 20:09:21 homematic1 local0.info ReGaHss: Info: no timer exists, so Wait (60 s) ! [ThreadFunction():iseRTTimer.cpp:212] Nov 22 20:09:22 homematic1 user.debug ^E: C> @1064325027: #92 LLMAC RX @ 8960ms -83dBm B0 A4 5F 2E 0D AC 16 32 1B AE 7F 0A 03 19 18 22 69 09 03 FE Nov 22 20:09:22 homematic1 user.debug ^E: Bidcos RX: #B0[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F 0A 03 19 18 22 69 09 03 FE Nov 22 20:09:22 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:09:22 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @ 9061ms [10k,NoCCA] B0 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:09:22 homematic1 user.debug ^E: AutoTx: #B0[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:09:22 homematic1 user.debug ^E: C<: #81 LLMAC TX @ 9061ms [10k,NoCCA] B0 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:09:22 homematic1 user.debug ^E: A<: #175 HmBidcos RxTelegram AuthNotNecessary #B0[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F 0A 03 19 18 22 69 09 03 FE Nov 22 20:09:22 homematic1 user.debug ^E: C< @1064325028: bin:FD 00 10 03 51 06 23 65 80 B0 80 02 16 32 1B 2E 0D AC 00 78 A0 Nov 22 20:09:22 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","LEQ0532838:2","BOOT",true}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:22 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:22 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","LEQ0532838:2","ENERGY_COUNTER",304717.800000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","POWER",2030.320000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","CURRENT",8809.000000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","VOLTAGE",230.700000}],[methodName:"event",params:{"RF-ccu","LEQ0532838:2","FREQUENCY",49.980000}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:22 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304717.799805 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304717.799805 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=2030.320000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304717.799805' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 2030.320000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304717.799805 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8809.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8809.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=230.700000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304717.799805' [Execute():iseDOMPrg.cpp:625] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.700000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.980000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.980000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:22 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:09:22 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:09:22 homematic1 user.debug ^E: C> @1064325144: #81 LLMAC Response ACK @9061: 23 65 Nov 22 20:09:22 homematic1 user.debug ^E: C<: #82 TRX GetDutyCycle Nov 22 20:09:22 homematic1 user.debug ^E: C< @1064325587: bin:FD 00 03 01 52 03 74 1B Nov 22 20:09:22 homematic1 user.debug ^E: C> @1064325690: #82 TRX Response Ack 05 Nov 22 20:09:22 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:09:24 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:24 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:24 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:24 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:25 homematic1 user.debug ^E: C> @1064328252: #93 LLMAC RX @12187ms -65dBm 97 A2 58 16 A3 77 13 D2 C5 00 FF Nov 22 20:09:25 homematic1 user.debug ^E: Bidcos RX: #97[BiDi|Ren|WMup] 16A377->13D2C5 HvacSetpoint: 00 FF Nov 22 20:09:25 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_NotForUs, wakeup=None Nov 22 20:09:25 homematic1 user.debug ^E: A<: #178 HmBidcos RxTelegram AuthNone #97[BiDi|Ren|WMup] 16A377->13D2C5 HvacSetpoint: 00 FF Nov 22 20:09:25 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","IEQ0077925:2","ADJUSTING_COMMAND",0}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:25 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:25 homematic1 local0.info ReGaHss: Info: Event="IEQ0077925:2"."ADJUSTING_COMMAND"=0 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1832 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1811 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 0 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:25 homematic1 local0.info ReGaHss: Info: Event="IEQ0077925:2"."ADJUSTING_DATA"=250 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1832 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1811 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 250 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:25 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","IEQ0077925:2","ADJUSTING_DATA",250}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:25 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:25 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:25 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:25 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:25 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:25 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:27 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:27 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:27 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:27 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:29 homematic1 local0.info ReGaHss: Info: Event="CENTRAL"."PONG"="ccu" [execute():iseXmlRpc.cpp:321] Nov 22 20:09:29 homematic1 local0.info ReGaHss: Info: PONG event 'CENTRAL' received. [execute():iseXmlRpc.cpp:335] Nov 22 20:09:29 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","CENTRAL","PONG","ccu"}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:29 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:29 homematic1 user.debug ^E: A>: #95 HmSystem DutyCycleRequest Nov 22 20:09:29 homematic1 user.debug ^E: A<: #95 HmSystem Response 02 05 Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:30 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:32 homematic1 user.debug ^E: C<: #83 TRX GetDutyCycle Nov 22 20:09:32 homematic1 user.debug ^E: C< @1064335587: bin:FD 00 03 01 53 03 F2 18 Nov 22 20:09:32 homematic1 user.debug ^E: C> @1064335691: #83 TRX Response Ack 05 Nov 22 20:09:32 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:09:33 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:33 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:33 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:33 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:35 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:35 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:35 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:35 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:36 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:36 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:36 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:36 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:39 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:39 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:39 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:39 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:39 homematic1 user.debug ^E: C> @1064342293: #94 LLMAC RX @26227ms -78dBm C4 A2 58 16 6B B9 13 D6 8A 00 00 Nov 22 20:09:39 homematic1 user.debug ^E: Bidcos RX: #C4[BiDi|Ren|WMup] 166BB9->13D68A HvacSetpoint: 00 00 Nov 22 20:09:39 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_NotForUs, wakeup=None Nov 22 20:09:39 homematic1 user.debug ^E: A<: #181 HmBidcos RxTelegram AuthNone #C4[BiDi|Ren|WMup] 166BB9->13D68A HvacSetpoint: 00 00 Nov 22 20:09:39 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","IEQ0067902:2","ADJUSTING_COMMAND",0}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:39 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:39 homematic1 local0.info ReGaHss: Info: Event="IEQ0067902:2"."ADJUSTING_COMMAND"=0 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1551 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1530 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 0 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:39 homematic1 local0.info ReGaHss: Info: Event="IEQ0067902:2"."ADJUSTING_DATA"=0 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1551 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 1530 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:39 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 0 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:39 homematic1 user.err Sniffing Mode: XmlRpcClient error calling event({[methodName:"event",params:{"RF-ccu","IEQ0067902:2","ADJUSTING_DATA",0}]}) on http://192.168.2.4:9125/RPC2: Nov 22 20:09:39 homematic1 user.err Sniffing Mode: XmlRpc transport error Nov 22 20:09:40 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:40 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:40 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:40 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:42 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:42 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:42 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:42 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:42 homematic1 user.debug ^E: C<: #84 TRX GetDutyCycle Nov 22 20:09:42 homematic1 user.debug ^E: C< @1064345587: bin:FD 00 03 01 54 03 60 1B Nov 22 20:09:42 homematic1 user.debug ^E: C> @1064345692: #84 TRX Response Ack 05 Nov 22 20:09:42 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:09:44 homematic1 user.debug ^E: C> @1064347027: #95 LLMAC RX @30960ms -82dBm B1 A4 5F 2E 0D AC 16 32 1B AE 7F 86 03 0C 32 21 C3 09 07 FD Nov 22 20:09:44 homematic1 user.debug ^E: Bidcos RX: #B1[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F 86 03 0C 32 21 C3 09 07 FD Nov 22 20:09:44 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:09:44 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @31061ms [10k,NoCCA] B1 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:09:44 homematic1 user.debug ^E: AutoTx: #B1[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:09:44 homematic1 user.debug ^E: C<: #85 LLMAC TX @31061ms [10k,NoCCA] B1 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:09:44 homematic1 user.debug ^E: A<: #184 HmBidcos RxTelegram AuthNotNecessary #B1[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F 86 03 0C 32 21 C3 09 07 FD Nov 22 20:09:44 homematic1 user.debug ^E: C< @1064347028: bin:FD 00 10 03 55 06 79 55 80 B1 80 02 16 32 1B 2E 0D AC 00 8F FC 7D Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304730.199707 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304730.199707 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=1997.299999 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1997.299999 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8643.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8643.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=231.100000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 231.100000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.970000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.970000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304730.199707' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304730.199707 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304730.199707' [Execute():iseDOMPrg.cpp:625] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:44 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:09:44 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:09:44 homematic1 user.debug ^E: C> @1064347144: #85 LLMAC Response ACK @31061: 79 55 Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:45 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:48 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:48 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:48 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:48 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:50 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:50 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:50 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:50 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: recvd 62 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:51 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:52 homematic1 user.debug ^E: C> @1064355028: #96 LLMAC RX @ 6192ms -82dBm B2 A4 5F 2E 0D AC 16 32 1B AE 7F B3 03 28 38 23 28 09 01 FD Nov 22 20:09:52 homematic1 user.debug ^E: Bidcos RX: #B2[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F B3 03 28 38 23 28 09 01 FD Nov 22 20:09:52 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:09:52 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @ 6293ms [10k,NoCCA] B2 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:09:52 homematic1 user.debug ^E: AutoTx: #B2[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:09:52 homematic1 user.debug ^E: C<: #86 LLMAC TX @ 6293ms [10k,NoCCA] B2 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:09:52 homematic1 user.debug ^E: A<: #186 HmBidcos RxTelegram AuthNotNecessary #B2[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F B3 03 28 38 23 28 09 01 FD Nov 22 20:09:52 homematic1 user.debug ^E: C< @1064355028: bin:FD 00 10 03 56 06 18 95 80 B2 80 02 16 32 1B 2E 0D AC 00 63 0B Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304734.699707 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304734.699707 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=2069.039997 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304734.699707' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304734.699707 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 2069.039997 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=9000.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 9000.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304734.699707' [Execute():iseDOMPrg.cpp:625] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=230.500000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.500000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.970000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.970000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:52 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:09:52 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:09:52 homematic1 user.debug ^E: C> @1064355144: #86 LLMAC Response ACK @6293: 18 95 Nov 22 20:09:52 homematic1 user.debug ^E: C<: #87 TRX GetDutyCycle Nov 22 20:09:52 homematic1 user.debug ^E: C< @1064355588: bin:FD 00 03 01 57 03 6A 1B Nov 22 20:09:52 homematic1 user.debug ^E: C> @1064355692: #87 TRX Response Ack 05 Nov 22 20:09:52 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 2.5 ) = 0 Nov 22 20:09:53 homematic1 user.debug ^E: C> @1064355988: #97 LLMAC RX @ 7152ms -78dBm 02 84 5E 2E 0D 9D 00 00 00 DE DD B5 00 00 65 00 22 09 11 FE Nov 22 20:09:53 homematic1 user.debug ^E: Bidcos RX: #02[BC|Ren] 2E0D9D->000000 EnergyCyclic: DE DD B5 00 00 65 00 22 09 11 FE Nov 22 20:09:53 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_NotForUs, wakeup=None Nov 22 20:09:53 homematic1 user.debug ^E: A<: #189 HmBidcos RxTelegram AuthNone #02[BC|Ren] 2E0D9D->000000 EnergyCyclic: DE DD B5 00 00 65 00 22 09 11 FE Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Event="LEQ0532823:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2207 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2172 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Event="LEQ0532823:2"."ENERGY_COUNTER"=621714.099609 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2207 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2172 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 621714.099609 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Event="LEQ0532823:2"."POWER"=1.010000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2224 'prgEnergyCounter_2207_LEQ0532823:2' with valNew = '621714.099609' ; oidTrigger = 2210 [SetState():iseDOMPrg.cpp:478] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2207 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2172 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1.010000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 621714.099609 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Event="LEQ0532823:2"."CURRENT"=34.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2207 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2172 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2224 'prgEnergyCounter_2207_LEQ0532823:2'; Destination ID = 2226 [SetState():iseDOMPrg.cpp:501] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 34.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Program ID = 2224 'prgEnergyCounter_2207_LEQ0532823:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Event="LEQ0532823:2"."VOLTAGE"=232.100000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2224 'prgEnergyCounter_2207_LEQ0532823:2'; Destination ID = 2226 [Execute():iseDOMPrg.cpp:611] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2207 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2172 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 232.100000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: added script element id= 2226; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: Event="LEQ0532823:2"."FREQUENCY"=49.980000 [execute():iseXmlRpc.cpp:321] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2224 'prgEnergyCounter_2207_LEQ0532823:2' with valNew = '621714.099609' [Execute():iseDOMPrg.cpp:625] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2207 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2172 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.980000 [execute():iseXmlRpc.cpp:364] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:09:53 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2226; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:09:53 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:09:54 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:54 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:54 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:54 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:09:58 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:10:00 homematic1 user.debug ^E: C> @1064363028: #98 LLMAC RX @14191ms -81dBm B3 A4 5F 2E 0D AC 16 32 1B AE 7F E0 03 1A 7C 22 7C 09 01 FE Nov 22 20:10:00 homematic1 user.debug ^E: Bidcos RX: #B3[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F E0 03 1A 7C 22 7C 09 01 FE Nov 22 20:10:00 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:10:00 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @14292ms [10k,NoCCA] B3 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:10:00 homematic1 user.debug ^E: AutoTx: #B3[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:10:00 homematic1 user.debug ^E: C<: #88 LLMAC TX @14292ms [10k,NoCCA] B3 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:10:00 homematic1 user.debug ^E: A<: #191 HmBidcos RxTelegram AuthNotNecessary #B3[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 7F E0 03 1A 7C 22 7C 09 01 FE Nov 22 20:10:00 homematic1 user.debug ^E: C< @1064363028: bin:FD 00 10 03 58 06 37 D4 80 B3 80 02 16 32 1B 2E 0D AC 00 20 0B Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304739.199707 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304739.199707 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=2033.879999 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304739.199707' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 2033.879999 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304739.199707 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8828.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [SetState():iseDOMPrg.cpp:501] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2', rule's trigger flags = 6 [SetState():iseDOMPrg.cpp:515] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: execute Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2'; Destination ID = 2289 [Execute():iseDOMPrg.cpp:611] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: start execute Destination within the trigger delay 0 [Execute():iseDOMPrg.cpp:615] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: added script element id= 2289; time= 0 [Add():iseRTScheduler.cpp:341] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: ExecuteDestination succeeded from Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304739.199707' [Execute():iseDOMPrg.cpp:625] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8828.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."VOLTAGE"=230.500000 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 230.500000 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."FREQUENCY"=49.980000 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 49.980000 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:00 homematic1 local0.debug ReGaHss: Verbose: ExecuteScript: id = 2289; ticks = 3 [Execute():iseRTScheduler.cpp:604] Nov 22 20:10:00 homematic1 local0.info ReGaHss: Info: wait for event ms= 4294967295 [ThreadFunction():iseRTScheduler.cpp:484] Nov 22 20:10:00 homematic1 user.debug ^E: C> @1064363144: #88 LLMAC Response ACK @14292: 37 D4 Nov 22 20:10:01 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:10:01 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:10:01 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:10:01 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:10:02 homematic1 user.debug ^E: C<: #89 TRX GetDutyCycle Nov 22 20:10:02 homematic1 user.debug ^E: C< @1064365588: bin:FD 00 03 01 59 03 CE 18 Nov 22 20:10:02 homematic1 user.debug ^E: C> @1064365692: #89 TRX Response Ack 06 Nov 22 20:10:02 homematic1 user.debug ^E: SubsystemBidcos::CheckDutyCycleEventThreshold( 2.5, 3.0 ) = 0 Nov 22 20:10:03 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:10:03 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:10:03 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:10:03 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:10:04 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:10:04 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:10:04 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:10:04 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:10:07 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:10:07 homematic1 local0.info ReGaHss: Info: recvd 735 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:10:07 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@KPQLRib8Va@&action=UpdateUI [GetSessionId():iseSession.cpp:185] Nov 22 20:10:07 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:10:09 homematic1 local0.info ReGaHss: Info: start web processing, worker thread #1 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208] Nov 22 20:10:09 homematic1 local0.info ReGaHss: Info: recvd 326 bytes by web server #2 [ThreadFunction():httpServer.cpp:769] Nov 22 20:10:09 homematic1 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185] Nov 22 20:10:09 homematic1 local0.info ReGaHss: Info: http id #2 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2033] Nov 22 20:10:10 homematic1 user.debug ^E: C> @1064373028: #99 LLMAC RX @24191ms -80dBm B4 A4 5F 2E 0D AC 16 32 1B AE 80 19 03 0B C1 21 C5 09 04 FE Nov 22 20:10:10 homematic1 user.debug ^E: Bidcos RX: #B4[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 80 19 03 0B C1 21 C5 09 04 FE Nov 22 20:10:10 homematic1 user.debug ^E: GetAckActionForIncomingTelegram() ackAction=AckAction_Ack, wakeup=None Nov 22 20:10:10 homematic1 user.debug ^E: MacController::OnDownstreamFrame(#255 LLMAC TX @24292ms [10k,NoCCA] B4 80 02 16 32 1B 2E 0D AC 00) Nov 22 20:10:10 homematic1 user.debug ^E: AutoTx: #B4[Ren] 16321B->2E0DAC Ack: 00 Nov 22 20:10:10 homematic1 user.debug ^E: C<: #90 LLMAC TX @24292ms [10k,NoCCA] B4 80 02 16 32 1B 2E 0D AC 00 Nov 22 20:10:10 homematic1 user.debug ^E: A<: #194 HmBidcos RxTelegram AuthNotNecessary #B4[BiDi|BC|Ren] 2E0DAC->16321B EnergySpontaenous: AE 80 19 03 0B C1 21 C5 09 04 FE Nov 22 20:10:10 homematic1 user.debug ^E: C< @1064373028: bin:FD 00 10 03 5A 06 5E E4 80 B4 80 02 16 32 1B 2E 0D AC 00 19 8C Nov 22 20:10:10 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."BOOT"=true [execute():iseXmlRpc.cpp:321] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69] Nov 22 20:10:10 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."ENERGY_COUNTER"=304744.899902 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 304744.899902 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:10 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."POWER"=1996.169998 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: called Program ID = 2287 'prgEnergyCounter_2270_LEQ0532838:2' with valNew = '304744.899902' ; oidTrigger = 2273 [SetState():iseDOMPrg.cpp:478] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, trigger dp exists [CheckCondition2Data():iseCondition.cpp:504] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_UPDATE is set, condition = 1 [CheckCondition2Data():iseCondition.cpp:510] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 304744.899902 valR1 = 0.000000 [Evaluate():iseCondition.cpp:324] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1996.169998 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:10 homematic1 local0.info ReGaHss: Info: Event="LEQ0532838:2"."CURRENT"=8645.000000 [execute():iseXmlRpc.cpp:321] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseCondition.cpp:1121] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2270 [SetLastDPActionTime():iseDOMchn.h:288] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: call on change; id = 2235 [SetLastActionTime():iseDOMdev.h:225] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1123] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 8645.000000 [execute():iseXmlRpc.cpp:364] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_TRUE is set. [Evaluate():iseRule.cpp:232] Nov 22 20:10:10 homematic1 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235]