evcc-io / evcc

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

OCPP: Timeout on restart of evcc upon wallbox reconnection #15874

Closed jeffborg closed 1 month ago

jeffborg commented 1 month ago

Describe the bug

wallbox firmware updated working fine, tested restarting evcc and does not start up. Need to powercycle both wallbox and restart evcc to get working. May be related to #15869

Steps to reproduce

  1. restart evcc
  2. when wallbox reconnects evcc times out.

Configuration details

chargers:
  - name: solax
    type: template
    template: ocpp
    timeout: 2m
    getconfiguration: false
    metervalues: Energy.Active.Import.Register,Power.Active.Import,Current.Import
    bootnotification: false
    meterinterval: 10s
    remotestart: true

Log details

[main  ] INFO 2024/09/03 08:38:49 evcc 0.136.6
[main  ] INFO 2024/09/03 08:38:49 using config file: /config/evcc.yaml
[db    ] INFO 2024/09/03 08:38:49 using sqlite database: /config/.evcc/evcc.db
[mqtt  ] INFO 2024/09/03 08:38:50 connecting evcc-497679395 at tcp://mqtt-mosquitto:1883
[mqtt  ] DEBUG 2024/09/03 08:38:50 tcp://mqtt-mosquitto:1883 connected
[main  ] INFO 2024/09/03 08:38:50 listening at :7070
[ocpp-1] DEBUG 2024/09/03 08:38:50 waiting for chargepoint: 5m0s
[ocpp  ] INFO 2024/09/03 08:39:01 charge point connected, registering: SN10052307203612
[ocpp  ] TRACE 2024/09/03 08:39:02 send SN10052307203612: [2,"3396786914","ChangeAvailability",{"connectorId":0,"type":"Operative"}]
[ocpp  ] TRACE 2024/09/03 08:39:02 recv SN10052307203612: [2,"475","DataTransfer",{"vendorId":"EN+","messageId":"gatewayInfo","data":"{\"SN\":\"SN10052307203612\",\"fwVer\":\"V43.2.636\",\"gateCode\":107,\"measureType\":1,\"disconnectReason\":0,\"maxCurr\":320,\"phase\":1,\"sdCardStatus\":0}"}]
[ocpp  ] TRACE 2024/09/03 08:39:02 send SN10052307203612: [3,"475",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:02 recv SN10052307203612: [2,"476","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:01Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Sample.Periodic"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:02 send SN10052307203612: [3,"476",{}]
[ocpp  ] TRACE 2024/09/03 08:39:05 recv SN10052307203612: [2,"477","Heartbeat",{}]
[ocpp  ] TRACE 2024/09/03 08:39:05 recv SN10052307203612: [3,"3396786914",{"status":"Rejected"}]
[ocpp-1] DEBUG 2024/09/03 08:39:05 failed configuring availability: Rejected
[ocpp  ] TRACE 2024/09/03 08:39:05 send SN10052307203612: [3,"477",{"currentTime":"2024-09-02T22:39:05Z"}]
[ocpp  ] TRACE 2024/09/03 08:39:05 send SN10052307203612: [2,"575591476","GetConfiguration",{}]
[ocpp  ] TRACE 2024/09/03 08:39:05 recv SN10052307203612: [2,"478","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":220,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/09/03 08:39:05 send SN10052307203612: [3,"478",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:06 recv SN10052307203612: [3,"575591476",{"configurationKey":[{"key":"vendorId","value":"EN+","readonly":false},{"key":"chargePointSN","value":"","readonly":false},{"key":"AuthorizationKey","value":"ABCDEFGHIJ123456","readonly":false},{"key":"ChargingParametersEnabled","value":"true","readonly":false},{"key":"AllowOfflineTxForUnknownId","value":"false","readonly":false},{"key":"AuthorizationCacheEnabled","value":"false","readonly":false},{"key":"AuthorizeRemoteTxRequests","value":"false","readonly":false},{"key":"BlinkRepeat","value":"0","readonly":false},{"key":"ClockAlignedDataInterval","value":"0","readonly":false},{"key":"ConnectionTimeOut","value":"30","readonly":false},{"key":"GetConfigurationMaxKeys","value":"50","readonly":true},{"key":"HeartbeatInterval","value":"60","readonly":false},{"key":"LocalAuthorizeOffline","value":"false","readonly":false},{"key":"LocalPreAuthorize","value":"false","readonly":false},{"key":"MaxEnergyOnInvalidId","value":"3000","readonly":false},{"key":"MeterValuesAlignedData","value":"Energy.Active.Import.Register","readonly":false},{"key":"MeterValuesSampledData","value":"Energy.Active.Import.Register,Current.Import,Voltage,SoC,Power.Active.Import","readonly":false},{"key":"MeterValueSampleInterval","value":"60","readonly":false},{"key":"MinimumStatusDuration","value":"0","readonly":false},{"key":"ResetRetries","value":"0","readonly":false},{"key":"ConnectorPhaseRotation","value":"","readonly":false},{"key":"StopTransactionOnEVSideDisconnect","value":"false","readonly":false},{"key":"StopTransactionOnInvalidId","value":"false","readonly":false},{"key":"StopTxnAlignedData","value":"","readonly":false},{"key":"StopTxnSampledData","value":"Energy.Active.Import.Register","readonly":false},{"key":"TransactionMessageAttempts","value":"0","readonly":false},{"key":"TransactionMessageRetryInterval","value":"15","readonly":false},{"key":"UnlockConnectorOnEVSideDisconnect","value":"false","readonly":false},{"key":"WebSocketPingInterval","value":"0","readonly":false},{"key":"NumberOfConnectors","value":"1","readonly":true},{"key":"MeterValuesAlignedDataMaxLength","value":"120","readonly":true},{"key":"MeterValuesSampledDataMaxLength","value":"120","readonly":true},{"key":"ConnectorPhaseRotationMaxLength","value":"120","readonly":true},{"key":"StopTxnAlignedDataMaxLength","value":"120","readonly":true},{"key":"StopTxnSampledDataMaxLength","value":"120","readonly":true},{"key":"SupportedFeatureProfiles","value":"Core,Reservation,FirmwareManagement,LocalAuthListManagement,RemoteTrigger,SmartCharging","readonly":true},{"key":"SupportedFeatureProfilesMaxLength","value":"120","readonly":true},{"key":"ReserveConnectorZeroSupported","value":"false","readonly":true},{"key":"LocalAuthListEnabled","value":"true","readonly":false},{"key":"LocalAuthListMaxLength","value":"10","readonly":true},{"key":"SendLocalListMaxLength","value":"10","readonly":true},{"key":"ChargeProfileMaxStackLevel","value":"5","readonly":true},{"key":"ChargingScheduleAllowedChargingRateUnit","value":"Current","readonly":true},{"key":"ChargingScheduleMaxPeriods","value":"5","readonly":true},{"key":"ConnectorSwitch3to1PhaseSupported","value":"false","readonly":true},{"key":"MaxChargingProfilesInstalled","value":"3","readonly":true}],"unknownKey":[]}]
[ocpp  ] TRACE 2024/09/03 08:39:06 send SN10052307203612: [2,"3107247997","TriggerMessage",{"requestedMessage":"BootNotification"}]
[ocpp  ] TRACE 2024/09/03 08:39:06 recv SN10052307203612: [3,"3107247997",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:06 recv SN10052307203612: [2,"479","BootNotification",{"chargePointModel":"23","chargePointSerialNumber":"SN10052307203612","chargePointVendor":"EN+","firmwareVersion":"1.5.91"}]
[ocpp  ] TRACE 2024/09/03 08:39:06 send SN10052307203612: [3,"479",{"currentTime":"2024-09-02T22:39:06Z","interval":60,"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:06 send SN10052307203612: [2,"3733828879","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Active.Import"}]
[ocpp  ] TRACE 2024/09/03 08:39:08 recv SN10052307203612: [3,"3733828879",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:08 send SN10052307203612: [2,"1760679551","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Energy.Active.Import.Register"}]
[ocpp  ] TRACE 2024/09/03 08:39:09 recv SN10052307203612: [3,"1760679551",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:09 send SN10052307203612: [2,"2098796689","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Current.Import"}]
[ocpp  ] TRACE 2024/09/03 08:39:09 recv SN10052307203612: [3,"2098796689",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:09 send SN10052307203612: [2,"507748885","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Voltage"}]
[ocpp  ] TRACE 2024/09/03 08:39:10 recv SN10052307203612: [3,"507748885",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:10 send SN10052307203612: [2,"4005503876","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Current.Offered"}]
[ocpp  ] TRACE 2024/09/03 08:39:11 recv SN10052307203612: [3,"4005503876",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:11 send SN10052307203612: [2,"2088164538","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Offered"}]
[ocpp  ] TRACE 2024/09/03 08:39:12 recv SN10052307203612: [3,"2088164538",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:12 send SN10052307203612: [2,"737847056","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"SoC"}]
[ocpp  ] TRACE 2024/09/03 08:39:13 recv SN10052307203612: [3,"737847056",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:13 send SN10052307203612: [2,"1400006221","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Active.Import,Energy.Active.Import.Register,Current.Import,Voltage,Current.Offered,Power.Offered,SoC"}]
[ocpp  ] TRACE 2024/09/03 08:39:14 recv SN10052307203612: [3,"1400006221",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:14 send SN10052307203612: [2,"3904974401","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:39:14 recv SN10052307203612: [3,"3904974401",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:14 send SN10052307203612: [2,"3491560900","ChangeConfiguration",{"key":"MeterValueSampleInterval","value":"10"}]
[ocpp  ] TRACE 2024/09/03 08:39:14 recv SN10052307203612: [2,"480","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:13Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:14 send SN10052307203612: [3,"480",{}]
[ocpp  ] TRACE 2024/09/03 08:39:16 recv SN10052307203612: [3,"3491560900",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:16 recv SN10052307203612: [2,"481","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":256,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":220,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:39:16 send SN10052307203612: [2,"1411483424","ChangeConfiguration",{"key":"WebSocketPingInterval","value":"30"}]
[ocpp  ] TRACE 2024/09/03 08:39:16 send SN10052307203612: [3,"481",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:18 recv SN10052307203612: [3,"1411483424",{"status":"NotSupported"}]
[ocpp  ] TRACE 2024/09/03 08:39:24 send SN10052307203612: [2,"1419846836","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:39:26 recv SN10052307203612: [3,"1419846836",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:26 recv SN10052307203612: [2,"482","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:23Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:26 send SN10052307203612: [2,"1199472704","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:39:26 send SN10052307203612: [3,"482",{}]
[ocpp  ] TRACE 2024/09/03 08:39:28 recv SN10052307203612: [3,"1199472704",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:28 recv SN10052307203612: [2,"483","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:25Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:28 recv SN10052307203612: [2,"484","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":256,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/09/03 08:39:28 send SN10052307203612: [3,"484",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:28 send SN10052307203612: [3,"483",{}]
[ocpp  ] TRACE 2024/09/03 08:39:28 recv SN10052307203612: [2,"485","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:39:28 send SN10052307203612: [3,"485",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:36 send SN10052307203612: [2,"3154436392","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:39:37 recv SN10052307203612: [3,"3154436392",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:37 recv SN10052307203612: [2,"486","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:35Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.4","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:37 send SN10052307203612: [3,"486",{}]
[ocpp  ] TRACE 2024/09/03 08:39:38 recv SN10052307203612: [2,"487","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:39:38 send SN10052307203612: [3,"487",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:46 recv SN10052307203612: [2,"488","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:44Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Sample.Periodic"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:46 send SN10052307203612: [3,"488",{}]
[ocpp  ] TRACE 2024/09/03 08:39:47 recv SN10052307203612: [2,"489","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:39:47 send SN10052307203612: [3,"489",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:54 send SN10052307203612: [2,"1679140935","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:39:56 recv SN10052307203612: [3,"1679140935",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:39:56 recv SN10052307203612: [2,"490","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:39:53Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:39:56 send SN10052307203612: [3,"490",{}]
[ocpp  ] TRACE 2024/09/03 08:39:56 recv SN10052307203612: [2,"491","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":256,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:39:56 send SN10052307203612: [3,"491",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:01 recv SN10052307203612: [2,"492","Heartbeat",{}]
[ocpp  ] TRACE 2024/09/03 08:40:01 send SN10052307203612: [3,"492",{"currentTime":"2024-09-02T22:40:01Z"}]
[ocpp  ] TRACE 2024/09/03 08:40:04 send SN10052307203612: [2,"3484462121","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:06 recv SN10052307203612: [3,"3484462121",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:06 recv SN10052307203612: [2,"493","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:03Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:06 send SN10052307203612: [3,"493",{}]
[ocpp  ] TRACE 2024/09/03 08:40:06 recv SN10052307203612: [2,"494","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/09/03 08:40:06 send SN10052307203612: [3,"494",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:14 send SN10052307203612: [2,"2611585181","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:14 recv SN10052307203612: [3,"2611585181",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:16 send SN10052307203612: [2,"4274647978","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:16 recv SN10052307203612: [2,"495","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:13Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:16 send SN10052307203612: [3,"495",{}]
[ocpp  ] TRACE 2024/09/03 08:40:16 recv SN10052307203612: [3,"4274647978",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:18 recv SN10052307203612: [2,"496","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:15Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:18 recv SN10052307203612: [2,"497","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":256,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:40:18 send SN10052307203612: [3,"496",{}]
[ocpp  ] TRACE 2024/09/03 08:40:18 send SN10052307203612: [3,"497",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:19 recv SN10052307203612: [2,"498","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/09/03 08:40:19 send SN10052307203612: [3,"498",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:26 send SN10052307203612: [2,"666249851","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:28 recv SN10052307203612: [3,"666249851",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:28 recv SN10052307203612: [2,"499","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:26Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:28 send SN10052307203612: [3,"499",{}]
[ocpp  ] TRACE 2024/09/03 08:40:30 recv SN10052307203612: [2,"500","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:40:30 send SN10052307203612: [3,"500",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:36 send SN10052307203612: [2,"3963483659","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:37 recv SN10052307203612: [3,"3963483659",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:38 send SN10052307203612: [2,"1471918389","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:38 recv SN10052307203612: [2,"501","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:35Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:38 send SN10052307203612: [3,"501",{}]
[ocpp  ] TRACE 2024/09/03 08:40:40 recv SN10052307203612: [3,"1471918389",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:40 recv SN10052307203612: [2,"502","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:37Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:40 recv SN10052307203612: [2,"503","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/09/03 08:40:40 send SN10052307203612: [3,"503",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:40 send SN10052307203612: [3,"502",{}]
[ocpp  ] TRACE 2024/09/03 08:40:41 recv SN10052307203612: [2,"504","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:40:41 send SN10052307203612: [3,"504",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:46 recv SN10052307203612: [2,"505","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:45Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.6","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Sample.Periodic"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:46 send SN10052307203612: [3,"505",{}]
[ocpp  ] TRACE 2024/09/03 08:40:47 recv SN10052307203612: [2,"506","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:40:47 send SN10052307203612: [3,"506",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:56 send SN10052307203612: [2,"2068749464","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:56 recv SN10052307203612: [3,"2068749464",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:40:58 send SN10052307203612: [2,"1438130917","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:40:58 recv SN10052307203612: [2,"507","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:55Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.6","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:40:58 send SN10052307203612: [3,"507",{}]
[ocpp  ] TRACE 2024/09/03 08:40:59 recv SN10052307203612: [3,"1438130917",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:41:00 recv SN10052307203612: [2,"508","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:40:57Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.6","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:41:00 recv SN10052307203612: [2,"509","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":256,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":256,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:41:00 send SN10052307203612: [3,"508",{}]
[ocpp  ] TRACE 2024/09/03 08:41:00 send SN10052307203612: [3,"509",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:41:01 recv SN10052307203612: [2,"510","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":1,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":12}"}]
[ocpp  ] TRACE 2024/09/03 08:41:01 send SN10052307203612: [3,"510",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:41:03 recv SN10052307203612: [2,"511","Heartbeat",{}]
[ocpp  ] TRACE 2024/09/03 08:41:03 send SN10052307203612: [3,"511",{"currentTime":"2024-09-02T22:41:03Z"}]
[ocpp  ] TRACE 2024/09/03 08:41:08 send SN10052307203612: [2,"1533328915","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/09/03 08:41:10 recv SN10052307203612: [3,"1533328915",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/09/03 08:41:10 recv SN10052307203612: [2,"512","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-09-02T22:41:07Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Trigger"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0.0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"246.6","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"95.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":9}]
[ocpp  ] TRACE 2024/09/03 08:41:10 send SN10052307203612: [3,"512",{}]
[ocpp  ] TRACE 2024/09/03 08:41:10 recv SN10052307203612: [2,"513","DataTransfer",{"vendorId":"EN+","messageId":"cpChargingParameterRpt","data":"{\"duty\":0,\"cpVolt\":931,\"transactionId\":9,\"totalPowerFactor\":259,\"aPhasesPowerFactor\":0,\"bPhasesPowerFactor\":6,\"cPhasesPowerFactor\":0,\"ccVolt\":259,\"offset0\":2010,\"offset1\":2019,\"leakcurr\":0,\"AMBTemp\":221,\"lockStatus\":1,\"ctCurrent\":9}"}]
[ocpp  ] TRACE 2024/09/03 08:41:10 send SN10052307203612: [3,"513",{"status":"Accepted"}]
[main  ] FATAL 2024/09/03 08:41:18 cannot create charger 'solax': cannot create charger type 'template': cannot create charger type 'ocpp': timeout
[main  ] FATAL 2024/09/03 08:41:18 will attempt restart in: 15m0s

What type of operating system are you running?

Linux

Version

0.136.6

premultiply commented 1 month ago

Reason: StatusNotification is not sent by charge point and not triggered.

Please try with Version >= 0.130.7

Should be already fixed by https://github.com/evcc-io/evcc/commit/f5547ba0e79ff875ebae607542fd2583a11492f7 Ocpp: do not return error on setup (https://github.com/evcc-io/evcc/pull/15853) https://github.com/evcc-io/evcc/commit/e6d3ad3f9f5e22b417d6472c99d2f41822cda53f Ocpp: initial StatusNotification trigger request (https://github.com/evcc-io/evcc/pull/15734) https://github.com/evcc-io/evcc/commit/71f58e93b2c14b04c6147426d72d67b3c0a6303f Ocpp: provide better support multiple connectors (https://github.com/evcc-io/evcc/pull/15762)

You should always avoid to add additional config options. Most of them are useless (and deprecated) or could make things even worse.

chargers:
  - name: solax
    type: template
    template: ocpp

If this is still not working please report back.

jeffborg commented 1 month ago

@premultiply just took 0.130.7 + my hack for the unplug/replug issue and yes the request to get status notification works 👍 Cross fingers today during pv charging the evse stays online without having to power cycling it with the new firmware.

Also reduced config but left in remotestart: true for now, I will check the new firmware if plug and charge mode keeps the ocpp connection alive tonight.