DSMR: Values are not read from DSMR anymore since updating 2021.9.3 to 2021.10.1

esphome / issues

Issue Tracker for ESPHome

https://esphome.io/

290 stars 34 forks source link

DSMR: Values are not read from DSMR anymore since updating 2021.9.3 to 2021.10.1 #2621

Closed JelleKoster closed 2 years ago

JelleKoster commented 2 years ago

The problem

Precisely since updating the ESPHome config of the Slimme Lezer (P1 DSMR reader by Marcel Zuidwijk) from v2019.3 to v2021.10.1, it no longer reads values from the DSMR v5 (Dutch) smart meter.

I can connect to the Slimme Lezer wirelessly from within ESPHome. When tailing the Slimme Lezer log within ESPHome, I see the wifi signal strength and device uptime being reported back OK. Neither the log nor Home Assistant shows the values of power consumed/produced (or per phase).

Powercycling the Slimme Lezer and re-plugging in the associated cables did not resolve the issue.

Which version of ESPHome has the issue?

2021.10.1

What type of installation are you using?

Home Assistant Add-on

Which version of Home Assistant has the issue?

2021.10.5

What platform are you using?

ESP8266

Board

d1_mini

Component causing the issue

DSMR

Example YAML snippet

substitutions:
  device_name: slimmelezer-7bf270
  device_description: "DIY P1 module to read your smart meter"

#external_components:
#  - source: github://zuidwijk/dsmr

esphome:
  name: ${device_name}
  comment: "${device_description}"
  platform: ESP8266
  esp8266_restore_from_flash: true
  board: d1_mini
  name_add_mac_suffix: false
  project:
    name: zuidwijk.slimmelezer
    version: "1.0"
  on_boot:
    then:
      - if:
          condition:
            lambda: return id(has_key);
          then:
            - lambda: |-
                std::string key(id(stored_decryption_key), 32);
                id(dsmr_instance).set_decryption_key(key);
          else:
            - logger.log:
                level: info
                format: "Not using decryption key. If you need to set a key use Home Assistant service 'ESPHome:  ${device_name}_set_dsmr_key'"

wifi:
  networks:
    - ssid: !secret wifiSSID
      password: !secret wifiPWD
  ap:
    ssid: ${device_name}
    ap_timeout: 15s

captive_portal:

# Enable logging
logger:

# Enable Home Assistant API
api:
  services:
    service: set_dsmr_key
    variables:
      private_key: string
    then:
      - logger.log:
          format: Setting private key %s. Set to empty string to disable
          args: [private_key.c_str()]
      - globals.set:
          id: has_key
          value: !lambda "return private_key.length() == 32;"
      - lambda: |-
          if (private_key.length() == 32)
            private_key.copy(id(stored_decryption_key), 32);
          id(dsmr_instance).set_decryption_key(private_key);

ota:

web_server:
  port: 80

uart:
  baud_rate: 115200
  rx_pin: D7

globals:
  - id: has_key
    type: bool
    restore_value: yes
    initial_value: "false"
  - id: stored_decryption_key
    type: char[32]
    restore_value: yes

dsmr:
  id: dsmr_instance
  # For Luxembourg users set here your decryption key
  #decryption_key: !secret decryption_key // enable this when using decryption for Luxembourg; key like '00112233445566778899AABBCCDDEEFF'

sensor:
  - platform: dsmr
#    energy_delivered_lux:
#      name: "Energy Consumed Luxembourg"
#      state_class: total_increasing
    energy_delivered_tariff1:
      name: "Energy Consumed Tariff 1"
      state_class: total_increasing
    energy_delivered_tariff2:
      name: "Energy Consumed Tariff 2"
      state_class: total_increasing
#    energy_returned_lux:
#      name: "Energy Produced Luxembourg"
#      state_class: total_increasing
    energy_returned_tariff1:
      name: "Energy Produced Tariff 1"
      state_class: total_increasing
    energy_returned_tariff2:
      name: "Energy Produced Tariff 2"
      state_class: total_increasing
    power_delivered:
      name: "Power Consumed"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_returned:
      name: "Power Produced"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    electricity_failures:
      name: "Electricity Failures"
      icon: mdi:alert
    electricity_long_failures:
      name: "Long Electricity Failures"
      icon: mdi:alert
    voltage_l1:
      name: "Voltage Phase 1"
    voltage_l2:
      name: "Voltage Phase 2"
    voltage_l3:
      name: "Voltage Phase 3"
    current_l1:
      name: "Current Phase 1"
    current_l2:
      name: "Current Phase 2"
    current_l3:
      name: "Current Phase 3"
    power_delivered_l1:
      name: "Power Consumed Phase 1"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_delivered_l2:
      name: "Power Consumed Phase 2"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_delivered_l3:
      name: "Power Consumed Phase 3"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_returned_l1:
      name: "Power Produced Phase 1"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_returned_l2:
      name: "Power Produced Phase 2"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_returned_l3:
      name: "Power Produced Phase 3"
      unit_of_measurement: "W"
      state_class: "measurement"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    gas_delivered:
      name: "Gas Consumed"
      state_class: total_increasing
#    gas_delivered_be:
#      name: "Gas Consumed Belgium"
#      state_class: total_increasing
  - platform: uptime
    name: "Uptime"
  - platform: wifi_signal
    name: "Wi-Fi Signal"
    update_interval: 60s

text_sensor:
  - platform: dsmr
    identification:
      name: "DSMR Identification"
    p1_version:
      name: "DSMR Version"
    p1_version_be:
      name: "DSMR Version Belgium"
  - platform: wifi_info
    ip_address:
      name: "IP Address"
    ssid:
      name: "Wi-Fi SSID"
    bssid:
      name: "Wi-Fi BSSID"
  - platform: version
    name: "ESPHome Version"
    hide_timestamp: true

Anything in the logs that might be useful for us?

No response

Additional information

No response

Fronty72 commented 2 years ago

Experiencing the same problem! Only difference is that I'm running an upgraded version ESP Home 2021.10.2 and Home Assistant 2021.10.6. Upgraded the from 2021.9.3 this morning to 2021.10.2 and the "slimmelezer Web Server" shows no values.

Flashing the Slimmelezer by wireless connection provides me with a error the moment the firmware.bin is being uploaded. After about 4% or sometimes 10% uploading stops and show the following error: "ERROR Error sending data: [Errno 104] Connection reset by peer"

JelleKoster commented 2 years ago

Experiencing the same problem! Only difference is that I'm running an upgraded version ESP Home 2021.10.2 and Home Assistant 2021.10.6. Upgraded the from 2021.9.3 this morning to 2021.10.2 and the "slimmelezer Web Server" shows no values.

Flashing the Slimmelezer by wireless connection provides me with a error the moment the firmware.bin is being uploaded. After about 4% or sometimes 10% uploading stops and show the following error: "ERROR Error sending data: [Errno 104] Connection reset by peer"

I get the same error when attempting to re-flash wirelessly. I'll give flashing by cable a shot this evening.

mmakaay commented 2 years ago

There exists an issue for this in the ESPHome repo: https://github.com/esphome/issues/issues/2577

I wrote a fix for the issue. There is a PR open for it: https://github.com/esphome/esphome/pull/2622

Until the PR is merged and released with ESPHome, you can make use of my patch by adding the following to your device yaml configuration:

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s

Fronty72 commented 2 years ago

Added Makaay

There exists an issue for this in the ESPHome repo: #2577

I wrote a fix for the issue. There is a PR open for it: esphome/esphome#2622

Until the PR is merged and released with ESPHome, you can make use of my patch by adding the following to your device yaml configuration:

I've added the part you mentioned to my yaml configuration, but still getting the error 104

`INFO Reading configuration /config/esphome/slimme-lezer.yaml... INFO Updating https://github.com/mmakaay/esphome@fix-dsmr-read-chunk-size INFO Generating C++ source... INFO Compiling app... Processing slimmelezer (board: d1_mini; framework: arduino; platform: platformio/espressif8266 @ 2.6.2)

HARDWARE: ESP8266 80MHz, 80KB RAM, 4MB Flash Dependency Graph |-- 1.2.3 | |-- 1.0 |-- 2.0.0 | |-- 1.2.3 | | |-- 1.0 | |-- 1.0 | |-- 0.2.0 | |-- 1.0 | |-- 5.13.3 |-- 1.0 |-- 5.13.3 |-- 0.5.0 |-- 0.2.0 |-- 1.2 | |-- 1.0 |-- 0.1.4 | |-- 1.10018.1 |-- 1.1.1 | |-- 1.0 RAM: [==== ] 41.0% (used 33572 bytes from 81920 bytes) Flash: [==== ] 44.1% (used 460276 bytes from 1044464 bytes) ========================= [SUCCESS] Took 9.81 seconds ========================= INFO Successfully compiled program. INFO Resolving IP address of slimmelezer.local INFO -> 192.168.1.166 INFO Uploading /data/slimmelezer/.pioenvs/slimmelezer/firmware.bin (464432 bytes) INFO Compressed to 317840 bytes Uploading: [=== ] 4% ERROR Error sending data: [Errno 104] Connection reset by peer`

mmakaay commented 2 years ago

The 104 error is not related to the patch. It looks like the device is disconnecting during the firmware transfer. I have seen that in really early dev versions of ESPHome 2021.10.0, but that got fixed. Since you're running the stable 2021.10.0 now (right?), I don't know what the issue could be.

Can you hook up the device to your PC and flash using serial? That should always work.

GeertvanHorrik commented 2 years ago

Having the same flash issues. For me the solution is by adding a power cable (Via the usb port) and disconnecting the data port. Then I can flash it again using OTA.

mvdwetering commented 2 years ago

@mmakaay I tried your fix, but it does not seem to work for me, I still see CRC errors in the log and nothing is showing in HA.

Anything I could try to get more info?

Compile output

INFO Reading configuration /config/slimmelezer.yaml...
INFO Updating https://github.com/mmakaay/esphome@fix-dsmr-read-chunk-size
INFO Generating C++ source...
INFO Compiling app...
Processing slimmelezer (board: d1_mini; framework: arduino; platform: platformio/espressif8266 @ 2.6.2)
--------------------------------------------------------------------------------
HARDWARE: ESP8266 80MHz, 80KB RAM, 4MB Flash
Dependency Graph
|-- <ESPAsyncTCP-esphome> 1.2.3
|   |-- <ESP8266WiFi> 1.0
|-- <ESPAsyncWebServer-esphome> 2.0.0
|   |-- <ESPAsyncTCP-esphome> 1.2.3
|   |   |-- <ESP8266WiFi> 1.0
|   |-- <Hash> 1.0
|   |-- <Crypto> 0.2.0
|   |-- <ESP8266WiFi> 1.0
|   |-- <ArduinoJson-esphomelib> 5.13.3
|-- <ESP8266WiFi> 1.0
|-- <ArduinoJson-esphomelib> 5.13.3
|-- <Dsmr> 0.5.0
|-- <Crypto> 0.2.0
|-- <ESP8266mDNS> 1.2
|   |-- <ESP8266WiFi> 1.0
|-- <noise-c> 0.1.4
|   |-- <libsodium> 1.10018.1
|-- <DNSServer> 1.1.1
|   |-- <ESP8266WiFi> 1.0
Compiling .pioenvs/slimmelezer/src/main.cpp.o
Linking .pioenvs/slimmelezer/firmware.elf
Building .pioenvs/slimmelezer/firmware.bin
RAM:   [====      ]  41.1% (used 33668 bytes from 81920 bytes)
Flash: [====      ]  44.1% (used 460168 bytes from 1044464 bytes)
========================= [SUCCESS] Took 62.53 seconds =========================
INFO Successfully compiled program.
INFO Resolving IP address of slimmelezer.local
INFO  -> 192.168.178.35
INFO Uploading /config/slimmelezer/.pioenvs/slimmelezer/firmware.bin (464320 bytes)
INFO Compressed to 317717 bytes
Uploading: [============================================================] 100% Done...

Slimmelezer configuration (the one from the slimmelezer repo with external_components section added)

---
substitutions:
  device_name: slimmelezer
  device_description: "DIY P1 module to read your smart meter"

esphome:
  name: ${device_name}
  comment: "${device_description}"
  platform: ESP8266
  esp8266_restore_from_flash: true
  board: d1_mini
  name_add_mac_suffix: false
  project:
    name: zuidwijk.slimmelezer
    version: "1.0"
  on_boot:
    then:
      - if:
          condition:
            lambda: return id(has_key);
          then:
            - lambda: |-
                std::string key(id(stored_decryption_key), 32);
                id(dsmr_instance).set_decryption_key(key);
          else:
            - logger.log:
                level: info
                format: "Not using decryption key. If you need to set a key use Home Assistant service 'ESPHome:  ${device_name}_set_dsmr_key'"

wifi:
  networks:
    - ssid: !secret wifi_ssid
      password: !secret wifi_password

  # Enable fallback hotspot (captive portal) in case wifi connection fails
  ap:
    ssid: ${device_name}
    ap_timeout: 15s

captive_portal:

# Enable logging
logger:

# Enable Home Assistant API
api:
  services:
    service: set_dsmr_key
    variables:
      private_key: string
    then:
      - logger.log:
          format: Setting private key %s. Set to empty string to disable
          args: [private_key.c_str()]
      - globals.set:
          id: has_key
          value: !lambda "return private_key.length() == 32;"
      - lambda: |-
          if (private_key.length() == 32)
            private_key.copy(id(stored_decryption_key), 32);
          id(dsmr_instance).set_decryption_key(private_key);

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s

ota:

web_server:
  port: 80

uart:
  baud_rate: 115200
  rx_pin: D7

globals:
  - id: has_key
    type: bool
    restore_value: yes
    initial_value: "false"
  - id: stored_decryption_key
    type: char[32]
    restore_value: yes

dsmr:
  id: dsmr_instance
  # For Luxembourg users set here your decryption key. 
  # Enable this when using decryption for Luxembourg;
  # key looks like '00112233445566778899AABBCCDDEEFF'
  #decryption_key: !secret decryption_key 

sensor:
  - platform: dsmr
    energy_delivered_lux:
      name: "Energy Consumed Luxembourg"
      state_class: total_increasing
    energy_delivered_tariff1:
      name: "Energy Consumed Tariff 1"
      state_class: total_increasing
    energy_delivered_tariff2:
      name: "Energy Consumed Tariff 2"
      state_class: total_increasing
    energy_returned_lux:
      name: "Energy Produced Luxembourg"
      state_class: total_increasing
    energy_returned_tariff1:
      name: "Energy Produced Tariff 1"
      state_class: total_increasing
    energy_returned_tariff2:
      name: "Energy Produced Tariff 2"
      state_class: total_increasing
    power_delivered:
      name: "Power Consumed"
      accuracy_decimals: 3
    power_returned:
      name: "Power Produced"
      accuracy_decimals: 3
    electricity_failures:
      name: "Electricity Failures"
      icon: mdi:alert
    electricity_long_failures:
      name: "Long Electricity Failures"
      icon: mdi:alert
    voltage_l1:
      name: "Voltage Phase 1"
    voltage_l2:
      name: "Voltage Phase 2"
    voltage_l3:
      name: "Voltage Phase 3"
    current_l1:
      name: "Current Phase 1"
    current_l2:
      name: "Current Phase 2"
    current_l3:
      name: "Current Phase 3"
    power_delivered_l1:
      name: "Power Consumed Phase 1"
      accuracy_decimals: 3
    power_delivered_l2:
      name: "Power Consumed Phase 2"
      accuracy_decimals: 3
    power_delivered_l3:
      name: "Power Consumed Phase 3"
      accuracy_decimals: 3
    power_returned_l1:
      name: "Power Produced Phase 1"
      accuracy_decimals: 3
    power_returned_l2:
      name: "Power Produced Phase 2"
      accuracy_decimals: 3
    power_returned_l3:
      name: "Power Produced Phase 3"
      accuracy_decimals: 3
    gas_delivered:
      name: "Gas Consumed"
      state_class: total_increasing
    gas_delivered_be:
      name: "Gas Consumed Belgium"
      state_class: total_increasing
  - platform: uptime
    name: "SlimmeLezer Uptime"
  - platform: wifi_signal
    name: "SlimmeLezer Wi-Fi Signal"
    update_interval: 60s

text_sensor:
  - platform: dsmr
    identification:
      name: "DSMR Identification"
    p1_version:
      name: "DSMR Version"
    p1_version_be:
      name: "DSMR Version Belgium"
  - platform: wifi_info
    ip_address:
      name: "SlimmeLezer IP Address"
    ssid:
      name: "SlimmeLezer Wi-Fi SSID"
    bssid:
      name: "SlimmeLezer Wi-Fi BSSID"
  - platform: version
    name: "ESPHome Version"
    hide_timestamp: true

(part of) ESP Home logs

[13:24:48][D][sensor:113]: 'SlimmeLezer Uptime': Sending state 49.97600 s with 0 decimals of accuracy
[13:25:00][D][sensor:113]: 'SlimmeLezer Wi-Fi Signal': Sending state -35.00000 dBm with 0 decimals of accuracy
[13:25:48][D][sensor:113]: 'SlimmeLezer Uptime': Sending state 109.97400 s with 0 decimals of accuracy
[13:26:00][D][sensor:113]: 'SlimmeLezer Wi-Fi Signal': Sending state -35.00000 dBm with 0 decimals of accuracy
[13:26:01][E][dsmr:178]: !FCAF
 ^
Checksum mismatch
[13:26:26][E][dsmr:178]: !4D9F
 ^
Checksum mismatch
[13:26:48][D][sensor:113]: 'SlimmeLezer Uptime': Sending state 169.98100 s with 0 decimals of accuracy
[13:26:48][E][dsmr:178]: !A2AC
 ^
Checksum mismatch
[13:27:00][D][sensor:113]: 'SlimmeLezer Wi-Fi Signal': Sending state -35.00000 dBm with 0 decimals of accuracy
[13:27:09][E][dsmr:178]: !C98C
 ^
Checksum mismatch

mmakaay commented 2 years ago

Are you using an encryption key for your smart meter?

mvdwetering commented 2 years ago

No encryption key

mmakaay commented 2 years ago

Have you tried increasing the Rx buffer size, as suggested in the other issue thread? Would be interesting to see if that changes anything.

uart:
  rx_buffer_size: 1024

probot-esphome[bot] commented 2 years ago

Hey there @glmnet, @zuidwijk, mind taking a look at this issue as it has been labeled with an integration (dsmr) you are listed as a code owner for? Thanks! _{^{(message by CodeOwnersMention)}}

mvdwetering commented 2 years ago

Have you tried increasing the Rx buffer size, as suggested in the other issue thread? Would be interesting to see if that changes anything.
uart:
  rx_buffer_size: 1024
I tried it now and it does not seem to make a difference. Still the CRC errors. Is there any way to get the raw data to make debugging easier?

mmakaay commented 2 years ago

I have written a UART debugger feature, but that one is not yet in the released code. It's still a PR. But, long live external components. You can add my branch to use the modified UART component.

Add the following external component to your config:

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: uart-debugging-external-component
    components: [ "uart" ]
    refresh: 60s

You can choose add an extra -source : ... entry to the external components section that you already have, or you can replace it fully with the above.

Additionally, you'll have to extend the UART component config with a few debug options:

uart:
  baud_rate: 115200
  rx_pin: D7
  debug:
    direction: RX
    after:
      delimiter: "\r\n"
    sequence:
      - lambda: UARTDebug::log_string(direction, bytes);

This will let the device log all bytes that are read from the serial input, so that should give some insight in what data are being processed.

JelleKoster commented 2 years ago

Having the same flash issues. For me the solution is by adding a power cable (Via the usb port) and disconnecting the data port. Then I can flash it again using OTA.

Having the data cable unplugged during installation as per Geert's comment indeed works around the 104 connection reset by peer error. I don't see any additional logging in the ESPHome popup, even after having installed it using above workaround and then doing another install that gets the 104 error without the workaround. Am I looking in the correct place?

I can't help but feel that, since unplugging the data cable is an OK workaround, the issue could very well be related to the problem #2577 works around.

After this, I've also installed your below fix. Despite this, I don't get the values from DSMR yet. I've captured a minute of logging here: https://gist.githubusercontent.com/JelleKoster/96a4919b0a9b7b81c4d02598d5f8ec81/raw/5e034720a4e22c5444d6000f01b485778eafac72/slimmelezer_log

The checksums seem to match except for the carriage return & linefeed. It complains about the checksums not matching though, so is it failing based on those?

There exists an issue for this in the ESPHome repo: #2577

I wrote a fix for the issue. There is a PR open for it: esphome/esphome#2622

Until the PR is merged and released with ESPHome, you can make use of my patch by adding the following to your device yaml configuration:
external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s

mmakaay commented 2 years ago

For me, the link to your log information does not work.

JelleKoster commented 2 years ago

@mmakaay I've made it public now. https://gist.github.com/JelleKoster/96a4919b0a9b7b81c4d02598d5f8ec81 Looks like this link should be better, it loads OK in an incognito tab.

mmakaay commented 2 years ago

Yes, way better :-) What I see in there, is that there are definitely bytes missing in the input. For example when looking at this:

[21:16:31][D][uart_debug:152]: <<< "1-0:72.36.0(00016)\r\n"
[21:16:31][D][uart_debug:152]: <<< ".7.0(00.000*kW)\r\n"
[21:16:31][D][uart_debug:152]: <<< "0-1:24.1.0(003)\r\n"

That second one should have been something like 1-0:22.7.0(00.000*kW)\r\n, but the id at the start misses the start. When comparing the messages, you also see that per message, lines are missing completely. AFAIK, every telegram normally contains the same set of lines.

There is also some really fishy data in there. For example:

[21:16:44][D][uart_debug:152]: <<< "1-0:\xBF&i\xB2r\x82B\x82\x82\x82\x8A\x92Jj\n1-0:72.36.0(00016)\r\n"

There are some non-printable characters in this line. That is very broken. Telegrams are fully ASCII, so these unprintable bytes should never be in there. It looks as if the UART bus is missing bits in there.

Can you check, when you start your logger client, what the UART configuration looks like? Especially the line that indicates hardware or software UART, because the behavior with the unprintable characters almost feels like failing software UART. So check for this line.

[20:50:35][C][uart.arduino_esp8266:106]:   Using hardware serial interface.

mmakaay commented 2 years ago

Oh, wait a minute. Looking at your configs, I see:

# Enable logging
logger:

That is not okay. The logger will be logging using the hardware UART and that will interfere with using UART for reading data from the smart meter. Please, try chaning this code to:

logger:
  baud_rate: 0

Update

I see that Zuidwijk removed the baud_rate: 0 from his example configuration in a recent commit. There was a correct configuration on GitHub for a while, because of a pull request by @jesserockz , but if you used the example configuration before Sep 16 or after Oct 21, then disabling the UART logger is missing in there.

JelleKoster commented 2 years ago

Huh In addition to giving better logging, adding that baud_rate: 0 on the logger as well fixed the 104 connection reset by peer issue (even with the data cable plugged in).

If I also remove your fix PR2622 fix then I get inconsistent (but working) processing. Adding that back in it's fully working again.

I think the right thing to do is check with Zuidwijk whether baud_rate: 0 shouldn't be in there after all. Do you agree @mmakaay ?

zuidwijk commented 2 years ago

baud_rate: 0 isn't needed/required for the dsmr component.

I was working with @glmnet to get the logging to the main uart, while (in my case) connect the dsmr to the 2nd uart (D7). Not sure if it's a left-over from that..?

The reason I use D7 is because of the inverter. The pull-up resistor is needed for the P1 port, that means unconnected to the meter, it's always high and therefore the signal after the inverter is low. If thats connected to the main Rx, it wouldn't be possible to flash it via USB as the Rx is constantly pulled low. The ESP12 has a nice 2nd uart to use (D7 for rx) so that was the best solution for me to use.

I want to spend more time this week to interact with you guys where needed, though I think @mmakaay is doing an awesome job in making this DSMR component much beter.

My ESPHome knowledge isn't that good, therefore I've asked @glmnet to make the component and eventually make it public accessible to everyone by merging it into the core. Many issues therefore is a little abracadabra for me, when digging deeper into the code is needed.

mmakaay commented 2 years ago

I use D7 on my own device for the same reasons. That is all okay. However, enabling UART logging really breaks things.

When you are using the first UART for logging, then the second UART cannot be used for receiving data. The reason is that on ESP8266, the second UART is TX only. This can be easily observed in the log output. If you don't set baud_rate: 0, then you'll see that the logging shows something like:

[11:09:47][C][uart.arduino_esp8266:095]: UART Bus:
[11:09:47][C][uart.arduino_esp8266:097]:   RX Pin: GPIO13
[11:09:47][C][uart.arduino_esp8266:099]:   RX Buffer Size: 256
[11:09:47][C][uart.arduino_esp8266:101]:   Baud Rate: 115200 baud
[11:09:47][C][uart.arduino_esp8266:102]:   Data Bits: 8
[11:09:47][C][uart.arduino_esp8266:103]:   Parity: NONE
[11:09:47][C][uart.arduino_esp8266:104]:   Stop bits: 1
[11:09:47][C][uart.arduino_esp8266:108]:   Using software serial  <------ whoops

After enabling baud_rate: 0, I get:

[11:15:15][C][uart.arduino_esp8266:106]:   Using hardware serial interface.

So please, do use baud_rate: 0 for logger to get hardware serial support for reading the P1 data. There's a reason that @jesserockz provided the PR for fixing this.

zuidwijk commented 2 years ago

Oh crap, indeed and I see I've removed it by mistake ... #sorry

JelleKoster commented 2 years ago

Thanks gentlemen! And no worries @zuidwijk - it happens to all of us :)

zuidwijk commented 2 years ago

@mmakaay where can I find more about the RX Buffer Size: 256? If heard something about this last few days, yet I was too busy with being ill, getting all windows & doors replaced at my house and working on more hardware...

mmakaay commented 2 years ago

The main issue thread in which I investigated this was: https://github.com/esphome/issues/issues/2577

I can imagine that that specific issue would get worse when dropping to software UART, but I also was able to trigger issues with hardware UART and having some other component blocking the main loop for too long. As a TL;DR for you, there are two thing that might help here:

Using my patch, which tries to read the full telegram once reading has started. That does block the main loop for a little while, but that is a good trade-off for having complete telegrams IMO.
Incrementing the RX Buffer size. That should keep the device from missing the header of new telegrams, when another component is blocking the main loop, right when a new telegram comes in. For this one, I would even suggest to increment it to 1500 bytes, which is also the hard-coded maximum telegram size in the dsmr component. In that case, a full telegram could be buffered. So if that is okay for the device, it would be a good addition to the default configuration.

Update

I'm running my device now with both 1. and 2. active. I got stable readings, and haven't lost any telegrams so far. For others who want to try the same setup. Here are the bits of configuration that you can use (do keep other options that you have for logger: and uart:, the config data below only shows you what to add to it):

logger:
  baud_rate: 0

uart:
  rx_buffer_size: 1500

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s

Really interested to see if this combination of settings fixes the issues that people have.

mvdwetering commented 2 years ago

@mmakaay I added the baud_rate: 0 , rx_buffer_size: 1500 and external_components to my configuration (well the external_component was already there from before) and now everything is fine again, no more checksum errors.

I copied the config for my slimmelezer from the example on the zuidwijk Github this weekend so that was indeed after the removal of baud_rate: 0

Lordfritz commented 2 years ago

@mmakaay I added the changes (baud_rate: 0, rx_buffer_size: 1000 and external_components) but unfortunatelty still have the same issue. Uptime and wifi signal strength are regularly updated, but no DSMR data.

ESPHome version 2021.10.2 Logs: https://pastebin.com/Cys6C7ht

mmakaay commented 2 years ago

Please follow the instructions that I posted in this message to setup UART debugging for your device. Right now, it is impossible to see if there's even serial data coming into the device. You can also set the log level to VERBOSE for some more output in the logs:

logger:
  baud_rate: 0
  level: VERBOSE

tomduijf commented 2 years ago

Hey there,

Problem still persists here with 'dev' changes. Do have to note that the errors are less frequent than without the dev version of DSMR.

Configuration highlights

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: uart-debugging-external-component
    components: [ "uart" ]
    refresh: 60s

uart:
  # D5: RTS
  # D6: RX
  - id: uart_bus
    baud_rate: 115200
    rx_pin: D6
    rx_buffer_size: 1024
    debug:
      direction: RX
      after:
        delimiter: "\r\n"
      sequence:
        - lambda: UARTDebug::log_string(direction, bytes);

dsmr:
  uart_id: uart_bus

logger:
  baud_rate: 0

Log part containing the Incomplete or malformed checksum error

[D][uart_debug:152]: <<< "1-0:Sr\xBAp0(00.L\xD3\x93i\xEB\x95j\n1-0:61.7.0(00.0\x11\x93i\xEB\x95j\n\x10K\xD222.7\x87B\x82\x82r\x82\x82\x82RZ]\xA5\r\n"
[D][uart_debug:152]: <<< "1-0:42.7.0(00.000*kW)\r\n"
[D][uart_debug:152]: <<< "1-0:62.7.0(00.000*kW)\r\n"
[D][uart_debug:152]: <<< "/CTA5ZIV-METER\r\n"
[D][uart_debug:152]: <<< "\r\n"
[D][uart_debug:152]: <<< "1-3:0.2.8(50)\r\n"
[D][uart_debug:152]: <<< "0-0:1.0.0(211028103617S)\r\n"
[D][uart_debug:152]: <<< "0-0:96.1.1(4530303639303030373036383032303230)\r\n"
[D][uart_debug:152]: <<< "1-0:1.8.1(003117.586*kWh)\r\n"
[D][uart_debug:152]: <<< "1-0:1.8.2(001903.856*kWh)\r\n"
[D][uart_debug:152]: <<< "1-0:2.8.1(001071.349*kWh)\r\n"
[D][uart_debug:152]: <<< "1-0:2.8.2(002424.847*kWh)\r\n"
[D][uart_debug:152]: <<< "0-0:96.14.0(0002)\r\n"
[D][uart_debug:152]: <<< "1-0:1.7.0(00.494*kW)\r\n"
[D][uart_debug:152]: <<< "1-0:2.7.0(00.000*kW)\r\n"
[D][uart_debug:152]: <<< "0-0:96.7.21(00042)\r\n"
[D][uart_debug:152]: <<< "0-0:96.7.9(00057)\r\n"
[D][uart_debug:152]: <<< "1-0:99.97.0(10)(0-0:96.7.19)(211021220250S)(0010960294*s)(211021220249S)(0010960294*s)(211021220249S)(0010960294*s)(210927013116S)(0008812800*s)(210927013115S)(0008812800*s)(210927013115S)(0008812800*s)(210902050011S)(0006665302*s)(210902050010S)(000666530"
[D][uart_debug:152]: <<< "2*s)(210902050010S)(0006665301*s)(210808082835S)(0004517806*s)\r\n"
[D][uart_debug:152]: <<< "1-0:32.32.0(00008)\r\n"
[D][uart_debug:152]: <<< "1-0:52.32.0(00010)\r\n"
[D][uart_debug:152]: <<< "1-0:72.&&\tB\x82\x82\x82\x82\xBAJj\n1-0:32.36.0(00014)\r\n"
[D][uart_debug:152]: <<< "1-\xD2\xAA\x92r\x9A\xB2r\x82B\x82\x82\x82\x8A\xAAJj\n1-0:72.36.0(00016)\r\n"
[D][uart_debug:152]: <<< "0-0:96.13.0()\r\n"
[D][uart_debug:152]: <<< "1-0:32.7.0\x94\xA6\xA6\x93\tR\xB2\xA5\r\n"
[D][uart_debug:152]: <<< "1-0:52.\x13\tB\x92\x9A\x9Ar\x82R\xB2\xA5\r\n"
[D][uart_debug:152]: <<< "1-0:72.7.0(233.0*V)\r\n"
[D][uart_debug:152]: <<< "1-0:31.7.0(001*A)\r\n"
[D][uart_debug:152]: <<< "1-0:51.7.0(000*A)\r\n"
[D][uart_debug:152]: <<< "1-0:71.7.0(000*A)\r\n"
[D][uart_debug:152]: <<< "1-0:21.7.0(00.269*kW)\r\n"
[D][uart_debug:152]: <<< "1-0\x18S07.0(00.176*!U\xA9H\xF81-0:61.7.0(00.023*kW)\r\n"
[E][dsmr:178]: 1-0S07.0(00.176*!U�H�1-0:61.7.0(00.023*kW)
                  ^
Incomplete or malformed checksum
[D][uart_debug:152]: <<< "1-0:22.7.0(00.000*kW)\r\n"
[D][uart_debug:152]: <<< "1-0:42.7.0(00.000*kW)\r\n"
[D][uart_debug:152]: <<< "/CTA5ZIV-METER\r\n"
[D][uart_debug:152]: <<< "\r\n"
[D][uart_debug:152]: <<< "1-3:0.2.8(50)\r\n"
[D][uart_debug:152]: <<< "0-0:1.0.0(211028103618S)\r\n"
[D][uart_debug:152]: <<< "0-0:96.1.1(4530303639303030373036383032303230)\r\n"

mmakaay commented 2 years ago

Looking at the debugging output, you see really malformed data with invalid characters. My first conclusion would be that you likely are using software UART here.

This suspicion is strengthened by the fact that you are using D6 (GPIO12) as the UART RX pin here. You'll have to use D7 instead, to make use of hardware UART.

You can check if I'm right by checking the configuration dump that you get when connecting to the logger. In the UART configuration, I think you will see that the UART is specified as a software UART, not a hardware UART.

tomduijf commented 2 years ago

The invalid chars were from iffy browser output copy/paste, double checked, regular chars. I got some malformed but mostly invalid checksum errors.

On the GPIO pin used, hmm sure HW UART is more efficient than the SW UART. After switching, issues are gone.

So it's working now, awesome!
Just wondering why these things break now (no criticism! ;)), have been running DMSR with native ESPHome lib and it's predecessor for a loong time, never these issues.
Could it be that checksum errors were silently ignored?

mmakaay commented 2 years ago

No, checksums were most definitely used, so that is not it.

I'm also very sure that bytes got mangled and that it's not only a copy/paste issue. For example the sequence \xD2\xAA\x92r\x9A\xB2 should never occur in a telegram. Those are bytes that are not representing a printable character, that's why I print them as hexadecimal escape codes here. That is the thing that really shows the iffy UART performance here.

And no worries, your curiosity doesn't feel like criticism to me :-) I have no idea what changed exactly between 2021.9 and 2021.10 that causes these issues. I'm wondering about the same thing. Best guess is that one or more components is taking more time than before, causing bytes getting mangled or lost with the software UART. I've even seen issues with hardware UART come up since 2021.10, and that is what I wrote my fix for.

I'm planning to implement some way to measure what % of processing time goes to the UART/DSMR components, and do some comparisons between 2021.9 and 2021.10. I hope to find a root cause for the issues. It might affect other components as well, so better dig deep on that one.

ghost commented 2 years ago

Having the same flash issues. For me the solution is by adding a power cable (Via the usb port) and disconnecting the data port. Then I can flash it again using OTA.

I also had the checksum and 104 error when i did the update to 2021.10.3, but with an powercable and the dataport of it worked when loading my yaml file again. :) And my data reading's are comming in again.

jeroomdm commented 2 years ago

I have the Belgium meter. No Electricity/Gas readings after upgrading to ESPHOME 2021.10.1 Tried all above solutions & still got no readings.

My config:

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: uart-debugging-external-component
    components: [ "uart" ]
    refresh: 60s

substitutions:
  device_name: slimmelezer
  device_description: "DIY P1 module to read your smart meter"

esphome:
  name: ${device_name}
  comment: "${device_description}"
  platform: ESP8266
  esp8266_restore_from_flash: true
  board: d1_mini
  name_add_mac_suffix: false
  project:
    name: zuidwijk.slimmelezer
    version: "1.0"
  on_boot:
    then:
      - if:
          condition:
            lambda: return id(has_key);
          then:
            - lambda: |-
                std::string key(id(stored_decryption_key), 32);
                id(dsmr_instance).set_decryption_key(key);
          else:
            - logger.log:
                level: info
                format: "Not using decryption key. If you need to set a key use Home Assistant service 'ESPHome:  ${device_name}_set_dsmr_key'"

wifi:
  networks:
    - ssid: !secret wifi_ssid
      password: !secret wifi_password

  # Enable fallback hotspot (captive portal) in case wifi connection fails
  ap:
    ssid: ${device_name}
    ap_timeout: 15s

captive_portal:

# Enable logging
logger:
  baud_rate: 0
  level: VERBOSE

# Enable Home Assistant API
api:
  services:
    service: set_dsmr_key
    variables:
      private_key: string
    then:
      - logger.log:
          format: Setting private key %s. Set to empty string to disable
          args: [private_key.c_str()]
      - globals.set:
          id: has_key
          value: !lambda "return private_key.length() == 32;"
      - lambda: |-
          if (private_key.length() == 32)
            private_key.copy(id(stored_decryption_key), 32);
          id(dsmr_instance).set_decryption_key(private_key);

ota:

web_server:
  port: 80

#uart:
#  baud_rate: 115200
#  rx_pin: D7
#  rx_buffer_size: 1500

uart:
  # D5: RTS
  # D6: RX
  - id: uart_bus
    baud_rate: 115200
    rx_pin: D6
    rx_buffer_size: 1024
    debug:
      direction: RX
      after:
        delimiter: "\r\n"
      sequence:
        - lambda: UARTDebug::log_string(direction, bytes);

globals:
  - id: has_key
    type: bool
    restore_value: yes
    initial_value: "false"
  - id: stored_decryption_key
    type: char[32]
    restore_value: yes

dsmr:
  id: dsmr_instance
  # For Luxembourg users set here your decryption key. 
  # Enable this when using decryption for Luxembourg;
  # key looks like '00112233445566778899AABBCCDDEEFF'
  #decryption_key: !secret decryption_key 
  uart_id: uart_bus
sensor:
  - platform: dsmr
    energy_delivered_tariff1:
      name: "Energy Consumed Tariff 1"
    energy_delivered_tariff2:
      name: "Energy Consumed Tariff 2"
    energy_returned_tariff1:
      name: "Energy Produced Tariff 1"
    energy_returned_tariff2:
      name: "Energy Produced Tariff 2"
    power_delivered:
      name: "Power Consumed"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_returned:
      name: "Power Produced"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    electricity_failures:
      name: "Electricity Failures"
      icon: mdi:alert
    electricity_long_failures:
      name: "Long Electricity Failures"
      icon: mdi:alert
    voltage_l1:
      name: "Voltage Phase 1"
    current_l1:
      name: "Current Phase 1"
    power_delivered_l1:
      name: "Power Consumed Phase 1"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    power_returned_l1:
      name: "Power Produced Phase 1"
      accuracy_decimals: 0
      filters:
        - multiply: 1000
    gas_delivered_be:
      name: "Gas Consumed"
  - platform: uptime
    name: "Uptime"
  - platform: wifi_signal
    name: "Wi-Fi Signal"
    update_interval: 60s

text_sensor:
  - platform: dsmr
    identification:
      name: "DSMR Identification"
    p1_version_be:
      name: "DSMR Version"

Logfile:

.......
[09:50:30][C][dsmr:194]:   identification 'DSMR Identification'
[09:50:30][C][dsmr:194]:   p1_version_be 'DSMR Version'
[09:50:30][C][captive_portal:150]: Captive Portal:
[09:50:30][C][web_server:160]: Web Server:
[09:50:30][C][web_server:161]:   Address: slimmelezer.local:80
[09:50:30][C][ota:082]: Over-The-Air Updates:
[09:50:30][C][ota:083]:   Address: slimmelezer.local:8266
[09:50:30][C][api:134]: API Server:
[09:50:30][C][api:135]:   Address: slimmelezer.local:6053
[09:50:30][C][api:139]:   Using noise encryption: NO
[09:50:30][C][wifi_signal.sensor:009]: WiFi Signal 'Wi-Fi Signal'
[09:50:30][C][wifi_signal.sensor:009]:   Device Class: 'signal_strength'
[09:50:30][C][wifi_signal.sensor:009]:   State Class: 'measurement'
[09:50:30][C][wifi_signal.sensor:009]:   Unit of Measurement: 'dBm'
[09:50:30][C][wifi_signal.sensor:009]:   Accuracy Decimals: 0
[09:50:30][V][wifi_signal.sensor:009]:   Unique ID: 'c45bbe62b4fb-wifisignal'
[09:50:50][V][sensor:062]: 'Wi-Fi Signal': Received new state -27.000000
[09:50:50][D][sensor:113]: 'Wi-Fi Signal': Sending state -27.00000 dBm with 0 decimals of accuracy
[09:50:52][V][sensor:062]: 'Uptime': Received new state 66894.296875
[09:50:52][D][sensor:113]: 'Uptime': Sending state 66894.29688 s with 0 decimals of accuracy
[09:51:50][V][sensor:062]: 'Wi-Fi Signal': Received new state -27.000000
[09:51:50][D][sensor:113]: 'Wi-Fi Signal': Sending state -27.00000 dBm with 0 decimals of accuracy
[09:51:52][V][sensor:062]: 'Uptime': Received new state 66954.289062
[09:51:52][D][sensor:113]: 'Uptime': Sending state 66954.28906 s with 0 decimals of accuracy

mmakaay commented 2 years ago

The log output does not include the UART information, but chances are high that you are also using software UART. Reason for this: I see that you're using rx_pin: D6. Switch to pin D7 instead to make use of hardware UART.

If that doesn't work, then please follow the information from this post to setup debugging for the UART. That way you can see what data is being received by the UART, which might help us determining the issue here.

deejaybeam commented 2 years ago

Hi - same problem here. Never got it running.

My Smartmeter is a Siemens im350 with encrypted signal.

relevant yaml:

esphome:
  name: ${devicename}
  comment: "${device_description}"
  platform: ESP8266
  esp8266_restore_from_flash: true
  board: d1_mini
  name_add_mac_suffix: false
  project:
    name: zuidwijk.slimmelezer
    version: "1.0"
  on_boot:
    then:
      - if:
          condition:
            lambda: return id(has_key);
          then:
            - lambda: |-
                std::string key(id(stored_decryption_key), 32);
                id(dsmr_instance).set_decryption_key(key);
          else:
            - logger.log:
                level: info
                format: "Not using decryption key. If you need to set a key use Home Assistant service 'ESPHome:  ${devicename}_set_dsmr_key'"

external_components:
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: fix-dsmr-read-chunk-size
    components: [ "dsmr" ]
    refresh: 60s
  - source:
      type: git
      url: https://github.com/mmakaay/esphome
      ref: uart-debugging-external-component
    components: [ "uart" ]
    refresh: 60s

# Enable logging
logger:
  baud_rate: 0
  level: VERBOSE

# Enable Home Assistant API
api:
  password: !secret esphome_api_pasword
  services:
    service: set_dsmr_key
    variables:
      private_key: string
    then:
      - logger.log:
          format: Setting private key %s. Set to empty string to disable
          args: [private_key.c_str()]
      - globals.set:
          id: has_key
          value: !lambda "return private_key.length() == 32;"
      - lambda: |-
          if (private_key.length() == 32)
            private_key.copy(id(stored_decryption_key), 32);
          id(dsmr_instance).set_decryption_key(private_key);

uart:
  - id: uart_bus
    baud_rate: 115200
    rx_pin: D7
    rx_buffer_size: 1024
    debug:
      direction: RX
      after:
        delimiter: "\r\n"
      sequence:
        - lambda: UARTDebug::log_string(direction, bytes);

globals:
  - id: has_key
    type: bool
    restore_value: yes
    initial_value: "false"
  - id: stored_decryption_key
    type: char[32]
    restore_value: yes

dsmr:
  id: dsmr_instance
  uart_id: uart_bus
  crc_check: false
  # For Luxembourg users set here your decryption key. 
  # Enable this when using decryption for Luxembourg;
  # key looks like '00112233445566778899AABBCCDDEEFF'
  #decryption_key: !secret decryption_key

log:


[19:24:12][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:12][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\x9B\xC9U\xA5\xFF\x12\xD2\xC1\x81\xAF\xFD0\xF7\x9C\xAD~\x8A&\xBD4V\x81:\xF9&$\xDE\x9F(&\xC8o!f\'g\xEC[\x80\xD0O\xC5vMHT\x83\x88K\x9D\xE2\x04\x10\xF6%Z\xBCLt\xF8 \x18/\xE1\t\xD7U{\xEC\xD1O\xAD\xC7\x0F\x85pw\xD2\xC7wvk\x15\xF1\xC7zU^]f+\xBAU~"
[19:24:13][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:13][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\x9C\xBB2nyC`\xE6\x96<\xFB\xEA\xA2\xEB\xC3\xFB\xC3\x98]!\x06\x8E>\xDD\xC2:\xF4f\v=\x16F(\xFA\xC5Q\xF2\xA0\xB2\xC8\xDE96\x91`\xE5v\xD8\xC1\x19%\x82\x91\xEBn\x9B3$\x87\x00\xAB\x96<R\x97V\xFE\xC8\xE8\xAD>-d\x1F3\xBEs\x86\xFC\xA3\x93z\xEC8\xC2\x1F*\x96\ayB\xB3~"
[19:24:14][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:14][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\x9Dm\x8AR\xA8\x1D\b\x9D[\x9DR\x13\xA3:\xCA,XAM?QE\xFC\np\xA0\x9BR\xCC\xBCN\x86\xFE\xF3\xA2V\xFD\x90\x1A\xF3\x16\xC1\xC0%\xBF\xA5\x95h\x13\xC1V;\xBF\xDD$\xB9\xD7\x00\x05\x0F\x99e\xB8\xF1Q\"\x87\x13\xEFM\x83\xB5\x04yh\xD1;\x1Df\x96;\x9Ar\x87\xAD\t\xC4\xDA\xAEv\x14G\x85~"
[19:24:15][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:15][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\x9Ev\x0E\xA0\x95Q{}s\\A\x00R\x14\x00\xFC\r\x18\xD6\xD16\xBA\x89\x013S\xB8\x96\xE2\x12\xE5` \xF6\x9E\x83\xD4+]\x82gD\xAE\xB2&\xDF\xD8\x86\x04\x97\xCC\xB8\xE3\x03)\e\xE23\xE7\"\xE7\xF9b\xF4\x9B\x10\x0F\x00\xCDI$9\xAC\xF8\xCA\xA1\xE4\xE7\x1D\x13\x97q\xA0\x06\x17Q\x90`J\xE7[Q\x90~"
[19:24:16][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:16][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\x9FG\x00\xCD\x1E\x91Q\xE6\xC5\xF2\xE5\x06~@\aI\x90\xDC#\xFA\xAAV@\xF1\xFF\x8E\xC4l\bl\x05\xE1\vh\x1A\x9E\x04\xDCm\xB35\xF8\xE5$v\xFA\x98\xAEx\x85\x8E\xCD\x8E\x85\xA1a/\xAB\xDE\xC2\xCB\x98_6\x0E\x14\xB4\"\x138I\xCENs\xB8\t\xC0\xC2\xBD\n|\xBE\x8A\xBFVv\x9E\xADP)ID\xD7~"
[19:24:17][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:17][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA0\r\xFE>\x1E\x13Vj\xCA\xD1\xBE$d\xABq\x86\xB3\xC2j\x02\xBA\xF6\x00y\xCF1:\xABxQ\xB1J\xD9_\x8F\x13\x9B\xD9\xEDA\xBC5\xE8m\xE0Mow\xB0.\xA7\xC1h\xD5X\xE9\xF9\xD8\xB2:>\xF4{\x01\xDA\xBB\xD5\x1C\xA7u{6\xEE\xFA\x02X9\xB179i\xF6\xEA$\xA4\x8B\xEC\x87:y\xAA<~"
[19:24:18][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:18][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA1-\xFD\xC3!\xFE\xEF\x14\x8D\x9A)\xBD\x86X9\xCC\x94b\x106\xB40\n\x1CR\xC5\x04\xD6\xA3\xDF\x0F)\xB2j@/e 96\x84cu6F\xAE\x16\xE5\x82 \x00{Y\xA8\x8A\xD2\xD9\x8B\xBEL\x85\xF7\x95\xB2\xF4\xE4\xBA\xDA\xC8\xC9o\xDA\x94\xFFW\xED6\xA2\x8D\xB0\x89\xB6DU\x14\xF5\xEF\xE1\x97p\x82y~"
[19:24:19][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:19][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA2\xB3\x9D}C\xE5\xF0/\xDE\x95\xFFS\x9E\xA9\x85,\xBA\x15\xA2\xE9\xCD&h$\xF3&(\x90\x90\x92?0\xFB/\x88-.\xD5\xEC\x9A\x03\xA4\x92\v\xA7#\xE2\xB74x\xB3?!&IW9\xBF\x17\xE3\x94;\xDB\x12\x1C\xC0\xEC\xB3\xE7H\xFA\xDC\xFE\x9AXc\xF19Gq\xE6\x89\f\xA8\xD6\xE0\xA13\x85\x18J\xE6,~"
[19:24:20][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:20][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA3\x9D\xB8\xCA\xE1\x89D\xA5R\xDCv=0\x9Dx1\xFC\xD4\xB0\x18\x9A\xF5\x12\x8D\xCA\xEC\xCF\be\xE1\xE5\xCF!\x02\xDC\xFC\"6\x1Ew:\n\x94Mm\xCC\xA2\xAErnSk\xC8\x8A\xA3O\xA8\xE7\x98#\xE1(4\x9E\x9D@\xE1\xC6=f\xD4v\x9E`\xCFkF6\xB3\x1F\x9B\xA0a\xC2\x8A\xA8\xCA\x8D\xCDG]\xBD\x01~"
[19:24:21][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:21][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA4|Q\xF6\x96\x114\x95Fp8\x17|9\x015\xEB\xC7_}\x05\t{Vk\xC2\xF1\xAFt\xE1|\xBB\xF8\x00\x00>3:\x9A\xAC\x87vD)S\x87F\xEEG\xAF5#\x92X\xA68~\xCB\x17\xFC\xE5\xF3}\xCF\xB5+\xE9\x05D\xE0\xDD?\x98~_\x8F\x94\xA9\xE7\x16\xF7\xE2\x02\xC17\e\xE65\xBE)\xD5\xE5\xC7~"
[19:24:22][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:22][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA5\xC9\xD9\xB4\xBD\xCD\xDBe\xD4\xAE(z\xC5\xCBw\xDB\xD2\x18\x85,,4@\x90\x15#\x82X\xB9\xFF\x98/\xF7\xEF5\'u\xAC*\x81&\xF7\x98h\xCE*a\xD2(\x9B\xA4\x1F\xA8\xF2\xFC@\xF60W\b\x87T\x12\x8D\x1C\xACw#)\t\xA2\"\x0FR;}\x8A\xF5\xC1I\x82\x9E\x17\x90\xE3\xB7\xA9\xC1\xF1\x8A\xCCq\xC8~"
[19:24:23][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:23][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA6\xB5\x10dlB\"\xDA\xE3\a?\x8F\xBA\xC8\x19M\x96\xB7\xB7=?\xF9e\xAC\xD5\xB8O\xB19\nU\\T\xDF[\x16\xA6U\f\x1A\n6\n]h\v\x8EO\x00\xF9G\xCF\xEAd\x81W\xDF\xC2\x9AG\xA50\xF2<\xF9B\xF3\x92\x02\xDA\xFD\x873\xEB\xF3\xC8\x81\xA7<\xB3\xDBb\xB9A\xA7\x12Ub\xFC\xFE\xCB\xF0\xE7~"
[19:24:24][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:24][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA79f\xD7\x12\xF6\x15\x96E\f\x8B\x88\x18\xE5.\xEC+\r\xDB@\x95pj\x85\x17~\xD5\r\xD3\xC3\x90\xD5D\xA2X\xF9\xBFvy\x86\x14m\x8D\x10\xEA\x8C\xFBCy>]\xA0_\xAD8\xB3\xDDn\x0F4\e\xBC\xF2]{R\xCA\xDC\x9B\x02)gg\xBDgP\xD5\x1A\xF1\xD6\xFD/\x1DE\xF6\xDC\xBE\xA9\x85\x13\x81\xA5\x91~"
[19:24:25][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:24:25][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01@\xA8\x841b\xD6\x1F\xD6\'\xA5\xF8\xF6\x06\xB2/h\xD5\xF1G\xAC\xD8h\\\xE9\x05\xC2[\xF8\x88\xCC\xE3/\x02<\x15\xE8\xDE\xE0w\xDE^\xED+\xD9R\xDFC\xB2\'h\x14A\x1E\x1E\xB8\xB0\xCE\fqd[\x04\x0E\xFA\xFDJ\x1A\xF3qh6\x94\xA3y|\xC7C\xC8\x01s\xBC\\\xE0\x18`\x8A\x83B}\xDF=:\xB3~"
[19:24:26][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.

when I set crc_check: true the log is:

[19:41:11][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:41:11][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01D\x96\xA4\xFBU\x86\x19\xE4\xC5\xB6\xC3\xB1k\xB4>\xBF\xE8\xD8\v\xFB\x1A\xE7e\xCE\xC4\xFF\x81\xA8\x01`\x95{hY\x16\xB8\x91C%\x93\xD8\xC2YB\xE3\x8C][7\x19\xFF\x16\x84@K\\\'\xD6\xF9@\x9D\xDA\xDDW\xA0\x91\x05\xE9\x0E\xBF\xBCp\x81\x8A\x8C\'ay\xE1B\xA0N\xC8\xA0H\xB6p\x93\xA4)\xCCI\xF8=~"
[19:41:12][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:41:12][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01D\x97\xEB\x1F\xCAD\x13\x84w\xD0\x95I*@`\x8BY\x82\xD6r\v\xBD\xEA\xA3\f\'/\x06m\x87x\xFB\xDE\xE9\xB9\xF8\xCE\xD5\xB3\x05F\xDCz\xC1\xD7y\xC5<\xA9![ h\xAC\xBD\x1E\xCA\xE8\xB1\xC2n}\x85f\x91I\xC2W\xC3\x89\a\xBF\xE0ze\'\xD0\xF2n\xB3\x194\xB7\xCB<Q\x9D\xE9\xDD\xDC\x13D\xFC~"
[19:41:13][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:41:13][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01D\x98[Hn\xDF\x1A?\x10@\xBD\xC8\xA3\fl\xBCS7\fD\xCCd\a\xD8\x92n\xFDW\xC7\xC8\x8Dy\xA5\xC0?Y\\zEI\xBAa\x15\x84kux\xFE!(\t\x9Bn\xCC\xA8F\xADs!\xD1f\xA4\xED\x98\x94\xD9J\xB5\xFD\xA0\xD0\xC51\xA4\x91\xD4&\x13\xF6\xFB\xEA\xC9X\xA75Fo\x1A\x8D\xC8<\x19\x99~"
[19:41:14][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:41:14][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01D\x99\x04E\x06\xD7e&4W\xCC72\fZ\xD15\rK\xDB9\xB8\x851:\xF2\x06=\xC6\x9DM \xAFE\xB8\xF9y\x0F8X\xDA\x9A?C\xD2\xAA\xB8\xB5\xA65\xBF]\xA0UKp\x9D\xF7\x02\xCF\xF9R\'IG\xF12\x8D\x8D\v\xA7\xF43\xF3\xC9K-\x00\x91\x1EJ\xCD\xF2S\x1A\x94\xD8\xD5\x8B\xEE\xC3Vo,~"
[19:41:15][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:41:15][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01D\x9A*\x01o\xF0.\t\xC6f\xC8\xC7n\xD4\xB3\x17\x13\xD2\x1D\x82\xA7}\x03:\xE2\xDD\x19\xF5i1\xCC\"\xA8\"X\xBDI\x8A\xF7\xFE\fj\xEC\xB9\xA5\xC8\xD1a\xDB\xDB\x913\x85\x01\xFBM-M\xF9\x18\xF1\x0E\x8DeY\xA4w\xFA\xDD\xD4|hgz%\xA1\x99\xFE\xE3\xDE\x90\x0E \xBF\xA4|8n\x06(\xA4\x1A\xCD~"
[19:41:16][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.
[19:41:16][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01D\x9B\v\xE6\x94\xC8\x8A+F\xF7\xAF~?\x03$\xE8\x16\x9F\x00\xD5\x8C\xD0\x92\xDF\xC2X\xCA\x81\xBF$H\xB3\xEBsIO\xFE\x8B\x96\xEC\xA83\x14\xA2\xFAtQ\xC6\xC1\xF2\x80\xCE\x87\xA2\x93_3\xAD\'\x02\xE9\xD8bGO|\xB8< ~\x1D\xA5\xA8\"\xB1\x06\x12\v\x1Cs\xE8\xD4\xE8)\xC51\xE1G?s\xE7l`I~"
[19:41:17][W][dsmr:165]: Timeout while waiting for encrypted data or invalid data received.

sometimes again the message:

[E][dsmr:114]: First byte of encrypted telegram should be 0xDB, aborting.

same Hardware works with this project: https://github.com/saghonfly/shrdzm/wiki/Siemens-IM350,-Iskra-AM550

edit: sorry - should be better in this issue: https://github.com/esphome/issues/issues/2600

mmakaay commented 2 years ago

Yes, your specific issue is related to #2600 , but no worries about that. The issues are closely related.

You are using encrypted DSMR, which means that the currently included external component version will have no benefit. That one is only for unencrypted DSMR, and the encrypted DSMR code is left untouched. In #2600 I have published a different code branch that implements the same kind of fix for encrypted data. To use that branch, change ref: fix-dsmr-read-chunk-size in your config to ref: fix-crypt-mdsr-chunked-reading. I'd love to hear if that fixes your issue.

deejaybeam commented 2 years ago

Hi - awwsome!

log with crc on:

[20:38:17][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:17][V][component:186]: Component dsmr took a long time for an operation (0.28 s).
[20:38:17][V][component:187]: Components should block for at most 20-30ms.
[20:38:17][C][wifi_info:011]: WifiInfo SSID 'dsmr01 Wi-Fi SSID'
[20:38:17][V][wifi_info:011]:   Unique ID: '2cf4324b23da-wifiinfo-ssid'
[20:38:17][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01Q\xE3f{\xC8\xA35\xC7\x1D\x864+\xAF\xF3\xBCOw<\x05\xC8Z\xE4\x18o>\x93>;L\xF1\x0Ei\x18\xEA\xC7m\xC2\xE8\xF9\x14\xCAg\xA0\x90Y[k\xB0N\xF6\xD2\xC8\xE5e\xB1\xBB([\xEF@\x8E\x89\x9BT\xEC*v\xA7y\xC0w\x14c\xAE1\xFD\xF0\xEF\xB8\xF0\xC1\xE3\ay\xDA\xD3\xD4\xADdo\xEF&1~"
[20:38:17][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:17][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:17][V][component:187]: Components should block for at most 20-30ms.
[20:38:17][C][wifi_info:012]: WifiInfo BSSID 'dsmr01 Wi-Fi BSSID'
[20:38:17][V][wifi_info:012]:   Unique ID: '2cf4324b23da-wifiinfo-bssid'
[20:38:18][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:18][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:18][V][component:187]: Components should block for at most 20-30ms.
[20:38:18][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:18][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:18][V][component:187]: Components should block for at most 20-30ms.
[20:38:18][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:18][V][component:186]: Component dsmr took a long time for an operation (0.30 s).
[20:38:18][V][component:187]: Components should block for at most 20-30ms.
[20:38:18][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01Q\xE4\x1D\xCA\x01\xC1&V\xE9\xE4\xA28\xF2\xBB|\x86\xA2tF\xD3\e\x84\xC5\xF4\xEA\xC7!\xD0\xAA\xA2\xE5U\xB29\xEA\xE3\xFA\x03>\x82\xD4A\x13\xA8\x98\xD1i\x05\xF4\vD8\x01F\x15\x9D\x054Y\xA2\x1F\t\xEA\x02\xD1\xBA\xED\xA0h\xFD\x9A\xFD\xE4\x06$\x12@\x02\xCA\x8De\x06g?b\xF9\xBB\x844\x9F\xA2\xB2\xE7~"
[20:38:18][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:18][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:18][V][component:187]: Components should block for at most 20-30ms.
[20:38:18][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:18][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:18][V][component:187]: Components should block for at most 20-30ms.
[20:38:19][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:19][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:19][V][component:187]: Components should block for at most 20-30ms.
[20:38:19][D][api.connection:157]: Home Assistant 2021.10.6 (192.168.31.215) requested disconnected
[20:38:19][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:19][V][component:186]: Component dsmr took a long time for an operation (0.31 s).
[20:38:19][V][component:187]: Components should block for at most 20-30ms.
[20:38:19][D][api:098]: Accepted 192.168.31.215
[20:38:19][V][api.connection:720]: Hello from client: 'Home Assistant 2021.10.6 (192.168.31.215)'
[20:38:19][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01Q\xE51)4\x914\x8D3\xE8Y\x8F\xEF\x17\xC28\x91\x12\xDF\x9DK\x8B\xFE\x81\xE5\xDD\xE1\a\xDF\xEC1\x91\x86Q\xD1[.\xBE\xF7\xEE%5\xD6\xB9\xC4\x8CQ\xEDGa\x83\xE0s\xF6\xAC\xFB7&nd\xB2\x82\xAE\xCB\x00\x045\x04\x91x\xF0\xA8\x85\xE7\x8E\xFF\xBC\xC6\xC6\x82\x1D4G\xF3?7)\x15q\x85\xCDr\x19~"
[20:38:19][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:19][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:19][V][component:187]: Components should block for at most 20-30ms.
[20:38:19][V][api:110]: Removing connection to Home Assistant 2021.10.6 (192.168.31.215)
[20:38:19][D][api.connection:736]: Home Assistant 2021.10.6 (192.168.31.215): Connected successfully
[20:38:20][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:20][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:20][V][component:187]: Components should block for at most 20-30ms.
[20:38:20][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:20][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:20][V][component:187]: Components should block for at most 20-30ms.
[20:38:20][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:20][V][component:186]: Component dsmr took a long time for an operation (0.30 s).
[20:38:20][V][component:187]: Components should block for at most 20-30ms.
[20:38:20][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01Q\xE6D\xE2\x8Fq\x95\x88s\xEC\xE9:\\\xD3\xE5\xE4\x9C\xD6!{z\xBE\xD7I\x81\xADt\xFD\xB8\xAD\x8Dh\xB1\xCDG\x82,\xE1\xBE\xAC\x13B\x911\xAF\x9D\xC1/T\xED\x8E\xF3\xF4\x15\xB6\xE7{}\xBFY6\xBE\x8C\x85\x02\xA3\x9Dz%}\xE4yG^\xFA\xA8\xA9\xEC0Of\x06q\x97\x14\xC0@\x0E\x89\x9C\v\xAE\x9F\xD0~"
[20:38:20][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:20][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:20][V][component:187]: Components should block for at most 20-30ms.
[20:38:21][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:21][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:21][V][component:187]: Components should block for at most 20-30ms.
[20:38:21][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:21][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:21][V][component:187]: Components should block for at most 20-30ms.
[20:38:21][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:21][V][component:186]: Component dsmr took a long time for an operation (0.30 s).
[20:38:21][V][component:187]: Components should block for at most 20-30ms.
[20:38:21][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01Q\xE7\xEC|V+\xE2k\x06\t\xEB\xDE\x1D8}\v.wJ<\xE2\xD9\xCB\xFA\xEF\x0E\xC1\x16\xE3\e\xF6{1/O\x9FZ\x02\xED\xF1\xD8\xF7\x0F\xB4\x8E]K\x910\xB7_J\xF1\xFE\xBCY~i\x99\xCB\xE3\xE4\xFC\xAF\a0\xB6\x11X\xF8p?\x9D\xBF-T\xA4E\x98\x04|u\xC3V\xE62w\xCF\x9E\x8A\xEA\xBF\x95\x81~"
[20:38:21][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:21][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:21][V][component:187]: Components should block for at most 20-30ms.
[20:38:22][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:22][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:22][V][component:187]: Components should block for at most 20-30ms.
[20:38:22][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:22][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:22][V][component:187]: Components should block for at most 20-30ms.
[20:38:22][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:22][V][component:186]: Component dsmr took a long time for an operation (0.30 s).
[20:38:22][V][component:187]: Components should block for at most 20-30ms.
[20:38:22][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01Q\xE8\x8EI\x93\xC6\xE4\xEA^\x8BAT\xB2W\x94\xF7\xEC+~\xD8\x18=\xFE\x96\xFF\x99\x88\xA7y\xCCY\x8CU\xB9EG\xF9\xA2@\x86\xD5\e\xA6\xD6K\xC9\xC3\xBC\x92[Yf\xA1\xD2\"\xB8\xA4\xAF\x13\xD5\x16\f\x884a\x05\x1E1];\x96\xFD\x01\xFC\xB7B\x0F\xA9\xF4\x89\xAC\xCC\x1E\x9A\xF0\xD8N@;h\xAF\xB4\xD1\x9E~"
[20:38:22][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:22][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:22][V][component:187]: Components should block for at most 20-30ms.
[20:38:23][W][dsmr:099]: Timeout while waiting for encrypted data or invalid data received.
[20:38:23][V][component:186]: Component dsmr took a long time for an operation (0.20 s).
[20:38:23][V][component:187]: Components should block for at most 20-30ms.

logs with crc off:

[20:41:49][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xB7=\xC0B\xD4R\xA6A\x8E\x1E\x8A\tX\xEAX\xE6\x02\x1A\x8Do\x9E^o\xA3\xFB\r\x0E\x0E\xB9Lt*[M\xF48J\xCA*\x8F\'\xCD}7\xE1ph \x95C-\xC6\xE8\xED\x98\x86\'\xAD\x91bl,1\"\xE48\x9DN\xC3g\x12\xCD\xED\xA5~\xEFP\xB4h\x85\x9C\xC3\xBB\xF5\xFA_\xFF\xFD\xDCZ\x87\x13\x9A~"
[20:41:50][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:50][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xB8\x05\x98\xDE\xCA\xC5\xBAD\xE4\xB3Lv\xA7\xBD\xA2\xAE-\xB9\a(\x9C\xB1\x96^\bc\xE4\xBB\xE6\"\xC1~xP\x9E\x8A\x85<\x1F1\xE6~\x90\x1E}!\xB9\xDB\xAE\xB0+:\xEA\x8B\x12\xE1\xFB4\xFE=#6\xCC\x18\x03\xC2\xE0\x19C-\\\xE0\xA9{\x87\x12\x92I9b\xF4@Xxv\xC6\r\xAD\x89\x92\x94}\xBC~"
[20:41:51][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:51][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xB9R\x88\xB3q\xE0\x05\xD9q9(\xD0\x03\xB0\xE0\x86(\xEC|\x8A\x99\xD0d\xF1a\t2\xF8,B,\xE2\x1C;\xDDQC\xE6n\xD0\xD7\xFB\xF7[\xF9\x14\xBC\xDA\'\x8C\x1FF\x9CHIr\x19\xF39\x14\xB6\x85\x01\x9A\xD0ro\xD7\x17\xE0(\xB3\x017L\xD0l\x99\xBA\x83\x8A\xDC\x8F\xCD\x8C\xD1\x96\xE3\xCAP\xF7\'~"
[20:41:52][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:52][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xBA|\xBA[\xF2\xE390\xC7\xF1\xE6\xA4:\xD7\xA1\xB2\b\x90\xA2\xFF*\x82\xBD\x9B\xA1IHK\x05\xC4\x00\t\xF1>\xB8\xFA\xDE^\xBE\xC0KA\x8B\x99sn\xD2\xFC\aW\x16}\xCC\x1D^\a\xA1\xD76\n\xA98\xF5%U\x94\xA9\x91\x15E\x88\x91\x81\x86|\xC8\xEB\x1CdE\xE2\xE9i/\x1E\'A\"C\f\xBB3\xB2~"
[20:41:53][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:53][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:53][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xBB\xC9\xADlG\"\x9C\xBEc\x9E\x1A\xD3\xBCh\xBA\xB0\x0F\xE4\x8D\xAC>\xE45q\x9Dw\xD6j\x03\r\xA14M\x8Bz\x02\x18?(\x91\x95$X\xEA\xE7\x11|\x95\x10\x88\x10G\\\xEB\xBA<A\xC4\xC0-\f\xDC\x9AH\x00\xE4\x9E!\xB6\x1C\x13\f@\xDC2\xF3Uq\x91C\xA8\xD0\xE9\xF5\x87\xCA\xFF\x13\xCD\xDD\x84|~"
[20:41:54][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:54][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:54][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xBC\xCCol\xBA\xB6(\xC4\xB2\xCC=\xA0\x1E\x02\xE7\xE0\xAD]L\xA1\xB1\xE1e]fO\xB8\x97D\xCC\x9Aor\xCD\x8C\xF3\xBA\x927RM\xE0G2\x16\xE7\xBF\x8E\xFA\xF2\xEE#jq\x0FC\xF9\xF2H-\x8EhN\xFD\xB8\xB5\x91\x8F1\xF7\xB1\xBA/>\x14\xF1\xBE\xB9\x85z\xA0c:\a\xD2\'\xFF\x1C\x91x\xB4~"
[20:41:55][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:55][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:55][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xBD\x9A\xCC\xF5scE\xB4\v`\x8BgPY\x1E`\xFD\xD9\xF1\xE9\x1F\x8F\xF9\x02\xE8H\xC6S\"\x1C\x14\xB6k\xB7\xD4\x81\xC4+\x18\xC7^\a\xB3\xDE\x8A\xA4\x8D\xA3#\xF9\x1F{\x10\xCE\x9A-F\xE3\xBF\xA7\a\x14\xBA\xAE\xA8`VJ\x9EN\x03\x06F\xB2\xB8\x01\xBF\x05]2\xCB\xCE\xCA\x8B\xB9{9\xBE\x00\xE0\xA5\t\xEA~"
[20:41:56][E][dsmr:116]: First byte of encrypted telegram should be 0xDB, aborting.
[20:41:56][D][uart_debug:152]: <<< "~\xA0y\xCF\x00\x02\x00#\x13\xD9\x86\xE6\xE7\x00\xDB\bSMSgp\x04\xED\xE9_ \x00\x01R\xBE\xFB\xEC\x85/s\xC9\xC4;\x99k%\xB4l\x9DKh\xAB\xB2\n}Flg\x13jk8\x02\x96o\xD4%\xA3\xE3t\xBF@B@\xDE\xCE\xC9\xD0\x1F\xD8\xEFQ\xF0\xE9\xCA\x8C\f*\xB4\x9E\xFE\xAB\xBD\x91\x01e\xB4a[\xA5\xEAa\xB2w\xA5%E\xACq\xF3\x99l\xF3N\xD1(`\xBFy\x19u5\xEA>\xD0\x9D\xCD~"

so - no, does not work...

mmakaay commented 2 years ago

Better continue this in #2600 then, doesn't look like we've got a quick fix here. In that issue report, please include the full startup info from you logging, so we can check how the components are setup. Also check the setup of the UART debugger in that other issue thread. For logging encrypted data, the strings logging is not very useful. Using hex logging makes more sense.

deejaybeam commented 2 years ago

Yes, will do that. Just one question: how do I change to hex logging?

mmakaay commented 2 years ago

Just one question: how do I change to hex logging?

This should do it.

uart:
  - id: uart_bus
    baud_rate: 115200
    rx_pin: D7
    rx_buffer_size: 1024
    debug:
      direction: RX
      after:
        bytes: 20
      sequence:
        - lambda: UARTDebug::log_hex(direction, bytes, ' ');

deejaybeam commented 2 years ago

compiling.... posting logs to: https://github.com/esphome/issues/issues/2600

GrumpyMeow commented 2 years ago

I also have the same issue Adding rx_buffer_size: 1000 under the uart-node resolved the issue for me. Thanks guys!

Multipage commented 2 years ago

I'm having same issues and used the external component from @mmakaay. Unfortunately it went went from DSMR:154 to DSMR:168.

Here is what i see. [14:56:49][D][text_sensor:067]: 'DSMR Version': Sending state '50'

[14:56:50] ^ [14:56:50]Checksum mismatch

[14:56:51] ^ [14:56:51]Checksum mismatch

[14:56:52] ^ [14:56:52]Checksum mismatch

[14:56:53] ^ [14:56:53]Checksum mismatch [14:56:54][D][sensor:113]: 'Energy Consumed Tariff 1': Sending state 2243.50708 kWh with 3 decimals of accuracy

[15:01:12] ^ [15:01:12]Checksum mismatch

[15:01:13] ^ [15:01:13]Checksum mismatch

[15:01:14] ^ [15:01:14]Checksum mismatch

@mmakaay where did you edit the chunksize in the code(file and line). I'm very new to ESPhome but want to get this fixed but don't know where to look atm

jsuanet commented 2 years ago

@Multipage Can you paste the uart and logger part of your yaml and also the configdump from the logs. I suspect you are using software UART instead of hardware UART.

Multipage commented 2 years ago

@jsuanet : The yaml parts (now):

uart:
  baud_rate: 115200
  rx_buffer_size: 2048
  rx_pin: D7
  debug:
    direction: RX
    after:
      bytes: 20
    sequence:
      - lambda: UARTDebug::log_hex(direction, bytes, ' ');

logger:
  baud_rate: 460800
  level: VERBOSE

Log output (if this is what you need):

[08:18:13][V][dsmr:073]: Footer of telegram found
[08:18:13][V][dsmr:161]: Trying to parse telegram
[08:18:13][E][dsmr:168]: !F9E7
[08:18:13] ^
[08:18:13]Checksum mismatch
[08:18:14][D][uart_debug:109]: <<< 2A 6D 33 29 0D 0A 21 46 39 45 37 0D 0A
[08:18:14][V][dsmr:045]: Header of telegram found
[08:18:14][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[08:18:14][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[08:18:14][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 30 38 31 38 31 36 57 29
[08:18:14][D][uart_debug:109]: <<< C3 61 D6 83 A6 96 B2 E6 16 E6 2E 86 46 56 36 06 9B 82 36 46
[08:18:14][D][uart_debug:109]: <<< 36 96 9B 06 36 82 36 76 36 46 36 0E 36 BA 36 56 36 96 36 56
[08:18:14][D][uart_debug:109]: <<< 36 92 36 16 36 96 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[08:18:14][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[08:18:14][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 34 2E B3 A6 66 65 6D 15 5B
[08:18:14][D][uart_debug:109]: <<< 59 98 58 6A 06 A6 26 E6 86 E6 16 86 06 06 C1 30 30 31 2E 35
[08:18:14][D][uart_debug:109]: <<< 36 66 A5 DB 35 5B D6 0A 31 2D 30 3A 32 2E 38 2E 32 28 30 30
[08:18:14][D][uart_debug:109]: <<< 30 30 30 30 2E 30 30 30 2A 6B 57 68 29 0D 0A 30 2D 30 3A 39
[08:18:14][D][uart_debug:109]: <<< 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31 2E
[08:18:14][D][uart_debug:109]: <<< 37 2E 30 28 30 30 2E 33 33 35 2A 6B 57 29 0D 0A 31 2D 30 3A
[08:18:14][D][uart_debug:109]: <<< 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30 2D
[08:18:14][D][uart_debug:109]: <<< 30 3A 39 36 97 E6 C9 31 28 30 98 30 31 31 29 86 0A 30 2D 30
[08:18:14][D][uart_debug:109]: <<< 3A 39 36 2E 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D 98 67
[08:18:14][D][uart_debug:109]: <<< CE CE 29 76 E6 06 86 26 96 86 82 D6 06 A6 96 B2 E6 76 E6 8A
[08:18:14][D][uart_debug:109]: <<< CA 96 1E 59 96 06 96 26 46 16 06 56 86 26 86 36 95 86 06 06
[08:18:14][D][uart_debug:109]: <<< 82 82 06 06 9B 06 46 06 A6 36 4B 86 16 CA 06 96 26 56 06 96
[08:18:14][D][uart_debug:109]: <<< 92 86 16 26 36 95 86 06 83 06 06 06 67 CB 96 46 76 53 36 4B
[08:18:14][D][uart_debug:109]: <<< D6 0A 31 2D 30 3A 33 32 2E 33 32 9F 30 28 30 30 30 31 30 29
[08:18:14][D][uart_debug:109]: <<< 0D 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31 29
[08:18:14][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D 30
[08:18:14][D][uart_debug:109]: <<< 3A 33 32 2E 37 2E 30 28 32 32 37 2E 35 2A 56 29 0D 0A 31 2D
[08:18:14][D][uart_debug:109]: <<< 30 3A 33 31 2E 37 2E 30 28 30 30 31 2A 41 29 0D 0A 31 2D 30
[08:18:14][D][uart_debug:109]: <<< 3A 32 66 D9 93 19 86 83 82 E6 36 9B 06 A6 B6 BB 4B D6 0A 31
[08:18:14][D][uart_debug:109]: <<< 2D 30 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29
[08:18:14][D][uart_debug:109]: <<< 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30
[08:18:14][D][uart_debug:109]: <<< 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 33 38
[08:18:14][D][uart_debug:109]: <<< 33 35 33 36 33 35 33 35 33 38 33 38 33 30 33 35 33 38 33 38
[08:18:14][D][uart_debug:109]: <<< 33 32 33 98 CA C3 18 6A 16 A6 26 46 E6 26 E6 16 86 26 16 16
[08:18:14][D][uart_debug:109]: <<< 16 06 46 06 C3 16 56 06 76 76 2D 19 19 06 26 76 36 E6 66 76
[08:18:14][V][dsmr:073]: Footer of telegram found
[08:18:14][V][dsmr:161]: Trying to parse telegram
[08:18:14][E][dsmr:168]: !C562
[08:18:14] ^
[08:18:14]Checksum mismatch
[08:18:15][D][uart_debug:109]: <<< 26 A6 D6 9B 96 D6 0A 21 43 35 36 32 0D 0A
[08:18:15][V][dsmr:045]: Header of telegram found
[08:18:15][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[08:18:15][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[08:18:15][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 30 38 31 38 31 37 57 29
[08:18:15][D][uart_debug:109]: <<< 0D 0A 30 CB A6 96 66 73 16 E6 16 86 46 AA 36 06 36 06 6E A2
[08:18:15][D][uart_debug:109]: <<< 36 CA 36 06 36 06 36 76 36 46 36 86 9A 76 9B 56 36 96 36 56
[08:18:15][D][uart_debug:109]: <<< 9A 26 36 16 36 CA 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[08:18:15][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[08:18:15][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 34 2E 37 33 33 2A 6B 57 68
[08:18:15][D][uart_debug:109]: <<< 29 0D 0A 31 2D 30 3A 32 2E 38 2E 31 28 30 30 30 30 30 31 2E
[08:18:15][D][uart_debug:109]: <<< 35 36 33 95 6D 0D A5 0D 0A 31 2D 30 3A 32 2E 38 2E 32 28 30
[08:18:15][D][uart_debug:109]: <<< 30 30 98 30 30 2E 30 30 30 2A 6B 57 68 29 0D 0A 30 96 30 3A
[08:18:15][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[08:18:15][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 32 39 2A 6B 57 29 0D 0A 31 2D 30
[08:18:15][D][uart_debug:109]: <<< 3A 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30
[08:18:15][D][uart_debug:109]: <<< 2D 30 3A 39 36 2E 37 2E 32 31 28 30 30 30 31 66 CA C3 08 6B
[08:18:15][D][uart_debug:109]: <<< 06 A6 96 B2 E6 76 E6 CA 86 06 06 06 06 36 96 D6 0A 31 2D 30
[08:18:15][D][uart_debug:109]: <<< 3A 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37
[08:18:15][D][uart_debug:109]: <<< 2E 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29 28
[08:18:15][D][uart_debug:109]: <<< 30 30 30 30 30 30 33 30 34 30 2A 73 29 28 31 39 30 39 32 35
[08:18:15][D][uart_debug:109]: <<< 30 39 32 38 31 32 53 29 28 30 30 30 30 30 36 39 39 34 37 2A
[08:18:15][D][uart_debug:109]: <<< 73 29 0D 0A 4C 19 A6 36 93 E6 36 26 E6 06 86 06 83 06 16 06
[08:18:15][D][uart_debug:109]: <<< 96 D6 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31
[08:18:15][D][uart_debug:109]: <<< 29 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D
[08:18:15][D][uart_debug:109]: <<< 30 3A 33 32 2E 37 2E 30 28 32 32 37 2E 36 2A 56 29 0D 0A 31
[08:18:15][D][uart_debug:109]: <<< 2D 30 3A 33 31 2E 33 19 42 82 06 16 A6 0A 4B D6 0A 31 2D 30
[08:18:15][D][uart_debug:109]: <<< 3A 32 31 2E 9B 97 30 28 98 30 2E 33 32 30 2A 6B 57 29 0D 0A
[08:18:15][D][uart_debug:109]: <<< 98 2D 30 3A 32 32 2E 37 97 30 28 30 30 2E 30 30 30 2A 6B 57
[08:18:15][D][uart_debug:109]: <<< 29 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A
[08:18:15][D][uart_debug:109]: <<< 30 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 33
[08:18:15][D][uart_debug:109]: <<< 38 33 35 33 36 33 35 99 35 33 38 33 38 33 30 33 35 33 38 33
[08:18:15][D][uart_debug:109]: <<< 38 33 32 33 30 29 0D 0A 30 2D 66 67 33 33 99 73 16 42 26 16
[08:18:15][D][uart_debug:109]: <<< 8B 16 06 46 06 86 16 56 06 76 76 2D 19 19 06 26 76 36 E6 66
[08:18:15][V][dsmr:073]: Footer of telegram found
[08:18:15][V][dsmr:161]: Trying to parse telegram
[08:18:15][E][dsmr:168]: !F66A
[08:18:15] ^
[08:18:15]Checksum mismatch
[08:18:16][D][uart_debug:109]: <<< 76 27 A6 D6 9B 96 D6 0A 21 46 36 36 41 0D 0A
[08:18:16]wifi evt: 7
[08:18:16]wifi evt: 7
[08:18:16]wifi evt: 7
[08:18:16][D][uart_debug:109]: <<< 97 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[08:18:16][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 9D
[08:18:16][D][uart_debug:109]: <<< 31 2E 30 2E 30 94 32 31 31 31 30 34 30 38 31 38 31 38 57 29
[08:18:16][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 2E 31 28 34 35 66 9A 0E 9A 46
[08:18:16][D][uart_debug:109]: <<< 36 96 36 06 CD 30 33 37 33 34 33 38 33 37 33 35 33 39 33 35
[08:18:16][D][uart_debug:109]: <<< 33 32 33 31 33 39 29 0D 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[08:18:16][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[08:18:16][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 34 2E 37 33 33 2A 6B 57 68
[08:18:16][D][uart_debug:109]: <<< 29 0D 0A 31 2D 30 3A 32 2E 38 2E 31 28 30 30 30 06 8B E6 AA
[08:18:16][D][uart_debug:109]: <<< 66 9B A6 B6 BB 43 4B D6 0A 31 2D 30 3A 32 2E 38 2E 32 28 30
[08:18:16][D][uart_debug:109]: <<< 30 30 30 30 30 2E 30 30 30 2A 6B 57 68 29 0D 0A 30 2D 30 3A
[08:18:16][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[08:18:16][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 32 39 2A 6B 57 29 0D 0A 31 2D 98
[08:18:16][D][uart_debug:109]: <<< 3A 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30
[08:18:16][D][uart_debug:109]: <<< 2D 30 3A 39 36 97 72 26 16 86 82 06 06 16 16 96 D6 0A 30 2D
[08:18:16][D][uart_debug:109]: <<< 30 3A 39 36 2E 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D 30
[08:18:16][D][uart_debug:109]: <<< 3A 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37
[08:18:16][D][uart_debug:109]: <<< 2E 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29 28
[08:18:16][D][uart_debug:109]: <<< 30 30 30 30 30 30 33 30 34 30 2A 73 29 28 31 39 30 39 32 35
[08:18:16][D][uart_debug:109]: <<< 30 39 32 38 31 32 53 29 28 30 30 30 30 30 9B 39 39 34 37 95
[08:18:16][D][uart_debug:109]: <<< 6E CA 98 B8 6A 06 D3 36 92 E6 36 26 E6 06 86 83 06 06 16 06
[08:18:16][D][uart_debug:109]: <<< 96 D6 0A 31 CB A6 36 26 E6 9A 66 E6 06 42 06 06 06 06 16 96
[08:18:16][D][uart_debug:109]: <<< D6 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D 30
[08:18:16][D][uart_debug:109]: <<< 3A 33 32 2E 37 2E 30 28 32 32 37 2E CD 65 B5 59 98 58 D6 82
[08:18:16][D][uart_debug:109]: <<< A6 36 16 72 76 E6 06 86 C1 30 32 95 D0 CA 48 58 D6 06 A6 C9
[08:18:16][D][uart_debug:109]: <<< 31 2E 37 2E 30 94 06 E6 36 36 56 A6 B6 BB 4B D6 0A 31 2D 30
[08:18:16][D][uart_debug:109]: <<< 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A
[08:18:16][D][uart_debug:109]: <<< 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30 2D 31
[08:18:16][D][uart_debug:109]: <<< 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 33 38 33 35
[08:18:16][D][uart_debug:109]: <<< 66 36 56 36 56 36 86 36 86 36 83 36 56 36 86 36 86 36 26 36
[08:18:16][D][uart_debug:109]: <<< 06 96 D6 0A 30 2D 31 3A 32 34 2E 32 2E 31 28 32 31 31 31 30
[08:18:16][D][uart_debug:109]: <<< 34 30 38 31 35 30 37 57 29 28 30 30 32 37 33 2E 36 37 32 2A
[08:18:17][D][uart_debug:109]: <<< 6D 33 29 0D 0A 21 43 42 45 34 0D 0A
[08:18:17][V][dsmr:045]: Header of telegram found
[08:18:17][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[08:18:17][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[08:18:17][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 30 38 31 38 31 39 57 29
[08:18:17][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 97 31 28 34 35 33 30 33 30 33
[08:18:17][D][uart_debug:109]: <<< 34 33 39 33 30 33 30 33 37 33 34 33 38 33 37 33 35 33 9C 33
[08:18:17][D][uart_debug:109]: <<< 35 33 32 33 31 33 39 29 0D 0A 31 2D 30 3A 31 2E 38 2E 31 28
[08:18:17][D][uart_debug:109]: <<< 30 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30
[08:18:17][D][uart_debug:109]: <<< 3A 31 2E 38 2E 32 28 30 30 32 30 38 34 2E 37 33 33 2A 6B 57
[08:18:17][D][uart_debug:109]: <<< 68 29 0D 0A 4C CB A6 26 E6 86 E6 16 42 06 06 06 06 82 16 E6
[08:18:17][D][uart_debug:109]: <<< 56 B2 36 A6 B6 BB 43 4B D6 0A 98 CB A6 92 E6 86 E7 92 86 06
[08:18:17][V][dsmr:045]: Header of telegram found
[08:18:17][D][uart_debug:109]: <<< 06 06 06 0E 83 72 06 06 06 52 5A BB 43 4B 6A 0A 30 2F 30 3A
[08:18:17][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[08:18:17][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 32 38 2A 6B 57 29 0D 0A 31 2D 30
[08:18:17][D][uart_debug:109]: <<< 3A 32 2E 37 2E 30 28 30 30 97 30 30 30 2A 6B 57 29 0D 0A 30
[08:18:17][D][uart_debug:109]: <<< 2D 30 3A 39 36 2E 37 2E 32 31 28 30 30 30 31 A6 D6 0A 30 2D
[08:18:17][D][uart_debug:109]: <<< 30 3A 39 36 2E 37 97 39 28 30 30 30 30 33 29 C3 A1 D6 06 A6
[08:18:17][D][uart_debug:109]: <<< CA 96 E6 96 76 E6 06 86 92 96 86 06 D6 82 A6 96 66 72 76 E6
[08:18:17][D][uart_debug:109]: <<< 16 96 96 86 16 CA 06 96 26 46 2E 06 56 86 26 86 36 95 86 06
[08:18:17][V][dsmr:045]: Header of telegram found
[08:18:17][D][uart_debug:109]: <<< 06 06 82 06 06 36 06 46 06 A6 36 2F 19 59 96 06 96 26 AA 06
[08:18:17][D][uart_debug:109]: <<< 96 26 86 16 26 36 2D 19 19 06 06 06 06 66 96 96 A3 76 A6 36
[08:18:17][D][uart_debug:109]: <<< 29 0D 0A CC 4B A6 9B 26 E6 9A 26 E6 06 86 06 83 06 16 06 96
[08:18:17][D][uart_debug:109]: <<< D6 0A 31 2D 30 9D 33 32 2E 33 36 2E 30 28 30 30 30 30 31 29
[08:18:17][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D 30
[08:18:17][D][uart_debug:109]: <<< 3A 33 32 2E 37 2E 30 28 32 32 37 2E 35 2A 56 29 0D 0A 31 2D
[08:18:17][D][uart_debug:109]: <<< 30 3A 33 31 2E 37 2E 30 28 30 30 31 2A 41 29 0D 0A 31 2D 30
[08:18:17][D][uart_debug:109]: <<< 3A 32 31 2E 37 2E 30 AC 98 E6 9B 36 06 A6 5B BB 4B AE 98 58
[08:18:17][D][uart_debug:109]: <<< D6 06 A6 26 26 E6 76 E6 06 86 06 06 E6 06 06 06 A6 B6 BB 4B
[08:18:17][D][uart_debug:109]: <<< D6 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30
[08:18:17][D][uart_debug:109]: <<< 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 99 38
[08:18:17][D][uart_debug:109]: <<< 33 35 33 36 33 35 33 35 33 38 33 38 33 30 33 35 33 38 33 38
[08:18:17][D][uart_debug:109]: <<< 66 66 33 96 6B 0A 30 2D 31 3A 32 34 2E 32 2E 31 28 32 31 31
[08:18:17][D][uart_debug:109]: <<< 31 30 34 30 38 31 35 30 37 57 BC 28 30 30 32 37 33 2E 36 37
[08:18:17][V][dsmr:073]: Footer of telegram found
[08:18:17][V][dsmr:161]: Trying to parse telegram
[08:18:17][E][dsmr:168]: !7F90
[08:18:17] ^
[08:18:17]Checksum mismatch
[08:18:18][D][uart_debug:109]: <<< 32 2A 6D 33 29 0D 0A 21 37 46 39 30 0D 0A
[08:18:18][V][dsmr:045]: Header of telegram found
[08:18:18][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[08:18:18][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[08:18:18][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 30 38 31 38 32 30 57 29
[08:18:18][D][uart_debug:109]: <<< 0D 0A 30 2D 98 3A 67 33 16 E6 16 86 46 AA 36 83 36 06 36 46
[08:18:18][D][uart_debug:109]: <<< 36 CA 36 06 36 06 9A 76 36 A3 36 86 36 76 9A 56 36 96 9B 56

jsuanet commented 2 years ago

@Multipage

logger:
  baud_rate: 460800
  level: VERBOSE

Due to your baud_rate: 460800 setting, both the dsmr and the logger component are using the hardware UART port. This leads to unwanted interference.

Please change this to

logger:
  baud_rate: 0
  level: VERBOSE

I'm pretty sure this will solve your problem.

Multipage commented 2 years ago

@jsuanet

Changed it to 0 and installed that version

Result:

INFO Successfully uploaded program.
INFO Starting log output from /dev/ttyUSB1 with baud rate 115200

[09:31:24][V][dsmr:045]: Header of telegram found
[09:31:24][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[09:31:24][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[09:31:24][D][uart_debug:109]: <<< 31 2E 98 2E 30 28 32 31 31 98 30 34 30 39 66 CC 66 B3 B5 59
[09:31:24][D][uart_debug:109]: <<< 98 18 6A 06 A6 96 66 E6 17 E6 16 86 46 AA 9B 06 9B 06 6E 46
[09:31:24][D][uart_debug:109]: <<< 36 CA 36 06 36 06 36 76 36 A2 36 86 36 76 36 56 36 96 36 5E
[09:31:24][D][uart_debug:109]: <<< B3 26 66 66 CC 66 CE CA C3 61 CC CB D2 16 E6 86 E6 8A 86 83
[09:31:24][D][uart_debug:109]: <<< 06 92 26 46 86 E6 86 76 06 A6 B6 BB A1 4B D6 0A 31 2D 30 3A
[09:31:24][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 35 2E 31 39 31 2A 6B 57 68
[09:31:24][D][uart_debug:109]: <<< 29 0D 0A 31 2D 30 3A 32 2E 38 2E 31 94 30 30 30 30 30 31 2E
[09:31:24][D][uart_debug:109]: <<< 35 36 33 2A 6B 57 68 29 0D 0A 31 2D 30 3A 32 2E 38 2E 99 94
[09:31:24][D][uart_debug:109]: <<< 98 30 30 30 30 98 2E 30 30 30 2A 6B 57 68 29 0D 0A 30 2D 30
[09:31:24][D][uart_debug:109]: <<< 3A 9C 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A
[09:31:24][D][uart_debug:109]: <<< 31 2E 37 2E 30 28 30 30 2E 33 31 37 2A 6B 57 29 0D 0A 31 2D
[09:31:24][D][uart_debug:109]: <<< 30 3A 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A
[09:31:24][D][uart_debug:109]: <<< 98 2D 30 3A 39 36 2E 37 2E 32 31 28 30 30 30 31 31 29 0D 0A
[09:31:24][D][uart_debug:109]: <<< 30 2D 30 3A 39 36 97 99 2C 86 06 06 06 06 36 96 D6 0A 31 2D
[09:31:24][D][uart_debug:109]: <<< 98 3A 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E
[09:31:24][D][uart_debug:109]: <<< 37 2E 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29
[09:31:24][D][uart_debug:109]: <<< 28 30 30 30 30 30 30 33 30 34 30 2A 73 29 28 31 39 30 39 32
[09:31:24][D][uart_debug:109]: <<< 35 30 39 32 38 31 32 53 29 28 30 30 30 30 30 36 39 39 34 37
[09:31:24][D][uart_debug:109]: <<< 2A 73 29 0D 0A 31 2D 98 9D 66 33 69 26 E6 06 43 06 06 06 16
[09:31:24][D][uart_debug:109]: <<< 06 96 D6 0A 31 2D 30 3A 33 32 2E 33 36 97 30 28 30 30 30 30
[09:31:24][D][uart_debug:109]: <<< 31 29 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31
[09:31:24][D][uart_debug:109]: <<< 2D 30 3A 33 32 2E 37 2E 30 28 32 33 30 2E 39 2A 56 29 0D 0A
[09:31:24][D][uart_debug:109]: <<< CC CB A6 36 16 9E D9 33 19 86 06 06 16 A6 16 29 0D 0A 31 2D
[09:31:24][D][uart_debug:109]: <<< 30 9D 32 31 2E 37 AF 30 28 30 98 2E 33 31 36 2A 6B 57 94 0D
[09:31:24][D][uart_debug:109]: <<< 0A 31 2D 30 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B
[09:31:24][D][uart_debug:109]: <<< 57 29 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D
[09:31:24][D][uart_debug:109]: <<< 0A 30 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37
[09:31:24][D][uart_debug:109]: <<< 33 38 33 35 33 36 33 35 33 35 33 38 33 38 33 30 33 35 33 38
[09:31:24][D][uart_debug:109]: <<< 66 36 26 36 06 96 6A 0A 30 2D 31 3A 32 34 2E 99 97 31 28 32
[09:31:24][D][uart_debug:109]: <<< 31 31 31 30 34 30 39 33 30 30 33 57 29 28 30 30 32 37 33 2F
[09:31:24][V][dsmr:045]: Header of telegram found
[09:31:24][V][dsmr:073]: Footer of telegram found
[09:31:24][V][dsmr:161]: Trying to parse telegram
[09:31:24][E][dsmr:168]: !254E
[09:31:24] ^
[09:31:24]Checksum mismatch
[09:31:24][D][uart_debug:109]: <<< 36 37 32 2A 6D 33 29 0D 0A 21 32 35 34 45 0D 0A
[09:31:25][V][dsmr:045]: Header of telegram found
[09:31:25][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[09:31:25][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[09:31:25][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 30 39 33 31 32 37 57 29
[09:31:25][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 98 97 31 28 9A 35 33 30 33 30 33
[09:31:25][D][uart_debug:109]: <<< 34 66 CE 66 36 82 36 76 36 46 36 1E 36 BA 36 56 9B 96 9A 56
[09:31:25][D][uart_debug:109]: <<< 36 26 36 16 36 96 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[09:31:25][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[09:31:25][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 35 2E 31 39 31 2A 6B 57 68
[09:31:25][D][uart_debug:109]: <<< 29 0D 0A 31 2D 30 3A 32 2E 38 2E 31 28 30 30 30 30 30 31 2E
[09:31:25][D][uart_debug:109]: <<< 35 36 33 2A 6B 1A 5B 59 98 58 D6 06 A6 26 E6 86 E6 26 86 06
[09:31:25][D][uart_debug:109]: <<< 06 06 06 82 06 E6 06 06 06 A6 B6 BB 43 4B D6 0A 30 2D 30 3A
[09:31:25][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[09:31:25][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 32 32 2A 6B 57 29 0D 0A 31 2D 30
[09:31:25][D][uart_debug:109]: <<< 3A 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30
[09:31:25][D][uart_debug:109]: <<< 2D 30 3A 39 36 2E 99 99 8A 43 06 06 06 16 8B 96 6A 0A 30 2D
[09:31:25][D][uart_debug:109]: <<< 30 3A 39 36 97 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D 30
[09:31:25][D][uart_debug:109]: <<< 3A 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37
[09:31:25][D][uart_debug:109]: <<< 2E 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29 28
[09:31:25][D][uart_debug:109]: <<< 30 30 30 30 30 98 33 30 34 30 2A 73 29 28 31 39 30 39 32 35
[09:31:25][D][uart_debug:109]: <<< 30 39 32 38 31 32 53 29 28 30 30 30 30 30 36 39 39 34 33 65
[09:31:25][D][uart_debug:109]: <<< 6E CA C3 28 6A 06 A6 36 26 E6 36 26 E6 06 86 06 06 06 16 06
[09:31:25][D][uart_debug:109]: <<< 96 6A 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31
[09:31:25][D][uart_debug:109]: <<< 29 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D
[09:31:25][D][uart_debug:109]: <<< 30 3A 33 32 2E 37 2E 30 28 32 33 30 2E CE 65 B5 59 98 28 6A
[09:31:25][D][uart_debug:109]: <<< 06 A6 36 8A E6 76 E6 06 86 06 06 16 52 0B 4B D6 0A 31 2D 30
[09:31:25][D][uart_debug:109]: <<< 9D 32 31 2E 33 06 86 82 06 CE D9 26 36 A6 B6 BB 4B D6 0A 31
[09:31:25][D][uart_debug:109]: <<< 2D 30 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29
[09:31:25][D][uart_debug:109]: <<< 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30
[09:31:25][D][uart_debug:109]: <<< 2D 31 3A 39 36 2E 31 2E 30 94 34 37 33 30 33 30 33 37 33 38
[09:31:25][D][uart_debug:109]: <<< 33 35 33 36 33 35 33 35 33 38 33 9C 66 9B 56 36 86 36 86 36
[09:31:25][D][uart_debug:109]: <<< 26 36 06 96 D6 0A 30 CB CC 67 66 33 99 E6 16 43 26 16 16 8A
[09:31:25][D][uart_debug:109]: <<< 06 46 06 96 36 06 06 36 76 2D 19 19 06 26 76 36 E6 66 76 26
[09:31:25][V][dsmr:073]: Footer of telegram found
[09:31:25][V][dsmr:161]: Trying to parse telegram
[09:31:25][E][dsmr:168]: !DD90
[09:31:25] ^
[09:31:25]Checksum mismatch
[09:31:25][D][uart_debug:109]: <<< A6 D6 9B 96 D6 0A 21 44 44 39 30 0D 0A
[09:31:26][V][dsmr:045]: Header of telegram found
[09:31:26][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[09:31:26][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[09:31:26][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 30 39 33 31 32 38 57 29
[09:31:26][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E CC 59 86 A3 AA 36 06 36 06 36 8E
[09:31:26][D][uart_debug:109]: <<< 36 CA 36 06 36 82 9A 76 36 46 36 86 36 76 36 56 36 CA 36 56
[09:31:26][D][uart_debug:109]: <<< 36 26 36 16 36 CA 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[09:31:26][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[09:31:26][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 35 2E 31 39 31 2A 6B 57 68
[09:31:26][D][uart_debug:109]: <<< 29 0D 85 CC CB A6 26 E6 86 E6 16 86 06 06 06 82 06 16 E6 AA
[09:31:26][D][uart_debug:109]: <<< 66 36 A6 B6 BB 43 A5 43 61 CC CB D2 26 E6 86 E6 26 86 06 06
[09:31:26][D][uart_debug:109]: <<< 06 06 06 06 9E 19 82 06 A6 B6 BB 43 4B D6 0A 30 2D 30 3A 39
[09:31:26][D][uart_debug:109]: <<< 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31 2E
[09:31:26][D][uart_debug:109]: <<< 37 2E 30 28 30 30 2E 33 32 35 2A 6B 57 29 0D 0A CC CB A6 26
[09:31:26][D][uart_debug:109]: <<< E6 76 E6 06 86 06 06 E6 06 06 06 A6 B6 BB 4B D6 85 30 2D 98
[09:31:26][D][uart_debug:109]: <<< 3A 39 9B D9 E6 26 16 86 06 06 06 16 16 96 D6 0A 30 2D 30 3A
[09:31:26][D][uart_debug:109]: <<< 39 36 2E 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D 30 3A 39
[09:31:26][D][uart_debug:109]: <<< 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37 2E 31
[09:31:26][D][uart_debug:109]: <<< 39 29 28 31 39 30 39 99 34 31 30 35 38 32 38 53 29 28 30 30
[09:31:26][D][uart_debug:109]: <<< 30 30 30 30 33 30 34 30 2A AE 0C 16 96 06 96 26 56 06 96 26
[09:31:26][D][uart_debug:109]: <<< 86 16 26 36 2D 19 19 06 06 06 06 66 96 96 46 76 A6 36 4B D6
[09:31:26][D][uart_debug:109]: <<< 0A 31 2D 30 3A 33 32 2E 33 32 2E 30 28 30 30 30 31 30 29 0D
[09:31:26][D][uart_debug:109]: <<< 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31 29 0D
[09:31:26][D][uart_debug:109]: <<< 0A 30 2D 30 3A CE 33 59 36 E6 06 86 96 6A 0A 31 2D 30 3A 33
[09:31:26][D][uart_debug:109]: <<< 99 2E 37 2E 30 28 32 99 30 2E 38 2A 57 29 0D 0A 98 2D 30 3A
[09:31:26][D][uart_debug:109]: <<< 33 31 2E 37 97 30 28 30 30 31 2A 41 29 0D 0A 31 2D 30 3A 32
[09:31:26][D][uart_debug:109]: <<< 31 2E 37 2E 30 28 30 30 2E 33 31 38 2A 6B 57 29 0D 0A 31 2D
[09:31:26][D][uart_debug:109]: <<< 30 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D
[09:31:26][D][uart_debug:109]: <<< 0A 30 2D CC 67 66 33 59 E6 06 86 06 06 36 96 D6 0A 30 2D 31
[09:31:26][D][uart_debug:109]: <<< 3A 39 36 97 31 2E 30 28 34 37 33 30 33 30 33 37 33 38 33 35
[09:31:26][D][uart_debug:109]: <<< 33 36 33 35 33 35 33 9C 33 9C 33 30 33 35 33 38 33 38 33 32
[09:31:26][D][uart_debug:109]: <<< 33 30 CA C3 61 D6 8B A6 26 46 E6 26 E6 16 86 26 16 16 16 06
[09:31:26][D][uart_debug:109]: <<< 46 06 96 36 06 06 36 76 2D 19 19 06 26 76 36 E6 66 76 26 A6
[09:31:26][V][dsmr:073]: Footer of telegram found
[09:31:26][V][dsmr:161]: Trying to parse telegram
[09:31:26][E][dsmr:168]: !07F5
[09:31:26] ^
[09:31:26]Checksum mismatch

I'm sorry. It isn't fixed.

mmakaay commented 2 years ago

To be sure, can you check the config dump when you start the logger, to see if the uart section now reports the use of hardware UART and not software UART?

And can you set the log level to VERY_VERBOSE? IIRC, that will log the decrypted telegram message. Curious to see what's in there, because it does seem to contain a recognizable checksum.

Multipage commented 2 years ago

@mmakaay

Getting only this info. Hope you'll find the info there

INFO Starting log output from /dev/ttyUSB1 with baud rate 115200
[10:40:33]rll\x90r$\xe2n\xc4l\xc7b|\x8e\x90\x92rb\xc4b\xf2nnlnn2bbp\x8c$blrlp\xf2n0\x90l\x8cbn\xe2n\xc7lb\xc4\xf2nn'l\x8cl`\x90nnl`nr\x8e\x92\x92nbl`r\x92\x92nbl`\x9cb\x8el\x92lr\x92`\xfc\x82nb\xe0
[10:40:33]SDK:2.2.2-dev(38a443e)/Core:2.7.3-3-g2843a5ac=20703003/lwIP:STABLE-2_1_2_RELEASE/glue:1.2-30-g92add50/BearSSL:5c771be
[10:40:33][I][logger:214]: Log initialized
[10:40:33][C][ota:452]: There have been 9 suspected unsuccessful boot attempts.
[10:40:33][I][app:029]: Running through setup()...
[10:40:33][V][app:030]: Sorting components by setup priority...
[10:40:33][VV][scheduler:057]: set_interval(name='', interval=60000, offset=27238)
[10:40:33][C][uart.arduino_esp8266:052]: Setting up UART bus...
[10:40:33][VV][scheduler:057]: set_interval(name='update', interval=60000, offset=15262)
[10:40:33][I][main:042]: Not using decryption key. If you need to set a key use Home Assistant service 'ESPHome:  slimmelezer_set_dsmr_key'
[10:40:33][V][text_sensor:016]: 'ESPHome Version': Received new state 2021.10.3
[10:40:33][D][text_sensor:067]: 'ESPHome Version': Sending state '2021.10.3'
[10:40:33][C][wifi:037]: Setting up WiFi...
[10:40:33][V][wifi_esp8266:068]: Disabling AP.
[10:40:33]bcn 0
[10:40:33]del if1
[10:40:33]usl
[10:40:33]mode : null
[10:40:33][V][wifi_esp8266:058]: Enabling STA.
[10:40:33]mode[V][wifi_esp8266:535]: Event: Changed Mode old=AP new=OFF
[10:40:33]wifi evt: 8
[10:40:33] : sta(ac:0b:fb:cf:d9:ab)
[10:40:33]add if0
[10:40:33][V][wifi_esp8266:535]: Event: Changed Mode old=OFF new=STA
[10:40:33]wifi evt: 8
[10:40:33]sleep disable
[10:40:33][D][wifi:372]: Starting scan...
[10:40:33][VV][scheduler:152]: Running interval '' with interval=60000 last_execution=4294880153 (now=180)
[10:40:33][VV][scheduler:152]: Running interval 'update' with interval=60000 last_execution=4294892141 (now=180)
[10:40:33][V][sensor:062]: 'SlimmeLezer Uptime': Received new state 0.198000
[10:40:33][D][sensor:113]: 'SlimmeLezer Uptime': Sending state 0.19800 s with 0 decimals of accuracy
[10:40:33][V][dsmr:045]: Header of telegram found
[10:40:33][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 CB CC 16 26 D6 0A 0D 0A
[10:40:33][D][uart_debug:109]: <<< 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A 31
[10:40:33][D][uart_debug:109]: <<< 2E 30 2E 30 28 32 31 31 31 30 34 31 30 34 30 33 35 57 29 0D
[10:40:33][D][uart_debug:109]: <<< 0A 98 CB A6 96 66 E6 16 E6 16 86 46 56 36 06 36 06 36 A3 36
[10:40:33][D][uart_debug:109]: <<< 96 9B 06 CD 30 66 B3 66 B3 66 9B 76 36 56 36 96 36 56 36 26
[10:40:33][D][uart_debug:109]: <<< 36 16 36 96 96 D6 0A 31 2D 98 3A 31 2E 38 2E 31 28 30 30 32
[10:40:33][D][uart_debug:109]: <<< 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A 31 2E
[10:40:33][D][uart_debug:109]: <<< 38 2E 32 28 30 30 32 30 38 35 2E 35 38 37 2A 6B 57 68 29 0D
[10:40:33][D][uart_debug:109]: <<< 0A 31 2D 30 3A 32 2E 38 2E 31 28 98 30 30 30 30 31 2E 35 36
[10:40:33][D][uart_debug:109]: <<< 33 2A 6B 57 68 29 0D 0A 31 2D 30 3A 32 2E 38 97 32 28 30 30
[10:40:33][D][uart_debug:109]: <<< 30 98 30 30 2E 30 30 30 2A 6B 57 68 29 0D 0A 30 2D 30 3A 39
[10:40:33][D][uart_debug:109]: <<< 36 BF 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31 2E
[10:40:33][D][uart_debug:109]: <<< 37 2E 30 28 30 30 2E 33 36 35 2A 6B 57 29 0D 0A 31 2D 30 3A
[10:40:33][D][uart_debug:109]: <<< 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30 2D
[10:40:33][D][uart_debug:109]: <<< 30 9D 39 36 2E 37 2E 32 31 28 30 30 30 31 31 29 0D 0A 30 2D
[10:40:33][D][uart_debug:109]: <<< 30 3A 39 36 2E 37 97 CE 19 06 83 06 36 96 D6 0A 31 2D 30 3A
[10:40:33][D][uart_debug:109]: <<< 39 39 2E CE 33 19 86 26 96 86 06 D6 83 A6 96 66 E6 76 E6 16
[10:40:33][D][uart_debug:109]: <<< CA 96 86 16 96 06 96 26 46 16 06 56 86 26 86 36 2D 19 19 06
[10:40:33][D][uart_debug:109]: <<< 06 06 06 06 36 06 46 06 A6 36 4B 86 16 96 06 96 26 56 06 96
[10:40:34][D][uart_debug:109]: <<< 26 C2 16 26 36 2D 19 19 06 06 06 06 66 96 96 46 76 A6 36 4B
[10:40:34][D][uart_debug:109]: <<< D6 0A 31 2D 30 3A 99 32 2E 33 32 2E 30 28 30 30 30 31 30 29
[10:40:34][D][uart_debug:109]: <<< 0D 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31 29
[10:40:34][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D 30
[10:40:34][D][uart_debug:109]: <<< 3A 33 32 2E 37 2E 30 28 32 32 39 2E 36 2A 56 29 0D 0A 31 2D
[10:40:34][D][uart_debug:109]: <<< 30 3A 33 31 2E 37 2E 30 28 30 30 31 2A 41 4A C3 61 CC CB A6
[10:40:34][D][uart_debug:109]: <<< 26 16 E6 76 E6 06 86 06 06 E6 36 66 56 A6 B6 BB CA C3 61 CC
[10:40:34][D][uart_debug:109]: <<< 19 A6 26 26 73 76 E6 06 0E 19 06 E6 83 06 06 A6 5A BB 4B D6
[10:40:34][D][uart_debug:109]: <<< 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30 2D
[10:40:34][D][uart_debug:109]: <<< 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 33 38 33
[10:40:34][D][uart_debug:109]: <<< 35 33 36 33 35 33 35 33 38 33 9C 33 30 33 35 33 38 33 38 33
[10:40:34][D][uart_debug:109]: <<< 32 33 30 29 0D 0A 30 2D 31 3A 32 34 2E 32 97 CC 99 16 8B 16
[10:40:34][D][uart_debug:109]: <<< 06 46 16 06 46 06 06 96 76 2D 19 19 06 26 BB 36 E6 66 76 26
[10:40:34][V][dsmr:073]: Footer of telegram found
[10:40:34][V][dsmr:161]: Trying to parse telegram
[10:40:34][E][dsmr:168]: !D767
[10:40:34] ^
[10:40:34]Checksum mismatch
[10:40:34][D][uart_debug:109]: <<< A6 D6 9B 96 D6 0A 21 44 37 36 37 0D 0A
[10:40:34][V][dsmr:045]: Header of telegram found
[10:40:34][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[10:40:34][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[10:40:34][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 31 30 34 30 33 36 57 29
[10:40:34][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 97 31 28 34 35 66 36 06 36 46
[10:40:34][D][uart_debug:109]: <<< 36 CB 36 06 36 06 36 76 36 46 9A 86 9A 76 36 56 36 96 36 56
[10:40:34][D][uart_debug:109]: <<< 36 26 36 8B 36 96 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[10:40:34][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[10:40:34][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 35 2E 35 38 37 2A 6B 57 68
[10:40:34][D][uart_debug:109]: <<< 29 0D 85 CC CB A6 26 E6 86 E6 16 86 1E 06 82 06 06 16 E6 56
[10:40:34][D][uart_debug:109]: <<< 66 9B 53 B6 BB 68 CA 98 28 D6 06 A6 27 E6 86 E6 26 42 06 06
[10:40:34][D][uart_debug:109]: <<< 06 06 06 06 E6 06 06 06 A6 B6 BB 43 4B 6B 0A 30 2D 30 3A 39
[10:40:34][D][uart_debug:109]: <<< 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31 2E
[10:40:34][D][uart_debug:109]: <<< 37 2E 30 28 30 30 2E 33 37 34 2A 6B 57 29 0D 0A 31 2D 98 3A
[10:40:34][D][uart_debug:109]: <<< 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30 2D
[10:40:34][D][uart_debug:109]: <<< 30 3A 39 37 97 72 26 16 86 06 06 06 16 C5 29 0D 0A 30 2D 30
[10:40:34][D][uart_debug:109]: <<< 3A 39 36 2E 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D 30 3A
[10:40:34][D][uart_debug:109]: <<< 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37 2E
[10:40:34][D][uart_debug:109]: <<< 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29 28 30
[10:40:34][D][uart_debug:109]: <<< 30 30 30 30 30 33 30 34 30 95 73 29 28 31 39 30 39 32 35 30
[10:40:35][D][uart_debug:109]: <<< 39 32 38 31 32 53 29 28 30 30 30 30 30 36 39 39 34 99 D9 4B
[10:40:35][D][uart_debug:109]: <<< D6 0A 31 2D 30 3A 33 32 2E 33 32 2E 30 28 30 30 30 31 30 29
[10:40:35][D][uart_debug:109]: <<< 0D 0A 31 2D 30 3A 3B 32 2E 33 36 2E 30 28 30 30 30 30 31 29
[10:40:35][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D 30
[10:40:35][D][uart_debug:109]: <<< 3A 33 32 2E 37 2E 30 28 32 32 39 2E 35 2A 56 29 0D 0A B8 2D
[10:40:35][D][uart_debug:109]: <<< 30 3A 33 31 2E 37 2E 30 28 30 30 32 2A 41 29 0D 0A 31 2D 30
[10:40:35][D][uart_debug:109]: <<< 3A 32 31 2E 37 2E 98 19 06 E6 36 76 16 A6 5B BB 4B D6 0A 31
[10:40:35][D][uart_debug:109]: <<< 2D 30 3A 99 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29
[10:40:35][D][uart_debug:109]: <<< 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30
[10:40:35][D][uart_debug:109]: <<< 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 33 38
[10:40:35][D][uart_debug:109]: <<< 33 35 33 36 66 B3 66 CD 66 36 86 36 06 36 56 36 86 36 86 36
[10:40:35][D][uart_debug:109]: <<< 26 36 06 96 D6 0A 30 96 31 9D 32 34 2E 32 2E 31 28 32 31 31
[10:40:35][D][uart_debug:109]: <<< 31 30 34 31 30 34 30 30 39 57 29 28 30 30 32 37 33 2E 36 37
[10:40:35][V][dsmr:073]: Footer of telegram found
[10:40:35][V][dsmr:161]: Trying to parse telegram
[10:40:35][E][dsmr:168]: !EF1E
[10:40:35] ^
[10:40:35]Checksum mismatch
[10:40:35][D][uart_debug:109]: <<< 32 2A 6D 33 29 0D 0A 21 45 46 31 45 0D 0A
[10:40:35][V][dsmr:045]: Header of telegram found
[10:40:35][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[10:40:35][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[10:40:35][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 31 30 34 30 33 37 57 29
[10:40:35][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 9B 97 31 97 31 28 34 35 33 30 33 30 33
[10:40:35][D][uart_debug:109]: <<< 34 33 39 33 30 33 30 33 37 33 34 33 38 33 37 33 35 33 39 33
[10:40:35][D][uart_debug:109]: <<< 35 33 32 33 31 33 39 29 0D 0A 31 2D 98 3A 31 2E 38 2E 31 28
[10:40:35][D][uart_debug:109]: <<< 30 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30
[10:40:35][D][uart_debug:109]: <<< 3A 31 2E 38 2E 32 28 30 30 32 30 38 35 2E 35 38 37 2A 6B 57
[10:40:35][D][uart_debug:109]: <<< 68 29 0D 0A 31 2D 30 3A 32 2E 38 2E 31 28 30 30 30 30 30 31
[10:40:35][D][uart_debug:109]: <<< 2E 35 36 33 2A 6D 0D 4B D6 0A 31 CB A6 26 E6 86 E6 26 86 06
[10:40:35][D][uart_debug:109]: <<< 06 06 06 06 06 E6 06 06 06 A6 5A 57 68 29 0D 0A 30 2D 30 3A
[10:40:35][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[10:40:35][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 37 32 2A 6B 57 29 0D 0A 31 2D 30
[10:40:35][D][uart_debug:109]: <<< 3A 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30
[10:40:35][D][uart_debug:109]: <<< 2D 98 9D 39 36 2E 37 97 32 31 28 30 30 98 31 31 29 0D 0A 30
[10:40:35][D][uart_debug:109]: <<< 2D 30 3A 39 36 2E 33 59 86 06 06 06 06 36 4B D6 0A 31 2D 30
[10:40:35][D][uart_debug:109]: <<< 3A 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37
[10:40:35][D][uart_debug:109]: <<< 2E 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29 28
[10:40:35][D][uart_debug:109]: <<< 98 30 30 30 30 30 33 30 34 30 2A 73 29 28 31 39 30 39 32 35
[10:40:36][D][uart_debug:109]: <<< 30 39 32 38 31 32 53 29 94 98 30 30 30 30 9B 39 39 36 37 2A
[10:40:36][D][uart_debug:109]: <<< 73 29 0D 0A 31 2D 30 3A 33 32 2E 33 32 2E 30 28 30 30 30 31
[10:40:36][D][uart_debug:109]: <<< 30 29 0D 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30
[10:40:36][D][uart_debug:109]: <<< 31 29 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31
[10:40:36][D][uart_debug:109]: <<< 2D 30 3A 33 32 2E 37 2E 30 28 32 32 39 2E 37 2A 56 29 0D 0A
[10:40:36][D][uart_debug:109]: <<< 31 2D 30 3A 33 31 97 33 19 86 06 1E 26 A6 16 4B D6 0A 31 2D
[10:40:36][D][uart_debug:109]: <<< 98 3A 32 31 2E 37 97 30 28 30 30 2E 33 37 36 2A 6B 57 29 0D
[10:40:36][D][uart_debug:109]: <<< 0A 31 2D 30 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B
[10:40:36][D][uart_debug:109]: <<< 57 29 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D
[10:40:36][D][uart_debug:109]: <<< 0A 30 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37
[10:40:36][D][uart_debug:109]: <<< 33 38 33 35 33 36 33 35 33 35 33 38 33 38 33 30 33 35 33 38
[10:40:36][D][uart_debug:109]: <<< 33 38 33 32 33 30 29 0D 0A 30 2D 31 9D 99 34 2E B9 2E 31 28
[10:40:36][D][uart_debug:109]: <<< 32 31 98 31 30 34 B8 30 34 30 30 39 57 29 94 30 30 32 37 33
[10:40:36][V][dsmr:073]: Footer of telegram found
[10:40:36][V][dsmr:161]: Trying to parse telegram
[10:40:36][E][dsmr:168]: !17E8
[10:40:36] ^
[10:40:36]Checksum mismatch
[10:40:36][D][uart_debug:109]: <<< 2E 36 37 32 2A 6D 33 29 0D 0A 21 31 37 45 38 0D 0A
[10:40:36][V][dsmr:045]: Header of telegram found
[10:40:36][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[10:40:36][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[10:40:36][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 31 30 34 30 33 38 57 29
[10:40:36][D][uart_debug:109]: <<< 0D 0A 98 83 A6 CB 66 E6 16 9E 59 86 46 D5 33 30 33 30 33 34
[10:40:36][D][uart_debug:109]: <<< 33 39 33 30 33 30 33 37 33 34 33 38 33 37 33 35 33 39 33 35
[10:40:36][D][uart_debug:109]: <<< 33 32 33 98 33 39 29 0D 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[10:40:36][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[10:40:36][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 CD B9 33 BA A6 B6 BB 43 4B
[10:40:36][D][uart_debug:109]: <<< D6 0A 31 2D 30 3A 32 2E 38 2E 31 28 98 30 30 30 30 31 2E 35
[10:40:36][D][uart_debug:109]: <<< 36 66 65 6D 35 5B 59 98 28 D6 06 A6 26 CE 19 E6 92 86 06 06
[10:40:36][D][uart_debug:109]: <<< 06 06 06 06 E6 06 06 06 A6 B6 BB 43 4B D6 0A 30 2D 30 3A 39
[10:40:36][D][uart_debug:109]: <<< 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31 2E
[10:40:36][D][uart_debug:109]: <<< 37 2E 30 28 30 98 2E 33 36 35 2A 6B 57 29 0D 0A 31 2D 30 3A
[10:40:36][D][uart_debug:109]: <<< 32 2E 37 2E 30 28 30 30 2E 30 30 30 95 6D B5 59 A4 30 2D 30
[10:40:36][D][uart_debug:109]: <<< 3A 39 36 2E 37 2E 32 31 28 30 30 30 31 31 29 0D 0A 30 2D 30
[10:40:36][D][uart_debug:109]: <<< 3A 39 36 2E 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D 30 3A
[10:40:36][D][uart_debug:109]: <<< 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E 37 2E
[10:40:36][D][uart_debug:109]: <<< 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 6A CA 19 82
[10:40:36][D][uart_debug:109]: <<< 0E 06 06 82 36 06 46 06 A6 36 4B 86 16 96 06 E5 32 35 30 3D
[10:40:37][D][uart_debug:109]: <<< E6 38 31 32 53 29 28 30 30 30 30 30 36 39 39 34 37 2A 73 29
[10:40:37][D][uart_debug:109]: <<< 0D 0A 31 2D 30 3A 33 32 2E 33 32 2E 30 28 30 30 30 31 30 29
[10:40:37][D][uart_debug:109]: <<< 0D 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31 29
[10:40:37][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D 30
[10:40:37][D][uart_debug:109]: <<< 3A 33 32 2E 37 97 86 26 26 CA E6 56 A6 66 2D 59 98 28 D6 06
[10:40:37][D][uart_debug:109]: <<< A6 9A 16 E6 76 E6 82 86 06 06 8A A6 16 4B 6B 0A 31 4B A6 26
[10:40:37][D][uart_debug:109]: <<< 16 E6 76 E6 82 86 83 06 E6 36 56 76 A6 B6 BB 4B D6 0A 31 2D
[10:40:37][D][uart_debug:109]: <<< 30 3A 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D
[10:40:37][D][uart_debug:109]: <<< 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D 0A 30 2D
[10:40:37][D][uart_debug:109]: <<< 31 9D 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37 33 38 33
[10:40:37][D][uart_debug:109]: <<< 35 33 36 33 35 99 CD 66 36 86 36 06 9B 56 36 86 9B 86 36 26
[10:40:37][D][uart_debug:109]: <<< 36 06 96 D6 0A 30 2D 31 3A 32 34 2E 32 2E CC 99 16 16 16 06
[10:40:37][D][uart_debug:109]: <<< 46 16 06 46 06 06 96 76 2D 19 19 06 26 76 36 E6 66 76 26 A6
[10:40:37][V][dsmr:073]: Footer of telegram found
[10:40:37][V][dsmr:161]: Trying to parse telegram
[10:40:37][E][dsmr:168]: !6329
[10:40:37] ^
[10:40:37]Checksum mismatch
[10:40:37][D][uart_debug:109]: <<< D6 9B 96 D6 0A 21 36 33 32 39 0D 0A
[10:40:37][V][dsmr:045]: Header of telegram found
[10:40:37][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[10:40:37][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[10:40:37][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 31 30 34 30 33 39 57 29
[10:40:37][D][uart_debug:109]: <<< 0D 0A 30 2D 30 3A 39 36 2E 31 97 31 28 34 35 33 98 33 30 33
[10:40:37][D][uart_debug:109]: <<< 34 33 39 33 30 99 30 33 37 33 34 33 38 33 37 33 4D A6 36 56
[10:40:37][D][uart_debug:109]: <<< 36 26 36 16 36 96 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30
[10:40:37][D][uart_debug:109]: <<< 30 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A
[10:40:37][D][uart_debug:109]: <<< 31 2E 38 2E 32 28 30 30 32 30 38 35 2E 35 38 37 2A 6B AB 68
[10:40:37][D][uart_debug:109]: <<< 29 0D 0A 31 2D 30 3A 32 2E 38 2E 31 28 30 30 30 30 30 31 2E
[10:40:37][D][uart_debug:109]: <<< 35 36 33 2A 6B 57 68 29 0D 0A B8 2D 30 3A 32 2E 9C 99 86 06
[10:40:37][D][uart_debug:109]: <<< 06 06 06 06 06 E6 06 06 06 A6 B6 5D 43 4B D6 0A 30 2D 30 3A
[10:40:37][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[10:40:37][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 36 39 2A 6B 57 29 0D 0A 31 2D 30
[10:40:37][D][uart_debug:109]: <<< 3A 99 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30
[10:40:37][D][uart_debug:109]: <<< 2D 30 3A 39 36 2E 37 2E 32 31 28 30 30 30 31 31 29 0D 0A 30
[10:40:37][D][uart_debug:109]: <<< 2D 30 3A 39 36 2E 37 2E 39 28 30 30 30 30 33 29 0D 0A 31 2D
[10:40:37][D][uart_debug:109]: <<< 30 3A 39 39 2E 39 37 2E 30 28 32 29 28 30 2D 30 3A 39 36 2E
[10:40:37][D][uart_debug:109]: <<< 37 2E 31 39 29 28 31 39 30 39 32 34 31 30 35 38 32 38 53 29
[10:40:37][D][uart_debug:109]: <<< 28 30 30 30 30 30 30 33 30 34 30 2A 73 29 28 31 39 30 39 32
[10:40:38][D][uart_debug:109]: <<< 35 30 39 32 38 31 32 53 29 28 30 30 30 30 30 36 39 39 34 37
[10:40:38][D][uart_debug:109]: <<< 2A 73 29 0D 0A 31 2D 30 3A 33 32 97 33 99 2E 30 28 30 30 30
[10:40:38][D][uart_debug:109]: <<< 31 30 29 0D 0A 31 2D 98 3A 33 32 2E 33 36 2E 30 28 30 30 30
[10:40:38][D][uart_debug:109]: <<< 30 98 29 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A
[10:40:38][D][uart_debug:109]: <<< 31 2D 30 3A 33 32 2E 37 2E 30 28 32 32 39 2E 34 2A 56 29 0D
[10:40:38][D][uart_debug:109]: <<< 0A 31 2D 30 3A 33 31 2E 37 2E 30 28 30 30 32 95 D0 CA C3 61
[10:40:38][D][uart_debug:109]: <<< 4C CB A6 26 16 E6 76 72 06 86 06 06 72 36 66 BB 53 5B BB 2F
[10:40:38][V][dsmr:045]: Header of telegram found
[10:40:38][D][uart_debug:109]: <<< 59 98 2C FF 06 A6 26 26 E6 76 E6 06 86 06 06 E6 06 06 06 A6
[10:40:38][D][uart_debug:109]: <<< B6 BB 4B D6 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29
[10:40:38][D][uart_debug:109]: <<< 0D 0A 30 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33
[10:40:38][D][uart_debug:109]: <<< 37 33 38 33 35 33 36 33 35 33 35 33 38 33 38 99 30 33 35 33
[10:40:38][D][uart_debug:109]: <<< 38 33 38 33 32 33 30 29 0D 0A 30 2D 31 3A 32 34 2E 32 97 31
[10:40:38][D][uart_debug:109]: <<< 28 32 31 31 31 30 34 31 30 34 30 30 39 57 29 28 30 30 32 37
[10:40:38][V][dsmr:073]: Footer of telegram found
[10:40:38][V][dsmr:161]: Trying to parse telegram
[10:40:38][E][dsmr:168]: !A40A
[10:40:38] ^
[10:40:38]Checksum mismatch
[10:40:38][D][uart_debug:109]: <<< 33 2E 36 37 32 2A 6D 33 29 0D 0A 21 41 34 30 41 0D 0A
[10:40:38][V][dsmr:045]: Header of telegram found
[10:40:38][D][uart_debug:109]: <<< 2F 49 53 4B 35 5C 32 4D 35 35 30 45 2D 31 30 31 32 0D 0A 0D
[10:40:38][D][uart_debug:109]: <<< 0A 31 2D 33 3A 30 2E 32 2E 38 28 35 30 29 0D 0A 30 2D 30 3A
[10:40:38][D][uart_debug:109]: <<< 31 2E 30 2E 30 28 32 31 31 31 30 34 31 30 34 30 34 30 57 29
[10:40:38][D][uart_debug:109]: <<< C3 61 D6 06 A6 96 B2 72 16 E6 16 86 46 AB 36 06 36 82 CD 34
[10:40:38][D][uart_debug:109]: <<< 33 39 33 30 33 30 33 37 33 34 66 36 76 36 56 36 CA 36 56 36
[10:40:38][D][uart_debug:109]: <<< 26 36 16 36 96 96 D6 0A 31 2D 30 3A 31 2E 38 2E 31 28 30 30
[10:40:38][D][uart_debug:109]: <<< 32 32 34 38 2E 38 37 30 2A 6B 57 68 29 0D 0A 31 2D 30 3A 31
[10:40:38][D][uart_debug:109]: <<< 2E 38 2E 32 28 30 30 32 30 38 35 2E 35 38 38 2A 6B 57 68 29
[10:40:38][D][uart_debug:109]: <<< 0D 0A 31 2D 30 3A 32 2E 38 2E 31 94 98 98 30 30 98 31 2E 35
[10:40:38][D][uart_debug:109]: <<< 36 33 2A 6B 57 68 29 0D 0A 31 2D 30 3A 32 2E 9C 2E 32 28 30
[10:40:38][D][uart_debug:109]: <<< 30 30 30 30 30 2E 30 30 30 2A 6B 57 68 29 0D 0A 30 2D 30 3A
[10:40:38][D][uart_debug:109]: <<< 39 36 2E 31 34 2E 30 28 30 30 30 32 29 0D 0A 31 2D 30 3A 31
[10:40:38][D][uart_debug:109]: <<< 2E 37 2E 30 28 30 30 2E 33 36 35 2A 6B 57 29 0D 0A 31 2D 30
[10:40:38][D][uart_debug:109]: <<< 3A 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B 57 29 0D 0A 30
[10:40:38][D][uart_debug:109]: <<< 2D 30 3A 67 93 D9 33 99 8A 86 06 06 06 16 16 96 D6 0A 30 2D
[10:40:38][D][uart_debug:109]: <<< 30 3A 39 36 2E 37 2E 39 28 30 30 30 30 33 CA C3 61 4C CB A6
[10:40:38][D][uart_debug:109]: <<< 96 96 72 96 76 E6 06 86 26 96 42 06 D6 06 A6 CA 66 E6 76 E6
[10:40:38][D][uart_debug:109]: <<< 16 96 96 86 16 96 06 CA 26 46 16 06 56 86 26 86 36 2D 19 09
[10:40:38][D][uart_debug:109]: <<< 06 06 06 06 82 36 06 46 06 A6 36 4B 86 16 96 06 CA 26 56 06
[10:40:39][D][uart_debug:109]: <<< CA 26 86 16 26 4D 4B 43 83 06 06 06 06 B3 96 CB 46 76 A6 36
[10:40:39][D][uart_debug:109]: <<< 4B D6 0A 31 2D 30 3A 33 33 2E 33 32 2E 30 28 30 30 30 31 30
[10:40:39][D][uart_debug:109]: <<< 29 0D 0A 31 2D 30 3A 33 32 2E 33 36 2E 30 28 30 30 30 30 31
[10:40:39][D][uart_debug:109]: <<< 29 0D 0A 30 2D 30 3A 39 36 2E 31 33 2E 30 28 29 0D 0A 31 2D
[10:40:39][D][uart_debug:109]: <<< 30 3A 33 32 2E 37 2E 30 28 32 33 30 2E 31 95 56 29 0D 0A 31
[10:40:39][D][uart_debug:109]: <<< 2D 30 3A 33 31 2E 37 2E 30 28 30 30 32 2A 41 29 0D 0A 31 2D
[10:40:39][D][uart_debug:109]: <<< 30 3A 32 31 9F 37 2E 30 28 30 30 2E BB 36 39 2A 6B 57 29 0D
[10:40:39][D][uart_debug:109]: <<< 0A 31 2D 30 9D 32 32 2E 37 2E 30 28 30 30 2E 30 30 30 2A 6B
[10:40:39][D][uart_debug:109]: <<< 57 29 0D 0A 30 2D 31 3A 32 34 2E 31 2E 30 28 30 30 33 29 0D
[10:40:39][D][uart_debug:109]: <<< 0A 30 2D 31 3A 39 36 2E 31 2E 30 28 34 37 33 30 33 30 33 37
[10:40:39][D][uart_debug:109]: <<< 33 38 33 35 33 36 33 35 33 35 33 38 33 38 33 30 33 35 33 38
[10:40:39][D][uart_debug:109]: <<< 33 38 33 32 33 30 94 43 61 D6 8B A6 26 46 E6 26 E6 16 86 26
[10:40:39][D][uart_debug:109]: <<< 16 16 16 06 46 16 07 46 06 06 96 76 2D 19 19 06 26 76 9B E6
[10:40:39][V][dsmr:073]: Footer of telegram found
[10:40:39][V][dsmr:161]: Trying to parse telegram
[10:40:39][E][dsmr:168]: !ADA7
[10:40:39] ^
[10:40:39]Checksum mismatch