ich betreibe erfolgreich einen OpenhabianPi (Raspberry Pi 3 Model B Rev 1.2, Linux 4.14.71-v7+) mit aufgestecktem RPI-RF-MOD. Genutzt wird das pivccu3 image in der Version 3.37.8.
Im Moment versuche ich ein Homematic-Wired Lan-Gateway (HMW-LGW-O-DR-GS-EU) und einige HomeMatic-Wired RS485 Rollladenaktoren (HMW-LC-Bl1-DR) in Betrieb zu nehmen. Die Komponenten sind an ein 24 V Mean Well-Hutschienennetzteil gemäß Schaltplan angeschlossen (Schirm jeweils einseitig auf Masse gelegt).
Ich nutze diverse RF-Komponenten problemlos und habe gestern das Lan-Gateway über Einstellungen->Systemsteuerung->LAN Gateway Konfiguration hinzugefügt. Ich habe die entsprechende IP-Adresse und den Sicherheitsschlüssel (Aufkleber auf Lan-Gateway) eingetragen, übernommen und sowohl die Homematic als auch die Hardware selbst mehrfach neu gestartet (stromlos):
Das Lan-Gateway wird auch mit dem Homematic-NetFinder erfolgreich gefunden:
Entsprechend diverser Forenbeiträge habe ich versucht den Lan-Gateway sowohl per DHCP (wird gefunden) als auch mit statischer IP (wird auch gefunden) zu betreiben.
Leider verbleibt das Status-Feld in jedem Betriebsmodus leer (weder "verbunden" noch "nicht verbunden"):
Ich habe daraufhin das LOGLEVEL_HS485D (/var/lib/piVCCU3/userfs/etc/config/syslog) auf 0 gestellt um möglichst viel Information zu sammeln.
Wenn ich das Gerät z. B. über den NetFinder erfolgreich neu starte, so kann ich im syslog erkennen, dass das Lan-Gateway kurz nicht erreichbar ist (erwartungskonform) und dann wieder erreichbar wird.
Code: Alles auswählen
Oct 23 10:40:00 ccu3-webui user.err hs485d: LanConnection::perror(): recv error
Oct 23 10:40:01 ccu3-webui user.err hs485d: response timeout
Oct 23 10:40:01 ccu3-webui user.err hs485d: HS485ControllerLGW::keepAliveMsgThreadFunction(): Did not get an answer
Oct 23 10:40:03 ccu3-webui user.crit hs485d: LGWPortWrapper::connect(): Reconnected.
Code: Alles auswählen
Oct 22 14:14:47 ccu3-webui user.info root: Updating Wired Lan Gateway Firmware
Oct 22 14:14:47 ccu3-webui user.info update-lgw-firmware: LAN Gateway Firmware Update...
Oct 22 14:14:47 ccu3-webui user.info update-lgw-firmware: Gateway PEQ0170186
Oct 22 14:14:49 ccu3-webui user.info update-lgw-firmware: Gateway type is eQ3-HMW-LGW-App
Oct 22 14:14:49 ccu3-webui user.info update-lgw-firmware: Available Firmware Version: 1.0.5
Oct 22 14:14:49 ccu3-webui user.info update-lgw-firmware: Lan Gateway Firmware Version: 1.0.5
Oct 22 14:14:49 ccu3-webui user.info update-lgw-firmware: Firmware is up to date
Oct 22 14:14:49 ccu3-webui user.info hs485d: Using configuration file: /etc/config/hs485d.conf
Oct 22 14:14:49 ccu3-webui user.info hs485d: Lan Device Information: Protocol-Version: 1 Product-ID: eQ3-HMW-LGW Firmware-Version: 1.0.5 Serial Number: PEQ0170186
Code: Alles auswählen
Oct 22 14:14:49 ccu3-webui user.err hs485d: XmlRpc transport error calling system.listMethods({"BidCos-Wired_java"}) on http://127.0.0.1:9292/bidcos:
Oct 22 14:14:49 ccu3-webui user.err hs485d: XmlRpc transport error calling system.listMethods({"2084"}) on binary://127.0.0.1:1999/RPC2:
Code: Alles auswählen
Oct 22 14:14:53 ccu3-webui user.err rfd: XmlRpc transport error calling system.listMethods({"BidCos-RF_java"}) on http://127.0.0.1:9292/bidcos:
Oct 22 14:14:53 ccu3-webui user.err rfd: XmlRpc transport error calling system.listMethods({"1007"}) on binary://127.0.0.1:1999/RPC2:
Code: Alles auswählen
Oct 22 13:13:47 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_1 subtype=default
Oct 22 13:13:47 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_2 subtype=default
Oct 22 13:13:47 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_1 subtype=default
Oct 22 13:13:47 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_2 subtype=default
Oct 22 13:13:47 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_BUS_CONFIG_MISMATCH subtype=default
Code: Alles auswählen
Oct 23 10:57:15 ccu3-webui syslog.info syslogd started: BusyBox v1.28.4
Oct 23 10:57:15 ccu3-webui user.notice kernel: klogd started: BusyBox v1.28.4 (2018-08-29 11:23:30 CEST)
Oct 23 10:57:15 ccu3-webui daemon.err udhcpc[161]: started, v1.28.4
Oct 23 10:57:15 ccu3-webui daemon.err udhcpc[161]: sending discover
Oct 23 10:57:16 ccu3-webui daemon.err udhcpc[161]: sending select for 192.168.178.44
Oct 23 10:57:16 ccu3-webui daemon.err udhcpc[161]: lease of 192.168.178.44 obtained, lease time 864000
Oct 23 10:57:16 ccu3-webui user.info firewall: 'modprobe: FATAL: Module ip6_tables not found in directory /lib/modules/4.14.71-v7+ ip6tables v1.6.2: can't initialize ip6tables table `filter': Table does not exist (do you need to insmod?) Perhaps ip6tables or your kernel needs to be upgraded.'
Oct 23 10:57:16 ccu3-webui daemon.info ifplugd(eth0)[209]: ifplugd 0.28 initializing.
Oct 23 10:57:16 ccu3-webui daemon.info ifplugd(eth0)[209]: Using interface eth0/0A:AE:46:3A:FB:CF with driver <veth> (version: 1.0)
Oct 23 10:57:16 ccu3-webui daemon.info ifplugd(eth0)[209]: Using detection mode: SIOCETHTOOL
Oct 23 10:57:16 ccu3-webui daemon.info ifplugd(eth0)[209]: Initialization complete, link beat detected.
Oct 23 10:57:16 ccu3-webui daemon.warn ifplugd(eth0)[209]: Could not open /dev/tty, cannot beep.
Oct 23 10:57:16 ccu3-webui daemon.info ifplugd(eth0)[209]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Oct 23 10:57:17 ccu3-webui daemon.warn ifplugd(eth0)[209]: client: ifup: interface eth0 already configured
Oct 23 10:57:17 ccu3-webui daemon.info ifplugd(eth0)[209]: Program executed successfully.
Oct 23 10:57:23 ccu3-webui daemon.notice ntpdate[214]: step time server 81.14.202.21 offset 0.001768 sec
Oct 23 10:57:23 ccu3-webui daemon.notice ntpd[215]: ntpd 4.2.8p11@1.3728-o Wed Aug 29 10:08:05 UTC 2018 (1): Starting
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[215]: Command line: /usr/sbin/ntpd -g ntp.homematic.com
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: proto: precision = 0.989 usec (-20)
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: Listen and drop on 0 v6wildcard [::]:123
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: Listen normally on 2 lo 127.0.0.1:123
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: Listen normally on 3 eth0 192.168.178.44:123
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: Listen normally on 4 lo [::1]:123
Oct 23 10:57:23 ccu3-webui daemon.info ntpd[217]: Listening on routing socket on fd #21 for interface updates
Oct 23 10:57:23 ccu3-webui daemon.err xinetd[234]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Oct 23 10:57:23 ccu3-webui daemon.crit xinetd[234]: 234 {init_services} no services. Exiting...
Oct 23 10:57:23 ccu3-webui user.info root: Updating RF Lan Gateway Coprocessor Firmware
Oct 23 10:57:23 ccu3-webui user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Oct 23 10:57:23 ccu3-webui user.info root: Updating RF Lan Gateway Firmware
Oct 23 10:57:23 ccu3-webui user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Oct 23 10:57:23 ccu3-webui user.info root: Updating Wired Lan Gateway Firmware
Oct 23 10:57:23 ccu3-webui user.info update-lgw-firmware: LAN Gateway Firmware Update...
Oct 23 10:57:23 ccu3-webui user.info update-lgw-firmware: Gateway PEQ0170186
Oct 23 10:57:25 ccu3-webui user.info update-lgw-firmware: Gateway type is eQ3-HMW-LGW-App
Oct 23 10:57:25 ccu3-webui user.info update-lgw-firmware: Available Firmware Version: 1.0.5
Oct 23 10:57:25 ccu3-webui user.info update-lgw-firmware: Lan Gateway Firmware Version: 1.0.5
Oct 23 10:57:25 ccu3-webui user.info update-lgw-firmware: Firmware is up to date
Oct 23 10:57:25 ccu3-webui daemon.info snmpd[297]: Turning on AgentX master support.
Oct 23 10:57:25 ccu3-webui daemon.info snmpd[299]: NET-SNMP version 5.7.3
Oct 23 10:57:25 ccu3-webui user.info dÎè~^B: Copro application running.
Oct 23 10:57:25 ccu3-webui user.info kernel: [87939.954060] eq3loop: created slave mmd_hmip
Oct 23 10:57:25 ccu3-webui user.info kernel: [87939.954462] eq3loop: created slave mmd_bidcos
Oct 23 10:57:25 ccu3-webui user.info dÎè~^B: Vapp=030408 Vbl=010001 Vhmos=012200
Oct 23 10:57:25 ccu3-webui user.info dÎè~^B: SGTIN=30 14 F7 11 A0 00 1F 58 A9 93 01 4E
Oct 23 10:57:25 ccu3-webui user.info dÎè~^B: RF address=65535
Oct 23 10:57:25 ccu3-webui user.info dÎè~^B: Serial Number=PEQ0629070
Oct 23 10:57:25 ccu3-webui user.info dÎè~^B: Timer=10545
Oct 23 10:57:25 ccu3-webui user.err hs485d: XmlRpc transport error calling system.listMethods({"BidCos-Wired_java"}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:25 ccu3-webui user.err hs485d: XmlRpc transport error calling system.listMethods({"2084"}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:27 ccu3-webui user.info rfd: BidCoS-Service started
Oct 23 10:57:27 ccu3-webui user.info rfd: XmlRpc Server is listening on TCP port 2001
Oct 23 10:57:27 ccu3-webui user.warn rfd: opendir(/etc/config/firmware//) failed
Oct 23 10:57:27 ccu3-webui user.info rfd: CCU2CommController::init(): Coprocessor Bootloader Version is: 1.0.1
Oct 23 10:57:27 ccu3-webui user.info rfd: CCU2CommController::init(): Coprocessor Firmware Version is: 3.4.8
Oct 23 10:57:27 ccu3-webui user.info rfd: () CCU2CommController::setCSMACAEnabled(): CSMA/CA disabled.
Oct 23 10:57:27 ccu3-webui user.info rfd: Current AES key=0, previous AES key=0
Oct 23 10:57:30 ccu3-webui user.info rfd: RF-ccu support system.multicall
Oct 23 10:57:30 ccu3-webui user.info rfd: RF-ccu support event
Oct 23 10:57:30 ccu3-webui user.info rfd: RF-ccu support deleteDevices
Oct 23 10:57:30 ccu3-webui user.info rfd: RF-ccu support newDevices
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error calling system.listMethods({"BidCos-RF_java"}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error calling system.listMethods({"1007"}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","OEQ0987184:2","ACTUAL_TEMPERATURE",19.000000}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","OEQ0987184:2","ACTUAL_TEMPERATURE",19.000000}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","OEQ0987184:2","ACTUAL_HUMIDITY",63.000000}],[methodName:"event",params:{"BidCos-RF_java","OEQ0987184:2","SET_TEMPERATURE",17.000000}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","OEQ0987184:2","ACTUAL_HUMIDITY",63.000000}],[methodName:"event",params:{"1007","OEQ0987184:2","SET_TEMPERATURE",17.000000}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0080884:1","TEMPERATURE",17.000000}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0080884:1","TEMPERATURE",17.000000}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0080884:1","HUMIDITY",70}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0080884:1","HUMIDITY",70}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:30 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:37 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:37 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","CENTRAL","PONG","ccu"}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:37 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:37 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:37 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","CENTRAL","PONG","ccu"}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:37 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:37 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 10:57:38 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #01[BiDi|BC|Ren] 205F46->164A81 Info: 06 01 00 00 54
Oct 23 10:57:38 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0055382:1","STATE",false}],[methodName:"event",params:{"1007","KEQ0055382:1","WORKING",false}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:38 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0055382:1","STATE",false}],[methodName:"event",params:{"BidCos-RF_java","KEQ0055382:1","WORKING",false}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:38 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #0A[BiDi|BC|Ren] 205F46->164A81 Info: 06 02 00 00 54
Oct 23 10:57:38 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:38 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0055382:2","STATE",false}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0055382:2","STATE",false}],[methodName:"event",params:{"1007","KEQ0055382:2","WORKING",false}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:38 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0055382:2","WORKING",false}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:38 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:39 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #13[BiDi|BC|Ren] 205F46->164A81 Info: 06 03 00 00 54
Oct 23 10:57:39 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:39 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0055382:3","STATE",false}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:39 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:39 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:39 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"BidCos-RF_java","KEQ0055382:3","WORKING",false}]}) on http://127.0.0.1:9292/bidcos:
Oct 23 10:57:39 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:39 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:39 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0055382:3","STATE",false}],[methodName:"event",params:{"1007","KEQ0055382:3","WORKING",false}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:39 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:40 ccu3-webui user.info dÎè~^B: ACK received after 2 repetitions: #1C[BiDi|BC|Ren] 205F46->164A81 Info: 06 04 00 00 54
Oct 23 10:57:40 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:40 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0055382:4","STATE",false}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:40 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 10:57:40 ccu3-webui user.warn rfd: XmlRpc transport failed (first try), retrying...
Oct 23 10:57:40 ccu3-webui user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"1007","KEQ0055382:4","WORKING",false}]}) on binary://127.0.0.1:1999/RPC2:
Oct 23 10:57:40 ccu3-webui user.err rfd: XmlRpc transport error
Oct 23 11:02:40 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #01[BiDi|BC|Ren] 528DFC->164A81 EnergySpontaenous: 80 00 00 00 00 00 00 00 09 10 FC
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support deleteDevices
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support event
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support listDevices
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support newDevices
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support replaceDevice
Oct 23 11:02:40 ccu3-webui user.info rfd: yes support replace device
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support reportValueUsage
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support setReadyConfig
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support system.listMethods
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support system.methodHelp
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support updateDevice
Oct 23 11:02:40 ccu3-webui user.info rfd: 1007 support system.multicall
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [iseXmlRpc.h:281]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute failed; method =init Params = {"xmlrpc_bin://127.0.0.1:1999","1008"} result= nil [iseXmlRpc.cpp:2599]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallInit: CallXmlrpcMethod failed [iseXmlRpc.cpp:1204]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: XmlRpcClientThread::ThreadFunction(): failed call init for interface=VirtualDevices [iseRTHss.cpp:163]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: XmlRpc: Error in XmlRpcClient::writeRequest: write error (error 111). [iseXmlRpc.h:281]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute failed; method =init Params = {"xmlrpc_bin://127.0.0.1:1999","1009"} result= nil [iseXmlRpc.cpp:2599]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallInit: CallXmlrpcMethod failed [iseXmlRpc.cpp:1204]
Oct 23 11:02:40 ccu3-webui local0.err ReGaHss: Error: XmlRpcClientThread::ThreadFunction(): failed call init for interface=HmIP-RF [iseRTHss.cpp:163]
Oct 23 11:02:40 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #01[BiDi|BC|Ren] 528E27->164A81 EnergySpontaenous: 80 00 00 00 00 00 00 00 08 F6 FA
Oct 23 11:02:40 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #0A[BiDi|BC|Ren] 528DFC->164A81 Info: 06 01 00 00 3F
Oct 23 11:02:41 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #0A[BiDi|BC|Ren] 528E27->164A81 Info: 06 01 00 00 4D
Oct 23 11:02:41 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #01[BiDi|BC|Ren] 62933D->164A81 Info: 06 01 00 00 50
Oct 23 11:02:41 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #01[BiDi|BC|Ren] 199221->164A81 Info: 06 01 C8 00 3B
Oct 23 11:02:41 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #25[BiDi|BC|Ren] 205F46->164A81 Info: 06 01 00 00 54
Oct 23 11:02:42 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #2E[BiDi|BC|Ren] 205F46->164A81 Info: 06 02 00 00 54
Oct 23 11:02:42 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #37[BiDi|BC|Ren] 205F46->164A81 Info: 06 03 00 00 54
Oct 23 11:02:42 ccu3-webui user.info dÎè~^B: ACK received after 0 repetitions: #40[BiDi|BC|Ren] 205F46->164A81 Info: 06 04 00 00 54
Oct 23 11:02:42 ccu3-webui user.err rfd: HSSParameter::GetValue() id=BRIGHTNESS failed getting physical value.
Oct 23 11:02:42 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"OEQ0024393:1","BRIGHTNESS"} result= [faultCode:-1,faultString:"Failure"] [iseXmlRpc.cpp:2605]
Oct 23 11:02:42 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Oct 23 11:02:42 ccu3-webui local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0 [iseDOMdpHSS.cpp:130]
Oct 23 11:02:42 ccu3-webui user.err rfd: HSSParameter::GetValue() id=DECISION_VALUE failed getting physical value.
Oct 23 11:02:42 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"OEQ0987184:7","DECISION_VALUE"} result= [faultCode:-1,faultString:"Failure"] [iseXmlRpc.cpp:2605]
Oct 23 11:02:42 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Oct 23 11:02:42 ccu3-webui local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0 [iseDOMdpHSS.cpp:130]
Code: Alles auswählen
Oct 23 10:57:32 de.eq3.lib.util.dynamics.GenericFactory INFO [main] @GenericFactory
Oct 23 10:57:32 de.eq3.lib.util.dynamics.GenericFactory INFO [main] creating instance of HMServerConfiguration with no-arg constructor
Oct 23 10:57:37 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_1 subtype=default
Oct 23 10:57:37 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_2 subtype=default
Oct 23 10:57:37 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_1 subtype=default
Oct 23 10:57:37 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_2 subtype=default
Oct 23 10:57:37 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN [Thread-0] Invalid state parameter or subtype: Parameter ERROR_BUS_CONFIG_MISMATCH subtype=default
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [KeyServerWorker] (1) *worker
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [BackendCommandHandler] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [CheckDeviceExistHandler] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)
Oct 23 10:57:37 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceInclusionDefaultConfigurationChanger] (1)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyInitializion] (1)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: deploying 20 classes to Vert.x
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [Thread-0] SYSTEM: 20 VertxDeployers initialized
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-6] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (23a912d6-bf86-4ffa-9c03-7c62f5038b87)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of CheckDeviceExistHandler succeeded (9f9d889a-9d2e-413f-9633-741d87755c96)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of HMIPTRXWriterWorker succeeded (c5ff3d8d-441e-4013-8be3-cc37c4d24734)
Oct 23 10:57:38 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-1] Missing key server configuration parameter (Network.Key) for mode: KEYSERVER_LOCAL
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of KeyServerWorker succeeded (a85c5ead-fa88-449a-8a7d-7ba745c98288)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of TransactionSubsystemHandler succeeded (c90c8122-6408-4e7c-9572-f8c24130ce25)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of DeviceInclusionDefaultConfigurationChanger succeeded (c9dd1e41-1881-4b10-a315-1786d6660dd5)
Oct 23 10:57:38 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (cc860671-9275-4500-9847-41b24dc2e0f1)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of KryoPersistenceWorker succeeded (814b0bc7-2caf-4a71-b26e-e9d43334c34c)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of LocalServerPersistentDataLoader succeeded (1fbf9d1a-bb45-4e0e-82d0-6fffdee939fd)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (8784137f-1de7-4d43-93f2-41e2e94dfa10)
Oct 23 10:57:39 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO [vert.x-worker-thread-3] SYSTEM: Firmware update directory is set to /etc/config/firmware
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of FirmwareLoaderFileSystem succeeded (c78ab22e-2a3f-4d9b-8d2d-1e0d674bd0b0)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-3] SYSTEM: start of LegacyAPIWorker succeeded (49d5b798-519e-446d-8d0e-19205438aea9)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of IncomingHMIPFrameHandler succeeded (00c977f2-057a-4e6f-93a7-60025b61d9c1)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of BackendCommandHandler succeeded (234bdca9-6a8c-46da-8aa0-551f7e8bfff4)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-3] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (c8e4a6a3-715d-4bff-8c37-64e30a191ccc)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of LegacyNotificationHandler succeeded (4292034c-17a1-4a3c-821c-5e19def9f33d)
Oct 23 10:57:39 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of LegacyBackendNotificationHandler succeeded (fcd6eee6-2b50-465b-83ac-a04d779be0c2)
Oct 23 10:57:40 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization ERROR [vert.x-eventloop-thread-6] Error 'LOCAL_ADAPTER_NO_SUCH_PORT' while trying to open port '/dev/mmd_hmip':
de.eq3.cbcs.lib.commdevice.CommDeviceException: Exception while trying to open serial port. Check configured port '/dev/mmd_hmip'
at de.eq3.cbcs.lib.nrjavaserialdevice.SerialCommDevice.open(SerialCommDevice.java:109)
at de.eq3.cbcs.lib.hmiptrxcommadapter.HomeMaticIPTRXCommAdapter.open(HomeMaticIPTRXCommAdapter.java:737)
at de.eq3.cbcs.lib.hmiptrxcommadapter.HomeMaticIPTRXCommAdapter.<init>(HomeMaticIPTRXCommAdapter.java:102)
at de.eq3.cbcs.server.local.base.internal.ShareableHomeMaticIPTRXCommAdapter.<init>(ShareableHomeMaticIPTRXCommAdapter.java:25)
at de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization.start(LocalServerAdapterInitialization.java:182)
at io.vertx.core.AbstractVerticle.start(AbstractVerticle.java:111)
at io.vertx.core.impl.DeploymentManager.lambda$doDeploy$8(DeploymentManager.java:434)
at io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
at java.lang.Thread.run(Thread.java:748)
Caused by: gnu.io.NoSuchPortException
at gnu.io.CommPortIdentifier.getPortIdentifier(CommPortIdentifier.java:294)
at de.eq3.cbcs.lib.nrjavaserialdevice.SerialCommDevice.open(SerialCommDevice.java:100)
... 12 more
Oct 23 10:57:40 de.eq3.cbcs.vertx.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of LocalServerAdapterInitialization succeeded (625d4c12-36b2-49d4-bab7-6b5964d6109a)
Oct 23 10:57:45 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-3] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration (still unfulfilled: [connector.open])
Oct 23 10:57:45 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-4] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration (still unfulfilled: [connector.open])
Oct 23 10:57:52 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-0] SYSTEM ADVICE: long-lasting deployment, check deployment configuration - currently deployed 18 out of 20
Oct 23 10:57:52 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-4] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration (still unfulfilled: [connector.open])
Oct 23 10:57:52 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-3] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration (still unfulfilled: [connector.open])
Oct 23 10:57:59 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-3] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration (still unfulfilled: [connector.open])
Oct 23 10:57:59 de.eq3.cbcs.vertx.management.VertxManager WARN [vert.x-eventloop-thread-4] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration (still unfulfilled: [connector.open])
Vielen Dank für die Hilfe
Marcus