Geräte Kommunikation gestört, Neu bei piVCCU

Virtualisierte CCU für Raspberry Pi und Clones

Moderator: Co-Administratoren

Antworten
Nitram
Beiträge: 24
Registriert: 15.10.2018, 10:48

Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von Nitram » 30.10.2018, 18:13

Hallo Miteinander,

ich bin ganz neu in der Materie und komme hier leider nicht weiter. Ich habe mir frisch ein Rasperry 3B+ System mit HM Mod aufgebaut. Ich habe initial das Image der piVCCU3 verwendet, als es hier zu Problemen kam aber auch schon ein bisschen rumgedoktert. Letztlich habe ich aber nun nur das Image drauf ein Update des Systems durchgeführt und das piVCCU3 nochmal neu vom Server drübergebügelt. Dann kam ich schon etwas weiter und konnte Geräte anlernen und anfangs auch schalten.

In einem anderen Thread hatte Alex schon beschrieben welche Systemausgaben er zur Fehleranalyse benötigt. Diese habe ich bereits abgefragt:

5 Minuten nach Systemstart:

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-info
piVCCU version: 3.37.8-6
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi3 UART:  Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
HMIP Hardware:  HM-MOD-RPI-PCB
Board serial:   PEQ0531326
Radio MAC:      0x6a604a
SGTIN:          3014F711A061A7D8A991837E
State:          RUNNING
PID:            721
IP:             192.168.1.122
CPU use:        25.01 seconds
BlkIO use:      59.18 MiB
Link:           vethpivccu
 TX bytes:      48.35 KiB
 RX bytes:      299.99 KiB
 Total bytes:   348.34 KiB

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/hm_mode
HM_HMIP_ADDRESS='0x6a604a'
HM_HMIP_DEV='HM-MOD-RPI-PCB'
HM_HMIP_DEVNODE='/dev/raw-uart'
HM_HMIP_SERIAL='PEQ0531326'
HM_HMIP_SGTIN='3014F711A061A7D8A991837E'
HM_HMIP_VERSION='2.8.6'
HM_HMRF_ADDRESS='0x6a604a'
HM_HMRF_DEV='HM-MOD-RPI-PCB'
HM_HMRF_DEVNODE='/dev/raw-uart'
HM_HMRF_SERIAL='PEQ0531326'
HM_HMRF_VERSION='2.8.6'
HM_HOST='rpi3'
HM_HOST_GPIO_RESET=''
HM_HOST_GPIO_UART='/dev/raw-uart'
HM_LED_GREEN=''
HM_LED_RED=''
HM_LED_YELLOW=''
HM_MODE='NORMAL'
HM_RTC=''

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/log/messages
Oct 30 17:56:41 piVCCU syslog.info syslogd started: BusyBox v1.28.4
Oct 30 17:56:41 piVCCU user.notice kernel: klogd started: BusyBox v1.28.4 (2018-08-29 11:23:30 CEST)
Oct 30 17:56:41 piVCCU syslog.info syslogd exiting
Oct 30 17:56:41 ccu3-webui syslog.info syslogd started: BusyBox v1.28.4
Oct 30 17:56:41 ccu3-webui user.notice kernel: klogd started: BusyBox v1.28.4 (2018-08-29 11:23:30 CEST)
Oct 30 17:56:41 ccu3-webui daemon.err udhcpc[152]: started, v1.28.4
Oct 30 17:56:41 ccu3-webui daemon.err udhcpc[152]: sending discover
Oct 30 17:56:41 ccu3-webui daemon.err udhcpc[152]: sending select for 192.168.1.122
Oct 30 17:56:42 ccu3-webui daemon.err udhcpc[152]: lease of 192.168.1.122 obtained, lease time 864000
Oct 30 17:56:42 ccu3-webui user.info firewall: 'modprobe: FATAL: Module ip6_tables not found in directory /lib/modules/4.14.71-v7+ ip6tables v1.6.2: can't initialize ip6tables table `filter': Table does not exist (do you need to insmod?) Perhaps ip6tables or your kernel needs to be upgraded.'
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: ifplugd 0.28 initializing.
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Using interface eth0/EA:4C:AE:CF:D9:7E with driver <veth> (version: 1.0)
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Using detection mode: SIOCETHTOOL
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Initialization complete, link beat detected.
Oct 30 17:56:42 ccu3-webui daemon.warn ifplugd(eth0)[200]: Could not open /dev/tty, cannot beep.
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Oct 30 17:56:42 ccu3-webui daemon.warn ifplugd(eth0)[200]: client: ifup: interface eth0 already configured
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Program executed successfully.
Oct 30 17:56:51 ccu3-webui daemon.notice ntpdate[205]: step time server 131.188.3.220 offset -0.001509 sec
Oct 30 17:56:51 ccu3-webui daemon.notice ntpd[206]: ntpd 4.2.8p11@1.3728-o Wed Aug 29 10:08:05 UTC 2018 (1): Starting
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[206]: Command line: /usr/sbin/ntpd -g 0.de.pool.ntp.org 1.de.pool.ntp.org 2.de.pool.ntp.org 3.de.pool.ntp.org
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: proto: precision = 0.416 usec (-21)
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen and drop on 0 v6wildcard [::]:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen normally on 2 lo 127.0.0.1:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen normally on 3 eth0 192.168.1.122:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen normally on 4 lo [::1]:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listening on routing socket on fd #21 for interface updates
Oct 30 17:56:52 ccu3-webui daemon.err xinetd[227]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Oct 30 17:56:52 ccu3-webui daemon.crit xinetd[227]: 227 {init_services} no services. Exiting...
Oct 30 17:56:52 ccu3-webui auth.info sshd[253]: Server listening on 0.0.0.0 port 22.
Oct 30 17:56:52 ccu3-webui auth.info sshd[253]: Server listening on :: port 22.
Oct 30 17:56:52 ccu3-webui user.info root: Updating RF Lan Gateway Coprocessor Firmware
Oct 30 17:56:52 ccu3-webui user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Oct 30 17:56:52 ccu3-webui user.info root: Updating RF Lan Gateway Firmware
Oct 30 17:56:52 ccu3-webui user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Oct 30 17:56:52 ccu3-webui user.info root: Updating Wired Lan Gateway Firmware
Oct 30 17:56:52 ccu3-webui user.info update-lgw-firmware: No gateway found in config file /etc/config/hs485d.conf
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Created directory: /var/lib/snmp
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Created directory: /var/lib/snmp/cert_indexes
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Created directory: /var/lib/snmp/mib_indexes
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Turning on AgentX master support.
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[289]: NET-SNMP version 5.7.3
Oct 30 17:56:52 ccu3-webui user.info kernel: [   38.790315] eq3loop: created slave mmd_hmip
Oct 30 17:57:09 ccu3-webui user.info kernel: [   55.716472] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 30 17:57:09 ccu3-webui user.info kernel: [   55.716647] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 30 17:57:09 ccu3-webui user.info kernel: [   55.720049] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 30 17:57:09 ccu3-webui user.notice kernel: [   55.731949] eq3loop: eq3loop_ioctl_slave() mmd_hmip: unhandled ioctl 0x5459

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/log/hmserver.log
Oct 30 17:57:02 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory
Oct 30 17:57:02 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] creating instance of HMServerConfiguration with no-arg constructor
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_1 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_2 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_1 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_2 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_BUS_CONFIG_MISMATCH subtype=default
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KeyServerWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [BackendCommandHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CheckDeviceExistHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionDefaultConfigurationChanger] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyInitializion] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: deploying 20 classes to Vert.x
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: 20 VertxDeployers initialized
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (a388a97f-5633-455e-b794-0c16efcc913c)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of TransactionSubsystemHandler succeeded (139acb8f-bc5d-451b-9c27-7c5b7fa288ea)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of DeviceInclusionDefaultConfigurationChanger succeeded (315739d0-82d8-466d-bc32-efa1a44bf539)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (74639478-4b6e-43a7-a658-198137c14736)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of CheckDeviceExistHandler succeeded (7abcd91e-de6a-437a-9a72-31b834cb2a90)
Oct 30 17:57:07 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-2] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of HMIPTRXWriterWorker succeeded (7f8d3678-8759-4c5d-b3ef-37ed7a4bef6a)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of KeyServerWorker succeeded (e743fc07-13cf-46ed-adc5-7839cc095717)
Oct 30 17:57:08 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of KryoPersistenceWorker succeeded (5976f5ef-dfce-4fd4-b6bd-5a8b65bd6383)
Oct 30 17:57:08 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LocalServerPersistentDataLoader succeeded (61b6ba56-9984-427b-aefd-a2dd28fa2e1d)
Oct 30 17:57:08 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (a0cf7f4a-45cb-4146-882e-caf766e11ae3)
Oct 30 17:57:09 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO  [vert.x-worker-thread-3] SYSTEM: Firmware update directory is set to /etc/config/firmware
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of FirmwareLoaderFileSystem succeeded (920832f5-3613-401e-ac59-3305d41c9740)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of IncomingHMIPFrameHandler succeeded (b8b18ec9-a345-44fa-8009-6f8c9dfdd4fb)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (17ab8ce9-1205-4aae-9f40-a0aa34cb7d27)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of LegacyBackendNotificationHandler succeeded (88ee7c51-b6aa-4c95-a696-a59601b3db9f)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of BackendCommandHandler succeeded (2b45f7a3-247e-4059-a89d-0b535aa4507f)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of LegacyNotificationHandler succeeded (28a50bb5-d356-4d3a-a9ba-2b5c7a15c22b)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of LegacyAPIWorker succeeded (2f3983ac-0236-48ee-94ca-4afa74d3fe60)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of LocalServerAdapterInitialization succeeded (bff61a05-dcb9-4e52-bd89-b32c6337a3da)
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter state 1: HMIP_TRX_App
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter application is already running or started
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] No NWK, try to set address ...
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to set radio address 11759593...
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set max send attempts for 3014F711A061A7D8A991837E to 3
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to get application version...
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Application version 2.8.6
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Bootloader version 1.0.3
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] hmos version 1.20.3
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] MCU type: Si1002_8051
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Duty Cycle: 0.0
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] set DutyCycle limit to ffffffc8
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set Duty Cycle Limit
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Current Security Counter: 1233152
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Update security counter to calculation: 1233217
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter has 5 link partners
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Adapter with Access Point id 3014F711A061A7D8A991837E initialized
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO  [Thread-6] HMIPTRXInitialResponseListener said that Adapter was initialized
Oct 30 17:57:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LegacyInitializion succeeded (b25783dc-79fd-4e9c-af02-6fbb93a6e210)
Oct 30 17:57:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of LocalServerFirmwareUpdateInitialization succeeded (b0996df2-a13b-469a-ad96-f5271a85e349)
Oct 30 17:57:10 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem WARN  [vert.x-worker-thread-4] SYSTEM: Firmware update directory in config file is no valid directory: /etc/config/firmware
Oct 30 17:57:10 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: Checking all devices on all accesspoints for updates
Oct 30 17:57:10 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: There are 0 APs queued with updatable devices
Oct 30 17:57:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: initial deployment complete _____________________________________________________
Oct 30 17:57:11 de.eq3.cbcs.server.local.LocalServer INFO  [Thread-0] SYSTEM: Bind XML-RPC api to port 2010
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default MaxEventLoopExecuteTime: 2000000000
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default BlockedThreadCheckInterval: 1000
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default MaxWorkerExecuteTime: 60000000000
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default EventLoopPoolSize: 8
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [BackendWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [RegaClientWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [HmIPGatewayManagementRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: deploying 10 classes to Vert.x
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: 10 VertxDeployers initialized
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of BackendWorker succeeded (470aad65-c789-4664-b5ac-5013a913db9c)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of EnergyPriceRequestWorker succeeded (8ccaba3b-3b14-4470-be9c-3cde4cf1b83b)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of RegaClientWorker succeeded (bc04469b-d200-4da1-8e9b-8d0ad24522ae)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of CouplingRequestWorker succeeded (fa5fcbd2-a54a-453e-ab78-bf35a672d3d4)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of StorageRequestWorker succeeded (1747b896-c895-47e4-af6d-371ffe70d3d4)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (169d7d95-0a67-40a4-a8f7-e4129f8a605c)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of DiagramRequestWorker succeeded (193e8da8-99e6-4106-ae00-649f076387a9)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of GroupRequestWorker succeeded (18b2682a-0271-4d3e-9fde-178e96b1d033)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (50c0c1d3-a5b1-4d45-be4e-89f65005c7b5)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of HmIPGatewayManagementRequestWorker succeeded (1b13f4ef-b033-43c3-b564-6068a4fa65ee)
Oct 30 17:57:13 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: initial deployment complete _____________________________________________________
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Starting HMServer at 127.0.0.1:9292
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Read Configuration
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create Bidcos Dispatcher
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] InitBidCosCache
Oct 30 17:57:14 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create groupDefinitionProvider
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create VirtualDeviceHolder
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create VirtualDeviceHandlerRega
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupAdministrationService
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupDeviceDispatcher
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupDeviceHandler
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] @GroupDeviceHandler - initializing...
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher))
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created virtualDeviceHandler (GroupDeviceService to ReGa)
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> got groupDefinitionProvider
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create BidCosGroupMemberProvider
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init groupAdministrationService
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init Virtual OS Device
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init ESHLight Bridge
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create RrdDatalogging
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create MeasurementService
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init MeasurementService
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create HTTP Server
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create BidCos context and start handler
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create group context and start handler
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:9292/bidcos
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] init finished
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-1] Added InterfaceId: HmIP-RF_java
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO  [vert.x-worker-thread-1] updateDevicesForClient HmIP-RF_java -> 39 device addresses will be added
Oct 30 17:57:17 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Starting HMServer done
Oct 30 17:57:27 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:1999
Oct 30 17:57:27 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] Added InterfaceId: 1008
Oct 30 17:57:27 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-11] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
Oct 30 17:57:37 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-3] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:1999
Oct 30 17:57:37 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-3] init finished
Oct 30 17:57:37 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-3] Added InterfaceId: 1009
Oct 30 18:02:08 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: 0 Accesspoints in Queue
Oct 30 18:02:08 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
Oct 30 18:02:08 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: Eventlistener Handler utilization: 0/50 used

Und nun noch nach dem Zugriff auf WebUI und einem fehlerhaften schalten eines Wandthermostats:

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-info
piVCCU version: 3.37.8-6
Kernel modules: Available
Raw UART dev:   Available
Rasp.Pi3 UART:  Assigned to GPIO pins
HMRF Hardware:  HM-MOD-RPI-PCB
HMIP Hardware:  HM-MOD-RPI-PCB
Board serial:   PEQ0531326
Radio MAC:      0x6a604a
SGTIN:          3014F711A061A7D8A991837E
State:          RUNNING
PID:            721
IP:             192.168.1.122
CPU use:        27.06 seconds
BlkIO use:      60.75 MiB
Link:           vethpivccu
 TX bytes:      786.44 KiB
 RX bytes:      446.10 KiB
 Total bytes:   1.20 MiB

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/hm_mode
HM_HMIP_ADDRESS='0x6a604a'
HM_HMIP_DEV='HM-MOD-RPI-PCB'
HM_HMIP_DEVNODE='/dev/raw-uart'
HM_HMIP_SERIAL='PEQ0531326'
HM_HMIP_SGTIN='3014F711A061A7D8A991837E'
HM_HMIP_VERSION='2.8.6'
HM_HMRF_ADDRESS='0x6a604a'
HM_HMRF_DEV='HM-MOD-RPI-PCB'
HM_HMRF_DEVNODE='/dev/raw-uart'
HM_HMRF_SERIAL='PEQ0531326'
HM_HMRF_VERSION='2.8.6'
HM_HOST='rpi3'
HM_HOST_GPIO_RESET=''
HM_HOST_GPIO_UART='/dev/raw-uart'
HM_LED_GREEN=''
HM_LED_RED=''
HM_LED_YELLOW=''
HM_MODE='NORMAL'
HM_RTC=''

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/log/messages
Oct 30 17:56:41 piVCCU syslog.info syslogd started: BusyBox v1.28.4
Oct 30 17:56:41 piVCCU user.notice kernel: klogd started: BusyBox v1.28.4 (2018-08-29 11:23:30 CEST)
Oct 30 17:56:41 piVCCU syslog.info syslogd exiting
Oct 30 17:56:41 ccu3-webui syslog.info syslogd started: BusyBox v1.28.4
Oct 30 17:56:41 ccu3-webui user.notice kernel: klogd started: BusyBox v1.28.4 (2018-08-29 11:23:30 CEST)
Oct 30 17:56:41 ccu3-webui daemon.err udhcpc[152]: started, v1.28.4
Oct 30 17:56:41 ccu3-webui daemon.err udhcpc[152]: sending discover
Oct 30 17:56:41 ccu3-webui daemon.err udhcpc[152]: sending select for 192.168.1.122
Oct 30 17:56:42 ccu3-webui daemon.err udhcpc[152]: lease of 192.168.1.122 obtained, lease time 864000
Oct 30 17:56:42 ccu3-webui user.info firewall: 'modprobe: FATAL: Module ip6_tables not found in directory /lib/modules/4.14.71-v7+ ip6tables v1.6.2: can't initialize ip6tables table `filter': Table does not exist (do you need to insmod?) Perhaps ip6tables or your kernel needs to be upgraded.'
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: ifplugd 0.28 initializing.
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Using interface eth0/EA:4C:AE:CF:D9:7E with driver <veth> (version: 1.0)
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Using detection mode: SIOCETHTOOL
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Initialization complete, link beat detected.
Oct 30 17:56:42 ccu3-webui daemon.warn ifplugd(eth0)[200]: Could not open /dev/tty, cannot beep.
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Oct 30 17:56:42 ccu3-webui daemon.warn ifplugd(eth0)[200]: client: ifup: interface eth0 already configured
Oct 30 17:56:42 ccu3-webui daemon.info ifplugd(eth0)[200]: Program executed successfully.
Oct 30 17:56:51 ccu3-webui daemon.notice ntpdate[205]: step time server 131.188.3.220 offset -0.001509 sec
Oct 30 17:56:51 ccu3-webui daemon.notice ntpd[206]: ntpd 4.2.8p11@1.3728-o Wed Aug 29 10:08:05 UTC 2018 (1): Starting
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[206]: Command line: /usr/sbin/ntpd -g 0.de.pool.ntp.org 1.de.pool.ntp.org 2.de.pool.ntp.org 3.de.pool.ntp.org
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: proto: precision = 0.416 usec (-21)
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen and drop on 0 v6wildcard [::]:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen normally on 2 lo 127.0.0.1:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen normally on 3 eth0 192.168.1.122:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listen normally on 4 lo [::1]:123
Oct 30 17:56:51 ccu3-webui daemon.info ntpd[208]: Listening on routing socket on fd #21 for interface updates
Oct 30 17:56:52 ccu3-webui daemon.err xinetd[227]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Oct 30 17:56:52 ccu3-webui daemon.crit xinetd[227]: 227 {init_services} no services. Exiting...
Oct 30 17:56:52 ccu3-webui auth.info sshd[253]: Server listening on 0.0.0.0 port 22.
Oct 30 17:56:52 ccu3-webui auth.info sshd[253]: Server listening on :: port 22.
Oct 30 17:56:52 ccu3-webui user.info root: Updating RF Lan Gateway Coprocessor Firmware
Oct 30 17:56:52 ccu3-webui user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Oct 30 17:56:52 ccu3-webui user.info root: Updating RF Lan Gateway Firmware
Oct 30 17:56:52 ccu3-webui user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Oct 30 17:56:52 ccu3-webui user.info root: Updating Wired Lan Gateway Firmware
Oct 30 17:56:52 ccu3-webui user.info update-lgw-firmware: No gateway found in config file /etc/config/hs485d.conf
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Created directory: /var/lib/snmp
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Created directory: /var/lib/snmp/cert_indexes
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Created directory: /var/lib/snmp/mib_indexes
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[287]: Turning on AgentX master support.
Oct 30 17:56:52 ccu3-webui daemon.info snmpd[289]: NET-SNMP version 5.7.3
Oct 30 17:56:52 ccu3-webui user.info kernel: [   38.790315] eq3loop: created slave mmd_hmip
Oct 30 17:57:09 ccu3-webui user.info kernel: [   55.716472] eq3loop: eq3loop_open_slave() mmd_hmip
Oct 30 17:57:09 ccu3-webui user.info kernel: [   55.716647] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 30 17:57:09 ccu3-webui user.info kernel: [   55.720049] eq3loop: eq3loop_close_slave() mmd_hmip
Oct 30 17:57:09 ccu3-webui user.notice kernel: [   55.731949] eq3loop: eq3loop_ioctl_slave() mmd_hmip: unhandled ioctl 0x5459
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"000C98A99506E2:1","PARTY_TIME_START"} result= [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"] [iseXmlRpc.cpp:2605]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =  [iseDOMdpHSS.cpp:130]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"000C98A99506E2:1","PARTY_TIME_END"} result= [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"] [iseXmlRpc.cpp:2605]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal =  [iseDOMdpHSS.cpp:130]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =getValue Params = {"000C98A99506E2:1","PARTY_SET_POINT_TEMPERATURE"} result= [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_SET_POINT_TEMPERATURE"] [iseXmlRpc.cpp:2605]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallGetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1432]
Oct 30 18:04:20 ccu3-webui local0.err ReGaHss: Error: IseHssDP::ReadValue: CallGetValue failed; sVal = 0.000000 [iseDOMdpHSS.cpp:130]
Oct 30 18:04:26 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =setValue Params = {"000C98A99506E2:1","ACTIVE_PROFILE",3} result= [faultCode:-1,faultString:"Generic error (TIMEOUT)"] [iseXmlRpc.cpp:2605]
Oct 30 18:04:26 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallSetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1502]
Oct 30 18:04:26 ccu3-webui local0.err ReGaHss: Error: IseHssDP::WriteValue: CallSetValue failed; address = 000C98A99506E2:1 [iseDOMdpHSS.cpp:77]
Oct 30 18:04:29 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallXmlrpcMethod: execute result isFault; method =setValue Params = {"000C98A99506E2:1","ACTIVE_PROFILE",2} result= [faultCode:-1,faultString:"Generic error (TIMEOUT)"] [iseXmlRpc.cpp:2605]
Oct 30 18:04:29 ccu3-webui local0.err ReGaHss: Error: IseXmlRpc::CallSetValue: CallXmlrpcMethod failed [iseXmlRpc.cpp:1502]
Oct 30 18:04:29 ccu3-webui local0.err ReGaHss: Error: IseHssDP::WriteValue: CallSetValue failed; address = 000C98A99506E2:1 [iseDOMdpHSS.cpp:77]

Code: Alles auswählen

pi@raspberrypi:~ $ sudo pivccu-attach cat /var/log/hmserver.log
Oct 30 17:57:02 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory
Oct 30 17:57:02 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] creating instance of HMServerConfiguration with no-arg constructor
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_1 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_POWER_SHORT_CIRCUIT_BUS_2 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_1 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_SHORT_CIRCUIT_DATA_LINE_BUS_2 subtype=default
Oct 30 17:57:07 de.eq3.cbcs.devicedescription.ChannelTypeDescription WARN  [Thread-0] Invalid state parameter or subtype: Parameter ERROR_BUS_CONFIG_MISMATCH subtype=default
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KeyServerWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [BackendCommandHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CheckDeviceExistHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionDefaultConfigurationChanger] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyInitializion] (1)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: deploying 20 classes to Vert.x
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: 20 VertxDeployers initialized
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (a388a97f-5633-455e-b794-0c16efcc913c)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of TransactionSubsystemHandler succeeded (139acb8f-bc5d-451b-9c27-7c5b7fa288ea)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of DeviceInclusionDefaultConfigurationChanger succeeded (315739d0-82d8-466d-bc32-efa1a44bf539)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (74639478-4b6e-43a7-a658-198137c14736)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of CheckDeviceExistHandler succeeded (7abcd91e-de6a-437a-9a72-31b834cb2a90)
Oct 30 17:57:07 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-2] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of HMIPTRXWriterWorker succeeded (7f8d3678-8759-4c5d-b3ef-37ed7a4bef6a)
Oct 30 17:57:07 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of KeyServerWorker succeeded (e743fc07-13cf-46ed-adc5-7839cc095717)
Oct 30 17:57:08 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-6] SYSTEM: start of KryoPersistenceWorker succeeded (5976f5ef-dfce-4fd4-b6bd-5a8b65bd6383)
Oct 30 17:57:08 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of LocalServerPersistentDataLoader succeeded (61b6ba56-9984-427b-aefd-a2dd28fa2e1d)
Oct 30 17:57:08 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (a0cf7f4a-45cb-4146-882e-caf766e11ae3)
Oct 30 17:57:09 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO  [vert.x-worker-thread-3] SYSTEM: Firmware update directory is set to /etc/config/firmware
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of FirmwareLoaderFileSystem succeeded (920832f5-3613-401e-ac59-3305d41c9740)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of IncomingHMIPFrameHandler succeeded (b8b18ec9-a345-44fa-8009-6f8c9dfdd4fb)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (17ab8ce9-1205-4aae-9f40-a0aa34cb7d27)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of LegacyBackendNotificationHandler succeeded (88ee7c51-b6aa-4c95-a696-a59601b3db9f)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-7] SYSTEM: start of BackendCommandHandler succeeded (2b45f7a3-247e-4059-a89d-0b535aa4507f)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of LegacyNotificationHandler succeeded (28a50bb5-d356-4d3a-a9ba-2b5c7a15c22b)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of LegacyAPIWorker succeeded (2f3983ac-0236-48ee-94ca-4afa74d3fe60)
Oct 30 17:57:09 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of LocalServerAdapterInitialization succeeded (bff61a05-dcb9-4e52-bd89-b32c6337a3da)
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter state 1: HMIP_TRX_App
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter application is already running or started
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] No NWK, try to set address ...
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to set radio address 11759593...
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set max send attempts for 3014F711A061A7D8A991837E to 3
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to get application version...
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Application version 2.8.6
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Bootloader version 1.0.3
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] hmos version 1.20.3
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] MCU type: Si1002_8051
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Duty Cycle: 0.0
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] set DutyCycle limit to ffffffc8
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set Duty Cycle Limit
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Current Security Counter: 1233152
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Update security counter to calculation: 1233217
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter has 5 link partners
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Adapter with Access Point id 3014F711A061A7D8A991837E initialized
Oct 30 17:57:09 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO  [Thread-6] HMIPTRXInitialResponseListener said that Adapter was initialized
Oct 30 17:57:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LegacyInitializion succeeded (b25783dc-79fd-4e9c-af02-6fbb93a6e210)
Oct 30 17:57:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of LocalServerFirmwareUpdateInitialization succeeded (b0996df2-a13b-469a-ad96-f5271a85e349)
Oct 30 17:57:10 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem WARN  [vert.x-worker-thread-4] SYSTEM: Firmware update directory in config file is no valid directory: /etc/config/firmware
Oct 30 17:57:10 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: Checking all devices on all accesspoints for updates
Oct 30 17:57:10 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: There are 0 APs queued with updatable devices
Oct 30 17:57:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: initial deployment complete _____________________________________________________
Oct 30 17:57:11 de.eq3.cbcs.server.local.LocalServer INFO  [Thread-0] SYSTEM: Bind XML-RPC api to port 2010
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default MaxEventLoopExecuteTime: 2000000000
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default BlockedThreadCheckInterval: 1000
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default MaxWorkerExecuteTime: 60000000000
Oct 30 17:57:12 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Default EventLoopPoolSize: 8
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [BackendWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [RegaClientWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [HmIPGatewayManagementRequestWorker] (1) *worker
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: deploying 10 classes to Vert.x
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: 10 VertxDeployers initialized
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of BackendWorker succeeded (470aad65-c789-4664-b5ac-5013a913db9c)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of EnergyPriceRequestWorker succeeded (8ccaba3b-3b14-4470-be9c-3cde4cf1b83b)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of RegaClientWorker succeeded (bc04469b-d200-4da1-8e9b-8d0ad24522ae)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of CouplingRequestWorker succeeded (fa5fcbd2-a54a-453e-ab78-bf35a672d3d4)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of StorageRequestWorker succeeded (1747b896-c895-47e4-af6d-371ffe70d3d4)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (169d7d95-0a67-40a4-a8f7-e4129f8a605c)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of DiagramRequestWorker succeeded (193e8da8-99e6-4106-ae00-649f076387a9)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of GroupRequestWorker succeeded (18b2682a-0271-4d3e-9fde-178e96b1d033)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (50c0c1d3-a5b1-4d45-be4e-89f65005c7b5)
Oct 30 17:57:12 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of HmIPGatewayManagementRequestWorker succeeded (1b13f4ef-b033-43c3-b564-6068a4fa65ee)
Oct 30 17:57:13 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: initial deployment complete _____________________________________________________
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Starting HMServer at 127.0.0.1:9292
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Read Configuration
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create Bidcos Dispatcher
Oct 30 17:57:13 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] InitBidCosCache
Oct 30 17:57:14 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create groupDefinitionProvider
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create VirtualDeviceHolder
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create VirtualDeviceHandlerRega
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupAdministrationService
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupDeviceDispatcher
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create GroupDeviceHandler
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] @GroupDeviceHandler - initializing...
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher))
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created virtualDeviceHandler (GroupDeviceService to ReGa)
Oct 30 17:57:15 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> got groupDefinitionProvider
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create BidCosGroupMemberProvider
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init groupAdministrationService
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init Virtual OS Device
Oct 30 17:57:15 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init ESHLight Bridge
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create RrdDatalogging
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create MeasurementService
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Init MeasurementService
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create HTTP Server
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create BidCos context and start handler
Oct 30 17:57:16 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Create group context and start handler
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:9292/bidcos
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] init finished
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-1] Added InterfaceId: HmIP-RF_java
Oct 30 17:57:16 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO  [vert.x-worker-thread-1] updateDevicesForClient HmIP-RF_java -> 39 device addresses will be added
Oct 30 17:57:17 de.eq3.ccu.server.BaseHMServer INFO  [Thread-2] Starting HMServer done
Oct 30 17:57:27 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:1999
Oct 30 17:57:27 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] Added InterfaceId: 1008
Oct 30 17:57:27 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-11] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
Oct 30 17:57:37 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-3] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:1999
Oct 30 17:57:37 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-3] init finished
Oct 30 17:57:37 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-3] Added InterfaceId: 1009
Oct 30 18:02:08 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: 0 Accesspoints in Queue
Oct 30 18:02:08 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
Oct 30 18:02:08 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-5] SYSTEM: Eventlistener Handler utilization: 0/50 used
Ich würde mich riesig freuen wenn mir jemand bei der Fehleranalyse helfen könnte! Vielen Dank!
Nach oben
Nitram
Beiträge: 24
Registriert: 15.10.2018, 10:48

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von Nitram » 30.10.2018, 18:35

Achso noch kurz zur Info, sonst läuft noch Homebridge und ioBroker auf dem PI. Bisher wurden nur vier Geräte angelernt und es laufen auch noch keine Programme auf der CCU.
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von deimos » 30.10.2018, 23:04

Hi,

das Mitsenden der Logausgaben ist schonmal vorbildlich.
Das Log zeigt schonmal, dass das Funkmodul korrekt erkannt wurde und dass das System komplett korrekt hochfahren konnte.
Laut dem Log kommt es zum Timeout bei der Kommunikation zum Thermostat. Grundsätzlich gibt es da mehrere Möglichkeiten:
- Funkmodul oder Antenne defekt: Bei dir eher unwahrscheinlich, sonst hättest du die Geräte nicht anmelden können.
- Keine ausreichende "Sicht" zum Thermostat
- Thermostat ohne Strom/Batterien
- Thermostat im Bootloader gefangen
- DutyCycle überschritten
- Störsender in der Umgebung, welche Übertragung verhindern (HmIP hat Listen before Talk)

Damit man jetzt etwas weiter eingrenzen kann, müsstest du mal etwas genauer deine Situation beschreiben.

Viele Grüße
Alex
Nach oben
Nitram
Beiträge: 24
Registriert: 15.10.2018, 10:48

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von Nitram » 31.10.2018, 08:46

Hallo Alex,

vielen Dank für deine Hilfe. Bitte entschuldige, dass ich mein System nicht sofort umfassend beschrieben habe.

- Funkmodul oder Antenne defekt: Bei dir eher unwahrscheinlich, sonst hättest du die Geräte nicht anmelden können.
Irgendwie scheint das Problem zeitlich beschränkt aufzutreten. Betroffen ist dann aber immer die gesamte Kommunikation, das heißt dann geht irgendwie kein Kommando zu keinem Gerät mehr durch. Gestern gegen 22 Uhr konnte ich dann aber plötzlich ohne Probleme zwei weitere Wandthermostate (habe FBH deshalb direkt mit 230V Stellantrieb) anlernen und konfigurieren. Auch andere Geräte ließen sich problemlos ansprechen. Heute morgen sah es auch erstmal gut aus.

- Keine ausreichende "Sicht" zum Thermostat
Habe eine 100m² DG Wohnung mit fast nur Gipswänden und der Schnitt ist recht quadratisch, das heißt mehr als 10m sind nicht zu überwinden.
Habe aber zusätzlich im Mittelpunkt der Wohnung eine Homematic IP Schalt-Mess-Steckdose, 140666A0, da ich nur HM IP verwende sollte diese doch als Verstärker fungieren?
Das Ding ist, ich habe das System bei mir auf Arbeit eingerichtet wegen dem guten Lötplatz und hatte nur die Steckdose und einen Fenstersensor im Probebetrieb (wenige Zentimeter von der Basis entfernt), trotzdem gab es auch hier zeitlich Probleme.

- Thermostat ohne Strom/Batterien
- Thermostat im Bootloader gefangen
Kein Stromproblem und wahrscheinlich auch keine Bootschleife, betrifft ja alle Geräte und die Thermostate hängen eh direkt an 230V Versorgung.

- DutyCycle überschritten
Da hatte ich direkt bei Einrichtung auch vermutet, das System lief aber Gestern Abend als ich nach Hause kam schon über 24h und trotzdem ging nix mehr. Es gibt wie gesagt bisher auch noch keine Programme, ich habe nur die Geräte eingerichtet, diese haben auch kein Firmwareupdate bekommen.

- Störsender in der Umgebung, welche Übertragung verhindern (HmIP hat Listen before Talk)
Das hatte ich bei mir auf Arbeit als Auslöser auch vermutet aber zu Hause tritt das selbe Problem auf.

Was mich auch wunderte, ich konnte mit dem reinen Image (Raspbian_Stretch_Lite_piVCCU3_2018-09-26) keine Geräte anlernen. Als ich auf "Geräte anlernen" ging, wurde die Seite scheinbar nicht komplett geladen. Es gab keine Auswahl HM IP oder HM Geräte jetzt anzulernen. Erst als ich das piVCCU3 Package manuell runtergeschmissen und neu vom Server geladen habe ging es plötzlich. Vielleicht macht dann auch meine SD Karte Probleme? Aber die Timeout Fehlermeldung wäre darauf ja wahrscheinlich nicht zurückzuführen.
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von deimos » 31.10.2018, 08:56

Hi,

so wie du es beschreibst, würde ich auf ein Problem mit dem DutyCycle tippen, grade in Verbindung mit der HMIP-PSM. Wenn die schlecht konfiguriert ist, dann sendet die jede Menge quittungspflichtige Nachrichten, was den DutyCycle hochtreibt. Das Thema ist hier im Forum leider nicht unbekannt.
Dazu kommt dann, dass die Thermostate einen Wake-Up Burst brauchen, welcher den DutyCycle bei jeder Kommunikation hochtreibt. Durch das Routing bei HmIP kann es aber sein, dass das DutyCycle Problem nicht auf der CCU zu suchen ist, sondern bei der HMIP-PSM, welche die Nachrichten weiterleitet und dann ja auch noch eigene Nachrichten hat.

Mit der SD Karte hat das sicher nichts zu tun, das sind Timeouts bei der Funkkommunikation.

Viele Grüße
Alex
Nach oben
Nitram
Beiträge: 24
Registriert: 15.10.2018, 10:48

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von Nitram » 31.10.2018, 09:25

Hallo Alex,

ich habe mich noch nicht so viel mit der Steckdose und dem damit verbundenen Routing beschäftigt. Wenn ich das aber richtig im Forum lese, muss dieses auch erst aktiviert werden?
Wenn dem so ist, dann dürfte das bei mir noch ausgeschaltet sein.

Puuh, dachte irgendwie auch nicht, dass ich mit meinem System von nun 5 Wandthermostaten, 1 Steckdose und einem Fenstersensor und ohne jegliche Programme bereits Probleme mit dem DutyCycle bekommen könnte. :?
Nach oben
Benutzeravatar
deimos
Beiträge: 5396
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 957 Mal
Kontaktdaten:
Kontaktdaten von deimos

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von deimos » 31.10.2018, 09:34

Hi,

erstmal ist das ja nur eine Mutmaßung von mir, die noch bestätigt oder widerlegt werden muss. Richtig konfiguriert sollte das auch kein Problem sein. Aber wenn die Steckdose z.B. bei jeder Spannungsveränderung von 0.1V senden muss, dann kommt da viel zusammen und regelmäßig braucht man es nicht so genau.

Viele Grüße
Alex
Nach oben
Nitram
Beiträge: 24
Registriert: 15.10.2018, 10:48

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von Nitram » 31.10.2018, 09:41

Hallo Alex,

die Steckdose ist noch frei, da hängt also noch kein Stromabnehmer dran.
Ich werde mich mal mit der Aufzeichnung des DC beschäftigen.

Was könnte ich noch tun wenn es nicht am DC liegt?

Grüße,
Martin
Nach oben
rbeudel
Beiträge: 278
Registriert: 29.06.2018, 17:37
System: Alternative CCU (auf Basis OCCU)
Wohnort: Essen
Hat sich bedankt: 27 Mal
Danksagung erhalten: 14 Mal

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von rbeudel » 31.10.2018, 12:00

Hallo,
auch wenn es nicht wahrscheinlich ist. Wenn ein DVB-T Stick zur Verfügung steht mal mit einem sdr Programm (sdr# oder bei mir läuft hdrsdr) die Funkfrequenz scannen. Auch eines der eigenen Geräte kann theoretisch stören. Mit dem Programm https://github.com/hobbyquaker/homematic-manager von hobbyquaker die RSSI Werte der HMIP Geräte anschauen.
Auch wenn es dafür keine gesicherten Erkenntnisse gibt, erst einmal ein sauberes Stretch lite ohne viel Schnickschnack aufsetzen und piVCCU3 wie von Alex beschrieben https://github.com/alexreinert/piVCCU/b ... berrypi.md installieren.
Auch bei mir häuften sich die Kommunikationsstörungen. Jetzt habe ich von einem zugemüllten Stretch auf ein sauberes lite umgestellt und seitdem keine einzige Störung mehr bekommen.(mehrere Tage,klopfe auf Holz). Ausserdem läuft nur noch openhab drauf. Das Java habe ich von Oracle(webupd8), sonst ist alles Original. Schon mehrere male ist mir aufgefallen, das nach einem Update weniger Störungen auftraten, als wenn irgentetwas zugemüllt war. Wie gesagt, das ist nur ein Bauchgefühl(aber der ist sehr groß :D )
Viel Glück bei der Suche :idea:
Viele Grüße,
Ralf
Viele Grüße,
Ralf

Debmatic und Openhab 4 in Proxmox 8 VM debian x86_64 auf Intel NUC mit influxdb und Grafana zur Visualisierung
Nach oben
Nitram
Beiträge: 24
Registriert: 15.10.2018, 10:48

Re: Geräte Kommunikation gestört, Neu bei piVCCU

Beitrag von Nitram » 01.11.2018, 11:02

Hier mal eine kurze Rückmeldung von mir. Ich habe nix mehr am System verändert und bisher läuft alles wie gewünscht. Anscheinend hatte ich wirklich Probleme mit dem DC beim Einrichten. Nun läuft das System durchgängig seit Dienstagabend und ich hatte keine Kommunikationsstörungen mehr.

Ich klopfe auf Holz und hoffe es bleibt dabei.
Vielen Dank nochmal für die kompetente Hilfe hier im Forum.
Nach oben
Antworten

Zurück zu „piVCCU“