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 ...pmiller hat geschrieben: ↑23.01.2021, 18:11Servus!
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
debmatic Update 3.55.10
Moderator: Co-Administratoren
-
- Beiträge: 30
- Registriert: 14.09.2019, 13:01
- Hat sich bedankt: 1 Mal
- Danksagung erhalten: 1 Mal
Re: debmatic Update 3.55.10
- eiGelbGeek
- Beiträge: 979
- Registriert: 24.07.2014, 17:46
- Wohnort: Ruhrpottrandgebiet
- Hat sich bedankt: 105 Mal
- Danksagung erhalten: 19 Mal
Re: debmatic Update 3.55.10
Hast du einen HmIP-HAP als LAN-Router im Netzwerk eingebunden?
Nur weil es nicht geht, muss es nicht kaputt sein ^^
Apple for Work, Linux for Network, iOS for Mobility and still Windows for Solitaire
Apple for Work, Linux for Network, iOS for Mobility and still Windows for Solitaire
-
- Beiträge: 30
- Registriert: 14.09.2019, 13:01
- Hat sich bedankt: 1 Mal
- Danksagung erhalten: 1 Mal
Re: debmatic Update 3.55.10
Nein, das Gesamtsetup ist eine standalone-VM mit ausschließlich debmatic im Einsatz ohne Erweiterungen.eiGelbGeek hat geschrieben: ↑15.02.2021, 23:24Hast du einen HmIP-HAP als LAN-Router im Netzwerk eingebunden?
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
- Beiträge: 5396
- Registriert: 20.06.2017, 10:38
- System: Alternative CCU (auf Basis OCCU)
- Wohnort: Leimersheim
- Hat sich bedankt: 121 Mal
- Danksagung erhalten: 957 Mal
- Kontaktdaten:
Re: debmatic Update 3.55.10
Hi,
was steht in der /var/log/hmserver.log und der /var/log/syslog bzw. /var/log/messages?
Viele Grüße
Alex
was steht in der /var/log/hmserver.log und der /var/log/syslog bzw. /var/log/messages?
Viele Grüße
Alex
Re: debmatic Update 3.55.10
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!
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!
-
- Beiträge: 30
- Registriert: 14.09.2019, 13:01
- Hat sich bedankt: 1 Mal
- Danksagung erhalten: 1 Mal
Re: debmatic Update 3.55.10
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]
- deimos
- Beiträge: 5396
- Registriert: 20.06.2017, 10:38
- System: Alternative CCU (auf Basis OCCU)
- Wohnort: Leimersheim
- Hat sich bedankt: 121 Mal
- Danksagung erhalten: 957 Mal
- Kontaktdaten:
Re: debmatic Update 3.55.10
Hi,
Viele Grüße
Alex
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
-
- Beiträge: 30
- Registriert: 14.09.2019, 13:01
- Hat sich bedankt: 1 Mal
- Danksagung erhalten: 1 Mal
Re: debmatic Update 3.55.10
/var/log/hmserver.log
/var/log/syslog
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
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
- Beiträge: 5396
- Registriert: 20.06.2017, 10:38
- System: Alternative CCU (auf Basis OCCU)
- Wohnort: Leimersheim
- Hat sich bedankt: 121 Mal
- Danksagung erhalten: 957 Mal
- Kontaktdaten:
Re: debmatic Update 3.55.10
Hi,
mit unvollständigen Logs kann ich leider auch nichts anfangen.
Viele Grüße
Alex
mit unvollständigen Logs kann ich leider auch nichts anfangen.
Viele Grüße
Alex