search

springfall2008 / batpred

Home battery prediction and charging automation for Home Assistant, supporting many inverter types
https://springfall2008.github.io/batpred/
116 stars 40 forks source link

Status: "changed to Warn: Inverter 0 write to pause_mode failed" #1490

Open LynchMicro opened 2 days ago

LynchMicro commented 2 days ago

Describe the bug I noticed this status for last few days, possibly following updating.

Expected behavior A clear and concise description of what you expected to happen.

Not have the error message in status. As far as I can tell predbat is still working.

Predbat version 8.4.9/1.2.1 xxxx

Environment details

Screenshots If applicable, add screenshots to help explain your problem. The most useful ones can be your battery chart, the Predbat HTML plan and your current settings in HA.

Log file Can you capture a log file from the time of the issue, debug mode is not normally required. If you are not keeping the full logs then please enable this in appdaemon.yaml (see the installation instruction in the Predbat docs area for details on how to do thi

2024-09-28 15:50:02.015085: Warn: Historical day 13 has 20 minutes of gap in the data, filled from 9.0 kWh to make new average 9.13 kWh (percent 99%) Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option 2024-09-28 15:51:06.355970: Warn: Inverter 0 Trying to write pause_mode to PauseDischarge didn't complete got Disabled 2024-09-28 15:51:06.388798: Info: record_status Warn: Inverter 0 write to pause_mode failed Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option 2024-09-28 15:53:27.575816: Warn: Inverter 0 Trying to write pause_mode to PauseDischarge didn't complete got Disabled 2024-09-28 15:53:27.608633: Info: record_status Warn: Inverter 0 write to pause_mode failed 2024-09-28 15:55:02.246448: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.46 kWh to make new average 14.0 kWh (percent 96%) 2024-09-28 15:55:02.247788: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 13.72 kWh to make new average 13.86 kWh (percent 99%) 2024-09-28 15:55:02.248733: Warn: Historical day 5 has 25 minutes of gap in the data, filled from 7.69 kWh to make new average 7.83 kWh (percent 98%) 2024-09-28 15:55:02.253143: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.38 kWh to make new average 14.89 kWh (percent 97%) 2024-09-28 15:55:02.265765: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.95 kWh to make new average 14.25 kWh (percent 98%) 2024-09-28 15:55:02.272913: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.57 kWh to make new average 15.74 kWh (percent 99%) 2024-09-28 15:55:02.275791: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 6.79 kWh to make new average 7.11 kWh (percent 95%) 2024-09-28 15:55:02.287555: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.51 kWh to make new average 7.87 kWh (percent 95%) 2024-09-28 15:55:02.300294: Warn: Historical day 12 has 55 minutes of gap in the data, filled from 17.0 kWh to make new average 17.68 kWh (percent 96%) 2024-09-28 15:55:02.317870: Warn: Historical day 13 has 25 minutes of gap in the data, filled from 8.96 kWh to make new average 9.12 kWh (percent 98%) Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option Trigger callback for select select_option 2024-09-28 15:56:06.668638: Warn: Inverter 0 Trying to write pause_mode to PauseDischarge didn't complete got Disabled 2024-09-28 15:56:06.703761: Info: record_status Warn: Inverter 0 write to pause_mode failed 2024-09-28 16:00:01.507755: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.53 kWh to make new average 14.06 kWh (percent 96%) 2024-09-28 16:00:01.509058: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 13.8 kWh to make new average 13.94 kWh (percent 99%) 2024-09-28 16:00:01.509993: Warn: Historical day 5 has 25 minutes of gap in the data, filled from 7.7 kWh to make new average 7.83 kWh (percent 98%) 2024-09-28 16:00:01.514556: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.36 kWh to make new average 14.88 kWh (percent 97%) 2024-09-28 16:00:01.527052: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.89 kWh to make new average 14.19 kWh (percent 98%) 2024-09-28 16:00:01.534738: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.65 kWh to make new average 15.81 kWh (percent 99%) 2024-09-28 16:00:01.538044: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 6.78 kWh to make new average 7.1 kWh (percent 95%) 2024-09-28 16:00:01.551793: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.51 kWh to make new average 7.87 kWh (percent 95%) 2024-09-28 16:00:01.566859: Warn: Historical day 12 has 50 minutes of gap in the data, filled from 17.01 kWh to make new average 17.62 kWh (percent 97%) 2024-09-28 16:00:01.582448: Warn: Historical day 13 has 30 minutes of gap in the data, filled from 8.92 kWh to make new average 9.11 kWh (percent 98%) 2024-09-28 16:01:35.404924: Info: record_status Idle 2024-09-28 16:05:02.007570: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.57 kWh to make new average 14.11 kWh (percent 96%) 2024-09-28 16:05:02.008919: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 13.87 kWh to make new average 14.01 kWh (percent 99%) 2024-09-28 16:05:02.009830: Warn: Historical day 5 has 25 minutes of gap in the data, filled from 7.7 kWh to make new average 7.83 kWh (percent 98%) 2024-09-28 16:05:02.014738: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.44 kWh to make new average 14.96 kWh (percent 97%) 2024-09-28 16:05:02.027151: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.81 kWh to make new average 14.1 kWh (percent 98%) 2024-09-28 16:05:02.034076: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.72 kWh to make new average 15.89 kWh (percent 99%) 2024-09-28 16:05:02.036883: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 6.79 kWh to make new average 7.11 kWh (percent 95%) 2024-09-28 16:05:02.048713: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.51 kWh to make new average 7.86 kWh (percent 95%) 2024-09-28 16:05:02.061316: Warn: Historical day 12 has 50 minutes of gap in the data, filled from 16.99 kWh to make new average 17.6 kWh (percent 97%) 2024-09-28 16:05:02.075661: Warn: Historical day 13 has 30 minutes of gap in the data, filled from 8.91 kWh to make new average 9.1 kWh (percent 98%) 2024-09-28 16:05:06.416521: Info: record_status Idle 2024-09-28 16:10:02.335154: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.64 kWh to make new average 14.18 kWh (percent 96%) 2024-09-28 16:10:02.336540: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 13.94 kWh to make new average 14.08 kWh (percent 99%) 2024-09-28 16:10:02.337499: Warn: Historical day 5 has 25 minutes of gap in the data, filled from 7.7 kWh to make new average 7.83 kWh (percent 98%) 2024-09-28 16:10:02.342390: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.63 kWh to make new average 15.15 kWh (percent 97%) 2024-09-28 16:10:02.354687: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.62 kWh to make new average 13.91 kWh (percent 98%) 2024-09-28 16:10:02.361562: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.86 kWh to make new average 16.03 kWh (percent 99%) 2024-09-28 16:10:02.364442: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 6.84 kWh to make new average 7.17 kWh (percent 95%) 2024-09-28 16:10:02.376184: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.5 kWh to make new average 7.86 kWh (percent 95%) 2024-09-28 16:10:02.388688: Warn: Historical day 12 has 50 minutes of gap in the data, filled from 16.96 kWh to make new average 17.57 kWh (percent 97%) 2024-09-28 16:10:02.403131: Warn: Historical day 13 has 30 minutes of gap in the data, filled from 8.92 kWh to make new average 9.11 kWh (percent 98%) 2024-09-28 16:11:19.703102: Info: record_status Idle 2024-09-28 16:15:02.294587: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.71 kWh to make new average 14.26 kWh (percent 96%) 2024-09-28 16:15:02.295982: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 14.0 kWh to make new average 14.15 kWh (percent 99%) 2024-09-28 16:15:02.296922: Warn: Historical day 5 has 25 minutes of gap in the data, filled from 7.7 kWh to make new average 7.84 kWh (percent 98%) 2024-09-28 16:15:02.301392: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.75 kWh to make new average 15.28 kWh (percent 97%) 2024-09-28 16:15:02.313464: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.51 kWh to make new average 13.8 kWh (percent 98%) 2024-09-28 16:15:02.320392: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.88 kWh to make new average 16.05 kWh (percent 99%) 2024-09-28 16:15:02.323226: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 6.94 kWh to make new average 7.27 kWh (percent 95%) 2024-09-28 16:15:02.335295: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.5 kWh to make new average 7.85 kWh (percent 95%) 2024-09-28 16:15:02.348389: Warn: Historical day 12 has 50 minutes of gap in the data, filled from 16.96 kWh to make new average 17.57 kWh (percent 97%) 2024-09-28 16:15:02.362593: Warn: Historical day 13 has 30 minutes of gap in the data, filled from 8.9 kWh to make new average 9.09 kWh (percent 98%) 2024-09-28 16:15:06.758519: Info: record_status Idle 2024-09-28 16:20:01.472663: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.77 kWh to make new average 14.32 kWh (percent 96%) 2024-09-28 16:20:01.474014: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 14.07 kWh to make new average 14.22 kWh (percent 99%) 2024-09-28 16:20:01.474943: Warn: Historical day 5 has 25 minutes of gap in the data, filled from 7.7 kWh to make new average 7.83 kWh (percent 98%) 2024-09-28 16:20:01.479746: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.78 kWh to make new average 15.32 kWh (percent 97%) 2024-09-28 16:20:01.492898: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.54 kWh to make new average 13.82 kWh (percent 98%) 2024-09-28 16:20:01.500541: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.78 kWh to make new average 15.95 kWh (percent 99%) 2024-09-28 16:20:01.503405: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 7.08 kWh to make new average 7.42 kWh (percent 95%) 2024-09-28 16:20:01.515108: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.49 kWh to make new average 7.85 kWh (percent 95%) 2024-09-28 16:20:01.529547: Warn: Historical day 12 has 50 minutes of gap in the data, filled from 16.97 kWh to make new average 17.58 kWh (percent 97%) 2024-09-28 16:20:01.544677: Warn: Historical day 13 has 30 minutes of gap in the data, filled from 8.85 kWh to make new average 9.04 kWh (percent 98%) 2024-09-28 16:21:20.330620: Info: record_status Idle 2024-09-28 16:25:02.044466: Warn: Historical day 2 has 55 minutes of gap in the data, filled from 13.83 kWh to make new average 14.38 kWh (percent 96%) 2024-09-28 16:25:02.045776: Warn: Historical day 3 has 15 minutes of gap in the data, filled from 14.14 kWh to make new average 14.29 kWh (percent 99%) 2024-09-28 16:25:02.046713: Warn: Hist Screenshot_20240928-163115 Screenshot_20240928-163101 orical day 5 has 25 minutes of gap in the data, filled from 7.7 kWh to make new average 7.83 kWh (percent 98%) 2024-09-28 16:25:02.051159: Warn: Historical day 7 has 50 minutes of gap in the data, filled from 14.74 kWh to make new average 15.27 kWh (percent 97%) 2024-09-28 16:25:02.064160: Warn: Historical day 8 has 30 minutes of gap in the data, filled from 13.66 kWh to make new average 13.95 kWh (percent 98%) 2024-09-28 16:25:02.071645: Warn: Historical day 9 has 15 minutes of gap in the data, filled from 15.69 kWh to make new average 15.86 kWh (percent 99%) 2024-09-28 16:25:02.074740: Warn: Historical day 10 has 65 minutes of gap in the data, filled from 7.2 kWh to make new average 7.54 kWh (percent 95%) 2024-09-28 16:25:02.086421: Warn: Historical day 11 has 65 minutes of gap in the data, filled from 7.49 kWh to make new average 7.84 kWh (percent 95%) 2024-09-28 16:25:02.099475: Warn: Historical day 12 has 50 minutes of gap in the data, filled from 16.99 kWh to make new average 17.6 kWh (percent 97%) 2024-09-28 16:25:02.113610: Warn: Historical day 13 has 30 minutes of gap in the data, filled from 8.81 kWh to make new average 9.0 kWh (percent 98%) 2024-09-28 16:25:06.491306: Info: record_status Idle

gcoan commented 2 days ago

This status indicates that Predbat did not get the correct response from the inverter when it sent a command to GivTCP.

either possibly an underlying network problem to your inverter (e.g. poor wifi), or that the inverter for some reason is rejecting the command.

Have a look at the givtcp log file and see if there are other error messages in there that could indicate a network problem

If that looks OK, then try resetting the inverter to defaults in the Givenergy portal as that may clear the inverter from rejecting the command.

I get these messages very occasionally, they are only a warning, and predbat will retry 5 minutes later when it next runs so as long as they are not happening all the time then it can probably be safely ignored

LynchMicro commented 2 days ago

Thanks. I'll look at that and update. Inverter is on ethernet so shouldn't be network issues unless it has switched back to WiFi somehow. We had a power cut as electric network replaced a cable on our road so possible something got reset.

LynchMicro commented 1 day ago

The inverter is still on ethernet and no obvious connection issues. Can't see any logs in GivTCP but maybe they get wiped after a restart?

I tried resetting the inverter to defaults.

Seems odd that the only command that doesn't get through to the inverter is the write to pause_mode.

image

If I'm the only one having the issue it sounds like it's a local issue rather than a bug so feel free to close and I'll keep trying to work out what's wrong.