debmatic Update 3.69.7-107

Debian/Ubuntu basierte CCU

Moderator: Co-Administratoren

Benutzeravatar
deimos
Beiträge: 5383
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 950 Mal
Kontaktdaten:

debmatic Update 3.69.7-107

Beitrag von deimos » 03.05.2023, 17:21

Hi,

ich habe im testing APT Repository eine neue Version bereitgestellt und würde Mutige bitten, diese zu testen.

An der CCU Software hat sich nichts getan, allerdings habe ich unter der Haube ein paar Anpassungen gemacht:

Es wird nicht mehr zwingend Java 8 oder 11 verwendet, sondern jetzt auch Java 17, 18 und 19 unterstützt. Der HMServer ist dadurch spürbar schneller geworden und damit sind dann auch die Weichen gestellt für Debian bookworm, welches kein Java 8 und 11 mehr haben wird. Bis vor einigen CCU Versionen war dies noch nicht möglich, da ist der HMServer direkt beim Starten mit höheren Java Versionen direkt ausgestiegen, aber meine aktuellen Tests sehe extrem vielversprechend aus.

Der Zugriff auf die XMLRPC Schnittstellen ist jetzt nur noch möglich, wenn debmatic komplett gestartet ist. Dies verhindert eine Art DoS, welcher von externen Systemen wie IOBroker oder openHab beim Start vorhanden war, weil die über die XMLRPC Schnittstelle geschickt haben, die aber noch nicht beantwortet haben und dadurch dann die für den Start wichtigen Requests der Rega blockiert haben.

Statische JS, CSS und Image Request in der WebUI werden jetzt ohne weitere internen Proxyrequest direkt beantwortet.

Beim Start wird das Funkmodul jetzt nicht mehr explizit resettet, sondern implizit durch detect_radio_module bei der Erkennung. Gleichzeit erkennt detect_radio_module jetzt, ob es exklussiven Zugrriff auf das Funkmodul hat, was Probleme verhindert, wenn man das Tools aufruft, obwohl debmatic noch gestartet ist.

Vermutlich heute abend wird noch ein weiteres Update kommen, in welchem ich das APT Repository auf eine URL umziehe als Vorbereitung für einige Dinge im Hintergrund bei den APT Repositories.

Viele Grüße
Alex

Benutzeravatar
hm247
Beiträge: 22
Registriert: 11.02.2023, 13:24
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 1 Mal
Danksagung erhalten: 2 Mal

Re: debmatic Update 3.69.7-107

Beitrag von hm247 » 03.05.2023, 18:20

Hallo Alex,

das hört sich interessant an - Ich werde das später gerne mal testen mit Java 17 (LTS).

Hat man eigentlich einen Einfluss darauf, welche der drei Java Versionen letztendlich von debmatic verwendet wird ?

Viele Grüße

Benutzeravatar
deimos
Beiträge: 5383
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 950 Mal
Kontaktdaten:

Re: debmatic Update 3.69.7-107

Beitrag von deimos » 03.05.2023, 18:45

Hi,

es wird die höchste installierte genommen. Den System Default kann ich an der Stelle leider nicht nehmen, weil dann theoretisch auch eine noch höhere Version genutzt werden würde, die ungetestet und ggf. inkompatibel wäre.

Viele Grüße
Alex

Benutzeravatar
hm247
Beiträge: 22
Registriert: 11.02.2023, 13:24
System: Alternative CCU (auf Basis OCCU)
Hat sich bedankt: 1 Mal
Danksagung erhalten: 2 Mal

Re: debmatic Update 3.69.7-107

Beitrag von hm247 » 06.05.2023, 15:37

Hallo Alex,

debmatic 3.69.7-108 läuft prima mit java 17 unter debian bullseye (arm64).

Viele Grüße

P.S.: Für alle, die es ausprobieren möchten: unter debian ist der Wechsel auf Java 17 schnell erledigt:

Code: Alles auswählen

apt update 
apt-cache search openjdk | grep 17
apt install openjdk-17-jre
systemctl restart debmatic

Code: Alles auswählen

# cat /var/log/syslog
May  6 16:22:23 hm systemd[1]: Starting piVCCU DKMS Modules...
May  6 16:22:23 hm systemd[1]: pivccu-dkms.service: Succeeded.
May  6 16:22:23 hm systemd[1]: Finished piVCCU DKMS Modules.
May  6 16:22:23 hm systemd[1]: Starting debmatic...
May  6 16:22:23 hm kernel: [ 4728.588669] raw-uart raw-uart: Reset radio module
May  6 16:22:23 hm kernel: [ 4728.694614] raw-uart raw-uart: Registered new raw-uart device using underlying device HB-RF-ETH@-.
May  6 16:22:23 hm kernel: [ 4728.694695] hb-rf-eth hb-rf-eth: failed creating connect sysfs file: 0
May  6 16:22:23 hm kernel: [ 4728.720286] hb-rf-eth hb-rf-eth: Trying to connect to xx.xx.xx.xx
May  6 16:22:23 hm kernel: [ 4728.830598] hb-rf-eth hb-rf-eth: Successfully connected to xx.xx.xx.xx
May  6 16:22:23 hm kernel: [ 4728.958618] raw-uart raw-uart: Reset radio module
May  6 16:22:26 hm kernel: [ 4731.390448] raw-uart raw-uart: Reset radio module
May  6 16:22:28 hm systemd[1]: Finished debmatic.
May  6 16:22:28 hm systemd[1]: Starting debmatic eq3configd...
May  6 16:22:28 hm systemd[1]: Starting debmatic HssLED...
May  6 16:22:28 hm systemd[1]: Starting debmatic prepare InterfacesList.xml...
May  6 16:22:28 hm systemd[1]: Starting debmatic ssdpd...
May  6 16:22:28 hm systemd[1]: Starting debmatic update rf firmware...
May  6 16:22:28 hm systemd[1]: Started debmatic HssLED.
May  6 16:22:28 hm systemd[1]: Finished debmatic prepare InterfacesList.xml.
May  6 16:22:28 hm systemd[1]: Starting debmatic lighttpd...
May  6 16:22:28 hm systemd[1]: Starting debmatic update lgw firmware...
May  6 16:22:28 hm systemd[1]: Started debmatic eq3configd.
May  6 16:22:28 hm systemd[1]: Started debmatic ssdpd.
May  6 16:22:28 hm update_rf_firmware.sh[7308]: RPI-RF-MOD has already desired version 4.4.22...
May  6 16:22:28 hm systemd[1]: Finished debmatic update rf firmware.
May  6 16:22:28 hm systemd[1]: Starting debmatic multimacd...
May  6 16:22:28 hm lighttpd[7362]: (server.c.1513) server started (lighttpd/1.4.59)
May  6 16:22:28 hm systemd[1]: Started debmatic lighttpd.
May  6 16:22:28 hm systemd[1]: Condition check resulted in debmatic hs485d being skipped.
May  6 16:22:28 hm start_multimacd.sh[7364]: kernel.sched_rt_runtime_us = -1
May  6 16:22:28 hm systemd[1]: Finished debmatic update lgw firmware.
May  6 16:22:28 hm systemd[1]: Starting debmatic update lgw key...
May  6 16:22:28 hm start_multimacd.sh[7369]: Daemonizing to background
May  6 16:22:28 hm update_lgw_key.sh[7379]: ls: cannot access '/etc/config/*.keychange': No such file or directory
May  6 16:22:28 hm systemd[1]: Finished debmatic update lgw key.
May  6 16:22:28 hm kernel: [ 4733.427436] eq3loop: created slave mmd_hmip
May  6 16:22:28 hm kernel: [ 4733.428136] eq3loop: created slave mmd_bidcos
May  6 16:22:32 hm systemd[1]: Started debmatic multimacd.
May  6 16:22:32 hm systemd[1]: Starting debmatic rfd...
May  6 16:22:32 hm start_rfd.sh[7407]: Daemonizing to background
May  6 16:22:32 hm kernel: [ 4737.563590] eq3loop: eq3loop_open_slave() mmd_bidcos
May  6 16:22:34 hm systemd[1]: Started debmatic rfd.
May  6 16:22:34 hm systemd[1]: Starting debmatic hmserver...
May  6 16:22:34 hm start_hmserver.sh[7442]: WARNING: sun.reflect.Reflection.getCallerClass is not supported. This will impact performance.
May  6 16:22:36 hm start_hmserver.sh[7442]: Init Hardware Info
May  6 16:22:44 hm start_hmserver.sh[7442]: RXTX Warning:  Removing stale lock file. /var/lock/LCK..mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.239048] eq3loop: eq3loop_open_slave() mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.239170] eq3loop: eq3loop_close_slave() mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.240901] eq3loop: eq3loop_open_slave() mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.240996] eq3loop: eq3loop_close_slave() mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.241420] eq3loop: eq3loop_open_slave() mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.241519] eq3loop: eq3loop_close_slave() mmd_hmip
May  6 16:22:44 hm kernel: [ 4749.245856] eq3loop: eq3loop_open_slave() mmd_hmip
May  6 16:22:56 hm systemd[1]: Started debmatic hmserver.
May  6 16:22:56 hm systemd[1]: Starting debmatic rega...
May  6 16:22:56 hm start_rega.sh[7580]: successfully loaded "/etc/config/homematic.regadom"
May  6 16:22:56 hm start_rega.sh[7580]: Load interfaces from file
May  6 16:22:56 hm start_rega.sh[7580]: HTTP server started successfully (6 workers)
May  6 16:22:56 hm start_rega.sh[7580]: Waiting for child to finish
May  6 16:22:56 hm start_rega.sh[7592]: Executing /bin/hm_startup in child 7592
May  6 16:23:01 hm wait_sysvar_creation.tcl[7581]: Waiting for ReGa startup . Done
May  6 16:23:01 hm systemd[1]: Started debmatic rega.
May  6 16:23:01 hm systemd[1]: Starting debmatic startup finished...
May  6 16:23:01 hm systemd[1]: Finished debmatic startup finished.
May  6 16:23:01 hm systemd[1]: Started debmatic set interface clock timer.
May  6 16:23:01 hm systemd[1]: Started debmatic Monitor HB-RF-ETH.
May  6 16:23:06 hm monitor_hb_rf_connection.sh[7596]: Waiting for ReGa startup . Done
May  6 16:23:06 hm monitor_hb_rf_connection.sh[7595]: HB-RF-ETH has reconnected
May  6 16:23:08 hm start_rega.sh[7580]: ReGa entering normal operation

Benutzeravatar
deimos
Beiträge: 5383
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 950 Mal
Kontaktdaten:

Re: debmatic Update 3.69.7-107

Beitrag von deimos » 06.05.2023, 16:56

Hi,

das empfehle eher das Paket openjdk-17-jre-headless. Das installiert nicht den nicht notwendigen GUI Teil.

Viele Grüße
Alex

mikkap
Beiträge: 4
Registriert: 29.10.2017, 11:28
Hat sich bedankt: 1 Mal

Re: debmatic Update 3.69.7-107

Beitrag von mikkap » 07.05.2023, 15:52

Hallo Alex,

das Update auf die JAVA Version openjdk-17-jre-headless hat auch bei mir fehlerlos geklappt.

Super was Du hier auf die Beine stellst.

viele Grüße
Michael

pmiller
Beiträge: 79
Registriert: 04.01.2020, 08:43
System: Alternative CCU (auf Basis OCCU)
Wohnort: Baden bei Wien
Hat sich bedankt: 6 Mal
Danksagung erhalten: 4 Mal

Re: debmatic Update 3.69.7-107

Beitrag von pmiller » 07.05.2023, 17:36

Servus Deimos,

wir haben heute Vormittag die große Update-Runde gedreht. Debmatic von Buster auf Bullseye, Java auf openjdk-17-jre-headless und debmatic auf 3.69.7-108 - sieht für's erste einmal gut aus.

Liebe Grüße aus Baden bei Wien
Alexa und Peter Miller

lonsimbt
Beiträge: 33
Registriert: 20.04.2016, 13:41
Wohnort: Ruhrgebiet
Hat sich bedankt: 53 Mal
Danksagung erhalten: 2 Mal

Re: debmatic Update 3.69.7-107

Beitrag von lonsimbt » 10.05.2023, 23:01

Installation ist hier auch problemlos durchgelaufen. Keine Auffälligkeiten soweit.

Gruß Marco
debmatic in VM (debian Buster) unter proxmox 7.2 mit HB-RF-USB und RPI-RF-MOD
iobroker im Container unter proxmox 7.2
knapp 40 HomeMatic Geräte, Phillips Hue, Ikea Tradfri, Logitech Harmony Hub, Botvac Connected, Robonect

Benutzeravatar
deimos
Beiträge: 5383
Registriert: 20.06.2017, 10:38
System: Alternative CCU (auf Basis OCCU)
Wohnort: Leimersheim
Hat sich bedankt: 121 Mal
Danksagung erhalten: 950 Mal
Kontaktdaten:

Re: debmatic Update 3.69.7-107

Beitrag von deimos » 15.05.2023, 16:22

Hi,

ich habe die Version jetzt auch ins stable APT Repository übernommen.

Viele Grüße
Alex

rbeudel
Beiträge: 276
Registriert: 29.06.2018, 17:37
System: Alternative CCU (auf Basis OCCU)
Wohnort: Essen
Hat sich bedankt: 27 Mal
Danksagung erhalten: 14 Mal

Re: debmatic Update 3.69.7-107

Beitrag von rbeudel » 17.05.2023, 12:12

Leider gerade bei mir völlig schief gegangen. Keine Geräte mehr erkannt.
HM-Server Log:

Code: Alles auswählen

2023-05-17 11:46:16,933 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 6804 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$97/1886058641.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$96/1805728761.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:46:17,933 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 7805 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$97/1886058641.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$96/1805728761.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:46:18,933 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 8805 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$97/1886058641.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$96/1805728761.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:46:19,933 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 9805 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$97/1886058641.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$96/1805728761.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:53:33,061 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory 
2023-05-17 11:53:33,077 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] created instance of HMServerConfiguration with parameter(s) 
2023-05-17 11:53:33,077 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] passed 1 parameter(s), in declarative order [String] 
2023-05-17 11:53:33,078 de.eq3.ccu.server.BaseHMServer INFO  [main] Creating instance of HMServer...  
2023-05-17 11:53:33,084 de.eq3.cbcs.util.EQ3LoggerOutputStream INFO  [main] std.out and std.err redirection active 
2023-05-17 11:53:33,117 de.eq3.ccu.server.BaseHMServer INFO  [main] Default MaxEventLoopExecuteTime: 2000000000 
2023-05-17 11:53:33,118 de.eq3.ccu.server.BaseHMServer INFO  [main] Default BlockedThreadCheckInterval: 1000 
2023-05-17 11:53:33,118 de.eq3.ccu.server.BaseHMServer INFO  [main] Default MaxWorkerExecuteTime: 60000000000 
2023-05-17 11:53:33,119 de.eq3.ccu.server.BaseHMServer INFO  [main] Default EventLoopPoolSize: 4 
2023-05-17 11:53:33,580 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [BackendWorker] (1) *worker 
2023-05-17 11:53:33,581 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker 
2023-05-17 11:53:33,583 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker 
2023-05-17 11:53:33,584 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker 
2023-05-17 11:53:33,586 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker 
2023-05-17 11:53:33,587 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker 
2023-05-17 11:53:33,589 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker 
2023-05-17 11:53:33,590 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [RegaClientWorker] (1) *worker 
2023-05-17 11:53:33,592 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker 
2023-05-17 11:53:33,601 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: deploying 9 classes to Vert.x 
2023-05-17 11:53:33,704 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: 9 VertxDeployers initialized 
2023-05-17 11:53:33,780 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of BackendWorker succeeded (7d310fcb-3317-46af-ab17-d69ac2e81220) 
2023-05-17 11:53:33,782 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of EnergyPriceRequestWorker succeeded (5db13490-15d2-4583-856f-6fc5e46b2b40) 
2023-05-17 11:53:33,787 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (22a9687b-774f-4044-a8cd-d5b5f4dac880) 
2023-05-17 11:53:33,787 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of StorageRequestWorker succeeded (0ffd01ed-4331-4112-9b93-0b7bf97c5a86) 
2023-05-17 11:53:33,788 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of RegaClientWorker succeeded (c8504c8e-e6ee-4a84-ba0b-4d0fa3d042ec) 
2023-05-17 11:53:33,788 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of CouplingRequestWorker succeeded (ef06b3be-3db1-4ff7-b988-f032a49b13e8) 
2023-05-17 11:53:33,872 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of GroupRequestWorker succeeded (61ca13a2-2b2c-4de0-a0f1-72f6d4cd7c9b) 
2023-05-17 11:53:33,874 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DiagramRequestWorker succeeded (0566c616-54f7-4609-8c0d-a030512f1875) 
2023-05-17 11:53:33,875 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (42bfcc96-d0b5-49c7-9b21-1eb9c6cb9619) 
2023-05-17 11:53:33,907 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: initial deployment complete _____________________________________________________ 
2023-05-17 11:53:33,908 de.eq3.ccu.server.BaseHMServer INFO  [main] Starting HMServer at 127.0.0.1:39292 
2023-05-17 11:53:33,909 de.eq3.ccu.server.BaseHMServer INFO  [main] Read Configuration 
2023-05-17 11:53:34,065 de.eq3.ccu.server.BaseHMServer INFO  [main] Create Bidcos Dispatcher 
2023-05-17 11:53:34,122 de.eq3.ccu.server.BaseHMServer INFO  [main] InitBidCosCache 
2023-05-17 11:53:35,013 de.eq3.ccu.server.BaseHMServer INFO  [main] Create groupDefinitionProvider 
2023-05-17 11:53:36,254 de.eq3.ccu.server.BaseHMServer INFO  [main] Create VirtualDeviceHolder 
2023-05-17 11:53:36,259 de.eq3.ccu.server.BaseHMServer INFO  [main] Create VirtualDeviceHandlerRega 
2023-05-17 11:53:36,265 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupAdministrationService 
2023-05-17 11:53:36,265 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupDeviceDispatcher 
2023-05-17 11:53:36,267 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupDeviceHandler 
2023-05-17 11:53:36,275 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] @GroupDeviceHandler - initializing... 
2023-05-17 11:53:36,276 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher)) 
2023-05-17 11:53:36,276 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> created virtualDeviceHandler (GroupDeviceService to ReGa) 
2023-05-17 11:53:36,277 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> got groupDefinitionProvider 
2023-05-17 11:53:36,278 de.eq3.ccu.server.BaseHMServer INFO  [main] Create BidCosGroupMemberProvider 
2023-05-17 11:53:36,298 de.eq3.ccu.server.BaseHMServer INFO  [main] Init groupAdministrationService 
2023-05-17 11:53:36,340 de.eq3.ccu.server.BaseHMServer INFO  [main] Init Virtual OS Device 
2023-05-17 11:53:36,345 de.eq3.ccu.server.BaseHMServer INFO  [main] Init ESHLight Bridge 
2023-05-17 11:53:37,072 de.eq3.ccu.server.BaseHMServer INFO  [main] Create RrdDatalogging 
2023-05-17 11:53:37,075 de.eq3.ccu.server.BaseHMServer INFO  [main] Create MeasurementService 
2023-05-17 11:53:37,081 de.eq3.ccu.server.BaseHMServer INFO  [main] Init MeasurementService 
2023-05-17 11:53:37,135 de.eq3.ccu.server.BaseHMServer INFO  [main] Create HTTP Server 
2023-05-17 11:53:37,433 de.eq3.ccu.server.BaseHMServer INFO  [main] Create BidCos context and start handler 
2023-05-17 11:53:37,444 de.eq3.ccu.server.BaseHMServer INFO  [main] Create group context and start handler 
2023-05-17 11:53:38,464 de.eq3.ccu.server.BaseHMServer INFO  [main] Starting HMServer done 
2023-05-17 11:53:48,004 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:31999 
2023-05-17 11:53:48,041 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] Added InterfaceId: 1236 
2023-05-17 11:53:48,055 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-9] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand 
2023-05-17 11:53:50,345 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 2338 ms, time limit is 2000 ms 
2023-05-17 11:53:51,341 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 3339 ms, time limit is 2000 ms 
2023-05-17 11:53:52,340 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 4338 ms, time limit is 2000 ms 
2023-05-17 11:53:53,341 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 5338 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/1841056155.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$88/1903496903.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:53:54,341 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 6338 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/1841056155.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$88/1903496903.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:53:55,341 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 7338 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/1841056155.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$88/1903496903.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:53:56,342 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 8339 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/1841056155.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$88/1903496903.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:53:57,341 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 9339 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/1841056155.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$88/1903496903.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:59:01,759 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory 
2023-05-17 11:59:01,778 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] created instance of HMServerConfiguration with parameter(s) 
2023-05-17 11:59:01,778 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] passed 1 parameter(s), in declarative order [String] 
2023-05-17 11:59:01,780 de.eq3.ccu.server.BaseHMServer INFO  [main] Creating instance of HMServer...  
2023-05-17 11:59:01,787 de.eq3.cbcs.util.EQ3LoggerOutputStream INFO  [main] std.out and std.err redirection active 
2023-05-17 11:59:01,821 de.eq3.ccu.server.BaseHMServer INFO  [main] Default MaxEventLoopExecuteTime: 2000000000 
2023-05-17 11:59:01,822 de.eq3.ccu.server.BaseHMServer INFO  [main] Default BlockedThreadCheckInterval: 1000 
2023-05-17 11:59:01,822 de.eq3.ccu.server.BaseHMServer INFO  [main] Default MaxWorkerExecuteTime: 60000000000 
2023-05-17 11:59:01,822 de.eq3.ccu.server.BaseHMServer INFO  [main] Default EventLoopPoolSize: 4 
2023-05-17 11:59:02,350 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [BackendWorker] (1) *worker 
2023-05-17 11:59:02,351 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker 
2023-05-17 11:59:02,353 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker 
2023-05-17 11:59:02,354 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker 
2023-05-17 11:59:02,356 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker 
2023-05-17 11:59:02,357 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker 
2023-05-17 11:59:02,358 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker 
2023-05-17 11:59:02,360 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [RegaClientWorker] (1) *worker 
2023-05-17 11:59:02,361 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker 
2023-05-17 11:59:02,370 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: deploying 9 classes to Vert.x 
2023-05-17 11:59:02,423 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: 9 VertxDeployers initialized 
2023-05-17 11:59:02,566 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of EnergyPriceRequestWorker succeeded (27f57761-c4d8-44a3-b5f4-f1a13ce54741) 
2023-05-17 11:59:02,582 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of StorageRequestWorker succeeded (e3064050-bdb1-47a4-95e0-2c04d0aaf5a4) 
2023-05-17 11:59:02,582 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of BackendWorker succeeded (6c117513-a76b-4022-85f7-bc7961f270eb) 
2023-05-17 11:59:02,596 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of RegaClientWorker succeeded (0ef567ce-0ca2-47c4-8b46-e832c4ba68a3) 
2023-05-17 11:59:02,598 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (66588011-7b6c-49aa-92ac-eaeff1190b29) 
2023-05-17 11:59:02,600 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of CouplingRequestWorker succeeded (e5c7e02e-97b8-4ebc-b52b-dfb5dc56b592) 
2023-05-17 11:59:02,672 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DiagramRequestWorker succeeded (72931b48-83d8-43bd-a42f-b0a3a052e605) 
2023-05-17 11:59:02,674 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of GroupRequestWorker succeeded (e82a7b28-c94e-4667-9a25-c55b7851f967) 
2023-05-17 11:59:02,677 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (f2fe307f-8e56-42c3-a6e0-afd3d05036ab) 
2023-05-17 11:59:02,759 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: initial deployment complete _____________________________________________________ 
2023-05-17 11:59:02,760 de.eq3.ccu.server.BaseHMServer INFO  [main] Starting HMServer at 127.0.0.1:39292 
2023-05-17 11:59:02,761 de.eq3.ccu.server.BaseHMServer INFO  [main] Read Configuration 
2023-05-17 11:59:02,921 de.eq3.ccu.server.BaseHMServer INFO  [main] Create Bidcos Dispatcher 
2023-05-17 11:59:02,978 de.eq3.ccu.server.BaseHMServer INFO  [main] InitBidCosCache 
2023-05-17 11:59:03,865 de.eq3.ccu.server.BaseHMServer INFO  [main] Create groupDefinitionProvider 
2023-05-17 11:59:05,015 de.eq3.ccu.server.BaseHMServer INFO  [main] Create VirtualDeviceHolder 
2023-05-17 11:59:05,025 de.eq3.ccu.server.BaseHMServer INFO  [main] Create VirtualDeviceHandlerRega 
2023-05-17 11:59:05,033 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupAdministrationService 
2023-05-17 11:59:05,033 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupDeviceDispatcher 
2023-05-17 11:59:05,035 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupDeviceHandler 
2023-05-17 11:59:05,043 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] @GroupDeviceHandler - initializing... 
2023-05-17 11:59:05,043 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher)) 
2023-05-17 11:59:05,044 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> created virtualDeviceHandler (GroupDeviceService to ReGa) 
2023-05-17 11:59:05,045 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> got groupDefinitionProvider 
2023-05-17 11:59:05,046 de.eq3.ccu.server.BaseHMServer INFO  [main] Create BidCosGroupMemberProvider 
2023-05-17 11:59:05,070 de.eq3.ccu.server.BaseHMServer INFO  [main] Init groupAdministrationService 
2023-05-17 11:59:05,113 de.eq3.ccu.server.BaseHMServer INFO  [main] Init Virtual OS Device 
2023-05-17 11:59:05,118 de.eq3.ccu.server.BaseHMServer INFO  [main] Init ESHLight Bridge 
2023-05-17 11:59:05,911 de.eq3.ccu.server.BaseHMServer INFO  [main] Create RrdDatalogging 
2023-05-17 11:59:05,915 de.eq3.ccu.server.BaseHMServer INFO  [main] Create MeasurementService 
2023-05-17 11:59:05,921 de.eq3.ccu.server.BaseHMServer INFO  [main] Init MeasurementService 
2023-05-17 11:59:05,993 de.eq3.ccu.server.BaseHMServer INFO  [main] Create HTTP Server 
2023-05-17 11:59:06,287 de.eq3.ccu.server.BaseHMServer INFO  [main] Create BidCos context and start handler 
2023-05-17 11:59:06,301 de.eq3.ccu.server.BaseHMServer INFO  [main] Create group context and start handler 
2023-05-17 11:59:07,309 de.eq3.ccu.server.BaseHMServer INFO  [main] Starting HMServer done 
2023-05-17 11:59:16,795 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-1] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:31999 
2023-05-17 11:59:16,801 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-1] Added InterfaceId: 1236 
2023-05-17 11:59:16,815 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-9] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand 
2023-05-17 11:59:19,078 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 2287 ms, time limit is 2000 ms 
2023-05-17 11:59:20,071 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 3287 ms, time limit is 2000 ms 
2023-05-17 11:59:21,071 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 4287 ms, time limit is 2000 ms 
2023-05-17 11:59:22,073 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 5287 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$94/122831391.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$93/1741867653.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:59:23,074 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 6288 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$94/122831391.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$93/1741867653.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:59:24,074 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 7289 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$94/122831391.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$93/1741867653.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:59:25,074 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 8289 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$94/122831391.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$93/1741867653.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 11:59:26,075 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 9289 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$94/122831391.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$93/1741867653.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 12:04:50,313 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory 
2023-05-17 12:04:50,331 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] created instance of HMServerConfiguration with parameter(s) 
2023-05-17 12:04:50,331 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] passed 1 parameter(s), in declarative order [String] 
2023-05-17 12:04:50,333 de.eq3.ccu.server.BaseHMServer INFO  [main] Creating instance of HMServer...  
2023-05-17 12:04:50,339 de.eq3.cbcs.util.EQ3LoggerOutputStream INFO  [main] std.out and std.err redirection active 
2023-05-17 12:04:50,376 de.eq3.ccu.server.BaseHMServer INFO  [main] Default MaxEventLoopExecuteTime: 2000000000 
2023-05-17 12:04:50,376 de.eq3.ccu.server.BaseHMServer INFO  [main] Default BlockedThreadCheckInterval: 1000 
2023-05-17 12:04:50,377 de.eq3.ccu.server.BaseHMServer INFO  [main] Default MaxWorkerExecuteTime: 60000000000 
2023-05-17 12:04:50,377 de.eq3.ccu.server.BaseHMServer INFO  [main] Default EventLoopPoolSize: 4 
2023-05-17 12:04:50,890 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [BackendWorker] (1) *worker 
2023-05-17 12:04:50,891 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker 
2023-05-17 12:04:50,893 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker 
2023-05-17 12:04:50,895 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker 
2023-05-17 12:04:50,896 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker 
2023-05-17 12:04:50,897 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker 
2023-05-17 12:04:50,899 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker 
2023-05-17 12:04:50,900 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [RegaClientWorker] (1) *worker 
2023-05-17 12:04:50,902 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: added for deployment [GroupConfigurationPersistenceFileSystem] (1) *worker 
2023-05-17 12:04:50,911 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: deploying 9 classes to Vert.x 
2023-05-17 12:04:50,955 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: 9 VertxDeployers initialized 
2023-05-17 12:04:51,056 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of EnergyPriceRequestWorker succeeded (e5db29a2-73fd-4073-9661-bee6fddd8130) 
2023-05-17 12:04:51,093 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of StorageRequestWorker succeeded (ccf7a6c3-9ed7-441e-8b46-29655c75f540) 
2023-05-17 12:04:51,096 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of CouplingRequestWorker succeeded (7cdf4cb8-72ac-462f-b5db-df75c630f3f3) 
2023-05-17 12:04:51,100 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of GroupConfigurationPersistenceFileSystem succeeded (b4fe4dc4-54cc-4144-bd8a-c84e9ed60061) 
2023-05-17 12:04:51,101 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of RegaClientWorker succeeded (e65d1508-48bb-40be-b7a3-8be267cfb58f) 
2023-05-17 12:04:51,106 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of BackendWorker succeeded (b4fdca14-fa41-46d8-9cf6-200c57a2be32) 
2023-05-17 12:04:51,202 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of DiagramRequestWorker succeeded (7be87be1-fb55-4f13-af70-d4bb00f28fe6) 
2023-05-17 12:04:51,202 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of GroupRequestWorker succeeded (6e5b2cdf-bfb7-4080-b2ee-282ea3dfbd24) 
2023-05-17 12:04:51,203 de.eq3.vertx.manager.management.VertxManager INFO  [vert.x-eventloop-thread-3] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (2d43806e-b8ca-4c07-9aa8-4514a2348075) 
2023-05-17 12:04:51,256 de.eq3.vertx.manager.management.VertxManager INFO  [main] SYSTEM: initial deployment complete _____________________________________________________ 
2023-05-17 12:04:51,257 de.eq3.ccu.server.BaseHMServer INFO  [main] Starting HMServer at 127.0.0.1:39292 
2023-05-17 12:04:51,257 de.eq3.ccu.server.BaseHMServer INFO  [main] Read Configuration 
2023-05-17 12:04:51,425 de.eq3.ccu.server.BaseHMServer INFO  [main] Create Bidcos Dispatcher 
2023-05-17 12:04:51,480 de.eq3.ccu.server.BaseHMServer INFO  [main] InitBidCosCache 
2023-05-17 12:04:52,364 de.eq3.ccu.server.BaseHMServer INFO  [main] Create groupDefinitionProvider 
2023-05-17 12:04:53,452 de.eq3.ccu.server.BaseHMServer INFO  [main] Create VirtualDeviceHolder 
2023-05-17 12:04:53,456 de.eq3.ccu.server.BaseHMServer INFO  [main] Create VirtualDeviceHandlerRega 
2023-05-17 12:04:53,463 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupAdministrationService 
2023-05-17 12:04:53,463 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupDeviceDispatcher 
2023-05-17 12:04:53,465 de.eq3.ccu.server.BaseHMServer INFO  [main] Create GroupDeviceHandler 
2023-05-17 12:04:53,473 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] @GroupDeviceHandler - initializing... 
2023-05-17 12:04:53,473 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher)) 
2023-05-17 12:04:53,474 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> created virtualDeviceHandler (GroupDeviceService to ReGa) 
2023-05-17 12:04:53,475 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [main] --> got groupDefinitionProvider 
2023-05-17 12:04:53,475 de.eq3.ccu.server.BaseHMServer INFO  [main] Create BidCosGroupMemberProvider 
2023-05-17 12:04:53,496 de.eq3.ccu.server.BaseHMServer INFO  [main] Init groupAdministrationService 
2023-05-17 12:04:53,533 de.eq3.ccu.server.BaseHMServer INFO  [main] Init Virtual OS Device 
2023-05-17 12:04:53,538 de.eq3.ccu.server.BaseHMServer INFO  [main] Init ESHLight Bridge 
2023-05-17 12:04:54,290 de.eq3.ccu.server.BaseHMServer INFO  [main] Create RrdDatalogging 
2023-05-17 12:04:54,298 de.eq3.ccu.server.BaseHMServer INFO  [main] Create MeasurementService 
2023-05-17 12:04:54,304 de.eq3.ccu.server.BaseHMServer INFO  [main] Init MeasurementService 
2023-05-17 12:04:54,358 de.eq3.ccu.server.BaseHMServer INFO  [main] Create HTTP Server 
2023-05-17 12:04:54,662 de.eq3.ccu.server.BaseHMServer INFO  [main] Create BidCos context and start handler 
2023-05-17 12:04:54,681 de.eq3.ccu.server.BaseHMServer INFO  [main] Create group context and start handler 
2023-05-17 12:04:55,687 de.eq3.ccu.server.BaseHMServer INFO  [main] Starting HMServer done 
2023-05-17 12:05:05,187 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-1] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:31999 
2023-05-17 12:05:05,202 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-1] Added InterfaceId: 1236 
2023-05-17 12:05:05,218 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-9] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand 
2023-05-17 12:05:07,633 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 2439 ms, time limit is 2000 ms 
2023-05-17 12:05:08,626 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 3440 ms, time limit is 2000 ms 
2023-05-17 12:05:09,625 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 4439 ms, time limit is 2000 ms 
2023-05-17 12:05:10,626 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 5439 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$90/936863170.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/698058272.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 12:05:11,626 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 6439 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$90/936863170.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/698058272.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 12:05:12,627 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 7440 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$90/936863170.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/698058272.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 12:05:13,627 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 8441 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$90/936863170.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/698058272.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
2023-05-17 12:05:14,628 io.vertx.core.impl.BlockedThreadChecker WARN  [vertx-blocked-thread-checker] Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 9441 ms, time limit is 2000 ms 
io.vertx.core.VertxException: Thread blocked
	at sun.misc.Unsafe.park(Native Method) ~[?:1.8.0_372]
	at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) ~[?:1.8.0_372]
	at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) ~[?:1.8.0_372]
	at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467) ~[?:1.8.0_372]
	at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99) ~[HMServer.jar:?]
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:1.8.0_372]
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:1.8.0_372]
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_372]
	at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_372]
	at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70) ~[HMServer.jar:?]
	at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24) ~[HMServer.jar:?]
	at io.vertx.core.impl.EventLoopContext.emit(EventLoopContext.java:50) ~[HMServer.jar:?]
	at io.vertx.core.impl.DuplicatedContext.emit(DuplicatedContext.java:168) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.dispatch(MessageConsumerImpl.java:187) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.next(HandlerRegistration.java:162) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$InboundDeliveryContext.dispatch(HandlerRegistration.java:127) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$90/936863170.run(Unknown Source) ~[?:?]
	at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:111) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.dispatch(HandlerRegistration.java:103) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.deliver(MessageConsumerImpl.java:193) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.MessageConsumerImpl.doReceive(MessageConsumerImpl.java:178) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration.lambda$receive$0(HandlerRegistration.java:54) ~[HMServer.jar:?]
	at io.vertx.core.eventbus.impl.HandlerRegistration$$Lambda$89/698058272.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:164) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:469) ~[HMServer.jar:?]
	at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:497) ~[HMServer.jar:?]
	at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:986) ~[HMServer.jar:?]
	at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[HMServer.jar:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMServer.jar:?]
	at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_372]
Debmatic Info:

Code: Alles auswählen

debmatic version: 3.69.7-108
OS:               Debian GNU/Linux 11 (bullseye)
Kernel:           5.10.0-23-amd64 x86_64
Service Status:   Running
Kernel modules:   Not available
Raw UART dev:     Not available
HMRF Hardware:    unknown
HMIP Hardware:    unknown
Kann man das noch retten?
Viele Grüße,
Ralf


Debmatic und Openhab 4 in Proxmox 8 VM debian x86_64 auf Intel NUC mit influxdb und Grafana zur Visualisierung

Antworten

Zurück zu „debmatic“