debmatic Update 3.69.7-107

[basty2]

Hallo. Leider stürzt bei mir debmatic nun regelmäßig nach einiger Zeit ab. Es hilft nur ein Restart des Service, dann stürzt es nach einiger Zeit ab.
Es hat schon ca. 1,5 Jahre gut funktioniert. Ich habe das System und debmatic geupdatet (apt update & upgrade).
Ich nutze Proxmox und dort eine VM.
Hier die letzten Einträge im Log

2023-08-05 11:50:58,753 io.vertx.core.impl.ContextImpl ERROR [vert.x-worker-thread-10] Unhandled exception
de.eq3.cbcs.legacy.communication.rpc.RpcIOException: java.net.ConnectException: Connection refused (Connection refused)
at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:110) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.sendRequest(RpcClient.java:94) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.invoke(RpcClient.java:82) ~[HMIPServer.jar:?]
at com.sun.proxy.$Proxy40.event(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker.handleEvent(RegaClientWorker.java:73) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker.access$000(RegaClientWorker.java:13) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker$1.handle(RegaClientWorker.java:35) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker$1.handle(RegaClientWorker.java:26) ~[HMIPServer.jar:?]
at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:100) ~[HMIPServer.jar:?]
at io.vertx.core.impl.WorkerContext.lambda$emit$0(WorkerContext.java:59) ~[HMIPServer.jar:?]
at io.vertx.core.impl.WorkerContext.lambda$execute$2(WorkerContext.java:104) ~[HMIPServer.jar:?]
at io.vertx.core.impl.TaskQueue.run(TaskQueue.java:76) ~[HMIPServer.jar:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[?:1.8.0_382]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[?:1.8.0_382]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_382]
Caused by: java.net.ConnectException: Connection refused (Connection refused)
at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:1.8.0_382]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:1.8.0_382]
at java.net.Socket.connect(Socket.java:607) ~[?:1.8.0_382]
at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:120) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:179) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:144) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:134) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:612) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:447) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:884) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:106) ~[HMIPServer.jar:?]
... 15 more
2023-08-05 11:50:58,759 io.vertx.core.impl.ContextImpl ERROR [vert.x-worker-thread-10] Unhandled exception
de.eq3.cbcs.legacy.communication.rpc.RpcIOException: java.net.ConnectException: Connection refused (Connection refused)
at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:110) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.sendRequest(RpcClient.java:94) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.invoke(RpcClient.java:82) ~[HMIPServer.jar:?]
at com.sun.proxy.$Proxy40.event(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker.handleEvent(RegaClientWorker.java:73) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker.access$000(RegaClientWorker.java:13) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker$1.handle(RegaClientWorker.java:35) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker$1.handle(RegaClientWorker.java:26) ~[HMIPServer.jar:?]
at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:100) ~[HMIPServer.jar:?]
at io.vertx.core.impl.WorkerContext.lambda$emit$0(WorkerContext.java:59) ~[HMIPServer.jar:?]
at io.vertx.core.impl.WorkerContext.lambda$execute$2(WorkerContext.java:104) ~[HMIPServer.jar:?]
at io.vertx.core.impl.TaskQueue.run(TaskQueue.java:76) ~[HMIPServer.jar:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[?:1.8.0_382]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[?:1.8.0_382]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_382]
Caused by: java.net.ConnectException: Connection refused (Connection refused)
at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:1.8.0_382]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:1.8.0_382]
at java.net.Socket.connect(Socket.java:607) ~[?:1.8.0_382]
at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:120) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:179) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:144) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:134) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:612) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:447) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:884) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:106) ~[HMIPServer.jar:?]
... 15 more
2023-08-05 11:50:58,764 io.vertx.core.impl.ContextImpl ERROR [vert.x-worker-thread-10] Unhandled exception
de.eq3.cbcs.legacy.communication.rpc.RpcIOException: java.net.ConnectException: Connection refused (Connection refused)
at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:110) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.sendRequest(RpcClient.java:94) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.RpcClient.invoke(RpcClient.java:82) ~[HMIPServer.jar:?]
at com.sun.proxy.$Proxy40.event(Unknown Source) ~[?:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker.handleEvent(RegaClientWorker.java:73) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker.access$000(RegaClientWorker.java:13) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker$1.handle(RegaClientWorker.java:35) ~[HMIPServer.jar:?]
at de.eq3.ccu.virtualdevice.service.internal.rega.vertx.RegaClientWorker$1.handle(RegaClientWorker.java:26) ~[HMIPServer.jar:?]
at io.vertx.core.impl.AbstractContext.dispatch(AbstractContext.java:100) ~[HMIPServer.jar:?]
at io.vertx.core.impl.WorkerContext.lambda$emit$0(WorkerContext.java:59) ~[HMIPServer.jar:?]
at io.vertx.core.impl.WorkerContext.lambda$execute$2(WorkerContext.java:104) ~[HMIPServer.jar:?]
at io.vertx.core.impl.TaskQueue.run(TaskQueue.java:76) ~[HMIPServer.jar:?]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) ~[?:1.8.0_382]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) ~[?:1.8.0_382]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
at java.lang.Thread.run(Thread.java:750) ~[?:1.8.0_382]
Caused by: java.net.ConnectException: Connection refused (Connection refused)
at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206) ~[?:1.8.0_382]
at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188) ~[?:1.8.0_382]
at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392) ~[?:1.8.0_382]
at java.net.Socket.connect(Socket.java:607) ~[?:1.8.0_382]
at org.apache.http.conn.scheme.PlainSocketFactory.connectSocket(PlainSocketFactory.java:120) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.DefaultClientConnectionOperator.openConnection(DefaultClientConnectionOperator.java:179) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.AbstractPoolEntry.open(AbstractPoolEntry.java:144) ~[HMIPServer.jar:?]
at org.apache.http.impl.conn.AbstractPooledConnAdapter.open(AbstractPooledConnAdapter.java:134) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.DefaultRequestDirector.tryConnect(DefaultRequestDirector.java:612) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:447) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:884) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:107) ~[HMIPServer.jar:?]
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:55) ~[HMIPServer.jar:?]
at de.eq3.cbcs.legacy.communication.rpc.internal.transport.http.HttpTransport.sendRequest(HttpTransport.java:106) ~[HMIPServer.jar:?]
... 15 more

Debmatic Info ergibt:

debmatic version: 3.69.7-108
OS: Ubuntu 22.04.3 LTS
Kernel: 5.15.0-78-generic x86_64
Service Status: Running
Kernel modules: Available
Raw UART dev: Available
HMRF Hardware: RPI-RF-MOD
Connected via: HB-RF-USB@usb-0000:01:1b.0-1 (/dev/raw-uart)
Board serial: 9D89A98030
Radio MAC: 0x769804
HMIP Hardware: RPI-RF-MOD
Connected via: HB-RF-USB@usb-0000:01:1b.0-1 (/dev/raw-uart)
SGTIN: 3014F711A0001F9D89A98030
Radio MAC: 0xB90BEE

Ich bin mir unsicher, ob und wie ich wieder downgraden sollte.

[deimos]

Hi,

dir fehlen die Kernel Module. Was liefert "sudo dpkg-reconfigure pivccu-modules-dkms"?

Viele Grüße
Alex

[basty2]

Hi,

den Befehl habe ich schon öfters durchlaufen lassen.

Disabled DKMS service ... Done
Remove obsolete kernel modules ... Done
Create kernel modules ... Done
Enable DKMS service ... Done

Dann soll ich rebooten.

Habe ich gemacht, hilft leider nicht. Nach einer gewissen Zeit ist die Weboberfläche (+ Einbindung in IOBROKER) nicht mehr erreichbar. Laut Service läuft es noch, aber eben nicht mehr erreichbar.

sudo systemctl status debmatic.service
● debmatic.service - debmatic
Loaded: loaded (/lib/systemd/system/debmatic.service; enabled; vendor pres>
Active: active (exited) since Sat 2023-08-05 23:18:29 CEST; 9h ago
Main PID: 687 (code=exited, status=0/SUCCESS)
CPU: 660ms

Aug 05 23:18:24 vm2-debmatic systemd[1]: Starting debmatic...
Aug 05 23:18:29 vm2-debmatic systemd[1]: Finished debmatic.

[deimos]

Hi,

das dpkg-reconfigure bezog sich auf @HitTheDrum

Viele Grüße
Alex

[deimos]

Hi,

@basty2: Das ist nur das Log vom HmIPServer. Für mich klingt es danach, dass die Rega abraucht, das ist ein eigener Service. debmatic ist nur ein Metaservice, der die ganzen Einzelservices startet.

Viele Grüße
Alex

[basty2]

@deimos
Kann ich etwas machen um das zu verhindern?

[HitTheDrum]

Hi,

dir fehlen die Kernel Module. Was liefert "sudo dpkg-reconfigure pivccu-modules-dkms"?

Viele Grüße
Alex

Hi Alex,

dabei erhalte ich folgende Meldungen/Fehler:

Code: Alles auswählen

Disabled DKMS service ... Done
Remove obsolete kernel modules ... Done
Create kernel modules ... FAILED
Check kernel headers ... Done
Prepare kernel headers ... Done
Install DKMS package ... Done
Try to load fresh build modules ... FAILED
modprobe: ERROR: could not insert 'generic_raw_uart': Exec format error
Enable DKMS service ... Done

Interessant, zuvor schien es kein Problem zu geben: Gleiche Maschine, nochmal per Backup-restore geprüft, wie vorher der Stand war:

Code: Alles auswählen

debmatic version ： 3.67.10.102
Kernet modutes ： Available
Raw UART dev ： Available
HMRF Hardware ：HMIP-RFUSB
  Connected via ：eQ-3 HmIP-RFUSB@usb-vhci_hcd.0-1 (/dev/raw-uart)
  Board sertat: 1F299EDOE5
  Radio MAC: OxF....
HMIP Hardware ：HMIP-RFUSB
  Connected via ：eQ-3 HmIP-RFUSB@usb-vhci hcd.O-1 (/dev/raw-uart)
  SGTIN ：3014......
  Radio MAC ：Ox....

Komischerweise traten die FAILED Meldungen nicht auf, als ich erneut installiert hatte, aber danach immer. (ich meine aber dabei wurden/werden auch kernel-modules created?)
BG

[deimos]

Hi,

@HitTheDrum: Das klingt nach kaputten Kernel Headern, die insb. der Ubuntu Installer immer mal wieder hinbekommt.

Code: Alles auswählen

sudo apt install --reinstall linux-headers-`uname -r`
sudo apt-mark auto linux-headers-`uname -r`
sudo dpkg-reconfigure pivccu-modules-dkms

und ein anschließender Reboot sollten das wieder beheben.

Viele Grüße
Alex

[basty2]

@deimos

Ich habe mal eine neue VM erstellt und alles sauber neu installiert. Leider bleibt das gleiche Problem, nachdem ich das Backup eingespielt habe. Es ist komisch, da es das letzte Jahr gut funktioniert hat.
Irgendeine Idee?

[HitTheDrum]

Hi,

@HitTheDrum: Das klingt nach kaputten Kernel Headern, die insb. der Ubuntu Installer immer mal wieder hinbekommt.

Code: Alles auswählen

sudo apt install --reinstall linux-headers-`uname -r`
sudo apt-mark auto linux-headers-`uname -r`
sudo dpkg-reconfigure pivccu-modules-dkms

und ein anschließender Reboot sollten das wieder beheben.

Viele Grüße
Alex

Das wars! Super, thx! Gutschein ist raus


BG!