search

alexreinert / piVCCU

piVCCU is a project to install the original Homematic CCU3 firmware inside a virtualized container (lxc) on ARM based single board computers.
Apache License 2.0
302 stars 64 forks source link

VCCU startet nicht mehr nach upgrade #450

Closed lobocobra closed 2 years ago

lobocobra commented 2 years ago

Hallo zusammen

Ich habe einen Raspi 4.0 und hatte homematic am laufen... alles war so schön stabil.... => dann kam ich auf die Idee ein upgrade des ganzen systems zu machen und jetzt läuft homematic nicht mehr. => Openhab läuft ganz normal und der Raspi auch ... einfach kommt die homematic Webgui nicht hoch. => Backup habe ich von gestern , würde aber natürlich lieber gerne den Fehler reparieren

Ich habe mehrere Stunden versucht den Fehler zu finden und auch die Kernel Module wie hier beschrieben updated...

Könnte mir bitte jemand helfen den Fehler zu finden? Das wäre lieb.

sudo apt install pivccu-modules-raspberrypi raspberrypi-kernel
Reading package lists... Done
Building dependency tree
Reading state information... Done
pivccu-modules-raspberrypi is already the newest version (2.0-17).
raspberrypi-kernel is already the newest version (1:1.20220308~buster-1).
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
pivccu-info
piVCCU version: 3.65.6-73
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 Board serial:  NEQ1327654
 Radio MAC:     0x4F690C
HMIP Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 SGTIN:         3014F711A061A7D5699DAA26
 Radio MAC:     0xBB38B9
State:          RUNNING
PID:            4101
IP:             192.168.20.80
IP:             fd63:c0f2:f00b:0:e829:4aff:fe20:a760
CPU use:        38.56 seconds
BlkIO use:      160.00 KiB
Link:           vethpivccu
 TX bytes:      336.21 KiB
 RX bytes:      67.91 KiB
 Total bytes:   404.12 KiB`
sudo apt install pivccu3
Reading package lists... Done
Building dependency tree
Reading state information... Done
pivccu3 is already the newest version (3.65.6-73).
0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.

cat /etc/debian_version
10.12
sudo sudo brctl show
bridge name     bridge id               STP enabled     interfaces
br0             8000.dca632584f92       no              eth0
                                                        vethpivccu
journalctl -u pivccu
-- Logs begin at Mon 2022-08-22 20:57:06 CEST, end at Mon 2022-08-22 21:31:12 CEST. --
Aug 22 20:57:18 openHABianDevice systemd[1]: Starting piVCCU...
Aug 22 20:57:34 openHABianDevice start_container.sh[850]: kernel.sched_rt_runtime_us = -1
Aug 22 20:57:35 openHABianDevice systemd[1]: Started piVCCU.
Aug 22 21:10:37 openHABianDevice systemd[1]: /lib/systemd/system/pivccu.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/pivccu3.pid →
Aug 22 21:13:03 openHABianDevice systemd[1]: /lib/systemd/system/pivccu.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/pivccu3.pid →
Aug 22 21:13:21 openHABianDevice systemd[1]: Stopping piVCCU...
Aug 22 21:13:44 openHABianDevice stop_container.sh[20573]: rmmod: ERROR: Module fake_hmrf is not currently loaded
Aug 22 21:13:44 openHABianDevice stop_container.sh[20573]: rmmod: ERROR: Module dummy_rx8130 is not currently loaded
Aug 22 21:13:44 openHABianDevice stop_container.sh[20573]: rmmod: ERROR: Module hb_rf_eth is not currently loaded
Aug 22 21:13:44 openHABianDevice systemd[1]: pivccu.service: Succeeded.
Aug 22 21:13:44 openHABianDevice systemd[1]: Stopped piVCCU.
-- Reboot --
Aug 22 21:14:01 openHABianDevice systemd[1]: Starting piVCCU...
Aug 22 21:14:18 openHABianDevice start_container.sh[866]: kernel.sched_rt_runtime_us = -1
Aug 22 21:14:19 openHABianDevice systemd[1]: Started piVCCU.
Aug 22 21:26:54 openHABianDevice systemd[1]: Stopping piVCCU...
Aug 22 21:27:13 openHABianDevice stop_container.sh[3321]: rmmod: ERROR: Module fake_hmrf is not currently loaded
Aug 22 21:27:13 openHABianDevice stop_container.sh[3321]: rmmod: ERROR: Module dummy_rx8130 is not currently loaded
Aug 22 21:27:13 openHABianDevice stop_container.sh[3321]: rmmod: ERROR: Module hb_rf_eth is not currently loaded
Aug 22 21:27:13 openHABianDevice systemd[1]: pivccu.service: Succeeded.
Aug 22 21:27:13 openHABianDevice systemd[1]: Stopped piVCCU.
Aug 22 21:27:13 openHABianDevice systemd[1]: /lib/systemd/system/pivccu.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/pivccu3.pid →
Aug 22 21:27:15 openHABianDevice systemd[1]: /lib/systemd/system/pivccu.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/pivccu3.pid →
Aug 22 21:27:54 openHABianDevice systemd[1]: /lib/systemd/system/pivccu.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/pivccu3.pid →
Aug 22 21:27:55 openHABianDevice systemd[1]: /lib/systemd/system/pivccu.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/pivccu3.pid →
Aug 22 21:27:56 openHABianDevice systemd[1]: Starting piVCCU...
Aug 22 21:28:06 openHABianDevice start_container.sh[3762]: kernel.sched_rt_runtime_us = -1
Aug 22 21:28:06 openHABianDevice systemd[1]: Started piVCCU.
sudo pivccu-attach ps -Aef
PID   USER     TIME  COMMAND
    1 root      0:00 init
   99 root      0:00 /bin/hss_led -l 6
  132 dbus      0:00 dbus-daemon --system
  162 root      0:00 /sbin/syslogd -n -m 0 -R 192.168.20.90 -L
  165 root      0:00 /sbin/klogd -n
  256 root      0:00 /usr/sbin/ifplugd -i eth0 -fI -u0 -d10
  267 root      0:00 /usr/sbin/ntpd -g 62.202.141.23,0.ch.pool.ntp.org
  292 root      0:00 /bin/eq3configd
  299 root      0:00 /usr/sbin/lighttpd-angel -f /etc/lighttpd/lighttpd.conf -D
  301 root      0:00 /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
  306 root      0:00 /bin/ssdpd
  312 root      0:00 /usr/sbin/sshd
  314 root      0:00 /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
  315 root      0:00 /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
  316 root      0:00 /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
  317 root      0:00 /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf -D
  338 root      0:00 /usr/local/addons/cuxd/cuxd
  383 root      0:00 /bin/multimacd -f /etc/multimacd.conf -l 5
  409 root      0:02 /bin/rfd -f /etc/config/rfd.conf -l 5
  438 root      0:31 java -Xmx128m -Dos.arch=arm -Dlog4j.configurationFile=file:///etc/config/log4j2.xml -Dfile.encoding=ISO-8859-1 -Dgnu.io.rxtx.SerialPorts=/dev/mmd_hmip
  547 root      0:00 /bin/eq3-uds-services
  553 root      0:03 /bin/ReGaHss.community -f /etc/rega.conf -l 2
  614 root      0:11 node /usr/local/addons/mediola/neo_server//automation.js
  707 root      0:00 {redmaticLoader} /bin/sh /usr/local/addons/redmatic/bin/redmaticLoader
  718 root      0:21 node-red
  719 root      0:00 logger -p daemon.err -t node-red
  724 root      0:00 /usr/sbin/crond -f -l 9
  773 root      0:02 /usr/bin/node /usr/local/addons/mediola/neo_server/node_modules/script_executor.js

SSH to the container works... and I can check the version ...

cat VERSION
VERSION=3.65.6
PRODUCT=ccu3
PLATFORM=rpi3

var/log/messages in the container shows some connection issues?

Aug 22 21:28:08 ccu3cat daemon.err ntpd[267]: bind(21) AF_INET6 fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123 flags 0x11 failed: Cannot assign requested address
Aug 22 21:28:08 ccu3cat daemon.err ntpd[267]: unable to create socket on eth0 (5) for fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123
Aug 22 21:28:08 ccu3cat daemon.info ntpd[267]: failed to init interface for address fd63:c0f2:f00b:0:e829:4aff:fe20:a760
Aug 22 21:28:08 ccu3cat daemon.info ntpd[267]: Listen normally on 6 eth0 [fe80::e829:4aff:fe20:a760%7]:123
Aug 22 21:28:08 ccu3cat daemon.info ntpd[267]: Listening on routing socket on fd #22 for interface updates
Aug 22 21:28:08 ccu3cat daemon.info ntpd[267]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Aug 22 21:28:08 ccu3cat daemon.info ntpd[267]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Aug 22 21:28:08 ccu3cat daemon.err xinetd[286]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Aug 22 21:28:08 ccu3cat daemon.crit xinetd[286]: 286 {init_services} no services. Exiting...
Aug 22 21:28:08 ccu3cat auth.info sshd[312]: Server listening on 0.0.0.0 port 22.
Aug 22 21:28:08 ccu3cat auth.info sshd[312]: Server listening on :: port 22.
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: write_pid /var/run/cuxd.pid [338]
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: CUx-Daemon(2.4.4) on CCU(3.65.6) start PID:338
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:7215 update(-71s):Mon Aug 22 21:26:57 2022
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: 7 device-paramset(s) loaded ok!
Aug 22 21:28:08 ccu3cat user.info cuxd: started cux-daemon
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: write_proxy /var/cache/cuxd_proxy.ini (338 /usr/local/addons/cuxd/ 2.4.4 3.65.6 0)
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: add interface 'CUxD'
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Aug 22 21:28:08 ccu3cat daemon.info cuxd[338]: USB(1-1.4/ttyACM0) CUL433 connect(38400:8N1)
Aug 22 21:28:08 ccu3cat user.info root: Updating RF Lan Gateway Coprocessor Firmware
Aug 22 21:28:08 ccu3cat user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Aug 22 21:28:08 ccu3cat user.info root: Updating RF Lan Gateway Firmware
Aug 22 21:28:08 ccu3cat user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Aug 22 21:28:09 ccu3cat daemon.err ntpd[267]: bind(25) AF_INET6 fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123 flags 0x11 failed: Cannot assign requested address
Aug 22 21:28:09 ccu3cat daemon.err ntpd[267]: unable to create socket on eth0 (7) for fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123
Aug 22 21:28:09 ccu3cat daemon.info ntpd[267]: failed to init interface for address fd63:c0f2:f00b:0:e829:4aff:fe20:a760
Aug 22 21:28:10 ccu3cat daemon.info cuxd[338]: INIT 'binary://192.168.20.90:9126' '(null)'
Aug 22 21:28:11 ccu3cat daemon.info ntpd[267]: Listen normally on 8 eth0 [fd63:c0f2:f00b:0:e829:4aff:fe20:a760]:123
Aug 22 21:28:29 ccu3cat user.info kernel: [  869.065105] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 22 21:28:29 ccu3cat user.info kernel: [  869.065443] eq3loop: eq3loop_close_slave() mmd_hmip
Aug 22 21:28:29 ccu3cat user.info kernel: [  869.070025] eq3loop: eq3loop_open_slave() mmd_hmip
ifconfig
br0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet 192.168.20.90  netmask 255.255.0.0  broadcast 192.168.101.255
        inet6 fe80::dea6:32ff:fe58:4f92  prefixlen 64  scopeid 0x20<link>
        inet6 fd63:c0f2:f00b:0:dea6:32ff:fe58:4f92  prefixlen 64  scopeid 0x0<global>
        ether dc:a6:32:58:4f:92  txqueuelen 1000  (Ethernet)
        RX packets 3340  bytes 396716 (387.4 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1779  bytes 170354 (166.3 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        ether dc:a6:32:58:4f:92  txqueuelen 1000  (Ethernet)
        RX packets 3331  bytes 619095 (604.5 KiB)
        RX errors 0  dropped 23  overruns 0  frame 0
        TX packets 2032  bytes 187024 (182.6 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

lo: flags=73<UP,LOOPBACK,RUNNING>  mtu 65536
        inet 127.0.0.1  netmask 255.0.0.0
        inet6 ::1  prefixlen 128  scopeid 0x10<host>
        loop  txqueuelen 1000  (Local Loopback)
        RX packets 556  bytes 36547 (35.6 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 556  bytes 36547 (35.6 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

vethpivccu: flags=4163<UP,BROADCAST,RUNNING,MULTICAST>  mtu 1500
        inet6 fe80::fc84:ecff:fe34:68e0  prefixlen 64  scopeid 0x20<link>
        ether fe:84:ec:34:68:e0  txqueuelen 1000  (Ethernet)
        RX packets 972  bytes 162833 (159.0 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1401  bytes 435548 (425.3 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0
alexreinert commented 2 years ago

Was genau ist dmit gemeint, dass die GUI nicht hochkommt?

lobocobra commented 2 years ago

Ich kann in die PIVCCU mit ssh rein und der Container läuft einwandfrei aber ich die WebUI kommt nicht hoch und homematic reagiert nicht. Somit kan mein Openhab keine Verbindung zu den homematic geräten aufbauen. => vor dem update lief alles einwandfrei stabil ... ich hatte das schon früher nach updates, aber jetzt reagiert die homematic virtuelle CCU3 nicht mehr.

lobocobra commented 2 years ago

Hier habe ich noch die /varl/log/messages der PIvccu3 :

Aug 23 11:35:26 piVCCU syslog.info syslogd started: BusyBox v1.29.2
Aug 23 11:35:26 piVCCU user.notice kernel: klogd started: BusyBox v1.29.2 (2022-06-23 16:14:07 CEST)
Aug 23 11:35:26 piVCCU syslog.info syslogd exiting
Aug 23 11:35:26 ccu3cat syslog.info syslogd started: BusyBox v1.29.2
Aug 23 11:35:26 ccu3cat user.notice kernel: klogd started: BusyBox v1.29.2 (2022-06-23 16:14:07 CEST)
Aug 23 11:35:27 ccu3cat user.info firewall: iptables v1.6.2: invalid port/service `310000' specified Try `iptables -h' or 'iptables --help' for more information.
Aug 23 11:35:27 ccu3cat user.info firewall: ip6tables v1.6.2: invalid port/service `310000' specified Try `ip6tables -h' or 'ip6tables --help' for more information.
Aug 23 11:35:27 ccu3cat user.info firewall: configuration set
Aug 23 11:35:27 ccu3cat daemon.info ifplugd(eth0)[282]: ifplugd 0.28 initializing.
Aug 23 11:35:27 ccu3cat daemon.info ifplugd(eth0)[282]: Using interface eth0/EA:29:4A:20:A7:60 with driver <veth> (version: 1.0)
Aug 23 11:35:27 ccu3cat daemon.info ifplugd(eth0)[282]: Using detection mode: SIOCETHTOOL
Aug 23 11:35:27 ccu3cat daemon.info ifplugd(eth0)[282]: Initialization complete, link beat detected.
Aug 23 11:35:27 ccu3cat daemon.warn ifplugd(eth0)[282]: Could not open /dev/tty, cannot beep.
Aug 23 11:35:27 ccu3cat daemon.info ifplugd(eth0)[282]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Aug 23 11:35:27 ccu3cat daemon.warn ifplugd(eth0)[282]: client: ifup: interface eth0 already configured
Aug 23 11:35:27 ccu3cat daemon.info ifplugd(eth0)[282]: Program executed successfully.
Aug 23 11:35:27 ccu3cat daemon.err ntpdate[290]: Can't find host 62.202.141.23,0.ch.pool.ntp.org: Name or service not known (-2)
Aug 23 11:35:27 ccu3cat daemon.err ntpdate[290]: no servers can be used, exiting
Aug 23 11:35:27 ccu3cat daemon.notice ntpd[291]: ntpd 4.2.8p12@1.3728-o Thu Jun 23 16:11:58 CEST 2022 (1): Starting
Aug 23 11:35:27 ccu3cat daemon.info ntpd[291]: Command line: /usr/sbin/ntpd -g 62.202.141.23,0.ch.pool.ntp.org
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: proto: precision = 1.482 usec (-19)
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listen and drop on 0 v6wildcard [::]:123
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listen normally on 2 lo 127.0.0.1:123
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listen normally on 3 eth0 192.168.20.80:123
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listen normally on 4 lo [::1]:123
Aug 23 11:35:27 ccu3cat daemon.err ntpd[293]: bind(21) AF_INET6 fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123 flags 0x11 failed: Cannot assign requested address
Aug 23 11:35:27 ccu3cat daemon.err ntpd[293]: unable to create socket on eth0 (5) for fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: failed to init interface for address fd63:c0f2:f00b:0:e829:4aff:fe20:a760
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listen normally on 6 eth0 [fe80::e829:4aff:fe20:a760%11]:123
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: Listening on routing socket on fd #22 for interface updates
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Aug 23 11:35:27 ccu3cat daemon.info ntpd[293]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Aug 23 11:35:27 ccu3cat daemon.err xinetd[312]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Aug 23 11:35:27 ccu3cat daemon.crit xinetd[312]: 312 {init_services} no services. Exiting...
Aug 23 11:35:28 ccu3cat auth.info sshd[338]: Server listening on 0.0.0.0 port 22.
Aug 23 11:35:28 ccu3cat auth.info sshd[338]: Server listening on :: port 22.
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: write_pid /var/run/cuxd.pid [364]
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: CUx-Daemon(2.4.4) on CCU(3.65.6) start PID:364
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:7207 update(-22s):Tue Aug 23 11:35:06 2022
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: 7 device-paramset(s) loaded ok!
Aug 23 11:35:28 ccu3cat user.info cuxd: started cux-daemon
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: write_proxy /var/cache/cuxd_proxy.ini (364 /usr/local/addons/cuxd/ 2.4.4 3.65.6 0)
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: add interface 'CUxD'
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Aug 23 11:35:28 ccu3cat daemon.info cuxd[364]: USB(1-1.4/ttyACM0) CUL433 connect(38400:8N1)
Aug 23 11:35:28 ccu3cat user.info root: Updating RF Lan Gateway Coprocessor Firmware
Aug 23 11:35:28 ccu3cat user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Aug 23 11:35:28 ccu3cat user.info root: Updating RF Lan Gateway Firmware
Aug 23 11:35:28 ccu3cat user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Aug 23 11:35:28 ccu3cat daemon.err ntpd[293]: bind(25) AF_INET6 fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123 flags 0x11 failed: Cannot assign requested address
Aug 23 11:35:28 ccu3cat daemon.err ntpd[293]: unable to create socket on eth0 (7) for fd63:c0f2:f00b:0:e829:4aff:fe20:a760#123
Aug 23 11:35:28 ccu3cat daemon.info ntpd[293]: failed to init interface for address fd63:c0f2:f00b:0:e829:4aff:fe20:a760
Aug 23 11:35:30 ccu3cat user.info kernel: [49751.858437] eq3loop: eq3loop_open_slave() mmd_bidcos
Aug 23 11:35:30 ccu3cat daemon.info ntpd[293]: Listen normally on 8 eth0 [fd63:c0f2:f00b:0:e829:4aff:fe20:a760]:123
Aug 23 11:35:33 ccu3cat auth.info sshd[418]: Accepted password for root from 192.168.20.90 port 60082 ssh2
Aug 23 11:35:49 ccu3cat user.info kernel: [49771.253081] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 23 11:35:49 ccu3cat user.info kernel: [49771.253522] eq3loop: eq3loop_close_slave() mmd_hmip
Aug 23 11:35:52 ccu3cat daemon.info cuxd[364]: INIT 'binary://192.168.20.90:9126' '(null)'
Aug 23 11:35:53 ccu3cat daemon.info cuxd[364]: INIT 'binary://192.168.20.90:9126' '(null)'
Aug 23 11:35:53 ccu3cat user.err rfd: XmlRpcClient error calling newDevices({"RF-3014F711A061A7D5699DAA26",{[ADDRESS:"BidCoS-RF",CHILDREN:{"BidCoS-RF:0","BidCoS-RF:1","BidCoS-RF:2","BidCoS-RF:3","BidCoS-RF:4","BidCoS-RF:5","BidCoS-RF:6","BidCoS-RF:7","BidCoS-RF:8","BidCoS-RF:9","BidCoS-RF:10","BidCoS-RF:11","BidCoS-RF:12","BidCoS-RF:13","BidCoS-RF:14","BidCoS
-RF:15","BidCoS-RF:16","BidCoS-RF:17","BidCoS-RF:18","BidCoS-RF:19","BidCoS-RF:20","BidCoS-RF:21","BidCoS-RF:22","BidCoS-RF:23","BidCoS-RF:24","BidCoS-RF:25","BidCoS-RF:26","BidCoS-RF:27","BidCoS-RF:28","BidCoS-RF:29","BidCoS-RF:30","BidCoS-RF:31","BidCoS-RF:32","BidCoS-RF:33","BidCoS-RF:34","BidCoS-RF:35","BidCoS-RF:36","BidCoS-RF:37","BidCoS-RF:38","BidCoS-
RF:39","BidCoS-RF:40","BidCoS-RF:41","BidCoS-RF:42","BidCoS-RF:43","BidCoS-RF:44","BidCoS-RF:45","BidCoS-RF:46","BidCoS-RF:47","BidCoS-RF:48","BidCoS-RF:49","BidCoS-RF:50"},FIRMWARE:"3.65.6",FLAGS:9,INTERFACE:"NEQ1327654",PARAMSETS:{"MASTER"},PARENT:"",RF_ADDRESS:4207243,ROAMING:0,RX_MODE:1,TYPE:"HM-RCV-50",UPDAT
Aug 23 11:35:53 ccu3cat user.err rfd: XmlRpc transport error
Aug 23 11:35:56 ccu3cat daemon.info cuxd[364]: INIT 'binary://192.168.20.90:9126' 'CUXD-3014F711A061A7D5699DAA26'
Aug 23 11:35:56 ccu3cat daemon.warn cuxd[364]: process_rpc_request(127.0.0.1) - illegal XMLRPC(listDevices) request
Aug 23 11:35:59 ccu3cat daemon.warn cuxd[364]: process_rpc_request(127.0.0.1) - illegal XMLRPC(init) request
Aug 23 11:36:05 ccu3cat daemon.info redmatic: RedMatic v7.1.1 (c) Sebastian Raff https://github.com/rdmtc/RedMatic
Aug 23 11:36:05 ccu3cat daemon.info redmatic: Starting Node-RED
Aug 23 11:36:09 ccu3cat daemon.info node-red[746]: Welcome to Node-RED
Aug 23 11:36:09 ccu3cat daemon.info node-red[746]: ===================
Aug 23 11:36:09 ccu3cat daemon.info node-red[746]: Node-RED version: v1.2.3
Aug 23 11:36:09 ccu3cat daemon.info node-red[746]: Node.js  version: v14.15.0
Aug 23 11:36:09 ccu3cat daemon.info node-red[746]: Linux 5.10.103-v7l+ arm LE
Aug 23 11:36:09 ccu3cat daemon.err node-red: (node:746) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Aug 23 11:36:09 ccu3cat daemon.err node-red: (Use `node --trace-deprecation ...` to show where the warning was created)
Aug 23 11:36:11 ccu3cat daemon.info node-red[746]: Loading palette nodes
Aug 23 11:36:16 ccu3cat daemon.info node-red[746]: node-red-contrib-ccu version: 3.2.1
Aug 23 11:36:16 ccu3cat daemon.info node-red[746]: Dashboard version 2.23.5 started at /addons/red/ui
Aug 23 11:36:17 ccu3cat daemon.info node-red[746]: Settings file  : /usr/local/addons/redmatic/lib/settings.js
Aug 23 11:36:17 ccu3cat daemon.info node-red[746]: Context store  : 'default' [module=localfilesystem]
Aug 23 11:36:17 ccu3cat daemon.info node-red[746]: Context store  : 'memory' [module=memory]
Aug 23 11:36:17 ccu3cat daemon.info node-red[746]: User directory : /usr/local/addons/redmatic/var
Aug 23 11:36:17 ccu3cat daemon.warn node-red[746]: Projects disabled : editorTheme.projects.enabled=false
Aug 23 11:36:17 ccu3cat daemon.info node-red[746]: Flows file     : /usr/local/addons/redmatic/var/flows.json
Aug 23 11:36:17 ccu3cat daemon.info node-red[746]: Server now running at http://127.0.0.1:1880/addons/red/
Aug 23 11:36:18 ccu3cat daemon.info node-red[746]: Starting flows
Aug 23 11:36:19 ccu3cat daemon.info node-red[746]: [ccu-connection:localhost] paramsets loaded from /usr/local/addons/redmatic/var/paramsets.json
Aug 23 11:36:19 ccu3cat daemon.info node-red[746]: [ccu-connection:localhost] metadata loaded from /usr/local/addons/redmatic/var/ccu_localhost.json
Aug 23 11:36:19 ccu3cat daemon.info node-red[746]: [ccu-connection:localhost] regadata loaded from /usr/local/addons/redmatic/var/ccu_rega_localhost.json
Aug 23 11:36:19 ccu3cat daemon.info node-red[746]: Started flows
Aug 23 11:36:19 ccu3cat daemon.info node-red[746]: [mqtt-broker:Connect MQTT] Connected to broker: mqtt://192.168.20.90:1883
Aug 23 11:36:20 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0009156993C2EB:1","ILLUMINATION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ILLUMINATION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:36:20 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0009156993C2EB:1","ILLUMINATION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:36:20 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 23 11:36:36 ccu3cat user.err rfd: XmlRpcClient error calling event({[methodName:"event",params:{"RF-3014F711A061A7D5699DAA26","OEQ0128004:1","TEMPERATURE",24.700000}],[methodName:"event",params:{"RF-3014F711A061A7D5699DAA26","OEQ0128004:1","HUMIDITY",50}]}) on http://192.168.20.90:9125/RPC2:
Aug 23 11:36:36 ccu3cat user.err rfd: XmlRpc transport error
Aug 23 11:36:40 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0009156993C2EB:1","MOTION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: MOTION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:36:40 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0009156993C2EB:1","MOTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:36:40 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 23 11:36:40 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:36:40 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:36:40 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 23 11:36:40 ccu3cat daemon.info cuxd[364]: INIT 'xmlrpc_bin://127.0.0.1:31999' '1503'
Aug 23 11:37:00 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 23 11:37:00 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E58A9A2642A:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E58A9A2642A:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 23 11:37:00 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 23 11:37:00 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E58A9A2642A:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E58A9A2642A:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 23 11:37:00 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]

Ich kann nicht in die homematic WebUI einloggen und die virtuelle CCU reagiert auf keine Anfragen. Ich habe nirgendwo Anhaltspunkte gefunden im internet, was hier schief läuft. ich habe sicher schon 5h investiert und komme leider nicht weiter.

Hast du mir eventuell einen Tipp? apt install --reinstall pivccu-modules-raspberrypi hilft nicht und auch die anderen Tipps hier auf github die ich gefunden habe, halfen nicht.

lobocobra commented 2 years ago

Hat es eventuell damit was zu tun?

sudo journalctl -u pivccu
[sudo] password for rrs:
-- Logs begin at Mon 2022-08-22 21:48:57 CEST, end at Tue 2022-08-23 12:17:17 CEST. --
Aug 23 12:14:42 openHABianDevice stop_container.sh[477]: rmmod: ERROR: Module fake_hmrf is not currently loaded
Aug 23 12:14:43 openHABianDevice stop_container.sh[477]: rmmod: ERROR: Module dummy_rx8130 is not currently loaded
Aug 23 12:14:43 openHABianDevice stop_container.sh[477]: rmmod: ERROR: Module hb_rf_eth is not currently loaded
Aug 23 12:14:43 openHABianDevice systemd[1]: pivccu.service: Succeeded.
Aug 23 12:14:43 openHABianDevice systemd[1]: Stopped piVCCU.
-- Reboot --
Aug 23 12:15:04 openHABianDevice systemd[1]: Starting piVCCU...
Aug 23 12:15:22 openHABianDevice start_container.sh[879]: kernel.sched_rt_runtime_us = -1
Aug 23 12:15:39 openHABianDevice systemd[1]: Started piVCCU.
lobocobra commented 2 years ago

now I purged all installation started from zero with this guide.... https://github.com/alexreinert/piVCCU/blob/master/docs/setup/raspberrypi.md

and can not get it installed again:

sudo pivccu-info
piVCCU version: 3.65.6-73
Kernel modules: Available
lxc doesn't exist
Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  unknown
HMIP Hardware:  unknown
lxc doesn't exist

how I get lxc installed please?

lobocobra commented 2 years ago

I followed one of the old resulutions here and uninstalled / installed systemd. => now init seems to be default and therefore all systemd commands do not run automatically.

Anyone an idea how I make systemd again default? please? Or in other words... which services do I need to start in init.d instead?

alexreinert commented 2 years ago

Ich habe leider immer noch nicht verstanden, was genau es bedeutet, das die Web GUI nicht verfügbar sein soll, ein allgemeines "Ich kann mich nicht einloggen" sagt nichts aus. Bitte genau beschreiben. Vermutlich reicht es aus, wenn Openhab verzögert gestartet wird, weil Openhab zu Anfang so viele Requests macht und so interne Requests blockiert. Das Entfernen von systemd höre ich zum ersten Mal und ich kann mir absolut nicht vorstellen, was das bringen soll.

lobocobra commented 2 years ago

Also die homematic Zentrale started nicht mehr und funktioniert somit nicht. => Weder habe ich ein WEB Interface, noch funktioniert irgend ein Homematic Gerät.

Darauf hin wollte ich PIVCCU komplett neu installieren und habe alles gelöscht und bin dabei in umgekehrter Reihenfolge vorgegangen und habe dabei auch die systemd geölscht (ungewollt) und wieder installiert, jedoch scheint nun PIVCCU nicht mehr zu starten, da es von systemd abhängt. => openhab ist übrigens kein problem, weil auch wenn ich es 2 h später starte started die CCU im container nicht mehr.

So wie ich es sehe ist dies aufgrund von 2 problemen.... 1) die lxc doesn't exist Meldung => der hier hatte wohl mal das gleich Problem, aber eine Neuinstallation ist ja nicht der Linux weg :-) https://homematic-forum.de/forum/viewtopic.php?t=41059

2) das pivccu nicht rightig gestartet wird => steht irgendwo was beim boot gestarted werden muss? dann kann ich dies durch init.d machen

lobocobra commented 2 years ago

So jetzt habe ich mal geschaut, was denn systemd überhaupt macht und bin auf folgenden Befehl gestossen.... /var/lib/piVCCU3/start_container.sh

Wenn ich den manuell aussführe .....
sudo pivccu-info shows again....

piVCCU version: 3.65.6-73
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 Board serial:  NEQ1327654
 Radio MAC:     0x4F690C
HMIP Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 SGTIN:         3014F711A061A7D5699DAA26
 Radio MAC:     0xBB38B9
State:          RUNNING
PID:            3563
IP:             192.168.27.238
CPU use:        36.28 seconds
BlkIO use:      52.00 KiB
Link:           vethpivccu
 TX bytes:      15.68 KiB
 RX bytes:      93.71 KiB
 Total bytes:   109.39 KiB

nun ist unter 192.168.27.238 das erste mal wieder eine WebUI aber dort steht....

Die Homematic CCU ist noch nicht bereit
Bitte warten. Sie werden automatisch auf die Startseite weitergeleitet.

Muss ich noch etwas anderes starten, damit die CCU nicht beim Starten hängt?

lobocobra commented 2 years ago

Gut... ein Problem weniger.... in durch Zufügen von init=/lib/systemd/systemd in die Kernel Optionen der cmdline.txt , ist nun systemd wieder default....


├─init.scope
           │ └─1 /lib/systemd/systemd
           └─system.slice
             ├─grafana-server.service
             │ └─822 /usr/sbin/grafana-server --config=/etc/grafana/grafana.ini --pidfile=/run/grafana/grafana-server.pid --packaging=deb cfg:default.paths.logs=/var/log/grafana cfg:default.paths.data=/var/lib/grafana cfg:default.paths.plugins=/var/lib/gra
             ├─alsa-state.service
             │ └─348 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon
             ├─influxdb.service

Nach dem reboot zeigt der container jetzt....

piVCCU version: 3.65.6-73
Kernel modules: Available

Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 Board serial:  NEQ1327654
 Radio MAC:     0x4F690C
HMIP Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 SGTIN:         3014F711A061A7D5699DAA26
 Radio MAC:     0xBB38B9
State:          STOPPED

was kann ich machen, damit der container wieder läuft?

lobocobra commented 2 years ago

Nachdem ich nun folgendes ausführe.... sudo /var/lib/piVCCU3/start_container.sh

zeigt der container:

sudo ./pivccu-info.sh
piVCCU version: 3.65.6-73
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 Board serial:  NEQ1327654
 Radio MAC:     0x4F690C
HMIP Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 SGTIN:         3014F711A061A7D5699DAA26
 Radio MAC:     0xBB38B9
State:          RUNNING
PID:            2798
IP:             192.168.27.238
CPU use:        3.79 seconds
BlkIO use:      15.86 MiB
Link:           vethpivccu
 TX bytes:      350.91 KiB
 RX bytes:      37.21 KiB
 Total bytes:   388.11 KiB

Und ich komme zum login der CCU3 Webui... (das was nicht mehr ging). Ich habe nun folgende Fragen 1) Wie kriege kann ich start_container.sh automatisch ausführen? An systemd liegt es ja nicht mehr 2) Zwar läuft homematic CCU3 ohne Daten wieder, aber sobald ich das Backup einspiele hängt es wieder und die CCU läuft nicht mehr. 3) Wie kann ich eine ältere Version von PIVCCU3 einspielen? es scheint, dass die letzte Version nicht kompatibel ist mit älteren.

Besten Dank für die Beantwortung dieser 3 Fragen.

alexreinert commented 2 years ago

Keine Ahnung, was man an deinem kaputten systemd alles reparieren muss, damit die pivccu Unit wieder automatisch startet, alleine die notwendige Anpassung der cmdline zeigt, dass da irgendwas noch im Argen liegt. Was du mit "Hängen" ganz konkret meinst, hast du noch immer nicht beschrieben. Da es aber erst nach einspielen des Backups passiert, dürfte der Grund in deiner Konfiguration liegen. Ggf. hilft hier ein Blick in die Logs innerhalb des Containers. Ich glaube nicht dran, dass die 3.65 mit Backups aus älteren Versionen nicht kompatibel ist, der Aufschrei in der Homematic Community wäre mir aufgefallen. Ganz allgemein kann man ein Downgrade per aptmachen, ich würde aber davon abraten, weil es andersrum so ist, dass Konfigurationen aus neueren Versionen teilweise nicht in alten Versionen laufen.

lobocobra commented 2 years ago

Ich denke Systemd kann ich mittlerweilen als Fehlerquelle ausschliessen, da alle anderen services funktionieren. => I purge aber sicherheitshalber nochmals VICCU

Was ich unter hängen verstehe ... => homematic funktioniert nicht mehr ab dem Punkt ab welchem das Backup in die neue Version eingespielt wird => Unter hängen (freeze) verstehe ich, dass homematic weder eine GUI anzeigt, noch irgendwelche Zusstände von Aktoren empfängt oder weiterleitet.

Als nächsten Schritt, werde ich nun auf die letzte alte Version von homematic zurück zu gehen. Wenn dies dann funktioniert, weiss ich dass hier ein Konfigurationsproblem besteht.

alexreinert commented 2 years ago

Was heisst keine GUI anzeigen? Fehlermeldung, Timeout, leere Seite, ...

lobocobra commented 2 years ago

Die Meldung die ich nach Einspielen des Backups bekomme lautet:

Die Website ist nicht erreichbarDie Antwort von 192.168.27.239 hat zu lange gedauert.
Versuche Folgendes:

Verbindung prüfen
[Proxy und Firewall prüfen](chrome-error://chromewebdata/#buttons)
[Windows-Netzwerkdiagnose ausführen](javascript:diagnoseErrors())
ERR_CONNECTION_TIMED_OUT

Um den Fehler einzugrenzen, habe ich in der leeren Homematic ein paar Geräte angelernt und auch RedMatic installiert. => Dies klappt tadellos. Erst wenn ich eines der täglichen Backups einspiele bekomme ich keine Antwort mehr.

Hast Du mir eventuell einen Tipp wie ich eine Ältere Version von Homematic einspielen kann... ca 6 Monate alt? Weil ich habe die Konfiguration seitem nicht mehr angefasst und somit möchte ich überprüfen, ob es etwas with der Homematic Version zu tun hat oder nicht. => Vielen Dank

alexreinert commented 2 years ago

ok, also Timeout. Ist im Backup eine feste IP eingetragen, welche abweichend von der jetzigen ist? Nach dem Einspielen des Backups wäre diese wieder aktiv. Kann man die CCU IP anpingen? Was liefern sudo pivccu-attach cat /var/log/messages und sudo pivccu-attach cat /var/log/hmserver.log?

Ältere Versionen kann man wie geschrieben per apt einspielen.

lobocobra commented 2 years ago

Vielen Dank!

Ja das stimmt. Im backup ist eine feste IP eingetragen und die ist anders als die vom DHCP vergebene. => nach dem Einspielen des Backups kann ich das system pingen und auch per SSH auf der CCU3 einloggen.

sudo pivccu-info (nach Einspielung des Backups)

piVCCU version: 3.65.6-73
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 Board serial:  NEQ1327654
 Radio MAC:     0x4F690C
HMIP Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 SGTIN:         3014F711A061A7D5699DAA26
 Radio MAC:     0xBB38B9
State:          RUNNING
PID:            5146
IP:             192.168.20.80
IP:             fd63:c0f2:f00b:0:e829:4aff:fe20:a760
CPU use:        101.43 seconds
BlkIO use:      144.00 KiB
Link:           vethpivccu
 TX bytes:      363.93 KiB
 RX bytes:      485.75 KiB
 Total bytes:   849.69 KiB

sudo pivccu-attach cat /var/log/messages

Aug 24 20:58:00 piVCCU syslog.info syslogd started: BusyBox v1.29.2
Aug 24 20:58:00 piVCCU user.notice kernel: klogd started: BusyBox v1.29.2 (2022-06-23 16:14:07 CEST)
Aug 24 20:58:00 piVCCU syslog.info syslogd exiting
Aug 24 20:58:00 ccu3cat syslog.info syslogd started: BusyBox v1.29.2
Aug 24 20:58:00 ccu3cat user.notice kernel: klogd started: BusyBox v1.29.2 (2022-06-23 16:14:07 CEST)
Aug 24 20:58:00 ccu3cat user.info firewall: iptables v1.6.2: invalid port/service `310000' specified Try `iptables -h' or 'iptables --help' for more information.
Aug 24 20:58:00 ccu3cat user.info firewall: ip6tables v1.6.2: invalid port/service `310000' specified Try `ip6tables -h' or 'ip6tables --help' for more information.
Aug 24 20:58:01 ccu3cat user.info firewall: configuration set
Aug 24 20:58:01 ccu3cat daemon.info ifplugd(eth0)[288]: ifplugd 0.28 initializing.
Aug 24 20:58:01 ccu3cat daemon.info ifplugd(eth0)[288]: Using interface eth0/EA:29:4A:20:A7:60 with driver <veth> (version: 1.0)
Aug 24 20:58:01 ccu3cat daemon.info ifplugd(eth0)[288]: Using detection mode: SIOCETHTOOL
Aug 24 20:58:01 ccu3cat daemon.info ifplugd(eth0)[288]: Initialization complete, link beat detected.
Aug 24 20:58:01 ccu3cat daemon.warn ifplugd(eth0)[288]: Could not open /dev/tty, cannot beep.
Aug 24 20:58:01 ccu3cat daemon.info ifplugd(eth0)[288]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Aug 24 20:58:01 ccu3cat daemon.warn ifplugd(eth0)[288]: client: ifup: interface eth0 already configured
Aug 24 20:58:01 ccu3cat daemon.info ifplugd(eth0)[288]: Program executed successfully.
Aug 24 20:58:01 ccu3cat daemon.err ntpdate[296]: Can't find host 62.202.141.23,0.ch.pool.ntp.org: Name or service not known (-2)
Aug 24 20:58:01 ccu3cat daemon.err ntpdate[296]: no servers can be used, exiting
Aug 24 20:58:01 ccu3cat daemon.notice ntpd[297]: ntpd 4.2.8p12@1.3728-o Thu Jun 23 16:11:58 CEST 2022 (1): Starting
Aug 24 20:58:01 ccu3cat daemon.info ntpd[297]: Command line: /usr/sbin/ntpd -g 62.202.141.23,0.ch.pool.ntp.org
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: proto: precision = 1.481 usec (-19)
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen and drop on 0 v6wildcard [::]:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen normally on 2 lo 127.0.0.1:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen normally on 3 eth0 192.168.20.80:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen normally on 4 lo [::1]:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen normally on 5 eth0 [fd63:c0f2:f00b:0:e829:4aff:fe20:a760]:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listen normally on 6 eth0 [fe80::e829:4aff:fe20:a760%9]:123
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: Listening on routing socket on fd #23 for interface updates
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Aug 24 20:58:01 ccu3cat daemon.info ntpd[299]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Aug 24 20:58:01 ccu3cat daemon.err xinetd[317]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Aug 24 20:58:01 ccu3cat daemon.crit xinetd[317]: 317 {init_services} no services. Exiting...
Aug 24 20:58:01 ccu3cat auth.info sshd[344]: Server listening on 0.0.0.0 port 22.
Aug 24 20:58:01 ccu3cat auth.info sshd[344]: Server listening on :: port 22.
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: write_pid /var/run/cuxd.pid [370]
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: CUx-Daemon(2.4.4) on CCU(3.65.6) start PID:370
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: load paramsets(/usr/local/addons/cuxd/cuxd.ps) size:7207 update(-2175734s):Sat Jul 30 16:35:47 2022
Aug 24 20:58:01 ccu3cat user.info cuxd: started cux-daemon
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: 7 device-paramset(s) loaded ok!
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: write_proxy /var/cache/cuxd_proxy.ini (370 /usr/local/addons/cuxd/ 2.4.4 3.65.6 0)
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: add interface 'CUxD'
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: write interface(1) 'BidCos-RF' to /usr/local/etc/config/InterfacesList.xml
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: write interface(2) 'VirtualDevices' to /usr/local/etc/config/InterfacesList.xml
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: write interface(3) 'HmIP-RF' to /usr/local/etc/config/InterfacesList.xml
Aug 24 20:58:01 ccu3cat daemon.info cuxd[370]: write interface(4) 'CUxD' to /usr/local/etc/config/InterfacesList.xml
Aug 24 20:58:01 ccu3cat user.info root: Updating RF Lan Gateway Coprocessor Firmware
Aug 24 20:58:01 ccu3cat user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Aug 24 20:58:01 ccu3cat user.info root: Updating RF Lan Gateway Firmware
Aug 24 20:58:02 ccu3cat user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Aug 24 20:58:02 ccu3cat user.info kernel: [ 5514.805120] eq3loop: created slave mmd_hmip
Aug 24 20:58:06 ccu3cat daemon.warn cuxd[370]: process_rpc_request(192.168.20.90) - illegal XMLRPC(init) request
Aug 24 20:58:07 ccu3cat daemon.info cuxd[370]: INIT 'binary://192.168.20.90:9126' 'CUXD-3014F711A061A7D5699DAA26'
Aug 24 20:58:23 ccu3cat user.info kernel: [ 5536.015252] eq3loop: eq3loop_open_slave() mmd_hmip
Aug 24 20:58:29 ccu3cat daemon.warn cuxd[370]: process_rpc_request(127.0.0.1) - illegal XMLRPC(listDevices) request
Aug 24 20:58:32 ccu3cat daemon.warn cuxd[370]: process_rpc_request(127.0.0.1) - illegal XMLRPC(init) request
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0009156993C2EB:1","ILLUMINATION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ILLUMINATION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0009156993C2EB:1","ILLUMINATION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0009156993C2EB:1","MOTION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: MOTION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0009156993C2EB:1","MOTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","OPERATING_VOLTAGE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","RSSI_DEVICE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_DEVICE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","RSSI_PEER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_PEER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000193C99D2C1B:0","UNREACH"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: UNREACH"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000193C99D2C1B:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","ERROR_CODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ERROR_CODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","ERROR_CODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","OPERATING_VOLTAGE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","RSSI_DEVICE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_DEVICE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","RSSI_PEER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_PEER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","SABOTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SABOTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","SABOTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:0","UNREACH"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: UNREACH"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D8A99E58D3:1","STATE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: STATE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D8A99E58D3:1","STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:44 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","ERROR_CODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ERROR_CODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","ERROR_CODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:44 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","OPERATING_VOLTAGE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","RSSI_DEVICE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_DEVICE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","RSSI_PEER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_PEER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","SABOTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SABOTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","SABOTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:0","UNREACH"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: UNREACH"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A2DAF:1","STATE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: STATE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A2DAF:1","STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","ERROR_CODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ERROR_CODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","ERROR_CODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","OPERATING_VOLTAGE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","RSSI_DEVICE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_DEVICE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","RSSI_PEER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_PEER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","SABOTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SABOTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","SABOTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:0","UNREACH"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: UNREACH"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0000D7099A1FDB:1","STATE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: STATE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"0000D7099A1FDB:1","STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","OPERATING_VOLTAGE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","RSSI_DEVICE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_DEVICE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","RSSI_PEER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_PEER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:0","UNREACH"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: UNREACH"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","ACTIVE_PROFILE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ACTIVE_PROFILE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","ACTIVE_PROFILE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","ACTUAL_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ACTUAL_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","ACTUAL_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","ACTUAL_TEMPERATURE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ACTUAL_TEMPERATURE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","ACTUAL_TEMPERATURE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","BOOST_TIME"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: BOOST_TIME"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","BOOST_TIME"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","FROST_PROTECTION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: FROST_PROTECTION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","FROST_PROTECTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","HEATING_COOLING"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: HEATING_COOLING"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","HEATING_COOLING"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","HUMIDITY"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: HUMIDITY"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","HUMIDITY"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","HUMIDITY_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: HUMIDITY_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","HUMIDITY_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","PARTY_MODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_MODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","PARTY_MODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","PARTY_SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","PARTY_SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","PARTY_TIME_END"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","PARTY_TIME_START"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","QUICK_VETO_TIME"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: QUICK_VETO_TIME"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","QUICK_VETO_TIME"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","SET_POINT_MODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SET_POINT_MODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","SET_POINT_MODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","SWITCH_POINT_OCCURED"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SWITCH_POINT_OCCURED"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","SWITCH_POINT_OCCURED"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"000E9A49A8AF45:1","WINDOW_STATE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: WINDOW_STATE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"000E9A49A8AF45:1","WINDOW_STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","DUTY_CYCLE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: DUTY_CYCLE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","DUTY_CYCLE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","LOW_BAT"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: LOW_BAT"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","LOW_BAT"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","OPERATING_VOLTAGE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","OPERATING_VOLTAGE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","OPERATING_VOLTAGE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: OPERATING_VOLTAGE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","OPERATING_VOLTAGE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","RSSI_DEVICE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_DEVICE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","RSSI_DEVICE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","RSSI_PEER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: RSSI_PEER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","RSSI_PEER"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:0","UNREACH"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: UNREACH"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:0","UNREACH"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","ACTIVE_PROFILE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ACTIVE_PROFILE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","ACTIVE_PROFILE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","ACTUAL_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ACTUAL_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","ACTUAL_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","ACTUAL_TEMPERATURE_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ACTUAL_TEMPERATURE_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","ACTUAL_TEMPERATURE_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","BOOST_TIME"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: BOOST_TIME"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","BOOST_TIME"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","FROST_PROTECTION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: FROST_PROTECTION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","FROST_PROTECTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","HEATING_COOLING"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: HEATING_COOLING"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","HEATING_COOLING"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","HUMIDITY"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: HUMIDITY"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","HUMIDITY"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","HUMIDITY_STATUS"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: HUMIDITY_STATUS"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","HUMIDITY_STATUS"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","PARTY_MODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_MODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","PARTY_MODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","PARTY_SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","PARTY_SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","PARTY_TIME_END"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","PARTY_TIME_START"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","QUICK_VETO_TIME"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: QUICK_VETO_TIME"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","QUICK_VETO_TIME"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","SET_POINT_MODE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SET_POINT_MODE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","SET_POINT_MODE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","SWITCH_POINT_OCCURED"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: SWITCH_POINT_OCCURED"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","SWITCH_POINT_OCCURED"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:45 ccu3cat local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00265BE989A619:1","WINDOW_STATE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: WINDOW_STATE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1237, params: {"00265BE989A619:1","WINDOW_STATE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Aug 24 20:58:45 ccu3cat local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Aug 24 20:58:54 ccu3cat daemon.info cuxd[370]: INIT 'xmlrpc_bin://127.0.0.1:31999' '1503'

sudo pivccu-attach cat /var/log/hmserver.log

2022-08-24 20:58:11,939 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory
2022-08-24 20:58:11,967 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] created instance of HMServerConfiguration with parameter(s)
2022-08-24 20:58:11,968 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] passed 1 parameter(s), in declarative order [String]
2022-08-24 20:58:20,003 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker
2022-08-24 20:58:20,006 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [KeyServerWorker] (1) *worker
2022-08-24 20:58:20,006 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker
2022-08-24 20:58:20,007 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker
2022-08-24 20:58:20,007 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker
2022-08-24 20:58:20,008 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)
2022-08-24 20:58:20,008 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)
2022-08-24 20:58:20,009 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [TransactionManagementVerticle] (1)
2022-08-24 20:58:20,009 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [BackendCommandHandler] (1)
2022-08-24 20:58:20,010 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [StatemanagementResponseHandler] (1)
2022-08-24 20:58:20,011 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [StatemanagementRequestHandler] (1)
2022-08-24 20:58:20,011 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)
2022-08-24 20:58:20,012 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [CheckDeviceExistHandler] (1)
2022-08-24 20:58:20,012 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)
2022-08-24 20:58:20,013 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)
2022-08-24 20:58:20,013 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DeviceLiveUpdateSubsystem] (1)
2022-08-24 20:58:20,013 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DeviceDefaultConfigurationChanger] (1)
2022-08-24 20:58:20,014 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)
2022-08-24 20:58:20,014 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [DisplayUpdateStatusHandler] (1)
2022-08-24 20:58:20,014 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)
2022-08-24 20:58:20,028 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker
2022-08-24 20:58:20,028 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker
2022-08-24 20:58:20,029 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker
2022-08-24 20:58:20,029 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker
2022-08-24 20:58:20,030 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: added for deployment [LegacyInitializion] (1)
2022-08-24 20:58:20,046 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: deploying 25 classes to Vert.x
2022-08-24 20:58:20,084 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: 25 VertxDeployers initialized
2022-08-24 20:58:20,403 de.eq3.cbcs.server.core.vertx.CyclicSmokeDetectorAwakening INFO  [vert.x-eventloop-thread-3] SYSTEM: CyclicSmokeDetectorAwakening Verticle or Worker started
2022-08-24 20:58:20,462 de.eq3.cbcs.server.core.vertx.CheckDeviceExistHandler INFO  [vert.x-eventloop-thread-6] SYSTEM: CheckDeviceExistHandler Verticle or Worker started
2022-08-24 20:58:20,467 de.eq3.cbcs.server.core.vertx.DeviceDefaultConfigurationChanger INFO  [vert.x-eventloop-thread-2] SYSTEM: DeviceDefaultConfigurationChanger Verticle or Worker started
2022-08-24 20:58:20,470 de.eq3.cbcs.server.core.vertx.TransactionSubsystemHandler INFO  [vert.x-worker-thread-1] SYSTEM: TransactionSubsystemHandler Verticle or Worker started
2022-08-24 20:58:20,474 de.eq3.cbcs.server.core.vertx.HMIPAbstractWriterWorker INFO  [vert.x-worker-thread-0] SYSTEM: HMIPTRXWriterWorker Verticle or Worker started
2022-08-24 20:58:20,475 de.eq3.cbcs.server.core.vertx.DisplayUpdateStatusHandler INFO  [vert.x-eventloop-thread-7] SYSTEM: DisplayUpdateStatusHandler Verticle or Worker started
2022-08-24 20:58:20,473 de.eq3.cbcs.statemanagement.StatemanagementRequestHandler INFO  [vert.x-eventloop-thread-4] SYSTEM: StatemanagementRequestHandler Verticle or Worker started
2022-08-24 20:58:20,496 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBlindLevelCorrectionHandler INFO  [vert.x-worker-thread-4] SYSTEM: LegacyBlindLevelCorrectionHandler Verticle or Worker started
2022-08-24 20:58:20,499 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-2] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL
2022-08-24 20:58:20,501 de.eq3.cbcs.server.core.vertx.KeyServerWorker INFO  [vert.x-worker-thread-2] SYSTEM: KeyServerWorker Verticle or Worker started
2022-08-24 20:58:20,625 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of CheckDeviceExistHandler succeeded (9bc3cb39-061c-4645-9cb2-e500b7998727)
2022-08-24 20:58:20,626 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (dd72cfc8-aba4-4bcd-9dae-c26a4ca9a0c6)
2022-08-24 20:58:20,627 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (11e20a01-70c3-48fc-82bb-363b4a97e3ed)
2022-08-24 20:58:20,625 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DeviceDefaultConfigurationChanger succeeded (0c013ed9-7b79-4808-b416-aa2e34b8d23a)
2022-08-24 20:58:20,627 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of KeyServerWorker succeeded (034bd2dc-7a3f-42ba-9308-3abe77554f02)
2022-08-24 20:58:20,657 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of HMIPTRXWriterWorker succeeded (c536d905-f4c9-4261-9dbf-71cc357d2c01)
2022-08-24 20:58:21,926 de.eq3.cbcs.server.core.otau.AbstractUpdateSubsystem INFO  [vert.x-eventloop-thread-1] SYSTEM: DeviceBackgroundUpdateSubsystem Verticle or Worker started
2022-08-24 20:58:21,926 de.eq3.cbcs.server.core.otau.AbstractUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: DeviceLiveUpdateSubsystem Verticle or Worker started
2022-08-24 20:58:21,933 de.eq3.cbcs.server.core.live_otau.DeviceLiveUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: DeviceLiveUpdateSubsystem started
2022-08-24 20:58:21,962 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of DisplayUpdateStatusHandler succeeded (e1bc3b3b-af18-459c-8c84-00c3561cefa5)
2022-08-24 20:58:21,966 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of DeviceLiveUpdateSubsystem succeeded (fae80cb5-4d04-4832-b67d-f8dbf867ba62)
2022-08-24 20:58:21,966 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (9b4c5611-a0e6-42de-97cf-dd9f41e67d7f)
2022-08-24 20:58:21,967 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of StatemanagementRequestHandler succeeded (44a33f04-7b16-458d-84ad-b6bc63e813d3)
2022-08-24 20:58:21,968 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of TransactionSubsystemHandler succeeded (7f5dfe03-c2fd-426e-bd72-b4bdaa4daed0)
2022-08-24 20:58:21,969 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of KryoPersistenceWorker succeeded (28b1d172-8d29-4b58-9453-7f6e5187f215)
2022-08-24 20:58:21,997 de.eq3.cbcs.server.local.base.internal.LocalServerPersistentDataLoader INFO  [vert.x-eventloop-thread-6] SYSTEM: LocalServerPersistentDataLoader Verticle or Worker started
2022-08-24 20:58:22,004 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of LocalServerPersistentDataLoader succeeded (e6869cf7-f462-4da2-aa37-72bbab402dfb)
2022-08-24 20:58:23,250 de.eq3.cbcs.server.core.vertx.DeviceInclusionAcceptHandler INFO  [vert.x-eventloop-thread-0] SYSTEM: DeviceInclusionAcceptHandler Verticle or Worker started
2022-08-24 20:58:23,250 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO  [vert.x-worker-thread-0] SYSTEM: Firmware update directory is set to /etc/config/firmware
2022-08-24 20:58:23,252 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO  [vert.x-worker-thread-0] SYSTEM: FirmwareLoaderFileSystem Verticle or Worker started
2022-08-24 20:58:23,252 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (fc9d9dd6-8231-4b08-893e-d34b15fadcca)
2022-08-24 20:58:23,254 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of FirmwareLoaderFileSystem succeeded (832c86ab-c5ec-400c-94ac-e640cbd9fc49)
2022-08-24 20:58:23,258 de.eq3.cbcs.server.core.vertx.IncomingHMIPFrameHandler INFO  [vert.x-eventloop-thread-1] SYSTEM: IncomingHMIPFrameHandler Verticle or Worker started
2022-08-24 20:58:23,260 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of IncomingHMIPFrameHandler succeeded (6827b229-06c6-4497-982c-482200a64ded)
2022-08-24 20:58:23,320 de.eq3.cbcs.server.core.transaction.TransactionManagementVerticle INFO  [vert.x-eventloop-thread-2] SYSTEM: TransactionManagementVerticle Verticle or Worker started
2022-08-24 20:58:23,323 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of TransactionManagementVerticle succeeded (cf199481-530f-48fa-ae9e-667bf4665a42)
2022-08-24 20:58:23,501 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO  [vert.x-eventloop-thread-3] SYSTEM: LocalServerAdapterInitialization Verticle or Worker started
2022-08-24 20:58:23,504 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of LocalServerAdapterInitialization succeeded (165c64ef-01c2-4067-8e32-4ff6c19c29c0)
2022-08-24 20:58:23,536 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] TRX adapter state 1: HMIP_TRX_App
2022-08-24 20:58:23,537 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] TRX adapter application is already running or started
2022-08-24 20:58:23,538 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] No NWK, try to set address ...
2022-08-24 20:58:23,538 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Try to set radio address 12269753...
2022-08-24 20:58:23,620 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Set max send attempts for 3014F711A061A7D5699DAA26 to 3
2022-08-24 20:58:23,641 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Try to get application version...
2022-08-24 20:58:23,664 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Application version 2.8.6
2022-08-24 20:58:23,664 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Bootloader version 1.0.3
2022-08-24 20:58:23,664 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] hmos version 1.20.3
2022-08-24 20:58:23,768 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] MCU type: Si1002_8051
2022-08-24 20:58:23,790 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Duty Cycle: 3.0
2022-08-24 20:58:23,790 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] set DutyCycle limit to ffffffc8
2022-08-24 20:58:23,894 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Set Duty Cycle Limit
2022-08-24 20:58:23,916 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Current Security Counter: 730326925
2022-08-24 20:58:23,916 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Update security counter to calculation: 730349137
2022-08-24 20:58:24,001 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] TRX adapter has no linkpartner
2022-08-24 20:58:24,095 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [RXTXPortMonitor(/dev/mmd_hmip)] Adapter with Access Point id 3014F711A061A7D5699DAA26 initialized
2022-08-24 20:58:24,104 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO  [RXTXPortMonitor(/dev/mmd_hmip)] HMIPTRXInitialResponseListener said that Adapter was initialized
2022-08-24 20:58:24,754 de.eq3.cbcs.server.core.vertx.StatemanagementResponseHandler INFO  [vert.x-eventloop-thread-0] SYSTEM: StatemanagementResponseHandler Verticle or Worker started
2022-08-24 20:58:24,760 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of StatemanagementResponseHandler succeeded (0d793147-19d9-489e-835b-9b7752f7b0a5)
2022-08-24 20:58:24,760 de.eq3.cbcs.server.local.base.internal.LocalServerFirmwareUpdateInitialization INFO  [vert.x-eventloop-thread-7] SYSTEM: LocalServerFirmwareUpdateInitialization Verticle or Worker started
2022-08-24 20:58:24,767 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LocalServerFirmwareUpdateInitialization succeeded (5f831acb-1a2b-40d3-9dec-e06a8623becb)
2022-08-24 20:58:24,773 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO  [vert.x-worker-thread-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2022-08-24 20:58:24,776 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO  [vert.x-worker-thread-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2022-08-24 20:58:24,776 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO  [vert.x-worker-thread-1] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2022-08-24 20:58:24,784 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of LegacyBackendNotificationHandler succeeded (36642aae-5ace-42e9-b23c-4f4d0051a00b)
2022-08-24 20:58:24,782 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyNotificationHandler INFO  [vert.x-worker-thread-3] SYSTEM: LegacyNotificationHandler Verticle or Worker started
2022-08-24 20:58:24,816 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LegacyNotificationHandler succeeded (4f849e20-71c6-492f-8608-09e5724be2b0)
2022-08-24 20:58:24,829 de.eq3.cbcs.server.core.vertx.BackendCommandHandler INFO  [vert.x-eventloop-thread-1] SYSTEM: BackendCommandHandler Verticle or Worker started
2022-08-24 20:58:24,830 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of BackendCommandHandler succeeded (0bd51b6d-ccc9-4bae-a333-1bf8f17e1316)
2022-08-24 20:58:24,879 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyAPIWorker INFO  [vert.x-worker-thread-4] SYSTEM: LegacyAPIWorker Verticle or Worker started
2022-08-24 20:58:24,881 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of LegacyAPIWorker succeeded (db471aad-7b6e-4812-b292-631df97bfdad)
2022-08-24 20:58:24,936 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-1] SYSTEM: Checking all devices on all accesspoints for updates
2022-08-24 20:58:26,302 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyInitializion INFO  [vert.x-eventloop-thread-5] SYSTEM: LegacyInitializion Verticle or Worker started
2022-08-24 20:58:26,305 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of LegacyInitializion succeeded (79cb3897-f31a-41a1-9683-882936657998)
2022-08-24 20:58:26,396 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-1] SYSTEM: initial deployment complete _____________________________________________________
2022-08-24 20:58:26,473 de.eq3.cbcs.server.local.LocalServer INFO  [Thread-1] SYSTEM: Bind XML-RPC api to port 32010
2022-08-24 20:58:26,977 de.eq3.cbcs.util.EQ3LoggerOutputStream INFO  [Thread-2] std.out and std.err redirection active
2022-08-24 20:58:26,979 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default MaxEventLoopExecuteTime: 2000000000
2022-08-24 20:58:26,979 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default BlockedThreadCheckInterval: 1000
2022-08-24 20:58:26,980 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default MaxWorkerExecuteTime: 60000000000
2022-08-24 20:58:26,980 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default EventLoopPoolSize: 8
2022-08-24 20:58:26,990 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [BackendWorker] (1) *worker
2022-08-24 20:58:26,992 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker
2022-08-24 20:58:26,994 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker
2022-08-24 20:58:26,996 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker
2022-08-24 20:58:26,998 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker
2022-08-24 20:58:26,999 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker
2022-08-24 20:58:27,001 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker
2022-08-24 20:58:27,003 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [RegaClientWorker] (1) *worker
2022-08-24 20:58:27,005 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker
2022-08-24 20:58:27,009 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [HmIPGatewayManagementRequestWorker] (1) *worker
2022-08-24 20:58:27,025 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [AccessPointUpdateServerWorker] (1) *worker
2022-08-24 20:58:27,025 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [AccessPointUpdateLoaderWorker] (1) *worker
2022-08-24 20:58:27,032 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: deploying 12 classes to Vert.x
2022-08-24 20:58:27,041 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: 12 VertxDeployers initialized
2022-08-24 20:58:27,050 de.eq3.ccu.hmip.accesspoint.update.AccessPointUpdateLoaderWorker INFO  [vert.x-worker-thread-4] SYSTEM: Firmware update directory is set to /etc/config/firmware
2022-08-24 20:58:27,086 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of EnergyPriceRequestWorker succeeded (fb533a24-9f0f-4476-a81e-9966a5c56745)
2022-08-24 20:58:27,088 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of BackendWorker succeeded (f28d6b78-fb0c-44fb-921b-56968568a282)
2022-08-24 20:58:27,091 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of AccessPointUpdateLoaderWorker succeeded (8d535558-02b9-45d3-9f8b-1d462482f465)
2022-08-24 20:58:27,091 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of CouplingRequestWorker succeeded (e73ea4c8-194c-4afb-a5cc-75c171097fd9)
2022-08-24 20:58:27,103 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of RegaClientWorker succeeded (96427bbe-ed49-4ce4-8f14-742d13091778)
2022-08-24 20:58:27,109 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (a5b23c56-7c7c-4e75-ba87-ec79fe9fa9f6)
2022-08-24 20:58:27,126 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of StorageRequestWorker succeeded (54622552-25d5-4ece-9c8d-d1a75518852e)
2022-08-24 20:58:27,130 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of AccessPointUpdateServerWorker succeeded (2e38af8e-3259-4f5c-b939-d376e4573b45)
2022-08-24 20:58:27,224 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DiagramRequestWorker succeeded (c4ded2f2-45f8-409d-877f-7a03669d1d6d)
2022-08-24 20:58:27,224 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of GroupRequestWorker succeeded (3f79c8ad-1c75-44dc-a8c3-212c55542ffe)
2022-08-24 20:58:27,228 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (3e68a4cf-62f5-4e94-83c4-768f690b13a9)
2022-08-24 20:58:27,235 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of HmIPGatewayManagementRequestWorker succeeded (e3e13dfa-2fbe-48e3-8fa7-9487828b19e9)
2022-08-24 20:58:27,242 de.eq3.vertx.manager.management.VertxManager INFO  [Thread-2] SYSTEM: initial deployment complete _____________________________________________________
2022-08-24 20:58:27,244 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Starting HMServer at 127.0.0.1:39292
2022-08-24 20:58:27,245 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Read Configuration
2022-08-24 20:58:27,505 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create Bidcos Dispatcher
2022-08-24 20:58:27,573 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] InitBidCosCache
2022-08-24 20:58:29,975 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create groupDefinitionProvider
2022-08-24 20:58:30,572 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create VirtualDeviceHolder
2022-08-24 20:58:30,580 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create VirtualDeviceHandlerRega
2022-08-24 20:58:30,592 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupAdministrationService
2022-08-24 20:58:30,593 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupDeviceDispatcher
2022-08-24 20:58:30,596 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupDeviceHandler
2022-08-24 20:58:30,608 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] @GroupDeviceHandler - initializing...
2022-08-24 20:58:30,609 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher))
2022-08-24 20:58:30,609 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created virtualDeviceHandler (GroupDeviceService to ReGa)
2022-08-24 20:58:30,610 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> got groupDefinitionProvider
2022-08-24 20:58:30,610 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create BidCosGroupMemberProvider
2022-08-24 20:58:30,757 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init groupAdministrationService
2022-08-24 20:58:30,766 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init Virtual OS Device
2022-08-24 20:58:30,773 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init ESHLight Bridge
2022-08-24 20:58:31,772 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create RrdDatalogging
2022-08-24 20:58:31,779 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create MeasurementService
2022-08-24 20:58:31,790 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init MeasurementService
2022-08-24 20:58:31,928 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create HTTP Server
2022-08-24 20:58:31,947 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create BidCos context and start handler
2022-08-24 20:58:31,960 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create group context and start handler
2022-08-24 20:58:31,996 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-3] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:39292/bidcos
2022-08-24 20:58:32,009 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO  [HmIP-RF_java_WorkerPool-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2022-08-24 20:58:32,025 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [HmIP-RF_java_WorkerPool-0] init finished
2022-08-24 20:58:32,050 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-4] Added InterfaceId: HmIP-RF_java
2022-08-24 20:58:32,209 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO  [vert.x-worker-thread-4] updateDevicesForClient HmIP-RF_java -> 109 device addresses will be added
2022-08-24 20:58:32,299 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-1] SYSTEM: Checking all devices on all accesspoints for updates
2022-08-24 20:58:32,979 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Starting HMServer done
2022-08-24 20:58:44,626 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-0] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:31999
2022-08-24 20:58:44,637 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-0] Added InterfaceId: 1236
2022-08-24 20:58:44,647 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-13] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
2022-08-24 20:58:47,018 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 2385 ms, time limit is 2000 ms
2022-08-24 20:58:48,006 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 3385 ms, time limit is 2000 ms
2022-08-24 20:58:49,006 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 4385 ms, time limit is 2000 ms
2022-08-24 20:58:50,008 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 5385 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
        at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_202]
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_202]
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) ~[?:1.8.0_202]
        at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_202]
        at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_202]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_202]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_202]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_202]
        at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$67/1993710.run(Unknown Source) ~[?:?]
        at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$66/24029560.run(Unknown Source) ~[?:?]
        at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMIPServer.jar:?]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMIPServer.jar:?]
        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
        at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_202]
2022-08-24 20:58:51,008 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 6385 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
        at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_202]
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_202]
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) ~[?:1.8.0_202]
        at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_202]
        at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_202]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_202]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_202]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_202]
        at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$67/1993710.run(Unknown Source) ~[?:?]
        at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$66/24029560.run(Unknown Source) ~[?:?]
        at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMIPServer.jar:?]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMIPServer.jar:?]
        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
        at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_202]
2022-08-24 20:58:52,008 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 7385 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
        at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_202]
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_202]
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) ~[?:1.8.0_202]
        at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_202]
        at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_202]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_202]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_202]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_202]
        at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$67/1993710.run(Unknown Source) ~[?:?]
        at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$66/24029560.run(Unknown Source) ~[?:?]
        at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMIPServer.jar:?]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMIPServer.jar:?]
        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
        at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_202]
2022-08-24 20:58:53,008 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 8385 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
        at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_202]
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_202]
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) ~[?:1.8.0_202]
        at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_202]
        at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_202]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_202]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_202]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_202]
        at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$67/1993710.run(Unknown Source) ~[?:?]
        at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$66/24029560.run(Unknown Source) ~[?:?]
        at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMIPServer.jar:?]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMIPServer.jar:?]
        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
        at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_202]
2022-08-24 20:58:54,007 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 9385 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
        at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_202]
        at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_202]
        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) ~[?:1.8.0_202]
        at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_202]
        at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_202]
        at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_202]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_202]
        at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_202]
        at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMIPServer.jar:?]
        at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMIPServer.jar:?]
        at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$67/1993710.run(Unknown Source) ~[?:?]
        at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMIPServer.jar:?]
        at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$66/24029560.run(Unknown Source) ~[?:?]
        at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMIPServer.jar:?]
        at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMIPServer.jar:?]
        at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMIPServer.jar:?]
        at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
        at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_202]
2022-08-24 20:58:54,652 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:31999
2022-08-24 20:58:54,656 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO  [1237_WorkerPool-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2022-08-24 20:58:54,663 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-4] Added InterfaceId: 1237
2022-08-24 20:58:54,663 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [1237_WorkerPool-0] init finished
alexreinert commented 2 years ago

Und ist die WebUI auch unter dieser IP erreichbar?

lobocobra commented 2 years ago

Ja die WebUI ist auch unter der gleichen IP erreichbar. Jedoch nur bis ich das Backup einspiele. Danach bekomme ich wieder den Fehler, dass die Seite nicht verfügbar ist.

Die Website ist nicht erreichbar192.168.20.80 hat die Verbindung abgelehnt.
Versuche Folgendes:

Verbindung prüfen
[Proxy und Firewall prüfen](chrome-error://chromewebdata/#buttons)
ERR_CONNECTION_REFUSED

Ich kann auch per SSH auf die CCU3 einloggen und mich normal im System bewegen. Nur läuft halt die CCU3 Funktionalität nicht (weder per WebUi noch per Openhab binding)

piVCCU version: 3.65.6-73
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi UART:   Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 Board serial:  NEQ1327654
 Radio MAC:     0x4F690C
HMIP Hardware:  HM-MOD-RPI-PCB
 Connected via: GPIO@fe201000.serial (/dev/raw-uart)
 SGTIN:         3014F711A061A7D5699DAA26
 Radio MAC:     0xBB38B9
State:          RUNNING
PID:            5146
IP:             192.168.20.80
IP:             fd63:c0f2:f00b:0:e829:4aff:fe20:a760
CPU use:        145.80 seconds
BlkIO use:      144.00 KiB
Link:           vethpivccu
 TX bytes:      1.17 MiB
 RX bytes:      4.83 MiB
 Total bytes:   6.01 MiB

lighttpd-error.log

2022-08-24 20:58:01: (server.c.1430) server started (lighttpd/1.4.50)
2022-08-24 20:58:06: (gw_backend.c.245) establishing connection failed: Connection refused socket: tcp:127.0.0.1:32000
2022-08-24 20:58:06: (gw_backend.c.960) all handlers for /? on  are down.
2022-08-24 20:58:06: (gw_backend.c.245) establishing connection failed: Connection refused socket: tcp:127.0.0.1:32010
2022-08-24 20:58:06: (gw_backend.c.960) all handlers for /? on  are down.
2022-08-24 20:58:06: (gw_backend.c.245) establishing connection failed: Connection refused socket: tcp:127.0.0.1:39292
2022-08-24 20:58:06: (gw_backend.c.960) all handlers for /groups? on  are down.
2022-08-24 20:58:07: (gw_backend.c.245) establishing connection failed: Connection refused socket: tcp:127.0.0.1:8183
2022-08-24 20:58:07: (gw_backend.c.960) all handlers for /tclrega.exe? on  are down.
2022-08-24 20:58:08: (gw_backend.c.324) gw-server re-enabled: tcp:127.0.0.1:39292 127.0.0.1 39292
2022-08-24 20:58:08: (gw_backend.c.324) gw-server re-enabled: tcp:127.0.0.1:32010 127.0.0.1 32010
2022-08-24 20:58:08: (gw_backend.c.324) gw-server re-enabled: tcp:127.0.0.1:32000 127.0.0.1 32000
2022-08-24 20:58:09: (gw_backend.c.324) gw-server re-enabled: tcp:127.0.0.1:8183 127.0.0.1 8183

P.S. ==> Um sicherzugehen, dass mein Raspi gut läuft, habe ich noch ein neues System aufgesetzt und versucht dort mein Backup einzuspielen... dies ging dort auch nicht (gleiches Resultat) ==> I downgrade zu 2.61.7 habe ich gemacht, aber ich bekomme dort Melungen über Kernelmodul-Konflikte. Ich versuche weiterhin dies zu schaffen, damit ich weiss ob das Problem am Backup selber liegt oder mit der neuen CCU3 Firmware zu tun hat.

lobocobra commented 2 years ago

I tried to downgrade pivccu3 to version 3.61.7.-69 => and got the message: PreDepends: pivccu-modules-dkms (>= 1.0.10) but it is not installable

sudo apt install pivccu3=3.61.7-69
Reading package lists... Done
Building dependency tree... Done
Reading state information... Done
Some packages could not be installed. This may mean that you have
requested an impossible situation or if you are using the unstable
distribution that some required packages have not yet been created
or been moved out of Incoming.
The following information may help to resolve the situation:

The following packages have unmet dependencies:
 pivccu3 : PreDepends: pivccu-modules-dkms (>= 1.0.10) but it is not installable

But the current version installed is already higher than what is requested...

aptitude versions  pivccu-modules-dkms
p   1.0.68                                                                                                stable                                                                              500
i   1.0.77                                                                                                 stable

=> Any idea how to downgrade to this version?

lobocobra commented 2 years ago

bump

anyone knows how to downgrade a pivccu3 (see error messages above)

lobocobra commented 2 years ago

I solved now my problem alone as the backup works in RaspberryMatic. All with same issues might want to switch as well...

What I did

So in case you have the same issue, just use RaspberryMatic... it works great. => Must be a bug in PiVCCU3