search

evcc-io / evcc

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

Ocpp: en+ hijacking logic #14673

Closed andig closed 3 months ago

andig commented 3 months ago

Discussed in https://github.com/evcc-io/evcc/discussions/14602

Originally posted by **jeffborg** June 28, 2024 Hi I have a en+ wallbox and it is sending the transactionId on metervalues even though the transaction has stopped `[2,"134","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-06-28T01:47:54Z","sampledValue":[{"measurand":"Current.Offered","unit":"A","value":"32.0","context":"Sample.Periodic"},{"measurand":"Current.Import","unit":"A","phase":"L1","value":"0"},{"measurand":"Voltage","unit":"V","phase":"L1","value":"234.4","context":"Sample.Periodic"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"1100.0","context":"Sample.Periodic"},{"measurand":"Power.Active.Import","unit":"W","value":"0","context":"Sample.Periodic"}]}],"transactionId":1}]` The problem is here https://github.com/evcc-io/evcc/blob/c04f52f8518e0ef8e5edf8bbaa8fc518da68c706/charger/ocpp/connector_core.go#L56-L57 is assuming this is a live working transaction. The only way to remove this transactionid from the wallbox is to turn it on and off again, then you get the MeterValues without it. I can get more reliable operation by simply turning off meter values for the box, but I'm thinking this hijacking should only occur if evcc thinks the charger is enabled and if the import W value is > 0 At least then if evcc get's it wrong you can stop and start in evcc and it will work. Once the transaction id is set internally the only way to get charging working again is to power cycle the wallbox and restart evcc at the same time.
andig commented 3 months ago

@jeffborg does this happen only after startup (when we want to hijack a transaction)? What txnId is that that the box sends?

jeffborg commented 3 months ago

@andig the only way to get it working is to power cycle the evse (so it doesn't send the property transactionId) and restart evcc (so to clear the stored transaction id). Then there is transactionId property sent in the MeterValues messages it's blank and everything works.

Once this evse has a transaction started no matter the state of started or stopped it will always send the last used txnid in the meter values. Doesn't matter if it disconnects and reconnects

I don't know is there some message in the ocpp standard which we can use to check if the transaction id we are about to assume is ok is valid or not? or query the current status of it. stopped or started.

andig commented 3 months ago

does this happen only after (evcc) startup?

ping @jeffborg

I don't know is there some message in the ocpp standard which we can use to check if the transaction id we are about to assume is ok is valid or not? or query the current status of it. stopped or started.

No... could you share an ocpp trace log of the evcc start? I'm uncertain how we would find out if there actually is a running transaction or not- maybe the messages give a hint.

amtssp commented 3 months ago

Hi I have an Orbis Viaris Charger with two connectors - I have just set it up today (very new user - so take care). I see the same hijack message. I have only seen it during startup

Here is a trace from the connector showing the hijack error:

[EVVC4FA6B4B5C-2] DEBUG 2024/07/06 14:34:07 waiting for chargepoint: 5m0s
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 AllowOfflineTxForUnknownId (r/w): false
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 AuthorizeRemoteTxRequests (r/w): false
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ChargeProfileMaxStackLevel (r/o): 63
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ChargingScheduleAllowedChargingRateUnit (r/o): Current,Power
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ChargingScheduleMaxPeriods (r/o): 8
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ClockAlignedDataInterval (r/w): 900
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ConnectionTimeOut (r/w): 60
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 GetConfigurationMaxKeys (r/o): 43
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 HeartbeatInterval (r/w): 60
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 LocalAuthListEnabled (r/w): false
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 LocalAuthListMaxLength (r/o): 500
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 LocalAuthorizeOffline (r/w): false
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 LocalPreAuthorize (r/w): false
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 MaxChargingProfilesInstalled (r/o): 10
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 MeterValueSampleInterval (r/w): 30
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 MeterValuesAlignedData (r/w): Energy.Active.Import.Register,Current.Import
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 MeterValuesAlignedDataMaxLength (r/o): 20
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 MeterValuesSampledData (r/w): Current.Import,Current.Offered,Energy.Active.Import.Register,Power.Active.Import,Voltage
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 MeterValuesSampledDataMaxLength (r/o): 20
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 NumberOfConnectors (r/o): 2
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ResetRetries (r/o): 3
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 SendLocalListMaxLength (r/o): 10
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 ServerUrl (r/w): ws://192.168.0.17/EVVC4FA6B4B5C
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 StopTxnAlignedData (r/w):
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 StopTxnAlignedDataMaxLength (r/o): 0
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 StopTxnSampledData (r/w):
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 StopTxnSampledDataMaxLength (r/o): 0
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 SupportedFeatureProfiles (r/o): Core,Firmware Management,Reservation,Smart Charging,Remote Trigger,Local Auth List Management
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 SupportedFeatureProfilesMaxLength (r/o): 1
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 TransactionMessageAttempts (r/w): 3
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 TransactionMessageRetryInterval (r/w): 30
[EVVC4FA6B4B5C-2] TRACE 2024/07/06 14:34:17 WebSocketPingInterval (r/w): 10
[EVVC4FA6B4B5C-2] DEBUG 2024/07/06 14:34:19 hijacking transaction: 1

Here is a trace from the connector not showing the hijack error:

[EVVC4FA6B4B5C-1] DEBUG 2024/07/06 14:34:07 waiting for chargepoint: 5m0s
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 AllowOfflineTxForUnknownId (r/w): false
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 AuthorizeRemoteTxRequests (r/w): false
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ChargeProfileMaxStackLevel (r/o): 63
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ChargingScheduleAllowedChargingRateUnit (r/o): Current,Power
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ChargingScheduleMaxPeriods (r/o): 8
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ClockAlignedDataInterval (r/w): 900
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ConnectionTimeOut (r/w): 60
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 GetConfigurationMaxKeys (r/o): 43
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 HeartbeatInterval (r/w): 60
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 LocalAuthListEnabled (r/w): false
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 LocalAuthListMaxLength (r/o): 500
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 LocalAuthorizeOffline (r/w): false
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 LocalPreAuthorize (r/w): false
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 MaxChargingProfilesInstalled (r/o): 10
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 MeterValueSampleInterval (r/w): 30
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 MeterValuesAlignedData (r/w): Energy.Active.Import.Register,Current.Import
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 MeterValuesAlignedDataMaxLength (r/o): 20
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 MeterValuesSampledData (r/w): Current.Import,Current.Offered,Energy.Active.Import.Register,Power.Active.Import,Voltage
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 MeterValuesSampledDataMaxLength (r/o): 20
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 NumberOfConnectors (r/o): 2
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ResetRetries (r/o): 3
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 SendLocalListMaxLength (r/o): 10
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 ServerUrl (r/w): ws://192.168.0.17/EVVC4FA6B4B5C
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 StopTxnAlignedData (r/w):
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 StopTxnAlignedDataMaxLength (r/o): 0
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 StopTxnSampledData (r/w):
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 StopTxnSampledDataMaxLength (r/o): 0
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 SupportedFeatureProfiles (r/o): Core,Firmware Management,Reservation,Smart Charging,Remote Trigger,Local Auth List Management
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 SupportedFeatureProfilesMaxLength (r/o): 1
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 TransactionMessageAttempts (r/w): 3
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 TransactionMessageRetryInterval (r/w): 30
[EVVC4FA6B4B5C-1] TRACE 2024/07/06 14:34:17 WebSocketPingInterval (r/w): 10

Here is the OCPP trace:

ocpp ] DEBUG 2024/07/06 14:34:15 charge point connected: EVVC4FA6B4B5C
[ocpp ] TRACE 2024/07/06 14:34:16 recv EVVC4FA6B4B5C: [2,"379","BootNotification",{"chargePointModel":"VIARIS COMBIPLUS","chargePointSerialNumber":"EVVC4FA6B4B5C","chargePointVendor":"Orbis","firmwareVersion":"7.2.53"}]
[ocpp ] TRACE 2024/07/06 14:34:16 sent EVVC4FA6B4B5C: [3,"379",{"currentTime":"2024-07-06T12:34:16Z","interval":60,"status":"Accepted"}]
[ocpp ] TRACE 2024/07/06 14:34:16 sent EVVC4FA6B4B5C: [2,"4247312435","GetConfiguration",{}]
[ocpp ] TRACE 2024/07/06 14:34:16 recv EVVC4FA6B4B5C: [2,"380","StatusNotification",{"connectorId":2,"errorCode":"NoError","status":"Charging","timestamp":"2024-07-06T12:34:16.243Z"}]
[ocpp ] TRACE 2024/07/06 14:34:16 sent EVVC4FA6B4B5C: [3,"380",{}]
[ocpp ] TRACE 2024/07/06 14:34:17 recv EVVC4FA6B4B5C: [3,"4247312435",{"configurationKey":[{"key":"AllowOfflineTxForUnknownId","readonly":false,"value":"false"},{"key":"AuthorizeRemoteTxRequests","readonly":false,"value":"false"},{"key":"ClockAlignedDataInterval","readonly":false,"value":"900"},{"key":"ConnectionTimeOut","readonly":false,"value":"60"},{"key":"GetConfigurationMaxKeys","readonly":true,"value":"43"},{"key":"HeartbeatInterval","readonly":false,"value":"60"},{"key":"LocalAuthorizeOffline","readonly":false,"value":"false"},{"key":"LocalPreAuthorize","readonly":false,"value":"false"},{"key":"MeterValuesAlignedData","readonly":false,"value":"Energy.Active.Import.Register,Current.Import"},{"key":"MeterValuesAlignedDataMaxLength","readonly":true,"value":"20"},{"key":"MeterValuesSampledData","readonly":false,"value":"Current.Import,Current.Offered,Energy.Active.Import.Register,Power.Active.Import,Voltage"},{"key":"MeterValuesSampledDataMaxLength","readonly":true,"value":"20"},{"key":"MeterValueSampleInterval","readonly":false,"value":"30"},{"key":"NumberOfConnectors","readonly":true,"value":"2"},{"key":"ResetRetries","readonly":true,"value":"3"},{"key":"StopTxnAlignedData","readonly":false,"value":""},{"key":"StopTxnAlignedDataMaxLength","readonly":true,"value":"0"},{"key":"StopTxnSampledData","readonly":false,"value":""},{"key":"StopTxnSampledDataMaxLength","readonly":true,"value":"0"},{"key":"SupportedFeatureProfiles","readonly":true,"value":"Core,Firmware Management,Reservation,Smart Charging,Remote Trigger,Local Auth List Management"},{"key":"SupportedFeatureProfilesMaxLength","readonly":true,"value":"1"},{"key":"TransactionMessageAttempts","readonly":false,"value":"3"},{"key":"TransactionMessageRetryInterval","readonly":false,"value":"30"},{"key":"WebSocketPingInterval","readonly":false,"value":"10"},{"key":"LocalAuthListEnabled","readonly":false,"value":"false"},{"key":"LocalAuthListMaxLength","readonly":true,"value":"500"},{"key":"SendLocalListMaxLength","readonly":true,"value":"10"},{"key":"ChargeProfileMaxStackLevel","readonly":true,"value":"63"},{"key":"ChargingScheduleAllowedChargingRateUnit","readonly":true,"value":"Current,Power"},{"key":"ChargingScheduleMaxPeriods","readonly":true,"value":"8"},{"key":"MaxChargingProfilesInstalled","readonly":true,"value":"10"},{"key":"ServerUrl","readonly":false,"value":"ws://192.168.0.17/EVVC4FA6B4B5C"}],"unknownKey":[]}]
[ocpp ] TRACE 2024/07/06 14:34:17 sent EVVC4FA6B4B5C: [2,"2480656234","GetConfiguration",{}]
[ocpp ] TRACE 2024/07/06 14:34:17 recv EVVC4FA6B4B5C: [3,"2480656234",{"configurationKey":[{"key":"AllowOfflineTxForUnknownId","readonly":false,"value":"false"},{"key":"AuthorizeRemoteTxRequests","readonly":false,"value":"false"},{"key":"ClockAlignedDataInterval","readonly":false,"value":"900"},{"key":"ConnectionTimeOut","readonly":false,"value":"60"},{"key":"GetConfigurationMaxKeys","readonly":true,"value":"43"},{"key":"HeartbeatInterval","readonly":false,"value":"60"},{"key":"LocalAuthorizeOffline","readonly":false,"value":"false"},{"key":"LocalPreAuthorize","readonly":false,"value":"false"},{"key":"MeterValuesAlignedData","readonly":false,"value":"Energy.Active.Import.Register,Current.Import"},{"key":"MeterValuesAlignedDataMaxLength","readonly":true,"value":"20"},{"key":"MeterValuesSampledData","readonly":false,"value":"Current.Import,Current.Offered,Energy.Active.Import.Register,Power.Active.Import,Voltage"},{"key":"MeterValuesSampledDataMaxLength","readonly":true,"value":"20"},{"key":"MeterValueSampleInterval","readonly":false,"value":"30"},{"key":"NumberOfConnectors","readonly":true,"value":"2"},{"key":"ResetRetries","readonly":true,"value":"3"},{"key":"StopTxnAlignedData","readonly":false,"value":""},{"key":"StopTxnAlignedDataMaxLength","readonly":true,"value":"0"},{"key":"StopTxnSampledData","readonly":false,"value":""},{"key":"StopTxnSampledDataMaxLength","readonly":true,"value":"0"},{"key":"SupportedFeatureProfiles","readonly":true,"value":"Core,Firmware Management,Reservation,Smart Charging,Remote Trigger,Local Auth List Management"},{"key":"SupportedFeatureProfilesMaxLength","readonly":true,"value":"1"},{"key":"TransactionMessageAttempts","readonly":false,"value":"3"},{"key":"TransactionMessageRetryInterval","readonly":false,"value":"30"},{"key":"WebSocketPingInterval","readonly":false,"value":"10"},{"key":"LocalAuthListEnabled","readonly":false,"value":"false"},{"key":"LocalAuthListMaxLength","readonly":true,"value":"500"},{"key":"SendLocalListMaxLength","readonly":true,"value":"10"},{"key":"ChargeProfileMaxStackLevel","readonly":true,"value":"63"},{"key":"ChargingScheduleAllowedChargingRateUnit","readonly":true,"value":"Current,Power"},{"key":"ChargingScheduleMaxPeriods","readonly":true,"value":"8"},{"key":"MaxChargingProfilesInstalled","readonly":true,"value":"10"},{"key":"ServerUrl","readonly":false,"value":"ws://192.168.0.17/EVVC4FA6B4B5C"}],"unknownKey":[]}]
[ocpp ] TRACE 2024/07/06 14:34:17 sent EVVC4FA6B4B5C: [2,"3169896989","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":2}]
[ocpp ] TRACE 2024/07/06 14:34:17 recv EVVC4FA6B4B5C: [3,"3169896989",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/06 14:34:17 sent EVVC4FA6B4B5C: [2,"1959581202","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/06 14:34:17 TriggerMessage MeterValues for EVVC4FA6B4B5C: Accepted
[ocpp ] TRACE 2024/07/06 14:34:17 recv EVVC4FA6B4B5C: [3,"1959581202",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/06 14:34:17 TriggerMessage MeterValues for EVVC4FA6B4B5C: Accepted
[ocpp ] TRACE 2024/07/06 14:34:17 recv EVVC4FA6B4B5C: [2,"381","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-06T12:34:17.181Z","sampledValue":[{"value":"0.000000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"0","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"8488387","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"0","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"0.000000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:34:17 sent EVVC4FA6B4B5C: [3,"381",{}]
[ocpp ] TRACE 2024/07/06 14:34:18 recv EVVC4FA6B4B5C: [2,"382","StatusNotification",{"connectorId":1,"errorCode":"NoError","status":"Available","timestamp":"2024-07-06T12:34:18.088Z"}]
[ocpp ] TRACE 2024/07/06 14:34:18 sent EVVC4FA6B4B5C: [3,"382",{}]
[ocpp ] TRACE 2024/07/06 14:34:19 recv EVVC4FA6B4B5C: [2,"383","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:34:19.023Z","sampledValue":[{"value":"17.514000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234103","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3945","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"229.890000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:34:19 sent EVVC4FA6B4B5C: [3,"383",{}]
[ocpp ] TRACE 2024/07/06 14:34:49 sent EVVC4FA6B4B5C: [2,"3685982749","SetChargingProfile",{"connectorId":2,"csChargingProfiles":{"chargingProfileId":1,"transactionId":1,"stackLevel":0,"chargingProfilePurpose":"TxProfile","chargingProfileKind":"Relative","chargingSchedule":{"chargingRateUnit":"A","chargingSchedulePeriod":[{"startPeriod":0,"limit":6}]}}}]
[ocpp ] TRACE 2024/07/06 14:34:49 recv EVVC4FA6B4B5C: [3,"3685982749",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/06 14:34:50 recv EVVC4FA6B4B5C: [2,"384","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:34:50.066Z","sampledValue":[{"value":"17.496000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234138","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3962","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"228.830000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:34:50 sent EVVC4FA6B4B5C: [3,"384",{}]
[ocpp ] TRACE 2024/07/06 14:35:16 recv EVVC4FA6B4B5C: [2,"385","Heartbeat",{}]
[ocpp ] TRACE 2024/07/06 14:35:16 sent EVVC4FA6B4B5C: [3,"385",{"currentTime":"2024-07-06T12:35:16Z"}]
[ocpp ] TRACE 2024/07/06 14:35:21 recv EVVC4FA6B4B5C: [2,"386","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:35:21.026Z","sampledValue":[{"value":"17.536000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234169","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3999","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"230.220000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:35:21 sent EVVC4FA6B4B5C: [3,"386",{}]
[ocpp ] TRACE 2024/07/06 14:35:51 recv EVVC4FA6B4B5C: [2,"387","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:35:51.023Z","sampledValue":[{"value":"17.540000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234205","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3996","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"229.960000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:35:51 sent EVVC4FA6B4B5C: [3,"387",{}]
[ocpp ] TRACE 2024/07/06 14:36:16 recv EVVC4FA6B4B5C: [2,"388","Heartbeat",{}]
[ocpp ] TRACE 2024/07/06 14:36:16 sent EVVC4FA6B4B5C: [3,"388",{"currentTime":"2024-07-06T12:36:16Z"}]
[ocpp ] TRACE 2024/07/06 14:36:21 recv EVVC4FA6B4B5C: [2,"389","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:36:21.032Z","sampledValue":[{"value":"17.535000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234238","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3993","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"230.080000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:36:21 sent EVVC4FA6B4B5C: [3,"389",{}]
[ocpp ] TRACE 2024/07/06 14:36:51 recv EVVC4FA6B4B5C: [2,"390","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:36:51.031Z","sampledValue":[{"value":"17.555000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234272","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3993","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"229.710000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:36:51 sent EVVC4FA6B4B5C: [3,"390",{}]
[ocpp ] TRACE 2024/07/06 14:37:16 recv EVVC4FA6B4B5C: [2,"391","Heartbeat",{}]
[ocpp ] TRACE 2024/07/06 14:37:16 sent EVVC4FA6B4B5C: [3,"391",{"currentTime":"2024-07-06T12:37:16Z"}]
[ocpp ] TRACE 2024/07/06 14:37:22 recv EVVC4FA6B4B5C: [2,"392","MeterValues",{"connectorId":2,"transactionId":1,"meterValue":[{"timestamp":"2024-07-06T12:37:22.031Z","sampledValue":[{"value":"17.533000","format":"Raw","measurand":"Current.Import","unit":"A"},{"value":"6","format":"Raw","measurand":"Current.Offered","unit":"A"},{"value":"2234307","format":"Raw","measurand":"Energy.Active.Import.Register","unit":"Wh"},{"value":"3992","format":"Raw","measurand":"Power.Active.Import","unit":"W"},{"value":"229.860000","format":"Raw","measurand":"Voltage","unit":"V"}]}]}]
[ocpp ] TRACE 2024/07/06 14:37:22 sent EVVC4FA6B4B5C: [3,"392",{}]
jeffborg commented 3 months ago

I haven't had a chance to get the trace output as I'm home late and if the charger <-> evcc communication breaks up I just need to get it working.

Given the autostart change in #14733 I don't think this will work as my unit has no setting to set to just always be on. so will still require an initial start transaction upon startup of the unit to get going.

What I am thinking @andig is maybe if a StopTransaction returns rejected then assume transaction stopped. If StartTransaction returns rejected then there is already a running transaction, and if SetChargingProfile returns rejected then a StartTrasaction will be required to be sent to get things going and then the SetCharginingProfile to be resent.

premultiply commented 3 months ago

See https://github.com/evcc-io/evcc/pull/14733#issuecomment-2213054603

andig commented 3 months ago

Given the autostart change in https://github.com/evcc-io/evcc/pull/14733 I don't think this will work as my unit has no setting to set to just always be on. so will still require an initial start transaction upon startup of the unit to get going.

It would be great if you could test this anyway. Out of curiosity: If your box doesn't have autostart- how would you charge without evcc?

jeffborg commented 3 months ago

@andig Can't do it, if I put the charger into "plug and play" mode vs Network it never connects to the defined ocpp server, I was previously using chargehq so always had ocpp in use with the unit.

andig commented 3 months ago

Oh, you just have to love those arbitrary OCPP implementations...

jeffborg commented 3 months ago

I think I may have worked out the pattern here, I'm running a evcc in k8s and evcc got restarted due to code server being updated - forgot about this but it explains a lot.

restarted evcc again here is the ocpp trace logs

[ocpp-1] DEBUG 2024/07/09 12:02:15 waiting for chargepoint: 5m0s
[ocpp ] INFO 2024/07/09 12:02:19 charge point connected, registering: SN10052307203612
[ocpp ] TRACE 2024/07/09 12:02:19 sent SN10052307203612: [2,"2431342368","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:02:19 recv SN10052307203612: [2,"2711","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/07/09 12:02:19 sent SN10052307203612: [3,"2711",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:02:21 recv SN10052307203612: [3,"2431342368",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:02:21 recv SN10052307203612: [2,"2712","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:02:19Z","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":"242.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:02:21 sent SN10052307203612: [2,"3540832731","ChangeConfiguration",{"key":"MeterValueSampleInterval","value":"10"}]
[ocpp-1] DEBUG 2024/07/09 12:02:21 hijacking transaction: 3
[ocpp ] TRACE 2024/07/09 12:02:21 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:02:21 sent SN10052307203612: [3,"2712",{}]
[ocpp ] TRACE 2024/07/09 12:02:21 recv SN10052307203612: [3,"3540832731",{"status":"Accepted"}]
[ocpp-1] DEBUG 2024/07/09 12:02:21 enabling meter watchdog
[ocpp ] TRACE 2024/07/09 12:02:29 recv SN10052307203612: [2,"2713","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:02:29 sent SN10052307203612: [3,"2713",{"currentTime":"2024-07-09T02:02:29Z"}]
[ocpp ] TRACE 2024/07/09 12:02:31 sent SN10052307203612: [2,"3720629883","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:02:32 recv SN10052307203612: [3,"3720629883",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:02:32 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:02:32 recv SN10052307203612: [2,"2714","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:02:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:02:32 sent SN10052307203612: [3,"2714",{}]
[ocpp ] TRACE 2024/07/09 12:02:51 sent SN10052307203612: [2,"1243541157","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:02:52 recv SN10052307203612: [3,"1243541157",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:02:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:02:53 recv SN10052307203612: [2,"2715","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:02:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:02:53 sent SN10052307203612: [3,"2715",{}]
[ocpp ] TRACE 2024/07/09 12:03:11 sent SN10052307203612: [2,"2022665267","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:03:12 recv SN10052307203612: [3,"2022665267",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:03:12 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:03:13 recv SN10052307203612: [2,"2716","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:03:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:03:13 sent SN10052307203612: [3,"2716",{}]
[ocpp ] TRACE 2024/07/09 12:03:21 sent SN10052307203612: [2,"1829607034","TriggerMessage",{"requestedMessage":"StatusNotification","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:03:22 recv SN10052307203612: [3,"1829607034",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:03:22 TriggerMessage StatusNotification for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:03:23 recv SN10052307203612: [2,"2717","StatusNotification",{"connectorId":1,"status":"Finishing","errorCode":"NoError","timestamp":"2024-07-09T02:02:27Z","vendorId":"EN+","info":"{\"reason\":\"plugInGun\",\"cpv\":0,\"rv\":0}"}]
[ocpp ] TRACE 2024/07/09 12:03:23 sent SN10052307203612: [3,"2717",{}]
[ocpp ] TRACE 2024/07/09 12:03:28 recv SN10052307203612: [2,"2718","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:03:28 sent SN10052307203612: [3,"2718",{"currentTime":"2024-07-09T02:03:28Z"}]
[ocpp ] TRACE 2024/07/09 12:03:31 sent SN10052307203612: [2,"4222561237","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:03:33 recv SN10052307203612: [3,"4222561237",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:03:33 recv SN10052307203612: [2,"2719","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:03:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:03:33 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:03:33 sent SN10052307203612: [3,"2719",{}]
[ocpp ] TRACE 2024/07/09 12:03:51 sent SN10052307203612: [2,"2962884332","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:03:52 recv SN10052307203612: [3,"2962884332",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:03:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:03:52 recv SN10052307203612: [2,"2720","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:03:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:03:52 sent SN10052307203612: [3,"2720",{}]
[ocpp ] TRACE 2024/07/09 12:04:11 sent SN10052307203612: [2,"1605418200","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:04:13 recv SN10052307203612: [3,"1605418200",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:04:13 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:04:13 recv SN10052307203612: [2,"2721","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:04:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:04:13 sent SN10052307203612: [3,"2721",{}]
[ocpp ] TRACE 2024/07/09 12:04:28 recv SN10052307203612: [2,"2722","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:04:28 sent SN10052307203612: [3,"2722",{"currentTime":"2024-07-09T02:04:28Z"}]
[ocpp ] TRACE 2024/07/09 12:04:31 sent SN10052307203612: [2,"2516172835","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:04:32 recv SN10052307203612: [3,"2516172835",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:04:32 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:04:33 recv SN10052307203612: [2,"2723","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:04:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:04:33 sent SN10052307203612: [3,"2723",{}]
[ocpp ] TRACE 2024/07/09 12:04:51 sent SN10052307203612: [2,"2858247556","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:04:52 recv SN10052307203612: [3,"2858247556",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:04:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:04:52 recv SN10052307203612: [2,"2724","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:04:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:04:52 sent SN10052307203612: [3,"2724",{}]
[ocpp ] TRACE 2024/07/09 12:05:11 sent SN10052307203612: [2,"3759798573","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:05:12 recv SN10052307203612: [3,"3759798573",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:05:12 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:05:12 recv SN10052307203612: [2,"2725","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:05:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:05:12 sent SN10052307203612: [3,"2725",{}]
[ocpp ] TRACE 2024/07/09 12:05:28 recv SN10052307203612: [2,"2726","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:05:28 sent SN10052307203612: [3,"2726",{"currentTime":"2024-07-09T02:05:28Z"}]
[ocpp ] TRACE 2024/07/09 12:05:31 sent SN10052307203612: [2,"1849322010","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:05:33 recv SN10052307203612: [3,"1849322010",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:05:33 recv SN10052307203612: [2,"2727","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:05:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:05:33 sent SN10052307203612: [3,"2727",{}]
[ocpp ] TRACE 2024/07/09 12:05:33 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:05:51 sent SN10052307203612: [2,"3897291314","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:05:52 recv SN10052307203612: [3,"3897291314",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:05:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:05:53 recv SN10052307203612: [2,"2728","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:05:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:05:53 sent SN10052307203612: [3,"2728",{}]
[ocpp ] TRACE 2024/07/09 12:05:55 sent SN10052307203612: [2,"265577552","SetChargingProfile",{"connectorId":1,"csChargingProfiles":{"chargingProfileId":1,"transactionId":3,"stackLevel":0,"chargingProfilePurpose":"TxProfile","chargingProfileKind":"Relative","chargingSchedule":{"chargingRateUnit":"A","chargingSchedulePeriod":[{"startPeriod":0,"limit":30}]}}}]
[ocpp ] TRACE 2024/07/09 12:05:56 recv SN10052307203612: [3,"265577552",{"status":"Rejected"}]
[ocpp ] TRACE 2024/07/09 12:06:11 sent SN10052307203612: [2,"2246071729","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:06:13 recv SN10052307203612: [3,"2246071729",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:06:13 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:06:13 recv SN10052307203612: [2,"2729","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:06:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:06:13 sent SN10052307203612: [3,"2729",{}]
[ocpp ] TRACE 2024/07/09 12:06:28 recv SN10052307203612: [2,"2730","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:06:28 sent SN10052307203612: [3,"2730",{"currentTime":"2024-07-09T02:06:28Z"}]
[ocpp ] TRACE 2024/07/09 12:06:31 sent SN10052307203612: [2,"1037973078","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:06:32 recv SN10052307203612: [3,"1037973078",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:06:32 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:06:33 recv SN10052307203612: [2,"2731","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:06:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:06:33 sent SN10052307203612: [3,"2731",{}]
[ocpp ] TRACE 2024/07/09 12:06:51 sent SN10052307203612: [2,"4213336296","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:06:53 recv SN10052307203612: [3,"4213336296",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:06:53 recv SN10052307203612: [2,"2732","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:06:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:06:53 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:06:53 sent SN10052307203612: [3,"2732",{}]
[ocpp ] TRACE 2024/07/09 12:07:11 sent SN10052307203612: [2,"880450201","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:07:12 recv SN10052307203612: [3,"880450201",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:07:12 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:07:12 recv SN10052307203612: [2,"2733","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:07:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:07:12 sent SN10052307203612: [3,"2733",{}]
[ocpp ] TRACE 2024/07/09 12:07:28 recv SN10052307203612: [2,"2734","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:07:28 sent SN10052307203612: [3,"2734",{"currentTime":"2024-07-09T02:07:28Z"}]
[ocpp ] TRACE 2024/07/09 12:07:31 sent SN10052307203612: [2,"3471723272","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:07:32 recv SN10052307203612: [3,"3471723272",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:07:32 recv SN10052307203612: [2,"2735","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:07:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:07:32 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:07:32 sent SN10052307203612: [3,"2735",{}]
[ocpp ] TRACE 2024/07/09 12:07:51 sent SN10052307203612: [2,"1864847532","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:07:52 recv SN10052307203612: [3,"1864847532",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:07:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:07:53 recv SN10052307203612: [2,"2736","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:07:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:07:53 sent SN10052307203612: [3,"2736",{}]
[ocpp ] TRACE 2024/07/09 12:08:11 sent SN10052307203612: [2,"981674067","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:08:11 recv SN10052307203612: [3,"981674067",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:08:11 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:08:13 recv SN10052307203612: [2,"2737","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:08:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:08:13 sent SN10052307203612: [3,"2737",{}]
[ocpp ] TRACE 2024/07/09 12:08:28 recv SN10052307203612: [2,"2738","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:08:28 sent SN10052307203612: [3,"2738",{"currentTime":"2024-07-09T02:08:28Z"}]
[ocpp ] TRACE 2024/07/09 12:08:31 sent SN10052307203612: [2,"1763816152","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:08:33 recv SN10052307203612: [3,"1763816152",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:08:33 recv SN10052307203612: [2,"2739","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:08:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:08:33 sent SN10052307203612: [3,"2739",{}]
[ocpp ] TRACE 2024/07/09 12:08:33 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:08:51 sent SN10052307203612: [2,"209128420","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:08:52 recv SN10052307203612: [3,"209128420",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:08:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:08:52 recv SN10052307203612: [2,"2740","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:08:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:08:52 sent SN10052307203612: [3,"2740",{}]
[ocpp ] TRACE 2024/07/09 12:09:11 sent SN10052307203612: [2,"65530951","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:09:13 recv SN10052307203612: [3,"65530951",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:09:13 recv SN10052307203612: [2,"2741","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:09:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:09:13 sent SN10052307203612: [3,"2741",{}]
[ocpp ] TRACE 2024/07/09 12:09:13 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:09:28 recv SN10052307203612: [2,"2742","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:09:28 sent SN10052307203612: [3,"2742",{"currentTime":"2024-07-09T02:09:28Z"}]
[ocpp ] TRACE 2024/07/09 12:09:31 sent SN10052307203612: [2,"599096890","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:09:33 recv SN10052307203612: [3,"599096890",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:09:33 recv SN10052307203612: [2,"2743","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:09:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:09:33 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:09:33 sent SN10052307203612: [3,"2743",{}]
[ocpp ] TRACE 2024/07/09 12:09:51 sent SN10052307203612: [2,"1064727713","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:09:53 recv SN10052307203612: [3,"1064727713",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:09:53 recv SN10052307203612: [2,"2744","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:09:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:09:53 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:09:53 sent SN10052307203612: [3,"2744",{}]
[ocpp ] TRACE 2024/07/09 12:10:11 sent SN10052307203612: [2,"3906013810","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:10:13 recv SN10052307203612: [3,"3906013810",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:10:13 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:10:13 recv SN10052307203612: [2,"2745","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:10:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:10:13 sent SN10052307203612: [3,"2745",{}]
[ocpp ] TRACE 2024/07/09 12:10:28 recv SN10052307203612: [2,"2746","Heartbeat",{}]
[ocpp ] TRACE 2024/07/09 12:10:28 sent SN10052307203612: [3,"2746",{"currentTime":"2024-07-09T02:10:28Z"}]
[ocpp ] TRACE 2024/07/09 12:10:31 sent SN10052307203612: [2,"3179045514","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:10:32 recv SN10052307203612: [3,"3179045514",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:10:32 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:10:32 recv SN10052307203612: [2,"2747","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:10:31Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"10798.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:10:32 sent SN10052307203612: [3,"2747",{}]
[ocpp ] TRACE 2024/07/09 12:10:40 sent SN10052307203612: [2,"2385963125","SetChargingProfile",{"connectorId":1,"csChargingProfiles":{"chargingProfileId":1,"transactionId":3,"stackLevel":0,"chargingProfilePurpose":"TxProfile","chargingProfileKind":"Relative","chargingSchedule":{"chargingRateUnit":"A","chargingSchedulePeriod":[{"startPeriod":0,"limit":6}]}}}]
[ocpp ] TRACE 2024/07/09 12:10:41 recv SN10052307203612: [3,"2385963125",{"status":"Rejected"}]
[ocpp ] TRACE 2024/07/09 12:10:42 sent SN10052307203612: [2,"1745457249","RemoteStopTransaction",{"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:10:43 recv SN10052307203612: [3,"1745457249",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:10:51 sent SN10052307203612: [2,"2071804179","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:10:52 recv SN10052307203612: [3,"2071804179",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:10:52 TriggerMessage MeterValues for SN10052307203612: Accepted
[ocpp ] TRACE 2024/07/09 12:10:52 recv SN10052307203612: [2,"2748","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:10:51Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:10:52 sent SN10052307203612: [3,"2748",{}]
[ocpp ] TRACE 2024/07/09 12:11:11 sent SN10052307203612: [2,"3207993018","TriggerMessage",{"requestedMessage":"MeterValues","connectorId":1}]
[ocpp ] TRACE 2024/07/09 12:11:13 recv SN10052307203612: [3,"3207993018",{"status":"Accepted"}]
[ocpp ] TRACE 2024/07/09 12:11:13 recv SN10052307203612: [2,"2749","MeterValues",{"connectorId":1,"meterValue":[{"timestamp":"2024-07-09T02:11:11Z","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":"245.8","context":"Trigger"},{"measurand":"Energy.Active.Import.Register","unit":"Wh","value":"0.0","context":"Trigger"},{"measurand":"Power.Active.Import","unit":"W","value":"0.0","context":"Trigger"}]}],"transactionId":3}]
[ocpp ] TRACE 2024/07/09 12:11:13 sent SN10052307203612: [3,"2749",{}]
[ocpp ] TRACE 2024/07/09 12:11:13 TriggerMessage MeterValues for SN10052307203612: Accepted

I'll need to powercycle the evse & restart evcc to get things working agian

andig commented 3 months ago

It seems this is a pretty unusual deployment scenario (though not wrong). Given how rare that is and having no good idea what a good logic would be for not taking over the txn I tend to close as wontfix. Thoughts?