[Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Virtualisierte CCU für Raspberry Pi und Clones

Moderator: Co-Administratoren

Antworten
strike
Beiträge: 13
Registriert: 10.08.2014, 23:34

[Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von strike » 21.05.2019, 17:07

Hallo,

Ich betreibe einen RPI3 B+ mit piVCCU und dem Funkmodul HM-MOD-RPI-PCB. Zusätzlich hängt wegen problematischer Funkabdeckung auch noch ein HomeMatic RF-LAN Gateway mit dran. Funktionert soweit.
Wenn ich das HomeMatic RF-LAN Gateway vom Strom trenne, dann kann ich nichts mehr schalten.
So wie es ausschaut wird das Funkmodul HM-MOD-RPI-PCB aber erkannt. Es erfolgt aber keine Kommunikation. Auch der Gerätetest wird nicht abgeschlossen. (bleibt gelb).

Gruß
strike

sudo pivccu-info

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-info
piVCCU version: 2.45.7-46
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi3 UART:  Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
HMIP Hardware:  HM-MOD-RPI-PCB
Board serial:   OEQ2XXX
Radio MAC:      0x65XXX
SGTIN:          XXX
State:          RUNNING
PID:            2096
IP:             192.168.1.XX
IP:             2003:ec:1714XXX
IP:             fd00::28e7XXX
CPU use:        122.49 seconds
BlkIO use:      1.10 MiB
Link:           vethKI7DFH
 TX bytes:      16.51 MiB
 RX bytes:      5.65 MiB
 Total bytes:   22.17 MiB
sudo pivccu-attach cat /var/log/messages

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/log/messages
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4766 [SetLastDPActionTime():iseDOMchn.h:288]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: type = DP_LESS, condition valL = 240.000000 valR1 = 1.000000 [Evaluate():iseCondition.cpp:327]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4737 [SetLastActionTime():iseDOMdev.h:225]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 0 [Evaluate():iseCondition.cpp:1074]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseCondition.cpp:1112]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 0 [Evaluate():iseCondition.cpp:1119]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseRule.cpp:227]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, condition result = 0 [Evaluate():iseRule.cpp:235]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: Rule evaluated state is FALSE by Program ID = 5004 'Skript_Asto_Zeitsteuerung' [SetState():iseDOMPrg.cpp:527]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: called Program ID = 3530 'SV_Nacht' with valNew = '36.900000' ; oidTrigger = 1285 [SetState():iseDOMPrg.cpp:478]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_CHANGE is set, trigger dp exists  [CheckCondition2Data():iseCondition.cpp:524]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: type = DP_LESSOREQUAL, condition valL = 36.900000 valR1 = -3.500000 [Evaluate():iseCondition.cpp:334]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 0 [Evaluate():iseCondition.cpp:1074]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseCondition.cpp:1112]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 0 [Evaluate():iseCondition.cpp:1119]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseRule.cpp:227]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, condition result = 0 [Evaluate():iseRule.cpp:235]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: Rule evaluated state is FALSE by Program ID = 3530 'SV_Nacht' [SetState():iseDOMPrg.cpp:527]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: called Program ID = 1880 'SV_Tag' with valNew = '36.900000' ; oidTrigger = 1285 [SetState():iseDOMPrg.cpp:478]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4766 [SetLastDPActionTime():iseDOMchn.h:288]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_CHANGE is set, trigger dp exists  [CheckCondition2Data():iseCondition.cpp:524]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4737 [SetLastActionTime():iseDOMdev.h:225]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: type = DP_GREATEROREQUAL, condition valL = 36.900000 valR1 = -4.000000 [Evaluate():iseCondition.cpp:320]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseCondition.cpp:1112]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 1 [Evaluate():iseCondition.cpp:1119]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: Event="CUX2800001:4"."STATE"=true [execute():iseXmlRpc.cpp:321]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseRule.cpp:227]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, condition result = 1 [Evaluate():iseRule.cpp:235]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4766 [SetLastDPActionTime():iseDOMchn.h:288]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4737 [SetLastActionTime():iseDOMdev.h:225]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: Rule evaluated state is TRUE by Program ID = 1880 'SV_Tag'; Destination ID = 1882 [SetState():iseDOMPrg.cpp:501]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: break execute for Program ID = 1880 'SV_Tag', rule's trigger flags = 0 [SetState():iseDOMPrg.cpp:521]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: Event="CUX2800001:4"."TS"="1558450831" [execute():iseXmlRpc.cpp:321]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: called Program ID = 5004 'Skript_Asto_Zeitsteuerung' with valNew = '240.000000' ; oidTrigger = 4771 [SetState():iseDOMPrg.cpp:478]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4766 [SetLastDPActionTime():iseDOMchn.h:288]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4737 [SetLastActionTime():iseDOMdev.h:225]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: DP_VALUE_CHECK_CHANGE is set, trigger dp exists  [CheckCondition2Data():iseCondition.cpp:524]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: type = DP_LESS, condition valL = 240.000000 valR1 = 1.000000 [Evaluate():iseCondition.cpp:327]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 0 [Evaluate():iseCondition.cpp:1074]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseCondition.cpp:1112]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1558450831 [execute():iseXmlRpc.cpp:364]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: sc = 0, condition result = 0 [Evaluate():iseCondition.cpp:1119]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: Event="CUX2800001:4"."WORKING"=true [execute():iseXmlRpc.cpp:321]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, CONDITION_TRIGGER_EXECUTE is reset. [Evaluate():iseRule.cpp:227]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4766 [SetLastDPActionTime():iseDOMchn.h:288]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: call on change; id = 4737 [SetLastActionTime():iseDOMdev.h:225]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: c = 0, condition result = 0 [Evaluate():iseRule.cpp:235]
May 21 16:56:31 homematic-ccu2 local0.info ReGaHss: Info: Rule evaluated state is FALSE by Program ID = 5004 'Skript_Asto_Zeitsteuerung' [SetState():iseDOMPrg.cpp:527]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: wait max timeout = 240000 [ThreadFunction():iseRTEvent.cpp:69]
May 21 16:56:31 homematic-ccu2 local0.debug ReGaHss: Verbose: UpdateValue succeeded; val = 1 [execute():iseXmlRpc.cpp:364]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:32 homematic-ccu2 user.err rfd: (KEQ1065536) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:32 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:33 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Trying to reconnect.
May 21 16:56:33 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Perform connect.
May 21 16:56:35 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:35 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:35 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:35 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:36 homematic-ccu2 user.debug multimac: C<: #252 TRX GetDutyCycle
May 21 16:56:36 homematic-ccu2 user.debug multimac: C< @8670128: bin:FD 00 03 01 FC 7C 03 10 12
May 21 16:56:36 homematic-ccu2 user.debug multimac: C> @8670231: #252 TRX Response Ack 00
May 21 16:56:36 homematic-ccu2 user.debug multimac: SubsystemBidcos::CheckDutyCycleEventThreshold( 0.0, 0.0 ) = 0
May 21 16:56:37 homematic-ccu2 user.err rfd: UnifiedLanCommController::connect(): Unable to connect.
May 21 16:56:37 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Device not found retrying in 5 seconds.
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:37 homematic-ccu2 user.err rfd: (KEQ1065536) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:37 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:38 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:38 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:38 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:38 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:39 homematic-ccu2 daemon.warn cuxd[284]: process_rpc_request(192.168.1.3) - illegal XMLRPC(ping) request
May 21 16:56:40 homematic-ccu2 user.debug multimac: A>: #241 TRX GetDutyCycle
May 21 16:56:40 homematic-ccu2 user.debug multimac: A<: #241 TRX Response Ack 00
May 21 16:56:41 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:41 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:41 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:41 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:42 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Trying to reconnect.
May 21 16:56:42 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Perform connect.
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:42 homematic-ccu2 user.err rfd: (KEQ1065536) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:42 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:44 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:44 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:44 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:44 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:45 homematic-ccu2 user.err rfd: UnifiedLanCommController::connect(): Unable to connect.
May 21 16:56:45 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Device not found retrying in 5 seconds.
May 21 16:56:46 homematic-ccu2 user.debug multimac: C<: #253 TRX GetDutyCycle
May 21 16:56:46 homematic-ccu2 user.debug multimac: C< @8680128: bin:FD 00 03 01 FC 7D 03 96 11
May 21 16:56:46 homematic-ccu2 user.debug multimac: C> @8680230: #253 TRX Response Ack 00
May 21 16:56:46 homematic-ccu2 user.debug multimac: SubsystemBidcos::CheckDutyCycleEventThreshold( 0.0, 0.0 ) = 0
May 21 16:56:47 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:47 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:47 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:47 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:48 homematic-ccu2 user.err rfd: (KEQ1065536) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: recvd 1535 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /rega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:48 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: recvd 996 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /rega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: recvd 62 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:49 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:50 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Trying to reconnect.
May 21 16:56:50 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Perform connect.
May 21 16:56:50 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:50 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:50 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:50 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:53 homematic-ccu2 user.err rfd: UnifiedLanCommController::connect(): Unable to connect.
May 21 16:56:53 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Device not found retrying in 5 seconds.
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:53 homematic-ccu2 user.err rfd: (KEQ1065536) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:53 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:54 homematic-ccu2 user.debug multimac: C> @8687831: #185 LLMAC RX @ 1287ms -79dBm F4 84 5E 27 40 4A 00 00 00 B1 94 49 00 00 00 00 00 09 33 03
May 21 16:56:54 homematic-ccu2 user.debug multimac: Bidcos RX: #F4[BC|Ren] 27404A->000000 EnergyCyclic: B1 94 49 00 00 00 00 00 09 33 03
May 21 16:56:54 homematic-ccu2 user.debug multimac: GetAckActionForIncomingTelegram(): Unknown peer, AckAction_NotForUs
May 21 16:56:54 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:54 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:54 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:54 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:55 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:55 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:55 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:55 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:56 homematic-ccu2 user.debug multimac: C<: #254 TRX GetDutyCycle
May 21 16:56:56 homematic-ccu2 user.debug multimac: C< @8690128: bin:FD 00 03 01 FE 03 9C 11
May 21 16:56:56 homematic-ccu2 user.debug multimac: C> @8690230: #254 TRX Response Ack 00
May 21 16:56:56 homematic-ccu2 user.debug multimac: SubsystemBidcos::CheckDutyCycleEventThreshold( 0.0, 0.0 ) = 0
May 21 16:56:57 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:57 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:57 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:56:57 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:58 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Trying to reconnect.
May 21 16:56:58 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Perform connect.
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:56:58 homematic-ccu2 user.err rfd: (KEQ1065536) CCU2CommController::getDutyCycle(): Could not get DutyCycle from coprocessor.
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:56:58 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:57:00 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:57:00 homematic-ccu2 local0.info ReGaHss: Info: recvd 729 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:57:00 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /esp/system.htm?sid=@w1AgNbIVtg@&action=UpdateUI [GetSessionId():iseSession.cpp:185]
May 21 16:57:00 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
May 21 16:57:01 homematic-ccu2 user.err rfd: UnifiedLanCommController::connect(): Unable to connect.
May 21 16:57:01 homematic-ccu2 user.debug rfd: LGWPortWrapper::reconnect(): Device not found retrying in 5 seconds.
May 21 16:57:01 homematic-ccu2 local0.info ReGaHss: Info: start web processing, worker thread #0 {"HTTP-Listener"} [spawnNewProcess():httpListener.cpp:208]
May 21 16:57:01 homematic-ccu2 local0.info ReGaHss: Info: recvd 326 bytes by web server #1 [ThreadFunction():httpServer.cpp:767]
May 21 16:57:01 homematic-ccu2 local0.info ReGaHss: Info: IseSession GetSessionId from URL: /tclrega.exe [GetSessionId():iseSession.cpp:185]
May 21 16:57:01 homematic-ccu2 local0.info ReGaHss: Info: http id #1 sends parsed file [ParseFileAndSendWebResponse():httpServer.cpp:2038]
sudo pivccu-attach cat /var/log/hmserver.log

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/log/hmserver.log
May 21 14:51:33 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory
May 21 14:51:33 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] creating instance of HMServerConfiguration with no-arg constructor
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KeyServerWorker] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [BackendCommandHandler] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CheckDeviceExistHandler] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceLiveUpdateSubsystem] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionDefaultConfigurationChanger] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [AccessPointElectroCardioGram] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyInitializion] (1)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: deploying 22 classes to Vert.x
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: 22 VertxDeployers initialized
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of AccessPointElectroCardioGram succeeded (a44f597e-635f-45cf-a14d-67e452651fd7)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (1a068862-2383-4aa7-ba76-90be2b0c089e)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceInclusionDefaultConfigurationChanger succeeded (9a3bfca2-d146-4aed-b6e4-2881c0e6e513)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of CheckDeviceExistHandler succeeded (c261a175-64de-4cc7-9e81-f08e7415041d)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of HMIPTRXWriterWorker succeeded (fb42a003-7478-4f4d-947b-e62cb89c674c)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (d11b50dc-c268-4212-a845-a862f0f43ac5)
May 21 14:51:38 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-3] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of TransactionSubsystemHandler succeeded (c974faac-7d4e-4a25-ada7-3d1b145919c0)
May 21 14:51:38 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of KeyServerWorker succeeded (22a22c7e-3861-4906-a03a-6d04d66b461a)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of KryoPersistenceWorker succeeded (c174a9a5-1d5c-4430-a5ef-7fa9912ba518)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of LocalServerPersistentDataLoader succeeded (ac84055d-fa35-4477-9d51-34dd12d188a4)
May 21 14:51:39 de.eq3.cbcs.server.core.live_otau.DeviceLiveUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: DeviceLiveUpdateSubsystem started
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DeviceLiveUpdateSubsystem succeeded (787754d1-3442-41f5-b420-dfe17d00d0cf)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (91d96b54-a89f-428d-b5b9-eb97d2b30fb8)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (5ab85a32-c7d1-46a5-9514-391dbe736592)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of IncomingHMIPFrameHandler succeeded (bdaa8605-17af-4d42-907e-911de08a6a87)
May 21 14:51:39 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO  [vert.x-worker-thread-2] SYSTEM: Firmware update directory is set to /etc/config/firmware
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of FirmwareLoaderFileSystem succeeded (206039c3-8e5a-4777-971c-1f09f0148326)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of BackendCommandHandler succeeded (6c4ddd2d-e888-4cd4-a773-05c5664dae86)
May 21 14:51:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LocalServerAdapterInitialization succeeded (8154942d-3aa4-4118-945b-209e4cb67486)
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter state 1: HMIP_TRX_App
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter application is already running or started
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] No NWK, try to set address ...
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to set radio address 12212705...
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set max send attempts for 3014F711A061A7D709AC8086 to 3
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to get application version...
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Application version 2.8.6
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Bootloader version 1.0.3
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] hmos version 1.20.3
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] MCU type: Si1002_8051
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Duty Cycle: 0.0
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] set DutyCycle limit to ffffffc8
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set Duty Cycle Limit
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Current Security Counter: 85821075
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Update security counter to calculation: 85824828
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter has no linkpartner
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Adapter with Access Point id 3014F711A061A7D709AC8086 initialized
May 21 14:51:40 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO  [Thread-6] HMIPTRXInitialResponseListener said that Adapter was initialized
May 21 14:51:41 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of LegacyNotificationHandler succeeded (c47b432c-7792-4e84-a8e7-00a4d7ff3dad)
May 21 14:51:41 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LocalServerFirmwareUpdateInitialization succeeded (2daf29ab-e6b1-46a7-96ed-a597260ebaf6)
May 21 14:51:41 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Checking all devices on all accesspoints for updates
May 21 14:51:41 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: There are 0 APs queued with updatable devices (RF)
May 21 14:51:41 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: There are 0 APs queued with updatable devices (WIRED)
May 21 14:51:41 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of LegacyBackendNotificationHandler succeeded (de55a2db-cee2-437e-b8d9-8476689f2c6e)
May 21 14:51:41 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of LegacyAPIWorker succeeded (f40179ca-e454-4a6e-9cea-98138e76972f)
May 21 14:51:42 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LegacyInitializion succeeded (666f0807-a008-4399-a18c-672288158b50)
May 21 14:51:42 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: initial deployment complete _____________________________________________________
May 21 14:51:43 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Default MaxEventLoopExecuteTime: 2000000000
May 21 14:51:43 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Default BlockedThreadCheckInterval: 1000
May 21 14:51:43 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Default MaxWorkerExecuteTime: 60000000000
May 21 14:51:43 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Default EventLoopPoolSize: 8
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [BackendWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [RegaClientWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [HmIPGatewayManagementRequestWorker] (1) *worker
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: deploying 10 classes to Vert.x
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: 10 VertxDeployers initialized
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of BackendWorker succeeded (b2e71f08-e5d5-4635-ac70-14bc89bbe30f)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (b8b6ccd6-dac9-481d-9f25-5a1ab5a453e5)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of CouplingRequestWorker succeeded (78db91c4-7ee5-4717-8894-064dcb35a407)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of EnergyPriceRequestWorker succeeded (3e28502b-2d23-48ca-8e96-2ade3756deef)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of RegaClientWorker succeeded (7edeb0cb-5b9f-4ee3-ac5d-7b43efc873f6)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of StorageRequestWorker succeeded (8f45582f-ce1f-4567-be60-ebce9a5f2fa7)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of GroupRequestWorker succeeded (b7d4e508-428e-47a2-93cc-7a493a59c133)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (453c3e44-47fb-41f3-a0e5-3320d6b26df3)
May 21 14:51:43 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of DiagramRequestWorker succeeded (fa3319b4-a8c2-481b-934d-736fd7fafb40)
May 21 14:51:43 de.eq3.cbcs.server.local.LocalServer INFO  [Thread-0] SYSTEM: Bind XML-RPC api to port 2010
May 21 14:51:46 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of HmIPGatewayManagementRequestWorker succeeded (24e69bc1-7fdc-450a-8b76-e180d47e5e56)
May 21 14:51:46 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-1] SYSTEM: initial deployment complete _____________________________________________________
May 21 14:51:46 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Starting HMServer at 127.0.0.1:9292
May 21 14:51:46 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Read Configuration
May 21 14:51:47 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create Bidcos Dispatcher
May 21 14:51:47 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] InitBidCosCache
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create groupDefinitionProvider
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create VirtualDeviceHolder
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create VirtualDeviceHandlerRega
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create GroupAdministrationService
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create GroupDeviceDispatcher
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create GroupDeviceHandler
May 21 14:51:48 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-1] @GroupDeviceHandler - initializing...
May 21 14:51:48 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-1] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher))
May 21 14:51:48 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-1] --> created virtualDeviceHandler (GroupDeviceService to ReGa)
May 21 14:51:48 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-1] --> got groupDefinitionProvider
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create BidCosGroupMemberProvider
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Init groupAdministrationService
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Init Virtual OS Device
May 21 14:51:48 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Init ESHLight Bridge
May 21 14:51:49 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create RrdDatalogging
May 21 14:51:49 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create MeasurementService
May 21 14:51:49 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Init MeasurementService
May 21 14:51:49 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create HTTP Server
May 21 14:51:49 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create BidCos context and start handler
May 21 14:51:49 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Create group context and start handler
May 21 14:51:49 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:9292/bidcos
May 21 14:51:49 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] init finished
May 21 14:51:49 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-4] Added InterfaceId: HmIP-RF_java
May 21 14:51:49 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO  [vert.x-worker-thread-4] updateDevicesForClient HmIP-RF_java -> 52 device addresses will be added
May 21 14:51:50 de.eq3.ccu.server.BaseHMServer INFO  [Thread-1] Starting HMServer done
May 21 14:52:00 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-4] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:1999
May 21 14:52:00 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-4] Added InterfaceId: 1008
May 21 14:52:00 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-11] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
May 21 14:52:00 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-11] updateDevicesForClient -> 4 device addresses will be added
May 21 14:52:10 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-1] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:1999
May 21 14:52:10 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-1] init finished
May 21 14:52:10 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-2] Added InterfaceId: 7207
May 21 14:52:10 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-11] set ready config of INT0000001
May 21 14:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 14:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 14:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:01:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:01:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:01:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:06:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:06:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:06:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:11:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:11:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:11:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:16:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:16:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:16:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:21:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:21:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:21:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:26:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:26:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:26:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:31:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:31:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:31:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:36:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:36:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:36:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:41:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:41:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:41:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:46:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:46:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:46:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:51:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:51:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:51:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 15:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 15:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 15:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:01:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:01:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:01:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:06:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:06:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:06:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:11:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:11:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:11:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:16:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:16:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:16:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:21:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:21:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:21:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:26:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:26:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:26:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:31:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:31:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:31:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:36:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:36:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:36:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:41:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:41:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:41:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:46:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:46:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:46:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:51:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:51:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:51:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
May 21 16:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: 0 Accesspoints in Queue
May 21 16:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
May 21 16:56:39 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-4] SYSTEM: Eventlistener Handler utilization: 0/50 used
Zuletzt geändert von strike am 21.05.2019, 22:36, insgesamt 1-mal geändert.
piVCCU auf RPI 3B+
Raspbian_Stretch_Lite_piVCCU_2018-07-04
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von deimos » 21.05.2019, 21:43

Hi,

laut Log ist dein LAN GW nicht erreichbar. Kann es sein, dass die Geräte alle fix diesem GW zugeordnet sind?

Zum Thema Gerätetest: Dieser ist nicht aktiv, sondern wartet nur auf eingehende Nachrichten. Je nach Gerät kann das dann durchaus einen Tag dauern, wenn man die nicht selbst zum Funken bringt (z.B. durch lokale Bedienung).

Viele Grüße
Alex
Nach oben
strike
Beiträge: 13
Registriert: 10.08.2014, 23:34

Re: [Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von strike » 21.05.2019, 22:44

Hallo,

Danke für die Rückmeldung.

Beim durchstöbern des piVCCU Unterforums ist mir ein Beitrag aufgefallen, bei dem es ähnliche Probleme gab. Im Zusammenhang mit der Migration von YAHM nach piVCCU.
Um sicher zu gehen das ich nicht auch einen Fehler nach dem Wechsel von YAHM nach piVCCU habe, zog ich es vor eine Neuinstallation mit einem fertigem piVCCU-Image zu machen. Problem gelöst

Gruß
strike
piVCCU auf RPI 3B+
Raspbian_Stretch_Lite_piVCCU_2018-07-04
Nach oben
klassisch
Beiträge: 3974
Registriert: 24.03.2011, 04:32
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 110 Mal
Danksagung erhalten: 71 Mal

Re: [Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von klassisch » 22.05.2019, 02:09

Danke für die Rückmeldung!
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: [Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von deimos » 22.05.2019, 02:23

Hi,

wenn du ein Backup aus YAHM eingespielt hast, dann musst du einige Migrationsschritte machen, auch wenn du mit einem fertigen piVCCU Image startest.
Solange du die nicht machst, kann es dir immer wieder zu dem Problem kommen, weil YAHM Ampassungen an den Konfigurationsdateien (auch in den Dateien im Backup) vornimmt, welche mit keinem CCU System kompatibel sind und diese Anpassungen müssen rückgängig gemacht werden.

Viele Grüße
Alex
Nach oben
strike
Beiträge: 13
Registriert: 10.08.2014, 23:34

Re: [Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von strike » 22.05.2019, 21:30

Hi,

Alex Du bist mein Held.
Ich habe jetzt noch mal alle Schritte für die Migration von YAHM nach piVCCU durchgeführt.
Jetzt werden auch in der RSSI-Liste das Funkmodul und das LAN-GW angezeigt.
Allerdings beim Funkmodul HM-MOD-RPI-PCB mit teilweise katastrophalen Werten. (teilw. 128 bis 192)

Wenn ich jetzt ein Backup über das CCU Webgui starte, dann kann ich das doch wieder in piVCCU ohne die YAHM Anpassungen wiederherstellen, oder?

Gruß
strike
piVCCU auf RPI 3B+
Raspbian_Stretch_Lite_piVCCU_2018-07-04
Nach oben
Slice
Beiträge: 1201
Registriert: 03.02.2016, 14:44
System: Alternative CCU (auf Basis OCCU)
Wohnort: irgendwo aus Süd BaWü
Hat sich bedankt: 141 Mal
Danksagung erhalten: 85 Mal

Re: [Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von Slice » 22.05.2019, 22:05

strike hat geschrieben: ↑
22.05.2019, 21:30
 Wenn ich jetzt ein Backup über das CCU Webgui starte, dann kann ich das doch wieder in piVCCU ohne die YAHM Anpassungen wiederherstellen, oder?
Wenn Du erfolgreich alle Schritte von der YAHM Migration durchgeführt hast ist das Backup über die WebUi jederzeit wieder im neuen System herstellbar.
Ich habe selbst von YAHM kommend über PiVCCU2 auf piVCCU3 umgestellt ohne Probleme mit den Backups.

Grüße,
Slice
----------------------------------------------------------------------------------------
Raspi3B+ Bullseye mit HB-RF-ETH und RPI-RF-MOD auf piVCCU-FW 3.75.7 / Addons: CuxD v2.11 - E-Mail v1.7.6 - Patcher v1.0.0 - Philips Hue v3.2.5 - Programme drucken v2.6 - Scriptparser v1.11 - XML-API v2.3
Geräte: 141 / Kanäle: 791 / Datenpunkte: 6080 / SysVars: 275 / Programme: 161 / Regadom IDs: 14010 / 48 CUxD-Kanäle in 3 CUxD-Geräten
Intel NUC i3-5010U @ 2,1 GHz mit 16 GB RAM & 512 GB SSD für Proxmox mit ioBroker VM und CCU-Historian/InfluxDB/Grafana VM
----------------------------------------------------------------------------------------
Projekte im Forum: HomeHub v4.1 / Fritzbox-Anruferliste für HomeHub
----------------------------------------------------------------------------------------
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: [Gelöst] Fehler mit Funkmodul HM-MOD-RPI-PCB

Beitrag von deimos » 22.05.2019, 22:19

Hi,
strike hat geschrieben: ↑
22.05.2019, 21:30
 Wenn ich jetzt ein Backup über das CCU Webgui starte, dann kann ich das doch wieder in piVCCU ohne die YAHM Anpassungen wiederherstellen, oder?
Korrekt.

Viele Grüße
Alex
Nach oben
Antworten

Zurück zu „piVCCU“