search

lumapu / ahoy

Various tools, examples, and documentation for communicating with Hoymiles microinverters
https://ahoydtu.de
Other
952 stars 224 forks source link

Verbindung ESP8266 --> HM-600 unmöglich ab V0.8.64 #1440

Closed rori1977 closed 8 months ago

rori1977 commented 8 months ago

Platform

ESP8266

Assembly

I did the assebly by myself

nRF24L01+ Module

nRF24L01+ plus

Antenna

circuit board

Power Stabilization

Elko (~100uF)

Connection picture

Version

0.8.83

Github Hash

5ebfe5a

Build & Flash Method

AhoyDTU Webinstaller

Setup

2024-02-19_13-35-04_v0.8.63_ahoy_setup.json

Debug Serial Log output

0.8.83 - 5ebfe5a
---------------
13:56:00.940 I: (#0) Radio infos: 0 0 0 0 0 | t: 0, s: 0, f: 0, n: 0 | p: 2
13:56:07.939 I: (#0) TX 27 CH23, 15 ret. | 15 0b 80
13:56:21.939 I: (#0) request timeout: 13998ms
13:56:21.940 -----
13:56:28.941 I: (#0) Radio infos: 0 -2 0 0 0 | t: 1, s: 0, f: 0, n: 1 | p: 2
13:56:35.938 I: (#0) TX 27 CH40, 15 ret. | 15 0b 80
13:56:49.940 I: (#0) request timeout: 14000ms
13:56:49.941 -----
13:56:56.937 I: (#0) Radio infos: 0 -2 -2 0 0 | t: 2, s: 0, f: 0, n: 2 | p: 2
13:57:03.936 I: (#0) TX 27 CH61, 15 ret. | 15 0b 80
13:57:17.934 I: (#0) request timeout: 13996ms
13:57:17.935 -----
13:57:24.933 I: (#0) Radio infos: 0 -2 -2 -2 0 | t: 3, s: 0, f: 0, n: 3 | p: 2

und hier zum Vergleich die letzte funktionierende Version

0.8.63 - 77b1f96
---------------
13:53:25.000 I: Iv: 0
13:53:25.002 I: Gartenhaus/ch1/U_DC: 40.100 V
13:53:25.002 I: Gartenhaus/ch1/I_DC: 0.940 A
13:53:25.003 I: Gartenhaus/ch1/P_DC: 37.700 W
13:53:25.004 I: Gartenhaus/ch1/YieldDay: 180.000 Wh
13:53:25.004 I: Gartenhaus/ch1/YieldTotal: 305.391 kWh
13:53:25.007 I: Gartenhaus/ch1/Irradiation: 8.871 %
13:53:25.010 I: Gartenhaus/ch1/MaxPower: 37.700 W
13:53:25.014 I: Gartenhaus/ch2/U_DC: 40.100 V
13:53:25.017 I: Gartenhaus/ch2/I_DC: 0.950 A
13:53:25.020 I: Gartenhaus/ch2/P_DC: 38.000 W
13:53:25.023 I: Gartenhaus/ch2/YieldDay: 181.000 Wh
13:53:25.026 I: Gartenhaus/ch2/YieldTotal: 338.147 kWh
13:53:25.030 I: Gartenhaus/ch2/Irradiation: 8.941 %
13:53:25.033 I: Gartenhaus/ch2/MaxPower: 38.000 W
13:53:25.036 I: Gartenhaus/ch0/U_AC: 238.000 V
13:53:25.039 I: Gartenhaus/ch0/I_AC: 0.300 A
13:53:25.042 I: Gartenhaus/ch0/P_AC: 72.300 W
13:53:25.045 I: Gartenhaus/ch0/F_AC: 50.020 Hz
13:53:25.048 I: Gartenhaus/ch0/PF_AC: 1.000 
13:53:25.051 I: Gartenhaus/ch0/Temp: 20.100 °C
13:53:25.054 I: Gartenhaus/ch0/ALARM_MES_ID: 6.000 
13:53:25.058 I: Gartenhaus/ch0/YieldDay: 361.000 Wh
13:53:25.061 I: Gartenhaus/ch0/YieldTotal: 643.538 kWh
13:53:25.065 I: Gartenhaus/ch0/P_DC: 75.700 W
13:53:25.068 I: Gartenhaus/ch0/Efficiency: 95.509 %
13:53:25.071 I: Gartenhaus/ch0/MaxPower: 72.300 W
13:53:25.075 I: 
13:53:28.001 I: (#0) Radio infos: -6 -4 -6 -6 -6 | t: 1, s: 1, f: 0, n: 0 | p: 2
13:53:28.004 I: last tx setup: 29ms
13:53:28.004 I: (#0) TX 27 CH23 | 15 05 80
13:53:28.256 I: (#0) request timeout: 251ms
13:53:28.257 I: last tx setup: 37ms
13:53:28.258 I: (#0) TX 27 CH23 | 15 05 80
13:53:28.510 I: (#0) request timeout: 250ms
13:53:28.511 -----
13:53:28.511 I: com loop duration: 511ms
13:53:28.512 -----

Error description

0.8.63 ist das letzte release, mit dem ich noch eine Verbindung aufgebaut bekomme zwischen meinem ESP8266 und meinem Hoymiles HM-600. Alle Versionen danach, 0.8.64 oder höher, inklusive des aktuellen Stable Releases 0.8.83) schaffen es nicht mehr eine Verbindung herzustellen.

0.8.83 - kein MQTT, kein Connect zum WR (stable) 0.8.69 - kein MQTT, kein Connect zum WR 0.8.65 - kein MQTT, kein Connect zum WR 0.8.64 - kein MQTT, kein Connect zum WR 0.8.53 -0.8.63 Problemloser Betrieb auf jedem einzelnen Point Release

Nach einem Downgrade funktioniert immer wieder alles problemlos. (bin mehrmals hin und her mit den Versionen, auch mal mit ERASE und einem ganz frischen Webinstaller drüber gebügelt)

Die Änderung, die meine Probleme verursacht, scheint also in 0.8.64 erstmalig im Code zu stecken.

Welche Informationen wären hilfreich, um hier der Sache auf die Schliche zu kommen? Bildschirmfoto vom 2024-02-19 13-59-38

rejoe2 commented 8 months ago

14s bis timeout?!? Ist da ein display dran oä?

Was auf alle Fälle auffällt: der Empfang war vorher schon nicht besonders gut. Ist da der Elko dran wie empfohlen?

Dirk-Ahoy commented 8 months ago

längeres Log mit der funktionierenden Version wäre auch mal interessant ich vermute auch das der Empfang vorher schon grenzwertig war.

lumapu commented 8 months ago

"print inverter data to serial console" bringt hier keinen Vorteil, daher würde ich empfehlen es auszuschalten.

folgendes habe ich in Changelog geschrieben für die 0.8.64

add ARC to log (NRF24 Debug) merge PR: ETH NTP update bugfix #1385

Beides hat keinen Einfluss auf den Empfang, daher kann ich den Fehler ab dieser Version noch nicht nachvollziehen. Evtl. verrät ein git diff hier mehr. (bin gerade am Handy, da ist mir das zu umständlich)

rori1977 commented 8 months ago

Hallo,

danke für die Hinweise.

@rejoe2 : Es ist kein Display dran und der Elko (100 uF) ist auch am Start (sonst hätte ich mich gar nicht getraut, hier ein Issue aufzumachen :-) )

Morgen versuche ich Folgendes:

1.) DTU näher an den WR ran (ist richtig, Empfang war vermutlich grenzwertig, aber 80% der Signale kam immer durch, das war genug für mich) 2.) @Dirk-Ahoy :Längeres Logfile vom (funktionierenden) V0.8.63 werde ich anhängen. 3.) @lumapu "print inverter data to serial console" werde ich - wie empfohlen- ausschalten (ich hatte gedacht, dann bekomme ich ggf. bessere, detailliertere Logs, um sie hier teilen zu können).

Was mir nicht klar ist: Warum sollte der Empfang bei höheren Versionen (0.8.64+) schlechter sein als davor (falls sich das als Ursache bestätigt)? Die Antenne ist ja dieselbe, die Position der DTU und der Antenne bleibt 100% unverändert, gleiches Netzteil, überall identische Hardware etc....)

Naja, vielleicht sind wir morgen Mittag schon klüger.... ich bleibe dran. Vielen Dank schonmal für Euren "Gehirnschmalz".

Es ist wirklich merkwürdig (aber seit Wochen klar reproduzierbar) da ich immer wieder die neuesten DEV Versionen ausprobiert habe und dann immer wieder nur durch das Downgrade wieder ein funktionierendens System bekommen habe. Für mich sieht es eindeutig nach einer Ursache in 0.8.64 aus, aber ich lasse mich morgen gerne eines Besseren belehren, Hauptsache wir komme der Sache auf die Spur.... Schönen Abend.

Dirk-Ahoy commented 8 months ago

interssant wäre noch was du für ein NRF Modul hast - auch externe Antenne oder Leiterplattenantenne.
Am besten eByte Modul benutzen oder Fusion Board mit externer Antenne. Wir haben in den letzten Monaten so viel umgebaut, gerade daher kann ich nicht glauben das es schlechter ist - meine Fusion Platine werkelt mit 2x HM600 im Garten mit etwa 90m Luftlinie ohne Probleme. Selbst da hab ich über den Tag ~99% Statisik.

lumapu commented 8 months ago

interessant, ich hoffe ein git diff hilft uns hier auch weiter

rori1977 commented 8 months ago

Hallo, ich verwende eine Leiterplattenantenne an einem "D1 mini".

Die Entfernung vom normalen DTU-Standort zum WR beträgt vielleicht 15 Meter. Ggf erschwerende Bedingungen am normalen Stadort sind: Stahlbeton im Fußboden, ein WLAN-Router direkt daneben (2.4 + 5 GHz) , ein Zigbee Controller (auch 2.4 GHz?) und ein JeeLink/Lacrosse Controller (868 Mhz). Das werde ich jetzt mal als Ursache eliminieren, am neuen Test-Standort ist dann genug Abstand zum Rest der Technik.

Wenn ich am Test-Setup noch was machen kann, dann bin ich für jeden Hinweis dankbar.

rori1977 commented 8 months ago

Auf der Rechnung der Firma "Makershop" steht:

NRF24L01+ PLUS - 2.4GHz Funkmodul Raspberry Pi Arduino Nano Modul Nano FHEM SKU: 3363

Dirk-Ahoy commented 8 months ago

E01-ML01DP5 als Modul nutzen das hat ne Abschirmung und mit externer Antenne geht das direkt mal 100% besser - die Leiterplattendinger sind mist.
die "C" Version ist gerade im Angebot https://www.amazon.de/gp/product/B09D72882F/ref=ppx_yo_dt_b_search_asin_title?ie=UTF8&psc=1

Geht auch, die normale hat gerade längere Lieferzeit - aber da brauchst du dann auch eine Antenne für.

Oder hier mit Antenne: https://shop.blinkyparts.com/de/Ebyte-Funkmodul-NRF24L01-E01-ML01DP5-2-4-GHz-inkl.-passender-3dBi-Antenne/blink238942

Ansonsten auch gerne mal im Discord vorbeikommen da kann man schneller mal eben helfen.

rejoe2 commented 8 months ago

Es wurde einiges geändert im Lauf der Entwicklung. @lumapu : Im Moment hätte ich ggf. die Beschränkung der rx-Channels im Verdacht, v.a., wenn man "untertägig" auf eine neue Version wechselt.

Um das zu bestätigen (oder zu widerlegen), wäre aber Log (-auszug) vom funktionierenden Empfang mit einer Vorversion sinnvoll (auf der man aber den/die rx-Channels ablesen können muss, was aber afair schon ziemlich lange drin ist).

lumapu commented 8 months ago

@rejoe2 sehr guter Punkt: oder die aktuelle Version in der Nacht installieren? Ich denke auch das wäre eine Option, evtl. geht einfach dieser "große" Sprung von der Chaos-Kommunikation der Version 0.8.36 nicht gut

lumapu commented 8 months ago

hier der diff zwischen 0.8.63 und 0.8.64: https://github.com/lumapu/ahoy/compare/77b1f96c03332f245d146a4d90c9e5264c054b07...7f386f07df602e71d0d66ed7e0ddc51c4630aa05

rejoe2 commented 8 months ago

hier der diff zwischen 0.8.63 und 0.8.64: 77b1f96...7f386f0

Na ja, das meiste davon ist wieder umgebaut, und ich würde auf die Schnelle eigentlich denken, dass da nichts "kritisches" dabei war...

lumapu commented 8 months ago

wenn ich das so sehe würde ich @rori1977 bitten mal die 0.8.65 zu testen, evtl. haben zufällig in der 0.8.64 die Abfragen von PLOS und ARC zu Fehlern geführt. Bei der 0.8.65 wurde diese Änderungen wieder ausgebaut

rori1977 commented 8 months ago

OK, dann installiere ich jetzt (nachts) mal die 0.8.65 und berichte morgen, ob sich was tut. Bei meinem letzten Versuch war ich damit nicht erfolgreich, allerdings habe ich das sehr wahrscheinlich tagsüber eingespielt.

rori1977 commented 8 months ago

Screenshot_2024-02-20-08-31-15-798_org mozilla firefox Gute Morgen,

Leider ist der Versuch mit 0.8.65 genau so verlaufen wie meine Tests davor. Kein Connect zum WR, MQTT kaputt.

rejoe2 commented 8 months ago

Leider ist der Versuch mit 0.8.65 genau so verlaufen wie meine Tests davor. Kein Connect zum WR, MQTT kaputt.

Bitte installiere die aktuelle Version und aktiviere im "Settings"-Menü die Punkte "Serial Debug" und "Privacy Mode".

Das dann hoffentlich etwas aussagekräftigere log kannst du mit der "copy"-Taste in die Zwischenablage packen, das ist dann besser zu lesen wie so ein screenshot.

rori1977 commented 8 months ago

`0.8.83 - 5ebfe5a

I: (#0) Radio infos: 0 0 0 0 0 | t: 0, s: 0, f: 0, n: 0 | p: 2 I: (#0) TX 27 CH23, 15 ret. | 15 0b 80 I: (#0) request timeout: 13998ms

I: (#0) Radio infos: 0 -2 0 0 0 | t: 1, s: 0, f: 0, n: 1 | p: 2 I: (#0) TX 27 CH40, 15 ret. | 15 0b 80 I: (#0) request timeout: 13996ms

I: (#0) Radio infos: 0 -2 -2 0 0 | t: 2, s: 0, f: 0, n: 2 | p: 2 I: (#0) TX 27 CH61, 15 ret. | 15 0b 80 I: (#0) request timeout: 13995ms

I: (#0) Radio infos: 0 -2 -2 -2 0 | t: 3, s: 0, f: 0, n: 3 | p: 2 I: (#0) TX 27 CH75, 15 ret. | 15 0b 80 I: (#0) request timeout: 13997ms

I: (#0) Radio infos: 0 -2 -2 -2 -2 | t: 4, s: 0, f: 0, n: 4 | p: 2 I: (#0) TX 27 CH03, 15 ret. | 15 0b 80 I: (#0) request timeout: 13997ms

I: (#0) Radio infos: -2 -2 -2 -2 -2 | t: 5, s: 0, f: 0, n: 5 | p: 2 I: (#0) TX 27 CH23, 15 ret. | 15 0b 80 I: (#0) request timeout: 13997ms

rejoe2 commented 8 months ago

a) brauchst du wirklich "HIGH" als Power-Einstellung? Dass da erschwerte Bedingungen sind, ok, aber evtl. wäre das mal ein Test wert, da etwas runter zu gehen? b) weiter komplett unklar ist mir, wo diese "ewigen" Zeiten herkommen. Kann es sein, dass da Probleme mit dem WiFi bestehen? (Aber warum dann ab dieser speziellen Version? Keine Idee im Moment).

rori1977 commented 8 months ago

So sieht es bei der (funktionierenden) V0.8.63 aus

0.8.63 - 77b1f96

0.8.63 - 77b1f96

09:01:25.001 I: (#0) Radio infos: -6 -4 -6 -6 -6 | t: 4, s: 3, f: 0, n: 1 | p: 2 09:01:25.004 I: last tx setup: 37ms 09:01:25.005 I: (#0) TX 27 CH23 | 15 01 80 09:01:25.257 I: (#0) request timeout: 250ms 09:01:25.258 I: last tx setup: 37ms 09:01:25.258 I: (#0) TX 27 CH23 | 15 01 80 09:01:25.510 I: (#0) request timeout: 250ms 09:01:25.511 ----- 09:01:25.511 I: com loop duration: 511ms 09:01:25.511 ----- 09:01:40.001 I: (#0) Radio infos: -6 -5 -6 -6 -6 | t: 6, s: 3, f: 0, n: 3 | p: 2 09:01:40.003 I: last tx setup: 37ms 09:01:40.004 I: (#0) TX 27 CH23 | 15 01 80 09:01:40.092 I: (#0) RX 41ms | 27 CH61 | 95 81 09:01:40.093 I: (#0) Payload (14) 09:01:40.095 ----- 09:01:40.095 I: com loop duration: 95ms 09:01:40.095 ----- 09:01:55.001 I: (#0) Radio infos: -6 -3 -6 -6 -6 | t: 7, s: 4, f: 0, n: 3 | p: 2 09:01:55.003 I: last tx setup: 37ms 09:01:55.004 I: (#0) TX 27 CH23 | 15 0b 80 09:01:55.166 I: (#0) RX 39ms | 27 CH61 | 95 01 09:01:55.167 I: (#0) RX 72ms | 27 CH61 | 95 02 09:01:55.168 I: (#0) RX 114ms | 23 CH61 | 95 83 09:01:55.169 I: (#0) Payload (42) 09:01:55.171 ----- 09:01:55.171 I: com loop duration: 171ms 09:01:55.172 ----- 09:02:10.002 I: (#0) Radio infos: -6 -3 -6 -6 -6 | t: 8, s: 5, f: 0, n: 3 | p: 2 09:02:10.004 I: last tx setup: 37ms 09:02:10.005 I: (#0) TX 27 CH23 | 15 00 80 09:02:10.607 I: (#0) request timeout: 600ms 09:02:10.608 I: last tx setup: 37ms 09:02:10.609 I: (#0) TX 27 CH23 | 15 00 80 09:02:10.696 I: (#0) RX 40ms | 27 CH61 | 95 81 09:02:10.697 I: (#0) Payload (14) 09:02:10.698 ----- 09:02:10.699 I: com loop duration: 697ms 09:02:10.699 ----- 09:02:25.001 I: (#0) Radio infos: -6 -3 -6 -6 -6 | t: 10, s: 6, f: 0, n: 4 | p: 2 09:02:25.003 I: last tx setup: 36ms 09:02:25.004 I: (#0) TX 27 CH23 | 15 0b 80 09:02:25.186 I: (#0) RX 81ms | 27 CH61 | 95 02 09:02:25.187 I: (#0) RX 135ms | 23 CH61 | 95 83 09:02:25.188 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:02:25.189 I: (#0) TX 11 CH23 | 15 5b 81 09:02:25.441 I: (#0) RX 30ms | 27 CH61 | 95 01 09:02:25.442 I: (#0) Payload (42) 09:02:25.444 ----- 09:02:25.444 I: com loop duration: 445ms 09:02:25.445 ----- 09:02:40.001 I: (#0) Radio infos: -6 -3 -6 -6 -6 | t: 11, s: 7, f: 0, n: 4 | p: 2 09:02:40.003 I: last tx setup: 28ms 09:02:40.004 I: (#0) TX 27 CH23 | 15 11 80 09:02:40.632 I: (#0) RX 72ms | 27 CH61 | 95 02 09:02:40.633 I: (#0) RX 124ms | 27 CH61 | 95 03 09:02:40.634 I: (#0) RX 172ms | 27 CH40 | 95 04 09:02:40.635 I: (#0) RX 222ms | 27 CH23 | 95 05 09:02:40.636 I: (#0) RX 272ms | 27 CH23 | 95 06 09:02:40.637 I: (#0) RX 324ms | 27 CH23 | 95 07 09:02:40.640 I: (#0) RX 376ms | 27 CH23 | 95 08 09:02:40.643 I: (#0) RX 424ms | 27 CH03 | 95 09 09:02:40.646 I: (#0) RX 581ms | 19 CH75 | 95 8c 09:02:40.649 W: (#0) frame 1 missing: request retransmit (17 attempts left) 09:02:40.655 I: (#0) TX 11 CH23 | 15 5b 81 09:02:40.908 I: (#0) RX 31ms | 27 CH61 | 95 01 09:02:40.909 W: (#0) frame 10 missing: request retransmit (16 attempts left) 09:02:40.910 I: (#0) TX 11 CH23 | 15 50 8a 09:02:41.162 I: (#0) RX 32ms | 27 CH61 | 95 0a 09:02:41.164 W: (#0) frame 11 missing: request retransmit (15 attempts left) 09:02:41.165 I: (#0) TX 11 CH23 | 15 51 8b 09:02:41.417 I: (#0) RX 32ms | 27 CH61 | 95 0b 09:02:41.419 I: (#0) Payload (182) 09:02:41.420 ----- 09:02:41.420 I: com loop duration: 1421ms 09:02:41.421 ----- 09:02:55.002 I: (#0) Radio infos: -6 -3 -6 -6 -6 | t: 12, s: 8, f: 0, n: 4 | p: 2 09:02:55.005 I: last tx setup: 29ms 09:02:55.005 I: (#0) TX 27 CH23 | 15 0b 80 09:02:55.170 I: (#0) RX 66ms | 27 CH61 | 95 02 09:02:55.171 I: (#0) RX 118ms | 23 CH61 | 95 83 09:02:55.172 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:02:55.173 I: (#0) TX 11 CH23 | 15 5b 81 09:02:55.425 I: (#0) RX 31ms | 27 CH61 | 95 01 09:02:55.426 I: (#0) Payload (42) 09:02:55.428 ----- 09:02:55.429 I: com loop duration: 428ms 09:02:55.429 ----- 09:03:10.001 I: (#0) Radio infos: -6 -3 -6 -6 -6 | t: 13, s: 9, f: 0, n: 4 | p: 2 09:03:10.003 I: last tx setup: 29ms 09:03:10.004 I: (#0) TX 27 CH23 | 15 0b 80 09:03:10.191 I: (#0) RX 88ms | 27 CH61 | 95 02 09:03:10.192 I: (#0) RX 140ms | 23 CH61 | 95 83 09:03:10.193 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:03:10.194 I: (#0) TX 11 CH23 | 15 5b 81 09:03:10.446 I: (#0) RX 30ms | 27 CH61 | 95 01 09:03:10.447 I: (#0) Payload (42) 09:03:10.449 ----- 09:03:10.450 I: (#0) Radio infos: -6 -1 -6 -6 -6 | t: 14, s: 10, f: 0, n: 4 | p: 2 09:03:10.451 I: last tx setup: 28ms 09:03:10.452 I: (#0) TX 27 CH23 | 15 15 80 09:03:10.704 I: (#0) request timeout: 250ms 09:03:10.705 I: last tx setup: 36ms 09:03:10.705 I: (#0) TX 27 CH23 | 15 15 80 09:03:10.791 I: (#0) RX 39ms | 17 CH61 | 95 81 09:03:10.792 I: (#0) Payload (4) 09:03:10.793 ----- 09:03:10.793 I: com loop duration: 793ms 09:03:10.793 ----- 09:03:25.001 I: (#0) Radio infos: -6 -1 -6 -6 -6 | t: 16, s: 11, f: 0, n: 5 | p: 2 09:03:25.003 I: last tx setup: 37ms 09:03:25.004 I: (#0) TX 27 CH23 | 15 0b 80 09:03:25.179 I: (#0) RX 39ms | 27 CH61 | 95 01 09:03:25.180 I: (#0) RX 81ms | 27 CH61 | 95 02 09:03:25.181 I: (#0) RX 128ms | 23 CH40 | 95 83 09:03:25.182 I: (#0) Payload (42) 09:03:25.184 ----- 09:03:25.184 I: com loop duration: 184ms 09:03:25.184 ----- 09:03:40.002 I: (#0) Radio infos: -6 -1 -6 -6 -6 | t: 17, s: 12, f: 0, n: 5 | p: 2 09:03:40.004 I: last tx setup: 37ms 09:03:40.005 I: (#0) TX 27 CH23 | 15 0b 80 09:03:40.177 I: (#0) RX 73ms | 27 CH61 | 95 02 09:03:40.178 I: (#0) RX 125ms | 23 CH61 | 95 83 09:03:40.179 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:03:40.180 I: (#0) TX 11 CH23 | 15 5b 81 09:03:40.432 I: (#0) RX 31ms | 27 CH61 | 95 01 09:03:40.433 I: (#0) Payload (42) 09:03:40.435 ----- 09:03:40.435 I: com loop duration: 435ms 09:03:40.436 ----- 09:03:55.001 I: (#0) Radio infos: -6 1 -6 -6 -6 | t: 18, s: 13, f: 0, n: 5 | p: 2 09:03:55.003 I: last tx setup: 28ms 09:03:55.004 I: (#0) TX 27 CH23 | 15 0b 80 09:03:55.173 I: (#0) RX 70ms | 27 CH61 | 95 02 09:03:55.174 I: (#0) RX 121ms | 23 CH61 | 95 83 09:03:55.175 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:03:55.176 I: (#0) TX 11 CH23 | 15 5b 81 09:03:55.428 I: (#0) RX 32ms | 27 CH61 | 95 01 09:03:55.429 I: (#0) Payload (42) 09:03:55.431 ----- 09:03:55.431 I: com loop duration: 432ms 09:03:55.432 ----- 09:04:10.001 I: (#0) Radio infos: -6 3 -6 -6 -6 | t: 19, s: 14, f: 0, n: 5 | p: 2 09:04:10.003 I: last tx setup: 29ms 09:04:10.004 I: (#0) TX 27 CH23 | 15 0b 80 09:04:10.189 I: (#0) RX 39ms | 27 CH61 | 95 01 09:04:10.190 I: (#0) RX 90ms | 27 CH61 | 95 02 09:04:10.191 I: (#0) RX 137ms | 23 CH40 | 95 83 09:04:10.192 I: (#0) Payload (42) 09:04:10.194 ----- 09:04:10.194 I: com loop duration: 194ms 09:04:10.194 ----- 09:04:25.001 I: (#0) Radio infos: -6 4 -6 -6 -6 | t: 20, s: 15, f: 0, n: 5 | p: 2 09:04:25.003 I: last tx setup: 36ms 09:04:25.004 I: (#0) TX 27 CH23 | 15 0b 80 09:04:25.186 I: (#0) RX 81ms | 27 CH61 | 95 02 09:04:25.187 I: (#0) RX 135ms | 23 CH61 | 95 83 09:04:25.188 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:04:25.189 I: (#0) TX 11 CH23 | 15 5b 81 09:04:25.441 I: (#0) RX 31ms | 27 CH61 | 95 01 09:04:25.442 I: (#0) Payload (42) 09:04:25.444 ----- 09:04:25.444 I: com loop duration: 445ms 09:04:25.445 ----- 09:04:40.001 I: (#0) Radio infos: -6 4 -6 -6 -6 | t: 21, s: 16, f: 0, n: 5 | p: 2 09:04:40.003 I: last tx setup: 28ms 09:04:40.004 I: (#0) TX 27 CH23 | 15 0b 80 09:04:40.180 I: (#0) RX 77ms | 27 CH61 | 95 02 09:04:40.181 I: (#0) RX 128ms | 23 CH61 | 95 83 09:04:40.182 W: (#0) frame 1 missing: request retransmit (3 attempts left) 09:04:40.183 I: (#0) TX 11 CH23 | 15 5b 81 09:04:40.435 I: (#0) RX 32ms | 27 CH61 | 95 01 09:04:40.436 I: (#0) Payload (42) 09:04:40.438 ----- 09:04:40.438 I: com loop duration: 439ms 09:04:40.439 ----- 09:04:55.001 I: (#0) Radio infos: -6 4 -6 -6 -6 | t: 22, s: 17, f: 0, n: 5 | p: 2 09:04:55.003 I: last tx setup: 29ms 09:04:55.004 I: (#0) TX 27 CH23 | 15 0b 80 09:04:55.170 I: (#0) RX 39ms | 27 CH61 | 95 01 09:04:55.171 I: (#0) RX 72ms | 27 CH61 | 95 02 09:04:55.172 I: (#0) RX 119ms | 23 CH40 | 95 83 09:04:55.173 I: (#0) Payload (42) 09:04:55.175 ----- 09:04:55.175 I: com loop duration: 175ms 09:04:55.176 ----- 09:05:10.001 I: (#0) Radio infos: -6 4 -6 -6 -6 | t: 23, s: 18, f: 0, n: 5 | p: 2 09:05:10.003 I: last tx setup: 37ms 09:05:10.004 I: (#0) TX 27 CH23 | 15 0b 80 09:05:10.195 I: (#0) RX 39ms | 27 CH61 | 95 01 09:05:10.196 I: (#0) RX 90ms | 27 CH61 | 95 02 09:05:10.197 I: (#0) RX 144ms | 23 CH61 | 95 83 09:05:10.198 I: (#0) Payload (42) 09:05:10.200 ----- 09:05:10.200 I: com loop duration: 200ms 09:05:10.201 -----

rejoe2 commented 8 months ago

@rejoe2 sehr guter Punkt: oder die aktuelle Version in der Nacht installieren? Ich denke auch das wäre eine Option, evtl. geht einfach dieser "große" Sprung von der Chaos-Kommunikation der Version 0.8.36 nicht gut

Der WR antwortet in der funktionierenden Fassung genau da, wo wir es erwarten (tx+3/tx+2). Das sollte es also nicht sein.

Also: Wo kommt diese "unendliche" Verzögerung beim ESP8266 (allgemein? oder nur bei diesem einen hier?!?) her?

rejoe2 commented 8 months ago

@rori1977 - hat dein ESP mit der aktuellen Fassung eine Zeit?!?

Da gab es eine Änderung betr. ethernet, aber da in deinem log von vorhin sind keine Zeitstempel drin.

Kannst du die aktuelle nochmal drauf machen und dann die Zeit manuell syncen (gibt irgendwo einen Knopf für "mit browser"), wenn der ESP sich keine selbst ziehen kann?

rori1977 commented 8 months ago

Hallo rejoe2,

ja, den Knopf "from Browser" muss ich jedes Mal nach dem Neustart drücken, weil er sich die Zeit nicht selber zieht (aus mir unbekannten Grund).

Das ist nach dem Flaschen kein Problem das zu machen, aber gelegenlixht (so nach 5-10 Tagen) startet der ESP8266 sich manchmal spontan selber neu und dann muss ich erst wieder manuell die Zeit synchronisieren. Kann mein Problem eventuell mit dem NTP-Thema zusammehängen? Das wäre zumindest eine heiße Spur?

rori1977 commented 8 months ago

Screenshot_2024-02-20-09-24-15-740_org mozilla firefox-edit

Sieht unlogisch aus. Er denkt, es ist Nacht?

rejoe2 commented 8 months ago

Kann mein Problem eventuell mit dem NTP-Thema zusammehängen? Das wäre zumindest eine heiße Spur? Könnte schon sein, aber das kannst du ja ohne weiteres testen....

Darf dein ESP den als default eingetragenen Zeitserver im Inet erreichen? Wenn nein: Mit den Einstellungen auf dem ESP mal auf den Router (Fritzbox?) zeigen?

Und wie schaut das mit der "night time" denn jetzt aus? Direkt nach dem sync ist das ggf. noch nicht aktualisiert, das passiert erst mit jedem Durchlauf (default: 15 Sekunden oder so?)

rori1977 commented 8 months ago

OK, es hat definitiv was mit dem NTP zu tun. Sobald ich "Disable night Communication" rausnehme, verbindet er sich sofort.

Meine Vermutung ist daher, das sich zwischen 0.8.63 und 0.8.64 was bei NTP geändert hat, was bei meinem Setup Ärger macht.

Es scheint so, also würde er booten, dann kann ich ihn nur manuell mit dem Browser syncen, aber er denkt irgendwie weiterhin, es wäre noch Nacht.

2 Fragen daher:

  1. Warum bekommt mein ESP.die Zeit nicht über das Internet (das liegt vermutlich an meinen Einstellungen)
  2. Unter diesen Umständen sollte Ahoy nach dem manuellen Sync merken, das "nicht mehr Nacht ist" und die Kommunikation starten?
rejoe2 commented 8 months ago

Lass dir doch nicht alles aus der Nase ziehen...

Darf dein ESP den als default eingetragenen Zeitserver im Inet erreichen? Wenn nein: Mit den Einstellungen auf dem ESP mal auf den Router (Fritzbox?) zeigen?

rori1977 commented 8 months ago

Sieht irgendwie ungut aus

Screenshot_2024-02-20-09-42-03-085_org mozilla firefox-edit

rejoe2 commented 8 months ago

Wieso ist das "ungut", wenn anscheinend ein sync stattfindet und dann eben die Zeit seit "Computer-Urknall" ein paar ms her ist?

rori1977 commented 8 months ago

OK, also ich denke das wichtigste ist, das islch jetzt wieder mit dem aktuellen Release online bin. Was das NTP Thema angeht, scheint es ein abweichendes Verhalten ab 0.8.64 zu geben. Ob das eine Verbesserung für die anderen Nutzer darstellt kann ich nicht beurteilen, bei mir führt es zu Problemen, die ich aber möglicherweise durch bessere NTP-Settings beheben kann. Mal sehen. Soll ich den Issue auf Closed setzen?

rejoe2 commented 8 months ago

OK, also ich denke das wichtigste ist, das islch jetzt wieder mit dem aktuellen Release online bin.

Das ist doch schon mal was. Jedenfalls haben wir kein Problem in der Kommunikation zum Inverter - das sollte eher besser sein wie bisher (ggf. versuchsweise mal mit dem Power-level runter?)

Was das NTP Thema angeht, scheint es ein abweichendes Verhalten ab 0.8.64 zu geben. Ob das eine Verbesserung für die anderen Nutzer darstellt kann ich nicht beurteilen, bei mir führt es zu Problemen, die ich aber möglicherweise durch bessere NTP-Settings beheben kann. Mal sehen.

Es war "schon immer" ein Problem, wenn der ESP keine Zeit erhalten hat. Für "ntp-lose setups" kann man seit einiger Zeit auch einen "standalone"-Modus einstellen. Wenn man die NTP-settings einfach nur "passend" setzen muss, ist das imo kein bug, sondern "works as designed".

Soll ich den Issue auf Closed setzen?

Wenn es "nur" an der korrekten Einstellung auf einen (erreichbaren!) NTP-Server liegt: mAn. ja. Ob das so ist, kannst erst mal nur du sehen...

rori1977 commented 8 months ago

Hab jetzt den Haken bei "Disable night communication" wieder gesetzt und auch das Power Level reduziert. Mal sehen, ob er morgen früh bei Sonnenaufgang gute Laune hat.

Screenshot_2024-02-20-10-11-57-198_org mozilla firefox-edit

rejoe2 commented 8 months ago

...wenn er jetzt "gute Laune" hat, habe ich wenig Zweifel, dass das morgen früh auch noch so ist...

Kannst ja in ein paar Minuten mal schauen, wo deine Statistikwerte so stehen bei der nRF-Kommunikation :grin:.

rori1977 commented 8 months ago

Nahe 100% (versus 80% am alten Standort),.sehr schön.... Da hat sich ordentlich was getan..vielen Dank!

Screenshot_2024-02-20-10-25-08-305_org mozilla firefox

rori1977 commented 8 months ago

Und am alten Standort immerhin noch 92% 😊

Screenshot_2024-02-20-15-26-47-522_org mozilla firefox-edit

rori1977 commented 8 months ago

Vielen Dank

lumapu commented 8 months ago

cool, das du so zufrieden bist 😊