ich habe gestern ein Update gemacht auf die 3.75.7-89, vorher lief die 3.65.11. Ich betreibe piVCCU auf einem Raspi mit FHEM (mit HMCCU) welches auf einem anderen Raspi läuft. Das hat auch bis zu dem update super funktioniert. Nun bekomme ich zu den Heizthermostaten keine Verbindung mehr- weder wird der aktuelle Status gezeigt noch kann ich die Heizung steuern. Auch anlernen geht nicht.Schaltsteckdosen funktionieren. Das piVCCU Log sieht so aus:
Ich bin für jede Hilfe dankbar.... Viele Grüße***** messages *****
Apr 21 14:45:23 piVCCU syslog.info syslogd started: BusyBox v1.29.2
Apr 21 14:45:23 piVCCU user.notice kernel: klogd started: BusyBox v1.29.2 (2024-04-03 13:52:57 CEST)
Apr 21 14:45:23 piVCCU syslog.info syslogd exiting
Apr 21 14:45:23 ccu3-webui syslog.info syslogd started: BusyBox v1.29.2
Apr 21 14:45:23 ccu3-webui user.notice kernel: klogd started: BusyBox v1.29.2 (2024-04-03 13:52:57 CEST)
Apr 21 14:45:23 ccu3-webui daemon.err udhcpc[185]: started, v1.29.2
Apr 21 14:45:23 ccu3-webui daemon.err udhcpc[185]: sending discover
Apr 21 14:45:23 ccu3-webui daemon.err udhcpc[185]: sending select for 192.168.155.3
Apr 21 14:45:23 ccu3-webui daemon.err udhcpc[185]: lease of 192.168.155.3 obtained, lease time 864000
Apr 21 14:45:24 ccu3-webui user.info firewall: iptables v1.6.2: invalid port/service `310000' specified Try `iptables -h' or 'iptables --help' for more information.
Apr 21 14:45:24 ccu3-webui user.info firewall: ip6tables v1.6.2: invalid port/service `310000' specified Try `ip6tables -h' or 'ip6tables --help' for more information.
Apr 21 14:45:24 ccu3-webui user.info firewall: configuration set
Apr 21 14:45:24 ccu3-webui daemon.info ifplugd(eth0)[270]: ifplugd 0.28 initializing.
Apr 21 14:45:24 ccu3-webui daemon.info ifplugd(eth0)[270]: Using interface eth0/7A:D5:DD:B2:90:B7 with driver <veth> (version: 1.0)
Apr 21 14:45:24 ccu3-webui daemon.info ifplugd(eth0)[270]: Using detection mode: SIOCETHTOOL
Apr 21 14:45:24 ccu3-webui daemon.info ifplugd(eth0)[270]: Initialization complete, link beat detected.
Apr 21 14:45:24 ccu3-webui daemon.warn ifplugd(eth0)[270]: Could not open /dev/tty, cannot beep.
Apr 21 14:45:24 ccu3-webui daemon.info ifplugd(eth0)[270]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Apr 21 14:45:24 ccu3-webui daemon.warn ifplugd(eth0)[270]: client: ifup: interface eth0 already configured
Apr 21 14:45:24 ccu3-webui daemon.info ifplugd(eth0)[270]: Program executed successfully.
Apr 21 14:45:33 ccu3-webui daemon.notice ntpdate[278]: step time server 17.253.14.251 offset -0.001761 sec
Apr 21 14:45:33 ccu3-webui daemon.notice ntpd[279]: ntpd 4.2.8p12@1.3728-o Wed 03 Apr 2024 01:50:09 PM CEST (1): Starting
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[279]: 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
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: proto: precision = 0.833 usec (-20)
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: Listen and drop on 0 v6wildcard [::]:123
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: Listen normally on 2 lo 127.0.0.1:123
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: Listen normally on 3 eth0 192.168.155.3:123
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: Listen normally on 4 lo [::1]:123
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: Listening on routing socket on fd #21 for interface updates
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Apr 21 14:45:33 ccu3-webui daemon.info ntpd[281]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Apr 21 14:45:33 ccu3-webui daemon.err xinetd[298]: Unable to read included directory: /etc/config/xinetd.d [file=/etc/xinetd.conf] [line=14]
Apr 21 14:45:33 ccu3-webui daemon.crit xinetd[298]: 298 {init_services} no services. Exiting...
Apr 21 14:45:33 ccu3-webui user.info root: Updating RF Lan Gateway Coprocessor Firmware
Apr 21 14:45:33 ccu3-webui user.debug update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Apr 21 14:45:33 ccu3-webui user.info root: Updating RF Lan Gateway Firmware
Apr 21 14:45:33 ccu3-webui user.info update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Apr 21 14:47:12 ccu3-webui user.info kernel: [ 8981.080185] eq3loop: eq3loop_close_slave() mmd_hmip
Apr 21 14:47:12 ccu3-webui user.info kernel: [ 8981.093205] eq3loop: eq3loop_open_slave() mmd_hmip
Apr 21 14:47:45 ccu3-webui user.err rfd: UnifiedLanCommController::connect(): Unable to connect.
Apr 21 14:47:45 ccu3-webui user.crit rfd: CCU2LGWCommController::init(): Cannot connect to HomeMatic Lan Gateway with serial number .
Apr 21 14:48:06 ccu3-webui daemon.err ntpd[281]: bind(24) AF_INET6 2003:c6:8f1b:eb00:78d5:ddff:feb2:90b7#123 flags 0x11 failed: Cannot assign requested address
Apr 21 14:48:06 ccu3-webui daemon.err ntpd[281]: unable to create socket on eth0 (5) for 2003:c6:8f1b:eb00:78d5:ddff:feb2:90b7#123
Apr 21 14:48:06 ccu3-webui daemon.info ntpd[281]: failed to init interface for address 2003:c6:8f1b:eb00:78d5:ddff:feb2:90b7
Apr 21 14:48:08 ccu3-webui daemon.info ntpd[281]: Listen normally on 6 eth0 [2003:c6:8f1b:eb00:78d5:ddff:feb2:90b7]:123
Apr 21 14:48:09 ccu3-webui local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00089D89A24651:5","ENERGY_COUNTER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ENERGY_COUNTER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2618]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00089D89A24651:5","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1445]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Apr 21 14:48:09 ccu3-webui local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0008A0C992FDA5:5","ENERGY_COUNTER"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ENERGY_COUNTER"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2618]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"0008A0C992FDA5:5","ENERGY_COUNTER"}) [CallGetValue():iseXmlRpc.cpp:1445]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Apr 21 14:48:09 ccu3-webui local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"00089D89A24651:5","ENERGY_COUNTER_OVERFLOW"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ENERGY_COUNTER_OVERFLOW"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2618]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"00089D89A24651:5","ENERGY_COUNTER_OVERFLOW"}) [CallGetValue():iseXmlRpc.cpp:1445]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Apr 21 14:48:09 ccu3-webui local0.warn ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"0008A0C992FDA5:5","ENERGY_COUNTER_OVERFLOW"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: ENERGY_COUNTER_OVERFLOW"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2618]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1009, params: {"0008A0C992FDA5:5","ENERGY_COUNTER_OVERFLOW"}) [CallGetValue():iseXmlRpc.cpp:1445]
Apr 21 14:48:09 ccu3-webui local0.err ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
***** hmserver.log *****
2024-04-21 14:47:00,307 de.eq3.lib.util.dynamics.GenericFactory INFO [main] @GenericFactory
2024-04-21 14:47:00,352 de.eq3.lib.util.dynamics.GenericFactory INFO [main] created instance of HMServerConfiguration with parameter(s)
2024-04-21 14:47:00,352 de.eq3.lib.util.dynamics.GenericFactory INFO [main] passed 1 parameter(s), in declarative order [String]
2024-04-21 14:47:08,875 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker
2024-04-21 14:47:08,877 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [KeyServerWorker] (1) *worker
2024-04-21 14:47:08,879 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker
2024-04-21 14:47:08,880 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker
2024-04-21 14:47:08,880 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker
2024-04-21 14:47:08,881 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)
2024-04-21 14:47:08,881 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)
2024-04-21 14:47:08,881 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [TransactionManagementVerticle] (1)
2024-04-21 14:47:08,882 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [BackendCommandHandler] (1)
2024-04-21 14:47:08,883 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [StatemanagementResponseHandler] (1)
2024-04-21 14:47:08,883 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [StatemanagementRequestHandler] (1)
2024-04-21 14:47:08,884 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)
2024-04-21 14:47:08,884 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [CheckDeviceExistHandler] (1)
2024-04-21 14:47:08,884 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)
2024-04-21 14:47:08,885 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)
2024-04-21 14:47:08,885 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceLiveUpdateSubsystem] (1)
2024-04-21 14:47:08,885 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DeviceDefaultConfigurationChanger] (1)
2024-04-21 14:47:08,886 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)
2024-04-21 14:47:08,886 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [DisplayUpdateStatusHandler] (1)
2024-04-21 14:47:08,886 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)
2024-04-21 14:47:08,899 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker
2024-04-21 14:47:08,899 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker
2024-04-21 14:47:08,900 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker
2024-04-21 14:47:08,900 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker
2024-04-21 14:47:08,900 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: added for deployment [LegacyInitializion] (1)
2024-04-21 14:47:08,916 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: deploying 25 classes to Vert.x
2024-04-21 14:47:09,065 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: 25 VertxDeployers initialized
2024-04-21 14:47:09,195 de.eq3.cbcs.server.core.vertx.CheckDeviceExistHandler INFO [vert.x-eventloop-thread-7] SYSTEM: CheckDeviceExistHandler Verticle or Worker started
2024-04-21 14:47:09,195 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBlindLevelCorrectionHandler INFO [vert.x-worker-thread-4] SYSTEM: LegacyBlindLevelCorrectionHandler Verticle or Worker started
2024-04-21 14:47:09,195 de.eq3.cbcs.server.core.vertx.DisplayUpdateStatusHandler INFO [vert.x-eventloop-thread-3] SYSTEM: DisplayUpdateStatusHandler Verticle or Worker started
2024-04-21 14:47:09,195 de.eq3.cbcs.server.core.vertx.DeviceDefaultConfigurationChanger INFO [vert.x-eventloop-thread-4] SYSTEM: DeviceDefaultConfigurationChanger Verticle or Worker started
2024-04-21 14:47:09,197 de.eq3.cbcs.server.core.vertx.HMIPAbstractWriterWorker INFO [vert.x-worker-thread-2] SYSTEM: HMIPTRXWriterWorker Verticle or Worker started
2024-04-21 14:47:09,194 de.eq3.cbcs.statemanagement.StatemanagementRequestHandler INFO [vert.x-eventloop-thread-6] SYSTEM: StatemanagementRequestHandler Verticle or Worker started
2024-04-21 14:47:09,220 de.eq3.cbcs.server.core.vertx.KeyServerWorker WARN [vert.x-worker-thread-1] Missing or invalid key server configuration parameter (Network.Key / Network.Key.Base) for mode: KEYSERVER_LOCAL
2024-04-21 14:47:09,222 de.eq3.cbcs.server.core.vertx.KeyServerWorker INFO [vert.x-worker-thread-1] SYSTEM: KeyServerWorker Verticle or Worker started
2024-04-21 14:47:09,222 de.eq3.cbcs.server.core.vertx.TransactionSubsystemHandler INFO [vert.x-worker-thread-0] SYSTEM: TransactionSubsystemHandler Verticle or Worker started
2024-04-21 14:47:09,261 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-3] SYSTEM: start of DisplayUpdateStatusHandler succeeded (de5207c5-6535-474f-ab09-869ee3a55244)
2024-04-21 14:47:09,261 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-6] SYSTEM: start of StatemanagementRequestHandler succeeded (3e1ac113-1dc7-467f-a605-df306b6a6645)
2024-04-21 14:47:09,276 de.eq3.cbcs.server.core.vertx.CyclicSmokeDetectorAwakening INFO [vert.x-eventloop-thread-3] SYSTEM: CyclicSmokeDetectorAwakening Verticle or Worker started
2024-04-21 14:47:09,275 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of CheckDeviceExistHandler succeeded (fde8ce0e-9daa-4a3f-95c9-11d98f58249b)
2024-04-21 14:47:09,281 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-3] SYSTEM: start of TransactionSubsystemHandler succeeded (17924e40-f3f1-4bea-acc9-961ebc22e0bb)
2024-04-21 14:47:09,277 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-0] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (5db5e5a7-8999-47a6-a83b-8f7b1b47df41)
2024-04-21 14:47:09,377 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of KryoPersistenceWorker succeeded (d6f07b4b-1a79-4e01-963e-f089deb897cc)
2024-04-21 14:47:09,402 de.eq3.cbcs.server.local.base.internal.LocalServerPersistentDataLoader INFO [vert.x-eventloop-thread-6] SYSTEM: LocalServerPersistentDataLoader Verticle or Worker started
2024-04-21 14:47:09,407 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-3] SYSTEM: start of LocalServerPersistentDataLoader succeeded (9252b5bf-83e5-4e3b-9bcc-8ade542d13cf)
2024-04-21 14:47:10,123 de.eq3.cbcs.server.core.otau.AbstractUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: DeviceBackgroundUpdateSubsystem Verticle or Worker started
2024-04-21 14:47:10,123 de.eq3.cbcs.server.core.otau.AbstractUpdateSubsystem INFO [vert.x-eventloop-thread-1] SYSTEM: DeviceLiveUpdateSubsystem Verticle or Worker started
2024-04-21 14:47:10,125 de.eq3.cbcs.server.core.live_otau.DeviceLiveUpdateSubsystem INFO [vert.x-eventloop-thread-1] SYSTEM: DeviceLiveUpdateSubsystem started
2024-04-21 14:47:10,151 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-0] SYSTEM: start of DeviceLiveUpdateSubsystem succeeded (38f85f31-ffdb-4e4c-8adc-c2dabab29028)
2024-04-21 14:47:10,151 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of HMIPTRXWriterWorker succeeded (91858a88-c35c-485e-b841-d8744d73d62a)
2024-04-21 14:47:10,155 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (8cc15f6e-4693-47b6-baa8-d8a34598c395)
2024-04-21 14:47:10,155 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of DeviceDefaultConfigurationChanger succeeded (e4a6d1a9-80ec-4b9c-84ca-5b39d8d74a15)
2024-04-21 14:47:10,156 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of KeyServerWorker succeeded (cb11e955-7a2f-44dd-a60b-51cd1cd46443)
2024-04-21 14:47:10,157 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (813162d8-adc3-44c5-8b5d-2f2fb7681065)
2024-04-21 14:47:11,992 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO [vert.x-worker-thread-0] SYSTEM: Firmware update directory is set to /etc/config/firmware
2024-04-21 14:47:12,026 de.eq3.cbcs.server.core.vertx.IncomingHMIPFrameHandler INFO [vert.x-eventloop-thread-3] SYSTEM: IncomingHMIPFrameHandler Verticle or Worker started
2024-04-21 14:47:12,031 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO [vert.x-worker-thread-0] SYSTEM: FirmwareLoaderFileSystem Verticle or Worker started
2024-04-21 14:47:12,016 de.eq3.cbcs.server.core.vertx.DeviceInclusionAcceptHandler INFO [vert.x-eventloop-thread-1] SYSTEM: DeviceInclusionAcceptHandler Verticle or Worker started
2024-04-21 14:47:12,039 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of FirmwareLoaderFileSystem succeeded (09e2a54a-6c08-4ecb-96f1-cd78a4a1f46f)
2024-04-21 14:47:12,039 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-6] SYSTEM: start of IncomingHMIPFrameHandler succeeded (e6b4395a-88ae-474b-91e0-b5557c6f6c90)
2024-04-21 14:47:12,040 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (c796735a-b351-4852-bd52-c74c1bff4c37)
2024-04-21 14:47:12,060 de.eq3.cbcs.server.core.transaction.TransactionManagementVerticle INFO [vert.x-eventloop-thread-4] SYSTEM: TransactionManagementVerticle Verticle or Worker started
2024-04-21 14:47:12,185 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO [vert.x-eventloop-thread-2] SYSTEM: LocalServerAdapterInitialization Verticle or Worker started
2024-04-21 14:47:12,188 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of LocalServerAdapterInitialization succeeded (6347c056-5c73-48fd-8247-f87ed96cbaf0)
2024-04-21 14:47:12,189 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of TransactionManagementVerticle succeeded (4b89867b-1a94-4c91-8b72-966123a37706)
2024-04-21 14:47:12,220 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] TRX adapter state 1: HMIP_TRX_App
2024-04-21 14:47:12,221 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] TRX adapter application is already running or started
2024-04-21 14:47:12,222 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] No NWK, try to set address ...
2024-04-21 14:47:12,232 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Try to set radio address 11796380...
2024-04-21 14:47:12,273 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Set max send attempts for 3014F711A061A7DA498FA223 to 3
2024-04-21 14:47:12,294 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Try to get application version...
2024-04-21 14:47:12,317 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Application version 2.8.6
2024-04-21 14:47:12,319 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Bootloader version 1.0.3
2024-04-21 14:47:12,320 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] hmos version 1.20.3
2024-04-21 14:47:12,447 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] MCU type: Si1002_8051
2024-04-21 14:47:12,479 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Duty Cycle: 7.5
2024-04-21 14:47:12,480 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] set DutyCycle limit to ffffffc8
2024-04-21 14:47:12,583 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Set Duty Cycle Limit
2024-04-21 14:47:12,606 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Current Security Counter: 328890908
2024-04-21 14:47:12,660 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Update security counter to calculation: 328892808
2024-04-21 14:47:12,751 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] TRX adapter has 26 link partners
2024-04-21 14:47:12,791 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO [RXTXPortMonitor(/dev/mmd_hmip)] Adapter with Access Point id 3014F711A061A7DA498FA223 initialized
2024-04-21 14:47:12,798 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO [RXTXPortMonitor(/dev/mmd_hmip)] HMIPTRXInitialResponseListener said that Adapter was initialized
2024-04-21 14:47:13,514 de.eq3.cbcs.server.core.vertx.StatemanagementResponseHandler INFO [vert.x-eventloop-thread-7] SYSTEM: StatemanagementResponseHandler Verticle or Worker started
2024-04-21 14:47:13,518 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-0] SYSTEM: start of StatemanagementResponseHandler succeeded (7cc5215e-05f2-4dc0-b814-9e7f26821441)
2024-04-21 14:47:13,536 de.eq3.cbcs.server.core.vertx.BackendCommandHandler INFO [vert.x-eventloop-thread-6] SYSTEM: BackendCommandHandler Verticle or Worker started
2024-04-21 14:47:13,539 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of BackendCommandHandler succeeded (3f73de8d-306b-46ef-bf0f-865d347a7acb)
2024-04-21 14:47:13,570 de.eq3.cbcs.server.local.base.internal.LocalServerFirmwareUpdateInitialization INFO [vert.x-eventloop-thread-2] SYSTEM: LocalServerFirmwareUpdateInitialization Verticle or Worker started
2024-04-21 14:47:13,574 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of LocalServerFirmwareUpdateInitialization succeeded (dc5fbe63-b557-4a60-b4aa-3cd5578c5d69)
2024-04-21 14:47:13,616 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyNotificationHandler INFO [vert.x-worker-thread-4] SYSTEM: LegacyNotificationHandler Verticle or Worker started
2024-04-21 14:47:13,620 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of LegacyNotificationHandler succeeded (f1a1b6cf-7abc-4272-8ecb-1548080d11a7)
2024-04-21 14:47:13,623 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [vert.x-worker-thread-2] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2024-04-21 14:47:13,623 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [vert.x-worker-thread-3] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2024-04-21 14:47:13,624 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [vert.x-worker-thread-2] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2024-04-21 14:47:13,632 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-7] SYSTEM: start of LegacyBackendNotificationHandler succeeded (a6d331ed-2043-47d0-bc65-7302af9bb304)
2024-04-21 14:47:13,784 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyAPIWorker INFO [vert.x-worker-thread-1] SYSTEM: LegacyAPIWorker Verticle or Worker started
2024-04-21 14:47:13,787 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-6] SYSTEM: start of LegacyAPIWorker succeeded (6cf5d335-6b6d-4d7f-9391-165d8ef64f25)
2024-04-21 14:47:13,834 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: Checking all devices on all accesspoints for updates
2024-04-21 14:47:15,074 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyInitializion INFO [vert.x-eventloop-thread-6] SYSTEM: LegacyInitializion Verticle or Worker started
2024-04-21 14:47:15,077 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of LegacyInitializion succeeded (7ff27d8e-1879-4ddc-8e3a-ef312893df0a)
2024-04-21 14:47:15,177 de.eq3.vertx.manager.management.VertxManager INFO [Thread-0] SYSTEM: initial deployment complete _____________________________________________________
2024-04-21 14:47:15,310 de.eq3.cbcs.server.local.LocalServer INFO [Thread-0] SYSTEM: Bind XML-RPC api to port 32010
2024-04-21 14:47:15,433 de.eq3.cbcs.util.EQ3LoggerOutputStream INFO [Thread-1] std.out and std.err redirection active
2024-04-21 14:47:15,436 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Default MaxEventLoopExecuteTime: 2000000000
2024-04-21 14:47:15,437 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Default BlockedThreadCheckInterval: 1000
2024-04-21 14:47:15,439 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Default MaxWorkerExecuteTime: 60000000000
2024-04-21 14:47:15,441 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Default EventLoopPoolSize: 8
2024-04-21 14:47:15,454 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [BackendWorker] (1) *worker
2024-04-21 14:47:15,456 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker
2024-04-21 14:47:15,458 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker
2024-04-21 14:47:15,461 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker
2024-04-21 14:47:15,463 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker
2024-04-21 14:47:15,465 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker
2024-04-21 14:47:15,467 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker
2024-04-21 14:47:15,469 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [RegaClientWorker] (1) *worker
2024-04-21 14:47:15,471 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker
2024-04-21 14:47:15,565 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [AccessPointUpdateServerWorker] (1) *worker
2024-04-21 14:47:15,566 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: added for deployment [AccessPointUpdateLoaderWorker] (1) *worker
2024-04-21 14:47:15,574 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: deploying 11 classes to Vert.x
2024-04-21 14:47:15,588 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: 11 VertxDeployers initialized
2024-04-21 14:47:15,632 de.eq3.ccu.hmip.accesspoint.update.AccessPointUpdateLoaderWorker INFO [vert.x-worker-thread-10] SYSTEM: Firmware update directory is set to /etc/config/firmware
2024-04-21 14:47:15,645 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of BackendWorker succeeded (0544efa4-900d-4b31-8f94-607e2c367a54)
2024-04-21 14:47:15,656 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (e18aedaf-0dde-412a-8f29-d6e71c8ffdcb)
2024-04-21 14:47:15,658 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-5] SYSTEM: start of CouplingRequestWorker succeeded (f1893c69-b13c-472c-8e61-9f9bd6a499ba)
2024-04-21 14:47:15,664 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of AccessPointUpdateLoaderWorker succeeded (ed0c9742-1ed1-45c1-b5a5-5362aa7b264f)
2024-04-21 14:47:15,664 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-3] SYSTEM: start of StorageRequestWorker succeeded (ebe2d08b-9543-43c8-9628-30d71ac126cd)
2024-04-21 14:47:15,676 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-0] SYSTEM: start of EnergyPriceRequestWorker succeeded (71b61613-52bb-4657-a283-6c8a70c5d395)
2024-04-21 14:47:15,675 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of RegaClientWorker succeeded (aa00e987-ecf0-4f0c-bf49-abd5e39ccdb4)
2024-04-21 14:47:15,678 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-0] SYSTEM: start of AccessPointUpdateServerWorker succeeded (51203581-b535-473a-a21c-6fcb6431803f)
2024-04-21 14:47:15,772 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-2] SYSTEM: start of GroupRequestWorker succeeded (96ec81b9-72ac-4760-ae2f-efbcc568a63d)
2024-04-21 14:47:15,771 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-4] SYSTEM: start of DiagramRequestWorker succeeded (81893dfb-e324-4db2-b5bf-8e71803c0be4)
2024-04-21 14:47:15,774 de.eq3.vertx.manager.management.VertxManager INFO [vert.x-eventloop-thread-1] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (6872512f-cb03-4ba5-992b-7dbb1c2f2062)
2024-04-21 14:47:15,790 de.eq3.vertx.manager.management.VertxManager INFO [Thread-1] SYSTEM: initial deployment complete _____________________________________________________
2024-04-21 14:47:15,795 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Starting HMServer at 127.0.0.1:39292
2024-04-21 14:47:15,796 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Read Configuration
2024-04-21 14:47:16,018 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create Bidcos Dispatcher
2024-04-21 14:47:16,089 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] InitBidCosCache
2024-04-21 14:47:47,422 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create groupDefinitionProvider
2024-04-21 14:47:47,964 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create VirtualDeviceHolder
2024-04-21 14:47:47,969 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create VirtualDeviceHandlerRega
2024-04-21 14:47:47,977 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create GroupAdministrationService
2024-04-21 14:47:47,977 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create GroupDeviceDispatcher
2024-04-21 14:47:47,979 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create GroupDeviceHandler
2024-04-21 14:47:47,988 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-1] @GroupDeviceHandler - initializing...
2024-04-21 14:47:47,989 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-1] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher))
2024-04-21 14:47:47,989 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-1] --> created virtualDeviceHandler (GroupDeviceService to ReGa)
2024-04-21 14:47:47,989 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-1] --> got groupDefinitionProvider
2024-04-21 14:47:47,990 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create BidCosGroupMemberProvider
2024-04-21 14:47:48,069 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Init groupAdministrationService
2024-04-21 14:47:49,277 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Init Virtual OS Device
2024-04-21 14:47:49,284 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Init ESHLight Bridge
2024-04-21 14:47:50,028 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create RrdDatalogging
2024-04-21 14:47:50,033 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create MeasurementService
2024-04-21 14:47:50,041 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Init MeasurementService
2024-04-21 14:47:50,123 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create HTTP Server
2024-04-21 14:47:50,131 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create BidCos context and start handler
2024-04-21 14:47:50,140 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Create group context and start handler
2024-04-21 14:47:50,182 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:39292/bidcos
2024-04-21 14:47:50,195 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [HmIP-RF_java_WorkerPool-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2024-04-21 14:47:50,202 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [HmIP-RF_java_WorkerPool-0] init finished
2024-04-21 14:47:50,210 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: Checking all devices on all accesspoints for updates
2024-04-21 14:47:50,217 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-3] Added InterfaceId: HmIP-RF_java
2024-04-21 14:47:50,282 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO [vert.x-worker-thread-3] updateDevicesForClient HmIP-RF_java -> 233 device addresses will be added
2024-04-21 14:47:51,153 de.eq3.ccu.server.BaseHMServer INFO [Thread-1] Starting HMServer done
2024-04-21 14:48:09,088 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
2024-04-21 14:48:09,108 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO [vert.x-eventloop-thread-0] Added InterfaceId: 1008
2024-04-21 14:48:09,114 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO [vert.x-worker-thread-19] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
2024-04-21 14:48:09,172 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] updateDevicesForClient -> 56 device addresses will be added
2024-04-21 14:48:11,861 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 2768 ms, time limit is 2000 ms
2024-04-21 14:48:12,849 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 3768 ms, time limit is 2000 ms
2024-04-21 14:48:13,850 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 4768 ms, time limit is 2000 ms
2024-04-21 14:48:14,852 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 5768 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
at jdk.internal.misc.Unsafe.park(Native Method) ~[?:?]
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) ~[?:?]
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source) ~[?:?]
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at java.lang.reflect.Method.invoke(Unknown Source) ~[?:?]
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$158/0x6959cc28.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$157/0x6959d028.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(Unknown Source) ~[?:?]
2024-04-21 14:48:15,851 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 6768 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
at jdk.internal.misc.Unsafe.park(Native Method) ~[?:?]
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) ~[?:?]
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source) ~[?:?]
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at java.lang.reflect.Method.invoke(Unknown Source) ~[?:?]
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$158/0x6959cc28.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$157/0x6959d028.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(Unknown Source) ~[?:?]
2024-04-21 14:48:16,851 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 7768 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
at jdk.internal.misc.Unsafe.park(Native Method) ~[?:?]
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) ~[?:?]
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source) ~[?:?]
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at java.lang.reflect.Method.invoke(Unknown Source) ~[?:?]
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$158/0x6959cc28.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$157/0x6959d028.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(Unknown Source) ~[?:?]
2024-04-21 14:48:17,851 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 8768 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
at jdk.internal.misc.Unsafe.park(Native Method) ~[?:?]
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) ~[?:?]
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source) ~[?:?]
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at java.lang.reflect.Method.invoke(Unknown Source) ~[?:?]
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$158/0x6959cc28.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$157/0x6959d028.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(Unknown Source) ~[?:?]
2024-04-21 14:48:18,852 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 9768 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
at jdk.internal.misc.Unsafe.park(Native Method) ~[?:?]
at java.util.concurrent.locks.LockSupport.parkNanos(Unknown Source) ~[?:?]
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(Unknown Source) ~[?:?]
at java.util.concurrent.LinkedBlockingQueue.poll(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]
at jdk.internal.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) ~[?:?]
at java.lang.reflect.Method.invoke(Unknown Source) ~[?:?]
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$158/0x6959cc28.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$157/0x6959d028.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(Unknown Source) ~[?:?]
2024-04-21 14:48:19,136 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-4] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:31999
2024-04-21 14:48:19,149 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [1009_WorkerPool-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2024-04-21 14:48:19,155 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [1009_WorkerPool-0] init finished
2024-04-21 14:48:19,157 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-0] Added InterfaceId: 1009
2024-04-21 14:48:22,154 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000001
2024-04-21 14:48:22,227 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000004
2024-04-21 14:48:22,232 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000005
2024-04-21 14:48:22,238 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000006
2024-04-21 14:48:22,244 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000007
2024-04-21 14:48:22,249 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000010
2024-04-21 14:48:22,255 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-19] set ready config of INT0000011
2024-04-21 14:52:10,128 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: 0 Accesspoints in Queue
2024-04-21 14:52:10,128 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
2024-04-21 14:52:10,129 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: Eventlistener Handler utilization: 0/50 used
2024-04-21 14:57:10,128 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: 0 Accesspoints in Queue
2024-04-21 14:57:10,129 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: Permanent-/Burstlistener Handler utilization: 0/50 used
2024-04-21 14:57:10,130 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-2] SYSTEM: Eventlistener Handler utilization: 0/50 used
Thomas