Energy Today is not incrementing with 3 lines PZEM setup if One PZEM is faulty

[AlfaBravoX]

PROBLEM DESCRIPTION

I run for couple of years 3 lines PZEM setup without any previous issues. I use split per phase configuration for counters.
Recently last L3 PZEM started to experienced some HW instability , ie. gives wrong sine frequency or dont report any AC params at all temporarily. Not a problem, this is not critical setup, so I can wait to find time to replace this faulty module.
What is bigger problem, that when this last L3 PZEM is not working , also Energy Today L1 and L2 counters not incrementing. I am not sure if this is bug or feature, but I'd like to have counters behaving 100% independent for each phase , even if one PZEM has failure.

REQUESTED INFORMATION

Make sure your have performed every step and checked the applicable boxes before submitting your issue. Thank you!

• Read the Contributing Guide and Policy and the Code of Conduct
• Searched the problem in issues
• Searched the problem in discussions
• Searched the problem in the docs
• [ x Searched the problem in the chat
• Device used (e.g., Sonoff Basic): PZEM004T
• Tasmota binary firmware version number used: 13.0.0 and tested also on 14.0 with same result
  • Pre-compiled
  • Self-compiled
• Flashing tools used: esptool.py fom console
• Provide the output of command: Backlog Template; Module; GPIO 255:

  Configuration output here:
12:21:22.067 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"NAME":"HW-655 PZEM","GPIO":[0,1472,0,1536,0,0,0,0,0,0,0,0,0,0],"FLAG":0,"BASE":1}
12:21:22.266 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"Module":{"0":"HW-655 PZEM"}}
12:21:22.472 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"GPIO0":{"0":"None"},"GPIO1":{"1472":"PZEM0XX Tx"},"GPIO2":{"0":"None"},"GPIO3":{"1536":"PZEM016 Rx"},"GPIO4":{"0":"None"},"GPIO5":{"0":"None"},"GPIO9":{"0":"None"},"GPIO10":{"0":"None"},"GPIO12":{"0":"None"},"GPIO13":{"0":"None"},"GPIO14":{"0":"None"},"GPIO15":{"0":"None"},"GPIO16":{"0":"None"},"GPIO17":{"0":"None"}}

• If using rules, provide the output of this command: Backlog Rule1; Rule2; Rule3:

  Rules output here:
12:13:06.134 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"Rule1":{"State":"OFF","Once":"ON","StopOnError":"OFF","Length":62,"Free":449,"Rules":"ON Time#Minute=1439 DO Backlog EnergyToday 0; Teleperiod ENDON"}}
12:13:06.335 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"Rule2":{"State":"OFF","Once":"OFF","StopOnError":"OFF","Length":0,"Free":511,"Rules":""}}
12:13:06.536 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"Rule3":{"State":"OFF","Once":"OFF","StopOnError":"OFF","Length":0,"Free":511,"Rules":""}}

• Provide the output of this command: Status 0:

  STATUS 0 output here:

12:13:24.347 MQT: chalan/stat/PZEM-CHBK32/STATUS = {"Status":{"Module":0,"DeviceName":"PZEM CHBK32","FriendlyName":["Tasmota"],"Topic":"PZEM-CHBK32","ButtonTopic":"0","Power":0,"PowerOnState":3,"LedState":1,"LedMask":"FFFF","SaveData":1,"SaveState":1,"SwitchTopic":"0","SwitchMode":[0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0],"ButtonRetain":0,"SwitchRetain":0,"SensorRetain":0,"PowerRetain":0,"InfoRetain":0,"StateRetain":0,"StatusRetain":0}}
12:13:24.354 MQT: chalan/stat/PZEM-CHBK32/STATUS1 = {"StatusPRM":{"Baudrate":9600,"SerialConfig":"8N1","GroupTopic":"tasmotas","OtaUrl":"http://ota.tasmota.com/tasmota/release-13.0.0/tasmota.bin.gz","RestartReason":"External System","Uptime":"0T01:49:03","StartupUTC":"2024-08-15T08:24:21","Sleep":50,"CfgHolder":4617,"BootCount":246,"BCResetTime":"2022-06-19T21:30:59","SaveCount":12396,"SaveAddress":"F8000"}}
12:13:24.359 MQT: chalan/stat/PZEM-CHBK32/STATUS2 = {"StatusFWR":{"Version":"13.0.0(tasmota)","BuildDateTime":"2023-06-26T10:42:07","Boot":31,"Core":"2_7_4_9","SDK":"2.2.2-dev(38a443e)","CpuFrequency":80,"Hardware":"ESP8266EX","CR":"384/699"}}
12:13:24.363 MQT: chalan/stat/PZEM-CHBK32/STATUS3 = {"StatusLOG":{"SerialLog":0,"WebLog":2,"MqttLog":0,"SysLog":0,"LogHost":"","LogPort":514,"SSId":["Na-Hlidce-891w","kostelecky"],"TelePeriod":30,"Resolution":"558380C0","SetOption":["00008009","2805C80001000600003C5A0A192800000000","00000080","00006000","0000C000","00000000"]}}
12:13:24.376 MQT: chalan/stat/PZEM-CHBK32/STATUS4 = {"StatusMEM":{"ProgramSize":634,"Free":368,"Heap":20,"ProgramFlashSize":1024,"FlashSize":4096,"FlashChipId":"164020","FlashFrequency":40,"FlashMode":"DOUT","Features":["00000809","8F9AC787","04368001","000000CF","010013C0","C000F981","00004004","00001000","54000020","00000080"],"Drivers":"1,2,3,4,5,6,7,8,9,10,12,16,18,19,20,21,22,24,26,27,29,30,35,37,45,62,68","Sensors":"1,2,3,4,5,6","I2CDriver":"7"}}
12:13:24.384 MQT: chalan/stat/PZEM-CHBK32/STATUS5 = {"StatusNET":{"Hostname":"PZEM-CHBK32-6476","IPAddress":"192.168.2.133","Gateway":"192.168.2.1","Subnetmask":"255.255.255.0","DNSServer1":"192.168.2.1","DNSServer2":"0.0.0.0","Mac":"BC:DD:C2:82:79:4C","Webserver":2,"HTTP_API":1,"WifiConfig":4,"WifiPower":17.0}}
12:13:24.389 MQT: chalan/stat/PZEM-CHBK32/STATUS6 = {"StatusMQT":{"MqttHost":"192.168.2.4","MqttPort":1883,"MqttClientMask":"DVES_%06X","MqttClient":"DVES_82794C","MqttUser":"DVES_USER","MqttCount":1,"MAX_PACKET_SIZE":1200,"KEEPALIVE":30,"SOCKET_TIMEOUT":4}}
12:13:24.396 MQT: chalan/stat/PZEM-CHBK32/STATUS7 = {"StatusTIM":{"UTC":"2024-08-15T10:13:24","Local":"2024-08-15T12:13:24","StartDST":"2024-03-31T02:00:00","EndDST":"2024-10-27T03:00:00","Timezone":99,"Sunrise":"06:43","Sunset":"21:04"}}
12:13:24.399 MQT: chalan/stat/PZEM-CHBK32/STATUS9 = {"StatusPTH":{"PowerDelta":[0,0,0],"PowerLow":0,"PowerHigh":0,"VoltageLow":0,"VoltageHigh":0,"CurrentLow":0,"CurrentHigh":0}}
12:13:24.412 MQT: chalan/stat/PZEM-CHBK32/STATUS10 = {"StatusSNS":{"Time":"2024-08-15T12:13:24","ENERGY":{"TotalStartTime":"2024-08-13T19:46:21","Total":[3804.073,2878.616,2448.653],"Yesterday":[0.288,0.021,0.062],"Today":[0.074,0.012,0.036],"Power":[3,7,17],"ApparentPower":[299,17,28],"ReactivePower":[299,15,22],"Factor":[0.01,0.40,0.61],"Frequency":[50,50,48],"Voltage":[236.9,236.4,236.6],"Current":[1.261,0.071,0.117]}}}
12:13:24.419 MQT: chalan/stat/PZEM-CHBK32/STATUS11 = {"StatusSTS":{"Time":"2024-08-15T12:13:24","Uptime":"0T01:49:03","UptimeSec":6543,"Heap":20,"SleepMode":"Dynamic","Sleep":50,"LoadAvg":19,"MqttCount":1,"Wifi":{"AP":2,"SSId":"kostelecky","BSSId":"7C:AD:74:A3:A6:A0","Channel":6,"Mode":"11n","RSSI":54,"Signal":-73,"LinkCount":1,"Downtime":"0T00:04:36"}}}

• Set weblog to 4 and then, when you experience your issue, provide the output of the Console log:

  Console output here:
I had weblog 4 enabled and I had avery second error similar as "cannot read PZEM 3 AC params"
Sorry I did not grab the text and now the problem with L3 PZEM has gone

TO REPRODUCE

Steps to reproduce the behavior:
Instal 3 PZEM and have last L3 faulty - not sure how this feasible, but this is repro

EXPECTED BEHAVIOUR

I'd like to have counters behaving 100% independent for each phase , even if one PZEM has failure.

SCREENSHOTS

L3 has bad sine freq, which it is issue with PZEM

ADDITIONAL CONTEXT

Not important, but the higher temperature is in area where PZEM is installed, the more unstable 3rd PZEM is

(Please, remember to close the issue when the problem has been addressed)

[arendst]

I need weblog 4 data to see how the number of detected PZEMs deteriorate.

What should happen is that if L3 fails communication within 30 seconds it is dropped from the poll loop and the other two should continue normally. Unless ofcourse the L3 PZEM screws up communication on your serial bus and all three PZEMs stop responding.

[AlfaBravoX]

OK, I will deliver weblog 4 data as soon the issue repeats. Now it seems to be stable since yesterday.

[AlfaBravoX]

Issue is back earlier then I expected.

weblog 4 attached:

16:15:41.953 CMD: Weblog 4
16:15:41.959 MQT: chalan/stat/PZEM-CHBK32/RESULT = {"WebLog":4}
16:15:42.188 CFG: Saved to flash at F5, Count 12399, Bytes 4096
16:15:42.602 DMP: 01 04 14 09 52 04 7A 00 00 00 5D 00 00 F0 13 00 3D 01 F4 00 03 00 00 00 CC
16:15:43.620 DMP: 03 10 94 4E 6B 00 A2 00 8B 00 A5 E2 40 00 59 FF
16:15:43.622 PAC: PzemAc 3 error 9
16:15:44.360 WIF: Checking connection...
16:15:44.601 DMP: 02 04 14 09 4C 00 49 00 00 00 43 00 00 84 24 00 2E 01 F4 00 27 00 00 24 D6
16:15:45.602 DMP: 01 04 14 09 51 04 6E 00 00 00 4F 00 00 F0 13 00 3D 01 F4 00 03 00 00 32 5E
16:15:46.621 DMP: 03 10 94 4D 6B 00 A3 00 8B 00 A5 E2 40 00 87
16:15:46.623 PAC: PzemAc 3 error 9
16:15:47.601 DMP: 02 04 14 09 4B 00 49 00 00 00 44 00 00 84 24 00 2E 01 F4 00 27 00 00 9A E5
16:15:48.601 DMP: 01 04 14 09 51 04 6F 00 00 00 4D 00 00 F0 13 00 3D 01 F4 00 03 00 00 64 8C
16:15:49.621 DMP: 03 10 94 4D 6C 00 A3 00 8B 00 A5 E2 3F 00 AA
16:15:49.623 PAC: PzemAc 3 error 9
16:15:50.601 DMP: 02 04 14 09 4C 00 4A 00 00 00 46 00 00 84 24 00 2E 01 F4 00 28 00 00 E8 20
16:15:51.601 DMP: 01 04 14 09 50 04 6E 00 00 00 4E 00 00 F0 13 00 3D 01 F4 00 03 00 00 0D 23
16:15:52.621 DMP: 03 10 94 4E 6B 00 A3 00 8B 00 A5 E2 40 00 19 FD
16:15:52.623 PAC: PzemAc 3 error 9
16:15:53.600 DMP: 02 04 14 09 4B 00 4A 00 00 00 45 00 00 84 24 00 2E 01 F4 00 27 00 00 68 94
16:15:54.602 DMP: 01 04 14 09 51 04 6C 00 00 00 4F 00 00 F0 13 00 3D 01 F4 00 03 00 00 93 3E
16:15:55.620 DMP: 03 10 94 4E 6B 00 A4 00 8B 00 A5 E2 40 00 B6
16:15:55.622 PAC: PzemAc 3 error 9
16:15:56.601 DMP: 02 04 14 09 4C 00 49 00 00 00 42 00 00 84 24 00 2E 01 F4 00 26 00 00 77 97
16:15:57.600 DMP: 01 04 14 09 51 04 72 00 00 00 4E 00 00 F0 13 00 3D 01 F4 00 03 00 00 F8 19
16:15:58.620 DMP: 03 10 94 4E 6C 00 A2 00 8B 00 A5 E2 3F 00 45 F3
16:15:58.622 PAC: PzemAc 3 error 9
16:15:59.602 DMP: 02 04 14 09 4B 00 49 00 00 00 43 00 00 84 24 00 2E 01 F4 00 27 00 00 91 A2
16:15:59.681 WIF: Sending Gratuitous ARP
16:16:00.601 DMP: 01 04 14 09 51 04 6F 00 00 00 52 00 00 F0 13 00 3D 01 F4 00 03 00 00 4E 13
16:16:01.620 DMP: 03 10 94 4E 6C 00 A2 00 8B 00 A5 E2 3F 00 45 F3
16:16:01.622 PAC: PzemAc 3 error 9
16:16:02.601 DMP: 02 04 14 09 4B 00 4A 00 00 00 44 00 00 84 24 00 2E 01 F3 00 27 00 00 DF D5
16:16:03.601 DMP: 01 04 14 09 52 04 72 00 00 00 52 00 00 F0 13 00 3D 01 F4 00 03 00 00 93 01

[AlfaBravoX]

also problem is manifested now:

I captured Energy Today at 16:18 CET TZ like this: 0.105 | 0.029 | 0.076 kWh
And now almost 30 mins later there is no any counter grow:
Energy Today |   | 0.105 |   | 0.029 |   | 0.076 |   | kWh

[arendst]

How does the weblog 4 output look now?

[arendst]

Ah, I see what goes wrong. Let me chk how to keep updating while there is still response albeit in error.....

[arendst]

Can you compile your binary? If so change these lines in file xnrg_05_pzem_ac.ino:

    if (error) {
      AddLog(LOG_LEVEL_DEBUG, PSTR("PAC: PzemAc %d error %d"), PZEM_AC_DEVICE_ADDRESS + PzemAc.phase, error);
    } else {
      Energy->data_valid[PzemAc.phase] = 0;
      if (10 == registers) {

        //           0     1     2     3     4     5     6     7     8     9           = ModBus register
        //  0  1  2  3  4  5  6  7  8  9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24  = Buffer index
        // 01 04 14 08 D1 00 6C 00 00 00 F4 00 00 00 26 00 00 01 F4 00 64 00 00 51 34
        // Id Cc Sz Volt- Current---- Power------ Energy----- Frequ PFact Alarm Crc--
        Energy->voltage[PzemAc.phase] = (float)((buffer[3] << 8) + buffer[4]) / 10.0f;                                                  // 6553.0 V
        Energy->current[PzemAc.phase] = (float)((buffer[7] << 24) + (buffer[8] << 16) + (buffer[5] << 8) + buffer[6]) / 1000.0f;        // 4294967.000 A
        Energy->active_power[PzemAc.phase] = (float)((buffer[11] << 24) + (buffer[12] << 16) + (buffer[9] << 8) + buffer[10]) / 10.0f;  // 429496729.0 W
        Energy->frequency[PzemAc.phase] = (float)((buffer[17] << 8) + buffer[18]) / 10.0f;                                              // 50.0 Hz
        Energy->power_factor[PzemAc.phase] = (float)((buffer[19] << 8) + buffer[20]) / 100.0f;                                          // 1.00
        Energy->import_active[PzemAc.phase] = (float)((buffer[15] << 24) + (buffer[16] << 16) + (buffer[13] << 8) + buffer[14]) / 1000.0f;  // 4294967.295 kWh
//        if (PzemAc.phase == Energy->phase_count -1) {
          if (TasmotaGlobal.uptime > (PZEM_AC_STABILIZE * ENERGY_MAX_PHASES)) {
            EnergyUpdateTotal();
          }
//        }
      }
    }

Notice the two commented lines.

[arendst]

Or this is even better:

    if (error) {
      AddLog(LOG_LEVEL_DEBUG, PSTR("PAC: PzemAc %d error %d"), PZEM_AC_DEVICE_ADDRESS + PzemAc.phase, error);
    } else {
      Energy->data_valid[PzemAc.phase] = 0;
      if (10 == registers) {

        //           0     1     2     3     4     5     6     7     8     9           = ModBus register
        //  0  1  2  3  4  5  6  7  8  9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24  = Buffer index
        // 01 04 14 08 D1 00 6C 00 00 00 F4 00 00 00 26 00 00 01 F4 00 64 00 00 51 34
        // Id Cc Sz Volt- Current---- Power------ Energy----- Frequ PFact Alarm Crc--
        Energy->voltage[PzemAc.phase] = (float)((buffer[3] << 8) + buffer[4]) / 10.0f;                                                  // 6553.0 V
        Energy->current[PzemAc.phase] = (float)((buffer[7] << 24) + (buffer[8] << 16) + (buffer[5] << 8) + buffer[6]) / 1000.0f;        // 4294967.000 A
        Energy->active_power[PzemAc.phase] = (float)((buffer[11] << 24) + (buffer[12] << 16) + (buffer[9] << 8) + buffer[10]) / 10.0f;  // 429496729.0 W
        Energy->frequency[PzemAc.phase] = (float)((buffer[17] << 8) + buffer[18]) / 10.0f;                                              // 50.0 Hz
        Energy->power_factor[PzemAc.phase] = (float)((buffer[19] << 8) + buffer[20]) / 100.0f;                                          // 1.00
        Energy->import_active[PzemAc.phase] = (float)((buffer[15] << 24) + (buffer[16] << 16) + (buffer[13] << 8) + buffer[14]) / 1000.0f;  // 4294967.295 kWh
      }
    }
    if (PzemAc.phase == Energy->phase_count -1) {
      if (TasmotaGlobal.uptime > (PZEM_AC_STABILIZE * ENERGY_MAX_PHASES)) {
        EnergyUpdateTotal();
      }
    }

[arendst]

The above fix is in latest dev branch v14.2.0.1

[AlfaBravoX]

awesome, thanks for super fast fix, i am now firing up gitpod and will try to compile dev branch v14.2.0.1 and test ;-)

[AlfaBravoX]

yeap!

energy Today has finally moved even L3 is broken:

Big thanks for that fix!