search

mdzio / ccu-jack

CCU-Jack bietet einen einfachen und sicheren REST- und MQTT-basierten Zugriff auf die Datenpunkte der Zentrale (CCU) des Hausautomations-Systems HomeMatic. Zudem können einfach Fremdgeräte an die CCU angebunden werden.
GNU General Public License v3.0
112 stars 11 forks source link

"Verschluckte Events" und Fehlermeldungen bei Kanal: MQTT Energiemessung #155

Closed Baxxy13 closed 7 months ago

Baxxy13 commented 8 months ago

Hallo Mathias, da mein Jack in letzter Zeit sporadisch aber doch recht häufig ERROR - geflaggte Fehlermeldungen raushaut habe ich mich mal drangesetzt um das zu untersuchen.

Die Fehlermeldungen sind immer gleich:

2023-11-06 14:27:21 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF -- | -- | -- | -- 2023-11-06 14:27:21 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:25:19 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:25:19 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:23:17 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:23:17 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:20:14 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:20:14 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:18:12 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:18:12 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF

"Verursacher" ist eine Tasmota Schaltsteckdose die an einen HM-ES-PMSw1-Pl mit den Kanälen...

Alles nach Anleitung eingerichtet und augenscheinlich auch funktional. Bis auf die Aussetzer.

Um das Setup zu vereinfachen habe ich nur den Schaltaktorkanal (komplett) sowie den Energiemesskanal (nur ENERGY_COUNTER) angebunden. Jack_PMS_Tasmota_Settings

TOPIC: tasmota/tele/tasmota_990BD7/SENSOR

TEMPLATE für den ENERGY_COUNTER: {{ mul (parseJSON .).ENERGY.Total 1000 }}

Beispiel-Topic: SENSOR = {"Time":"2023-11-06T14:39:33","ENERGY":{"TotalStartTime":"2022-12-15T15:57:20","Total":0.165,"Yesterday":0.000,"Today":0.033,"Period":1,"Power":43,"ApparentPower":43,"ReactivePower":0,"Factor":1.00,"Voltage":232,"Current":0.184}}

Fehleranalyse:

Die Tasmota-Dose sendet alle 61s und die Daten kommen auch beim Jack an (mit dem MQTT-Explorer "überwacht"). Den Messwertkanal habe ich in der CCU auf protokolliert gestellt. Man sieht im Systemprotokoll zeitliche Lücken, also fehlende Events. Genau zum Zeitpunkt der fehlenden Events gibt es dann entsprechend die ERROR-Meldungen.

Systemprotokoll:

14:22:16 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 152.00Wh -- | -- | -- | -- 14:24:18 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 154.00Wh 14:26:20 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 155.00Wh 14:28:22 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 157.00Wh 14:29:23 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 157.00Wh 14:30:24 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 158.00Wh 14:31:25 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 159.00Wh 14:33:27 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 160.00Wh 14:35:29 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 162.00Wh 14:37:31 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 163.00Wh 14:39:33 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 165.00Wh 14:41:35 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 166.00Wh 14:43:37 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 168.00Wh 14:45:39 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 169.00Wh 14:46:40 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 170.00Wh 14:48:42 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 171.00Wh

Logauszug zeitlich passend:

Zeitstempel | Dringlichkeit | Modul | Meldung -- | -- | -- | -- 2023-11-06 14:47:41 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:46:40 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:44:38 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:44:38 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:42:36 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:42:36 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:40:34 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:40:34 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:38:32 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:38:32 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:36:30 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:36:30 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:34:28 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:33:27 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:32:26 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:31:25 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:27:21 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:27:21 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:25:19 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-06 14:25:19 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-06 14:23:17 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF

Bei einem testweise angelegtem MQTT-Analogwertempfänger tritt das Problem genauso auf.

Testsystem ist ein Pi3B+ mit RaspberryMatic 3.71.12.20231020 Viel los ist auf dem System nicht, 3 IP und 3 HM Geräte sind angelernt, dazu ein paar kleine Testprogramme.

Wenn du weitere Info's brauchst gib bitte Bescheid, auch wenn ich irgendwas testen soll.

Danke... Baxxy

mdzio commented 8 months ago

Dann bitte einmal das Log auf TRACE stellen und die Meldungen vor und nach dem Auftreten der Meldung EOF mit aufzeichnen. Wenn die Meldung über die Web-UI des Jacks nicht erwischt wird, in die Log-Datei /var/log/ccu-jack.log schauen.

Baxxy13 commented 8 months ago

Ich hab jetzt nochmal alles neu gemacht (Jack-Geräte gelöscht, Jack deinstalliert, Reboot, Jack installiert, Reboot und Jack Gerät wieder angelegt) und bisher keine EOF Meldungen mehr gehabt. Behalte das aber im Auge um das Log abzugreifen wenn es wieder auftreten sollte.

Baxxy13 commented 8 months ago

Die Ruhe hat nicht lange angehalten:

2023-11-06 17:16:58|TRACE  |xmlrpc-server  |Request received from 127.0.0.1:47670, URI /RPC3
2023-11-06 17:16:58|TRACE  |xmlrpc-server  |Request XML: <?xml version="1.0" encoding="iso-8859-1"?>\n<methodCall><methodName>system.listMethods</methodName>\n<params></params></methodCall>\n
2023-11-06 17:16:58|DEBUG  |xmlrpc-server  |Call of method system.listMethods received
2023-11-06 17:16:58|TRACE  |xmlrpc-server  |Response XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodResponse><params><param><value><array><data><value>system.multicall</value><value>getValue</value><value>reportValueUsage</value><value>firmwareUpdateStatusChanged</value><value>init</value><value>setValue</value><value>ping</value><value>getDeviceDescription</value><value>getParamsetDescription</value><value>getParamset</value><value>putParamset</value><value>system.listMethods</value><value>listDevices</value><value>deleteDevice</value><value>system.methodHelp</value><value>getLinks</value><value>getParamsetId</value></data></array></value></param></params></methodResponse>
2023-11-06 17:16:59|DEBUG  |virt-dev       |Message for JACK000005:2.ENERGY_COUNTER received: tasmota/tele/tasmota_990BD7/SENSOR, {"Time":"2023-11-06T17:16:58","ENERGY":{"TotalStartTime":"2022-12-15T15:57:20","Total":0.276,"Yesterday":0.000,"Today":0.144,"Period":1,"Power":42,"ApparentPower":42,"ReactivePower":0,"Factor":1.00,"Voltage":231,"Current":0.183}}
2023-11-06 17:16:59|TRACE  |v-devices      |Publishing event: JACK000005:2, ENERGY_COUNTER, 276
2023-11-06 17:16:59|TRACE  |mqtt-server    |Publishing virtdev/status/JACK000005/2/ENERGY_COUNTER: {"ts":1699287419424,"v":276,"s":0}
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(CCU-Jack_java, JACK000005:2, ENERGY_COUNTER, 276) on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(8037, JACK000005:2, ENERGY_COUNTER, 276) on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>CCU-Jack_java</value></param><param><value>JACK000005:2</value></param><param><value>ENERGY_COUNTER</value></param><param><value><double>276</double></value></param></params></methodCall>
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:1999
2023-11-06 17:16:59|DEBUG  |virt-dev       |Message for JACK000005:2.POWER received: tasmota/tele/tasmota_990BD7/SENSOR, {"Time":"2023-11-06T17:16:58","ENERGY":{"TotalStartTime":"2022-12-15T15:57:20","Total":0.276,"Yesterday":0.000,"Today":0.144,"Period":1,"Power":42,"ApparentPower":42,"ReactivePower":0,"Factor":1.00,"Voltage":231,"Current":0.183}}
2023-11-06 17:16:59|TRACE  |v-devices      |Publishing event: JACK000005:2, POWER, 42
2023-11-06 17:16:59|TRACE  |mqtt-server    |Publishing virtdev/status/JACK000005/2/POWER: {"ts":1699287419425,"v":42,"s":0}
2023-11-06 17:16:59|DEBUG  |virt-dev       |Message for JACK000005:2.CURRENT received: tasmota/tele/tasmota_990BD7/SENSOR, {"Time":"2023-11-06T17:16:58","ENERGY":{"TotalStartTime":"2022-12-15T15:57:20","Total":0.276,"Yesterday":0.000,"Today":0.144,"Period":1,"Power":42,"ApparentPower":42,"ReactivePower":0,"Factor":1.00,"Voltage":231,"Current":0.183}}
2023-11-06 17:16:59|TRACE  |v-devices      |Publishing event: JACK000005:2, CURRENT, 183
2023-11-06 17:16:59|TRACE  |mqtt-server    |Publishing virtdev/status/JACK000005/2/CURRENT: {"ts":1699287419427,"v":183,"s":0}
2023-11-06 17:16:59|ERROR  |v-devices      |Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>8037</value></param><param><value>JACK000005:2</value></param><param><value>ENERGY_COUNTER</value></param><param><value><double>276</double></value></param></params></methodCall>
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(CCU-Jack_java, JACK000005:2, POWER, 42) on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>CCU-Jack_java</value></param><param><value>JACK000005:2</value></param><param><value>POWER</value></param><param><value><double>42</double></value></param></params></methodCall>
2023-11-06 17:16:59|DEBUG  |virt-dev       |Message for JACK000005:2.VOLTAGE received: tasmota/tele/tasmota_990BD7/SENSOR, {"Time":"2023-11-06T17:16:58","ENERGY":{"TotalStartTime":"2022-12-15T15:57:20","Total":0.276,"Yesterday":0.000,"Today":0.144,"Period":1,"Power":42,"ApparentPower":42,"ReactivePower":0,"Factor":1.00,"Voltage":231,"Current":0.183}}
2023-11-06 17:16:59|TRACE  |v-devices      |Publishing event: JACK000005:2, VOLTAGE, 231
2023-11-06 17:16:59|TRACE  |mqtt-server    |Publishing virtdev/status/JACK000005/2/VOLTAGE: {"ts":1699287419428,"v":231,"s":0}
2023-11-06 17:16:59|ERROR  |v-devices      |Event failed on 127.0.0.1:1999, interface ID 8037: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(8037, JACK000005:2, POWER, 42) on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>8037</value></param><param><value>JACK000005:2</value></param><param><value>POWER</value></param><param><value><double>42</double></value></param></params></methodCall>
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Response XML: <?xml version="1.0" encoding="iso-8859-1"?>\n<methodResponse><params><param>\n    <value></value>\n</param></params></methodResponse>\n
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(8037, JACK000005:2, CURRENT, 183) on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>8037</value></param><param><value>JACK000005:2</value></param><param><value>CURRENT</value></param><param><value><double>183</double></value></param></params></methodCall>
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Response XML: <?xml version='1.0' encoding='ISO-8859-1'?><methodResponse><params><param><value></value></param></params></methodResponse>
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(CCU-Jack_java, JACK000005:2, CURRENT, 183) on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>CCU-Jack_java</value></param><param><value>JACK000005:2</value></param><param><value>CURRENT</value></param><param><value><double>183</double></value></param></params></methodCall>
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Response XML: <?xml version="1.0" encoding="iso-8859-1"?>\n<methodResponse><params><param>\n    <value></value>\n</param></params></methodResponse>\n
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(8037, JACK000005:2, VOLTAGE, 231) on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:1999
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>8037</value></param><param><value>JACK000005:2</value></param><param><value>VOLTAGE</value></param><param><value><double>231</double></value></param></params></methodCall>
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Response XML: <?xml version='1.0' encoding='ISO-8859-1'?><methodResponse><params><param><value></value></param></params></methodResponse>
2023-11-06 17:16:59|DEBUG  |itf-l-client   |Calling method event(CCU-Jack_java, JACK000005:2, VOLTAGE, 231) on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Calling method event on 127.0.0.1:9292/bidcos
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Request XML: <?xml version="1.0" encoding="ISO-8859-1"?>\n<methodCall><methodName>event</methodName><params><param><value>CCU-Jack_java</value></param><param><value>JACK000005:2</value></param><param><value>VOLTAGE</value></param><param><value><double>231</double></value></param></params></methodCall>
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Response XML: <?xml version="1.0" encoding="iso-8859-1"?>\n<methodResponse><params><param>\n    <value></value>\n</param></params></methodResponse>\n
2023-11-06 17:16:59|TRACE  |xmlrpc-client  |Response XML: <?xml version='1.0' encoding='ISO-8859-1'?><methodResponse><params><param><value></value></param></params></methodResponse>
2023-11-06 17:17:00|TRACE  |binrpc-server  |Request received from 127.0.0.1:37782
Baxxy13 commented 8 months ago

Nachtrag: Die Aussetzer sind aktuell recht selten. Inzwischen habe ich alle Werte (außer FREQUENCY) angebunden. Auffällig ist das immer nur ENERGY_COUNTER fehlt wenn der Fehler eintritt.

17:30:12 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 285.00Wh, Leistung 42.00W, Strom 183.00mA, Spannung 231.00V -- | -- | -- | -- 17:31:13 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Leistung 43.00W, Strom 184.00mA, Spannung 232.00V 17:32:14 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 287.00Wh, Leistung 42.00W, Strom 184.00mA, Spannung 231.00V 17:33:15 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 287.00Wh, Leistung 42.00W, Strom 183.00mA, Spannung 230.00V 17:34:16 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 288.00Wh, Leistung 42.00W, Strom 183.00mA, Spannung 229.00V 17:35:17 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 289.00Wh, Leistung 42.00W, Strom 184.00mA, Spannung 231.00V 17:36:18 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 289.00Wh, Leistung 42.00W, Strom 183.00mA, Spannung 230.00V 17:37:19 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Leistung 42.00W, Strom 183.00mA, Spannung 230.00V 17:38:20 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Leistung 42.00W, Strom 183.00mA, Spannung 231.00V 17:39:21 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Energie-Zähler Gerät 292.00Wh, Leistung 43.00W, Strom 184.00mA, Spannung 231.00V 17:40:22 | 06.11.2023 | HM-ES-PMSw1-Pl JACK000005:2 | Leistung 42.00W, Strom 183.00mA, Spannung 230.00V
mdzio commented 7 months ago

Bitte mal die V2.8.1-beta.1 installieren, und schauen, ob die Fehlermeldungen weiterhin auftauchen.

Baxxy13 commented 7 months ago

Kann ich mir ab morgen angucken. Ich konnte die Problematik in unterschiedlichem Umfang auf mehreren Testsystemen reproduzieren. Selbst eine "nackte" CCU3 (3.71.12) hatte über Nacht etwa 20 Aussetzer. Mein mittleres Testsystem (RM) hingegen zeigt die Probleme bei fast jeder 2. Datenübertragung der Tasmota-Dose.

mdzio commented 7 months ago

Hast du die Authentifizierung für die XMLRPC-API auf der CCU eingeschaltet? Könntest du diese Einstellung testweise mal ändern.

Ich kann bei mir den Fehler noch nicht so richtig nachvollziehbar reproduzieren. Bisher konnte ich die Fehlermeldung nur auf der Original-CCU-Firmware bei Verwenden der internen 30000er Ports der Schnittstellenprozesse beobachten. Dazu habe ich den CCU-Jack kurz angepasst. Allerdings ist dann diese Fehlermeldung bei jeder Anfrage an den HMIPServer aufgetreten. Alles sehr merkwürdig...

Baxxy13 commented 7 months ago

Hallo Mathias, die CCU3 ist so offen wie möglich eingestellt. Also in der Firewall "Ports offen" sowie überall "Vollzugriff". Authentifizierung für die XML-RPC-API sowie HTTPS-Umleitung sind NICHT aktiv.

Hatte heute morgen die 2.8.2 beta1 installiert und das dann laufen lassen. Die "Ausbeute" des Tages sieht bisher so aus:

Zeitstempel | Dringlichkeit | Modul | Meldung -- | -- | -- | -- 2023-11-12 20:04:20 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 19:50:06 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 19:22:39 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 18:44:01 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 17:56:14 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 17:55:13 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 17:44:02 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 17:42:00 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-12 16:52:11 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 16:51:10 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 16:50:09 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-12 16:47:06 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-12 16:07:27 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 16:06:26 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-12 16:02:22 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 12:11:34 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF 2023-11-12 11:22:46 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 09:19:45 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF 2023-11-12 08:53:19 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF

Bei der "Auswertung" ist mir jetzt was aufgefallen. Es gibt ja 2 verschiedene Meldungen. A: 2023-11-12 08:53:19 | ERROR | v-devices | Event failed on 127.0.0.1:1999, interface ID 1482: HTTP request failed on 127.0.0.1:1999: Post "http://127.0.0.1:1999": EOF

B: 2023-11-12 09:19:45 | ERROR | v-devices | Event failed on 127.0.0.1:9292/bidcos, interface ID CCU-Jack_java: HTTP request failed on 127.0.0.1:9292/bidcos: Post "http://127.0.0.1:9292/bidcos": EOF

Nur bei den "A-Meldungen" fehlen laut Systemprotokoll die Events (und damit die Werte) für den "ENERGY_COUNTER".

08:53:19 | 12.11.2023 | HM-ES-PMSw1-Pl JACK000000:2 | Leistung 0.00W, Strom 0.00mA, Spannung 0.00V -- | -- | -- | --

Bei den "B-Meldungen" ist alles laut Systemprotokoll vorhanden. Welche (und ob die) Auswirkungen haben kann ich aktuell nicht sagen.

09:19:45 | 12.11.2023 | HM-ES-PMSw1-Pl JACK000000:2 | Energie-Zähler Gerät 403.00Wh, Leistung 0.00W, Strom 0.00mA, Spannung 0.00V -- | -- | -- | --
mdzio commented 7 months ago

Dieselbe Meldung wird an zwei Logikschichten verschickt. Die A-Meldungen (an Port 1999) sind für die ReGaHss, die auch das Systemprotokoll füllt oder auch Programme anstößt. Die B-Meldungen (an Port 9292) sind für den HmIP-Server. Dieser erstellt, soweit ich weiß, die Diagramme und macht noch einige andere Sachen.

mdzio commented 7 months ago

Bitte mal V2.8.1-beta.2 testen.

Baxxy13 commented 7 months ago

Also seitdem ich gestern Abend die V2.8.2-beta.2 auf der CCU3 installiert hatte gab es keine ERROR-Meldungen mehr und auch das Systemprotokoll ist lückenlos. Sieht also erstmal gut aus.

Werde jetzt wieder auf das RM-Testsystem umschwenken und beobachten.