Hi Britkat
I've been losing my mind as to why I can no longer get GivTCP working in HA. I'm not sure if the issue is related to V2 or a new inverter as both events happened around the same time for me. Original inverter was replaced under warranty by GE after a month of perfect operation with GivTCP installed.
I've tried pretty much everything, deleting .pkl files, changing IP address from DCHP to static, reinstalling of the addon, new build of HA (running on VM), new VM host, restarting the inverter, syncing time etc etc.
I've searched here by can't seem to find anyone else with the same error which has always been the same since October when the new inverter was installed.
debug log file:
2023-01-06 19:02:10,364 - read - [INFO] - ----------------------------Starting----------------------------
2023-01-06 19:02:10,365 - read - [INFO] - Getting All Registers
2023-01-06 19:02:10,366 - read - [INFO] - setting lock file
2023-01-06 19:02:10,366 - read - [INFO] - Connecting to: 192.168.108.4
2023-01-06 19:02:10,443 - sync - [DEBUG] - Connection to Modbus server established. Socket ('192.168.108.137', 44207)
2023-01-06 19:02:10,445 - transaction - [DEBUG] - Current transaction state - IDLE
2023-01-06 19:02:10,449 - transaction - [DEBUG] - Running transaction 1
2023-01-06 19:02:10,450 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:10,690 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:10,692 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:10,693 - transaction - [DEBUG] - Adding transaction 1
2023-01-06 19:02:10,693 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:11,197 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:11,197 - transaction - [DEBUG] - Running transaction 2
2023-01-06 19:02:11,198 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:11,440 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:11,441 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:11,442 - transaction - [DEBUG] - Adding transaction 2
2023-01-06 19:02:11,442 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:11,943 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:11,943 - transaction - [DEBUG] - Running transaction 3
2023-01-06 19:02:11,943 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:12,190 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:12,190 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:12,190 - transaction - [DEBUG] - Adding transaction 3
2023-01-06 19:02:12,191 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:12,692 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:12,692 - transaction - [DEBUG] - Running transaction 4
2023-01-06 19:02:12,692 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:12,928 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:12,929 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:12,929 - transaction - [DEBUG] - Adding transaction 4
2023-01-06 19:02:12,930 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:13,432 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:13,433 - transaction - [DEBUG] - Running transaction 5
2023-01-06 19:02:13,433 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:13,680 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:13,680 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:13,680 - transaction - [DEBUG] - Adding transaction 5
2023-01-06 19:02:13,680 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:14,184 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:14,184 - transaction - [DEBUG] - Running transaction 6
2023-01-06 19:02:14,185 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:14,425 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:14,426 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:14,426 - transaction - [DEBUG] - Adding transaction 6
2023-01-06 19:02:14,426 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:14,929 - GivLUT - [CRITICAL] - Consecutive failure count= 5
2023-01-06 19:02:14,930 - read - [ERROR] - Error collecting registers: (<class 'ValueError'>, ValueError('hour must be in 0..23'), <traceback object at 0x7fabc135b4c0>)
2023-01-06 19:02:14,931 - read - [INFO] - Removing lock file due to read error
2023-01-06 19:02:15,243 - read - [INFO] - ----------------------------Starting----------------------------
2023-01-06 19:02:15,243 - read - [INFO] - Getting All Registers
2023-01-06 19:02:15,243 - read - [INFO] - setting lock file
2023-01-06 19:02:15,244 - read - [INFO] - Connecting to: 192.168.108.4
2023-01-06 19:02:15,250 - sync - [DEBUG] - Connection to Modbus server established. Socket ('192.168.108.137', 35399)
2023-01-06 19:02:15,252 - transaction - [DEBUG] - Current transaction state - IDLE
2023-01-06 19:02:15,254 - transaction - [DEBUG] - Running transaction 1
2023-01-06 19:02:15,255 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:15,487 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:15,488 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:15,489 - transaction - [DEBUG] - Adding transaction 1
2023-01-06 19:02:15,489 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:15,997 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:15,998 - transaction - [DEBUG] - Running transaction 2
2023-01-06 19:02:15,999 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:16,230 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:16,231 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:16,231 - transaction - [DEBUG] - Adding transaction 2
2023-01-06 19:02:16,231 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:16,734 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:16,734 - transaction - [DEBUG] - Running transaction 3
2023-01-06 19:02:16,735 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:16,974 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:16,974 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:16,975 - transaction - [DEBUG] - Adding transaction 3
2023-01-06 19:02:16,975 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:17,490 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:17,491 - transaction - [DEBUG] - Running transaction 4
2023-01-06 19:02:17,492 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:17,729 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:17,731 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:17,732 - transaction - [DEBUG] - Adding transaction 4
2023-01-06 19:02:17,733 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:18,236 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:18,237 - transaction - [DEBUG] - Running transaction 5
2023-01-06 19:02:18,238 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:18,474 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:18,475 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:18,475 - transaction - [DEBUG] - Adding transaction 5
2023-01-06 19:02:18,476 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:18,977 - transaction - [DEBUG] - Current transaction state - TRANSACTION_COMPLETE
2023-01-06 19:02:18,978 - transaction - [DEBUG] - Running transaction 6
2023-01-06 19:02:18,978 - sync - [DEBUG] - New Transaction state 'SENDING'
2023-01-06 19:02:19,222 - transaction - [DEBUG] - Changing transaction state from 'WAITING FOR REPLY' to 'PROCESSING REPLY'
2023-01-06 19:02:19,223 - payload - [DEBUG] - [b'\x00\x00', b'\x00\x00', b'\x00\x00', b'\x00\x8a']
2023-01-06 19:02:19,224 - transaction - [DEBUG] - Adding transaction 6
2023-01-06 19:02:19,225 - transaction - [DEBUG] - Changing transaction state from 'PROCESSING REPLY' to 'TRANSACTION_COMPLETE'
2023-01-06 19:02:19,734 - GivLUT - [CRITICAL] - Consecutive failure count= 6
2023-01-06 19:02:19,734 - read - [ERROR] - Error collecting registers: (<class 'ValueError'>, ValueError('hour must be in 0..23'), <traceback object at 0x7f2138533e40>)
2023-01-06 19:02:19,736 - read - [INFO] - Removing lock file due to read error
Log file:
`2023-01-08 10:12:29,433 - startup - [CRITICAL] - Config directory already exists
2023-01-08 10:12:29,437 - startup - [CRITICAL] - Running Redis
2023-01-08 10:12:29,440 - startup - [CRITICAL] - Running RQ Dashboard on port 9181
2023-01-08 10:12:29,440 - startup - [CRITICAL] - Setting up invertor: 1 of 1
2023-01-08 10:12:29,551 - startup - [CRITICAL] - Recreating settings.py for invertor 1
2023-01-08 10:12:29,557 - startup - [CRITICAL] - Running RQ worker to queue and process givernergy-modbus calls
2023-01-08 10:12:29,557 - startup - [CRITICAL] - Running Invertor read loop every 5s
2023-01-08 10:12:29,577 - startup - [CRITICAL] - Subscribing Mosquitto on port 1883
2023-01-08 10:12:29,597 - startup - [CRITICAL] - Starting Gunicorn on port 6345
2023-01-08 10:12:29,616 - startup - [CRITICAL] - Creating web dashboard config
2023-01-08 10:12:29,617 - startup - [CRITICAL] - Serving Web Dashboard from port 3042
2023-01-08 10:12:29,656 - startup - [CRITICAL] - Setting daily charge target forecast job to run at: 00:20
[2023-01-08 10:12:31 +0000] [15] [INFO] Starting gunicorn 20.1.0
[2023-01-08 10:12:31 +0000] [15] [INFO] Listening at: http://0.0.0.0:6345 (15)
[2023-01-08 10:12:31 +0000] [15] [INFO] Using worker: sync
[2023-01-08 10:12:31 +0000] [29] [INFO] Booting worker with pid: 29
[2023-01-08 10:12:31 +0000] [30] [INFO] Booting worker with pid: 30
[2023-01-08 10:12:31 +0000] [31] [INFO] Booting worker with pid: 31
RQ Dashboard version 0.6.0
- Running on 0.0.0.0:9181
- Serving Flask app 'rq_dashboard.cli'
- Debug mode: off
UPDATE The latest version of serve is 14.1.2.
INFO: Accepting connections at http://localhost:3042.
2023-01-08 10:12:37,086 - GivLUT - [CRITICAL] - Consecutive failure count= 6
2023-01-08 10:12:37,090 - read - [ERROR] - Error collecting registers: (<class 'ValueError'>, ValueError('hour must be in 0..23'), <traceback object at 0x7f658226ea80>)`
Any help would be much appreciated. I can't work out if it's me or the inverter?
Thanks
@AndyD