debmatic Update 3.55.10

[RexRocker]

Hi,

Natürlich gleich mal den test mit der Version gemacht - aber leider: das gleiche Problem wie bei schon bei 3.55.5 beschrieben: alle paar Minuten startet das WebGUI durch.

Was meinst du genau damit? Der Webserver startet neu? du musst dich neu anmelden? Was ganz anderes? Ich kann mir da leider komplett nicht vorstellen, was du mit der Formulierung meinst.

Viele Grüße
Alex

Servus!

OK - war ein bischen gar schnell dahingetippt. Ich versuche es mal kurz zu umreißen:

- unsere debmatic war auf Firmware 3.53.34.59
- Update auf die testversion von 3.55.10 gemacht
- Rebootet
- Flott wieder da (yeah!
- GUI ist normal erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
- nach ein paar Minuten nicht mehr erreichbar und man sieht das klassische "DIe Homematic CCU ist noch nicht bereit"
- Dann ist die GUI wieder erreichbar
...

- wieder retour auf 3.53.34.59
- alles bestens

Ich hoff das ist jetzt ein bisserl nachvollziehbarer

Liebe Grüße aus Baden bei Wien
Peter

Der Vollständgikeit halber ... dasselbe Verhalten habe ich auch. Nachdem bei den Temperaturen gerade Heizen wichtiger ist als Fehleranalyse habe ich erst mal den VM Snapshot zurückgerollt ...

[eiGelbGeek]

Der Vollständgikeit halber ... dasselbe Verhalten habe ich auch. Nachdem bei den Temperaturen gerade Heizen wichtiger ist als Fehleranalyse habe ich erst mal den VM Snapshot zurückgerollt ...

Hast du einen HmIP-HAP als LAN-Router im Netzwerk eingebunden?

[RexRocker]

Hast du einen HmIP-HAP als LAN-Router im Netzwerk eingebunden?

Nein, das Gesamtsetup ist eine standalone-VM mit ausschließlich debmatic im Einsatz ohne Erweiterungen.

Ich dachte ja zuerst dass es daran liegt dass ich beim Patchen der Kiste den Snapshots versehentlich mit Memory->Disk Mapping habe laufen lassen, so nach dem Motto die Platten kommen nicht hinterher. Aber im zweiten Anlauf ohne Memory Snapshot dasselbe Verhalten in grün.

[deimos]

Hi,

was steht in der /var/log/hmserver.log und der /var/log/syslog bzw. /var/log/messages?

Viele Grüße
Alex

[RexRocker]

Das muss ich schauen wenn ich den Upgrade nochmal teste im dritten Anlauf. Weiterhin heizen war mir vorerst wichtiger

[rterlien]

My system also started to experience issues after the upgrade to version 3.55.10

I removed the HMIP-HAP as suggested somewhere on this forum but no luck. Symptoms are:

- Devices lose connection and or respond slowly to commands given
- Consistently high duty cycle (ca. 20%)
- High number of system messages "communication disturbed"

I tried to roll back to 3.53 but get the message this is no longer available. Any advice would be much appreciated!

[RexRocker]

Hi,

was steht in der /var/log/hmserver.log und der /var/log/syslog bzw. /var/log/messages?

Viele Grüße
Alex

Das Einzige was ich in besagten Logfiles als Fehlermeldung identifizieren kann ist das hier:

Code: Alles auswählen

Feb 17 11:49:37 rexrockerknx ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"002018A99D1A56:1","PARTY_TIME_START"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1151, params: {"002018A99D1A56:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Feb 17 11:49:37 rexrockerknx ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"002018A99D1A56:1","PARTY_TIME_END"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1151, params: {"002018A99D1A56:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]

Alles andere ist mit INFO geflagged oder mMn irrelevant. Habe die Outputs aber bei Bedarf noch da.

[deimos]

Hi,

Alles andere ist mit INFO geflagged oder mMn irrelevant. Habe die Outputs aber bei Bedarf noch da.

Wenn ich keinen Bedarf daran gehabt hätte, dann hätte ich auch nicht danach gefragt bzw. gleich nach gefilterten Daten gefragt.

Viele Grüße
Alex

[RexRocker]

/var/log/hmserver.log

Code: Alles auswählen

Feb 17 11:46:05 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-10] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
Feb 17 11:46:16 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:31999
Feb 17 11:46:16 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-3] init finished
Feb 17 11:46:16 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-4] Added InterfaceId: 1151
Feb 17 11:47:46 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
Feb 17 11:47:46 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] Added InterfaceId: 1007
Feb 17 11:47:46 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-11] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
Feb 17 11:47:57 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-4] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:31999
Feb 17 11:47:57 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-1] init finished
Feb 17 11:47:57 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-2] Added InterfaceId: 1151
Feb 17 11:49:26 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
Feb 17 11:49:26 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-5] Added InterfaceId: 1007
Feb 17 11:49:27 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-4] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand
Feb 17 11:49:37 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:31999
Feb 17 11:49:37 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-4] Added InterfaceId: 1151
Feb 17 11:49:37 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-0] init finished

/var/log/syslog

Code: Alles auswählen

Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$23/843233087.handle(Unknown Source)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.impl.ContextImpl$$Lambda$13/310751369.run(Unknown Source)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 17 11:49:35 rexrockerknx start_hmserver.sh[160621]: #011at java.lang.Thread.run(Thread.java:748)
Feb 17 11:49:35 rexrockerknx multipathd[635]: sda: add missing path
Feb 17 11:49:35 rexrockerknx multipathd[635]: sda: failed to get udev uid: Invalid argument
Feb 17 11:49:35 rexrockerknx multipathd[635]: sda: failed to get sysfs uid: Invalid argument
Feb 17 11:49:35 rexrockerknx multipathd[635]: sda: failed to get sgio uid: No such file or directory
Feb 17 11:49:36 rexrockerknx ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"002018A9A43B50:1","VALVE_ADAPTION"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: VALVE_ADAPTION"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Feb 17 11:49:36 rexrockerknx ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1151, params: {"002018A9A43B50:1","VALVE_ADAPTION"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb 17 11:49:36 rexrockerknx ReGaHss: ERROR: CallGetValue failed; sVal = 0 [ReadValue():iseDOMdpHSS.cpp:124]
Feb 17 11:49:36 rexrockerknx ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"002018A99C23F0:1","PARTY_SET_POINT_TEMPERATURE"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_SET_POINT_TEMPERATURE"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Feb 17 11:49:36 rexrockerknx ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1151, params: {"002018A99C23F0:1","PARTY_SET_POINT_TEMPERATURE"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb 17 11:49:36 rexrockerknx ReGaHss: ERROR: CallGetValue failed; sVal = 0.000000 [ReadValue():iseDOMdpHSS.cpp:124]
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: Feb 17, 2021 11:49:36 AM io.vertx.core.impl.BlockedThreadChecker
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: WARNING: Thread Thread[vert.x-eventloop-thread-5,5,main] has been blocked for 9741 ms, time limit is 2000
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: io.vertx.core.VertxException: Thread blocked
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at sun.misc.Unsafe.park(Native Method)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega.registerCallback(VirtualDeviceHandlerRega.java:99)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at java.lang.reflect.Method.invoke(Method.java:498)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at de.eq3.cbcs.legacy.communication.rpc.internal.rpc.AnnotationAwareRpcHandler.execute(AnnotationAwareRpcHandler.java:80)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:70)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at de.eq3.ccu.server.internal.RpcMessageHandler.handle(RpcMessageHandler.java:24)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.deliver(HandlerRegistration.java:212)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.HandlerRegistration.handle(HandlerRegistration.java:191)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.EventBusImpl.lambda$deliverToHandler$3(EventBusImpl.java:505)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.eventbus.impl.EventBusImpl$$Lambda$23/843233087.handle(Unknown Source)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.impl.ContextImpl.lambda$wrapTask$2(ContextImpl.java:337)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.vertx.core.impl.ContextImpl$$Lambda$13/310751369.run(Unknown Source)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.util.concurrent.AbstractEventExecutor.safeExecute(AbstractEventExecutor.java:163)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.util.concurrent.SingleThreadEventExecutor.runAllTasks(SingleThreadEventExecutor.java:403)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:445)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at io.netty.util.concurrent.SingleThreadEventExecutor$5.run(SingleThreadEventExecutor.java:858)
Feb 17 11:49:36 rexrockerknx start_hmserver.sh[160621]: #011at java.lang.Thread.run(Thread.java:748)
Feb 17 11:49:37 rexrockerknx ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"002018A99D1A56:1","PARTY_TIME_START"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_START"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1151, params: {"002018A99D1A56:1","PARTY_TIME_START"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Feb 17 11:49:37 rexrockerknx ReGaHss: WARNING: XMLRPC 'getValue': rpcClient.isFault() failed (url: xmlrpc://127.0.0.1:32010, params: {"002018A99D1A56:1","PARTY_TIME_END"}, result: [faultCode:-5,faultString:"Unknown Parameter value for value key: PARTY_TIME_END"]) [CallXmlrpcMethod():iseXmlRpc.cpp:2608]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: XMLRPC 'getValue' call failed (interface: 1151, params: {"002018A99D1A56:1","PARTY_TIME_END"}) [CallGetValue():iseXmlRpc.cpp:1435]
Feb 17 11:49:37 rexrockerknx ReGaHss: ERROR: CallGetValue failed; sVal =  [ReadValue():iseDOMdpHSS.cpp:124]
Feb 17 11:49:38 rexrockerknx : /sys/class/leds/rpi_rf_mod:red/trigger kann nicht zugegriffen werden
Feb 17 11:49:38 rexrockerknx : /sys/class/leds/rpi_rf_mod:green/trigger kann nicht zugegriffen werden
Feb 17 11:49:38 rexrockerknx : /sys/class/leds/rpi_rf_mod:blue/trigger kann nicht zugegriffen werden
Feb 17 11:49:40 rexrockerknx multipathd[635]: sda: add missing path
Feb 17 11:49:40 rexrockerknx multipathd[635]: sda: failed to get udev uid: Invalid argument
Feb 17 11:49:40 rexrockerknx multipathd[635]: sda: failed to get sysfs uid: Invalid argument
Feb 17 11:49:40 rexrockerknx multipathd[635]: sda: failed to get sgio uid: No such file or directory

[deimos]

Hi,

mit unvollständigen Logs kann ich leider auch nichts anfangen.

Viele Grüße
Alex