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.

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

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

Closed
1 task
rori1977 opened this issue Feb 19, 2024 · 37 comments
Closed
1 task

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

rori1977 opened this issue Feb 19, 2024 · 37 comments
Assignees
Labels
bug Something isn't working

Comments

@rori1977
Copy link
Contributor

Platform

ESP8266

Assembly

I did the assebly by myself

nRF24L01+ Module

nRF24L01+ plus

Antenna

circuit board

Power Stabilization

Elko (~100uF)

Connection picture

  • I will attach/upload an Image of my wiring

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

@rori1977 rori1977 added the bug Something isn't working label Feb 19, 2024
@rejoe2
Copy link
Contributor

rejoe2 commented Feb 19, 2024

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

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

@lumapu
Copy link
Owner

lumapu commented Feb 19, 2024

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

rori1977 commented Feb 19, 2024

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

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

lumapu commented Feb 19, 2024

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

@rori1977
Copy link
Contributor Author

rori1977 commented Feb 19, 2024

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

Auf der Rechnung der Firma "Makershop" steht:

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

@Dirk-Ahoy
Copy link

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

rejoe2 commented Feb 19, 2024

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

lumapu commented Feb 19, 2024

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

lumapu commented Feb 19, 2024

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

@rejoe2
Copy link
Contributor

rejoe2 commented Feb 19, 2024

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

lumapu commented Feb 19, 2024

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

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

rori1977 commented Feb 20, 2024

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

rejoe2 commented Feb 20, 2024

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

`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
Copy link
Contributor

rejoe2 commented Feb 20, 2024

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

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

rejoe2 commented Feb 20, 2024

@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
Copy link
Contributor

rejoe2 commented Feb 20, 2024

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

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

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

Sieht unlogisch aus. Er denkt, es ist Nacht?

@rejoe2
Copy link
Contributor

rejoe2 commented Feb 20, 2024

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

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

rejoe2 commented Feb 20, 2024

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

Sieht irgendwie ungut aus

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

@rejoe2
Copy link
Contributor

rejoe2 commented Feb 20, 2024

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

@rori1977
Copy link
Contributor Author

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

rejoe2 commented Feb 20, 2024

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

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

rejoe2 commented Feb 20, 2024

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

@rori1977
Copy link
Contributor Author

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

Und am alten Standort immerhin noch 92% 😊

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

@rori1977
Copy link
Contributor Author

Vielen Dank

@lumapu
Copy link
Owner

lumapu commented Feb 22, 2024

cool, das du so zufrieden bist 😊

lumapu added a commit that referenced this issue Mar 16, 2024
* fix NTP issues #1440 #1497 #1499
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

4 participants