Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

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

Closed
Baxxy13 opened this issue Nov 6, 2023 · 11 comments
Closed

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

Baxxy13 opened this issue Nov 6, 2023 · 11 comments
Labels
bug Something isn't working question Further information is requested
Milestone
v2.8.1-beta.2

Comments

@Baxxy13
Copy link

Baxxy13 commented Nov 6, 2023

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...

  • MQTT Schaltaktor mir Rückmeldung
  • MQTT Energiemessung
    ... gekoppelt ist.

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
@Baxxy13 Baxxy13 mentioned this issue Nov 6, 2023
Closed
@mdzio mdzio added bug Something isn't working question Further information is requested labels Nov 6, 2023
@mdzio
Copy link
Owner

mdzio commented Nov 6, 2023

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
Copy link
Author

Baxxy13 commented Nov 6, 2023

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
Copy link
Author

Baxxy13 commented Nov 6, 2023

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
Copy link
Author

Baxxy13 commented Nov 6, 2023

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
Copy link
Owner

mdzio commented Nov 11, 2023

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

@Baxxy13
Copy link
Author

Baxxy13 commented Nov 11, 2023

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
Copy link
Owner

mdzio commented Nov 12, 2023 •
edited
Loading

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
Copy link
Author

Baxxy13 commented Nov 12, 2023

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
Copy link
Owner

mdzio commented Nov 13, 2023

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 mdzio closed this as completed in 4b2e389 Nov 14, 2023
@mdzio mdzio added this to the next milestone Nov 14, 2023
@mdzio
Copy link
Owner

mdzio commented Nov 14, 2023

Bitte mal V2.8.1-beta.2 testen.

@Baxxy13
Copy link
Author

Baxxy13 commented Nov 15, 2023

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.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working question Further information is requested
Projects
None yet
Milestone
v2.8.1-beta.2
Development

No branches or pull requests
2 participants
@mdzio @Baxxy13