DebMatic - CCU auf Debian Systemen

[deimos]

Hi,

@Mitch: Exakt die Binärversion habe ich nicht mehr, ich könnte nur aus den Git Sourcen das Paket wieder herstellen. Wir können aber auch einfach einen Weg ausprobieren, welcher mehr Debug Informationen gibt und welcher zwischen den beiden Paketen zu 100% nicht verändert ist:

Code Auswählen Erweitern


sudo systemctl stop debmatic
sudo debmatic-info
lsmod | grep cp210x
ls -la /dev/ttyUSB*
lsof | grep ttyUSB0
/usr/bin/java -Dgnu.io.rxtx.SerialPorts=/dev/ttyUSB0 -jar /opt/HmIP/hmip-copro-update.jar -p /dev/ttyUSB0 -v -a


Viele Grüße
Alex

[Mitch]

Werde ich gerne am WE probieren.

Was ich gerade noch gemacht habe, die /var/hm_mode manuell ergänzt (wie bei der Version davor) und nun geht es wieder.


Mein HMLan Problem scheint leider nicht an DebMatic zu liegen  sorry für die Panik hier   
Habe auch Ausfälle gehabt, als DebMatic gar nicht lief.
Echt komisch (und ärgerlich).

[deimos]

Hi,

@fhemfreund Mach mal ein Update per apt. Danach sollte das automatische Firmware Update vom Funkmodul jetzt funktionieren.

Viele Grüße
Alex

[fhemfreund]

Hi,

@fhemfreund Mach mal ein Update per apt. Danach sollte das automatische Firmware Update vom Funkmodul jetzt funktionieren.

Viele Grüße
Alex

@Alex,

Habe das jetzt mal gemacht. Leider klappt der Update immer noch nicht. Scheinbar passt ein Pfad nicht:

Code Auswählen Erweitern


Feb 16 01:38:54 fhemDebmatic update_rf_firmware.sh[6040]: Starting update of HM-MOD-RPI-PCB to version 2.8.6...
Feb 16 01:38:54 fhemDebmatic systemd[1]: Starting debmatic update lgw firmware...
Feb 16 01:38:54 fhemDebmatic systemd[1]: Starting debmatic lighttpd...
Feb 16 01:38:54 fhemDebmatic systemd[1]: Started debmatic HssLED.
Feb 16 01:38:54 fhemDebmatic systemd[1]: Started debmatic eq3configd.
Feb 16 01:38:54 fhemDebmatic update-coprocessor: unable to open file /firmware/HM-MOD-UART/fwmap/fwmap
Feb 16 01:38:54 fhemDebmatic systemd[1]: Started debmatic ssdpd.
Feb 16 01:38:54 fhemDebmatic update-coprocessor: Error reading firmware map.
Feb 16 01:38:54 fhemDebmatic update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Feb 16 01:38:54 fhemDebmatic update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Feb 16 01:38:54 fhemDebmatic systemd[1]: Started debmatic update lgw firmware.
Feb 16 01:38:54 fhemDebmatic systemd[1]: Starting debmatic update lgw key...
Feb 16 01:38:54 fhemDebmatic lighttpd[6075]: (log.c.217) server started
Feb 16 01:38:54 fhemDebmatic systemd[1]: Started debmatic lighttpd.
Feb 16 01:38:54 fhemDebmatic update_lgw_key.sh[6073]: ls: cannot access '/etc/config/*.keychange': No such file or directory
Feb 16 01:38:54 fhemDebmatic systemd[1]: Started debmatic update lgw key.
Feb 16 01:38:56 fhemDebmatic update_rf_firmware.sh[6040]: Failed to update HM-MOD-RPI-PCB to version 2.8.6.


Habe dann mal versucht die FW manuell via

Code Auswählen Erweitern


sudo /bin/eq3configcmd update-coprocessor -p /dev/raw-uart -t HM-MOD-UART -u -f -d /firmware/HM-MOD-UART -c -l 0


upzudaten. Das hat scheinbar auch funktioniert:

Bekomme mit

Code Auswählen Erweitern


sudo /bin/eq3configcmd update-coprocessor -p /dev/raw-uart -t HM-MOD-UART -c -v


folgendes angezeigt:

Code Auswählen Erweitern


2019/02/16 02:40:03.705 <Info> CCU2CommControllerMod::sendSystemCommand(): failed
2019/02/16 02:40:03.705 <Error> CCU2CommControllerMod::performIdentify(): Unable to determine coprocessor state.
2019/02/16 02:40:04.312 <Info> Version: 2.8.6


Kann auch meine 1.4.1er FW über diesen Weg zurückflashen, und den HM-MOD-RPI-PCB in meinem HMUARTGW wieder verwenden, sodass der Flashvorgang an sich manuell funktioniert.

Leider funktioniert aber Debmatic selbst mit der 2.8.6er FW immer noch nicht.

/var/hm_mode zeigt:

Code Auswählen Erweitern


HM_HOST='DEBMATIC'
HM_HOST_RAW_UART=''
HM_HOST_GPIO_UART=''
HM_HOST_GPIO_RESET=''
HM_LED_GREEN=''
HM_LED_RED=''
HM_LED_YELLOW=''
HM_RTC=''
HM_MODE='NORMAL'
HM_HMRF_DEVNODE=''
HM_HMIP_DEVNODE=''
HM_HMRF_DEV=''
HM_HMIP_DEV=''
HM_HMRF_SERIAL='DEB5357349'
HM_HMRF_VERSION=''
HM_HMRF_ADDRESS=''
HM_HMIP_SGTIN=''
HM_HMIP_SERIAL=''
HM_HMIP_VERSION=''
HM_HMIP_ADDRESS=''


/usr/sbin/debmatic-info zeigt:

Code Auswählen Erweitern


debmatic version: 3.41.11-3
Kernel modules: Available
Raw UART dev:   Available
HMRF Hardware:  unknown
HMIP Hardware:  unknown
Board serial:   DEB5357349
Radio MAC:      unknown
SGTIN:          unknown


Andreas

[schwatter]

@deimos

Habe eben zum ausprobieren DebMatic auf meinem Pi3 installiert. Kein Erfolg. Damit ich keinen Ärger bekomme erstmal alles zurück  ,
da HM-MOD-RPI-PCB verständlicherweise nicht mehr unter Fhem lief.
Also deinstall und alles zurück. Dabei kam zu Tage, das ich 2 Einträge von

Code Auswählen Erweitern

dtoverlay=

in meiner /boot/config.txt hatte.

Code Auswählen Erweitern

dtoverlay=pi3-miniuart-bt
dtoverlay=hierWasMitRaspi

Vielleicht wäre besser den Eintrag zu tauschen, anstatt hinzuzufügen bei deinen Installationsroutinen. Oder sehe ich das falsch?

[deimos]

Hi,

das was man in der /boot/config.txt manuell ändert muss man logischerweise selbst wieder rückgängig machen. Das was vom Paket pivccu-modules-raspberrypi angepasst wird, wird bei der Deinstallation dieses Pakets wieder rückgängig gemacht. Alle andere Pakete ändern absolut nichts an der /boot/config.txt, weil sie unabhängig von der Platform RaspberryPi sind.

Viele Grüße
Alex

[schwatter]

Mh ok.

So habe mal wieder probiert. Läuft bis jetzt noch nicht. Bekomme folgenden Fehler:

Code Auswählen Erweitern

● debmatic.service - debmatic
   Loaded: loaded (/lib/systemd/system/debmatic.service; enabled; vendor preset: enabled)
   Active: active (exited) since Sat 2019-02-16 14:40:35 CET; 3min 26s ago
  Process: 690 ExecStart=/usr/share/debmatic/bin/initsystem.sh (code=exited, status=0/SUCCESS)
Main PID: 690 (code=exited, status=0/SUCCESS)
   CGroup: /system.slice/debmatic.service

Feb 16 14:40:25 raspiFhem systemd[1]: Starting debmatic...
Feb 16 14:40:35 raspiFhem initsystem.sh[690]: /usr/share/debmatic/bin/initsystem.sh: Zeile 131: ipcalc: Kommando nicht gefunden.
Feb 16 14:40:35 raspiFhem initsystem.sh[690]: /usr/share/debmatic/bin/initsystem.sh: Zeile 132: ipcalc: Kommando nicht gefunden.
Feb 16 14:40:35 raspiFhem systemd[1]: Started debmatic.

[deimos]

Hi,

installier mal das Paket ipcalc nach. Ich dachte, das wäre in den Standardpaketen drin, aber dann nehm ich die Abhängigkeit noch mit auf.

Viele Grüße
Alex

[schwatter]

Ok, Fehler weg und endlich ist auch hm_mode befüllt.

Code Auswählen Erweitern

root@raspiFhem:~# cat /var/hm_mode
HM_HMIP_ADDRESS=0x5834cf
HM_HMIP_DEV=HM-MOD-RPI-PCB
HM_HMIP_DEVNODE=/dev/mmd_hmip
HM_HMIP_SERIAL=OEQ0306308
HM_HMIP_SGTIN=
HM_HMIP_VERSION=1.4.1
HM_HMRF_ADDRESS=0x5834cf
HM_HMRF_DEV=HM-MOD-RPI-PCB
HM_HMRF_DEVNODE=/dev/mmd_bidcos
HM_HMRF_SERIAL=OEQ0306308
HM_HMRF_VERSION=1.4.1
HM_HOST=DEBMATIC
HM_HOST_GPIO_RESET=
HM_HOST_GPIO_UART=/dev/raw-uart
HM_HOST_RAW_UART=raw-uart
HM_LED_GREEN=
HM_LED_RED=
HM_LED_YELLOW=
HM_MODE=NORMAL
HM_RTC=
root@raspiFhem:~#

Vielleicht eine doofe Frage...Laut orig Doku finde ich die webgui unter

Code Auswählen Erweitern

http://homematic-ccu2/
http://ccu3-webui/

Wie sieht das bei DebMatic aus? Denke gleich, weil, ich finde die Gui noch nicht.

edit:
Nutze seit 2 jahren Homematic ohne Ip direkt an Fhem. Daher keine Berührung mit original Software.

edit2:
Ah jetzt, Netfinder findet was. Unter einer anderen IP-Range.

edit3:
Mein Pi3 läuft unter der IP 192.168.178.*
Die CCU unter 192.168.1.7

[fhemfreund]

@Alex,

habe auch noch mal ein bisschen im Syslog geschaut. Da ich ja eine VM verwende ist mein Network Interface 'ens18' und nicht 'eth0'. Scheinbar kommen da einige Scripte nicht damit zurecht:

Code Auswählen Erweitern


Feb 16 15:48:08 fhemDebmatic kernel: [    4.752563] raw-uart raw-uart: Reset radio module
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 5: /etc/network/if-up.d/debmatic: [[: not found
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 11: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 16: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 24: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 29: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 36: /etc/network/if-up.d/debmatic: [[: not found
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 42: [: ==: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 5: /etc/network/if-up.d/debmatic: [[: not found
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 11: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 16: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 24: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 29: [: 1: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 36: /etc/network/if-up.d/debmatic: [[: not found
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 42: [: ens18: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 5: /etc/network/if-up.d/debmatic: [[: not found
Feb 16 15:48:08 fhemDebmatic ifup[373]: cat: /sys/class/net/--all/carrier: No such file or directory
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 11: [: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: cat: /sys/class/net/--all/carrier: No such file or directory
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 16: [: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: Device "--all" does not exist.
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 24: [: 0: unexpected operator
Feb 16 15:48:08 fhemDebmatic ifup[373]: Device "--all" does not exist.
Feb 16 15:48:08 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 29: [: 0: unexpected operator
Feb 16 15:48:09 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 36: /etc/network/if-up.d/debmatic: [[: not found
Feb 16 15:48:09 fhemDebmatic ifup[373]: /etc/network/if-up.d/debmatic: 42: [: ens18: unexpected operator

...

Feb 16 15:48:26 fhemDebmatic initsystem.sh[370]: Device "eth0" does not exist.
Feb 16 15:48:26 fhemDebmatic update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Feb 16 15:48:26 fhemDebmatic update-lgw-firmware: No gateway found in config file /etc/config/rfd.conf
Feb 16 15:48:26 fhemDebmatic update-coprocessor: firmware filename is: coprocessor_update_hm_only.eq3
Feb 16 15:48:26 fhemDebmatic update_lgw_key.sh[656]: ls: cannot access '/etc/config/*.keychange': No such file or directory
...


Es kommen dann noch weitere Fehler - k.a. ob die eine Folge davon sind:

Code Auswählen Erweitern


...
Feb 16 15:48:27 fhemDebmatic start_hmserver.sh[660]: Init Hardware Info
Feb 16 15:48:27 fhemDebmatic start_hmserver.sh[660]: WARNING: An illegal reflective access operation has occurred
Feb 16 15:48:27 fhemDebmatic start_hmserver.sh[660]: WARNING: Illegal reflective access by io.netty.util.internal.PlatformDependent0$1 (file:/opt/HMServer/HMServer.jar) to field java.nio.Buffer.address
Feb 16 15:48:27 fhemDebmatic start_hmserver.sh[660]: WARNING: Please consider reporting this to the maintainers of io.netty.util.internal.PlatformDependent0$1
Feb 16 15:48:27 fhemDebmatic start_hmserver.sh[660]: WARNING: Use --illegal-access=warn to enable warnings of further illegal reflective access operations
Feb 16 15:48:27 fhemDebmatic start_hmserver.sh[660]: WARNING: All illegal access operations will be denied in a future release
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:29 PM io.vertx.core.eventbus.impl.HandlerRegistration
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: SEVERE: Failed to handleMessage
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: de.eq3.cbcs.legacy.communication.rpc.RpcIOException: java.net.ConnectException: Connection refused (Connection refused)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:90)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.sendRequest(RpcClient.java:99)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.invoke(RpcClient.java:87)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at com.sun.proxy.$Proxy15.listDevices(Unknown Source)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand.updateDevicesForClient(BackendUpdateDevicesCommand.java:34)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand.run(BackendUpdateDevicesCommand.java:29)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker.handle(BackendWorker.java:27)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker.handle(BackendWorker.java:11)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.impl.TaskQueue.lambda$new$0(TaskQueue.java:60)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: Caused by: java.net.ConnectException: Connection refused (Connection refused)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.Socket.connect(Socket.java:591)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:120)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:179)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:144)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:134)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:612)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:447)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:884)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:86)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011... 15 more
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:29 PM io.vertx.core.impl.ContextImpl
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: SEVERE: Unhandled exception
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: de.eq3.cbcs.legacy.communication.rpc.RpcIOException: java.net.ConnectException: Connection refused (Connection refused)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:90)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.sendRequest(RpcClient.java:99)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.invoke(RpcClient.java:87)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at com.sun.proxy.$Proxy15.listDevices(Unknown Source)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand.updateDevicesForClient(BackendUpdateDevicesCommand.java:34)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand.run(BackendUpdateDevicesCommand.java:29)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker.handle(BackendWorker.java:27)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker.handle(BackendWorker.java:11)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at io.vertx.core.impl.TaskQueue.lambda$new$0(TaskQueue.java:60)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.lang.Thread.run(Thread.java:834)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: Caused by: java.net.ConnectException: Connection refused (Connection refused)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.PlainSocketImpl.socketConnect(Native Method)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:399)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:242)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:224)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.SocksSocketImpl.connect(SocksSocketImpl.java:403)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at java.base/java.net.Socket.connect(Socket.java:591)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:120)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:179)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:144)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:134)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:612)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:447)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:884)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:86)
Feb 16 15:48:29 fhemDebmatic start_hmserver.sh[660]: #011... 15 more
Feb 16 15:48:39 fhemDebmatic systemd-timesyncd[313]: Synchronized to time server 129.70.132.37:123 (2.debian.pool.ntp.org).
Feb 16 15:48:43 fhemDebmatic systemd[1]: Started debmatic hmserver.
Feb 16 15:48:43 fhemDebmatic systemd[1]: Starting debmatic rega...
Feb 16 15:48:43 fhemDebmatic systemd[1]: Started debmatic rega.
Feb 16 15:48:43 fhemDebmatic systemd[1]: Startup finished in 3.546s (kernel) + 34.873s (userspace) = 38.419s.
Feb 16 15:48:43 fhemDebmatic start_rega.sh[713]: successfully loaded "/etc/config/homematic.regadom"
Feb 16 15:48:43 fhemDebmatic start_rega.sh[713]: Load interfaces from file
Feb 16 15:48:43 fhemDebmatic start_rega.sh[713]: HTTP server started successfully (6 workers)
Feb 16 15:48:43 fhemDebmatic start_rega.sh[713]: Waiting for child to finish
Feb 16 15:48:43 fhemDebmatic start_rega.sh[713]: Executing /bin/hm_startup in child 724
Feb 16 15:48:50 fhemDebmatic ReGaHss: Error: IseXmlRpc::CallInit: CallXmlrpcMethod failed [iseXmlRpc.cpp:1204]
Feb 16 15:48:50 fhemDebmatic ReGaHss: Error: XmlRpcClientThread::ThreadFunction(): failed call init for interface=BidCos-RF [iseRTHss.cpp:163]
Feb 16 15:48:53 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:53 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:53 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 2915 ms, time limit is 2000
Feb 16 15:48:54 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:54 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:54 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 3915 ms, time limit is 2000
Feb 16 15:48:55 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:55 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:55 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 4915 ms, time limit is 2000
Feb 16 15:48:55 fhemDebmatic start_rega.sh[713]: ReGa entering normal operation
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:56 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 5914 ms, time limit is 2000
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: io.vertx.core.VertxException: Thread blocked
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.misc.Unsafe.park(Native Method)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:458)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.reflect.Method.invoke(Method.java:566)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:85)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$129/0x0000000840282440.handle(Unknown Source)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl$$Lambda$82/0x00000008400fbc40.run(Unknown Source)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 16 15:48:56 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.Thread.run(Thread.java:834)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:57 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 6915 ms, time limit is 2000
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: io.vertx.core.VertxException: Thread blocked
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.misc.Unsafe.park(Native Method)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:458)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.reflect.Method.invoke(Method.java:566)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:85)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$129/0x0000000840282440.handle(Unknown Source)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl$$Lambda$82/0x00000008400fbc40.run(Unknown Source)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 16 15:48:57 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.Thread.run(Thread.java:834)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:58 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 7915 ms, time limit is 2000
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: io.vertx.core.VertxException: Thread blocked
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.misc.Unsafe.park(Native Method)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:458)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.reflect.Method.invoke(Method.java:566)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:85)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$129/0x0000000840282440.handle(Unknown Source)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl$$Lambda$82/0x00000008400fbc40.run(Unknown Source)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 16 15:48:58 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.Thread.run(Thread.java:834)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:48:59 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 8915 ms, time limit is 2000
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: io.vertx.core.VertxException: Thread blocked
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.misc.Unsafe.park(Native Method)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:458)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.reflect.Method.invoke(Method.java:566)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:85)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$129/0x0000000840282440.handle(Unknown Source)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl$$Lambda$82/0x00000008400fbc40.run(Unknown Source)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 16 15:48:59 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.Thread.run(Thread.java:834)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: Feb 16, 2019 3:49:00 PM io.vertx.core.impl.BlockedThreadChecker
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: WARNING: Thread Thread[vert.x-eventloop-thread-1,5,main] has been blocked for 9915 ms, time limit is 2000
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: io.vertx.core.VertxException: Thread blocked
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.misc.Unsafe.park(Native Method)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:234)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2123)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:458)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.reflect.Method.invoke(Method.java:566)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:85)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$129/0x0000000840282440.handle(Unknown Source)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.vertx.core.impl.ContextImpl$$Lambda$82/0x00000008400fbc40.run(Unknown Source)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at app//io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 16 15:49:00 fhemDebmatic start_hmserver.sh[660]: #011at java.base@11.0.2/java.lang.Thread.run(Thread.java:834)
Feb 16 15:49:00 fhemDebmatic ReGaHss: Error: IseXmlRpc::CallInit: CallXmlrpcMethod failed [iseXmlRpc.cpp:1204]
Feb 16 15:49:00 fhemDebmatic ReGaHss: Error: XmlRpcClientThread::ThreadFunction(): failed call init for interface=HmIP-RF [iseRTHss.cpp:163]
Feb 16 16:00:17 fhemDebmatic systemd[1]: Starting debmatic-setinterfaceclock.service...
Feb 16 16:00:17 fhemDebmatic SetInterfaceClock[943]: Error in XmlRpcClient::doConnect: Could not connect to server (error 101).
Feb 16 16:00:18 fhemDebmatic systemd[1]: debmatic-setinterfaceclock.service: Main process exited, code=exited, status=2/INVALIDARGUMENT
Feb 16 16:00:18 fhemDebmatic systemd[1]: debmatic-setinterfaceclock.service: Failed with result 'exit-code'.
Feb 16 16:00:18 fhemDebmatic systemd[1]: Failed to start debmatic-setinterfaceclock.service.
...


(Die WebUI ist wie gesagt up und ich kann herumclicken)

Andreas

Update 1 [18:19]: konnte den Netzwerk-Interface Namen auf eth0 ändern. Die Fehlermeldung

Code Auswählen Erweitern


Feb 16 15:48:26 fhemDebmatic initsystem.sh[370]: Device "eth0" does not exist.


ist nun weg.

Alledings habe ich immer noch die Fehler von oben. Nur halt entsprechend dem neuen Interface:

Code Auswählen Erweitern


Feb 16 18:11:04 fhemDebmatic ifup[379]: /etc/network/if-up.d/debmatic: 42: [: eth0: unexpected operator


Update 2 [19:20] konnte den Fehler im Script

Code Auswählen Erweitern


Feb 16 18:11:04 fhemDebmatic ifup[379]: /etc/network/if-up.d/debmatic: 42: [: eth0: unexpected operator


auch finden. In der Abfrage

Code Auswählen Erweitern


if [[ ! -L "/sys/class/net/${IFACE}" ]]; then


war ein Klammernpaar zu viel. Letzten Endes sind die anderen oben genannten Fehler immer noch vorhanden ...

[schwatter]

Ich bekomme meine Ip nicht geändert. Gefunden habe ich, das diese in /etc/config/netconfig definiert ist.

Code Auswählen Erweitern

sudo systemctl stop debmatic

Code Auswählen Erweitern

sudo /etc/config/netconfig
HOSTNAME=debMatic
MODE=DHCP
CURRENT_IP=192.168.1.7    <- Wird von mir zu 192.168.178.45 editiert
CURRENT_NETMASK=255.255.255.0
CURRENT_GATEWAY=192.168.178.1
CURRENT_NAMESERVER1=
CURRENT_NAMESERVER2=
IP=192.168.178.65
NETMASK=255.255.255.0
GATEWAY=192.168.178.1
NAMESERVER1=192.168.178.1
NAMESERVER2=0.0.0.0
CRYPT=0

Code Auswählen Erweitern

sudo systemctl start debmatic

Nachdem ich den Dienst wieder starte, wird CURRENT_IP zurückgesetzt auf 192.168.1.7
Netfinder findet auch immer die 192.168.1.7.

[deimos]

Hi,

ich habe grade ein Update hochgeladen:

- im Netfinder wird jetzt die korrekte IP angezeigt
- beim Upgrade auf dem Raspberry Pi kommt jetzt kein Fehler mehr wg. der /boot/VERSION
- das automatische Firmware Update für das HM-MOD-RPI-PCB funktioniert jetzt
- die Dependency für ipcalc ist jetzt korrekt drin
- es wird das korrekte Interface verwendet und nicht eth0

Die vielen Warnungen vom hmserver sind nur Warnungen. Hier werde ich aber eQ-3 informieren, mit einer zukünftigen Java Version wird das von Warnung auf Fehler umgestellt werden und ich vermute mal, dass die das nicht auf dem Schirm haben. Den Wechsel von Java 8 auf Java >=9 machen sie ja erst in der jetzt kommenden CCU Firmware 3.43.x und erst ab da können sie die Warnung sehen, weil das in Java 8 noch kein Thema war.

@Mitch Dein Problem bekomme ich nicht nachgestellt. Abgesehen vom Firmware Update läuft das HM-MOD-RPI-PCB bei mir mit allen Versionen. Allerdings halt mit meiner "offiziellen" Platine. Da ich den Schaltplan deiner Platine nicht kenne, kann ich aber auch nicht wirklich sagen, ob das ein Software- oder Hardware-Problem ist. An sich supporte ich auch nur die offizielle Platine, die Gründe dafür habe ich ja weiter vorne bereits ausgeführt. Da ich die unbestückten Platinen zum Selbstkostenpreis abgebe, ist das auch nicht der Versuch einer Kommerzialisierung des Ganzen.

Viele Grüße
Alex

Viele Grüße
Alex

[schwatter]

Super,

Betreibe Fhem mit HM-MOD-RPI-PCB und FW 1.4.1. Laut meinen Wissensstand, letzte BidCos-RF Firmware.
Habe nur HM-Geräte ohne IP.
Dazu ein paar Fragen:

- funktioniert die DualCopro FW mit Fhem direkt, wahrscheinlich nicht?
- wenn ich jetzt ein Update von Debmatic mache, wird mein HM-MOD-RPI-PCB einfach geupdatet?
- Kann ich in der WebGui von der CCU3 zwischen der alten und der neuen Firmware per flash switchen?

Ich stelle die Fragen, da ich im Moment nur ein Modul für Homematic habe und zur Not immer wieder zu
Fhem + HM-MOD-RPI-PCB + FW 1.4.1 zurück muss. Ansonsten muss ich mir mal ein 2ten HM-MOD-RPI-PCB
oder den USB-Stick besorgen.

[deimos]

- funktioniert die DualCopro FW mit Fhem direkt, wahrscheinlich nicht?

Das ist so im Moment nicht vorgesehen, aber theoretisch denkbar, dass ich das umsetzen könnte.

- wenn ich jetzt ein Update von Debmatic mache, wird mein HM-MOD-RPI-PCB einfach geupdatet?

Ja.

- Kann ich in der WebGui von der CCU3 zwischen der alten und der neuen Firmware per flash switchen?

Nein. Die WebUI ist die normale WebUI der CCU und bei der CCU wird immer davon ausgegangen, dass man due DualCopro Firmware nutzt. Daher kann man dann nur manuell zurück auf die 1.4

Viele Grüße
Alex

[Mitch]

@Mitch Dein Problem bekomme ich nicht nachgestellt. Abgesehen vom Firmware Update läuft das HM-MOD-RPI-PCB bei mir mit allen Versionen. Allerdings halt mit meiner "offiziellen" Platine. Da ich den Schaltplan deiner Platine nicht kenne, kann ich aber auch nicht wirklich sagen, ob das ein Software- oder Hardware-Problem ist. An sich supporte ich auch nur die offizielle Platine, die Gründe dafür habe ich ja weiter vorne bereits ausgeführt. Da ich die unbestückten Platinen zum Selbstkostenpreis abgebe, ist das auch nicht der Versuch einer Kommerzialisierung des Ganzen.

Naja, der HmIP-RFUSB ist ja keine inoffizielle Platine? Das ist der offizielle Stick von ELV und wird auf deiner Seite ja auch als unterstützt angegeben: HmIP-RFUSB (nur HmIP).
Hab gerade den Update gemacht, die hm_mode sieht jetzt anders aus (andere Reihenfolge) und hat den Stick direkt richtig erkannt.
Damit stimmt die GUI auch wieder. Ausserdem ist sie nun viel viel schneller. Vielen Dank!

Wie komme ich denn an deine Platine?