folgendes sehe ich im Log, grad der letzte Teil sieht ja nicht wirklich normal aus
Code: Alles auswählen
2023-04-07 08:44:30,319 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Starting HMServer at 127.0.0.1:39292
2023-04-07 08:44:30,320 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Read Configuration
2023-04-07 08:44:30,655 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create Bidcos Dispatcher
2023-04-07 08:44:30,729 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] InitBidCosCache
2023-04-07 08:44:32,769 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create groupDefinitionProvider
2023-04-07 08:44:33,285 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create VirtualDeviceHolder
2023-04-07 08:44:33,297 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create VirtualDeviceHandlerRega
2023-04-07 08:44:33,305 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create GroupAdministrationService
2023-04-07 08:44:33,306 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create GroupDeviceDispatcher
2023-04-07 08:44:33,312 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create GroupDeviceHandler
2023-04-07 08:44:33,321 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-2] @GroupDeviceHandler - initializing...
2023-04-07 08:44:33,322 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-2] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher))
2023-04-07 08:44:33,322 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-2] --> created virtualDeviceHandler (GroupDeviceService to ReGa)
2023-04-07 08:44:33,322 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO [Thread-2] --> got groupDefinitionProvider
2023-04-07 08:44:33,322 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create BidCosGroupMemberProvider
2023-04-07 08:44:33,411 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Init groupAdministrationService
2023-04-07 08:44:33,416 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Init Virtual OS Device
2023-04-07 08:44:33,429 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Init ESHLight Bridge
2023-04-07 08:44:34,758 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create RrdDatalogging
2023-04-07 08:44:34,768 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create MeasurementService
2023-04-07 08:44:34,776 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Init MeasurementService
2023-04-07 08:44:34,998 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create HTTP Server
2023-04-07 08:44:35,005 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create BidCos context and start handler
2023-04-07 08:44:35,016 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Create group context and start handler
2023-04-07 08:44:35,051 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [vert.x-worker-thread-3] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:39292/bidcos
2023-04-07 08:44:35,062 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [HmIP-RF_java_WorkerPool-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2023-04-07 08:44:35,071 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [HmIP-RF_java_WorkerPool-0] init finished
2023-04-07 08:44:35,083 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-1] Added InterfaceId: HmIP-RF_java
2023-04-07 08:44:35,090 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO [vert.x-eventloop-thread-1] SYSTEM: Checking all devices on all accesspoints for updates
2023-04-07 08:44:35,131 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO [vert.x-worker-thread-1] updateDevicesForClient HmIP-RF_java -> 54 device addresses will be added
2023-04-07 08:44:35,526 de.eq3.cbcs.server.core.framehandling.HMIPApplicationHandler WARN [vert.x-eventloop-thread-6] AP 3014F711A0001F98A99A3EDB: Frame HMIP_APP_STATUS received from Device with unknown address 8081BF
2023-04-07 08:44:36,032 de.eq3.ccu.server.BaseHMServer INFO [Thread-2] Starting HMServer done
2023-04-07 08:44:44,780 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
2023-04-07 08:44:44,793 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO [vert.x-eventloop-thread-0] Added InterfaceId: 1236
2023-04-07 08:44:44,798 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO [vert.x-worker-thread-12] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
2023-04-07 08:44:44,855 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO [vert.x-worker-thread-12] updateDevicesForClient -> 1 device addresses will be deleted
2023-04-07 08:44:45,550 de.eq3.cbcs.server.core.framehandling.HMIPApplicationHandler WARN [vert.x-eventloop-thread-6] AP 3014F711A0001F98A99A3EDB: Frame HMIP_APP_STATUS received from Device with unknown address 8081BF
2023-04-07 08:44:46,092 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:9875
2023-04-07 08:44:46,118 de.eq3.cbcs.legacy.bidcos.rpc.internal.LegacyBackendNotificationHandler INFO [HAP_HmIP-RF._WorkerPool-0] SYSTEM: LegacyBackendNotificationHandler Verticle or Worker started
2023-04-07 08:44:46,130 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO [HAP_HmIP-RF._WorkerPool-0] init finished
2023-04-07 08:44:46,145 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO [vert.x-worker-thread-3] Added InterfaceId: HAP_HmIP-RF.
2023-04-07 08:44:47,064 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 2276 ms, time limit is 2000 ms
2023-04-07 08:44:48,055 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 3276 ms, time limit is 2000 ms
2023-04-07 08:44:49,055 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 4276 ms, time limit is 2000 ms
2023-04-07 08:44:50,057 io.vertx.core.impl.BlockedThreadChecker WARN [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-0,5,main] has been blocked for 5276 ms, time limit is 2000 ms
io.vertx.core.VertxException: Thread blocked
at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_202]
at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_202]
at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078) ~[?:1.8.0_202]
at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_202]
at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMIPServer.jar:?]
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_202]
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_202]
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_202]
at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_202]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMIPServer.jar:?]
at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMIPServer.jar:?]
at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMIPServer.jar:?]
at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMIPServer.jar:?]
at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMIPServer.jar:?]
at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMIPServer.jar:?]
at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMIPServer.jar:?]
at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMIPServer.jar:?]
at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$84/16022160.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$83/11956399.run(Unknown Source) ~[?:?]
at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMIPServer.jar:?]
at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMIPServer.jar:?]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMIPServer.jar:?]
at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMIPServer.jar:?]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMIPServer.jar:?]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
at java.lang.Thread.run(Thread.java:748) ~[?:1.8.0_202]