openDTU with CMT2300A suddenly stops communicating with HMS 1600

Zaphodorange commented 1 year ago

What happened?

After almost one month with v23.9.18 the DTU just does not want to communicate with the Inverter at all. I tried with the (also / still) available DTU lite from Hoymiles without problem. I resetted the DTU to factory results (as stated in another bug) to no success. I did not recently update the DTU nor did right now since the updates after 23.9.18 seem not to touch any of my needed and used functionality. And the power supply is capable of doing up to 2,4 Amps / was not changed to working setup.

To Reproduce Bug

Reset, factory defaults and entering all configuration again does not help. DTU lite from hoymiles is working. Setup ran without problem 4 weeks before.

Expected Behavior

just connect as the weeks before

Install Method

Pre-Compiled binary from GitHub

What git-hash/version of OpenDTU?

v23.9.18

Relevant log/trace output

RX Period End
17:03:01.559 > All missing
17:03:01.559 > Nothing received, resend whole request
17:03:01.559 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B2 00 00 00 00 00 00 00 00 A6 92 E2 
17:03:01.559 > TX SPI Timeout
17:03:02.330 > RX Period End
17:03:02.330 > All missing
17:03:02.330 > Nothing received, resend whole request
17:03:02.330 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B2 00 00 00 00 00 00 00 00 A6 92 E2 
17:03:02.330 > TX SPI Timeout
17:03:03.101 > RX Period End
17:03:03.101 > All missing
17:03:03.101 > Nothing received, resend whole request
17:03:03.101 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B2 00 00 00 00 00 00 00 00 A6 92 E2 
17:03:03.101 > TX SPI Timeout
17:03:03.854 > RX Period End
17:03:03.854 > All missing
17:03:03.854 > Nothing received, resend count exeeded
17:03:03.903 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B2 00 00 00 00 00 00 00 00 B2 86 F6 
17:03:03.903 > TX SPI Timeout
17:03:04.086 > RX Period End
17:03:04.086 > All missing
17:03:04.086 > Nothing received, resend whole request
17:03:04.086 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B2 00 00 00 00 00 00 00 00 B2 86 F6 
17:03:04.086 > TX SPI Timeout
17:03:04.310 > RX Period End
17:03:04.310 > All missing
17:03:04.310 > Nothing received, resend whole request
17:03:04.310 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B2 00 00 00 00 00 00 00 00 B2 86 F6 
17:03:04.310 > TX SPI Timeout
17:03:04.555 > RX Period End
17:03:04.555 > All missing
17:03:04.555 > Nothing received, resend whole request
17:03:04.555 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B2 00 00 00 00 00 00 00 00 B2 86 F6 
17:03:04.555 > TX SPI Timeout
17:03:04.758 > RX Period End
17:03:04.758 > All missing
17:03:04.758 > Nothing received, resend whole request
17:03:04.758 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B2 00 00 00 00 00 00 00 00 B2 86 F6 
17:03:04.758 > TX SPI Timeout
17:03:04.960 > RX Period End
17:03:04.960 > All missing
17:03:04.960 > Nothing received, resend count exeeded
17:03:04.960 > Fetch inverter: 116491545678
17:03:04.960 > Request SystemConfigPara
17:03:05.005 > TX ChannelChangeCommand 868.00 MHz --> 56 91 54 56 78 80 18 45 48 02 15 21 14 14 1E 
17:03:05.005 > TX SPI Timeout
17:03:05.048 > RX Period End
17:03:05.048 > All missing
17:03:05.048 > Nothing received, resend count exeeded
17:03:05.093 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC B9 00 00 00 00 00 00 00 00 4C FA 71 
17:03:05.093 > TX SPI Timeout
17:03:05.516 > RX Period End
17:03:05.516 > All missing
17:03:05.516 > Nothing received, resend whole request
17:03:05.516 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC B9 00 00 00 00 00 00 00 00 4C FA 71 
17:03:05.516 > TX SPI Timeout
17:03:06.036 > RX Period End
17:03:06.036 > All missing
17:03:06.036 > Nothing received, resend whole request
17:03:06.036 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC B9 00 00 00 00 00 00 00 00 4C FA 71 
17:03:06.036 > TX SPI Timeout
17:03:06.554 > RX Period End
17:03:06.554 > All missing
17:03:06.554 > Nothing received, resend whole request
17:03:06.554 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC B9 00 00 00 00 00 00 00 00 4C FA 71 
17:03:06.554 > TX SPI Timeout
17:03:07.076 > RX Period End
17:03:07.076 > All missing
17:03:07.076 > Nothing received, resend whole request
17:03:07.076 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC B9 00 00 00 00 00 00 00 00 4C FA 71 
17:03:07.076 > TX SPI Timeout
17:03:07.577 > RX Period End
17:03:07.577 > All missing
17:03:07.577 > Nothing received, resend count exeeded
17:03:07.629 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B9 00 00 00 00 00 00 00 00 96 E1 AA 
17:03:07.629 > TX SPI Timeout
17:03:08.361 > RX Period End
17:03:08.361 > All missing
17:03:08.361 > Nothing received, resend whole request
17:03:08.361 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B9 00 00 00 00 00 00 00 00 96 E1 AA 
17:03:08.361 > TX SPI Timeout
17:03:09.135 > RX Period End
17:03:09.135 > All missing
17:03:09.135 > Nothing received, resend whole request
17:03:09.135 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B9 00 00 00 00 00 00 00 00 96 E1 AA 
17:03:09.135 > TX SPI Timeout
17:03:09.901 > RX Period End
17:03:09.901 > All missing
17:03:09.901 > Nothing received, resend whole request
17:03:09.901 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B9 00 00 00 00 00 00 00 00 96 E1 AA 
17:03:09.901 > TX SPI Timeout
17:03:10.671 > RX Period End
17:03:10.671 > All missing
17:03:10.671 > Nothing received, resend whole request
17:03:10.671 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC B9 00 00 00 00 00 00 00 00 96 E1 AA 
17:03:10.671 > TX SPI Timeout
17:03:11.429 > RX Period End
17:03:11.429 > All missing
17:03:11.429 > Nothing received, resend count exeeded
17:03:11.475 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B9 00 00 00 00 00 00 00 00 82 F5 BE 
17:03:11.475 > TX SPI Timeout
17:03:11.658 > RX Period End
17:03:11.658 > All missing
17:03:11.658 > Nothing received, resend whole request
17:03:11.658 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B9 00 00 00 00 00 00 00 00 82 F5 BE 
17:03:11.658 > TX SPI Timeout
17:03:11.877 > RX Period End
17:03:11.877 > All missing
17:03:11.877 > Nothing received, resend whole request
17:03:11.877 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B9 00 00 00 00 00 00 00 00 82 F5 BE 
17:03:11.877 > TX SPI Timeout
17:03:12.095 > RX Period End
17:03:12.095 > All missing
17:03:12.095 > Nothing received, resend whole request
17:03:12.095 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B9 00 00 00 00 00 00 00 00 82 F5 BE 
17:03:12.095 > TX SPI Timeout
17:03:12.317 > RX Period End
17:03:12.317 > All missing
17:03:12.317 > Nothing received, resend whole request
17:03:12.317 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC B9 00 00 00 00 00 00 00 00 82 F5 BE 
17:03:12.317 > TX SPI Timeout
17:03:12.520 > RX Period End
17:03:12.520 > All missing
17:03:12.520 > Nothing received, resend count exeeded
17:03:12.520 > Fetch inverter: 116491545678
17:03:12.520 > Request SystemConfigPara
17:03:12.568 > TX ChannelChangeCommand 868.00 MHz --> 56 91 54 56 78 80 18 45 48 02 15 21 14 14 1E 
17:03:12.568 > TX SPI Timeout
17:03:12.612 > RX Period End
17:03:12.612 > All missing
17:03:12.612 > Nothing received, resend count exeeded
17:03:12.656 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C1 00 00 00 00 00 00 00 00 4E F8 09 
17:03:12.656 > TX SPI Timeout
17:03:13.082 > RX Period End
17:03:13.083 > All missing
17:03:13.083 > Nothing received, resend whole request
17:03:13.083 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C1 00 00 00 00 00 00 00 00 4E F8 09 
17:03:13.083 > TX SPI Timeout
17:03:13.642 > RX Period End
17:03:13.642 > All missing
17:03:13.642 > Nothing received, resend whole request
17:03:13.642 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C1 00 00 00 00 00 00 00 00 4E F8 09 
17:03:13.642 > TX SPI Timeout
17:03:14.122 > RX Period End
17:03:14.122 > All missing
17:03:14.122 > Nothing received, resend whole request
17:03:14.122 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C1 00 00 00 00 00 00 00 00 4E F8 09 
17:03:14.122 > TX SPI Timeout
17:03:14.690 > RX Period End
17:03:14.690 > All missing
17:03:14.690 > Nothing received, resend whole request
17:03:14.690 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C1 00 00 00 00 00 00 00 00 4E F8 09 
17:03:14.690 > TX SPI Timeout
17:03:15.144 > RX Period End
17:03:15.144 > All missing
17:03:15.144 > Nothing received, resend count exeeded
17:03:15.201 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C1 00 00 00 00 00 00 00 00 94 E3 D2 
17:03:15.201 > TX SPI Timeout
17:03:15.928 > RX Period End
17:03:15.928 > All missing
17:03:15.928 > Nothing received, resend whole request
17:03:15.928 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C1 00 00 00 00 00 00 00 00 94 E3 D2 
17:03:15.928 > TX SPI Timeout
17:03:16.698 > RX Period End
17:03:16.698 > All missing
17:03:16.698 > Nothing received, resend whole request
17:03:16.698 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C1 00 00 00 00 00 00 00 00 94 E3 D2 
17:03:16.698 > TX SPI Timeout
17:03:17.470 > RX Period End
17:03:17.470 > All missing
17:03:17.470 > Nothing received, resend whole request
17:03:17.470 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C1 00 00 00 00 00 00 00 00 94 E3 D2 
17:03:17.470 > TX SPI Timeout
17:03:18.240 > RX Period End
17:03:18.240 > All missing
17:03:18.240 > Nothing received, resend whole request
17:03:18.240 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C1 00 00 00 00 00 00 00 00 94 E3 D2 
17:03:18.240 > TX SPI Timeout
17:03:18.992 > RX Period End
17:03:18.992 > All missing
17:03:18.992 > Nothing received, resend count exeeded
17:03:19.042 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC C1 00 00 00 00 00 00 00 00 80 F7 C6 
17:03:19.042 > TX SPI Timeout
17:03:19.225 > RX Period End
17:03:19.225 > All missing
17:03:19.225 > Nothing received, resend whole request
17:03:19.225 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC C1 00 00 00 00 00 00 00 00 80 F7 C6 
17:03:19.225 > TX SPI Timeout
17:03:19.444 > RX Period End
17:03:19.444 > All missing
17:03:19.444 > Nothing received, resend whole request
17:03:19.444 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC C1 00 00 00 00 00 00 00 00 80 F7 C6 
17:03:19.444 > TX SPI Timeout
17:03:19.663 > RX Period End
17:03:19.663 > All missing
17:03:19.663 > Nothing received, resend whole request
17:03:19.663 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC C1 00 00 00 00 00 00 00 00 80 F7 C6 
17:03:19.663 > TX SPI Timeout
17:03:19.888 > RX Period End
17:03:19.888 > All missing
17:03:19.888 > Nothing received, resend whole request
17:03:19.888 > TX SystemConfigPara 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 05 00 65 47 BC C1 00 00 00 00 00 00 00 00 80 F7 C6 
17:03:19.888 > TX SPI Timeout
17:03:20.094 > RX Period End
17:03:20.094 > All missing
17:03:20.094 > Nothing received, resend count exeeded
17:03:20.094 > Fetch inverter: 116491545678
17:03:20.094 > Request SystemConfigPara
17:03:20.140 > TX ChannelChangeCommand 868.00 MHz --> 56 91 54 56 78 80 18 45 48 02 15 21 14 14 1E 
17:03:20.140 > TX SPI Timeout
17:03:20.185 > RX Period End
17:03:20.185 > All missing
17:03:20.185 > Nothing received, resend count exeeded
17:03:20.231 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C9 00 00 00 00 00 00 00 00 8E 9F A6 
17:03:20.231 > TX SPI Timeout
17:03:20.654 > RX Period End
17:03:20.654 > All missing
17:03:20.654 > Nothing received, resend whole request
17:03:20.654 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C9 00 00 00 00 00 00 00 00 8E 9F A6 
17:03:20.654 > TX SPI Timeout
17:03:21.174 > RX Period End
17:03:21.174 > All missing
17:03:21.174 > Nothing received, resend whole request
17:03:21.174 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C9 00 00 00 00 00 00 00 00 8E 9F A6 
17:03:21.174 > TX SPI Timeout
17:03:21.694 > RX Period End
17:03:21.694 > All missing
17:03:21.694 > Nothing received, resend whole request
17:03:21.694 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C9 00 00 00 00 00 00 00 00 8E 9F A6 
17:03:21.694 > TX SPI Timeout
17:03:22.218 > RX Period End
17:03:22.218 > All missing
17:03:22.218 > Nothing received, resend whole request
17:03:22.218 > TX RealTimeRunData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 0B 00 65 47 BC C9 00 00 00 00 00 00 00 00 8E 9F A6 
17:03:22.218 > TX SPI Timeout
17:03:22.720 > RX Period End
17:03:22.720 > All missing
17:03:22.720 > Nothing received, resend count exeeded
17:03:22.766 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C9 00 00 00 00 00 00 00 00 54 84 7D 
17:03:22.766 > TX SPI Timeout
17:03:23.500 > RX Period End
17:03:23.500 > All missing
17:03:23.500 > Nothing received, resend whole request
17:03:23.500 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C9 00 00 00 00 00 00 00 00 54 84 7D 
17:03:23.500 > TX SPI Timeout
17:03:24.270 > RX Period End
17:03:24.270 > All missing
17:03:24.270 > Nothing received, resend whole request
17:03:24.270 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C9 00 00 00 00 00 00 00 00 54 84 7D 
17:03:24.270 > TX SPI Timeout
17:03:25.078 > RX Period End
17:03:25.078 > All missing
17:03:25.078 > Nothing received, resend whole request
17:03:25.078 > TX AlarmData 865.00 MHz --> 15 91 54 56 78 80 18 45 48 80 11 00 65 47 BC C9 00 00 00 00 00 00 00 00 54 84 7D 
17:03:25.078 > TX SPI Timeout

Anything else?

No response

tbnobody commented 1 year ago

TX SPI Timeout

Seems to be bad cable connection or defective module

Zaphodorange commented 1 year ago

Thanks for the comment ! I will open the Hardware and try to fix it. I can perform this only tomorrow - will do a follow up in this thread.

4irm commented 1 year ago

Hi there, I would like to add that I am also not able to establish any connection to my new HMS 1600. My 2nd inverter (HM 1500) works flawlessly. I double-checked every cable, etc. - Hence, I do not believe we are talking about a hardware defect here.

msaphoto06 commented 1 year ago

I have an identical problem on an esp32 with CMT The connection is unstable. No matter the setting 0db +20db I am at -65rssi when I transmit..

Off -14:39:39 On -14:37:12 On -13:18:42 Off - 12:36:34 On - 10:55:12

Zaphodorange commented 1 year ago

Hello, at least for what I opened the bug for is fixed; once I unplugged an re-plugged the 2,4Ghz board it started working again; thanks @tbnobody for the help ! As for @4irm : you are aware that the HMS uses 2,4 Ghz and the HMT 800 Mhz ?! You need two RF modules and I do not know how good they work in parallel ?!

Lineflyer commented 1 year ago

As for @4irm : you are aware that the HMS uses 2,4 Ghz and the HMT 800 Mhz ?! You need two RF modules and I do not know how good they work in parallel ?!

Just a correction: HM uses 2,4 GHz HMS and HMT use 800 MHz

But in case of @4irm you are right: The HM and HMS do not use the same frequency. He needs 2 RF modules.

tbnobody / OpenDTU