Development of 3 Phase Inverter Compatability

LeeFarm11 commented 2 weeks ago

Hi @britkat1980 Using Dev 3.0.32

First issue is here -

2024-10-21 19:41:26,798 - read - [INFO] - Starting watch_plant loop...
2024-10-21 19:41:26,815 - read - [CRITICAL] - Detecting inverter characteristics...
2024-10-21 19:41:47,835 - read - [DEBUG] - Running full refresh
2024-10-21 19:41:47,835 - read - [DEBUG] - Running processData
2024-10-21 19:41:47,835 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 19:41:47,852 - read - [DEBUG] - Getting Battery Details
2024-10-21 19:41:47,855 - read - [DEBUG] - Building battery output: 
2024-10-21 19:41:47,856 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 19:41:47,857 - read - [DEBUG] - Building battery output: 
2024-10-21 19:41:47,858 - read - [DEBUG] - Battery HY2343G257 added
2024-10-21 19:41:47,859 - read - [DEBUG] - Building battery output: 
2024-10-21 19:41:47,860 - read - [DEBUG] - Battery HY2343G446 added
2024-10-21 19:41:47,860 - read - [DEBUG] - Building battery output: 
2024-10-21 19:41:47,861 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 19:41:47,862 - read - [DEBUG] - Building battery output: 
2024-10-21 19:41:47,863 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 19:41:47,864 - read - [DEBUG] - Building battery output: 
2024-10-21 19:41:47,865 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 19:41:47,866 - read - [DEBUG] - Getting mode control figures
2024-10-21 19:41:47,867 - read - [DEBUG] - Saving the battery reserve percentage for later: 4
2024-10-21 19:41:47,868 - read - [DEBUG] - Calculating Mode...
2024-10-21 19:41:47,868 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 19:41:47,869 - read - [DEBUG] - Force Export is not Running
2024-10-21 19:41:47,870 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 19:41:47,871 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 19:41:47,874 - read - [DEBUG] - cache len= 0
2024-10-21 19:41:47,875 - read - [ERROR] - inverter Update failed so using last known good data from cache: ((<class 'KeyError'>, KeyError('Grid_Power'), <traceback object at 0x7eff9ec1cc80>), 1681)
2024-10-21 19:41:47,876 - read - [DEBUG] - processData result: processData Error processing registers: ((<class 'KeyError'>, KeyError('Grid_Power'), <traceback object at 0x7eff9ec1cc80>), 1681)
2024-10-21 19:41:47,877 - read - [DEBUG] - Running pubFromPickle
2024-10-21 19:41:47,877 - read - [ERROR] - runAll2 Error processing registers: ('KeyError', 'read.py', 1837)
2024-10-21 19:42:17,923 - read - [DEBUG] - Running partial refresh
2024-10-21 19:42:23,864 - read - [DEBUG] - Data get was successful, now running handler if needed: 
2024-10-21 19:42:23,864 - read - [DEBUG] - Running processData
2024-10-21 19:42:23,864 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 19:42:23,880 - read - [DEBUG] - Getting Battery Details
2024-10-21 19:42:23,883 - read - [DEBUG] - Building battery output: 
2024-10-21 19:42:23,885 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 19:42:23,885 - read - [DEBUG] - Building battery output: 
2024-10-21 19:42:23,886 - read - [DEBUG] - Battery HY2343G257 added
2024-10-21 19:42:23,887 - read - [DEBUG] - Building battery output: 
2024-10-21 19:42:23,888 - read - [DEBUG] - Battery HY2343G446 added
2024-10-21 19:42:23,889 - read - [DEBUG] - Building battery output: 
2024-10-21 19:42:23,890 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 19:42:23,891 - read - [DEBUG] - Building battery output: 
2024-10-21 19:42:23,892 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 19:42:23,892 - read - [DEBUG] - Building battery output: 
2024-10-21 19:42:23,894 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 19:42:23,895 - read - [DEBUG] - Getting mode control figures
2024-10-21 19:42:23,896 - read - [DEBUG] - Calculating Mode...
2024-10-21 19:42:23,897 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 19:42:23,897 - read - [DEBUG] - Force Export is not Running
2024-10-21 19:42:23,899 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 19:42:23,899 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 19:42:23,902 - read - [DEBUG] - cache len= 0
2024-10-21 19:42:23,904 - read - [ERROR] - inverter Update failed so using last known good data from cache: ((<class 'KeyError'>, KeyError('Grid_Power'), <traceback object at 0x7eff9ec41a40>), 1681)
2024-10-21 19:42:23,904 - read - [DEBUG] - processData result: processData Error processing registers: ((<class 'KeyError'>, KeyError('Grid_Power'), <traceback object at 0x7eff9ec41a40>), 1681)
2024-10-21 19:42:23,905 - read - [DEBUG] - Running pubFromPickle
2024-10-21 19:42:23,906 - read - [ERROR] - runAll2 Error processing registers: ('KeyError', 'read.py', 1837)
2024-10-21 19:42:53,940 - read - [DEBUG] - Running partial refresh
2024-10-21 19:42:58,374 - read - [DEBUG] - Data get was successful, now running handler if needed: 
2024-10-21 19:42:58,375 - read - [DEBUG] - Running processData
2024-10-21 19:42:58,376 - read - [DEBUG] - Beginning parsing of Inverter data

Approximately every 7 minutes I do get attempt to remove cache (pkl) files -

2024-10-21 19:58:08,383 - read - [DEBUG] - Building battery output: 
2024-10-21 19:58:08,383 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 19:58:08,384 - read - [DEBUG] - Building battery output: 
2024-10-21 19:58:08,385 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 19:58:08,385 - read - [DEBUG] - Building battery output: 
2024-10-21 19:58:08,386 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 19:58:08,387 - read - [DEBUG] - Getting mode control figures
2024-10-21 19:58:08,388 - read - [DEBUG] - Calculating Mode...
2024-10-21 19:58:08,388 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 19:58:08,389 - read - [DEBUG] - Force Export is not Running
2024-10-21 19:58:08,389 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 19:58:08,390 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 19:58:08,392 - read - [DEBUG] - cache len= 0
2024-10-21 19:58:08,393 - read - [ERROR] - 10 failed inverter reads in a row so removing Cache (pkl) files to force update...
2024-10-21 19:58:08,394 - read - [ERROR] - inverter Update failed so using last known good data from cache: ((<class 'KeyError'>, KeyError('Grid_Power'), <traceback object at 0x7fbb69601f80>), 1681)
2024-10-21 19:58:08,394 - read - [DEBUG] - processData result: processData Error processing registers: ((<class 'KeyError'>, KeyError('Grid_Power'), <traceback object at 0x7fbb69601f80>), 1681)
2024-10-21 19:58:08,394 - read - [DEBUG] - Running pubFromPickle
2024-10-21 19:58:08,395 - read - [ERROR] - runAll2 Error processing registers: ('KeyError', 'read.py', 1837)
2024-10-21 19:58:38,450 - read - [DEBUG] - Running partial refresh
2024-10-21 19:58:43,384 - read - [DEBUG] - Data get was successful, now running handler if needed: 
2024-10-21 19:58:43,384 - read - [DEBUG] - Running processData
2024-10-21 19:58:43,385 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 19:58:43,401 - read - [DEBUG] - Getting Battery Details
2024-10-21 19:58:43,403 - read - [DEBUG] - Building battery output: 
2024-10-21 19:58:43,404 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 19:58:43,404 - read - [DEBUG] - Building battery output:

LeeFarm11 commented 2 weeks ago

By the way, some background info. Giv have made some changes recently. In the Portal we now see some changes -

The numbering of the Charge and Discharge Slots has been messed up between Remote Control and the App/ Portal Settings. I raised in on FaceBook a while ago and now it has been mentioned in the Known Issues section in the Portal -

3PH Charge Slots - Between Remote Control and Inverter Card/App

We have identified that there is a small error and therefore mismatch of charge slots, 9 and 10 on the inverter card/app, pair with 1 and 2 on the remote control. This will be resolved imminently.

In the short term - remote control can be utilized if this is an easier way to manage your charge slots. PORTAL WORKING ON IT Created at 2024-10-16 09:31:21 by Adam R. (GivEnergy)

Remote Control has an issue with Charge Slot 2 -

There are 2 copies of Start Time and End Time. They seem to work in unison, so I guess only a UI issue.

New items -

For discharge lower limit we now have -

Power Factor, Battery Reserve % Charge Settings - Discharge Down to % Charge/ Discharge Schedules - DC Discharge (1 to 10) Lower SOC % Limit

I wonder in what order they have priority.

I attach latest set of settings available by REST and my current settings.

Inv_Values.csv

LeeFarm11 commented 2 weeks ago

Now updated to Dev 3.0.36

Various strange looking messages -

2024-10-21 22:47:33,432 - HA_Discovery - [ERROR] - Error connecting to MQTT Broker: (<class 'RuntimeError'>, RuntimeError('dictionary changed size during iteration'), <traceback object at 0x7f3f881fdd80>)

2024-10-21 22:48:52,924 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490069.0. Min limit: -30000. Max limit: 30000

2024-10-21 22:48:52,924 - read - [DEBUG] - Data cleansed for: Battery_Details 2024-10-21 22:48:52,924 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490069.0. Min limit: -30000. Max limit: 30000 2024-10-21 22:48:52,925 - read - [DEBUG] - Data cleansed for: Power 2024-10-21 22:48:52,926 - read - [DEBUG] - Data cleansed for: Power 2024-10-21 22:48:52,926 - read - [DEBUG] - Power_Factor is Zero so using old value 2024-10-21 22:48:52,927 - read - [DEBUG] - Data cleansed for: TD2343G049 2024-10-21 22:48:52,927 - read - [DEBUG] - Frequency is Zero so using old value 2024-10-21 22:48:52,927 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value 2024-10-21 22:48:52,928 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value 2024-10-21 22:48:52,928 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value 2024-10-21 22:48:52,928 - read - [DEBUG] - Data cleansed for: Meter_ID1 2024-10-21 22:48:52,928 - read - [DEBUG] - Data cleansed for: Meter_Details 2024-10-21 22:48:52,929 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value 2024-10-21 22:48:52,929 - read - [DEBUG] - Data cleansed for: Today 2024-10-21 22:48:52,929 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value 2024-10-21 22:48:52,930 - read - [DEBUG] - Data cleansed for: Total 2024-10-21 22:48:52,930 - read - [DEBUG] - Data cleansed for: Energy 2024-10-21 22:48:52,931 - read - [DEBUG] - Data cleansed for: Timeslots 2024-10-21 22:48:52,931 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value

Same messages repeating every 30 seconds - 2024-10-21 22:51:49,475 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490067.0. Min limit: -30000. Max limit: 30000 2024-10-21 22:51:49,476 - read - [DEBUG] - Data cleansed for: Power 2024-10-21 22:51:49,476 - read - [DEBUG] - Data cleansed for: Power 2024-10-21 22:51:49,476 - read - [DEBUG] - Power_Factor is Zero so using old value 2024-10-21 22:51:49,479 - read - [DEBUG] - Data cleansed for: TD2343G049 2024-10-21 22:51:49,480 - read - [DEBUG] - Frequency is Zero so using old value 2024-10-21 22:51:49,480 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value 2024-10-21 22:51:49,480 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value 2024-10-21 22:51:49,481 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value 2024-10-21 22:51:49,481 - read - [DEBUG] - Data cleansed for: Meter_ID1 2024-10-21 22:51:49,481 - read - [DEBUG] - Data cleansed for: Meter_Details 2024-10-21 22:51:49,482 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value 2024-10-21 22:51:49,482 - read - [DEBUG] - Data cleansed for: Today 2024-10-21 22:51:49,482 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value 2024-10-21 22:51:49,483 - read - [DEBUG] - Data cleansed for: Total 2024-10-21 22:51:49,483 - read - [DEBUG] - Data cleansed for: Energy 2024-10-21 22:51:49,483 - read - [DEBUG] - Data cleansed for: Timeslots 2024-10-21 22:51:49,484 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value

Also this multiple times - 2024-10-21 22:52:59,074 - read - [INFO] - Phase_1_Current jumped too far in a single read: 2.7->10.1 so using previous value

Also this - 2024-10-21 22:56:29,413 - read - [INFO] - Inverter_Power_Out jumped too far in a single read: 429490069.0->2655.0 so using previous value 2024-10-21 22:56:29,413 - read - [INFO] - Load_Phase2_Power jumped too far in a single read: 0.0->6461.2 so using previous value 2024-10-21 22:56:29,413 - read - [INFO] - Load_Phase3_Power jumped too far in a single read: 23.6->6485.0 so using previous value 2024-10-21 22:56:29,414 - read - [INFO] - Export_Phase1_Power jumped too far in a single read: 6550.0->87.6 so using previous value 2024-10-21 22:56:29,415 - read - [INFO] - Export_Phase2_Power jumped too far in a single read: 6550.7->91.3 so using previous value 2024-10-21 22:56:29,415 - read - [INFO] - Export_Phase3_Power jumped too far in a single read: 6548.6->86.6 so using previous value 2024-10-21 22:56:29,416 - read - [INFO] - Grid_Phase1_Current jumped too far in a single read: 0.8->3.9 so using previous value 2024-10-21 22:56:29,416 - read - [INFO] - Grid_Phase2_Current jumped too far in a single read: 0.8->4.0 so using previous value 2024-10-21 22:56:29,417 - read - [INFO] - Grid_Phase3_Current jumped too far in a single read: 0.8->3.8 so using previous value 2024-10-21 22:56:29,418 - read - [DEBUG] - Data cleansed for: Power 2024-10-21 22:56:29,418 - read - [DEBUG] - Data cleansed for: Power 2024-10-21 22:56:29,419 - read - [DEBUG] - Power_Factor is Zero so using old value 2024-10-21 22:56:29,420 - read - [DEBUG] - Data cleansed for: TD2343G049 2024-10-21 22:56:29,420 - read - [INFO] - Phase_1_Current jumped too far in a single read: 2.7->10.1 so using previous value 2024-10-21 22:56:29,421 - read - [DEBUG] - Frequency is Zero so using old value 2024-10-21 22:56:29,421 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value 2024-10-21 22:56:29,422 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value 2024-10-21 22:56:29,422 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value 2024-10-21 22:56:29,423 - read - [DEBUG] - Data cleansed for: Meter_ID1 2024-10-21 22:56:29,424 - read - [DEBUG] - Data cleansed for: Meter_Details 2024-10-21 22:56:29,425 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value 2024-10-21 22:56:29,425 - read - [DEBUG] - Data cleansed for: Today 2024-10-21 22:56:29,426 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value 2024-10-21 22:56:29,427 - read - [DEBUG] - Data cleansed for: Total 2024-10-21 22:56:29,427 - read - [DEBUG] - Data cleansed for: Energy 2024-10-21 22:56:29,428 - read - [DEBUG] - Data cleansed for: Timeslots 2024-10-21 22:56:29,428 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value

First couple of minutes log is here -

2024-10-21 22:46:59,227 - read - [INFO] - Starting watch_plant loop...
2024-10-21 22:46:59,234 - read - [CRITICAL] - Detecting inverter characteristics...
2024-10-21 22:47:27,248 - read - [DEBUG] - Running full refresh
2024-10-21 22:47:27,248 - read - [DEBUG] - Running processData
2024-10-21 22:47:27,248 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 22:47:27,263 - read - [DEBUG] - Getting Battery Details
2024-10-21 22:47:27,266 - read - [DEBUG] - Building battery output: 
2024-10-21 22:47:27,267 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 22:47:27,267 - read - [DEBUG] - Building battery output: 
2024-10-21 22:47:27,268 - read - [DEBUG] - Battery HY2343G257 added
2024-10-21 22:47:27,268 - read - [DEBUG] - Building battery output: 
2024-10-21 22:47:27,269 - read - [DEBUG] - Battery HY2343G446 added
2024-10-21 22:47:27,270 - read - [DEBUG] - Building battery output: 
2024-10-21 22:47:27,271 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 22:47:27,271 - read - [DEBUG] - Building battery output: 
2024-10-21 22:47:27,273 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 22:47:27,273 - read - [DEBUG] - Building battery output: 
2024-10-21 22:47:27,274 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 22:47:27,278 - read - [DEBUG] - Getting mode control figures
2024-10-21 22:47:27,279 - read - [DEBUG] - Saving the battery reserve percentage for later: 5
2024-10-21 22:47:27,279 - read - [DEBUG] - Calculating Mode...
2024-10-21 22:47:27,280 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 22:47:27,280 - read - [DEBUG] - Force Export is not Running
2024-10-21 22:47:27,281 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:47:27,281 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:47:27,284 - read - [DEBUG] - cache len= 0
2024-10-21 22:47:27,284 - read - [DEBUG] - Starting ratecalcs...
2024-10-21 22:47:27,318 - read - [DEBUG] - Night Start= Mon Oct 21 01:00:00 2024
2024-10-21 22:47:27,319 - read - [DEBUG] - Day Start= Mon Oct 21 06:00:00 2024
2024-10-21 22:47:27,319 - read - [DEBUG] - No rate_data exists, so creating new baseline
2024-10-21 22:47:27,320 - read - [DEBUG] - No Night Start Energy so setting it to: 3037.7
2024-10-21 22:47:27,320 - read - [DEBUG] - No Day Start Energy so setting it to: 3037.7
2024-10-21 22:47:27,326 - read - [DEBUG] - Setting Rate to Night
2024-10-21 22:47:27,327 - read - [DEBUG] - First time running so saving AC Charge status
2024-10-21 22:47:27,327 - read - [DEBUG] - Battery rate calcs complete
2024-10-21 22:47:27,331 - read - [DEBUG] - Successfully processed data from: 192.168.1.101
2024-10-21 22:47:27,345 - read - [DEBUG] - processData result: Success processing data
2024-10-21 22:47:27,346 - read - [DEBUG] - Running pubFromPickle
2024-10-21 22:47:27,347 - read - [DEBUG] - Dealt with HY2343G025
2024-10-21 22:47:27,348 - read - [DEBUG] - Dealt with HY2343G144
2024-10-21 22:47:27,348 - read - [DEBUG] - Dealt with HY2343G257
2024-10-21 22:47:27,349 - read - [DEBUG] - Dealt with HY2343G259
2024-10-21 22:47:27,349 - read - [DEBUG] - Dealt with HY2343G302
2024-10-21 22:47:27,350 - read - [DEBUG] - Dealt with HY2343G446
2024-10-21 22:47:27,350 - read - [DEBUG] - Dealt with Battery_Stack_1
2024-10-21 22:47:27,350 - read - [DEBUG] - Dealt with Battery_Details
2024-10-21 22:47:27,351 - read - [DEBUG] - Running updateFirstRun with SN= TD2343G049
2024-10-21 22:47:27,351 - read - [DEBUG] - Opening settings for first run
2024-10-21 22:47:27,351 - read - [DEBUG] - Settings available
2024-10-21 22:47:27,352 - read - [DEBUG] - serial number aready exists: "    serial_number="TD2343G049"
"
2024-10-21 22:47:27,353 - read - [DEBUG] - removing lockfile
2024-10-21 22:47:27,353 - read - [INFO] - Publishing Home Assistant Discovery messages
2024-10-21 22:47:27,381 - HA_Discovery - [DEBUG] - Publishing: GivEnergy/TD2343G049/Battery_Details
2024-10-21 22:47:27,381 - mqtt - [DEBUG] - Prepping HY2343G025 for publishing
2024-10-21 22:47:27,382 - mqtt - [DEBUG] - Prepping HY2343G144 for publishing
2024-10-21 22:47:27,382 - mqtt - [DEBUG] - Prepping HY2343G257 for publishing
2024-10-21 22:47:27,382 - mqtt - [DEBUG] - Prepping HY2343G259 for publishing
2024-10-21 22:47:27,383 - mqtt - [DEBUG] - Prepping HY2343G302 for publishing
2024-10-21 22:47:27,383 - mqtt - [DEBUG] - Prepping HY2343G446 for publishing
2024-10-21 22:47:27,384 - mqtt - [DEBUG] - Prepping Battery_Stack_1 for publishing
2024-10-21 22:47:27,416 - HA_Discovery - [DEBUG] - Publishing: GivEnergy/TD2343G049/Stats/Timeout_Error
2024-10-21 22:47:28,420 - HA_Discovery - [DEBUG] - Foundmessages= 0
2024-10-21 22:47:28,421 - HA_Discovery - [DEBUG] - Moremessages= 470
2024-10-21 22:47:29,422 - HA_Discovery - [DEBUG] - Foundmessages= 470
2024-10-21 22:47:29,422 - HA_Discovery - [DEBUG] - Moremessages= 470
2024-10-21 22:47:30,423 - HA_Discovery - [DEBUG] - Foundmessages= 470
2024-10-21 22:47:30,423 - HA_Discovery - [DEBUG] - Moremessages= 470
2024-10-21 22:47:31,424 - HA_Discovery - [DEBUG] - Foundmessages= 470
2024-10-21 22:47:31,424 - HA_Discovery - [DEBUG] - Moremessages= 470
2024-10-21 22:47:32,425 - HA_Discovery - [DEBUG] - Foundmessages= 470
2024-10-21 22:47:32,425 - HA_Discovery - [DEBUG] - Moremessages= 470
2024-10-21 22:47:33,427 - HA_Discovery - [DEBUG] - Foundmessages= 470
2024-10-21 22:47:33,427 - HA_Discovery - [DEBUG] - Moremessages= 470
2024-10-21 22:47:33,432 - HA_Discovery - [ERROR] - Error connecting to MQTT Broker: (<class 'RuntimeError'>, RuntimeError('dictionary changed size during iteration'), <traceback object at 0x7f3f881fdd80>)
2024-10-21 22:47:36,436 - HA_Discovery - [DEBUG] - Connecting to broker: core-mosquitto
2024-10-21 22:47:36,436 - HA_Discovery - [DEBUG] - In wait loop (sendDiscoMsg)
2024-10-21 22:47:36,637 - HA_Discovery - [DEBUG] - In wait loop (sendDiscoMsg)
2024-10-21 22:47:36,838 - HA_Discovery - [DEBUG] - In wait loop (sendDiscoMsg)
2024-10-21 22:47:37,038 - HA_Discovery - [DEBUG] - In wait loop (sendDiscoMsg)
2024-10-21 22:47:37,240 - HA_Discovery - [DEBUG] - In wait loop (sendDiscoMsg)
2024-10-21 22:47:37,439 - HA_Discovery - [DEBUG] - connected OK Returned code=Success
2024-10-21 22:47:37,441 - HA_Discovery - [DEBUG] - Publishing MQTT: core-mosquitto
2024-10-21 22:47:43,486 - HA_Discovery - [DEBUG] - Sent 240 discovery messages
2024-10-21 22:47:43,487 - HA_Discovery - [DEBUG] - Found 474 discovery messages
2024-10-21 22:47:43,489 - read - [DEBUG] - Publish all to MQTT
2024-10-21 22:47:43,489 - mqtt - [DEBUG] - MQTT Connection appears closed, re-opening
2024-10-21 22:47:43,490 - mqtt - [DEBUG] - Opening MQTT Connection to core-mosquitto
2024-10-21 22:47:43,494 - mqtt - [DEBUG] - connected OK Returned code=Success
2024-10-21 22:47:43,495 - mqtt - [DEBUG] - Subscribing to GivEnergy/control/TD2343G049/#
2024-10-21 22:47:43,495 - mqtt - [DEBUG] - Publishing: GivEnergy/TD2343G049/Battery_Details
2024-10-21 22:47:43,496 - mqtt - [DEBUG] - Prepping HY2343G025 for publishing
2024-10-21 22:47:43,497 - mqtt - [DEBUG] - Prepping HY2343G144 for publishing
2024-10-21 22:47:43,497 - mqtt - [DEBUG] - Prepping HY2343G257 for publishing
2024-10-21 22:47:43,497 - mqtt - [DEBUG] - Prepping HY2343G259 for publishing
2024-10-21 22:47:43,499 - mqtt - [DEBUG] - Prepping HY2343G302 for publishing
2024-10-21 22:47:43,499 - mqtt - [DEBUG] - Prepping HY2343G446 for publishing
2024-10-21 22:47:43,500 - mqtt - [DEBUG] - Prepping Battery_Stack_1 for publishing
2024-10-21 22:47:43,540 - mqtt - [DEBUG] - Publishing: GivEnergy/TD2343G049/Stats/Timeout_Error
2024-10-21 22:48:13,590 - read - [DEBUG] - Running partial refresh
2024-10-21 22:48:18,013 - read - [DEBUG] - Data get was successful, now running handler if needed: 
2024-10-21 22:48:18,013 - read - [DEBUG] - Running processData
2024-10-21 22:48:18,018 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 22:48:18,035 - read - [DEBUG] - Getting Battery Details
2024-10-21 22:48:18,037 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:18,038 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 22:48:18,039 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:18,039 - read - [DEBUG] - Battery HY2343G257 added
2024-10-21 22:48:18,040 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:18,041 - read - [DEBUG] - Battery HY2343G446 added
2024-10-21 22:48:18,041 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:18,042 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 22:48:18,042 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:18,043 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 22:48:18,044 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:18,045 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 22:48:18,046 - read - [DEBUG] - Getting mode control figures
2024-10-21 22:48:18,047 - read - [DEBUG] - Calculating Mode...
2024-10-21 22:48:18,047 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 22:48:18,048 - read - [DEBUG] - Force Export is not Running
2024-10-21 22:48:18,048 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:48:18,049 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:48:18,054 - read - [DEBUG] - cache len= 1
2024-10-21 22:48:18,055 - read - [DEBUG] - Starting ratecalcs...
2024-10-21 22:48:18,055 - read - [DEBUG] - Night Start= Mon Oct 21 01:00:00 2024
2024-10-21 22:48:18,055 - read - [DEBUG] - Day Start= Mon Oct 21 06:00:00 2024
2024-10-21 22:48:18,057 - read - [DEBUG] - Setting Rate to Night
2024-10-21 22:48:18,058 - read - [DEBUG] - Battery rate calcs complete
2024-10-21 22:48:18,066 - read - [DEBUG] - Successfully processed data from: 192.168.1.101
2024-10-21 22:48:18,080 - read - [DEBUG] - processData result: Success processing data
2024-10-21 22:48:18,080 - read - [DEBUG] - Running pubFromPickle
2024-10-21 22:48:18,081 - read - [DEBUG] - Dealt with HY2343G025
2024-10-21 22:48:18,082 - read - [DEBUG] - Dealt with HY2343G144
2024-10-21 22:48:18,082 - read - [DEBUG] - Dealt with HY2343G257
2024-10-21 22:48:18,083 - read - [DEBUG] - Dealt with HY2343G259
2024-10-21 22:48:18,083 - read - [DEBUG] - Dealt with HY2343G302
2024-10-21 22:48:18,084 - read - [DEBUG] - Dealt with HY2343G446
2024-10-21 22:48:18,084 - read - [DEBUG] - Dealt with Battery_Stack_1
2024-10-21 22:48:18,085 - read - [DEBUG] - Dealt with Battery_Details
2024-10-21 22:48:18,085 - read - [DEBUG] - firstrun exists, so this should already have been run
2024-10-21 22:48:18,085 - read - [DEBUG] - Publish all to MQTT
2024-10-21 22:48:18,085 - mqtt - [DEBUG] - Publishing: GivEnergy/TD2343G049/Battery_Details
2024-10-21 22:48:18,086 - mqtt - [DEBUG] - Prepping HY2343G025 for publishing
2024-10-21 22:48:18,086 - mqtt - [DEBUG] - Prepping HY2343G144 for publishing
2024-10-21 22:48:18,086 - mqtt - [DEBUG] - Prepping HY2343G257 for publishing
2024-10-21 22:48:18,087 - mqtt - [DEBUG] - Prepping HY2343G259 for publishing
2024-10-21 22:48:18,087 - mqtt - [DEBUG] - Prepping HY2343G302 for publishing
2024-10-21 22:48:18,087 - mqtt - [DEBUG] - Prepping HY2343G446 for publishing
2024-10-21 22:48:18,088 - mqtt - [DEBUG] - Prepping Battery_Stack_1 for publishing
2024-10-21 22:48:48,196 - read - [DEBUG] - Running partial refresh
2024-10-21 22:48:52,872 - read - [DEBUG] - Data get was successful, now running handler if needed: 
2024-10-21 22:48:52,872 - read - [DEBUG] - Running processData
2024-10-21 22:48:52,873 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 22:48:52,891 - read - [DEBUG] - Getting Battery Details
2024-10-21 22:48:52,894 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:52,894 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 22:48:52,895 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:52,896 - read - [DEBUG] - Battery HY2343G257 added
2024-10-21 22:48:52,896 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:52,897 - read - [DEBUG] - Battery HY2343G446 added
2024-10-21 22:48:52,897 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:52,898 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 22:48:52,898 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:52,899 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 22:48:52,900 - read - [DEBUG] - Building battery output: 
2024-10-21 22:48:52,900 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 22:48:52,901 - read - [DEBUG] - Getting mode control figures
2024-10-21 22:48:52,902 - read - [DEBUG] - Calculating Mode...
2024-10-21 22:48:52,903 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 22:48:52,904 - read - [DEBUG] - Force Export is not Running
2024-10-21 22:48:52,904 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:48:52,905 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:48:52,912 - read - [DEBUG] - cache len= 2
2024-10-21 22:48:52,912 - read - [DEBUG] - Running the data cleansing process
2024-10-21 22:48:52,917 - read - [DEBUG] - Data cleansed for: HY2343G302
2024-10-21 22:48:52,919 - read - [DEBUG] - Data cleansed for: HY2343G257
2024-10-21 22:48:52,920 - read - [DEBUG] - Data cleansed for: HY2343G446
2024-10-21 22:48:52,921 - read - [DEBUG] - Data cleansed for: HY2343G025
2024-10-21 22:48:52,922 - read - [DEBUG] - Data cleansed for: HY2343G259
2024-10-21 22:48:52,923 - read - [DEBUG] - Data cleansed for: HY2343G144
2024-10-21 22:48:52,924 - read - [DEBUG] - Data cleansed for: Battery_Stack_1
2024-10-21 22:48:52,924 - read - [DEBUG] - Data cleansed for: Battery_Details
2024-10-21 22:48:52,924 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490069.0. Min limit: -30000. Max limit: 30000
2024-10-21 22:48:52,925 - read - [DEBUG] - Data cleansed for: Power
2024-10-21 22:48:52,926 - read - [DEBUG] - Data cleansed for: Power
2024-10-21 22:48:52,926 - read - [DEBUG] - Power_Factor is Zero so using old value
2024-10-21 22:48:52,927 - read - [DEBUG] - Data cleansed for: TD2343G049
2024-10-21 22:48:52,927 - read - [DEBUG] - Frequency is Zero so using old value
2024-10-21 22:48:52,927 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value
2024-10-21 22:48:52,928 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value
2024-10-21 22:48:52,928 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value
2024-10-21 22:48:52,928 - read - [DEBUG] - Data cleansed for: Meter_ID1
2024-10-21 22:48:52,928 - read - [DEBUG] - Data cleansed for: Meter_Details
2024-10-21 22:48:52,929 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value
2024-10-21 22:48:52,929 - read - [DEBUG] - Data cleansed for: Today
2024-10-21 22:48:52,929 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value
2024-10-21 22:48:52,930 - read - [DEBUG] - Data cleansed for: Total
2024-10-21 22:48:52,930 - read - [DEBUG] - Data cleansed for: Energy
2024-10-21 22:48:52,931 - read - [DEBUG] - Data cleansed for: Timeslots
2024-10-21 22:48:52,931 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value
2024-10-21 22:48:52,932 - read - [DEBUG] - Data cleansed for: Control
2024-10-21 22:48:52,933 - read - [DEBUG] - Data cleansed for: Stats
2024-10-21 22:48:52,933 - read - [DEBUG] - Starting ratecalcs...
2024-10-21 22:48:52,933 - read - [DEBUG] - Night Start= Mon Oct 21 01:00:00 2024
2024-10-21 22:48:52,934 - read - [DEBUG] - Day Start= Mon Oct 21 06:00:00 2024
2024-10-21 22:48:52,936 - read - [DEBUG] - Setting Rate to Night
2024-10-21 22:48:52,937 - read - [DEBUG] - Battery rate calcs complete
2024-10-21 22:48:52,945 - read - [DEBUG] - Successfully processed data from: 192.168.1.101
2024-10-21 22:48:52,959 - read - [DEBUG] - processData result: Success processing data
2024-10-21 22:48:52,959 - read - [DEBUG] - Running pubFromPickle
2024-10-21 22:48:52,961 - read - [DEBUG] - Dealt with HY2343G025
2024-10-21 22:48:52,961 - read - [DEBUG] - Dealt with HY2343G144
2024-10-21 22:48:52,962 - read - [DEBUG] - Dealt with HY2343G257
2024-10-21 22:48:52,962 - read - [DEBUG] - Dealt with HY2343G259
2024-10-21 22:48:52,963 - read - [DEBUG] - Dealt with HY2343G302
2024-10-21 22:48:52,964 - read - [DEBUG] - Dealt with HY2343G446
2024-10-21 22:48:52,964 - read - [DEBUG] - Dealt with Battery_Stack_1
2024-10-21 22:48:52,964 - read - [DEBUG] - Dealt with Battery_Details
2024-10-21 22:48:52,965 - read - [DEBUG] - firstrun exists, so this should already have been run
2024-10-21 22:48:52,965 - read - [DEBUG] - Publish all to MQTT
2024-10-21 22:48:52,965 - mqtt - [DEBUG] - Publishing: GivEnergy/TD2343G049/Battery_Details
2024-10-21 22:48:52,966 - mqtt - [DEBUG] - Prepping HY2343G025 for publishing
2024-10-21 22:48:52,966 - mqtt - [DEBUG] - Prepping HY2343G144 for publishing
2024-10-21 22:48:52,966 - mqtt - [DEBUG] - Prepping HY2343G257 for publishing
2024-10-21 22:48:52,967 - mqtt - [DEBUG] - Prepping HY2343G259 for publishing
2024-10-21 22:48:52,967 - mqtt - [DEBUG] - Prepping HY2343G302 for publishing
2024-10-21 22:48:52,967 - mqtt - [DEBUG] - Prepping HY2343G446 for publishing
2024-10-21 22:48:52,967 - mqtt - [DEBUG] - Prepping Battery_Stack_1 for publishing
2024-10-21 22:49:23,043 - read - [DEBUG] - Running partial refresh
2024-10-21 22:49:28,973 - read - [DEBUG] - Data get was successful, now running handler if needed: 
2024-10-21 22:49:28,974 - read - [DEBUG] - Running processData
2024-10-21 22:49:28,974 - read - [DEBUG] - Beginning parsing of Inverter data
2024-10-21 22:49:28,999 - read - [DEBUG] - Getting Battery Details
2024-10-21 22:49:29,003 - read - [DEBUG] - Building battery output: 
2024-10-21 22:49:29,005 - read - [DEBUG] - Battery HY2343G302 added
2024-10-21 22:49:29,006 - read - [DEBUG] - Building battery output: 
2024-10-21 22:49:29,008 - read - [DEBUG] - Battery HY2343G257 added
2024-10-21 22:49:29,009 - read - [DEBUG] - Building battery output: 
2024-10-21 22:49:29,010 - read - [DEBUG] - Battery HY2343G446 added
2024-10-21 22:49:29,011 - read - [DEBUG] - Building battery output: 
2024-10-21 22:49:29,013 - read - [DEBUG] - Battery HY2343G025 added
2024-10-21 22:49:29,014 - read - [DEBUG] - Building battery output: 
2024-10-21 22:49:29,016 - read - [DEBUG] - Battery HY2343G259 added
2024-10-21 22:49:29,017 - read - [DEBUG] - Building battery output: 
2024-10-21 22:49:29,018 - read - [DEBUG] - Battery HY2343G144 added
2024-10-21 22:49:29,020 - read - [DEBUG] - Getting mode control figures
2024-10-21 22:49:29,021 - read - [DEBUG] - Calculating Mode...
2024-10-21 22:49:29,023 - read - [DEBUG] - Mode is: Eco (Paused)
2024-10-21 22:49:29,023 - read - [DEBUG] - Force Export is not Running
2024-10-21 22:49:29,024 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:49:29,026 - read - [DEBUG] - Getting TimeSlot data
2024-10-21 22:49:29,038 - read - [DEBUG] - cache len= 3
2024-10-21 22:49:29,039 - read - [DEBUG] - Running the data cleansing process
2024-10-21 22:49:29,044 - read - [DEBUG] - Data cleansed for: HY2343G302
2024-10-21 22:49:29,046 - read - [DEBUG] - Data cleansed for: HY2343G257
2024-10-21 22:49:29,047 - read - [DEBUG] - Data cleansed for: HY2343G446
2024-10-21 22:49:29,049 - read - [DEBUG] - Data cleansed for: HY2343G025
2024-10-21 22:49:29,051 - read - [DEBUG] - Data cleansed for: HY2343G259
2024-10-21 22:49:29,053 - read - [DEBUG] - Data cleansed for: HY2343G144
2024-10-21 22:49:29,053 - read - [DEBUG] - Data cleansed for: Battery_Stack_1
2024-10-21 22:49:29,054 - read - [DEBUG] - Data cleansed for: Battery_Details
2024-10-21 22:49:29,055 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490067.0. Min limit: -30000. Max limit: 30000
2024-10-21 22:49:29,056 - read - [DEBUG] - Data cleansed for: Power
2024-10-21 22:49:29,056 - read - [DEBUG] - Data cleansed for: Power
2024-10-21 22:49:29,059 - read - [DEBUG] - Power_Factor is Zero so using old value
2024-10-21 22:49:29,060 - read - [DEBUG] - Data cleansed for: TD2343G049
2024-10-21 22:49:29,061 - read - [DEBUG] - Frequency is Zero so using old value
2024-10-21 22:49:29,062 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value
2024-10-21 22:49:29,063 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value
2024-10-21 22:49:29,064 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value
2024-10-21 22:49:29,065 - read - [DEBUG] - Data cleansed for: Meter_ID1
2024-10-21 22:49:29,066 - read - [DEBUG] - Data cleansed for: Meter_Details
2024-10-21 22:49:29,067 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value
2024-10-21 22:49:29,067 - read - [DEBUG] - Data cleansed for: Today
2024-10-21 22:49:29,067 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value
2024-10-21 22:49:29,068 - read - [DEBUG] - Data cleansed for: Total
2024-10-21 22:49:29,068 - read - [DEBUG] - Data cleansed for: Energy
2024-10-21 22:49:29,068 - read - [DEBUG] - Data cleansed for: Timeslots
2024-10-21 22:49:29,071 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value
2024-10-21 22:49:29,073 - read - [DEBUG] - Data cleansed for: Control
2024-10-21 22:49:29,074 - read - [DEBUG] - Data cleansed for: Stats
2024-10-21 22:49:29,075 - read - [DEBUG] - Starting ratecalcs...
2024-10-21 22:49:29,076 - read - [DEBUG] - Night Start= Mon Oct 21 01:00:00 2024
2024-10-21 22:49:29,077 - read - [DEBUG] - Day Start= Mon Oct 21 06:00:00 2024
2024-10-21 22:49:29,080 - read - [DEBUG] - Setting Rate to Night
2024-10-21 22:49:29,082 - read - [DEBUG] - Battery rate calcs complete
2024-10-21 22:49:29,095 - read - [DEBUG] - Successfully processed data from: 192.168.1.101
2024-10-21 22:49:29,110 - read - [DEBUG] - processData result: Success processing data
2024-10-21 22:49:29,111 - read - [DEBUG] - Running pubFromPickle
2024-10-21 22:49:29,113 - read - [DEBUG] - Dealt with HY2343G025
2024-10-21 22:49:29,113 - read - [DEBUG] - Dealt with HY2343G144
2024-10-21 22:49:29,115 - read - [DEBUG] - Dealt with HY2343G257
2024-10-21 22:49:29,116 - read - [DEBUG] - Dealt with HY2343G259
2024-10-21 22:49:29,117 - read - [DEBUG] - Dealt with HY2343G302
2024-10-21 22:49:29,118 - read - [DEBUG] - Dealt with HY2343G446
2024-10-21 22:49:29,119 - read - [DEBUG] - Dealt with Battery_Stack_1

britkat1980 commented 1 week ago

It looks like it's running OK now? If you run latest prod version 3.0.4 do you get those same. Any issues with the values and/or control

LeeFarm11 commented 1 week ago

I didn't think it was running OK with those above messages, so I haven't tested further. Are you suggesting that each of the above messages are ok? Especially those repeated below -

1. 2024-10-21 22:47:33,432 - HA_Discovery - [ERROR] - Error connecting to MQTT Broker: (<class 'RuntimeError'>, RuntimeError('dictionary changed size during iteration'), <traceback object at 0x7f3f881fdd80>)

2. 2024-10-21 22:48:52,924 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490069.0. Min limit: -30000. Max limit: 30000

3. 2024-10-21 22:48:52,924 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429490069.0. Min limit: -30000. Max limit: 30000

4. 2024-10-21 22:48:52,929 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value 2024-10-21 22:48:52,929 - read - [DEBUG] - Data cleansed for: Today 2024-10-21 22:48:52,929 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value

5. 2024-10-21 22:52:59,074 - read - [INFO] - Phase_1_Current jumped too far in a single read: 2.7->10.1 so using previous value

6. 2024-10-21 22:56:29,413 - read - [INFO] - Inverter_Power_Out jumped too far in a single read: 429490069.0->2655.0 so using previous value 2024-10-21 22:56:29,413 - read - [INFO] - Load_Phase2_Power jumped too far in a single read: 0.0->6461.2 so using previous value 2024-10-21 22:56:29,413 - read - [INFO] - Load_Phase3_Power jumped too far in a single read: 23.6->6485.0 so using previous value 2024-10-21 22:56:29,414 - read - [INFO] - Export_Phase1_Power jumped too far in a single read: 6550.0->87.6 so using previous value 2024-10-21 22:56:29,415 - read - [INFO] - Export_Phase2_Power jumped too far in a single read: 6550.7->91.3 so using previous value 2024-10-21 22:56:29,415 - read - [INFO] - Export_Phase3_Power jumped too far in a single read: 6548.6->86.6 so using previous value 2024-10-21 22:56:29,416 - read - [INFO] - Grid_Phase1_Current jumped too far in a single read: 0.8->3.9 so using previous value 2024-10-21 22:56:29,416 - read - [INFO] - Grid_Phase2_Current jumped too far in a single read: 0.8->4.0 so using previous value 2024-10-21 22:56:29,417 - read - [INFO] - Grid_Phase3_Current jumped too far in a single read: 0.8->3.8 so using previous value

LeeFarm11 commented 3 days ago

Hi. Think I misread your last post. I have now installed Prod version 3.0.4. And now running for a couple of days.

I still have this every 15-20 seconds -

2024-11-01 12:11:17,407 - read - [DEBUG] - Power_Factor is Zero so using old value
2024-11-01 12:11:17,408 - read - [DEBUG] - Data cleansed for: TD2343G049
2024-11-01 12:11:17,408 - read - [DEBUG] - Frequency is Zero so using old value
2024-11-01 12:11:17,410 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value
2024-11-01 12:11:17,413 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value
2024-11-01 12:11:17,413 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value
2024-11-01 12:11:17,414 - read - [DEBUG] - Data cleansed for: Meter_ID1
2024-11-01 12:11:17,414 - read - [DEBUG] - Data cleansed for: Meter_Details
2024-11-01 12:11:17,415 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value
2024-11-01 12:11:17,415 - read - [DEBUG] - Data cleansed for: Today
2024-11-01 12:11:17,418 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value
2024-11-01 12:11:17,419 - read - [DEBUG] - Data cleansed for: Total
2024-11-01 12:11:17,419 - read - [DEBUG] - Data cleansed for: Energy
2024-11-01 12:11:17,420 - read - [DEBUG] - Data cleansed for: Timeslots
2024-11-01 12:11:17,421 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value
2024-11-01 12:11:17,421 - read - [DEBUG] - Data cleansed for: Control
2024-11-01 12:11:17,422 - read - [DEBUG] - Data cleansed for: Stats

I also have 414 'jumped too far in a single read' messages today like here -

2024-11-01 14:05:52,772 - read - [DEBUG] - Data cleansed for: Battery_Details
2024-11-01 14:05:52,773 - read - [DEBUG] - Battery_Charge_Power jumped too far in a single read: 0.5->11142.3 so using previous value
2024-11-01 14:05:52,774 - read - [DEBUG] - Returning new Battery_Discharge_Power data as the last read was smoothed
2024-11-01 14:05:52,774 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429485933.6. Min limit: -30000. Max limit: 30000
2024-11-01 14:05:52,774 - read - [DEBUG] - Returning new AC_Charge_Power data as the last read was smoothed
2024-11-01 14:05:52,775 - read - [DEBUG] - Returning new Grid_Apparent_Power data as the last read was smoothed
2024-11-01 14:05:52,775 - read - [DEBUG] - Returning new Meter_Import_Power data as the last read was smoothed
2024-11-01 14:05:52,776 - read - [DEBUG] - Returning new Load_Phase2_Power data as the last read was smoothed
2024-11-01 14:05:52,776 - read - [DEBUG] - Returning new Load_Phase3_Power data as the last read was smoothed
2024-11-01 14:05:52,776 - read - [DEBUG] - Export_Phase1_Power jumped too far in a single read: 22.6->6196.7 so using previous value
2024-11-01 14:05:52,777 - read - [DEBUG] - Export_Phase2_Power jumped too far in a single read: 25.4->6192.6 so using previous value
2024-11-01 14:05:52,777 - read - [DEBUG] - Export_Phase3_Power jumped too far in a single read: 21.6->6191.9 so using previous value
2024-11-01 14:05:52,778 - read - [DEBUG] - Returning new Grid_Phase1_Current data as the last read was smoothed
2024-11-01 14:05:52,778 - read - [DEBUG] - Returning new Grid_Phase2_Current data as the last read was smoothed
2024-11-01 14:05:52,778 - read - [DEBUG] - Returning new Grid_Phase3_Current data as the last read was smoothed
2024-11-01 14:05:52,779 - read - [DEBUG] - Data cleansed for: Power
2024-11-01 14:05:52,779 - read - [DEBUG] - Data cleansed for: Power
2024-11-01 14:05:52,779 - read - [DEBUG] - Power_Factor is Zero so using old value
2024-11-01 14:05:52,780 - read - [DEBUG] - Data cleansed for: TD2343G049
2024-11-01 14:05:52,780 - read - [DEBUG] - Phase_1_Current jumped too far in a single read: 7.3->20.2 so using previous value
2024-11-01 14:05:52,780 - read - [DEBUG] - Phase_2_Current jumped too far in a single read: 1.1->12.1 so using previous value
2024-11-01 14:05:52,781 - read - [DEBUG] - Phase_3_Current jumped too far in a single read: 3.2->10.3 so using previous value
2024-11-01 14:05:52,781 - read - [DEBUG] - Phase_1_Power jumped too far in a single read: 1722->6943 so using previous value
2024-11-01 14:05:52,781 - read - [DEBUG] - Phase_2_Power jumped too far in a single read: -267->5046 so using previous value
2024-11-01 14:05:52,782 - read - [DEBUG] - Phase_3_Power jumped too far in a single read: -783->4590 so using previous value
2024-11-01 14:05:52,782 - read - [DEBUG] - Frequency is Zero so using old value
2024-11-01 14:05:52,782 - read - [DEBUG] - Phase_1_Power_Factor is Zero so using old value
2024-11-01 14:05:52,782 - read - [DEBUG] - Phase_2_Power_Factor is Zero so using old value
2024-11-01 14:05:52,783 - read - [DEBUG] - Phase_3_Power_Factor is Zero so using old value
2024-11-01 14:05:52,783 - read - [DEBUG] - Data cleansed for: Meter_ID1
2024-11-01 14:05:52,783 - read - [DEBUG] - Data cleansed for: Meter_Details
2024-11-01 14:05:52,784 - read - [DEBUG] - Export2_Energy_Today_kWh is Zero so using old value
2024-11-01 14:05:52,784 - read - [DEBUG] - Data cleansed for: Today
2024-11-01 14:05:52,784 - read - [DEBUG] - Export2_Energy_Total_kWh is Zero so using old value
2024-11-01 14:05:52,785 - read - [DEBUG] - Data cleansed for: Total
2024-11-01 14:05:52,785 - read - [DEBUG] - Data cleansed for: Energy
2024-11-01 14:05:52,785 - read - [DEBUG] - Data cleansed for: Timeslots
2024-11-01 14:05:52,786 - read - [DEBUG] - Battery_Power_Cutoff is Zero so using old value
2024-11-01 14:05:52,787 - read - [DEBUG] - Data cleansed for: Control
2024-11-01 14:05:52,787 - read - [DEBUG] - Data cleansed for: Stats

And many outside of allowable bounds messages, especially for Inverter_Power_Out and PV_Voltage_String_1

25 x PV_Voltage_String_1 between 07:44 and 08:26 and between 15:09 and 15:49 today 2024-11-01 08:20:47,318 - read - [DEBUG] - PV_Voltage_String_1 is outside of allowable bounds so using old value. Out of bounds value is: 745.4. Min limit: 0. Max limit: 700

374 x Inverter_Power_Out all starting with 4294 like below today 2024-11-01 04:52:34,147 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429481472.0. Min limit: -30000. Max limit: 30000

LeeFarm11 commented 3 days ago

Also the first 56000 records in the log today, from 00:00:00 to 03:09:52 were like this -

2024-11-01 03:09:52,569 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)

followed by this -

2024-11-01 03:09:54,476 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:09:54,481 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:10:06,138 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:10:06,142 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:10:33,042 - read - [ERROR] - Error in inital detect/refresh: ('TimeoutError', 'read.py', 76)
2024-11-01 03:10:33,044 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:10:33,049 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:10:44,685 - read - [ERROR] - Error in inital detect/refresh: ('TimeoutError', 'read.py', 76)
2024-11-01 03:10:44,687 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:10:44,692 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:10:54,521 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:10:54,527 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:11:06,391 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:11:06,397 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:11:33,081 - read - [ERROR] - Error in inital detect/refresh: ('TimeoutError', 'read.py', 76)
2024-11-01 03:11:33,082 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:11:33,094 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:11:44,943 - read - [ERROR] - Error in inital detect/refresh: ('TimeoutError', 'read.py', 76)
2024-11-01 03:11:44,944 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:11:44,948 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:11:54,414 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:11:54,424 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:12:05,510 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:12:05,514 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:12:33,042 - read - [ERROR] - Error in inital detect/refresh: ('TimeoutError', 'read.py', 76)
2024-11-01 03:12:33,044 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:12:33,062 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:12:44,123 - read - [ERROR] - Error in inital detect/refresh: ('TimeoutError', 'read.py', 76)
2024-11-01 03:12:44,123 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:12:44,129 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:12:54,349 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:12:54,353 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:13:05,609 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:13:05,614 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:13:16,922 - read - [ERROR] - Error in inital detect/refresh: ('KeyError', 'read.py', 76)
2024-11-01 03:13:16,923 - read - [INFO] - Starting watch_plant loop...
2024-11-01 03:13:16,927 - read - [CRITICAL] - Detecting inverter characteristics...
2024-11-01 03:13:17,580 - read - [DEBUG] - Running full refresh
2024-11-01 03:13:17,580 - read - [DEBUG] - Running processData
2024-11-01 03:13:17,581 - read - [DEBUG] - Beginning parsing of Inverter data
2024-11-01 03:13:17,621 - read - [DEBUG] - Getting Battery Details
2024-11-01 03:13:17,624 - read - [DEBUG] - Building battery output: 
2024-11-01 03:13:17,625 - read - [DEBUG] - Battery HY2343G302 added
2024-11-01 03:13:17,626 - read - [DEBUG] - Building battery output: 
2024-11-01 03:13:17,626 - read - [DEBUG] - Battery HY2343G257 added
2024-11-01 03:13:17,627 - read - [DEBUG] - Building battery output: 
2024-11-01 03:13:17,628 - read - [DEBUG] - Battery HY2343G446 added
2024-11-01 03:13:17,628 - read - [DEBUG] - Building battery output: 
2024-11-01 03:13:17,629 - read - [DEBUG] - Battery HY2343G025 added
2024-11-01 03:13:17,629 - read - [DEBUG] - Building battery output: 
2024-11-01 03:13:17,630 - read - [DEBUG] - Battery HY2343G259 added
2024-11-01 03:13:17,630 - read - [DEBUG] - Building battery output: 
2024-11-01 03:13:17,631 - read - [DEBUG] - Battery HY2343G144 added
2024-11-01 03:13:17,632 - read - [DEBUG] - Getting mode control figures
2024-11-01 03:13:17,633 - read - [DEBUG] - Calculating Mode...
2024-11-01 03:13:17,633 - read - [DEBUG] - Mode is: Eco (Paused)
2024-11-01 03:13:17,634 - read - [DEBUG] - Force Export is not Running
2024-11-01 03:13:17,634 - read - [DEBUG] - Getting TimeSlot data
2024-11-01 03:13:17,635 - read - [DEBUG] - Getting TimeSlot data
2024-11-01 03:13:17,661 - read - [DEBUG] - cache len= 11
2024-11-01 03:13:17,661 - read - [DEBUG] - Running the data cleansing process
2024-11-01 03:13:17,671 - read - [DEBUG] - Stack_Charge_Energy_Today_kWh jumped too far in a single read: 0.1->2.4 so using previous value
2024-11-01 03:13:17,673 - read - [DEBUG] - Data cleansed for: HY2343G302
2024-11-01 03:13:17,674 - read - [DEBUG] - Data cleansed for: HY2343G257
2024-11-01 03:13:17,676 - read - [DEBUG] - Data cleansed for: HY2343G446
2024-11-01 03:13:17,677 - read - [DEBUG] - Data cleansed for: HY2343G025
2024-11-01 03:13:17,678 - read - [DEBUG] - Data cleansed for: HY2343G259
2024-11-01 03:13:17,679 - read - [DEBUG] - Data cleansed for: HY2343G144
2024-11-01 03:13:17,679 - read - [DEBUG] - Data cleansed for: Battery_Stack_1
2024-11-01 03:13:17,679 - read - [DEBUG] - Data cleansed for: Battery_Details
2024-11-01 03:13:17,680 - read - [DEBUG] - Inverter_Power_Out is outside of allowable bounds so using old value. Out of bounds value is: 429485733.6. Min limit: -30000. Max limit: 30000
2024-11-01 03:13:17,681 - read - [DEBUG] - SOC jumped too far in a single read: 10->15 so using previous value
2024-11-01 03:13:17,681 - read - [DEBUG] - Data cleansed for: Power

I have also had many thousands more of that mqtt message during the day.

LeeFarm11 commented 3 days ago

And discrepancy between the log file in File Editor and in Addons Log. The Addons Log appears to have more data, but cannot see the additional data if more than a few minutes ago. The evc data is missing from the File Editer Log. But the 'Running full refresh' message only shows in the File Editer Log, not in the Addons Log.

File Editor Log -

2024-11-01 18:38:48,591 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:48,793 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:48,997 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,199 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,403 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,610 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,815 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,021 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,225 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,426 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,629 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,830 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,031 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,233 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,439 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,640 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,844 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,045 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,252 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,459 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,661 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,862 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,063 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,264 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,346 - read - [DEBUG] - Running full refresh
2024-11-01 18:38:53,469 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,675 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,884 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,086 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,287 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,488 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,689 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,893 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,098 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,299 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,500 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,706 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,908 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,109 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,311 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,511 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,713 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,916 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,117 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,318 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,519 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,723 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,932 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,138 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,339 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,540 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,741 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,949 - mqtt - [DEBUG] - In wait loop (multi_MQTT_publish)

And in Addons Log for the same time frame.

2024-11-01 18:38:48,591 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:48,793 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:48,997 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,199 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,403 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,610 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:49,815 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,021 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,225 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,426 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,436 - EVC - evc         -  [DEBUG   ] - connected OK Returned code=Success
2024-11-01 18:38:50,437 - EVC - evc         -  [DEBUG   ] - Subscribing to GivEnergy/control/#
2024-11-01 18:38:50,629 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:50,830 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,012 - EVC - evc         -  [DEBUG   ] - Socket isn't yet open
2024-11-01 18:38:51,021 - EVC - evc         -  [DEBUG   ] - Socket is still open
2024-11-01 18:38:51,031 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,033 - EVC - evc         -  [DEBUG   ] - Dealt with Charger
2024-11-01 18:38:51,034 - EVC - evc         -  [DEBUG   ] - Publish all to MQTT
2024-11-01 18:38:51,035 - EVC - evc         -  [DEBUG   ] - Publishing: GivEnergy/11288853538258/Charger
2024-11-01 18:38:51,233 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,439 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,640 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:51,844 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,045 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,252 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,445 - EVC - evc         -  [DEBUG   ] - connected OK Returned code=Success
2024-11-01 18:38:52,446 - EVC - evc         -  [DEBUG   ] - Subscribing to GivEnergy/control/#
2024-11-01 18:38:52,459 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,661 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:52,862 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,063 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,264 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,469 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,675 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:53,884 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,086 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,287 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,461 - EVC - evc         -  [DEBUG   ] - connected OK Returned code=Success
2024-11-01 18:38:54,463 - EVC - evc         -  [DEBUG   ] - Subscribing to GivEnergy/control/#
2024-11-01 18:38:54,488 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,689 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:54,893 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,098 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,299 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,500 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,706 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:55,908 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,109 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,311 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,472 - EVC - evc         -  [DEBUG   ] - connected OK Returned code=Success
2024-11-01 18:38:56,473 - EVC - evc         -  [DEBUG   ] - Subscribing to GivEnergy/control/#
2024-11-01 18:38:56,511 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,713 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:56,916 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,117 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,318 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,519 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,723 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:57,932 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,138 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,339 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,488 - EVC - evc         -  [DEBUG   ] - connected OK Returned code=Success
2024-11-01 18:38:58,489 - EVC - evc         -  [DEBUG   ] - Subscribing to GivEnergy/control/#
2024-11-01 18:38:58,540 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,741 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)
2024-11-01 18:38:58,949 - GivTCP - mqtt        -  [DEBUG   ] - In wait loop (multi_MQTT_publish)

britkat1980 commented 3 days ago

Those debug messages aren't of concern, unless the rejected value is actually correct, but they don't seem to be. Does the data in the HA graphs seem sensible and stable? The repeated rebooting of self-run is the OK boy one that needs looking at.

LeeFarm11 commented 3 days ago

Log completely empty (apart from In wait loop) for over 3 hours is OK. Seriously ?

This graph?

LeeFarm11 commented 2 days ago

Looking into the graphs a bit more, this seems very weird. The GivTCP Power Load Phase 1 Power, GivTCP Power Load Phase 2 Power, GivTCP Power Load Phase 3 Power are showing really odd results. Doesn't seem to relate to my undestanding of Load. I include print from Giv Portal for comparison.

LeeFarm11 commented 6 hours ago

Enable Charge Schedule does not work. As previously discussed, on the Phase 3 Inverter it seems this setting does not exist, but it is needed for Predbat. I think it needs to switch both Force Charge Enable and Force AC Charge Enable. I tried to switch On Enable Charge Schedule while Force Charge Enable and Force AC Charge Enable were both Off, and also when they were both On. Log file from 19:53 to 20:06 is included.

GivTCPLog.txt

LeeFarm11 commented 6 hours ago

Adding @gcoan for info

gcoan commented 4 hours ago

Enable Charge Schedule does not work. As previously discussed, on the Phase 3 Inverter it seems this setting does not exist, but it is needed for Predbat

Good to see you're still plugging away at this !

If Predbat didn't require Enable Charge Schedule then do you think (a) 3 phase control via GivTCP is working and (b) Predbat could control it OK?

(and the same with enable discharge schedule) ?

As a test, in your apps.yaml comment out the REST lines so that Predbat is controlling GivTCP using the home assistant controls

Then where the configuration for the enable charge and discharge schedule is in apps.yaml:

scheduled_charge_enable:
    - switch.givtcp_{geserial}_enable_charge_schedule
  #  - switch.givtcp2_{geserial2}_enable_charge_schedule
  scheduled_discharge_enable:
    - switch.givtcp_{geserial}_enable_discharge_schedule
  #  - switch.givtcp2_{geserial2}_enable_discharge_schedule

Create a dummy helper switch in Home Assistant and put the name of it in place of the givtcp enable_charge_schedule control

And see what happens.

If this is the only problem with getting Predbat to work with GivTCP then we have a workaround for the 3 phase inverters and can look to make it more elegant afterwards (i.e. allowing Predbat to ignore this control for 3 phase inverters and getting Predbat to work in REST mode)

britkat1980 / giv_tcp

Development of 3 Phase Inverter Compatability #286