search

evcc-io / evcc

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

OCPP EN+ AC7000-AE-45 startup issues #15691

Closed jeffborg closed 2 months ago

jeffborg commented 2 months ago

Describe the bug

After upgrade from 0.130.2 to 0.130.4 evse is non functional

Steps to reproduce

  1. upgrade evcc from 0.130.2 to 0.130.4
  2. does not work

Configuration details

chargers:
  - name: solax
    type: template
    template: ocpp
    timeout: 2m
    remotestart: true

Log details

AFTER EVCC UPGRADE NO RESTART OF EVSE 

[main  ] INFO 2024/08/26 07:56:55 evcc 0.130.4
[main  ] INFO 2024/08/26 07:56:55 using config file: /config/evcc.yaml
[db    ] INFO 2024/08/26 07:56:55 using sqlite database: /config/.evcc/evcc.db
[mqtt  ] INFO 2024/08/26 07:56:57 connecting evcc-1321152531 at tcp://mqtt-mosquitto:1883
[mqtt  ] DEBUG 2024/08/26 07:56:57 tcp://mqtt-mosquitto:1883 connected
[main  ] INFO 2024/08/26 07:56:57 listening at :7070
[ocpp-1] DEBUG 2024/08/26 07:56:57 waiting for chargepoint: 5m0s
[ocpp  ] INFO 2024/08/26 07:57:12 charge point connected, registering: SN10052307203612
[ocpp  ] TRACE 2024/08/26 07:57:13 recv SN10052307203612: [2,"398","DataTransfer",{"vendorId":"EN+","messageId":"gatewayInfo","data":"{\"SN\":\"SN10052307203612\",\"fwVer\":\"V43.0.552\",\"gateCode\":107,\"measureType\":1,\"disconnectReason\":0,\"maxCurr\":320,\"phase\":1,\"sdCardStatus\":0}"}]
[ocpp  ] TRACE 2024/08/26 07:57:13 send SN10052307203612: [3,"398",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/26 07:57:13 send SN10052307203612: [2,"3096612571","ChangeAvailability",{"connectorId":0,"type":"Operative"}]
[ocpp  ] TRACE 2024/08/26 07:57:14 recv SN10052307203612: [3,"3096612571",{"status":"Rejected"}]
[main  ] FATAL 2024/08/26 07:57:14 cannot create charger 'solax': cannot create charger type 'template': cannot create charger type 'ocpp': Rejected
[main  ] FATAL 2024/08/26 07:57:14 will attempt restart in: 15m0s
[ocpp  ] TRACE 2024/08/26 07:57:23 recv SN10052307203612: [2,"399","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 07:57:23 send SN10052307203612: [3,"399",{"currentTime":"2024-08-25T21:57:23Z"}]
[ocpp  ] TRACE 2024/08/26 07:58:22 recv SN10052307203612: [2,"400","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 07:58:22 send SN10052307203612: [3,"400",{"currentTime":"2024-08-25T21:58:22Z"}]
[ocpp  ] TRACE 2024/08/26 07:59:21 recv SN10052307203612: [2,"401","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 07:59:21 send SN10052307203612: [3,"401",{"currentTime":"2024-08-25T21:59:21Z"}]
[ocpp  ] TRACE 2024/08/26 08:00:22 recv SN10052307203612: [2,"402","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:00:22 send SN10052307203612: [3,"402",{"currentTime":"2024-08-25T22:00:22Z"}]
[ocpp  ] TRACE 2024/08/26 08:01:22 recv SN10052307203612: [2,"403","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:01:22 send SN10052307203612: [3,"403",{"currentTime":"2024-08-25T22:01:22Z"}]
[ocpp  ] TRACE 2024/08/26 08:02:22 recv SN10052307203612: [2,"404","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:02:22 send SN10052307203612: [3,"404",{"currentTime":"2024-08-25T22:02:22Z"}]
[ocpp  ] TRACE 2024/08/26 08:03:22 recv SN10052307203612: [2,"405","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:03:22 send SN10052307203612: [3,"405",{"currentTime":"2024-08-25T22:03:22Z"}]
[ocpp  ] TRACE 2024/08/26 08:04:22 recv SN10052307203612: [2,"406","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:04:22 send SN10052307203612: [3,"406",{"currentTime":"2024-08-25T22:04:22Z"}]
[ocpp  ] TRACE 2024/08/26 08:05:23 recv SN10052307203612: [2,"407","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:05:23 send SN10052307203612: [3,"407",{"currentTime":"2024-08-25T22:05:23Z"}]
[ocpp  ] TRACE 2024/08/26 08:06:23 recv SN10052307203612: [2,"408","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:06:23 send SN10052307203612: [3,"408",{"currentTime":"2024-08-25T22:06:23Z"}]
[ocpp  ] TRACE 2024/08/26 08:07:23 recv SN10052307203612: [2,"409","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:07:23 send SN10052307203612: [3,"409",{"currentTime":"2024-08-25T22:07:23Z"}]

AFTER RESTART OF EVCC & POWER CYCLE EVSE

[main  ] INFO 2024/08/26 08:08:51 evcc 0.130.4
[main  ] INFO 2024/08/26 08:08:51 using config file: /config/evcc.yaml
[db    ] INFO 2024/08/26 08:08:51 using sqlite database: /config/.evcc/evcc.db
[mqtt  ] INFO 2024/08/26 08:08:53 connecting evcc-1832195559 at tcp://mqtt-mosquitto:1883
[main  ] INFO 2024/08/26 08:08:53 listening at :7070
[mqtt  ] DEBUG 2024/08/26 08:08:53 tcp://mqtt-mosquitto:1883 connected
[mqtt  ] DEBUG 2024/08/26 08:08:53 tcp://mqtt-mosquitto:1883 subscribe energy/solar
[ocpp-1] DEBUG 2024/08/26 08:08:53 waiting for chargepoint: 5m0s
[ocpp  ] INFO 2024/08/26 08:09:10 charge point connected, registering: SN10052307203612
[ocpp  ] TRACE 2024/08/26 08:09:11 send SN10052307203612: [2,"2228006118","ChangeAvailability",{"connectorId":0,"type":"Operative"}]
[ocpp  ] TRACE 2024/08/26 08:09:16 recv SN10052307203612: [2,"2","BootNotification",{"chargePointModel":"23","chargePointSerialNumber":"SN10052307203612","chargePointVendor":"EN+","firmwareVersion":"1.3.53"}]
[ocpp  ] TRACE 2024/08/26 08:09:16 send SN10052307203612: [3,"2",{"currentTime":"2024-08-25T22:09:16Z","interval":60,"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/26 08:09:16 recv SN10052307203612: [2,"3","DataTransfer",{"vendorId":"EN+","messageId":"gatewayInfo","data":"{\"SN\":\"SN10052307203612\",\"fwVer\":\"V43.0.552\",\"gateCode\":107,\"measureType\":1,\"disconnectReason\":0,\"maxCurr\":320,\"phase\":1,\"sdCardStatus\":0}"}]
[ocpp  ] TRACE 2024/08/26 08:09:16 send SN10052307203612: [3,"3",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/26 08:09:16 recv SN10052307203612: [2,"offline-1","StopTransaction",{"meterStop":1,"transactionId":10,"transactionData":[{"sampledValue":[{"measurand":"Energy.Active.Import.Register","context":"Transaction.End","value":"0.001","unit":"kWh"}],"timestamp":"2024-08-25T22:07:23Z"}],"timestamp":"2024-08-25T22:07:23Z","idTag":"evcc","reason":"SoftReset"}]
[ocpp  ] TRACE 2024/08/26 08:09:16 send SN10052307203612: [3,"offline-1",{"idTagInfo":{"status":"Accepted"}}]
[ocpp  ] TRACE 2024/08/26 08:09:17 recv SN10052307203612: [2,"4","StatusNotification",{"connectorId":0,"status":"Preparing","errorCode":"NoError","timestamp":"2024-08-25T22:09:14Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/26 08:09:17 send SN10052307203612: [3,"4",{}]
[ocpp  ] TRACE 2024/08/26 08:09:17 recv SN10052307203612: [2,"5","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:09:17 send SN10052307203612: [3,"5",{"currentTime":"2024-08-25T22:09:17Z"}]
[ocpp  ] TRACE 2024/08/26 08:09:17 recv SN10052307203612: [2,"6","StatusNotification",{"connectorId":1,"status":"Preparing","errorCode":"NoError","timestamp":"2024-08-25T22:09:14Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/26 08:09:17 send SN10052307203612: [3,"6",{}]
[ocpp  ] TRACE 2024/08/26 08:09:29 recv SN10052307203612: [2,"7","StatusNotification",{"connectorId":1,"status":"Finishing","errorCode":"NoError","timestamp":"2024-08-25T22:09:28Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp  ] TRACE 2024/08/26 08:09:29 send SN10052307203612: [3,"7",{}]
[main  ] FATAL 2024/08/26 08:09:41 cannot create charger 'solax': cannot create charger type 'template': cannot create charger type 'ocpp': timeout
[main  ] FATAL 2024/08/26 08:09:41 will attempt restart in: 15m0s

AFTER EVCC RESTART ONLY 3rd attempt

[main  ] INFO 2024/08/26 08:15:44 evcc 0.130.4
[main  ] INFO 2024/08/26 08:15:44 using config file: /config/evcc.yaml
[db    ] INFO 2024/08/26 08:15:44 using sqlite database: /config/.evcc/evcc.db
[mqtt  ] INFO 2024/08/26 08:15:45 connecting evcc-1873554375 at tcp://mqtt-mosquitto:1883
[mqtt  ] DEBUG 2024/08/26 08:15:45 tcp://mqtt-mosquitto:1883 connected
[main  ] INFO 2024/08/26 08:15:45 listening at :7070
[ocpp-1] DEBUG 2024/08/26 08:15:45 waiting for chargepoint: 5m0s
[ocpp  ] INFO 2024/08/26 08:16:06 charge point connected, registering: SN10052307203612
[ocpp  ] TRACE 2024/08/26 08:16:07 recv SN10052307203612: [2,"14","DataTransfer",{"vendorId":"EN+","messageId":"gatewayInfo","data":"{\"SN\":\"SN10052307203612\",\"fwVer\":\"V43.0.552\",\"gateCode\":107,\"measureType\":1,\"disconnectReason\":0,\"maxCurr\":320,\"phase\":1,\"sdCardStatus\":0}"}]
[ocpp  ] TRACE 2024/08/26 08:16:07 send SN10052307203612: [3,"14",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/26 08:16:07 send SN10052307203612: [2,"1225249548","ChangeAvailability",{"connectorId":0,"type":"Operative"}]
[ocpp  ] TRACE 2024/08/26 08:16:08 recv SN10052307203612: [3,"1225249548",{"status":"Rejected"}]
[main  ] FATAL 2024/08/26 08:16:08 cannot create charger 'solax': cannot create charger type 'template': cannot create charger type 'ocpp': Rejected
[main  ] FATAL 2024/08/26 08:16:08 will attempt restart in: 15m0s
[ocpp  ] TRACE 2024/08/26 08:16:17 recv SN10052307203612: [2,"15","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/26 08:16:17 send SN10052307203612: [3,"15",{"currentTime":"2024-08-25T22:16:17Z"}]

What type of operating system are you running?

Linux

Version

0.130.4

jeffborg commented 2 months ago

@premultiply 0.130.6 #15692 does not fix this issue, evcc won't start up here is the logs up to the FATAL error

[main  ] INFO 2024/08/27 08:31:39 evcc 0.130.6
[main  ] INFO 2024/08/27 08:31:39 using config file: /config/evcc.yaml
[db    ] INFO 2024/08/27 08:31:39 using sqlite database: /config/.evcc/evcc.db
[mqtt  ] INFO 2024/08/27 08:31:40 connecting evcc-353219863 at tcp://mqtt-mosquitto:1883
[mqtt  ] DEBUG 2024/08/27 08:31:40 tcp://mqtt-mosquitto:1883 connected
[main  ] INFO 2024/08/27 08:31:40 listening at :7070
[ocpp-1] DEBUG 2024/08/27 08:31:40 waiting for chargepoint: 5m0s
[ocpp  ] INFO 2024/08/27 08:31:45 charge point connected, registering: SN10052307203612
[ocpp  ] TRACE 2024/08/27 08:31:45 recv SN10052307203612: [2,"7067","DataTransfer",{"vendorId":"EN+","messageId":"gatewayInfo","data":"{\"SN\":\"SN10052307203612\",\"fwVer\":\"V43.0.552\",\"gateCode\":107,\"measureType\":1,\"disconnectReason\":0,\"maxCurr\":320,\"phase\":1,\"sdCardStatus\":0}"}]
[ocpp  ] TRACE 2024/08/27 08:31:45 send SN10052307203612: [3,"7067",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:46 send SN10052307203612: [2,"3702398607","ChangeAvailability",{"connectorId":0,"type":"Operative"}]
[ocpp  ] TRACE 2024/08/27 08:31:47 recv SN10052307203612: [2,"7068","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/27 08:31:47 send SN10052307203612: [3,"7068",{"currentTime":"2024-08-26T22:31:47Z"}]
[ocpp  ] TRACE 2024/08/27 08:31:47 recv SN10052307203612: [3,"3702398607",{"status":"Rejected"}]
[ocpp-1] DEBUG 2024/08/27 08:31:47 failed configuring availability: Rejected
[ocpp  ] TRACE 2024/08/27 08:31:47 send SN10052307203612: [2,"2022111343","GetConfiguration",{}]
[ocpp  ] TRACE 2024/08/27 08:31:48 recv SN10052307203612: [3,"2022111343",{"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":"Power.Active.Import,Energy.Active.Import.Register,Current.Import,Voltage,Current.Offered,Power.Offe","readonly":false},{"key":"MeterValueSampleInterval","value":"10","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/08/27 08:31:48 send SN10052307203612: [2,"3940550627","TriggerMessage",{"requestedMessage":"BootNotification"}]
[ocpp  ] TRACE 2024/08/27 08:31:49 recv SN10052307203612: [3,"3940550627",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:49 recv SN10052307203612: [2,"7069","BootNotification",{"chargePointModel":"23","chargePointSerialNumber":"SN10052307203612","chargePointVendor":"EN+","firmwareVersion":"1.3.53"}]
[ocpp  ] TRACE 2024/08/27 08:31:49 send SN10052307203612: [3,"7069",{"currentTime":"2024-08-26T22:31:49Z","interval":60,"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:49 send SN10052307203612: [2,"165255274","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Active.Import"}]
[ocpp  ] TRACE 2024/08/27 08:31:50 recv SN10052307203612: [3,"165255274",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:50 send SN10052307203612: [2,"619523124","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Energy.Active.Import.Register"}]
[ocpp  ] TRACE 2024/08/27 08:31:51 recv SN10052307203612: [3,"619523124",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:51 send SN10052307203612: [2,"3292162753","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Current.Import"}]
[ocpp  ] TRACE 2024/08/27 08:31:52 recv SN10052307203612: [3,"3292162753",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:52 send SN10052307203612: [2,"2197521291","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Voltage"}]
[ocpp  ] TRACE 2024/08/27 08:31:53 recv SN10052307203612: [3,"2197521291",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:53 send SN10052307203612: [2,"125785717","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Current.Offered"}]
[ocpp  ] TRACE 2024/08/27 08:31:54 recv SN10052307203612: [3,"125785717",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:54 send SN10052307203612: [2,"4004172136","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Offered"}]
[ocpp  ] TRACE 2024/08/27 08:31:56 recv SN10052307203612: [3,"4004172136",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:56 send SN10052307203612: [2,"1777782124","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"SoC"}]
[ocpp  ] TRACE 2024/08/27 08:31:58 recv SN10052307203612: [3,"1777782124",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:31:58 send SN10052307203612: [2,"2809018105","ChangeConfiguration",{"key":"MeterValuesSampledData","value":"Power.Active.Import,Energy.Active.Import.Register,Current.Import,Voltage,Current.Offered,Power.Offered,SoC"}]
[ocpp  ] TRACE 2024/08/27 08:32:04 recv SN10052307203612: [3,"2809018105",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:04 send SN10052307203612: [2,"700711819","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:32:04 recv SN10052307203612: [3,"700711819",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:04 recv SN10052307203612: [2,"7070","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:32: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:32:04 send SN10052307203612: [3,"7070",{}]
[ocpp  ] TRACE 2024/08/27 08:32:04 send SN10052307203612: [2,"439863440","ChangeConfiguration",{"key":"MeterValueSampleInterval","value":"10"}]
[ocpp  ] TRACE 2024/08/27 08:32:06 recv SN10052307203612: [3,"439863440",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:06 send SN10052307203612: [2,"1395658129","ChangeConfiguration",{"key":"WebSocketPingInterval","value":"30"}]
[ocpp  ] TRACE 2024/08/27 08:32:10 recv SN10052307203612: [3,"1395658129",{"status":"NotSupported"}]
[ocpp  ] TRACE 2024/08/27 08:32:14 send SN10052307203612: [2,"1764759976","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:32:15 recv SN10052307203612: [3,"1764759976",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:15 recv SN10052307203612: [2,"7071","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:32:14Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:32:15 send SN10052307203612: [3,"7071",{}]
[ocpp  ] TRACE 2024/08/27 08:32:24 send SN10052307203612: [2,"1755897453","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:32:27 recv SN10052307203612: [3,"1755897453",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:27 recv SN10052307203612: [2,"7072","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:32: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:32:27 send SN10052307203612: [3,"7072",{}]
[ocpp  ] TRACE 2024/08/27 08:32:36 send SN10052307203612: [2,"4026786983","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:32:37 recv SN10052307203612: [3,"4026786983",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:37 recv SN10052307203612: [2,"7073","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:32:36Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:32:37 send SN10052307203612: [3,"7073",{}]
[ocpp  ] TRACE 2024/08/27 08:32:45 recv SN10052307203612: [2,"7074","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/27 08:32:45 send SN10052307203612: [3,"7074",{"currentTime":"2024-08-26T22:32:45Z"}]
[ocpp  ] TRACE 2024/08/27 08:32:46 send SN10052307203612: [2,"370155933","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:32:47 recv SN10052307203612: [3,"370155933",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:47 recv SN10052307203612: [2,"7075","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:32:46Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:32:47 send SN10052307203612: [3,"7075",{}]
[ocpp  ] TRACE 2024/08/27 08:32:56 send SN10052307203612: [2,"3464706699","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:32:57 recv SN10052307203612: [3,"3464706699",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:32:57 recv SN10052307203612: [2,"7076","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:32:56Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:32:57 send SN10052307203612: [3,"7076",{}]
[ocpp  ] TRACE 2024/08/27 08:33:06 send SN10052307203612: [2,"3832827897","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:33:07 recv SN10052307203612: [3,"3832827897",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:33:07 recv SN10052307203612: [2,"7077","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:33:06Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:33:07 send SN10052307203612: [3,"7077",{}]
[ocpp  ] TRACE 2024/08/27 08:33:16 send SN10052307203612: [2,"1907816954","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:33:17 recv SN10052307203612: [3,"1907816954",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:33:17 recv SN10052307203612: [2,"7078","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:33:16Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:33:17 send SN10052307203612: [3,"7078",{}]
[ocpp  ] TRACE 2024/08/27 08:33:26 send SN10052307203612: [2,"3699887678","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:33:27 recv SN10052307203612: [3,"3699887678",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:33:27 recv SN10052307203612: [2,"7079","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:33: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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:33:27 send SN10052307203612: [3,"7079",{}]
[ocpp  ] TRACE 2024/08/27 08:33:36 send SN10052307203612: [2,"2300206256","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:33:37 recv SN10052307203612: [3,"2300206256",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:33:37 recv SN10052307203612: [2,"7080","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:33:36Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:33:37 send SN10052307203612: [3,"7080",{}]
[ocpp  ] TRACE 2024/08/27 08:33:45 recv SN10052307203612: [2,"7081","Heartbeat",{}]
[ocpp  ] TRACE 2024/08/27 08:33:45 send SN10052307203612: [3,"7081",{"currentTime":"2024-08-26T22:33:45Z"}]
[ocpp  ] TRACE 2024/08/27 08:33:46 send SN10052307203612: [2,"2930521988","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:33:47 recv SN10052307203612: [3,"2930521988",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:33:47 recv SN10052307203612: [2,"7082","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:33:46Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:33:47 send SN10052307203612: [3,"7082",{}]
[ocpp  ] TRACE 2024/08/27 08:33:56 send SN10052307203612: [2,"1879948984","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:33:57 recv SN10052307203612: [3,"1879948984",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:33:57 recv SN10052307203612: [2,"7083","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:33:56Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:33:57 send SN10052307203612: [3,"7083",{}]
[ocpp  ] TRACE 2024/08/27 08:34:06 send SN10052307203612: [2,"332709275","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp  ] TRACE 2024/08/27 08:34:07 recv SN10052307203612: [3,"332709275",{"status":"Accepted"}]
[ocpp  ] TRACE 2024/08/27 08:34:07 recv SN10052307203612: [2,"7084","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-08-26T22:34:06Z","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":"243.2","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"27010.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp  ] TRACE 2024/08/27 08:34:07 send SN10052307203612: [3,"7084",{}]
[main  ] FATAL 2024/08/27 08:34:10 cannot create charger 'solax': cannot create charger type 'template': cannot create charger type 'ocpp': timeout
[main  ] FATAL 2024/08/27 08:34:10 will attempt restart in: 15m0s
premultiply commented 2 months ago

Right. It did not receive any StatusNotification.

jeffborg commented 2 months ago

@premultiply got it working, but it's sort of getting annoying you would expect that simply updating evcc would be enough.

I had to do this as evcc 0.129.0 couldn't limit the current after it connected to 0.130.6 for some reason and I think the evse got into some state.

finally working

All these manual steps!, But I did see the new waiting for authorisation icon which at least gives some indication on what is going on.