search

evcc-io / evcc

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

OCPP: evcc not starting due to SmartCharging profile is not supported, FATAL error again #15681

Closed jgantenberg closed 2 months ago

jgantenberg commented 2 months ago

Describe the bug

After update to version 0.130.3 evcc is not starting anymore due to SmartCharging profile not being supported by wallbox. After downgrade to 0.130.2 evcc starts normal

Steps to reproduce

  1. update to 0.130.3
  2. start evcc

  3. ...

Configuration details

chargers:
  - name: EntratekWallbox
    type: ocpp
    connecttimeout: 5m
    timeout: 5m
    metervalues: Energy.Active.Import.Register,Power.Active.Import,Current.Offered,Power.Offered,Current.Import,Voltage # ,SoC # optional

loadpoints:
  - title: Garage
    charger: EntratekWallbox
    mode: pv
    meter: charge1
    enable:
      delay: 2m
      threshold: -500
    disable:
      delay: 10m
      threshold: 1000

Log details

Aug 25 17:05:19 opi evcc[30758]: [main  ] INFO 2024/08/25 17:05:19 evcc 0.130.3
Aug 25 17:05:19 opi evcc[30758]: [main  ] INFO 2024/08/25 17:05:19 using config file: /etc/evcc.yaml
Aug 25 17:05:19 opi evcc[30758]: [db    ] INFO 2024/08/25 17:05:19 using sqlite database: /var/lib/evcc/evcc.db
Aug 25 17:05:20 opi evcc[30758]: [mqtt  ] INFO 2024/08/25 17:05:20 connecting evcc at tcp://192.168.96.3:1883
Aug 25 17:05:20 opi evcc[30758]: [main  ] INFO 2024/08/25 17:05:20 listening at :7070
Aug 25 17:05:26 opi evcc[30758]: [ocpp  ] INFO 2024/08/25 17:05:26 charge point connected, registering: 03xxxxxxxxxxxxxxxxxx94
Aug 25 17:05:27 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:27 send 03xxxxxxxxxxxxxxxxxx94: [2,"3747914330","GetConfiguration",{}]
Aug 25 17:05:38 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:38 recv 03xxxxxxxxxxxxxxxxxx94: [2, "81349c7a-66cb-483d-e228-12a720596276", "BootNotification", {"chargePointModel": "DUOSIDA Mode3@96A", "chargePointSerialNumber": "03xxxxxxxxxxxxxxxxxx94", "chargePointVendor": "UCHEN", "firmwareVersion": "V2.5@322795f+337,F75.M16.wifi2,ws,V2.0@5bac49d+65", "iccid": "", "imsi": ""}]
Aug 25 17:05:38 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:38 send 03xxxxxxxxxxxxxxxxxx94: [3,"81349c7a-66cb-483d-e228-12a720596276",{"currentTime":"2024-08-25T15:05:38Z","interval":60,"status":"Accepted"}]
Aug 25 17:05:41 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:41 recv 03xxxxxxxxxxxxxxxxxx94: [3, "3747914330", {"configurationKey": [{"key": "AuthorizeRemoteTxRequests", "readonly": false, "value": "0"}, {"key": "ClockAlignedDataInterval", "readonly": false, "value": "900"}, {"key": "ConnectionTimeOut", "readonly": false, "value": "120"}, {"key": "ConnectorPhaseRotation", "readonly": false, "value": "RST"}, {"key": "ChargingScheduleAllowedChargingRateUnit", "readonly": true, "value": "Current, Power"}, {"key": "ChargingScheduleMaxPeriods", "readonly": true, "value": "5"}, {"key": "GetConfigurationMaxKeys", "readonly": true, "value": "30"}, {"key": "HeartbeatInterval", "readonly": false, "value": "60"}, {"key": "LocalAuthorizeOffline", "readonly": false, "value": "1"}, {"key": "LocalPreAuthorize", "readonly": false, "value": "1"}, {"key": "MeterValuesAlignedData", "readonly": false, "value": "Current.Import,Energy.Active.Import.Interval,Power.Active.Import,Temperature,Voltage"}, {"key": "MeterValuesSampledData", "readonly": false, "value": "Energy.Active.Import.Register,Power.Active.Import,Current.Offered,Power.Offered,Current.Import,Voltage"}, {"key": "MeterValueSampleInterval", "readonly": false, "value": "30"}, {"key": "NumberOfConnectors", "readonly": true, "value": "1"}, {"key": "ResetRetries", "readonly": false, "value": "0"}, {"key": "StopTransactionOnInvalidId", "readonly": false, "value": "1"}, {"key": "StopTxnAlignedData", "readonly": false, "value": "Current.Import,Energy.Active.Import.Interval,Power.Active.Import,Temperature,Voltage"}, {"key": "StopTxnSampledData", "readonly": false, "value": "Current.Import,Energy.Active.Import.Register,Power.Active.Import,Temperature,Voltage"}, {"key": "SupportedFeatureProfiles", "readonly": true, "value": ""}, {"key": "TransactionMessageAttempts", "readonly": false, "value": "3"}, {"key": "TransactionMessageRetryInterval", "readonly": false, "value": "60"}, {"key": "UnlockConnectorOnEVSideDisconnect", "readonly": false, "value": "1"}, {"key": "LocalAuthListEnabled", "readonly": false, "value": "0"}, {"key": "LocalAuthListMaxLength", "readonly": true, "value": "60"}, {"key": "SendLocalListMaxLength", "readonly": true, "value": "8"}, {"key": "ChargeProfileMaxStackLevel", "readonly": true, "value": "8"}, {"key": "MaxChargingProfilesInstalled", "readonly": true, "value": "16"}, {"key": "VendorMaxWorkCurrent", "readonly": false, "value": "16.00"}, {"key": "VendorDirectWorkMode", "readonly": false, "value": "1"}, {"key": "GunInsertUpdateStatus", "readonly": false, "value": "1"}]}]
Aug 25 17:05:41 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:41 recv 03xxxxxxxxxxxxxxxxxx94: [2, "8134a696-66cb-4840-5f55-e212568f6194", "StatusNotification", {"connectorId": 0, "errorCode": "NoError", "info": "", "status": "Available", "vendorId": ""}]
Aug 25 17:05:41 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:41 send 03xxxxxxxxxxxxxxxxxx94: [3,"8134a696-66cb-4840-5f55-e212568f6194",{}]
Aug 25 17:05:41 opi evcc[30758]: [main  ] FATAL 2024/08/25 17:05:41 cannot create charger 'EntratekWallbox': cannot create charger type 'ocpp': the mandatory SmartCharging profile is not supported
Aug 25 17:05:41 opi evcc[30758]: [main  ] FATAL 2024/08/25 17:05:41 will attempt restart in: 15m0s
Aug 25 17:05:42 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:42 recv 03xxxxxxxxxxxxxxxxxx94: [2, "8134ab52-66cb-4841-c96f-d7fd43de4e26", "StatusNotification", {"connectorId": 1, "errorCode": "NoError", "info": "", "status": "Available", "vendorId": ""}]
Aug 25 17:05:42 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:05:42 send 03xxxxxxxxxxxxxxxxxx94: [3,"8134ab52-66cb-4841-c96f-d7fd43de4e26",{}]
Aug 25 17:06:44 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:06:44 recv 03xxxxxxxxxxxxxxxxxx94: [2, "81359a43-66cb-487f-9097-13b3c1a40af5", "Heartbeat", {}]
Aug 25 17:06:44 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:06:44 send 03xxxxxxxxxxxxxxxxxx94: [3,"81359a43-66cb-487f-9097-13b3c1a40af5",{"currentTime":"2024-08-25T15:06:44Z"}]
Aug 25 17:07:45 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:07:45 recv 03xxxxxxxxxxxxxxxxxx94: [2, "8136892c-66cb-48bc-09a1-68d3e85d86c1", "Heartbeat", {}]
Aug 25 17:07:45 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:07:45 send 03xxxxxxxxxxxxxxxxxx94: [3,"8136892c-66cb-48bc-09a1-68d3e85d86c1",{"currentTime":"2024-08-25T15:07:45Z"}]
Aug 25 17:08:47 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:08:47 recv 03xxxxxxxxxxxxxxxxxx94: [2, "8137780c-66cb-48fa-5b1e-2eae0efc6d2d", "Heartbeat", {}]
Aug 25 17:08:47 opi evcc[30758]: [ocpp  ] TRACE 2024/08/25 17:08:47 send 03xxxxxxxxxxxxxxxxxx94: [3,"8137780c-66cb-48fa-5b1e-2eae0efc6d2d",{"currentTime":"2024-08-25T15:08:47Z"}]
Aug 25 17:09:06 opi systemd[1]: Stopping evcc.service - evcc...

What type of operating system are you running?

Linux

Version

0.130.3

premultiply commented 2 months ago

@andig Das kommt durch https://github.com/evcc-io/evcc/pull/15369 Hier wurde die Logik geändert. Revert?

andig commented 2 months ago

Closed in https://github.com/evcc-io/evcc/commit/35cf628986ac19795eb00b918a8a70165bb51691, sorry für den Ärger.

jgantenberg commented 2 months ago

it's getting little bit wild at the moment: after upgrade to 0.130.4 I receive FATAL 2024/08/25 23:03:03 database disk image is malformed (11) Full log: 

Aug 25 23:03:02 opi systemd[1]: Started evcc.service - evcc.
Aug 25 23:03:03 opi evcc[23442]: [main  ] INFO 2024/08/25 23:03:03 evcc 0.130.4
Aug 25 23:03:03 opi evcc[23442]: [main  ] INFO 2024/08/25 23:03:03 using config file: /etc/evcc.yaml
Aug 25 23:03:03 opi evcc[23442]: [db    ] INFO 2024/08/25 23:03:03 using sqlite database: /var/lib/evcc/evcc.db
Aug 25 23:03:03 opi evcc[23442]: [mqtt  ] INFO 2024/08/25 23:03:03 connecting evcc at tcp://192.168.96.3:1883
Aug 25 23:03:03 opi evcc[23442]: [main  ] INFO 2024/08/25 23:03:03 listening at :7070
Aug 25 23:03:03 opi evcc[23442]: [main  ] FATAL 2024/08/25 23:03:03 database disk image is malformed (11)
Aug 25 23:03:03 opi evcc[23442]: [main  ] FATAL 2024/08/25 23:03:03 will attempt restart in: 15m0s
Aug 25 23:03:38 opi systemd[1]: Stopping evcc.service - evcc...

After downgrade to 0.130.2 evcc starts with no error. Any idea?

andig commented 2 months ago

Sorry for that- one of the dependencies didn‘t play along as expected.