search

evcc-io / evcc

Sonne tanken ☀️🚘
https://evcc.io
MIT License
3.58k stars 661 forks source link

vehicleOdometer not updated anymore (single vehicle) #3470

Closed e-frog closed 2 years ago

e-frog commented 2 years ago

Describe the bug

Since evcc 0.92 vehicleOdometer is not updated anymore. It works when restarting evcc but doesn't update when connecting the car and start charging. This seems to be related to the changes in #3309.

Steps to reproduce

see above

Configuration details

vehicles:
- type: template
  template: vw
  title: e-Golf
  user: <username>
  password: <pw>
  vin: WVWZxxxxxxxxxxx
  capacity: 31.5
  phases: 2
  mode: pv
  minSoC: 0
  targetSoC: 100
  minCurrent: 6
  maxCurrent: 16
  identifiers:
  - 
  name: ev3

Log details

nothing in the logs as DEBUG code was removed in #3309 :-)

What type of operating system are you running?

Linux

Version

Tested in 0.92 and 0.93

andig commented 2 years ago

@e-frog it should still be visible from the trace log that the odometer API is being called when the vehicle is identified. Could we please check that?

e-frog commented 2 years ago

at startup

[lp-1  ] INFO 2022/05/22 21:44:34 vehicle updated: unknown -> e-Golf
[cache ] TRACE 2022/05/22 21:44:34 lp-1/hasVehicle: true
[cache ] TRACE 2022/05/22 21:44:34 lp-1/mode: pv
[cache ] TRACE 2022/05/22 21:44:34 lp-1/targetSoC: 100
[cache ] TRACE 2022/05/22 21:44:34 lp-1/minSoC: 0
[cache ] TRACE 2022/05/22 21:44:34 lp-1/vehiclePresent: true
[influx] TRACE 2022/05/22 21:44:34 write targetSoC=100 (map[loadpoint:Garage vehicle:])
[influx] TRACE 2022/05/22 21:44:34 write minSoC=0 (map[loadpoint:Garage vehicle:])
[cache ] TRACE 2022/05/22 21:44:34 lp-1/vehicleTitle: e-Golf
[influx] TRACE 2022/05/22 21:44:34 write vehicleCapacity=31 (map[loadpoint:Garage vehicle:e-Golf])
[vw    ] TRACE 2022/05/22 21:44:34 GET https://mal-1a.prd.ece.vwg-connect.com/api/bs/vsr/v1/vehicles/***/status
[cache ] TRACE 2022/05/22 21:44:34 lp-1/vehicleCapacity: 31
[vw    ] TRACE 2022/05/22 21:44:34 {"StoredVehicleDataResponse":{"vin":"***","vehicleData":{"data": [{"id":"0x0101010001","field": [{"id":"0x0101010001","tsCarSentUtc":"2022-05-22T16:14:50Z","tsCarSent":"2000-01-01T00:00:00","tsCarCaptured":"2000-01-01T00:00:00","tsTssReceivedUtc":"2022-05-22T16:14:50Z","milCarCaptured":0,"milCarSent":0,"value":"echo"}]},{"id":"0x0101010002","field": [{"id":"0x0101010002","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"16990"}]},{"id":"0x0203FFFFFF","field": [{"id":"0x0203010001","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":null},{"id":"0x0203010002","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":null},{"id":"0x0203010003","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":"-13100","unit":"km","textId":"interval.inspection.distance"},{"id":"0x0203010004","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":"-49","textId":"interval.inspection.time"}]},{"id":"0x0204040001","field": [{"id":"0x0204040001","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":"0.0","unit":"l"}]},{"id":"0x0204040003","field": [{"id":"0x0204040003","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":"0.0","unit":"%","textId":"oil.level"}]},{"id":"0x0204040004","field": [{"id":"0x0204040004","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":"0","textId":"oilDisplay.0"}]},{"id":"0x02040C0001","field": [{"id":"0x02040C0001","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T00:32:09","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16947,"milCarSent":16990,"value":null,"textId":"not_supported"}]},{"id":"0x030101FFFF","field": [{"id":"0x0301010001","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"2","textId":"status_parking_light_off"}]},{"id":"0x030102FFFF","field": [{"id":"0x0301020001","tsCarSentUtc":"2022-05-22T16:14:50Z","tsCarSent":"2000-01-01T00:00:00","tsCarCaptured":"2000-01-01T00:00:00","tsTssReceivedUtc":"2022-05-22T16:14:50Z","milCarCaptured":0,"milCarSent":0,"value":"2985","unit":"dK"}]},{"id":"0x030103FFFF","field": [{"id":"0x0301030001","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"1","textId":"parking_brake_active"},{"id":"0x0301030002","tsCarSentUtc":"2022-05-22T16:14:41Z","tsCarSent":"2000-01-01T00:00:00","tsCarCaptured":"2000-01-01T00:00:00","tsTssReceivedUtc":"2022-05-22T16:14:53Z","milCarCaptured":0,"milCarSent":0,"value":"90","unit":"%","textId":"soc_ok"},{"id":"0x0301030003","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"textId":"bem_error"},{"id":"0x0301030004","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"0","unit":"km/h","textId":"speed_ok"},{"id":"0x0301030005","tsCarSentUtc":"2022-05-22T16:14:41Z","tsCarSent":"2000-01-01T00:00:00","tsCarCaptured":"2000-01-01T00:00:00","tsTssReceivedUtc":"2022-05-22T16:14:53Z","milCarCaptured":0,"milCarSent":0,"value":"248","unit":"km","textId":"range_ok"},{"id":"0x0301030006","tsCarSentUtc":"2022-05-22T16:14:41Z","tsCarSent":"2000-01-01T00:00:00","tsCarCaptured":"2000-01-01T00:00:00","tsTssReceivedUtc":"2022-05-22T16:14:53Z","milCarCaptured":0,"milCarSent":0,"value":"248","unit":"km","textId":"range_ok"},{"id":"0x0301030007","tsCarSentUtc":"2022-05-22T16:14:41Z","tsCarSent":"2000-01-01T00:00:00","tsCarCaptured":"2000-01-01T00:00:00","tsTssReceivedUtc":"2022-05-22T16:14:53Z","milCarCaptured":0,"milCarSent":0,"value":"3","textId":"engine_type_electric"},{"id":"0x0301030008","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"textId":"range_error"},{"id":"0x0301030009","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"0","textId":"engine_type_unsupported"},{"id":"0x030103000A","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"textId":"fuel_level_error"},{"id":"0x030103000B","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"0","textId":"fuel_level_measured"},{"id":"0x030103000D","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"textId":"gas_level_error"}]},{"id":"0x030104FFFF","field": [{"id":"0x0301040001","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_unlocked"},{"id":"0x0301040002","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_closed"},{"id":"0x0301040003","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_unsafe"},{"id":"0x0301040004","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_unlocked"},{"id":"0x0301040005","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_closed"},{"id":"0x0301040006","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_unsafe"},{"id":"0x0301040007","tsCarSentUtc":"2022-05-22T14:30:20Z","tsCarSent":"2022-05-22T14:30:10","tsCarCaptured":"2022-05-22T14:30:10","tsTssReceivedUtc":"2022-05-22T14:30:25Z","milCarCaptured":16990,"milCarSent":16990,"value":"3","textId":"door_unlocked"}
[lp-1  ] DEBUG 2022/05/22 21:44:34 set charge mode: pv
[lp-1  ] DEBUG 2022/05/22 21:44:34 set min current: 6
[lp-1  ] DEBUG 2022/05/22 21:44:34 set max current: 16
[influx] TRACE 2022/05/22 21:44:34 write vehicleOdometer=16990 (map[loadpoint:Garage vehicle:e-Golf])
[lp-1  ] DEBUG 2022/05/22 21:44:34 set min soc: 0
[lp-1  ] DEBUG 2022/05/22 21:44:34 set target soc: 100
[cache ] TRACE 2022/05/22 21:44:34 lp-1/vehicleOdometer: 16990
[influx] TRACE 2022/05/22 21:44:34 write vehicleSoC=0 (map[loadpoint:Garage vehicle:e-Golf])

Connecting/Disconnecting cycle:

[lp-1  ] INFO 2022/05/22 21:45:25 car connected
[lp-1  ] DEBUG 2022/05/22 21:45:25 vehicle api refresh
[lp-1  ] DEBUG 2022/05/22 21:45:25 pv timer elapse
[cache ] TRACE 2022/05/22 21:45:25 lp-1/chargedEnergy: 0
[cache ] TRACE 2022/05/22 21:45:25 lp-1/connectedDuration: 0s
[cache ] TRACE 2022/05/22 21:45:25 lp-1/pvAction: inactive
[cache ] TRACE 2022/05/22 21:45:25 lp-1/pvRemaining: 0s
[lp-1  ] DEBUG 2022/05/22 21:45:25 pv timer inactive
[influx] TRACE 2022/05/22 21:45:25 write chargeCurrent=0 (map[loadpoint:Garage vehicle:e-Golf])
[cache ] TRACE 2022/05/22 21:45:25 lp-1/chargeCurrent: 0
[cache ] TRACE 2022/05/22 21:45:25 lp-1/connected: true
[go-e  ] TRACE 2022/05/22 21:45:25 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:25 lp-1/charging: false
[cache ] TRACE 2022/05/22 21:45:25 lp-1/enabled: false
[go-e  ] TRACE 2022/05/22 21:45:25 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[vw    ] TRACE 2022/05/22 21:45:25 GET https://msg.volkswagen.de/fs-car/bs/batterycharge/v1/VW/DE/vehicles/***/charger
[vw    ] TRACE 2022/05/22 21:45:25 {"charger":{"settings":{"maxChargeCurrent":{"content":32,"timestamp":"2022-05-22T16:14:41Z"},"chargeModeSelection":{"modificationState":{"content":"canNotBeModified","timestamp":"2022-05-22T16:14:41Z"},"modificationReason":{"content":"noReason","timestamp":"2022-05-22T16:14:41Z"},"value":{"content":"timerBasedCharging","timestamp":"2022-05-22T16:14:41Z"}}},"status":{"chargingStatusData":{"chargingMode":{"content":"off","timestamp":"2022-05-22T16:14:53Z"},"chargingStateErrorCode":{"content":0,"timestamp":"2022-05-22T16:14:53Z"},"chargingReason":{"content":"invalid","timestamp":"2022-05-22T16:14:53Z"},"externalPowerSupplyState":{"content":"unavailable","timestamp":"2022-05-22T16:14:53Z"},"energyFlow":{"content":"off","timestamp":"2022-05-22T16:14:53Z"},"chargingState":{"content":"off","timestamp":"2022-05-22T16:14:53Z"}},"cruisingRangeStatusData":{"engineTypeFirstEngine":{"content":"typeIsElectric","timestamp":"2022-05-22T16:14:53Z"},"primaryEngineRange":{"content":248,"timestamp":"2022-05-22T16:14:53Z"},"hybridRange":{"content":248,"timestamp":"2022-05-22T16:14:53Z"},"engineTypeSecondEngine":{"content":"unsupported","timestamp":"2022-05-22T14:30:25Z"}},"ledStatusData":{"ledColor":{"content":"none","timestamp":"2022-05-22T16:14:53Z"},"ledState":{"content":"off","timestamp":"2022-05-22T16:14:53Z"}},"batteryStatusData":{"stateOfCharge":{"content":90,"timestamp":"2022-05-22T16:14:53Z"},"remainingChargingTime":{"content":-1,"timestamp":"2022-05-22T16:14:53Z"},"remainingChargingTimeTargetSOC":{"content":"minSOC","timestamp":"2022-05-22T16:14:53Z"}},"plugStatusData":{"plugState":{"content":"disconnected","timestamp":"2022-05-22T16:14:53Z"},"lockState":{"content":"unlocked","timestamp":"2022-05-22T16:14:53Z"}}}}}
[go-e  ] TRACE 2022/05/22 21:45:25 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[go-e  ] TRACE 2022/05/22 21:45:25 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:25 vehicle status: A
[lp-1  ] DEBUG 2022/05/22 21:45:25 vehicle soc: 90%
[lp-1  ] DEBUG 2022/05/22 21:45:25 vehicle range: 248km
[go-e  ] TRACE 2022/05/22 21:45:25 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:25 lp-1/vehicleSoC: 90
[cache ] TRACE 2022/05/22 21:45:25 lp-1/vehicleRange: 248
[go-e  ] TRACE 2022/05/22 21:45:25 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:25 pv charge current: 0A = 0A + -1.29A (591W @ 2p)
[vw    ] TRACE 2022/05/22 21:45:25 GET https://msg.volkswagen.de/fs-car/bs/climatisation/v1/VW/DE/vehicles/***/climater
[vw    ] TRACE 2022/05/22 21:45:25 {"climater":{"settings":{"targetTemperature":{"timestamp":"2022-05-22T16:14:38Z","content":2941},"climatisationWithoutHVpower":{"timestamp":"2022-05-22T16:14:38Z","content":false},"heaterSource":{"timestamp":"2022-05-22T16:14:38Z","content":"electric"}},"status":{"climatisationStatusData":{"climatisationState":{"timestamp":"2022-05-22T16:14:46Z","content":"off"},"climatisationStateErrorCode":{"timestamp":"2022-05-22T16:14:46Z","content":0},"remainingClimatisationTime":{"timestamp":"2022-05-22T16:14:46Z","content":10},"climatisationReason":{"timestamp":"2022-05-22T16:14:46Z","content":"immediate"}},"windowHeatingStatusData":{"windowHeatingStateFront":{"timestamp":"2021-04-21T14:59:54Z","content":"off"},"windowHeatingStateRear":{"timestamp":"2021-04-21T14:59:54Z","content":"off"},"windowHeatingErrorCode":{"timestamp":"2021-04-21T14:59:54Z","content":0}},"temperatureStatusData":{"outdoorTemperature":{"timestamp":"2022-05-22T16:14:50Z","content":2985}},"vehicleParkingClockStatusData":{"vehicleParkingClock":{"timestamp":"2022-05-22T14:29:58Z","content":"2022-05-22T14:29:45Z"}}}}}
[lp-1  ] DEBUG 2022/05/22 21:45:25 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[lp-1  ] DEBUG 2022/05/22 21:45:25 pv enable timer reset
[lp-1  ] DEBUG 2022/05/22 21:45:25 pv timer inactive
[lp-1  ] DEBUG 2022/05/22 21:45:25 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[cache ] TRACE 2022/05/22 21:45:25 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:25 lp-1/pvAction: inactive
[cache ] TRACE 2022/05/22 21:45:25 lp-1/pvRemaining: 0s
[cache ] TRACE 2022/05/22 21:45:25 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:25 homePower: 591.31
[influx] TRACE 2022/05/22 21:45:25 write homePower=591.31 (map[])
[modbus] TRACE 2022/05/22 21:45:30 modbus: closing connection due to idle timeout: 5.000214026s
[site  ] DEBUG 2022/05/22 21:45:34 ----
[modbus] TRACE 2022/05/22 21:45:34 modbus: send 00 1a 00 00 00 06 00 03 31 68 00 02
[modbus] TRACE 2022/05/22 21:45:34 modbus: recv 00 1a 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:45:34 charge power: 0W
[http  ] TRACE 2022/05/22 21:45:34 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[cache ] TRACE 2022/05/22 21:45:34 lp-1/chargePower: 0
[influx] TRACE 2022/05/22 21:45:34 write chargePower=0 (map[loadpoint:Garage vehicle:e-Golf])
[http  ] TRACE 2022/05/22 21:45:34 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 594.55999999999995,
            "P_Load" : -594.55999999999995,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:45:34+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:45:34 pv power: 0W
[http  ] TRACE 2022/05/22 21:45:34 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[cache ] TRACE 2022/05/22 21:45:34 pvPower: 0
[influx] TRACE 2022/05/22 21:45:34 write pvPower=0 (map[])
[http  ] TRACE 2022/05/22 21:45:34 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 594.55999999999995,
            "P_Load" : -594.55999999999995,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:45:34+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:45:34 grid power: 595W
[site  ] DEBUG 2022/05/22 21:45:34 site power: 595W
[modbus] TRACE 2022/05/22 21:45:34 modbus: send 00 1b 00 00 00 06 00 03 31 54 00 02
[cache ] TRACE 2022/05/22 21:45:34 gridPower: 594.56
[cache ] TRACE 2022/05/22 21:45:34 lp-1/mode: pv
[influx] TRACE 2022/05/22 21:45:34 write gridPower=594.56 (map[])
[modbus] TRACE 2022/05/22 21:45:34 modbus: recv 00 1b 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:45:34 modbus: send 00 1c 00 00 00 06 00 03 31 56 00 02
[modbus] TRACE 2022/05/22 21:45:34 modbus: recv 00 1c 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:45:34 modbus: send 00 1d 00 00 00 06 00 03 31 58 00 02
[modbus] TRACE 2022/05/22 21:45:34 modbus: recv 00 1d 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:45:34 charge currents: [0 0 0]A
[modbus] TRACE 2022/05/22 21:45:34 modbus: send 00 1e 00 00 00 06 00 03 31 66 00 02
[cache ] TRACE 2022/05/22 21:45:34 lp-1/chargeCurrents: [0 0 0]
[cache ] TRACE 2022/05/22 21:45:34 lp-1/chargeCurrent: 0
[influx] TRACE 2022/05/22 21:45:34 write chargeCurrents=[0 0 0] (map[loadpoint:Garage vehicle:e-Golf])
[influx] TRACE 2022/05/22 21:45:34 write chargeCurrent=0 (map[loadpoint:Garage vehicle:e-Golf])
[modbus] TRACE 2022/05/22 21:45:34 modbus: recv 00 1e 00 00 00 07 00 03 04 16 a3 46 a3
[go-e  ] TRACE 2022/05/22 21:45:34 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:34 lp-1/chargedEnergy: 0
[cache ] TRACE 2022/05/22 21:45:34 lp-1/chargeDuration: 10s
[go-e  ] TRACE 2022/05/22 21:45:35 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[232,232,232,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:35 charger status: B
[cache ] TRACE 2022/05/22 21:45:35 lp-1/connected: true
[cache ] TRACE 2022/05/22 21:45:35 lp-1/charging: false
[cache ] TRACE 2022/05/22 21:45:35 lp-1/enabled: false
[go-e  ] TRACE 2022/05/22 21:45:35 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[go-e  ] TRACE 2022/05/22 21:45:35 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[232,232,232,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:35 next soc poll remaining time: 1h59m50s
[go-e  ] TRACE 2022/05/22 21:45:35 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[go-e  ] TRACE 2022/05/22 21:45:35 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,232,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:35 pv charge current: 0A = 0A + -1.29A (595W @ 2p)
[lp-1  ] DEBUG 2022/05/22 21:45:35 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[lp-1  ] DEBUG 2022/05/22 21:45:35 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[cache ] TRACE 2022/05/22 21:45:35 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:35 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:35 homePower: 594.56
[influx] TRACE 2022/05/22 21:45:35 write homePower=594.56 (map[])
[modbus] TRACE 2022/05/22 21:45:39 modbus: closing connection due to idle timeout: 5.000684915s
[site  ] DEBUG 2022/05/22 21:45:44 ----
[modbus] TRACE 2022/05/22 21:45:44 modbus: send 00 1f 00 00 00 06 00 03 31 68 00 02
[modbus] TRACE 2022/05/22 21:45:44 modbus: recv 00 1f 00 00 00 07 00 03 04 1e 6e 40 00
[lp-1  ] DEBUG 2022/05/22 21:45:44 charge power: 2W
[http  ] TRACE 2022/05/22 21:45:44 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[cache ] TRACE 2022/05/22 21:45:44 lp-1/chargePower: 2.001857280731201
[influx] TRACE 2022/05/22 21:45:44 write chargePower=2.001857280731201 (map[loadpoint:Garage vehicle:e-Golf])
[http  ] TRACE 2022/05/22 21:45:44 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 597.03999999999996,
            "P_Load" : -597.03999999999996,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:45:44+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:45:44 pv power: 0W
[influx] TRACE 2022/05/22 21:45:44 write pvPower=0 (map[])
[cache ] TRACE 2022/05/22 21:45:44 pvPower: 0
[http  ] TRACE 2022/05/22 21:45:44 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[http  ] TRACE 2022/05/22 21:45:44 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 597.03999999999996,
            "P_Load" : -597.03999999999996,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:45:44+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:45:44 grid power: 597W
[site  ] DEBUG 2022/05/22 21:45:44 site power: 597W
[modbus] TRACE 2022/05/22 21:45:44 modbus: send 00 20 00 00 00 06 00 03 31 54 00 02
[cache ] TRACE 2022/05/22 21:45:44 gridPower: 597.04
[cache ] TRACE 2022/05/22 21:45:44 lp-1/mode: pv
[influx] TRACE 2022/05/22 21:45:44 write gridPower=597.04 (map[])
[modbus] TRACE 2022/05/22 21:45:44 modbus: recv 00 20 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:45:44 modbus: send 00 21 00 00 00 06 00 03 31 56 00 02
[modbus] TRACE 2022/05/22 21:45:44 modbus: recv 00 21 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:45:44 modbus: send 00 22 00 00 00 06 00 03 31 58 00 02
[modbus] TRACE 2022/05/22 21:45:44 modbus: recv 00 22 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:45:44 charge currents: [0 0 0]A
[modbus] TRACE 2022/05/22 21:45:44 modbus: send 00 23 00 00 00 06 00 03 31 66 00 02
[cache ] TRACE 2022/05/22 21:45:44 lp-1/chargeCurrents: [0 0 0]
[cache ] TRACE 2022/05/22 21:45:44 lp-1/chargeCurrent: 0
[influx] TRACE 2022/05/22 21:45:44 write chargeCurrents=[0 0 0] (map[loadpoint:Garage vehicle:e-Golf])
[influx] TRACE 2022/05/22 21:45:44 write chargeCurrent=0 (map[loadpoint:Garage vehicle:e-Golf])
[modbus] TRACE 2022/05/22 21:45:44 modbus: recv 00 23 00 00 00 07 00 03 04 16 a3 46 a3
[go-e  ] TRACE 2022/05/22 21:45:44 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:44 lp-1/chargedEnergy: 0
[cache ] TRACE 2022/05/22 21:45:44 lp-1/chargeDuration: 20s
[go-e  ] TRACE 2022/05/22 21:45:45 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:45 charger status: B
[cache ] TRACE 2022/05/22 21:45:45 lp-1/connected: true
[cache ] TRACE 2022/05/22 21:45:45 lp-1/charging: false
[go-e  ] TRACE 2022/05/22 21:45:45 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:45 lp-1/enabled: false
[go-e  ] TRACE 2022/05/22 21:45:45 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:45 next soc poll remaining time: 1h59m40s
[go-e  ] TRACE 2022/05/22 21:45:45 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[go-e  ] TRACE 2022/05/22 21:45:45 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:45 pv charge current: 0A = 0A + -1.3A (597W @ 2p)
[lp-1  ] DEBUG 2022/05/22 21:45:45 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[lp-1  ] DEBUG 2022/05/22 21:45:45 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[cache ] TRACE 2022/05/22 21:45:45 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:45 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:45 homePower: 595.0381427192688
[cache ] TRACE 2022/05/22 21:45:45 savingsTotalCharged: 1.950372661833812e-05
[cache ] TRACE 2022/05/22 21:45:45 savingsGridCharged: 1.950372661833812e-05
[influx] TRACE 2022/05/22 21:45:45 write homePower=595.0381427192688 (map[])
[influx] TRACE 2022/05/22 21:45:45 write savingsTotalCharged=1.950372661833812e-05 (map[])
[cache ] TRACE 2022/05/22 21:45:45 savingsSelfConsumptionCharged: 0
[influx] TRACE 2022/05/22 21:45:45 write savingsGridCharged=1.950372661833812e-05 (map[])
[influx] TRACE 2022/05/22 21:45:45 write savingsSelfConsumptionCharged=0 (map[])
[influx] TRACE 2022/05/22 21:45:45 write savingsSelfConsumptionPercent=0 (map[])
[cache ] TRACE 2022/05/22 21:45:45 savingsSelfConsumptionPercent: 0
[cache ] TRACE 2022/05/22 21:45:45 savingsEffectivePrice: 0.2579000000000001
[cache ] TRACE 2022/05/22 21:45:45 savingsAmount: 0
[influx] TRACE 2022/05/22 21:45:45 write savingsEffectivePrice=0.2579000000000001 (map[])
[influx] TRACE 2022/05/22 21:45:45 write savingsAmount=0 (map[])
[modbus] TRACE 2022/05/22 21:45:49 modbus: closing connection due to idle timeout: 5.000705647s
[site  ] DEBUG 2022/05/22 21:45:54 ----
[modbus] TRACE 2022/05/22 21:45:54 modbus: send 00 24 00 00 00 06 00 03 31 68 00 02
[modbus] TRACE 2022/05/22 21:45:54 modbus: recv 00 24 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:45:54 charge power: 0W
[http  ] TRACE 2022/05/22 21:45:54 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[cache ] TRACE 2022/05/22 21:45:54 lp-1/chargePower: 0
[influx] TRACE 2022/05/22 21:45:54 write chargePower=0 (map[loadpoint:Garage vehicle:e-Golf])
[http  ] TRACE 2022/05/22 21:45:54 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 600.72000000000003,
            "P_Load" : -600.72000000000003,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:45:54+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:45:54 pv power: 0W
[influx] TRACE 2022/05/22 21:45:54 write pvPower=0 (map[])
[cache ] TRACE 2022/05/22 21:45:54 pvPower: 0
[http  ] TRACE 2022/05/22 21:45:54 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[http  ] TRACE 2022/05/22 21:45:54 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 600.72000000000003,
            "P_Load" : -600.72000000000003,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:45:54+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:45:54 grid power: 601W
[site  ] DEBUG 2022/05/22 21:45:54 site power: 601W
[cache ] TRACE 2022/05/22 21:45:54 gridPower: 600.72
[cache ] TRACE 2022/05/22 21:45:54 lp-1/mode: pv
[influx] TRACE 2022/05/22 21:45:54 write gridPower=600.72 (map[])
[modbus] TRACE 2022/05/22 21:45:54 modbus: send 00 25 00 00 00 06 00 03 31 54 00 02
[modbus] TRACE 2022/05/22 21:45:54 modbus: recv 00 25 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:45:54 modbus: send 00 26 00 00 00 06 00 03 31 56 00 02
[modbus] TRACE 2022/05/22 21:45:54 modbus: recv 00 26 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:45:54 modbus: send 00 27 00 00 00 06 00 03 31 58 00 02
[modbus] TRACE 2022/05/22 21:45:54 modbus: recv 00 27 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:45:54 charge currents: [0 0 0]A
[modbus] TRACE 2022/05/22 21:45:54 modbus: send 00 28 00 00 00 06 00 03 31 66 00 02
[cache ] TRACE 2022/05/22 21:45:54 lp-1/chargeCurrents: [0 0 0]
[cache ] TRACE 2022/05/22 21:45:54 lp-1/chargeCurrent: 0
[influx] TRACE 2022/05/22 21:45:54 write chargeCurrents=[0 0 0] (map[loadpoint:Garage vehicle:e-Golf])
[influx] TRACE 2022/05/22 21:45:54 write chargeCurrent=0 (map[loadpoint:Garage vehicle:e-Golf])
[modbus] TRACE 2022/05/22 21:45:54 modbus: recv 00 28 00 00 00 07 00 03 04 16 a3 46 a3
[go-e  ] TRACE 2022/05/22 21:45:54 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:54 lp-1/chargedEnergy: 0
[cache ] TRACE 2022/05/22 21:45:54 lp-1/chargeDuration: 30s
[go-e  ] TRACE 2022/05/22 21:45:55 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,232,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:55 charger status: B
[cache ] TRACE 2022/05/22 21:45:55 lp-1/connected: true
[cache ] TRACE 2022/05/22 21:45:55 lp-1/charging: false
[go-e  ] TRACE 2022/05/22 21:45:55 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:45:55 lp-1/enabled: false
[go-e  ] TRACE 2022/05/22 21:45:55 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,232,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:55 next soc poll remaining time: 1h59m30s
[go-e  ] TRACE 2022/05/22 21:45:55 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[go-e  ] TRACE 2022/05/22 21:45:55 {"alw":false,"eto":131555,"car":3,"wh":0,"trx":null,"nrg":[233,232,232,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:45:55 pv charge current: 0A = 0A + -1.31A (601W @ 2p)
[lp-1  ] DEBUG 2022/05/22 21:45:55 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[lp-1  ] DEBUG 2022/05/22 21:45:55 climater active: false, target temp: 21.1°C, outside temp: 25.5°C
[cache ] TRACE 2022/05/22 21:45:55 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:55 lp-1/climater: off
[cache ] TRACE 2022/05/22 21:45:55 homePower: 600.72
[influx] TRACE 2022/05/22 21:45:55 write homePower=600.72 (map[])
[modbus] TRACE 2022/05/22 21:45:59 modbus: closing connection due to idle timeout: 5.000165649s
[site  ] DEBUG 2022/05/22 21:46:04 ----
[modbus] TRACE 2022/05/22 21:46:04 modbus: send 00 29 00 00 00 06 00 03 31 68 00 02
[modbus] TRACE 2022/05/22 21:46:04 modbus: recv 00 29 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:46:04 charge power: 0W
[http  ] TRACE 2022/05/22 21:46:04 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[influx] TRACE 2022/05/22 21:46:04 write chargePower=0 (map[loadpoint:Garage vehicle:e-Golf])
[cache ] TRACE 2022/05/22 21:46:04 lp-1/chargePower: 0
[http  ] TRACE 2022/05/22 21:46:04 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 603.45000000000005,
            "P_Load" : -603.45000000000005,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:46:04+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:46:04 pv power: 0W
[http  ] TRACE 2022/05/22 21:46:04 GET http://192.168.178.86/solar_api/v1/GetPowerFlowRealtimeData.fcgi
[cache ] TRACE 2022/05/22 21:46:04 pvPower: 0
[influx] TRACE 2022/05/22 21:46:04 write pvPower=0 (map[])
[http  ] TRACE 2022/05/22 21:46:04 {
   "Body" : {
      "Data" : {
         "Inverters" : {
            "1" : {
               "DT" : 123,
               "E_Day" : 64570,
               "E_Total" : 45913600,
               "E_Year" : 4226774.5,
               "P" : 0
            }
         },
         "Site" : {
            "E_Day" : 64570,
            "E_Total" : 45913600,
            "E_Year" : 4226774.5,
            "Meter_Location" : "grid",
            "Mode" : "meter",
            "P_Akku" : null,
            "P_Grid" : 603.45000000000005,
            "P_Load" : -603.45000000000005,
            "P_PV" : null,
            "rel_Autonomy" : 0,
            "rel_SelfConsumption" : null
         },
         "Version" : "12"
      }
   },
   "Head" : {
      "RequestArguments" : {},
      "Status" : {
         "Code" : 0,
         "Reason" : "",
         "UserMessage" : ""
      },
      "Timestamp" : "2022-05-22T21:46:04+02:00"
   }
}
[site  ] DEBUG 2022/05/22 21:46:04 grid power: 603W
[site  ] DEBUG 2022/05/22 21:46:04 site power: 603W
[modbus] TRACE 2022/05/22 21:46:04 modbus: send 00 2a 00 00 00 06 00 03 31 54 00 02
[cache ] TRACE 2022/05/22 21:46:04 gridPower: 603.45
[influx] TRACE 2022/05/22 21:46:04 write gridPower=603.45 (map[])
[cache ] TRACE 2022/05/22 21:46:04 lp-1/mode: pv
[modbus] TRACE 2022/05/22 21:46:04 modbus: recv 00 2a 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:46:04 modbus: send 00 2b 00 00 00 06 00 03 31 56 00 02
[modbus] TRACE 2022/05/22 21:46:04 modbus: recv 00 2b 00 00 00 07 00 03 04 00 00 00 00
[modbus] TRACE 2022/05/22 21:46:04 modbus: send 00 2c 00 00 00 06 00 03 31 58 00 02
[modbus] TRACE 2022/05/22 21:46:04 modbus: recv 00 2c 00 00 00 07 00 03 04 00 00 00 00
[lp-1  ] DEBUG 2022/05/22 21:46:04 charge currents: [0 0 0]A
[modbus] TRACE 2022/05/22 21:46:04 modbus: send 00 2d 00 00 00 06 00 03 31 66 00 02
[cache ] TRACE 2022/05/22 21:46:04 lp-1/chargeCurrents: [0 0 0]
[cache ] TRACE 2022/05/22 21:46:04 lp-1/chargeCurrent: 0
[influx] TRACE 2022/05/22 21:46:04 write chargeCurrents=[0 0 0] (map[loadpoint:Garage vehicle:e-Golf])
[influx] TRACE 2022/05/22 21:46:04 write chargeCurrent=0 (map[loadpoint:Garage vehicle:e-Golf])
[modbus] TRACE 2022/05/22 21:46:04 modbus: recv 00 2d 00 00 00 07 00 03 04 16 a3 46 a3
[cache ] TRACE 2022/05/22 21:46:04 lp-1/chargedEnergy: 0
[go-e  ] TRACE 2022/05/22 21:46:04 GET http://192.168.178.88/api/status?filter=alw,car,eto,nrg,wh,trx,cards
[cache ] TRACE 2022/05/22 21:46:04 lp-1/chargeDuration: 40s
[go-e  ] TRACE 2022/05/22 21:46:05 {"alw":true,"eto":131555,"car":1,"wh":0,"trx":null,"nrg":[233,233,231,0,0,0,0,0,0,0,0,0,0,0,0,0],"cards":[{"name":"User 1","energy":0,"cardId":true},{"name":"User 2","energy":0,"cardId":false},{"name":"User 3","energy":0,"cardId":false},{"name":"User 4","energy":0,"cardId":false},{"name":"User 5","energy":0,"cardId":false},{"name":"User 6","energy":0,"cardId":false},{"name":"User 7","energy":0,"cardId":false},{"name":"User 8","energy":0,"cardId":false},{"name":"User 9","energy":0,"cardId":false},{"name":"User 10","energy":0,"cardId":false}]}
[lp-1  ] DEBUG 2022/05/22 21:46:05 charger status: A
[lp-1  ] INFO 2022/05/22 21:46:05 car disconnected
andig commented 2 years ago

Ich sehe in Deinem Log keine 

vehicle updated

Meldung. Wenns nicht erkannt wird dann wird auch kein km-Stand gelesen.

e-frog commented 2 years ago

vehicle updated ist nur nach Neutstart von evcc in den logs.

[lp-1 ] INFO 2022/05/22 21:44:34 vehicle updated: unknown -> e-Golf

Dort wird das das Fahrzeug erkannt. Sollte das nochmal neu erkannt werden beim Anstecken/Ladestart?

andig commented 2 years ago

Sollte, man weiß ja sonst nicht welches nach Abstecken dran hängt.

e-frog commented 2 years ago

vehicle updated kommt beim Anstecken nur wenn mehrere Fahrzeuge konfiguriert sind. Bei nur einem Fahrzeug wird setActiveVehicle() sofort wieder verlassen und daher der km-Stand niemals aktualisiert.

// setActiveVehicle assigns currently active vehicle and configures soc estimator
func (lp *LoadPoint) setActiveVehicle(vehicle api.Vehicle) {
    if lp.vehicle == vehicle {
        return
    }
andig commented 2 years ago

Klasse Analyse, danke!

andig commented 2 years ago

Beim zweiten drauf schauen: das liegt an der unglücklichen Behandlung der Sondersituation "nur ein Auto konfiguriert". Ich glaube wir sollten das vorerst gar nicht weiter anpassen, sondern lieber zusammen mit dem Parkplatzfeature grundsätzlich aufräumen. Eine Diskussion zum Design wird es in Kürze geben.

/cc @naltatis

kaluxx commented 2 years ago

Vielleicht könnt ihr mir mal auf die Sprünge helfen. Ich habe dasselbe Problem, dass es keinen Update auf den vehicleOdometer nach dem Start mehr gibt. Also Odo nur einmalig nach Start von evcc. Blöd, da ich damit meine gefahrenen KM darstelle. Kann ich etwas in meiner Konfiguration ändern, damit das wieder upgedated wird? Wieso ist es denn ein Sonderfall, nur ein Auto konfiguriert zu haben??

andig commented 2 years ago

Wieso ist es denn ein Sonderfall, nur ein Auto konfiguriert zu haben??

Ist im Moment einfach so... Schau mal hier für Workaround: https://github.com/evcc-io/evcc/issues/3323#issuecomment-1152190224

kaluxx commented 2 years ago

...habe dieses Dummy Fahrzeug angelegt und bekomme immer noch keine Odometer Daten (bzw. nur einmalig nach Neustart). Muss ich das Fzg. noch einem Loadpoint hinzufügen?? Ist auch nicht so schlimm. Falls das Problem in einem der nächsten Releases gelöst wird, würde ich da gar nicht weiter rumbasteln.

andig commented 2 years ago

Please verify with tomorrow's nightly.