Homematic Manager 2.x

[HMNutzer]

Wenn Du mit System meinen PC meinst der bootet täglich; wenn Du meine RM meinst die läuft normalerweise wochenlang durch.
Aktuell zwar erst seit 3 Wochen am dauerlaufen (Dusseligkeit des Nutzers), aber ansonsten nur Neustarts bei Update.

[McPan]

Dann bootest Du Dein System "häufig" (< 4 Wochen). Bei mir war es so, dass dem System nach ca. 4 Wochen Laufzeit der Speicher ausging, weil die CCU die gecacheten Daten nicht losgeworden ist.

Kann ich so in der Art bestätigen. Die Version 2.7.1 bleibt bei mit mit dem blauen Ladebalken hängen und zeigt keine Geräte an. Da bleibt dann im Anschluss wohl auf der Raspimatic Prozess mit einer Verbindung offen, welche in regelmäßigen Abständen (5-10 Minuten) mit dem Gerät, auf welchem der Homematic-Manager gestartet wurde, kommunizeren will. Das Ganze geht dann solange, bis die Fehlerlogs dann den Speicher zugemmüllt haben.

Der Effekt war dann hier eigentlich immer so, dass die Raspimatic zwar noch problemlos per WebUI oder SSH erreichbar war, aber keine Programme mehr ausgeführt wurden, da der HMServer streikte.

Ein paar Fehleranhänsel anbei.

hmserver.log

Code: Alles auswählen

...
2025-06-27 07:34:45,082 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] Jun 27, 2025 7:34:45 AM org.apache.http.impl.client.DefaultHttpClient tryConnect 
2025-06-27 07:34:45,082 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] INFO: I/O exception (java.net.NoRouteToHostException) caught when connecting to {}->http://192.168.178.123:2000: No route to host (Host unreachable) 
2025-06-27 07:34:45,083 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] Jun 27, 2025 7:34:45 AM org.apache.http.impl.client.DefaultHttpClient tryConnect 
2025-06-27 07:34:45,083 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] INFO: Retrying connect to {}->http://192.168.178.123:2000 
2025-06-27 07:34:48,154 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] Jun 27, 2025 7:34:48 AM org.apache.http.impl.client.DefaultHttpClient tryConnect 
2025-06-27 07:34:48,154 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] INFO: I/O exception (java.net.NoRouteToHostException) caught when connecting to {}->http://192.168.178.123:2000: No route to host (Host unreachable) 
2025-06-27 07:34:48,154 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] Jun 27, 2025 7:34:48 AM org.apache.http.impl.client.DefaultHttpClient tryConnect 
2025-06-27 07:34:48,154 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] INFO: Retrying connect to {}->http://192.168.178.123:2000 
2025-06-27 07:34:51,226 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] Jun 27, 2025 7:34:51 AM org.apache.http.impl.client.DefaultHttpClient tryConnect 
2025-06-27 07:34:51,226 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] INFO: I/O exception (java.net.NoRouteToHostException) caught when connecting to {}->http://192.168.178.123:2000: No route to host (Host unreachable) 
2025-06-27 07:34:51,226 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] Jun 27, 2025 7:34:51 AM org.apache.http.impl.client.DefaultHttpClient tryConnect 
2025-06-27 07:34:51,226 de.eq3.cbcs.util.EQ3LoggerOutputStream ERROR [vert.x-worker-thread-17] INFO: Retrying connect to {}->http://192.168.178.123:2000 
2025-06-27 07:34:54,297 io.vertx.core.impl.ContextImpl ERROR [vert.x-worker-thread-17] Unhandled exception 
de.eq3.cbcs.legacy.communication.rpc.RpcIOException: java.net.NoRouteToHostException: No route to host (Host unreachable)
	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$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(Unknown Source) ~[?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) ~[?:?]
	at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[HMIPServer.jar:?]
	at java.lang.Thread.run(Unknown Source) ~[?:?]
Caused by: java.net.NoRouteToHostException: No route to host (Host unreachable)
	at java.net.PlainSocketImpl.socketConnect(Native Method) ~[?:?]
	at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source) ~[?:?]
	at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source) ~[?:?]
	at java.net.AbstractPlainSocketImpl.connect(Unknown Source) ~[?:?]
	at java.net.SocksSocketImpl.connect(Unknown Source) ~[?:?]
	at java.net.Socket.connect(Unknown Source) ~[?:?]
	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:?]
...

Das obige wiederholt sich dann in dieser Form in 5-Minuten-Abständen, bis Speicher voll. Die im Log aufgeführte IP 192.168.178.123 ist das Gerät, auf dem der Homematic-Manager gestartet wurde. Hatte da spaßeshalber mal die Probe auf's Exempel gemacht, die Fehlermeldungen tauchen erst auf, nachdem der Homematic-Manager gestartet wurde.

syslog:

Code: Alles auswählen

...
Jun 30 03:15:17 hm-rpi user.err monit[2171]: 'HMIPServer' service restarted 1 times within 1 cycles(s) - exec
Jun 30 03:15:17 hm-rpi user.info monit[2171]: 'HMIPServer' exec: '/bin/triggerAlarm.tcl HMIPServer restarted WatchDog: hmipserver-restart true'
Jun 30 03:15:17 hm-rpi user.info monit[2171]: 'HMIPServer' process is running after previous restart timeout (manually recovered?)
Jun 30 03:15:17 hm-rpi user.err monit[2171]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Jun 30 03:15:17 hm-rpi user.info monit[2171]: 'HMIPServer' trying to restart
Jun 30 03:15:17 hm-rpi user.info monit[2171]: 'ccu-historian' stop: '/etc/config/rc.d/ccu-historian stop'
Jun 30 03:15:17 hm-rpi user.debug ccu-historian: Stopping
Jun 30 03:15:35 hm-rpi kern.info kernel: [1237614.192714] eq3loop: eq3loop_write_master() return error: -14
Jun 30 03:15:35 hm-rpi kern.err kernel: [1237684.541650] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 29
Jun 30 03:15:47 hm-rpi user.err monit[2171]: 'ccu-historian' failed to stop (exit status -1) -- Program '/etc/config/rc.d/ccu-historian stop' timed out after 30 s
Jun 30 03:16:01 hm-rpi kern.info kernel: [1237684.541694] eq3loop: eq3loop_write_master() return error: -14
Jun 30 03:16:01 hm-rpi kern.err kernel: [1237710.198350] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 40
Jun 30 03:16:05 hm-rpi user.err monit[2171]: 'HMIPServer' failed protocol test [DEFAULT] at [localhost]:39292 [TCP/IP] -- Connection refused
Jun 30 03:16:05 hm-rpi user.info monit[2171]: 'HMIPServer' trying to restart
Jun 30 03:16:05 hm-rpi user.info monit[2171]: 'ccu-historian' stop: '/etc/config/rc.d/ccu-historian stop'
Jun 30 03:16:05 hm-rpi user.debug ccu-historian: Stopping
...

kernel.log:

Code: Alles auswählen

...
<6>[1231937.404948] eq3loop: eq3loop_write_master() return error: -14
<3>[1231976.652705] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 39
<6>[1231976.652738] eq3loop: eq3loop_write_master() return error: -14
<3>[1232005.937744] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 45
<6>[1232005.937777] eq3loop: eq3loop_write_master() return error: -14
<3>[1232009.719934] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 39
<6>[1232009.719965] eq3loop: eq3loop_write_master() return error: -14
<3>[1232045.675212] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 40
<6>[1232045.675245] eq3loop: eq3loop_write_master() return error: -14
<3>[1232062.712585] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 37
<6>[1232062.712619] eq3loop: eq3loop_write_master() return error: -14
<3>[1232107.504322] eq3loop: eq3loop_write_master() mmd_hmip: not enough space in buffers. free space = 6, required space = 39
<6>[1232107.504357] eq3loop: eq3loop_write_master() return error: -14
...

Nutze das Tool allerdings auch nicht mehr, hier nur zur Dokumentation.

[jp112sdl]

Bei mir war es so, dass dem System nach ca. 4 Wochen Laufzeit der Speicher ausging, weil die CCU die gecacheten Daten nicht losgeworden ist.

Kommt das evtl. davon?
https://github.com/eq-3/occu/issues/103
viewtopic.php?f=65&t=53432#p532094

Ich hab noch dunkel in Erinnerung, dass der HMIPServer immer rumzickt, wenn eine RPC Verbindung "unerwartet" beendet wird.
Andere Prozesse (z.B. RFD) machen die dann einfach nach nem Timeout zu. Aber der HMIPServer nicht. So war es mal. Ob es noch so ist kann ich nicht sagen

[Xel66]

Ja, meine ich. Ich habe auch irgendwo ein Script (gefunden), welches die offenen Schnittstellenprozesse auswirft und meine damals was gesehen zu haben. Da ich den HM schon lange wegen dieser Problematik nicht mehr nutze, habe ich das auch nicht weiter verfolgt. Will auch aktuell nicht testen, da ich gerade ein Geräteupdate übertrage und bei einem ggf. notwendigen Reboot wieder von vorn anfangen müsste.

Gruß Xel66

[Silverstar]

[...] irgendwo ein Script (gefunden), welches die offenen Schnittstellenprozesse auswirft [...]

viewtopic.php?f=31&t=62096&p=614148#p614148